{"level":"warn","ts":"2026-07-30T19:19:38.663694Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T19:19:38.663773Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.222: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://162.253.55.222:2380","--initial-cluster=instance=https://162.253.55.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.222:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-30T19:19:38.663827Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T19:19:38.663839Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.222:2380"]} {"level":"info","ts":"2026-07-30T19:19:38.663854Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T19:19:38.664464Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"]} {"level":"info","ts":"2026-07-30T19:19:38.664533Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.222:2380"],"listen-peer-urls":["https://162.253.55.222:2380"],"advertise-client-urls":["https://162.253.55.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.222:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-30T19:19:38.67063Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.643121ms"} {"level":"info","ts":"2026-07-30T19:19:38.682351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5a9e84e0e0a90b63","cluster-id":"c7ddd50204a2b828"} {"level":"info","ts":"2026-07-30T19:19:38.682409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T19:19:38.682425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became follower at term 0"} {"level":"info","ts":"2026-07-30T19:19:38.682515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5a9e84e0e0a90b63 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T19:19:38.682521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became follower at term 1"} {"level":"info","ts":"2026-07-30T19:19:38.682593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=(6529802611110513507)"} {"level":"warn","ts":"2026-07-30T19:19:38.691613Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T19:19:38.69292Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T19:19:38.69401Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-30T19:19:38.69607Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5a9e84e0e0a90b63","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T19:19:38.696689Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T19:19:38.696638Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5a9e84e0e0a90b63","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T19:19:38.696777Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T19:19:38.696785Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T19:19:38.697833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=(6529802611110513507)"} {"level":"info","ts":"2026-07-30T19:19:38.698106Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7ddd50204a2b828","local-member-id":"5a9e84e0e0a90b63","added-peer-id":"5a9e84e0e0a90b63","added-peer-peer-urls":["https://162.253.55.222:2380"]} {"level":"info","ts":"2026-07-30T19:19:38.699971Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T19:19:38.700174Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5a9e84e0e0a90b63","initial-advertise-peer-urls":["https://162.253.55.222:2380"],"listen-peer-urls":["https://162.253.55.222:2380"],"advertise-client-urls":["https://162.253.55.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T19:19:38.700207Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T19:19:38.700316Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.222:2380"} {"level":"info","ts":"2026-07-30T19:19:38.700333Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.222:2380"} {"level":"info","ts":"2026-07-30T19:19:39.283803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T19:19:39.283863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T19:19:39.283897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 received MsgPreVoteResp from 5a9e84e0e0a90b63 at term 1"} {"level":"info","ts":"2026-07-30T19:19:39.283916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became candidate at term 2"} {"level":"info","ts":"2026-07-30T19:19:39.28393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 received MsgVoteResp from 5a9e84e0e0a90b63 at term 2"} {"level":"info","ts":"2026-07-30T19:19:39.283951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became leader at term 2"} {"level":"info","ts":"2026-07-30T19:19:39.28397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5a9e84e0e0a90b63 elected leader 5a9e84e0e0a90b63 at term 2"} {"level":"info","ts":"2026-07-30T19:19:39.285047Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5a9e84e0e0a90b63","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.222:2379]}","request-path":"/0/members/5a9e84e0e0a90b63/attributes","cluster-id":"c7ddd50204a2b828","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T19:19:39.285201Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T19:19:39.285287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T19:19:39.285637Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T19:19:39.285709Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T19:19:39.285559Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T19:19:39.286903Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7ddd50204a2b828","local-member-id":"5a9e84e0e0a90b63","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T19:19:39.287029Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T19:19:39.287083Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T19:19:39.288942Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T19:19:39.290708Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.222:2379"} {"level":"info","ts":"2026-07-30T19:20:14.614338Z","caller":"traceutil/trace.go:171","msg":"trace[186982460] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"115.671728ms","start":"2026-07-30T19:20:14.498644Z","end":"2026-07-30T19:20:14.614316Z","steps":["trace[186982460] 'process raft request' (duration: 115.541684ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:20:20.767818Z","caller":"traceutil/trace.go:171","msg":"trace[1508286779] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"258.876918ms","start":"2026-07-30T19:20:20.508907Z","end":"2026-07-30T19:20:20.767784Z","steps":["trace[1508286779] 'process raft request' (duration: 257.224269ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:20:20.946483Z","caller":"traceutil/trace.go:171","msg":"trace[629720674] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"128.509601ms","start":"2026-07-30T19:20:20.81794Z","end":"2026-07-30T19:20:20.946449Z","steps":["trace[629720674] 'process raft request' (duration: 100.792006ms)","trace[629720674] 'compare' (duration: 27.458997ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:20:21.613524Z","caller":"traceutil/trace.go:171","msg":"trace[955228807] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"578.728933ms","start":"2026-07-30T19:20:21.034768Z","end":"2026-07-30T19:20:21.613497Z","steps":["trace[955228807] 'process raft request' (duration: 558.451404ms)","trace[955228807] 'compare' (duration: 20.157456ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:20:21.614535Z","caller":"traceutil/trace.go:171","msg":"trace[1420980280] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"578.134965ms","start":"2026-07-30T19:20:21.036371Z","end":"2026-07-30T19:20:21.614506Z","steps":["trace[1420980280] 'process raft request' (duration: 577.395423ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:20:21.614702Z","caller":"traceutil/trace.go:171","msg":"trace[1449991463] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"249.92342ms","start":"2026-07-30T19:20:21.364749Z","end":"2026-07-30T19:20:21.614672Z","steps":["trace[1449991463] 'process raft request' (duration: 249.26034ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:20:21.614579Z","caller":"traceutil/trace.go:171","msg":"trace[2134680580] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"155.131978ms","start":"2026-07-30T19:20:21.459418Z","end":"2026-07-30T19:20:21.61455Z","steps":["trace[2134680580] 'read index received' (duration: 133.849048ms)","trace[2134680580] 'applied index is now lower than readState.Index' (duration: 21.28194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:20:21.614927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.493689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:20:21.615069Z","caller":"traceutil/trace.go:171","msg":"trace[1239726853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"155.681545ms","start":"2026-07-30T19:20:21.459371Z","end":"2026-07-30T19:20:21.615052Z","steps":["trace[1239726853] 'agreement among raft nodes before linearized reading' (duration: 155.453568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:20:21.616008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:20:21.034741Z","time spent":"578.853226ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:20:21.616206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:20:21.036356Z","time spent":"578.370293ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:20:33.2654Z","caller":"traceutil/trace.go:171","msg":"trace[373313732] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"110.946669ms","start":"2026-07-30T19:20:33.154429Z","end":"2026-07-30T19:20:33.265376Z","steps":["trace[373313732] 'process raft request' (duration: 110.777644ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:20:33.269047Z","caller":"traceutil/trace.go:171","msg":"trace[1852064034] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"114.505016ms","start":"2026-07-30T19:20:33.15453Z","end":"2026-07-30T19:20:33.269035Z","steps":["trace[1852064034] 'process raft request' (duration: 114.367312ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:20:33.269199Z","caller":"traceutil/trace.go:171","msg":"trace[296230324] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"114.589878ms","start":"2026-07-30T19:20:33.154597Z","end":"2026-07-30T19:20:33.269187Z","steps":["trace[296230324] 'process raft request' (duration: 114.407843ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.131139Z","caller":"traceutil/trace.go:171","msg":"trace[346542084] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"268.869551ms","start":"2026-07-30T19:21:23.862238Z","end":"2026-07-30T19:21:24.131108Z","steps":["trace[346542084] 'process raft request' (duration: 173.015598ms)","trace[346542084] 'compare' (duration: 95.709138ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:24.13297Z","caller":"traceutil/trace.go:171","msg":"trace[1397691710] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1416; }","duration":"134.749134ms","start":"2026-07-30T19:21:23.998191Z","end":"2026-07-30T19:21:24.13294Z","steps":["trace[1397691710] 'read index received' (duration: 37.09337ms)","trace[1397691710] 'applied index is now lower than readState.Index' (duration: 97.654834ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:24.133291Z","caller":"traceutil/trace.go:171","msg":"trace[1654137229] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"243.327384ms","start":"2026-07-30T19:21:23.8898Z","end":"2026-07-30T19:21:24.133127Z","steps":["trace[1654137229] 'process raft request' (duration: 242.986624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:24.133799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.601359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:21:24.134263Z","caller":"traceutil/trace.go:171","msg":"trace[1194801130] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1370; }","duration":"136.163805ms","start":"2026-07-30T19:21:23.998077Z","end":"2026-07-30T19:21:24.134241Z","steps":["trace[1194801130] 'agreement among raft nodes before linearized reading' (duration: 135.576558ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.543322Z","caller":"traceutil/trace.go:171","msg":"trace[132748142] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"147.282415ms","start":"2026-07-30T19:21:24.39601Z","end":"2026-07-30T19:21:24.543292Z","steps":["trace[132748142] 'process raft request' (duration: 143.025422ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.543394Z","caller":"traceutil/trace.go:171","msg":"trace[1626362487] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"147.027798ms","start":"2026-07-30T19:21:24.39633Z","end":"2026-07-30T19:21:24.543357Z","steps":["trace[1626362487] 'process raft request' (duration: 146.883714ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.543445Z","caller":"traceutil/trace.go:171","msg":"trace[1189931321] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"145.896195ms","start":"2026-07-30T19:21:24.397534Z","end":"2026-07-30T19:21:24.54343Z","steps":["trace[1189931321] 'process raft request' (duration: 145.74159ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.543447Z","caller":"traceutil/trace.go:171","msg":"trace[1489469980] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"145.857244ms","start":"2026-07-30T19:21:24.397566Z","end":"2026-07-30T19:21:24.543423Z","steps":["trace[1489469980] 'process raft request' (duration: 145.753721ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.54368Z","caller":"traceutil/trace.go:171","msg":"trace[1111329458] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"145.049501ms","start":"2026-07-30T19:21:24.398589Z","end":"2026-07-30T19:21:24.543639Z","steps":["trace[1111329458] 'process raft request' (duration: 144.774303ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.543669Z","caller":"traceutil/trace.go:171","msg":"trace[60780352] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1433; }","duration":"144.280649ms","start":"2026-07-30T19:21:24.399341Z","end":"2026-07-30T19:21:24.543621Z","steps":["trace[60780352] 'read index received' (duration: 30.17191ms)","trace[60780352] 'applied index is now lower than readState.Index' (duration: 114.086709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:21:24.543846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.938088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:21:24.543889Z","caller":"traceutil/trace.go:171","msg":"trace[1571425039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1391; }","duration":"111.025721ms","start":"2026-07-30T19:21:24.43285Z","end":"2026-07-30T19:21:24.543876Z","steps":["trace[1571425039] 'agreement among raft nodes before linearized reading' (duration: 110.942108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:24.543907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.508236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:21:24.543949Z","caller":"traceutil/trace.go:171","msg":"trace[1091468000] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission; range_end:; response_count:0; response_revision:1391; }","duration":"144.624228ms","start":"2026-07-30T19:21:24.39931Z","end":"2026-07-30T19:21:24.543934Z","steps":["trace[1091468000] 'agreement among raft nodes before linearized reading' (duration: 144.468684ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.652016Z","caller":"traceutil/trace.go:171","msg":"trace[442350854] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"100.135136ms","start":"2026-07-30T19:21:24.551798Z","end":"2026-07-30T19:21:24.651933Z","steps":["trace[442350854] 'process raft request' (duration: 95.923514ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.694002Z","caller":"traceutil/trace.go:171","msg":"trace[1529412085] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"114.12149ms","start":"2026-07-30T19:21:24.579843Z","end":"2026-07-30T19:21:24.693964Z","steps":["trace[1529412085] 'process raft request' (duration: 113.802051ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.694023Z","caller":"traceutil/trace.go:171","msg":"trace[1639187544] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"113.276186ms","start":"2026-07-30T19:21:24.580726Z","end":"2026-07-30T19:21:24.694002Z","steps":["trace[1639187544] 'process raft request' (duration: 113.159563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:25.01673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.758273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-07-30T19:21:25.01686Z","caller":"traceutil/trace.go:171","msg":"trace[769935673] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:1414; }","duration":"118.949909ms","start":"2026-07-30T19:21:24.89789Z","end":"2026-07-30T19:21:25.01684Z","steps":["trace[769935673] 'range keys from in-memory index tree' (duration: 118.568398ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:25.018122Z","caller":"traceutil/trace.go:171","msg":"trace[1590518214] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"114.928752ms","start":"2026-07-30T19:21:24.902877Z","end":"2026-07-30T19:21:25.017806Z","steps":["trace[1590518214] 'process raft request' (duration: 114.709366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:25.2427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.140293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:21:25.242818Z","caller":"traceutil/trace.go:171","msg":"trace[1915256495] linearizableReadLoop","detail":"{readStateIndex:1465; appliedIndex:1464; }","duration":"172.404798ms","start":"2026-07-30T19:21:25.070397Z","end":"2026-07-30T19:21:25.242802Z","steps":["trace[1915256495] 'read index received' (duration: 62.001167ms)","trace[1915256495] 'applied index is now lower than readState.Index' (duration: 110.402391ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:25.242935Z","caller":"traceutil/trace.go:171","msg":"trace[1424663672] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"210.972159ms","start":"2026-07-30T19:21:25.031925Z","end":"2026-07-30T19:21:25.242897Z","steps":["trace[1424663672] 'process raft request' (duration: 100.411693ms)","trace[1424663672] 'compare' (duration: 109.903266ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:21:25.243005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.597413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:21:25.243066Z","caller":"traceutil/trace.go:171","msg":"trace[562062729] range","detail":"{range_begin:/registry/roles/ingress-nginx/ingress-nginx-admission; range_end:; response_count:0; response_revision:1417; }","duration":"172.675555ms","start":"2026-07-30T19:21:25.070381Z","end":"2026-07-30T19:21:25.243056Z","steps":["trace[562062729] 'agreement among raft nodes before linearized reading' (duration: 172.50828ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:25.51666Z","caller":"traceutil/trace.go:171","msg":"trace[1377437124] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"233.704592ms","start":"2026-07-30T19:21:25.282924Z","end":"2026-07-30T19:21:25.516629Z","steps":["trace[1377437124] 'process raft request' (duration: 174.167887ms)","trace[1377437124] 'compare' (duration: 59.125773ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:26.594308Z","caller":"traceutil/trace.go:171","msg":"trace[993675288] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"273.62655ms","start":"2026-07-30T19:21:26.320665Z","end":"2026-07-30T19:21:26.594291Z","steps":["trace[993675288] 'process raft request' (duration: 273.515457ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:26.595052Z","caller":"traceutil/trace.go:171","msg":"trace[1598349426] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"198.60802ms","start":"2026-07-30T19:21:26.396417Z","end":"2026-07-30T19:21:26.595025Z","steps":["trace[1598349426] 'process raft request' (duration: 198.462246ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:27.138985Z","caller":"traceutil/trace.go:171","msg":"trace[1729348668] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"114.358641ms","start":"2026-07-30T19:21:27.02461Z","end":"2026-07-30T19:21:27.138968Z","steps":["trace[1729348668] 'process raft request' (duration: 114.260669ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:28.91173Z","caller":"traceutil/trace.go:171","msg":"trace[734945529] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"310.721741ms","start":"2026-07-30T19:21:28.600985Z","end":"2026-07-30T19:21:28.911707Z","steps":["trace[734945529] 'process raft request' (duration: 310.554156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:28.911873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:28.600956Z","time spent":"310.840155ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:21:29.366835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.429633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:21:29.367472Z","caller":"traceutil/trace.go:171","msg":"trace[1170623884] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"451.683083ms","start":"2026-07-30T19:21:28.915759Z","end":"2026-07-30T19:21:29.367442Z","steps":["trace[1170623884] 'process raft request' (duration: 167.566001ms)","trace[1170623884] 'compare' (duration: 283.186346ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:29.367504Z","caller":"traceutil/trace.go:171","msg":"trace[1601936790] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"451.669663ms","start":"2026-07-30T19:21:28.915785Z","end":"2026-07-30T19:21:29.367455Z","steps":["trace[1601936790] 'process raft request' (duration: 451.141638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:29.367631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:28.915741Z","time spent":"451.828077ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:21:29.367833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:28.915765Z","time spent":"451.819077ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:21:29.974855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.201808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:21:29.975635Z","caller":"traceutil/trace.go:171","msg":"trace[186579626] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"603.430287ms","start":"2026-07-30T19:21:29.372175Z","end":"2026-07-30T19:21:29.975605Z","steps":["trace[186579626] 'process raft request' (duration: 175.381374ms)","trace[186579626] 'compare' (duration: 426.635982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:21:29.975763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:29.372154Z","time spent":"603.5388ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:21:29.975766Z","caller":"traceutil/trace.go:171","msg":"trace[810635848] linearizableReadLoop","detail":"{readStateIndex:1504; appliedIndex:1503; }","duration":"514.370216ms","start":"2026-07-30T19:21:29.461235Z","end":"2026-07-30T19:21:29.975605Z","steps":["trace[810635848] 'read index received' (duration: 86.304273ms)","trace[810635848] 'applied index is now lower than readState.Index' (duration: 428.063963ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:21:29.975661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.416547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:21:29.97621Z","caller":"traceutil/trace.go:171","msg":"trace[1012627987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1455; }","duration":"514.999333ms","start":"2026-07-30T19:21:29.461194Z","end":"2026-07-30T19:21:29.976194Z","steps":["trace[1012627987] 'agreement among raft nodes before linearized reading' (duration: 514.415997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:29.976106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.960472ms","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:5"} {"level":"info","ts":"2026-07-30T19:21:29.976355Z","caller":"traceutil/trace.go:171","msg":"trace[1752236154] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1455; }","duration":"450.375304ms","start":"2026-07-30T19:21:29.525937Z","end":"2026-07-30T19:21:29.976312Z","steps":["trace[1752236154] 'agreement among raft nodes before linearized reading' (duration: 449.958932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:29.976418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:29.525915Z","time spent":"450.484327ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:21:29.976542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:29.461175Z","time spent":"515.351674ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T19:21:30.910819Z","caller":"traceutil/trace.go:171","msg":"trace[1920201961] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"240.040752ms","start":"2026-07-30T19:21:30.670755Z","end":"2026-07-30T19:21:30.910796Z","steps":["trace[1920201961] 'process raft request' (duration: 239.884478ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:51.349717Z","caller":"traceutil/trace.go:171","msg":"trace[1260662909] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"108.53347ms","start":"2026-07-30T19:21:51.241161Z","end":"2026-07-30T19:21:51.349694Z","steps":["trace[1260662909] 'compare' (duration: 97.982639ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:51.350344Z","caller":"traceutil/trace.go:171","msg":"trace[74378601] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"108.261652ms","start":"2026-07-30T19:21:51.242056Z","end":"2026-07-30T19:21:51.350318Z","steps":["trace[74378601] 'process raft request' (duration: 108.073677ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:51.350454Z","caller":"traceutil/trace.go:171","msg":"trace[954356285] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"108.952062ms","start":"2026-07-30T19:21:51.241487Z","end":"2026-07-30T19:21:51.350439Z","steps":["trace[954356285] 'process raft request' (duration: 108.391946ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:51.350505Z","caller":"traceutil/trace.go:171","msg":"trace[768608731] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"108.131938ms","start":"2026-07-30T19:21:51.24235Z","end":"2026-07-30T19:21:51.350481Z","steps":["trace[768608731] 'process raft request' (duration: 107.847939ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:51.350727Z","caller":"traceutil/trace.go:171","msg":"trace[276068459] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"106.351218ms","start":"2026-07-30T19:21:51.244319Z","end":"2026-07-30T19:21:51.350671Z","steps":["trace[276068459] 'process raft request' (duration: 105.923236ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:53.46471Z","caller":"traceutil/trace.go:171","msg":"trace[1520470288] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"305.334266ms","start":"2026-07-30T19:21:53.159353Z","end":"2026-07-30T19:21:53.464688Z","steps":["trace[1520470288] 'read index received' (duration: 305.12072ms)","trace[1520470288] 'applied index is now lower than readState.Index' (duration: 212.636µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:53.464899Z","caller":"traceutil/trace.go:171","msg":"trace[1493995341] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"417.599471ms","start":"2026-07-30T19:21:53.047214Z","end":"2026-07-30T19:21:53.464814Z","steps":["trace[1493995341] 'process raft request' (duration: 417.335733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:53.465003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.634985ms","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":"info","ts":"2026-07-30T19:21:53.465072Z","caller":"traceutil/trace.go:171","msg":"trace[2027727650] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1849; }","duration":"305.754759ms","start":"2026-07-30T19:21:53.1593Z","end":"2026-07-30T19:21:53.465055Z","steps":["trace[2027727650] 'agreement among raft nodes before linearized reading' (duration: 305.560603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:53.465116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:53.159282Z","time spent":"305.82715ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-30T19:21:53.465119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:53.04719Z","time spent":"417.819627ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:21:54.194388Z","caller":"traceutil/trace.go:171","msg":"trace[766304921] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"120.983543ms","start":"2026-07-30T19:21:54.073385Z","end":"2026-07-30T19:21:54.194368Z","steps":["trace[766304921] 'read index received' (duration: 120.919201ms)","trace[766304921] 'applied index is now lower than readState.Index' (duration: 61.792µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:54.194518Z","caller":"traceutil/trace.go:171","msg":"trace[366885262] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"187.231072ms","start":"2026-07-30T19:21:54.007246Z","end":"2026-07-30T19:21:54.194477Z","steps":["trace[366885262] 'process raft request' (duration: 187.006306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:54.194574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.172828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T19:21:54.194599Z","caller":"traceutil/trace.go:171","msg":"trace[1768378006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1853; }","duration":"121.249411ms","start":"2026-07-30T19:21:54.073344Z","end":"2026-07-30T19:21:54.194593Z","steps":["trace[1768378006] 'agreement among raft nodes before linearized reading' (duration: 121.121897ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:59.761624Z","caller":"traceutil/trace.go:171","msg":"trace[1917160103] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"117.608993ms","start":"2026-07-30T19:21:59.643986Z","end":"2026-07-30T19:21:59.761595Z","steps":["trace[1917160103] 'process raft request' (duration: 117.452758ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:22:07.523798Z","caller":"traceutil/trace.go:171","msg":"trace[1941091871] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2047; }","duration":"109.062922ms","start":"2026-07-30T19:22:07.414719Z","end":"2026-07-30T19:22:07.523782Z","steps":["trace[1941091871] 'read index received' (duration: 93.782368ms)","trace[1941091871] 'applied index is now lower than readState.Index' (duration: 15.279524ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:22:07.523947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.182045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:22:07.523981Z","caller":"traceutil/trace.go:171","msg":"trace[534136797] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1967; }","duration":"109.300021ms","start":"2026-07-30T19:22:07.414668Z","end":"2026-07-30T19:22:07.523968Z","steps":["trace[534136797] 'agreement among raft nodes before linearized reading' (duration: 109.199957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:28.721639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.772657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:22:28.721674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.409393ms","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-07-30T19:22:28.721739Z","caller":"traceutil/trace.go:171","msg":"trace[618245630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2098; }","duration":"234.520257ms","start":"2026-07-30T19:22:28.487203Z","end":"2026-07-30T19:22:28.721723Z","steps":["trace[618245630] 'range keys from in-memory index tree' (duration: 234.27415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:28.722058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.214538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-30T19:22:28.722163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.187904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T19:22:28.722269Z","caller":"traceutil/trace.go:171","msg":"trace[1704887601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2098; }","duration":"206.345209ms","start":"2026-07-30T19:22:28.515902Z","end":"2026-07-30T19:22:28.722247Z","steps":["trace[1704887601] 'range keys from in-memory index tree' (duration: 206.001588ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:22:28.722192Z","caller":"traceutil/trace.go:171","msg":"trace[1238453878] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2098; }","duration":"192.450085ms","start":"2026-07-30T19:22:28.529691Z","end":"2026-07-30T19:22:28.722141Z","steps":["trace[1238453878] 'range keys from in-memory index tree' (duration: 191.947771ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:22:28.722959Z","caller":"traceutil/trace.go:171","msg":"trace[200421269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2098; }","duration":"186.115626ms","start":"2026-07-30T19:22:28.536824Z","end":"2026-07-30T19:22:28.722939Z","steps":["trace[200421269] 'range keys from in-memory index tree' (duration: 184.723996ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:22:28.725915Z","caller":"traceutil/trace.go:171","msg":"trace[78677651] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"155.634492ms","start":"2026-07-30T19:22:28.570261Z","end":"2026-07-30T19:22:28.725895Z","steps":["trace[78677651] 'process raft request' (duration: 155.391536ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:22:28.867694Z","caller":"traceutil/trace.go:171","msg":"trace[170657529] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"140.821892ms","start":"2026-07-30T19:22:28.726845Z","end":"2026-07-30T19:22:28.867667Z","steps":["trace[170657529] 'process raft request' (duration: 116.75784ms)","trace[170657529] 'compare' (duration: 23.941918ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:22:29.983301Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.138329714s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T19:22:30.004999Z","caller":"traceutil/trace.go:171","msg":"trace[604167730] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"1.277873038s","start":"2026-07-30T19:22:28.7271Z","end":"2026-07-30T19:22:30.004973Z","steps":["trace[604167730] 'process raft request' (duration: 1.256413821s)","trace[604167730] 'compare' (duration: 20.334286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:22:30.005237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:28.727092Z","time spent":"1.277972671s","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:22:30.005443Z","caller":"traceutil/trace.go:171","msg":"trace[468553243] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2184; }","duration":"1.174019444s","start":"2026-07-30T19:22:28.831405Z","end":"2026-07-30T19:22:30.005425Z","steps":["trace[468553243] 'read index received' (duration: 12.169654ms)","trace[468553243] 'applied index is now lower than readState.Index' (duration: 1.16184772s)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:22:30.005049Z","caller":"traceutil/trace.go:171","msg":"trace[1158991320] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"1.277880459s","start":"2026-07-30T19:22:28.727143Z","end":"2026-07-30T19:22:30.005023Z","steps":["trace[1158991320] 'process raft request' (duration: 1.277729655s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:30.005661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174245291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-30T19:22:30.005716Z","caller":"traceutil/trace.go:171","msg":"trace[4466535] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2102; }","duration":"1.174338894s","start":"2026-07-30T19:22:28.831365Z","end":"2026-07-30T19:22:30.005704Z","steps":["trace[4466535] 'agreement among raft nodes before linearized reading' (duration: 1.174163009s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:30.005748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:28.831351Z","time spent":"1.174389535s","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-30T19:22:30.006385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.780621ms","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":"info","ts":"2026-07-30T19:22:30.006434Z","caller":"traceutil/trace.go:171","msg":"trace[893967464] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"272.869833ms","start":"2026-07-30T19:22:29.733552Z","end":"2026-07-30T19:22:30.006422Z","steps":["trace[893967464] 'agreement among raft nodes before linearized reading' (duration: 272.75718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:30.00655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.324003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T19:22:30.006589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.923893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T19:22:30.006627Z","caller":"traceutil/trace.go:171","msg":"trace[645418431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2103; }","duration":"237.984445ms","start":"2026-07-30T19:22:29.768632Z","end":"2026-07-30T19:22:30.006617Z","steps":["trace[645418431] 'agreement among raft nodes before linearized reading' (duration: 237.893383ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:22:30.006635Z","caller":"traceutil/trace.go:171","msg":"trace[1831172521] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2103; }","duration":"504.469507ms","start":"2026-07-30T19:22:29.502144Z","end":"2026-07-30T19:22:30.006614Z","steps":["trace[1831172521] 'agreement among raft nodes before linearized reading' (duration: 504.280822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:30.006698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:29.502123Z","time spent":"504.5588ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":15,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:22:30.006434Z","caller":"traceutil/trace.go:171","msg":"trace[1155301976] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"384.029824ms","start":"2026-07-30T19:22:29.622371Z","end":"2026-07-30T19:22:30.006401Z","steps":["trace[1155301976] 'process raft request' (duration: 383.772057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:30.007112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:29.62234Z","time spent":"384.581269ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:22:30.007156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.067163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:22:30.007344Z","caller":"traceutil/trace.go:171","msg":"trace[1435072881] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2103; }","duration":"620.147365ms","start":"2026-07-30T19:22:29.387039Z","end":"2026-07-30T19:22:30.007187Z","steps":["trace[1435072881] 'agreement among raft nodes before linearized reading' (duration: 619.168868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:30.007399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:29.387017Z","time spent":"620.367172ms","remote":"127.0.0.1:51530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:22:30.007931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:28.727121Z","time spent":"1.278521447s","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:22:30.329917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.955443ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:22:30.330051Z","caller":"traceutil/trace.go:171","msg":"trace[251443782] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2106; }","duration":"202.113217ms","start":"2026-07-30T19:22:30.127919Z","end":"2026-07-30T19:22:30.330033Z","steps":["trace[251443782] 'range keys from in-memory index tree' (duration: 201.924112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:30.904513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":820675154564598814,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T19:22:31.405105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":820675154564598814,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T19:22:31.56848Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.190281187s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T19:22:31.568883Z","caller":"traceutil/trace.go:171","msg":"trace[1504198230] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"1.164769745s","start":"2026-07-30T19:22:30.404088Z","end":"2026-07-30T19:22:31.568858Z","steps":["trace[1504198230] 'read index received' (duration: 1.164518938s)","trace[1504198230] 'applied index is now lower than readState.Index' (duration: 249.967µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:22:31.568971Z","caller":"traceutil/trace.go:171","msg":"trace[888624306] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"1.190948426s","start":"2026-07-30T19:22:30.378012Z","end":"2026-07-30T19:22:31.56896Z","steps":["trace[888624306] 'process raft request' (duration: 1.190658007s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:31.569105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:30.377987Z","time spent":"1.191016198s","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:22:31.569132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107981305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:22:31.569238Z","caller":"traceutil/trace.go:171","msg":"trace[1939554706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2107; }","duration":"1.108148211s","start":"2026-07-30T19:22:30.461065Z","end":"2026-07-30T19:22:31.569213Z","steps":["trace[1939554706] 'agreement among raft nodes before linearized reading' (duration: 1.107968206s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:31.569301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:30.461054Z","time spent":"1.108233143s","remote":"127.0.0.1:48982","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-07-30T19:22:31.569335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165251798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T19:22:31.56938Z","caller":"traceutil/trace.go:171","msg":"trace[793931063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2107; }","duration":"1.16533139s","start":"2026-07-30T19:22:30.404038Z","end":"2026-07-30T19:22:31.569369Z","steps":["trace[793931063] 'agreement among raft nodes before linearized reading' (duration: 1.165193836s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:31.569415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:30.404021Z","time spent":"1.165387612s","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T19:22:31.56946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.617975ms","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-07-30T19:22:31.569538Z","caller":"traceutil/trace.go:171","msg":"trace[2007311467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2107; }","duration":"698.737917ms","start":"2026-07-30T19:22:30.870784Z","end":"2026-07-30T19:22:31.569522Z","steps":["trace[2007311467] 'agreement among raft nodes before linearized reading' (duration: 698.502821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:31.569575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:30.870771Z","time spent":"698.79708ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T19:22:31.569641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.805479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:22:31.569719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.404578ms","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":"info","ts":"2026-07-30T19:22:31.56976Z","caller":"traceutil/trace.go:171","msg":"trace[1431273911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2107; }","duration":"609.972893ms","start":"2026-07-30T19:22:30.959769Z","end":"2026-07-30T19:22:31.569742Z","steps":["trace[1431273911] 'agreement among raft nodes before linearized reading' (duration: 609.47803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:31.569816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:30.959755Z","time spent":"610.047485ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:22:31.569772Z","caller":"traceutil/trace.go:171","msg":"trace[824206419] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2107; }","duration":"554.46649ms","start":"2026-07-30T19:22:31.015288Z","end":"2026-07-30T19:22:31.569755Z","steps":["trace[824206419] 'agreement among raft nodes before linearized reading' (duration: 553.994307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:31.570332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:31.015283Z","time spent":"555.003655ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-30T19:22:32.038944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.074239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c7279f21b06b71\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-30T19:22:32.039026Z","caller":"traceutil/trace.go:171","msg":"trace[245489858] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c7279f21b06b71; range_end:; response_count:1; response_revision:2109; }","duration":"339.196232ms","start":"2026-07-30T19:22:31.699813Z","end":"2026-07-30T19:22:32.039009Z","steps":["trace[245489858] 'range keys from in-memory index tree' (duration: 338.918931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:32.040237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:31.699792Z","time spent":"340.427636ms","remote":"127.0.0.1:49086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c7279f21b06b71\" "} {"level":"warn","ts":"2026-07-30T19:22:32.039427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.068352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-07-30T19:22:32.040434Z","caller":"traceutil/trace.go:171","msg":"trace[476825915] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2109; }","duration":"289.09015ms","start":"2026-07-30T19:22:31.75133Z","end":"2026-07-30T19:22:32.04042Z","steps":["trace[476825915] 'range keys from in-memory index tree' (duration: 287.835755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:32.554803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.371742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:22:32.554911Z","caller":"traceutil/trace.go:171","msg":"trace[634583662] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"511.406378ms","start":"2026-07-30T19:22:32.043487Z","end":"2026-07-30T19:22:32.554893Z","steps":["trace[634583662] 'process raft request' (duration: 104.853431ms)","trace[634583662] 'compare' (duration: 406.224898ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:22:32.554973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.043466Z","time spent":"511.479081ms","remote":"127.0.0.1:49086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:22:32.658432Z","caller":"traceutil/trace.go:171","msg":"trace[2040494680] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"614.656184ms","start":"2026-07-30T19:22:32.043753Z","end":"2026-07-30T19:22:32.65841Z","steps":["trace[2040494680] 'process raft request' (duration: 614.579092ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:22:32.658483Z","caller":"traceutil/trace.go:171","msg":"trace[280439792] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"614.758856ms","start":"2026-07-30T19:22:32.04369Z","end":"2026-07-30T19:22:32.658449Z","steps":["trace[280439792] 'process raft request' (duration: 614.152648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:32.658591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.043746Z","time spent":"614.761817ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:22:32.658655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.043666Z","time spent":"614.88972ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:22:32.658755Z","caller":"traceutil/trace.go:171","msg":"trace[1879720517] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"614.954042ms","start":"2026-07-30T19:22:32.043778Z","end":"2026-07-30T19:22:32.658732Z","steps":["trace[1879720517] 'process raft request' (duration: 614.597672ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:22:32.658916Z","caller":"traceutil/trace.go:171","msg":"trace[736147688] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"615.088686ms","start":"2026-07-30T19:22:32.043811Z","end":"2026-07-30T19:22:32.658899Z","steps":["trace[736147688] 'process raft request' (duration: 614.760236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:32.65901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.043805Z","time spent":"615.156837ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:22:32.658998Z","caller":"traceutil/trace.go:171","msg":"trace[759265077] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2195; }","duration":"608.712984ms","start":"2026-07-30T19:22:32.050258Z","end":"2026-07-30T19:22:32.658971Z","steps":["trace[759265077] 'read index received' (duration: 98.060957ms)","trace[759265077] 'applied index is now lower than readState.Index' (duration: 510.649767ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:22:32.659044Z","caller":"traceutil/trace.go:171","msg":"trace[1395967831] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"615.191108ms","start":"2026-07-30T19:22:32.043834Z","end":"2026-07-30T19:22:32.659025Z","steps":["trace[1395967831] 'process raft request' (duration: 614.953421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:32.659121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.043826Z","time spent":"615.25501ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:22:32.658935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.04377Z","time spent":"615.123306ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:22:32.659242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.385544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:22:32.659379Z","caller":"traceutil/trace.go:171","msg":"trace[930496839] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2116; }","duration":"339.56161ms","start":"2026-07-30T19:22:32.319802Z","end":"2026-07-30T19:22:32.659364Z","steps":["trace[930496839] 'agreement among raft nodes before linearized reading' (duration: 339.402805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:32.659433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.31978Z","time spent":"339.636221ms","remote":"127.0.0.1:49156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:22:32.659194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.92196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:22:32.659498Z","caller":"traceutil/trace.go:171","msg":"trace[1501972419] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2116; }","duration":"609.257519ms","start":"2026-07-30T19:22:32.050228Z","end":"2026-07-30T19:22:32.659486Z","steps":["trace[1501972419] 'agreement among raft nodes before linearized reading' (duration: 608.885789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:32.659544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.050221Z","time spent":"609.31386ms","remote":"127.0.0.1:49498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-30T19:22:32.659277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.081662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T19:22:32.659683Z","caller":"traceutil/trace.go:171","msg":"trace[292179410] range","detail":"{range_begin:/registry/masterleases/162.253.55.222; range_end:; response_count:1; response_revision:2116; }","duration":"437.468253ms","start":"2026-07-30T19:22:32.222145Z","end":"2026-07-30T19:22:32.659613Z","steps":["trace[292179410] 'agreement among raft nodes before linearized reading' (duration: 436.994789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:32.659819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:32.222114Z","time spent":"437.683809ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.222\" "} {"level":"info","ts":"2026-07-30T19:24:06.174168Z","caller":"traceutil/trace.go:171","msg":"trace[252434090] linearizableReadLoop","detail":"{readStateIndex:2827; appliedIndex:2826; }","duration":"111.083053ms","start":"2026-07-30T19:24:06.063067Z","end":"2026-07-30T19:24:06.17415Z","steps":["trace[252434090] 'read index received' (duration: 110.87352ms)","trace[252434090] 'applied index is now lower than readState.Index' (duration: 208.523µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:24:06.174394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.316527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T19:24:06.174429Z","caller":"traceutil/trace.go:171","msg":"trace[1139247943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2720; }","duration":"111.409609ms","start":"2026-07-30T19:24:06.063011Z","end":"2026-07-30T19:24:06.174421Z","steps":["trace[1139247943] 'agreement among raft nodes before linearized reading' (duration: 111.256377ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:06.174226Z","caller":"traceutil/trace.go:171","msg":"trace[1385655533] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"147.397128ms","start":"2026-07-30T19:24:06.026803Z","end":"2026-07-30T19:24:06.1742Z","steps":["trace[1385655533] 'process raft request' (duration: 147.193505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:06.390439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.648813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:24:06.39054Z","caller":"traceutil/trace.go:171","msg":"trace[376988616] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"213.348314ms","start":"2026-07-30T19:24:06.177174Z","end":"2026-07-30T19:24:06.390522Z","steps":["trace[376988616] 'process raft request' (duration: 95.538698ms)","trace[376988616] 'compare' (duration: 117.561192ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:24:06.415713Z","caller":"traceutil/trace.go:171","msg":"trace[1273408949] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"237.183401ms","start":"2026-07-30T19:24:06.178501Z","end":"2026-07-30T19:24:06.415684Z","steps":["trace[1273408949] 'process raft request' (duration: 237.050018ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:06.55844Z","caller":"traceutil/trace.go:171","msg":"trace[486114700] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"102.417755ms","start":"2026-07-30T19:24:06.455999Z","end":"2026-07-30T19:24:06.558417Z","steps":["trace[486114700] 'process raft request' (duration: 71.021711ms)","trace[486114700] 'compare' (duration: 30.970606ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:27:45.377805Z","caller":"traceutil/trace.go:171","msg":"trace[503598384] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"111.716929ms","start":"2026-07-30T19:27:45.266063Z","end":"2026-07-30T19:27:45.37778Z","steps":["trace[503598384] 'process raft request' (duration: 100.567778ms)","trace[503598384] 'compare' (duration: 10.701219ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:27:45.659936Z","caller":"traceutil/trace.go:171","msg":"trace[1251869465] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"389.951433ms","start":"2026-07-30T19:27:45.269949Z","end":"2026-07-30T19:27:45.659901Z","steps":["trace[1251869465] 'process raft request' (duration: 308.67252ms)","trace[1251869465] 'compare' (duration: 81.015087ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:27:45.660073Z","caller":"traceutil/trace.go:171","msg":"trace[734061912] linearizableReadLoop","detail":"{readStateIndex:4746; appliedIndex:4744; }","duration":"198.49921ms","start":"2026-07-30T19:27:45.461554Z","end":"2026-07-30T19:27:45.660053Z","steps":["trace[734061912] 'read index received' (duration: 116.986531ms)","trace[734061912] 'applied index is now lower than readState.Index' (duration: 81.511669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:27:45.660285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:45.269932Z","time spent":"390.094627ms","remote":"127.0.0.1:58792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:27:45.660332Z","caller":"traceutil/trace.go:171","msg":"trace[472134061] transaction","detail":"{read_only:false; response_revision:4568; number_of_response:1; }","duration":"331.914984ms","start":"2026-07-30T19:27:45.328406Z","end":"2026-07-30T19:27:45.660321Z","steps":["trace[472134061] 'process raft request' (duration: 331.428422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:45.660406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:45.328392Z","time spent":"331.967306ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:27:45.660577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.036574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:27:45.660614Z","caller":"traceutil/trace.go:171","msg":"trace[449164078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4568; }","duration":"199.123356ms","start":"2026-07-30T19:27:45.461481Z","end":"2026-07-30T19:27:45.660605Z","steps":["trace[449164078] 'agreement among raft nodes before linearized reading' (duration: 199.064934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:45.660944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.854371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-30T19:27:45.660981Z","caller":"traceutil/trace.go:171","msg":"trace[1714684704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4568; }","duration":"111.935903ms","start":"2026-07-30T19:27:45.549034Z","end":"2026-07-30T19:27:45.66097Z","steps":["trace[1714684704] 'agreement among raft nodes before linearized reading' (duration: 111.786129ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:50.859474Z","caller":"traceutil/trace.go:171","msg":"trace[616406830] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"100.125493ms","start":"2026-07-30T19:27:50.759324Z","end":"2026-07-30T19:27:50.859449Z","steps":["trace[616406830] 'process raft request' (duration: 74.968159ms)","trace[616406830] 'compare' (duration: 24.962119ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:27:52.161217Z","caller":"traceutil/trace.go:171","msg":"trace[859084438] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4849; }","duration":"462.041479ms","start":"2026-07-30T19:27:51.699153Z","end":"2026-07-30T19:27:52.161194Z","steps":["trace[859084438] 'read index received' (duration: 461.837734ms)","trace[859084438] 'applied index is now lower than readState.Index' (duration: 202.965µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:27:52.161463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.291336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-30T19:27:52.161518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.764892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:27:52.16155Z","caller":"traceutil/trace.go:171","msg":"trace[824590140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4671; }","duration":"462.425099ms","start":"2026-07-30T19:27:51.699106Z","end":"2026-07-30T19:27:52.161531Z","steps":["trace[824590140] 'agreement among raft nodes before linearized reading' (duration: 462.192303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.161528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.664077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:27:52.161583Z","caller":"traceutil/trace.go:171","msg":"trace[1635567325] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4671; }","duration":"125.894065ms","start":"2026-07-30T19:27:52.035672Z","end":"2026-07-30T19:27:52.161566Z","steps":["trace[1635567325] 'agreement among raft nodes before linearized reading' (duration: 125.765742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.161592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:51.699092Z","time spent":"462.491941ms","remote":"127.0.0.1:49300","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-07-30T19:27:52.161629Z","caller":"traceutil/trace.go:171","msg":"trace[1325442018] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4671; }","duration":"115.80574ms","start":"2026-07-30T19:27:52.045803Z","end":"2026-07-30T19:27:52.161609Z","steps":["trace[1325442018] 'agreement among raft nodes before linearized reading' (duration: 115.593565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.161653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.691524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9824cca-c8e5-471e-917f-711c53bde076\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-30T19:27:52.161735Z","caller":"traceutil/trace.go:171","msg":"trace[902812873] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9824cca-c8e5-471e-917f-711c53bde076; range_end:; response_count:1; response_revision:4671; }","duration":"431.828727ms","start":"2026-07-30T19:27:51.729888Z","end":"2026-07-30T19:27:52.161717Z","steps":["trace[902812873] 'agreement among raft nodes before linearized reading' (duration: 431.583331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.161792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:51.729868Z","time spent":"431.91512ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9824cca-c8e5-471e-917f-711c53bde076\" "} {"level":"warn","ts":"2026-07-30T19:27:52.161958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.063375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:27:52.162056Z","caller":"traceutil/trace.go:171","msg":"trace[271313317] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4671; }","duration":"461.185327ms","start":"2026-07-30T19:27:51.700856Z","end":"2026-07-30T19:27:52.162041Z","steps":["trace[271313317] 'agreement among raft nodes before linearized reading' (duration: 460.48538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.162188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:51.700848Z","time spent":"461.32035ms","remote":"127.0.0.1:45658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:27:52.161314Z","caller":"traceutil/trace.go:171","msg":"trace[1036157518] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"485.232034ms","start":"2026-07-30T19:27:51.676045Z","end":"2026-07-30T19:27:52.161277Z","steps":["trace[1036157518] 'process raft request' (duration: 484.999338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.162356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:51.676029Z","time spent":"486.258049ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:27:52.663361Z","caller":"traceutil/trace.go:171","msg":"trace[529156748] linearizableReadLoop","detail":"{readStateIndex:4851; appliedIndex:4850; }","duration":"492.43735ms","start":"2026-07-30T19:27:52.170899Z","end":"2026-07-30T19:27:52.663337Z","steps":["trace[529156748] 'read index received' (duration: 459.408427ms)","trace[529156748] 'applied index is now lower than readState.Index' (duration: 33.027763ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:27:52.663454Z","caller":"traceutil/trace.go:171","msg":"trace[2014040100] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"496.408039ms","start":"2026-07-30T19:27:52.167003Z","end":"2026-07-30T19:27:52.663411Z","steps":["trace[2014040100] 'process raft request' (duration: 463.288663ms)","trace[2014040100] 'compare' (duration: 32.86804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:27:52.663492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.574963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:27:52.663528Z","caller":"traceutil/trace.go:171","msg":"trace[1345105166] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:4672; }","duration":"492.684465ms","start":"2026-07-30T19:27:52.170832Z","end":"2026-07-30T19:27:52.663516Z","steps":["trace[1345105166] 'agreement among raft nodes before linearized reading' (duration: 492.611354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.663563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.170814Z","time spent":"492.739047ms","remote":"127.0.0.1:51550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:27:52.6636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.166984Z","time spent":"496.535113ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:27:52.85531Z","caller":"traceutil/trace.go:171","msg":"trace[1688463516] linearizableReadLoop","detail":"{readStateIndex:4852; appliedIndex:4851; }","duration":"191.865049ms","start":"2026-07-30T19:27:52.663422Z","end":"2026-07-30T19:27:52.855287Z","steps":["trace[1688463516] 'read index received' (duration: 145.650674ms)","trace[1688463516] 'applied index is now lower than readState.Index' (duration: 46.213235ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:27:52.855321Z","caller":"traceutil/trace.go:171","msg":"trace[1436771969] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"578.315146ms","start":"2026-07-30T19:27:52.276982Z","end":"2026-07-30T19:27:52.855297Z","steps":["trace[1436771969] 'process raft request' (duration: 531.28392ms)","trace[1436771969] 'compare' (duration: 46.858751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:27:52.855456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.898704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:27:52.85547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.276969Z","time spent":"578.442499ms","remote":"127.0.0.1:49086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T19:27:52.855554Z","caller":"traceutil/trace.go:171","msg":"trace[1142137981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4673; }","duration":"394.024117ms","start":"2026-07-30T19:27:52.461465Z","end":"2026-07-30T19:27:52.855489Z","steps":["trace[1142137981] 'agreement among raft nodes before linearized reading' (duration: 393.903774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.855668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.461448Z","time spent":"394.208943ms","remote":"127.0.0.1:48982","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-07-30T19:27:52.855715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.64661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-07-30T19:27:52.85588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.55729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-30T19:27:52.855922Z","caller":"traceutil/trace.go:171","msg":"trace[702894030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4673; }","duration":"570.636412ms","start":"2026-07-30T19:27:52.285274Z","end":"2026-07-30T19:27:52.855911Z","steps":["trace[702894030] 'agreement among raft nodes before linearized reading' (duration: 570.55276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.855955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.285252Z","time spent":"570.696723ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T19:27:52.856028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.457653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T19:27:52.855866Z","caller":"traceutil/trace.go:171","msg":"trace[1766338991] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4673; }","duration":"539.808194ms","start":"2026-07-30T19:27:52.316039Z","end":"2026-07-30T19:27:52.855847Z","steps":["trace[1766338991] 'agreement among raft nodes before linearized reading' (duration: 539.583869ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:52.856143Z","caller":"traceutil/trace.go:171","msg":"trace[726402516] range","detail":"{range_begin:/registry/masterleases/162.253.55.222; range_end:; response_count:1; response_revision:4673; }","duration":"617.554195ms","start":"2026-07-30T19:27:52.238532Z","end":"2026-07-30T19:27:52.856086Z","steps":["trace[726402516] 'agreement among raft nodes before linearized reading' (duration: 617.303499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.856231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.238517Z","time spent":"617.696279ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.222\" "} {"level":"warn","ts":"2026-07-30T19:27:52.856133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.316028Z","time spent":"540.089761ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-30T19:27:52.855548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.016926ms","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-07-30T19:27:52.855718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.048475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T19:27:52.856395Z","caller":"traceutil/trace.go:171","msg":"trace[731383515] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4673; }","duration":"545.919789ms","start":"2026-07-30T19:27:52.310463Z","end":"2026-07-30T19:27:52.856382Z","steps":["trace[731383515] 'agreement among raft nodes before linearized reading' (duration: 544.838302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.856417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.678959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9824cca-c8e5-471e-917f-711c53bde076\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-30T19:27:52.856422Z","caller":"traceutil/trace.go:171","msg":"trace[164107140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4673; }","duration":"539.778323ms","start":"2026-07-30T19:27:52.316618Z","end":"2026-07-30T19:27:52.856396Z","steps":["trace[164107140] 'agreement among raft nodes before linearized reading' (duration: 538.84541ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:52.856484Z","caller":"traceutil/trace.go:171","msg":"trace[427691780] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9824cca-c8e5-471e-917f-711c53bde076; range_end:; response_count:1; response_revision:4673; }","duration":"579.799013ms","start":"2026-07-30T19:27:52.276668Z","end":"2026-07-30T19:27:52.856467Z","steps":["trace[427691780] 'agreement among raft nodes before linearized reading' (duration: 579.631279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.856518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.316604Z","time spent":"539.880266ms","remote":"127.0.0.1:49300","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-07-30T19:27:52.856443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.31044Z","time spent":"545.99062ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-30T19:27:52.856529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.276656Z","time spent":"579.862674ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9824cca-c8e5-471e-917f-711c53bde076\" "} {"level":"warn","ts":"2026-07-30T19:27:52.855772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.166156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T19:27:52.856965Z","caller":"traceutil/trace.go:171","msg":"trace[391946609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4673; }","duration":"555.373777ms","start":"2026-07-30T19:27:52.301574Z","end":"2026-07-30T19:27:52.856948Z","steps":["trace[391946609] 'agreement among raft nodes before linearized reading' (duration: 554.075774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:52.857022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:52.301563Z","time spent":"555.446698ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T19:27:53.063525Z","caller":"traceutil/trace.go:171","msg":"trace[1091043320] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"187.643063ms","start":"2026-07-30T19:27:52.875845Z","end":"2026-07-30T19:27:53.063488Z","steps":["trace[1091043320] 'process raft request' (duration: 187.501279ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:53.064242Z","caller":"traceutil/trace.go:171","msg":"trace[281380703] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"202.132918ms","start":"2026-07-30T19:27:52.862073Z","end":"2026-07-30T19:27:53.064206Z","steps":["trace[281380703] 'process raft request' (duration: 201.126963ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:53.064261Z","caller":"traceutil/trace.go:171","msg":"trace[1673577055] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"200.829474ms","start":"2026-07-30T19:27:52.863404Z","end":"2026-07-30T19:27:53.064234Z","steps":["trace[1673577055] 'process raft request' (duration: 199.84062ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:53.064901Z","caller":"traceutil/trace.go:171","msg":"trace[10683393] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"199.898272ms","start":"2026-07-30T19:27:52.864974Z","end":"2026-07-30T19:27:53.064872Z","steps":["trace[10683393] 'process raft request' (duration: 198.312803ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:53.065299Z","caller":"traceutil/trace.go:171","msg":"trace[1285796514] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"203.275757ms","start":"2026-07-30T19:27:52.861994Z","end":"2026-07-30T19:27:53.065269Z","steps":["trace[1285796514] 'process raft request' (duration: 201.115853ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:53.065549Z","caller":"traceutil/trace.go:171","msg":"trace[1449076741] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"204.428986ms","start":"2026-07-30T19:27:52.861043Z","end":"2026-07-30T19:27:53.065472Z","steps":["trace[1449076741] 'process raft request' (duration: 102.885994ms)","trace[1449076741] 'compare' (duration: 98.795403ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:27:53.065702Z","caller":"traceutil/trace.go:171","msg":"trace[306479517] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"204.56994ms","start":"2026-07-30T19:27:52.8611Z","end":"2026-07-30T19:27:53.06567Z","steps":["trace[306479517] 'process raft request' (duration: 201.945864ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:53.065865Z","caller":"traceutil/trace.go:171","msg":"trace[26142917] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"203.809431ms","start":"2026-07-30T19:27:52.862043Z","end":"2026-07-30T19:27:53.065852Z","steps":["trace[26142917] 'process raft request' (duration: 201.111543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:53.646271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.491862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:27:53.646428Z","caller":"traceutil/trace.go:171","msg":"trace[863005331] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4862; }","duration":"242.248692ms","start":"2026-07-30T19:27:53.404162Z","end":"2026-07-30T19:27:53.646411Z","steps":["trace[863005331] 'read index received' (duration: 94.499843ms)","trace[863005331] 'applied index is now lower than readState.Index' (duration: 147.747149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:27:53.646707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.54593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T19:27:53.646834Z","caller":"traceutil/trace.go:171","msg":"trace[1948758018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4683; }","duration":"242.699753ms","start":"2026-07-30T19:27:53.404122Z","end":"2026-07-30T19:27:53.646822Z","steps":["trace[1948758018] 'agreement among raft nodes before linearized reading' (duration: 242.398205ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:27:53.646684Z","caller":"traceutil/trace.go:171","msg":"trace[1353150106] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"356.673188ms","start":"2026-07-30T19:27:53.289969Z","end":"2026-07-30T19:27:53.646643Z","steps":["trace[1353150106] 'process raft request' (duration: 208.716845ms)","trace[1353150106] 'compare' (duration: 147.351318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:27:53.647127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:27:53.289952Z","time spent":"357.063289ms","remote":"127.0.0.1:49086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:27:54.354449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.824266ms","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-07-30T19:27:54.354516Z","caller":"traceutil/trace.go:171","msg":"trace[101807621] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4685; }","duration":"205.939319ms","start":"2026-07-30T19:27:54.148564Z","end":"2026-07-30T19:27:54.354504Z","steps":["trace[101807621] 'range keys from in-memory index tree' (duration: 205.654042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:27:54.354691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.149849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:27:54.354827Z","caller":"traceutil/trace.go:171","msg":"trace[467960443] linearizableReadLoop","detail":"{readStateIndex:4866; appliedIndex:4865; }","duration":"104.332814ms","start":"2026-07-30T19:27:54.250481Z","end":"2026-07-30T19:27:54.354814Z","steps":["trace[467960443] 'read index received' (duration: 27.541µs)","trace[467960443] 'applied index is now lower than readState.Index' (duration: 104.303703ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:27:54.355075Z","caller":"traceutil/trace.go:171","msg":"trace[18128798] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"186.797425ms","start":"2026-07-30T19:27:54.168206Z","end":"2026-07-30T19:27:54.355003Z","steps":["trace[18128798] 'process raft request' (duration: 63.114973ms)","trace[18128798] 'compare' (duration: 123.014576ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:27:54.355145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.664582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9824cca-c8e5-471e-917f-711c53bde076\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-30T19:27:54.355207Z","caller":"traceutil/trace.go:171","msg":"trace[381059013] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9824cca-c8e5-471e-917f-711c53bde076; range_end:; response_count:1; response_revision:4686; }","duration":"104.760164ms","start":"2026-07-30T19:27:54.250437Z","end":"2026-07-30T19:27:54.355197Z","steps":["trace[381059013] 'agreement among raft nodes before linearized reading' (duration: 104.637841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.918545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":820675154564605729,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:28:05.991821Z","caller":"traceutil/trace.go:171","msg":"trace[1109003644] linearizableReadLoop","detail":"{readStateIndex:5021; appliedIndex:5020; }","duration":"573.830011ms","start":"2026-07-30T19:28:05.417969Z","end":"2026-07-30T19:28:05.991799Z","steps":["trace[1109003644] 'read index received' (duration: 573.609586ms)","trace[1109003644] 'applied index is now lower than readState.Index' (duration: 219.475µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:28:05.991857Z","caller":"traceutil/trace.go:171","msg":"trace[1568935128] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"594.232728ms","start":"2026-07-30T19:28:05.397598Z","end":"2026-07-30T19:28:05.991831Z","steps":["trace[1568935128] 'process raft request' (duration: 594.072604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.992243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.931722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:28:05.992284Z","caller":"traceutil/trace.go:171","msg":"trace[1004830673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4835; }","duration":"530.997804ms","start":"2026-07-30T19:28:05.461275Z","end":"2026-07-30T19:28:05.992273Z","steps":["trace[1004830673] 'agreement among raft nodes before linearized reading' (duration: 530.899122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.992321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:05.461257Z","time spent":"531.056986ms","remote":"127.0.0.1:48982","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-07-30T19:28:05.992231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.714714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T19:28:05.992383Z","caller":"traceutil/trace.go:171","msg":"trace[819890627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4835; }","duration":"212.888939ms","start":"2026-07-30T19:28:05.779476Z","end":"2026-07-30T19:28:05.992365Z","steps":["trace[819890627] 'agreement among raft nodes before linearized reading' (duration: 212.662583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.992395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.318487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-07-30T19:28:05.992521Z","caller":"traceutil/trace.go:171","msg":"trace[1576190493] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4835; }","duration":"304.537823ms","start":"2026-07-30T19:28:05.687959Z","end":"2026-07-30T19:28:05.992497Z","steps":["trace[1576190493] 'agreement among raft nodes before linearized reading' (duration: 304.132683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.992653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:05.687943Z","time spent":"304.661246ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-07-30T19:28:05.992064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.924682ms","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-07-30T19:28:05.992827Z","caller":"traceutil/trace.go:171","msg":"trace[1028522365] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4835; }","duration":"572.718203ms","start":"2026-07-30T19:28:05.420095Z","end":"2026-07-30T19:28:05.992813Z","steps":["trace[1028522365] 'agreement among raft nodes before linearized reading' (duration: 571.836541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.992993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:05.420085Z","time spent":"572.813805ms","remote":"127.0.0.1:49174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-30T19:28:05.993097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:05.397586Z","time spent":"594.671019ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:28:05.992111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.217785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:28:05.993256Z","caller":"traceutil/trace.go:171","msg":"trace[351973770] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4835; }","duration":"320.403895ms","start":"2026-07-30T19:28:05.67284Z","end":"2026-07-30T19:28:05.993244Z","steps":["trace[351973770] 'agreement among raft nodes before linearized reading' (duration: 319.247786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.992112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.129279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-07-30T19:28:05.993347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:05.672819Z","time spent":"320.513817ms","remote":"127.0.0.1:49334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:28:05.993662Z","caller":"traceutil/trace.go:171","msg":"trace[1859457197] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4835; }","duration":"575.755001ms","start":"2026-07-30T19:28:05.41789Z","end":"2026-07-30T19:28:05.993645Z","steps":["trace[1859457197] 'agreement among raft nodes before linearized reading' (duration: 574.030526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.99374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:05.417864Z","time spent":"575.859354ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-07-30T19:28:05.99215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.734959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:28:05.994143Z","caller":"traceutil/trace.go:171","msg":"trace[1634307091] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:4835; }","duration":"351.762431ms","start":"2026-07-30T19:28:05.642369Z","end":"2026-07-30T19:28:05.994131Z","steps":["trace[1634307091] 'agreement among raft nodes before linearized reading' (duration: 349.75932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:05.994267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:05.642349Z","time spent":"351.865873ms","remote":"127.0.0.1:45560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:28:05.9922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.009113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:28:05.994526Z","caller":"traceutil/trace.go:171","msg":"trace[1537167515] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:4835; }","duration":"221.364853ms","start":"2026-07-30T19:28:05.77315Z","end":"2026-07-30T19:28:05.994515Z","steps":["trace[1537167515] 'agreement among raft nodes before linearized reading' (duration: 219.020934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.443586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.859723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:28:06.44366Z","caller":"traceutil/trace.go:171","msg":"trace[852920787] linearizableReadLoop","detail":"{readStateIndex:5022; appliedIndex:5021; }","duration":"442.985687ms","start":"2026-07-30T19:28:06.000664Z","end":"2026-07-30T19:28:06.44365Z","steps":["trace[852920787] 'read index received' (duration: 229.656µs)","trace[852920787] 'applied index is now lower than readState.Index' (duration: 442.755331ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:28:06.443705Z","caller":"traceutil/trace.go:171","msg":"trace[1959028628] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"443.055039ms","start":"2026-07-30T19:28:06.000645Z","end":"2026-07-30T19:28:06.4437Z","steps":["trace[1959028628] 'process raft request' (duration: 259.026122ms)","trace[1959028628] 'compare' (duration: 183.781521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:28:06.443736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.000626Z","time spent":"443.09593ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:28:06.443852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.189222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-07-30T19:28:06.44387Z","caller":"traceutil/trace.go:171","msg":"trace[612397059] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4836; }","duration":"443.237194ms","start":"2026-07-30T19:28:06.000628Z","end":"2026-07-30T19:28:06.443865Z","steps":["trace[612397059] 'agreement among raft nodes before linearized reading' (duration: 443.166542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.443884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.000612Z","time spent":"443.268714ms","remote":"127.0.0.1:49106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1185,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"info","ts":"2026-07-30T19:28:06.49253Z","caller":"traceutil/trace.go:171","msg":"trace[480256544] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"490.922794ms","start":"2026-07-30T19:28:06.001577Z","end":"2026-07-30T19:28:06.4925Z","steps":["trace[480256544] 'process raft request' (duration: 490.835222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.492731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.001568Z","time spent":"491.070488ms","remote":"127.0.0.1:49314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:28:06.493122Z","caller":"traceutil/trace.go:171","msg":"trace[629196079] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"492.303579ms","start":"2026-07-30T19:28:06.000804Z","end":"2026-07-30T19:28:06.493107Z","steps":["trace[629196079] 'process raft request' (duration: 491.323895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.4932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.000793Z","time spent":"492.370121ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:28:06.493472Z","caller":"traceutil/trace.go:171","msg":"trace[1798832480] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"492.027732ms","start":"2026-07-30T19:28:06.001401Z","end":"2026-07-30T19:28:06.493429Z","steps":["trace[1798832480] 'process raft request' (duration: 490.954836ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:28:06.493658Z","caller":"traceutil/trace.go:171","msg":"trace[1610232220] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"492.773531ms","start":"2026-07-30T19:28:06.000871Z","end":"2026-07-30T19:28:06.493645Z","steps":["trace[1610232220] 'process raft request' (duration: 491.419917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.493689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.00137Z","time spent":"492.207536ms","remote":"127.0.0.1:49314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:28:06.493748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.000866Z","time spent":"492.837652ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:28:06.52455Z","caller":"traceutil/trace.go:171","msg":"trace[1750499119] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"136.853742ms","start":"2026-07-30T19:28:06.387672Z","end":"2026-07-30T19:28:06.524526Z","steps":["trace[1750499119] 'process raft request' (duration: 136.633016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.524729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.270985ms","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-07-30T19:28:06.524802Z","caller":"traceutil/trace.go:171","msg":"trace[1006175646] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4841; }","duration":"119.415848ms","start":"2026-07-30T19:28:06.405368Z","end":"2026-07-30T19:28:06.524784Z","steps":["trace[1006175646] 'agreement among raft nodes before linearized reading' (duration: 119.06397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.524898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.741295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:28:06.524946Z","caller":"traceutil/trace.go:171","msg":"trace[624598309] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4841; }","duration":"209.235367ms","start":"2026-07-30T19:28:06.315698Z","end":"2026-07-30T19:28:06.524933Z","steps":["trace[624598309] 'agreement among raft nodes before linearized reading' (duration: 208.735505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.524974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.193258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-30T19:28:06.525021Z","caller":"traceutil/trace.go:171","msg":"trace[52561020] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4841; }","duration":"498.26825ms","start":"2026-07-30T19:28:06.026741Z","end":"2026-07-30T19:28:06.525009Z","steps":["trace[52561020] 'agreement among raft nodes before linearized reading' (duration: 497.926051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:06.525055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.026686Z","time spent":"498.359362ms","remote":"127.0.0.1:49590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":519,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:28:06.779396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.670342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:28:06.779503Z","caller":"traceutil/trace.go:171","msg":"trace[1138016126] linearizableReadLoop","detail":"{readStateIndex:5028; appliedIndex:5027; }","duration":"249.727715ms","start":"2026-07-30T19:28:06.529758Z","end":"2026-07-30T19:28:06.779486Z","steps":["trace[1138016126] 'read index received' (duration: 59.94812ms)","trace[1138016126] 'applied index is now lower than readState.Index' (duration: 189.777885ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:28:06.7796Z","caller":"traceutil/trace.go:171","msg":"trace[1019921179] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"249.858528ms","start":"2026-07-30T19:28:06.529726Z","end":"2026-07-30T19:28:06.779584Z","steps":["trace[1019921179] 'process raft request' (duration: 59.91467ms)","trace[1019921179] 'compare' (duration: 189.516247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:28:06.779885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.105994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-07-30T19:28:06.779962Z","caller":"traceutil/trace.go:171","msg":"trace[1091251473] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4842; }","duration":"250.207107ms","start":"2026-07-30T19:28:06.529737Z","end":"2026-07-30T19:28:06.779944Z","steps":["trace[1091251473] 'agreement among raft nodes before linearized reading' (duration: 249.971641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:07.144508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.833882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:28:07.144602Z","caller":"traceutil/trace.go:171","msg":"trace[356644243] linearizableReadLoop","detail":"{readStateIndex:5030; appliedIndex:5029; }","duration":"315.537845ms","start":"2026-07-30T19:28:06.829049Z","end":"2026-07-30T19:28:07.144587Z","steps":["trace[356644243] 'read index received' (duration: 57.470198ms)","trace[356644243] 'applied index is now lower than readState.Index' (duration: 258.065977ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:28:07.144838Z","caller":"traceutil/trace.go:171","msg":"trace[817326528] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"359.920962ms","start":"2026-07-30T19:28:06.784904Z","end":"2026-07-30T19:28:07.144825Z","steps":["trace[817326528] 'process raft request' (duration: 101.675139ms)","trace[817326528] 'compare' (duration: 257.692199ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:28:07.144984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.784887Z","time spent":"359.976653ms","remote":"127.0.0.1:49086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:28:07.145141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.059609ms","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-07-30T19:28:07.145223Z","caller":"traceutil/trace.go:171","msg":"trace[1578824455] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4844; }","duration":"316.176032ms","start":"2026-07-30T19:28:06.829028Z","end":"2026-07-30T19:28:07.145204Z","steps":["trace[1578824455] 'agreement among raft nodes before linearized reading' (duration: 315.936426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:07.145341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:28:06.829019Z","time spent":"316.312815ms","remote":"127.0.0.1:49174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-30T19:28:07.182267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.86537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5c54f46ff5-t4xqh\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-07-30T19:28:07.182354Z","caller":"traceutil/trace.go:171","msg":"trace[1815736658] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5c54f46ff5-t4xqh; range_end:; response_count:1; response_revision:4845; }","duration":"260.999393ms","start":"2026-07-30T19:28:06.921336Z","end":"2026-07-30T19:28:07.182335Z","steps":["trace[1815736658] 'agreement among raft nodes before linearized reading' (duration: 260.698686ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:28:07.48011Z","caller":"traceutil/trace.go:171","msg":"trace[1745012742] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"222.735602ms","start":"2026-07-30T19:28:07.257345Z","end":"2026-07-30T19:28:07.480081Z","steps":["trace[1745012742] 'process raft request' (duration: 140.560737ms)","trace[1745012742] 'compare' (duration: 81.393845ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:28:07.491497Z","caller":"traceutil/trace.go:171","msg":"trace[1187872285] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"232.857829ms","start":"2026-07-30T19:28:07.258625Z","end":"2026-07-30T19:28:07.491483Z","steps":["trace[1187872285] 'process raft request' (duration: 232.743176ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:28:07.491808Z","caller":"traceutil/trace.go:171","msg":"trace[1770182503] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"230.92951ms","start":"2026-07-30T19:28:07.260868Z","end":"2026-07-30T19:28:07.491798Z","steps":["trace[1770182503] 'process raft request' (duration: 230.584661ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:28:49.522787Z","caller":"traceutil/trace.go:171","msg":"trace[46869459] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"216.718736ms","start":"2026-07-30T19:28:49.30605Z","end":"2026-07-30T19:28:49.522769Z","steps":["trace[46869459] 'process raft request' (duration: 216.587342ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:28:49.552705Z","caller":"traceutil/trace.go:171","msg":"trace[1792248224] linearizableReadLoop","detail":"{readStateIndex:5414; appliedIndex:5412; }","duration":"166.997108ms","start":"2026-07-30T19:28:49.385686Z","end":"2026-07-30T19:28:49.552683Z","steps":["trace[1792248224] 'read index received' (duration: 137.011506ms)","trace[1792248224] 'applied index is now lower than readState.Index' (duration: 29.984442ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:28:49.552812Z","caller":"traceutil/trace.go:171","msg":"trace[2094687822] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"211.333118ms","start":"2026-07-30T19:28:49.341448Z","end":"2026-07-30T19:28:49.552781Z","steps":["trace[2094687822] 'process raft request' (duration: 211.084712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:49.552952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.250454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T19:28:49.553175Z","caller":"traceutil/trace.go:171","msg":"trace[338371032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5218; }","duration":"167.517811ms","start":"2026-07-30T19:28:49.385645Z","end":"2026-07-30T19:28:49.553163Z","steps":["trace[338371032] 'agreement among raft nodes before linearized reading' (duration: 167.158192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:49.555592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.183925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-30T19:28:49.555719Z","caller":"traceutil/trace.go:171","msg":"trace[1538882934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5218; }","duration":"103.320949ms","start":"2026-07-30T19:28:49.452346Z","end":"2026-07-30T19:28:49.555667Z","steps":["trace[1538882934] 'agreement among raft nodes before linearized reading' (duration: 102.96903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:28:49.561009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.126489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-07-30T19:28:49.561204Z","caller":"traceutil/trace.go:171","msg":"trace[2063812270] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5218; }","duration":"165.316984ms","start":"2026-07-30T19:28:49.395855Z","end":"2026-07-30T19:28:49.561172Z","steps":["trace[2063812270] 'agreement among raft nodes before linearized reading' (duration: 157.313628ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:28:49.853704Z","caller":"traceutil/trace.go:171","msg":"trace[29099108] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"155.316386ms","start":"2026-07-30T19:28:49.698359Z","end":"2026-07-30T19:28:49.853675Z","steps":["trace[29099108] 'process raft request' (duration: 155.025209ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:28:57.576685Z","caller":"traceutil/trace.go:171","msg":"trace[1142575588] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"147.907383ms","start":"2026-07-30T19:28:57.428757Z","end":"2026-07-30T19:28:57.576664Z","steps":["trace[1142575588] 'process raft request' (duration: 147.77743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:29:01.815171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.369327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-30T19:29:01.815261Z","caller":"traceutil/trace.go:171","msg":"trace[1286932738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5310; }","duration":"120.510411ms","start":"2026-07-30T19:29:01.69473Z","end":"2026-07-30T19:29:01.81524Z","steps":["trace[1286932738] 'agreement among raft nodes before linearized reading' (duration: 120.249514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:29:01.815281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.176011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-07-30T19:29:01.815674Z","caller":"traceutil/trace.go:171","msg":"trace[2003399990] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5310; }","duration":"106.51891ms","start":"2026-07-30T19:29:01.709037Z","end":"2026-07-30T19:29:01.815556Z","steps":["trace[2003399990] 'agreement among raft nodes before linearized reading' (duration: 106.054448ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:29:39.331352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2897} {"level":"info","ts":"2026-07-30T19:29:39.417197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2897,"took":"84.114569ms","hash":3025727785,"current-db-size-bytes":54792192,"current-db-size":"55 MB","current-db-size-in-use-bytes":29847552,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-30T19:29:39.41734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3025727785,"revision":2897,"compact-revision":-1} {"level":"warn","ts":"2026-07-30T19:31:08.885259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.631623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:31:08.885682Z","caller":"traceutil/trace.go:171","msg":"trace[1501192491] transaction","detail":"{read_only:false; response_revision:6101; number_of_response:1; }","duration":"449.232689ms","start":"2026-07-30T19:31:08.436409Z","end":"2026-07-30T19:31:08.885641Z","steps":["trace[1501192491] 'process raft request' (duration: 448.946872ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:31:08.885714Z","caller":"traceutil/trace.go:171","msg":"trace[1077137362] transaction","detail":"{read_only:false; response_revision:6100; number_of_response:1; }","duration":"450.092132ms","start":"2026-07-30T19:31:08.435579Z","end":"2026-07-30T19:31:08.885671Z","steps":["trace[1077137362] 'process raft request' (duration: 346.971566ms)","trace[1077137362] 'compare' (duration: 102.272364ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:31:08.885802Z","caller":"traceutil/trace.go:171","msg":"trace[1491123312] linearizableReadLoop","detail":"{readStateIndex:6328; appliedIndex:6325; }","duration":"423.563443ms","start":"2026-07-30T19:31:08.462221Z","end":"2026-07-30T19:31:08.885785Z","steps":["trace[1491123312] 'read index received' (duration: 12.367005ms)","trace[1491123312] 'applied index is now lower than readState.Index' (duration: 411.194598ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:31:08.885867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:31:08.436397Z","time spent":"449.371453ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:31:08.885879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.660245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:31:08.885923Z","caller":"traceutil/trace.go:171","msg":"trace[4653663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6101; }","duration":"423.750879ms","start":"2026-07-30T19:31:08.462166Z","end":"2026-07-30T19:31:08.885917Z","steps":["trace[4653663] 'agreement among raft nodes before linearized reading' (duration: 423.688037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:31:08.885949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:31:08.462149Z","time spent":"423.793379ms","remote":"127.0.0.1:48982","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-07-30T19:31:08.885956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:31:08.435523Z","time spent":"450.312518ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:31:08.975856Z","caller":"traceutil/trace.go:171","msg":"trace[1720609854] transaction","detail":"{read_only:false; response_revision:6102; number_of_response:1; }","duration":"344.090121ms","start":"2026-07-30T19:31:08.631742Z","end":"2026-07-30T19:31:08.975832Z","steps":["trace[1720609854] 'process raft request' (duration: 343.580168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:31:08.975992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:31:08.631719Z","time spent":"344.204173ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6923,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:31:38.731006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.039513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-30T19:31:38.73109Z","caller":"traceutil/trace.go:171","msg":"trace[1595923933] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6412; }","duration":"116.170477ms","start":"2026-07-30T19:31:38.614898Z","end":"2026-07-30T19:31:38.731068Z","steps":["trace[1595923933] 'range keys from in-memory index tree' (duration: 115.861659ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:31:38.887397Z","caller":"traceutil/trace.go:171","msg":"trace[1454519499] linearizableReadLoop","detail":"{readStateIndex:6651; appliedIndex:6649; }","duration":"125.959325ms","start":"2026-07-30T19:31:38.761409Z","end":"2026-07-30T19:31:38.887369Z","steps":["trace[1454519499] 'read index received' (duration: 8.892114ms)","trace[1454519499] 'applied index is now lower than readState.Index' (duration: 117.065751ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:31:38.88745Z","caller":"traceutil/trace.go:171","msg":"trace[1051951188] transaction","detail":"{read_only:false; response_revision:6414; number_of_response:1; }","duration":"130.861465ms","start":"2026-07-30T19:31:38.756544Z","end":"2026-07-30T19:31:38.887406Z","steps":["trace[1051951188] 'process raft request' (duration: 97.413982ms)","trace[1051951188] 'compare' (duration: 33.220276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:31:38.88763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.202871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"info","ts":"2026-07-30T19:31:38.888237Z","caller":"traceutil/trace.go:171","msg":"trace[1465950188] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6414; }","duration":"126.837368ms","start":"2026-07-30T19:31:38.761375Z","end":"2026-07-30T19:31:38.888212Z","steps":["trace[1465950188] 'agreement among raft nodes before linearized reading' (duration: 126.112679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:31:45.344128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.883325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-30T19:31:45.344207Z","caller":"traceutil/trace.go:171","msg":"trace[871697390] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6450; }","duration":"262.020859ms","start":"2026-07-30T19:31:45.082168Z","end":"2026-07-30T19:31:45.344189Z","steps":["trace[871697390] 'range keys from in-memory index tree' (duration: 261.622448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:31:45.344385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.682409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T19:31:45.344459Z","caller":"traceutil/trace.go:171","msg":"trace[538707720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6450; }","duration":"264.761491ms","start":"2026-07-30T19:31:45.079677Z","end":"2026-07-30T19:31:45.344439Z","steps":["trace[538707720] 'range keys from in-memory index tree' (duration: 264.441513ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:32:09.13Z","caller":"traceutil/trace.go:171","msg":"trace[163880896] linearizableReadLoop","detail":"{readStateIndex:6871; appliedIndex:6870; }","duration":"162.55548ms","start":"2026-07-30T19:32:08.967418Z","end":"2026-07-30T19:32:09.129974Z","steps":["trace[163880896] 'read index received' (duration: 162.408437ms)","trace[163880896] 'applied index is now lower than readState.Index' (duration: 146.273µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:32:09.130314Z","caller":"traceutil/trace.go:171","msg":"trace[800676075] transaction","detail":"{read_only:false; response_revision:6624; number_of_response:1; }","duration":"170.155601ms","start":"2026-07-30T19:32:08.960138Z","end":"2026-07-30T19:32:09.130294Z","steps":["trace[800676075] 'process raft request' (duration: 169.706561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:32:09.130386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.956779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com\" ","response":"range_response_count:1 size:111517"} {"level":"info","ts":"2026-07-30T19:32:09.130658Z","caller":"traceutil/trace.go:171","msg":"trace[1871876441] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com; range_end:; response_count:1; response_revision:6624; }","duration":"163.242945ms","start":"2026-07-30T19:32:08.967402Z","end":"2026-07-30T19:32:09.130645Z","steps":["trace[1871876441] 'agreement among raft nodes before linearized reading' (duration: 162.660342ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:34:39.336704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5608} {"level":"info","ts":"2026-07-30T19:34:39.413524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5608,"took":"75.737946ms","hash":2658709786,"current-db-size-bytes":54792192,"current-db-size":"55 MB","current-db-size-in-use-bytes":18497536,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-30T19:34:39.413578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2658709786,"revision":5608,"compact-revision":2897}