{"level":"warn","ts":"2026-08-01T18:38:43.228171Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-01T18:38:43.228321Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-08-01T18:38:43.228468Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-01T18:38:43.228496Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-01T18:38:43.228534Z","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-08-01T18:38:43.229865Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-08-01T18:38:43.230005Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-08-01T18:38:43.235333Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.944297ms"} {"level":"info","ts":"2026-08-01T18:38:43.244966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-08-01T18:38:43.245055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-01T18:38:43.245086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-08-01T18:38:43.2451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-01T18:38:43.24513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-08-01T18:38:43.245179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-08-01T18:38:43.250538Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-01T18:38:43.251721Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-01T18:38:43.252854Z","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-08-01T18:38:43.254765Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-01T18:38:43.258827Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-01T18:38:43.258823Z","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-08-01T18:38:43.258938Z","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-08-01T18:38:43.258952Z","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-08-01T18:38:43.259829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-08-01T18:38:43.259975Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-01T18:38:43.260613Z","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-08-01T18:38:43.260794Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-01T18:38:43.260821Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-01T18:38:43.2609Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-01T18:38:43.260911Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-01T18:38:43.746142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-01T18:38:43.746195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-01T18:38:43.746247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-08-01T18:38:43.746267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-08-01T18:38:43.746284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-01T18:38:43.7463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-08-01T18:38:43.746319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-01T18:38:43.795155Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-01T18:38:43.797Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-01T18:38:43.797047Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-01T18:38:43.797141Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-01T18:38:43.797292Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-08-01T18:38:43.797501Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-01T18:38:43.797553Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-01T18:38:43.797673Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-01T18:38:43.797728Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-01T18:38:43.800158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-08-01T18:38:43.801247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-01T18:38:51.240546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.998817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:38:51.240667Z","caller":"traceutil/trace.go:171","msg":"trace[806825641] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:321; }","duration":"198.197137ms","start":"2026-08-01T18:38:51.042448Z","end":"2026-08-01T18:38:51.240645Z","steps":["trace[806825641] 'range keys from in-memory index tree' (duration: 197.922424ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:01.225385Z","caller":"traceutil/trace.go:171","msg":"trace[1115594287] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"172.23769ms","start":"2026-08-01T18:39:01.053124Z","end":"2026-08-01T18:39:01.225362Z","steps":["trace[1115594287] 'process raft request' (duration: 172.084743ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:07.460132Z","caller":"traceutil/trace.go:171","msg":"trace[1213952464] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"176.659324ms","start":"2026-08-01T18:39:07.283448Z","end":"2026-08-01T18:39:07.460107Z","steps":["trace[1213952464] 'process raft request' (duration: 176.404944ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:17.149493Z","caller":"traceutil/trace.go:171","msg":"trace[554378690] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"145.664117ms","start":"2026-08-01T18:39:17.003592Z","end":"2026-08-01T18:39:17.149256Z","steps":["trace[554378690] 'process raft request' (duration: 143.105829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:21.94929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.747993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:39:21.949416Z","caller":"traceutil/trace.go:171","msg":"trace[2001203877] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"294.208707ms","start":"2026-08-01T18:39:21.655191Z","end":"2026-08-01T18:39:21.9494Z","steps":["trace[2001203877] 'read index received' (duration: 86.8062ms)","trace[2001203877] 'applied index is now lower than readState.Index' (duration: 207.401277ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:39:21.949484Z","caller":"traceutil/trace.go:171","msg":"trace[219985282] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"302.136437ms","start":"2026-08-01T18:39:21.647337Z","end":"2026-08-01T18:39:21.949474Z","steps":["trace[219985282] 'process raft request' (duration: 94.698548ms)","trace[219985282] 'compare' (duration: 206.582787ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:39:21.949542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:21.647331Z","time spent":"302.186078ms","remote":"127.0.0.1:52638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-01T18:39:21.949673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.443046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:39:21.94982Z","caller":"traceutil/trace.go:171","msg":"trace[299057533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"294.623131ms","start":"2026-08-01T18:39:21.655171Z","end":"2026-08-01T18:39:21.949794Z","steps":["trace[299057533] 'agreement among raft nodes before linearized reading' (duration: 294.31932ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.950257Z","caller":"traceutil/trace.go:171","msg":"trace[1184279952] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"280.974482ms","start":"2026-08-01T18:39:21.669269Z","end":"2026-08-01T18:39:21.950243Z","steps":["trace[1184279952] 'process raft request' (duration: 280.727753ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.950505Z","caller":"traceutil/trace.go:171","msg":"trace[820688573] transaction","detail":"{read_only:false; number_of_response:0; response_revision:540; }","duration":"295.206233ms","start":"2026-08-01T18:39:21.655282Z","end":"2026-08-01T18:39:21.950489Z","steps":["trace[820688573] 'process raft request' (duration: 294.633022ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.950621Z","caller":"traceutil/trace.go:171","msg":"trace[751058371] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"274.030227ms","start":"2026-08-01T18:39:21.676545Z","end":"2026-08-01T18:39:21.950575Z","steps":["trace[751058371] 'process raft request' (duration: 273.683874ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.950671Z","caller":"traceutil/trace.go:171","msg":"trace[1820884108] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"281.162359ms","start":"2026-08-01T18:39:21.669469Z","end":"2026-08-01T18:39:21.950631Z","steps":["trace[1820884108] 'process raft request' (duration: 280.658641ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.950751Z","caller":"traceutil/trace.go:171","msg":"trace[1951324786] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"249.664375ms","start":"2026-08-01T18:39:21.701067Z","end":"2026-08-01T18:39:21.950731Z","steps":["trace[1951324786] 'process raft request' (duration: 249.509479ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.950788Z","caller":"traceutil/trace.go:171","msg":"trace[1046629204] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"279.750188ms","start":"2026-08-01T18:39:21.671028Z","end":"2026-08-01T18:39:21.950778Z","steps":["trace[1046629204] 'process raft request' (duration: 279.166076ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.950838Z","caller":"traceutil/trace.go:171","msg":"trace[511749440] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"281.355286ms","start":"2026-08-01T18:39:21.669475Z","end":"2026-08-01T18:39:21.95083Z","steps":["trace[511749440] 'process raft request' (duration: 280.583257ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.950907Z","caller":"traceutil/trace.go:171","msg":"trace[1428409818] transaction","detail":"{read_only:false; number_of_response:0; response_revision:540; }","duration":"292.083549ms","start":"2026-08-01T18:39:21.658804Z","end":"2026-08-01T18:39:21.950888Z","steps":["trace[1428409818] 'process raft request' (duration: 291.156015ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.951003Z","caller":"traceutil/trace.go:171","msg":"trace[1979202817] transaction","detail":"{read_only:false; number_of_response:1; response_revision:543; }","duration":"281.417149ms","start":"2026-08-01T18:39:21.669524Z","end":"2026-08-01T18:39:21.950942Z","steps":["trace[1979202817] 'process raft request' (duration: 280.64172ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.951087Z","caller":"traceutil/trace.go:171","msg":"trace[1670362088] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"265.962342ms","start":"2026-08-01T18:39:21.685115Z","end":"2026-08-01T18:39:21.951078Z","steps":["trace[1670362088] 'process raft request' (duration: 265.193103ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.951188Z","caller":"traceutil/trace.go:171","msg":"trace[1485690667] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"265.288917ms","start":"2026-08-01T18:39:21.68589Z","end":"2026-08-01T18:39:21.951179Z","steps":["trace[1485690667] 'process raft request' (duration: 264.575381ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.951028Z","caller":"traceutil/trace.go:171","msg":"trace[672796534] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"269.547393ms","start":"2026-08-01T18:39:21.681468Z","end":"2026-08-01T18:39:21.951016Z","steps":["trace[672796534] 'process raft request' (duration: 268.802936ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:21.960949Z","caller":"traceutil/trace.go:171","msg":"trace[192203003] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"161.944522ms","start":"2026-08-01T18:39:21.797337Z","end":"2026-08-01T18:39:21.959281Z","steps":["trace[192203003] 'process raft request' (duration: 161.524386ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:27.878354Z","caller":"traceutil/trace.go:171","msg":"trace[530897706] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"287.156139ms","start":"2026-08-01T18:39:27.591172Z","end":"2026-08-01T18:39:27.878328Z","steps":["trace[530897706] 'process raft request' (duration: 286.937032ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:28.445055Z","caller":"traceutil/trace.go:171","msg":"trace[1245547285] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"850.11978ms","start":"2026-08-01T18:39:27.594906Z","end":"2026-08-01T18:39:28.445026Z","steps":["trace[1245547285] 'process raft request' (duration: 849.619513ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:28.445233Z","caller":"traceutil/trace.go:171","msg":"trace[1707807897] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"841.259283ms","start":"2026-08-01T18:39:27.603936Z","end":"2026-08-01T18:39:28.445195Z","steps":["trace[1707807897] 'process raft request' (duration: 840.769856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:28.445379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:27.594895Z","time spent":"850.234115ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:39:28.445411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:27.603908Z","time spent":"841.410248ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:39:28.445478Z","caller":"traceutil/trace.go:171","msg":"trace[951744070] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"846.337939ms","start":"2026-08-01T18:39:27.599114Z","end":"2026-08-01T18:39:28.445452Z","steps":["trace[951744070] 'read index received' (duration: 279.11094ms)","trace[951744070] 'applied index is now lower than readState.Index' (duration: 567.224449ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:39:28.445681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.554096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-01T18:39:28.44575Z","caller":"traceutil/trace.go:171","msg":"trace[1402585888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"846.614679ms","start":"2026-08-01T18:39:27.5991Z","end":"2026-08-01T18:39:28.445715Z","steps":["trace[1402585888] 'agreement among raft nodes before linearized reading' (duration: 846.452093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:28.445812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:27.599095Z","time spent":"846.703792ms","remote":"127.0.0.1:52878","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-08-01T18:39:28.446517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.627948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:39:28.446818Z","caller":"traceutil/trace.go:171","msg":"trace[596757665] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"697.911475ms","start":"2026-08-01T18:39:27.748883Z","end":"2026-08-01T18:39:28.446795Z","steps":["trace[596757665] 'process raft request' (duration: 696.187156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:28.446944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:27.748858Z","time spent":"698.028849ms","remote":"127.0.0.1:52684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:39:28.446708Z","caller":"traceutil/trace.go:171","msg":"trace[1703335459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"795.844585ms","start":"2026-08-01T18:39:27.650844Z","end":"2026-08-01T18:39:28.446689Z","steps":["trace[1703335459] 'agreement among raft nodes before linearized reading' (duration: 795.580566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:28.447157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:27.65083Z","time spent":"796.312382ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:39:28.8363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.485771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-01T18:39:28.83652Z","caller":"traceutil/trace.go:171","msg":"trace[1186097454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"814.827963ms","start":"2026-08-01T18:39:28.021661Z","end":"2026-08-01T18:39:28.836489Z","steps":["trace[1186097454] 'agreement among raft nodes before linearized reading' (duration: 425.304587ms)","trace[1186097454] 'range keys from in-memory index tree' (duration: 389.097691ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:39:28.836514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.433129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-08-01T18:39:28.836588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:28.021617Z","time spent":"814.952368ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-01T18:39:28.83667Z","caller":"traceutil/trace.go:171","msg":"trace[2053872505] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"185.157653ms","start":"2026-08-01T18:39:28.651497Z","end":"2026-08-01T18:39:28.836655Z","steps":["trace[2053872505] 'read index received' (duration: 148.049365ms)","trace[2053872505] 'applied index is now lower than readState.Index' (duration: 37.107238ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:39:28.836783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.262516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:39:28.836824Z","caller":"traceutil/trace.go:171","msg":"trace[1053761565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"185.343239ms","start":"2026-08-01T18:39:28.651469Z","end":"2026-08-01T18:39:28.836813Z","steps":["trace[1053761565] 'agreement among raft nodes before linearized reading' (duration: 185.241576ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:28.837058Z","caller":"traceutil/trace.go:171","msg":"trace[1968055316] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"334.476201ms","start":"2026-08-01T18:39:28.50257Z","end":"2026-08-01T18:39:28.837046Z","steps":["trace[1968055316] 'process raft request' (duration: 334.032186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:28.837121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:28.502545Z","time spent":"334.547944ms","remote":"127.0.0.1:53114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-01T18:39:28.837209Z","caller":"traceutil/trace.go:171","msg":"trace[1777996688] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"385.034072ms","start":"2026-08-01T18:39:28.452141Z","end":"2026-08-01T18:39:28.837175Z","steps":["trace[1777996688] 'process raft request' (duration: 167.836946ms)","trace[1777996688] 'compare' (duration: 216.323536ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:39:28.837379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:28.452124Z","time spent":"385.172256ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:39:29.351062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534134652154508,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:39:29.960971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.865703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:39:29.961103Z","caller":"traceutil/trace.go:171","msg":"trace[68981628] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"1.111355165s","start":"2026-08-01T18:39:28.849702Z","end":"2026-08-01T18:39:29.961057Z","steps":["trace[68981628] 'read index received' (duration: 687.219424ms)","trace[68981628] 'applied index is now lower than readState.Index' (duration: 424.134241ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:39:29.961189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111484359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:39:29.961219Z","caller":"traceutil/trace.go:171","msg":"trace[1485259972] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:638; }","duration":"1.111537981s","start":"2026-08-01T18:39:28.849669Z","end":"2026-08-01T18:39:29.961207Z","steps":["trace[1485259972] 'agreement among raft nodes before linearized reading' (duration: 1.111482119s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:29.96119Z","caller":"traceutil/trace.go:171","msg":"trace[1942241957] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.117625531s","start":"2026-08-01T18:39:28.843532Z","end":"2026-08-01T18:39:29.961157Z","steps":["trace[1942241957] 'process raft request' (duration: 693.474199ms)","trace[1942241957] 'compare' (duration: 423.287112ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:39:29.961253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:28.849655Z","time spent":"1.111591073s","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-01T18:39:29.961352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:28.843522Z","time spent":"1.117748884s","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:39:29.974676Z","caller":"traceutil/trace.go:171","msg":"trace[1435951784] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"410.499446ms","start":"2026-08-01T18:39:29.564144Z","end":"2026-08-01T18:39:29.974643Z","steps":["trace[1435951784] 'process raft request' (duration: 410.33898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:29.974704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.67067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:39:29.97479Z","caller":"traceutil/trace.go:171","msg":"trace[1557311787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"322.762143ms","start":"2026-08-01T18:39:29.652003Z","end":"2026-08-01T18:39:29.974766Z","steps":["trace[1557311787] 'agreement among raft nodes before linearized reading' (duration: 322.622198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:29.974827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:29.56412Z","time spent":"410.62635ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:39:29.974831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:29.65195Z","time spent":"322.873267ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-01T18:39:31.905464Z","caller":"traceutil/trace.go:171","msg":"trace[967836292] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"135.524435ms","start":"2026-08-01T18:39:31.769893Z","end":"2026-08-01T18:39:31.905418Z","steps":["trace[967836292] 'process raft request' (duration: 135.302818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:34.885908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.592386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-08-01T18:39:34.886006Z","caller":"traceutil/trace.go:171","msg":"trace[1202889754] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"117.780282ms","start":"2026-08-01T18:39:34.768204Z","end":"2026-08-01T18:39:34.885985Z","steps":["trace[1202889754] 'range keys from in-memory index tree' (duration: 117.39759ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:39:41.923793Z","caller":"traceutil/trace.go:171","msg":"trace[405508679] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"225.508769ms","start":"2026-08-01T18:39:41.698257Z","end":"2026-08-01T18:39:41.923766Z","steps":["trace[405508679] 'process raft request' (duration: 225.310303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:44.868882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.101054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:39:44.868966Z","caller":"traceutil/trace.go:171","msg":"trace[996251407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"217.228857ms","start":"2026-08-01T18:39:44.651716Z","end":"2026-08-01T18:39:44.868945Z","steps":["trace[996251407] 'range keys from in-memory index tree' (duration: 217.016791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:44.869994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.248083ms","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-08-01T18:39:44.870082Z","caller":"traceutil/trace.go:171","msg":"trace[290300072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"348.375446ms","start":"2026-08-01T18:39:44.521688Z","end":"2026-08-01T18:39:44.870063Z","steps":["trace[290300072] 'range keys from in-memory index tree' (duration: 348.054117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:44.870124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:39:44.521668Z","time spent":"348.446859ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-01T18:39:44.887532Z","caller":"traceutil/trace.go:171","msg":"trace[1913480773] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"173.166158ms","start":"2026-08-01T18:39:44.714345Z","end":"2026-08-01T18:39:44.887512Z","steps":["trace[1913480773] 'process raft request' (duration: 172.927971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:39:59.065097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.725081ms","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-08-01T18:39:59.065161Z","caller":"traceutil/trace.go:171","msg":"trace[441635569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"120.839384ms","start":"2026-08-01T18:39:58.944308Z","end":"2026-08-01T18:39:59.065147Z","steps":["trace[441635569] 'range keys from in-memory index tree' (duration: 120.568046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:14.152613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534134652155561,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-01T18:40:14.193307Z","caller":"traceutil/trace.go:171","msg":"trace[1531061788] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"554.226893ms","start":"2026-08-01T18:40:13.639055Z","end":"2026-08-01T18:40:14.193281Z","steps":["trace[1531061788] 'process raft request' (duration: 554.053549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:14.193748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:40:13.639034Z","time spent":"554.355737ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:40:14.193826Z","caller":"traceutil/trace.go:171","msg":"trace[954175277] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1237; }","duration":"541.660372ms","start":"2026-08-01T18:40:13.652141Z","end":"2026-08-01T18:40:14.193801Z","steps":["trace[954175277] 'read index received' (duration: 540.930454ms)","trace[954175277] 'applied index is now lower than readState.Index' (duration: 727.998µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:40:14.19395Z","caller":"traceutil/trace.go:171","msg":"trace[1343176296] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"359.821147ms","start":"2026-08-01T18:40:13.83411Z","end":"2026-08-01T18:40:14.193931Z","steps":["trace[1343176296] 'process raft request' (duration: 359.581371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:14.19405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:40:13.834076Z","time spent":"359.916678ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:40:14.194142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.980499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:40:14.194265Z","caller":"traceutil/trace.go:171","msg":"trace[1371191904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"542.140983ms","start":"2026-08-01T18:40:13.652098Z","end":"2026-08-01T18:40:14.194239Z","steps":["trace[1371191904] 'agreement among raft nodes before linearized reading' (duration: 541.965829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:14.19433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:40:13.652082Z","time spent":"542.234576ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:40:26.077885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.596176ms","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-08-01T18:40:26.078014Z","caller":"traceutil/trace.go:171","msg":"trace[1461419494] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1318; }","duration":"155.736309ms","start":"2026-08-01T18:40:25.922261Z","end":"2026-08-01T18:40:26.077997Z","steps":["trace[1461419494] 'range keys from in-memory index tree' (duration: 155.583546ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:26.443489Z","caller":"traceutil/trace.go:171","msg":"trace[1457550460] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"181.331859ms","start":"2026-08-01T18:40:26.262131Z","end":"2026-08-01T18:40:26.443463Z","steps":["trace[1457550460] 'process raft request' (duration: 181.206306ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:26.446482Z","caller":"traceutil/trace.go:171","msg":"trace[70965110] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1364; }","duration":"175.903254ms","start":"2026-08-01T18:40:26.270564Z","end":"2026-08-01T18:40:26.446467Z","steps":["trace[70965110] 'read index received' (duration: 172.757772ms)","trace[70965110] 'applied index is now lower than readState.Index' (duration: 3.144672ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:40:26.446552Z","caller":"traceutil/trace.go:171","msg":"trace[1151410088] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"182.865644ms","start":"2026-08-01T18:40:26.263681Z","end":"2026-08-01T18:40:26.446546Z","steps":["trace[1151410088] 'process raft request' (duration: 182.70343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:26.446784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.439843ms","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-08-01T18:40:26.446844Z","caller":"traceutil/trace.go:171","msg":"trace[1777884498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"175.526875ms","start":"2026-08-01T18:40:26.271299Z","end":"2026-08-01T18:40:26.446826Z","steps":["trace[1777884498] 'agreement among raft nodes before linearized reading' (duration: 175.352861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:26.446861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.280323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-01T18:40:26.446886Z","caller":"traceutil/trace.go:171","msg":"trace[1978519169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"176.348724ms","start":"2026-08-01T18:40:26.270529Z","end":"2026-08-01T18:40:26.446878Z","steps":["trace[1978519169] 'agreement among raft nodes before linearized reading' (duration: 176.243691ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:36.526173Z","caller":"traceutil/trace.go:171","msg":"trace[1057655217] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"167.447943ms","start":"2026-08-01T18:40:36.358685Z","end":"2026-08-01T18:40:36.526133Z","steps":["trace[1057655217] 'process raft request' (duration: 137.490275ms)","trace[1057655217] 'compare' (duration: 29.733523ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:40:45.18681Z","caller":"traceutil/trace.go:171","msg":"trace[1554318413] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"147.906357ms","start":"2026-08-01T18:40:45.038881Z","end":"2026-08-01T18:40:45.186787Z","steps":["trace[1554318413] 'process raft request' (duration: 139.453239ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.196776Z","caller":"traceutil/trace.go:171","msg":"trace[326260123] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"147.946258ms","start":"2026-08-01T18:40:45.048803Z","end":"2026-08-01T18:40:45.196749Z","steps":["trace[326260123] 'process raft request' (duration: 147.747784ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.196991Z","caller":"traceutil/trace.go:171","msg":"trace[892930174] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"148.015319ms","start":"2026-08-01T18:40:45.048968Z","end":"2026-08-01T18:40:45.196984Z","steps":["trace[892930174] 'process raft request' (duration: 147.665542ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.197228Z","caller":"traceutil/trace.go:171","msg":"trace[1495389672] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"148.173823ms","start":"2026-08-01T18:40:45.049045Z","end":"2026-08-01T18:40:45.197219Z","steps":["trace[1495389672] 'process raft request' (duration: 147.919378ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.197255Z","caller":"traceutil/trace.go:171","msg":"trace[1247510853] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"146.760633ms","start":"2026-08-01T18:40:45.050479Z","end":"2026-08-01T18:40:45.19724Z","steps":["trace[1247510853] 'process raft request' (duration: 146.717792ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.197378Z","caller":"traceutil/trace.go:171","msg":"trace[1389863654] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"148.004509ms","start":"2026-08-01T18:40:45.049363Z","end":"2026-08-01T18:40:45.197367Z","steps":["trace[1389863654] 'process raft request' (duration: 147.769424ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.338419Z","caller":"traceutil/trace.go:171","msg":"trace[1200395209] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"118.977228ms","start":"2026-08-01T18:40:45.219403Z","end":"2026-08-01T18:40:45.338381Z","steps":["trace[1200395209] 'process raft request' (duration: 115.005924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:45.338429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.331451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:40:45.338888Z","caller":"traceutil/trace.go:171","msg":"trace[155727896] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1608; }","duration":"124.830131ms","start":"2026-08-01T18:40:45.214032Z","end":"2026-08-01T18:40:45.338862Z","steps":["trace[155727896] 'agreement among raft nodes before linearized reading' (duration: 124.31995ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.63751Z","caller":"traceutil/trace.go:171","msg":"trace[1864860389] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"194.115441ms","start":"2026-08-01T18:40:45.443372Z","end":"2026-08-01T18:40:45.637487Z","steps":["trace[1864860389] 'process raft request' (duration: 193.896336ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.637955Z","caller":"traceutil/trace.go:171","msg":"trace[1943275394] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"194.02429ms","start":"2026-08-01T18:40:45.443832Z","end":"2026-08-01T18:40:45.637856Z","steps":["trace[1943275394] 'process raft request' (duration: 193.601381ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.63824Z","caller":"traceutil/trace.go:171","msg":"trace[1527692874] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"192.65028ms","start":"2026-08-01T18:40:45.445571Z","end":"2026-08-01T18:40:45.638221Z","steps":["trace[1527692874] 'process raft request' (duration: 192.113219ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.638384Z","caller":"traceutil/trace.go:171","msg":"trace[954943517] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"189.233148ms","start":"2026-08-01T18:40:45.449126Z","end":"2026-08-01T18:40:45.63836Z","steps":["trace[954943517] 'process raft request' (duration: 188.875681ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.638791Z","caller":"traceutil/trace.go:171","msg":"trace[1997448775] linearizableReadLoop","detail":"{readStateIndex:1697; appliedIndex:1692; }","duration":"162.656339ms","start":"2026-08-01T18:40:45.476108Z","end":"2026-08-01T18:40:45.638764Z","steps":["trace[1997448775] 'read index received' (duration: 56.028791ms)","trace[1997448775] 'applied index is now lower than readState.Index' (duration: 106.626348ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:40:45.638859Z","caller":"traceutil/trace.go:171","msg":"trace[1469510511] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"141.39237ms","start":"2026-08-01T18:40:45.497431Z","end":"2026-08-01T18:40:45.638824Z","steps":["trace[1469510511] 'process raft request' (duration: 140.998122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:45.63907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.950085ms","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-08-01T18:40:45.639118Z","caller":"traceutil/trace.go:171","msg":"trace[621560279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1633; }","duration":"163.044297ms","start":"2026-08-01T18:40:45.476061Z","end":"2026-08-01T18:40:45.639106Z","steps":["trace[621560279] 'agreement among raft nodes before linearized reading' (duration: 162.832783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:45.640877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.335403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-01T18:40:45.640949Z","caller":"traceutil/trace.go:171","msg":"trace[472435717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1634; }","duration":"163.431015ms","start":"2026-08-01T18:40:45.477504Z","end":"2026-08-01T18:40:45.640935Z","steps":["trace[472435717] 'agreement among raft nodes before linearized reading' (duration: 163.20031ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.640761Z","caller":"traceutil/trace.go:171","msg":"trace[1529393073] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"104.479653ms","start":"2026-08-01T18:40:45.536266Z","end":"2026-08-01T18:40:45.640745Z","steps":["trace[1529393073] 'process raft request' (duration: 104.36973ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.795459Z","caller":"traceutil/trace.go:171","msg":"trace[2963043] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"130.646384ms","start":"2026-08-01T18:40:45.664777Z","end":"2026-08-01T18:40:45.795423Z","steps":["trace[2963043] 'process raft request' (duration: 85.698377ms)","trace[2963043] 'compare' (duration: 44.743023ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:40:45.816671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.914697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-01T18:40:45.816769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.305116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1113"} {"level":"info","ts":"2026-08-01T18:40:45.816831Z","caller":"traceutil/trace.go:171","msg":"trace[2118503142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1641; }","duration":"110.119601ms","start":"2026-08-01T18:40:45.706673Z","end":"2026-08-01T18:40:45.816792Z","steps":["trace[2118503142] 'agreement among raft nodes before linearized reading' (duration: 109.844016ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:45.816881Z","caller":"traceutil/trace.go:171","msg":"trace[2046178758] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:1641; }","duration":"111.436899ms","start":"2026-08-01T18:40:45.705422Z","end":"2026-08-01T18:40:45.816859Z","steps":["trace[2046178758] 'agreement among raft nodes before linearized reading' (duration: 111.168533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:45.816943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.420921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:131882"} {"level":"info","ts":"2026-08-01T18:40:45.817057Z","caller":"traceutil/trace.go:171","msg":"trace[658928407] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:1641; }","duration":"104.553333ms","start":"2026-08-01T18:40:45.712479Z","end":"2026-08-01T18:40:45.817032Z","steps":["trace[658928407] 'agreement among raft nodes before linearized reading' (duration: 104.074433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:48.534135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.87247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:40:48.534346Z","caller":"traceutil/trace.go:171","msg":"trace[1897338098] linearizableReadLoop","detail":"{readStateIndex:1754; appliedIndex:1752; }","duration":"153.534796ms","start":"2026-08-01T18:40:48.380796Z","end":"2026-08-01T18:40:48.534331Z","steps":["trace[1897338098] 'read index received' (duration: 51.41348ms)","trace[1897338098] 'applied index is now lower than readState.Index' (duration: 102.120446ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:40:48.534369Z","caller":"traceutil/trace.go:171","msg":"trace[799361175] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"182.723653ms","start":"2026-08-01T18:40:48.351619Z","end":"2026-08-01T18:40:48.534343Z","steps":["trace[799361175] 'process raft request' (duration: 182.620611ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:48.534553Z","caller":"traceutil/trace.go:171","msg":"trace[1112230501] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"128.559956ms","start":"2026-08-01T18:40:48.405981Z","end":"2026-08-01T18:40:48.534541Z","steps":["trace[1112230501] 'process raft request' (duration: 128.299251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:40:48.534791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.984955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-01T18:40:48.534829Z","caller":"traceutil/trace.go:171","msg":"trace[137067472] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1687; }","duration":"154.065797ms","start":"2026-08-01T18:40:48.380755Z","end":"2026-08-01T18:40:48.53482Z","steps":["trace[137067472] 'agreement among raft nodes before linearized reading' (duration: 153.997666ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:40:48.534997Z","caller":"traceutil/trace.go:171","msg":"trace[511201786] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"184.032451ms","start":"2026-08-01T18:40:48.350925Z","end":"2026-08-01T18:40:48.534957Z","steps":["trace[511201786] 'process raft request' (duration: 81.276492ms)","trace[511201786] 'compare' (duration: 101.752898ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:41:01.411918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534134652156900,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-01T18:41:01.803035Z","caller":"traceutil/trace.go:171","msg":"trace[1332251190] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"891.961575ms","start":"2026-08-01T18:41:00.911055Z","end":"2026-08-01T18:41:01.803017Z","steps":["trace[1332251190] 'read index received' (duration: 891.917574ms)","trace[1332251190] 'applied index is now lower than readState.Index' (duration: 42.911µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:41:01.803146Z","caller":"traceutil/trace.go:171","msg":"trace[1435142701] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"918.195047ms","start":"2026-08-01T18:41:00.884918Z","end":"2026-08-01T18:41:01.803113Z","steps":["trace[1435142701] 'process raft request' (duration: 917.982482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:01.803205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.143318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-01T18:41:01.803309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:00.884895Z","time spent":"918.33734ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:41:01.803398Z","caller":"traceutil/trace.go:171","msg":"trace[1293287730] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1863; }","duration":"892.21801ms","start":"2026-08-01T18:41:00.911008Z","end":"2026-08-01T18:41:01.803226Z","steps":["trace[1293287730] 'agreement among raft nodes before linearized reading' (duration: 892.105378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:01.803509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:00.91099Z","time spent":"892.509985ms","remote":"127.0.0.1:55048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-01T18:41:02.262262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.339201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:41:02.262535Z","caller":"traceutil/trace.go:171","msg":"trace[1317173659] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"459.420097ms","start":"2026-08-01T18:41:01.803093Z","end":"2026-08-01T18:41:02.262513Z","steps":["trace[1317173659] 'read index received' (duration: 356.681108ms)","trace[1317173659] 'applied index is now lower than readState.Index' (duration: 102.736609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:41:02.262612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.679871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-01T18:41:02.262655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.245907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:41:02.262662Z","caller":"traceutil/trace.go:171","msg":"trace[824862959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"908.729512ms","start":"2026-08-01T18:41:01.353909Z","end":"2026-08-01T18:41:02.262639Z","steps":["trace[824862959] 'agreement among raft nodes before linearized reading' (duration: 908.6215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.262746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167194545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93a818a0-6d09-4f63-9b48-30740e76105c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-01T18:41:02.262687Z","caller":"traceutil/trace.go:171","msg":"trace[1320744620] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"466.408576ms","start":"2026-08-01T18:41:01.796247Z","end":"2026-08-01T18:41:02.262656Z","steps":["trace[1320744620] 'process raft request' (duration: 363.502514ms)","trace[1320744620] 'compare' (duration: 102.221969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:41:02.262762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.553097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93a818a0-6d09-4f63-9b48-30740e76105c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-01T18:41:02.262741Z","caller":"traceutil/trace.go:171","msg":"trace[2098319655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"611.39445ms","start":"2026-08-01T18:41:01.651336Z","end":"2026-08-01T18:41:02.262731Z","steps":["trace[2098319655] 'agreement among raft nodes before linearized reading' (duration: 611.188946ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:02.262821Z","caller":"traceutil/trace.go:171","msg":"trace[169924679] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93a818a0-6d09-4f63-9b48-30740e76105c; range_end:; response_count:1; response_revision:1865; }","duration":"808.629258ms","start":"2026-08-01T18:41:01.454175Z","end":"2026-08-01T18:41:02.262804Z","steps":["trace[169924679] 'agreement among raft nodes before linearized reading' (duration: 808.486465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.26286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:01.796225Z","time spent":"466.566769ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:41:02.26287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:01.454164Z","time spent":"808.69472ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93a818a0-6d09-4f63-9b48-30740e76105c\" "} {"level":"warn","ts":"2026-08-01T18:41:02.262885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303360007s","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-08-01T18:41:02.262895Z","caller":"traceutil/trace.go:171","msg":"trace[364128532] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"237.853708ms","start":"2026-08-01T18:41:02.025033Z","end":"2026-08-01T18:41:02.262886Z","steps":["trace[364128532] 'process raft request' (duration: 237.304177ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:02.262911Z","caller":"traceutil/trace.go:171","msg":"trace[1341955557] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1865; }","duration":"1.303419678s","start":"2026-08-01T18:41:00.959481Z","end":"2026-08-01T18:41:02.262901Z","steps":["trace[1341955557] 'agreement among raft nodes before linearized reading' (duration: 1.303318716s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.262931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:00.959462Z","time spent":"1.303464819s","remote":"127.0.0.1:52878","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-08-01T18:41:02.262933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:01.651278Z","time spent":"611.564503ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-01T18:41:02.262777Z","caller":"traceutil/trace.go:171","msg":"trace[1279046771] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93a818a0-6d09-4f63-9b48-30740e76105c; range_end:; response_count:1; response_revision:1865; }","duration":"1.167281167s","start":"2026-08-01T18:41:01.095487Z","end":"2026-08-01T18:41:02.262768Z","steps":["trace[1279046771] 'agreement among raft nodes before linearized reading' (duration: 1.167175474s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.263042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:01.095464Z","time spent":"1.167568333s","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93a818a0-6d09-4f63-9b48-30740e76105c\" "} {"level":"warn","ts":"2026-08-01T18:41:02.262746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.298311ms","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-08-01T18:41:02.263107Z","caller":"traceutil/trace.go:171","msg":"trace[381298187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"481.710029ms","start":"2026-08-01T18:41:01.781387Z","end":"2026-08-01T18:41:02.263097Z","steps":["trace[381298187] 'agreement among raft nodes before linearized reading' (duration: 481.26391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.263147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:01.781358Z","time spent":"481.776821ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-01T18:41:02.262772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:01.353847Z","time spent":"908.916936ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:41:02.262819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.717849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-01T18:41:02.263263Z","caller":"traceutil/trace.go:171","msg":"trace[1143500874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"424.191239ms","start":"2026-08-01T18:41:01.839064Z","end":"2026-08-01T18:41:02.263255Z","steps":["trace[1143500874] 'agreement among raft nodes before linearized reading' (duration: 423.71754ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.263292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:01.839044Z","time spent":"424.24095ms","remote":"127.0.0.1:52878","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-08-01T18:41:02.573668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.505386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:41:02.57384Z","caller":"traceutil/trace.go:171","msg":"trace[2034056633] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"306.632216ms","start":"2026-08-01T18:41:02.267192Z","end":"2026-08-01T18:41:02.573824Z","steps":["trace[2034056633] 'process raft request' (duration: 129.902175ms)","trace[2034056633] 'compare' (duration: 176.402074ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:41:02.57394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:02.267129Z","time spent":"306.778719ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:41:02.600834Z","caller":"traceutil/trace.go:171","msg":"trace[1395207074] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"333.245422ms","start":"2026-08-01T18:41:02.267561Z","end":"2026-08-01T18:41:02.600806Z","steps":["trace[1395207074] 'process raft request' (duration: 333.031688ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:02.600861Z","caller":"traceutil/trace.go:171","msg":"trace[1680134684] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"332.360634ms","start":"2026-08-01T18:41:02.268475Z","end":"2026-08-01T18:41:02.600836Z","steps":["trace[1680134684] 'process raft request' (duration: 332.263343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.60101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:02.267552Z","time spent":"333.343094ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:41:02.601009Z","caller":"traceutil/trace.go:171","msg":"trace[2121172218] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"330.596829ms","start":"2026-08-01T18:41:02.270389Z","end":"2026-08-01T18:41:02.600986Z","steps":["trace[2121172218] 'process raft request' (duration: 330.389765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.601023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:02.268467Z","time spent":"332.461056ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:41:02.601098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:02.270382Z","time spent":"330.669231ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:41:02.910584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.550202ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-01T18:41:02.910816Z","caller":"traceutil/trace.go:171","msg":"trace[484718177] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1941; }","duration":"397.142674ms","start":"2026-08-01T18:41:02.513641Z","end":"2026-08-01T18:41:02.910784Z","steps":["trace[484718177] 'read index received' (duration: 86.947698ms)","trace[484718177] 'applied index is now lower than readState.Index' (duration: 310.192076ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:41:02.910961Z","caller":"traceutil/trace.go:171","msg":"trace[346306265] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"217.186339ms","start":"2026-08-01T18:41:02.693746Z","end":"2026-08-01T18:41:02.910932Z","steps":["trace[346306265] 'process raft request' (duration: 216.960254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.911098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.45009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-01T18:41:02.911147Z","caller":"traceutil/trace.go:171","msg":"trace[946656580] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1870; }","duration":"397.555442ms","start":"2026-08-01T18:41:02.513575Z","end":"2026-08-01T18:41:02.911131Z","steps":["trace[946656580] 'agreement among raft nodes before linearized reading' (duration: 397.352038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.911188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:02.513549Z","time spent":"397.631083ms","remote":"127.0.0.1:52878","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-08-01T18:41:02.911397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.925824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:41:02.911466Z","caller":"traceutil/trace.go:171","msg":"trace[132317203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1870; }","duration":"260.024625ms","start":"2026-08-01T18:41:02.651425Z","end":"2026-08-01T18:41:02.911449Z","steps":["trace[132317203] 'agreement among raft nodes before linearized reading' (duration: 259.923053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.911466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.379543ms","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-08-01T18:41:02.911586Z","caller":"traceutil/trace.go:171","msg":"trace[606817131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1870; }","duration":"177.528666ms","start":"2026-08-01T18:41:02.734042Z","end":"2026-08-01T18:41:02.911571Z","steps":["trace[606817131] 'agreement among raft nodes before linearized reading' (duration: 177.311782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.911709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.922565ms","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-08-01T18:41:02.91181Z","caller":"traceutil/trace.go:171","msg":"trace[1260108872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1870; }","duration":"175.041617ms","start":"2026-08-01T18:41:02.736728Z","end":"2026-08-01T18:41:02.911769Z","steps":["trace[1260108872] 'agreement among raft nodes before linearized reading' (duration: 174.831633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.912058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.295341ms","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-08-01T18:41:02.912116Z","caller":"traceutil/trace.go:171","msg":"trace[602446259] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"102.390362ms","start":"2026-08-01T18:41:02.80971Z","end":"2026-08-01T18:41:02.912101Z","steps":["trace[602446259] 'agreement among raft nodes before linearized reading' (duration: 101.78743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.912232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.765843ms","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-08-01T18:41:02.912276Z","caller":"traceutil/trace.go:171","msg":"trace[358148416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1870; }","duration":"206.852145ms","start":"2026-08-01T18:41:02.705413Z","end":"2026-08-01T18:41:02.912265Z","steps":["trace[358148416] 'agreement among raft nodes before linearized reading' (duration: 206.746373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:02.912267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.967968ms","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":"info","ts":"2026-08-01T18:41:02.913293Z","caller":"traceutil/trace.go:171","msg":"trace[1066183360] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1870; }","duration":"205.784174ms","start":"2026-08-01T18:41:02.706566Z","end":"2026-08-01T18:41:02.912351Z","steps":["trace[1066183360] 'agreement among raft nodes before linearized reading' (duration: 204.882646ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:03.175425Z","caller":"traceutil/trace.go:171","msg":"trace[1780694296] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"258.182608ms","start":"2026-08-01T18:41:02.917206Z","end":"2026-08-01T18:41:03.175389Z","steps":["trace[1780694296] 'process raft request' (duration: 258.147417ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:03.175533Z","caller":"traceutil/trace.go:171","msg":"trace[92711152] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"259.597366ms","start":"2026-08-01T18:41:02.915915Z","end":"2026-08-01T18:41:03.175513Z","steps":["trace[92711152] 'process raft request' (duration: 198.866111ms)","trace[92711152] 'compare' (duration: 60.057372ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:41:03.17572Z","caller":"traceutil/trace.go:171","msg":"trace[1750350780] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"258.870352ms","start":"2026-08-01T18:41:02.916817Z","end":"2026-08-01T18:41:03.175687Z","steps":["trace[1750350780] 'process raft request' (duration: 258.500325ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:03.175762Z","caller":"traceutil/trace.go:171","msg":"trace[431637791] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"259.646127ms","start":"2026-08-01T18:41:02.916087Z","end":"2026-08-01T18:41:03.175734Z","steps":["trace[431637791] 'process raft request' (duration: 259.163977ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:10.297986Z","caller":"traceutil/trace.go:171","msg":"trace[1899194559] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"124.588097ms","start":"2026-08-01T18:41:10.173366Z","end":"2026-08-01T18:41:10.297955Z","steps":["trace[1899194559] 'process raft request' (duration: 124.360333ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:10.714673Z","caller":"traceutil/trace.go:171","msg":"trace[159415582] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"101.592544ms","start":"2026-08-01T18:41:10.613052Z","end":"2026-08-01T18:41:10.714644Z","steps":["trace[159415582] 'process raft request' (duration: 101.081625ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:36.030634Z","caller":"traceutil/trace.go:171","msg":"trace[1099461052] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"118.731595ms","start":"2026-08-01T18:41:35.911854Z","end":"2026-08-01T18:41:36.030585Z","steps":["trace[1099461052] 'process raft request' (duration: 118.573533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:36.419776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.399286ms","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-08-01T18:41:36.419858Z","caller":"traceutil/trace.go:171","msg":"trace[66662663] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"284.544999ms","start":"2026-08-01T18:41:36.135292Z","end":"2026-08-01T18:41:36.419837Z","steps":["trace[66662663] 'range keys from in-memory index tree' (duration: 284.210864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:37.34421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534134652157470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-01T18:41:37.385211Z","caller":"traceutil/trace.go:171","msg":"trace[1215157820] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"542.467537ms","start":"2026-08-01T18:41:36.842716Z","end":"2026-08-01T18:41:37.385183Z","steps":["trace[1215157820] 'process raft request' (duration: 542.308044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:37.385623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:36.842693Z","time spent":"542.59549ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:41:37.42584Z","caller":"traceutil/trace.go:171","msg":"trace[1842333424] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2190; }","duration":"581.623789ms","start":"2026-08-01T18:41:36.844191Z","end":"2026-08-01T18:41:37.425815Z","steps":["trace[1842333424] 'read index received' (duration: 540.821458ms)","trace[1842333424] 'applied index is now lower than readState.Index' (duration: 40.801331ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:41:37.425907Z","caller":"traceutil/trace.go:171","msg":"trace[1246040029] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"582.694758ms","start":"2026-08-01T18:41:36.843156Z","end":"2026-08-01T18:41:37.42585Z","steps":["trace[1246040029] 'process raft request' (duration: 582.463944ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:37.425932Z","caller":"traceutil/trace.go:171","msg":"trace[1415070784] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"371.769982ms","start":"2026-08-01T18:41:37.054129Z","end":"2026-08-01T18:41:37.425899Z","steps":["trace[1415070784] 'process raft request' (duration: 371.631039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:37.426101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:37.054103Z","time spent":"371.911795ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:41:37.426179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.968645ms","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":"warn","ts":"2026-08-01T18:41:37.42618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:36.843141Z","time spent":"582.861542ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:41:37.426225Z","caller":"traceutil/trace.go:171","msg":"trace[954124011] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2111; }","duration":"582.029986ms","start":"2026-08-01T18:41:36.844182Z","end":"2026-08-01T18:41:37.426212Z","steps":["trace[954124011] 'agreement among raft nodes before linearized reading' (duration: 581.792072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:37.426457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:36.844157Z","time spent":"582.28489ms","remote":"127.0.0.1:52878","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-08-01T18:41:37.426972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.989351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-01T18:41:37.427038Z","caller":"traceutil/trace.go:171","msg":"trace[1581752949] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:2111; }","duration":"530.105874ms","start":"2026-08-01T18:41:36.896916Z","end":"2026-08-01T18:41:37.427022Z","steps":["trace[1581752949] 'agreement among raft nodes before linearized reading' (duration: 529.90626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:37.427071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:36.896894Z","time spent":"530.166995ms","remote":"127.0.0.1:52622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-08-01T18:41:38.780676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050958752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:41:38.780781Z","caller":"traceutil/trace.go:171","msg":"trace[287673068] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2117; }","duration":"1.051114565s","start":"2026-08-01T18:41:37.729643Z","end":"2026-08-01T18:41:38.780758Z","steps":["trace[287673068] 'count revisions from in-memory index tree' (duration: 1.050877951s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:38.780795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.690325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:41:38.78084Z","caller":"traceutil/trace.go:171","msg":"trace[964802794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2117; }","duration":"979.771776ms","start":"2026-08-01T18:41:37.801056Z","end":"2026-08-01T18:41:38.780828Z","steps":["trace[964802794] 'range keys from in-memory index tree' (duration: 979.619644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:38.780838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:37.729623Z","time spent":"1.051197876s","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:41:38.780871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:37.801037Z","time spent":"979.827178ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:41:38.781037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120159822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-01T18:41:38.781137Z","caller":"traceutil/trace.go:171","msg":"trace[2083738686] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2117; }","duration":"1.120313745s","start":"2026-08-01T18:41:37.660801Z","end":"2026-08-01T18:41:38.781115Z","steps":["trace[2083738686] 'range keys from in-memory index tree' (duration: 1.119770935s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:38.781219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:37.660783Z","time spent":"1.120424247s","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-01T18:41:38.781483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.964993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:41:38.781596Z","caller":"traceutil/trace.go:171","msg":"trace[1893737674] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"247.578527ms","start":"2026-08-01T18:41:38.534Z","end":"2026-08-01T18:41:38.781579Z","steps":["trace[1893737674] 'read index received' (duration: 38.05µs)","trace[1893737674] 'applied index is now lower than readState.Index' (duration: 247.538677ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:41:38.781805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.063415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:41:38.781872Z","caller":"traceutil/trace.go:171","msg":"trace[1886566276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2118; }","duration":"130.168818ms","start":"2026-08-01T18:41:38.651686Z","end":"2026-08-01T18:41:38.781855Z","steps":["trace[1886566276] 'agreement among raft nodes before linearized reading' (duration: 130.070606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:38.782157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.157508ms","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-08-01T18:41:38.782212Z","caller":"traceutil/trace.go:171","msg":"trace[874624558] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2118; }","duration":"248.249419ms","start":"2026-08-01T18:41:38.533946Z","end":"2026-08-01T18:41:38.782195Z","steps":["trace[874624558] 'agreement among raft nodes before linearized reading' (duration: 248.084176ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:38.782968Z","caller":"traceutil/trace.go:171","msg":"trace[1039222784] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"744.797537ms","start":"2026-08-01T18:41:38.036877Z","end":"2026-08-01T18:41:38.781674Z","steps":["trace[1039222784] 'process raft request' (duration: 420.345046ms)","trace[1039222784] 'compare' (duration: 323.009976ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:41:38.783258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:38.036849Z","time spent":"746.281924ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:41:39.468794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.252749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:41:39.468919Z","caller":"traceutil/trace.go:171","msg":"trace[893474187] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"528.111866ms","start":"2026-08-01T18:41:38.940785Z","end":"2026-08-01T18:41:39.468896Z","steps":["trace[893474187] 'read index received' (duration: 40.291µs)","trace[893474187] 'applied index is now lower than readState.Index' (duration: 528.069334ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:41:39.468974Z","caller":"traceutil/trace.go:171","msg":"trace[773244188] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"682.879292ms","start":"2026-08-01T18:41:38.78606Z","end":"2026-08-01T18:41:39.468939Z","steps":["trace[773244188] 'process raft request' (duration: 117.399459ms)","trace[773244188] 'compare' (duration: 565.021085ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:41:39.46904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.249658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-01T18:41:39.46906Z","caller":"traceutil/trace.go:171","msg":"trace[1970947060] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2119; }","duration":"528.322689ms","start":"2026-08-01T18:41:38.940731Z","end":"2026-08-01T18:41:39.469054Z","steps":["trace[1970947060] 'agreement among raft nodes before linearized reading' (duration: 528.243758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.469084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:38.940712Z","time spent":"528.36645ms","remote":"127.0.0.1:52684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:41:39.469172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:38.786041Z","time spent":"683.016725ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:41:39.527117Z","caller":"traceutil/trace.go:171","msg":"trace[199138056] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"281.90835ms","start":"2026-08-01T18:41:39.245191Z","end":"2026-08-01T18:41:39.5271Z","steps":["trace[199138056] 'process raft request' (duration: 281.777258ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:39.777194Z","caller":"traceutil/trace.go:171","msg":"trace[405838313] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"346.638035ms","start":"2026-08-01T18:41:39.430536Z","end":"2026-08-01T18:41:39.777174Z","steps":["trace[405838313] 'process raft request' (duration: 346.417951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.777357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:39.430511Z","time spent":"346.761477ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:41:39.777386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.475222ms","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-08-01T18:41:39.777476Z","caller":"traceutil/trace.go:171","msg":"trace[105647952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2121; }","duration":"346.620645ms","start":"2026-08-01T18:41:39.430827Z","end":"2026-08-01T18:41:39.777448Z","steps":["trace[105647952] 'agreement among raft nodes before linearized reading' (duration: 346.36729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.77753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:39.430791Z","time spent":"346.729646ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-01T18:41:39.777755Z","caller":"traceutil/trace.go:171","msg":"trace[884617509] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2202; }","duration":"308.172448ms","start":"2026-08-01T18:41:39.468953Z","end":"2026-08-01T18:41:39.777125Z","steps":["trace[884617509] 'read index received' (duration: 58.107607ms)","trace[884617509] 'applied index is now lower than readState.Index' (duration: 250.063991ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:41:39.777964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.871143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-01T18:41:39.778674Z","caller":"traceutil/trace.go:171","msg":"trace[496103255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2121; }","duration":"387.672326ms","start":"2026-08-01T18:41:39.390975Z","end":"2026-08-01T18:41:39.778648Z","steps":["trace[496103255] 'agreement among raft nodes before linearized reading' (duration: 386.76017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.778768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:41:39.390953Z","time spent":"387.795169ms","remote":"127.0.0.1:52878","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-08-01T18:41:39.778821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.008735ms","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-08-01T18:41:39.778877Z","caller":"traceutil/trace.go:171","msg":"trace[1549947980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2121; }","duration":"248.083296ms","start":"2026-08-01T18:41:39.53078Z","end":"2026-08-01T18:41:39.778863Z","steps":["trace[1549947980] 'agreement among raft nodes before linearized reading' (duration: 247.916813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.778886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.027977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-08-01T18:41:39.778934Z","caller":"traceutil/trace.go:171","msg":"trace[647850301] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2121; }","duration":"275.074488ms","start":"2026-08-01T18:41:39.503843Z","end":"2026-08-01T18:41:39.778918Z","steps":["trace[647850301] 'agreement among raft nodes before linearized reading' (duration: 274.827654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.778986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.969775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:41:39.779018Z","caller":"traceutil/trace.go:171","msg":"trace[1298490296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2121; }","duration":"127.036947ms","start":"2026-08-01T18:41:39.651972Z","end":"2026-08-01T18:41:39.779009Z","steps":["trace[1298490296] 'agreement among raft nodes before linearized reading' (duration: 126.993186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.77922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.370121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-01T18:41:39.779261Z","caller":"traceutil/trace.go:171","msg":"trace[1610552325] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2121; }","duration":"248.417672ms","start":"2026-08-01T18:41:39.53083Z","end":"2026-08-01T18:41:39.779247Z","steps":["trace[1610552325] 'agreement among raft nodes before linearized reading' (duration: 248.32743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.779517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.787779ms","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":"warn","ts":"2026-08-01T18:41:39.779532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.138405ms","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-08-01T18:41:39.779563Z","caller":"traceutil/trace.go:171","msg":"trace[230101556] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2121; }","duration":"249.197366ms","start":"2026-08-01T18:41:39.530356Z","end":"2026-08-01T18:41:39.779554Z","steps":["trace[230101556] 'agreement among raft nodes before linearized reading' (duration: 249.112815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:41:39.779702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.098905ms","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-08-01T18:41:39.779768Z","caller":"traceutil/trace.go:171","msg":"trace[1587230551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2121; }","duration":"249.205866ms","start":"2026-08-01T18:41:39.530547Z","end":"2026-08-01T18:41:39.779753Z","steps":["trace[1587230551] 'agreement among raft nodes before linearized reading' (duration: 249.054193ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:41:39.779561Z","caller":"traceutil/trace.go:171","msg":"trace[199397453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2121; }","duration":"248.88431ms","start":"2026-08-01T18:41:39.530666Z","end":"2026-08-01T18:41:39.77955Z","steps":["trace[199397453] 'agreement among raft nodes before linearized reading' (duration: 248.750768ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:42:31.778819Z","caller":"traceutil/trace.go:171","msg":"trace[1821924607] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"228.384807ms","start":"2026-08-01T18:42:31.550384Z","end":"2026-08-01T18:42:31.778768Z","steps":["trace[1821924607] 'process raft request' (duration: 207.274558ms)","trace[1821924607] 'compare' (duration: 20.510709ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:42:31.829429Z","caller":"traceutil/trace.go:171","msg":"trace[704872019] linearizableReadLoop","detail":"{readStateIndex:2549; appliedIndex:2545; }","duration":"275.525995ms","start":"2026-08-01T18:42:31.553879Z","end":"2026-08-01T18:42:31.829405Z","steps":["trace[704872019] 'read index received' (duration: 203.836462ms)","trace[704872019] 'applied index is now lower than readState.Index' (duration: 71.688423ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:42:31.829758Z","caller":"traceutil/trace.go:171","msg":"trace[1342243311] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"278.863949ms","start":"2026-08-01T18:42:31.550881Z","end":"2026-08-01T18:42:31.829745Z","steps":["trace[1342243311] 'process raft request' (duration: 278.435582ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:42:31.829785Z","caller":"traceutil/trace.go:171","msg":"trace[1768533021] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"278.242039ms","start":"2026-08-01T18:42:31.551515Z","end":"2026-08-01T18:42:31.829757Z","steps":["trace[1768533021] 'process raft request' (duration: 277.849683ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:42:31.82966Z","caller":"traceutil/trace.go:171","msg":"trace[2029838562] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"278.969991ms","start":"2026-08-01T18:42:31.550662Z","end":"2026-08-01T18:42:31.829632Z","steps":["trace[2029838562] 'process raft request' (duration: 278.521274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:31.830071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.157535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:42:31.830446Z","caller":"traceutil/trace.go:171","msg":"trace[1091625345] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2454; }","duration":"276.661193ms","start":"2026-08-01T18:42:31.553768Z","end":"2026-08-01T18:42:31.830429Z","steps":["trace[1091625345] 'agreement among raft nodes before linearized reading' (duration: 276.230596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:31.830335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.727012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:42:31.830692Z","caller":"traceutil/trace.go:171","msg":"trace[709741371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2454; }","duration":"178.176598ms","start":"2026-08-01T18:42:31.652495Z","end":"2026-08-01T18:42:31.830672Z","steps":["trace[709741371] 'agreement among raft nodes before linearized reading' (duration: 177.734171ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:42:40.195219Z","caller":"traceutil/trace.go:171","msg":"trace[1064498224] linearizableReadLoop","detail":"{readStateIndex:2632; appliedIndex:2631; }","duration":"134.971657ms","start":"2026-08-01T18:42:40.060221Z","end":"2026-08-01T18:42:40.195193Z","steps":["trace[1064498224] 'read index received' (duration: 134.438688ms)","trace[1064498224] 'applied index is now lower than readState.Index' (duration: 531.409µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:42:40.195307Z","caller":"traceutil/trace.go:171","msg":"trace[575415526] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"224.223417ms","start":"2026-08-01T18:42:39.971054Z","end":"2026-08-01T18:42:40.195277Z","steps":["trace[575415526] 'process raft request' (duration: 223.632548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:40.195486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.116682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"warn","ts":"2026-08-01T18:42:40.195472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.230331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-01T18:42:40.195524Z","caller":"traceutil/trace.go:171","msg":"trace[1893860804] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2535; }","duration":"115.207834ms","start":"2026-08-01T18:42:40.080306Z","end":"2026-08-01T18:42:40.195514Z","steps":["trace[1893860804] 'agreement among raft nodes before linearized reading' (duration: 115.038211ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:42:40.195549Z","caller":"traceutil/trace.go:171","msg":"trace[1062311156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2535; }","duration":"135.345963ms","start":"2026-08-01T18:42:40.060184Z","end":"2026-08-01T18:42:40.19553Z","steps":["trace[1062311156] 'agreement among raft nodes before linearized reading' (duration: 135.1227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:40.195567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.357266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-01T18:42:40.195575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.371436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-01T18:42:40.195613Z","caller":"traceutil/trace.go:171","msg":"trace[405241687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2535; }","duration":"118.435107ms","start":"2026-08-01T18:42:40.07717Z","end":"2026-08-01T18:42:40.195606Z","steps":["trace[405241687] 'agreement among raft nodes before linearized reading' (duration: 118.305275ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:42:40.195657Z","caller":"traceutil/trace.go:171","msg":"trace[773790814] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2535; }","duration":"118.476517ms","start":"2026-08-01T18:42:40.077149Z","end":"2026-08-01T18:42:40.195626Z","steps":["trace[773790814] 'agreement among raft nodes before linearized reading' (duration: 118.264644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:40.195693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.015735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-01T18:42:40.195708Z","caller":"traceutil/trace.go:171","msg":"trace[427578176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2535; }","duration":"122.057486ms","start":"2026-08-01T18:42:40.073646Z","end":"2026-08-01T18:42:40.195704Z","steps":["trace[427578176] 'agreement among raft nodes before linearized reading' (duration: 122.013946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:40.195731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.234149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-01T18:42:40.195775Z","caller":"traceutil/trace.go:171","msg":"trace[1257784401] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2535; }","duration":"111.30182ms","start":"2026-08-01T18:42:40.08445Z","end":"2026-08-01T18:42:40.195752Z","steps":["trace[1257784401] 'agreement among raft nodes before linearized reading' (duration: 111.216109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:40.195816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.441933ms","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-08-01T18:42:40.195831Z","caller":"traceutil/trace.go:171","msg":"trace[3456202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2535; }","duration":"108.480873ms","start":"2026-08-01T18:42:40.087346Z","end":"2026-08-01T18:42:40.195827Z","steps":["trace[3456202] 'agreement among raft nodes before linearized reading' (duration: 108.440423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:40.196211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.745533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-01T18:42:40.196257Z","caller":"traceutil/trace.go:171","msg":"trace[1253557707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2535; }","duration":"134.806144ms","start":"2026-08-01T18:42:40.06144Z","end":"2026-08-01T18:42:40.196246Z","steps":["trace[1253557707] 'agreement among raft nodes before linearized reading' (duration: 133.906149ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:42:40.867618Z","caller":"traceutil/trace.go:171","msg":"trace[1344077761] linearizableReadLoop","detail":"{readStateIndex:2640; appliedIndex:2639; }","duration":"215.809128ms","start":"2026-08-01T18:42:40.651793Z","end":"2026-08-01T18:42:40.867602Z","steps":["trace[1344077761] 'read index received' (duration: 215.575484ms)","trace[1344077761] 'applied index is now lower than readState.Index' (duration: 232.754µs)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:42:40.867711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.91139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:42:40.867682Z","caller":"traceutil/trace.go:171","msg":"trace[415314981] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"344.613395ms","start":"2026-08-01T18:42:40.523031Z","end":"2026-08-01T18:42:40.867645Z","steps":["trace[415314981] 'process raft request' (duration: 344.441032ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:42:40.867744Z","caller":"traceutil/trace.go:171","msg":"trace[849932736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2543; }","duration":"216.021982ms","start":"2026-08-01T18:42:40.651711Z","end":"2026-08-01T18:42:40.867733Z","steps":["trace[849932736] 'agreement among raft nodes before linearized reading' (duration: 215.958831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:42:40.867938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:42:40.523005Z","time spent":"344.768257ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:43:13.388066Z","caller":"traceutil/trace.go:171","msg":"trace[1163695412] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"180.159507ms","start":"2026-08-01T18:43:13.207876Z","end":"2026-08-01T18:43:13.388035Z","steps":["trace[1163695412] 'process raft request' (duration: 179.954358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:13.615394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.528681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:43:13.615513Z","caller":"traceutil/trace.go:171","msg":"trace[1407962244] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"224.965008ms","start":"2026-08-01T18:43:13.390528Z","end":"2026-08-01T18:43:13.615493Z","steps":["trace[1407962244] 'process raft request' (duration: 97.24869ms)","trace[1407962244] 'compare' (duration: 127.388974ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:43:14.574464Z","caller":"traceutil/trace.go:171","msg":"trace[1787427877] linearizableReadLoop","detail":"{readStateIndex:2838; appliedIndex:2837; }","duration":"198.800516ms","start":"2026-08-01T18:43:14.375641Z","end":"2026-08-01T18:43:14.574442Z","steps":["trace[1787427877] 'read index received' (duration: 198.611088ms)","trace[1787427877] 'applied index is now lower than readState.Index' (duration: 188.658µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:43:14.574683Z","caller":"traceutil/trace.go:171","msg":"trace[374657812] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"229.191326ms","start":"2026-08-01T18:43:14.345431Z","end":"2026-08-01T18:43:14.574622Z","steps":["trace[374657812] 'process raft request' (duration: 228.879733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:14.574768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.118589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-01T18:43:14.574815Z","caller":"traceutil/trace.go:171","msg":"trace[1506188562] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2733; }","duration":"199.224173ms","start":"2026-08-01T18:43:14.375578Z","end":"2026-08-01T18:43:14.574803Z","steps":["trace[1506188562] 'agreement among raft nodes before linearized reading' (duration: 199.003344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:14.575011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.067551ms","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-08-01T18:43:14.575044Z","caller":"traceutil/trace.go:171","msg":"trace[2099584006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2733; }","duration":"128.105412ms","start":"2026-08-01T18:43:14.446928Z","end":"2026-08-01T18:43:14.575034Z","steps":["trace[2099584006] 'agreement among raft nodes before linearized reading' (duration: 128.024009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:14.575069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.099918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-01T18:43:14.575153Z","caller":"traceutil/trace.go:171","msg":"trace[1549208780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2733; }","duration":"183.241644ms","start":"2026-08-01T18:43:14.391897Z","end":"2026-08-01T18:43:14.575138Z","steps":["trace[1549208780] 'agreement among raft nodes before linearized reading' (duration: 183.033666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:14.575176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.847475ms","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-08-01T18:43:14.575545Z","caller":"traceutil/trace.go:171","msg":"trace[688409631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2733; }","duration":"182.339916ms","start":"2026-08-01T18:43:14.393183Z","end":"2026-08-01T18:43:14.575522Z","steps":["trace[688409631] 'agreement among raft nodes before linearized reading' (duration: 181.714149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:14.575215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.349433ms","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":"warn","ts":"2026-08-01T18:43:14.5756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.657317ms","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-08-01T18:43:14.575633Z","caller":"traceutil/trace.go:171","msg":"trace[1674887382] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2733; }","duration":"128.781131ms","start":"2026-08-01T18:43:14.44683Z","end":"2026-08-01T18:43:14.575611Z","steps":["trace[1674887382] 'agreement among raft nodes before linearized reading' (duration: 128.167454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:14.575386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.450205ms","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-08-01T18:43:14.575671Z","caller":"traceutil/trace.go:171","msg":"trace[1507142094] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2733; }","duration":"185.752741ms","start":"2026-08-01T18:43:14.389903Z","end":"2026-08-01T18:43:14.575655Z","steps":["trace[1507142094] 'agreement among raft nodes before linearized reading' (duration: 184.77137ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:43:14.575715Z","caller":"traceutil/trace.go:171","msg":"trace[1268727747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2733; }","duration":"131.804789ms","start":"2026-08-01T18:43:14.443896Z","end":"2026-08-01T18:43:14.575701Z","steps":["trace[1268727747] 'agreement among raft nodes before linearized reading' (duration: 130.860019ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:43:15.297664Z","caller":"traceutil/trace.go:171","msg":"trace[967294227] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"717.632063ms","start":"2026-08-01T18:43:14.579997Z","end":"2026-08-01T18:43:15.297629Z","steps":["trace[967294227] 'process raft request' (duration: 717.190794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:15.297844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:43:14.579974Z","time spent":"717.773599ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:43:15.301136Z","caller":"traceutil/trace.go:171","msg":"trace[1787825478] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"482.796476ms","start":"2026-08-01T18:43:14.818316Z","end":"2026-08-01T18:43:15.301113Z","steps":["trace[1787825478] 'process raft request' (duration: 482.756504ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:43:15.301462Z","caller":"traceutil/trace.go:171","msg":"trace[1001327915] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"720.917262ms","start":"2026-08-01T18:43:14.580522Z","end":"2026-08-01T18:43:15.301439Z","steps":["trace[1001327915] 'process raft request' (duration: 720.426981ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:43:15.30148Z","caller":"traceutil/trace.go:171","msg":"trace[893002389] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"721.392002ms","start":"2026-08-01T18:43:14.580047Z","end":"2026-08-01T18:43:15.301439Z","steps":["trace[893002389] 'process raft request' (duration: 720.743854ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:43:15.301503Z","caller":"traceutil/trace.go:171","msg":"trace[1914157100] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"721.083529ms","start":"2026-08-01T18:43:14.580394Z","end":"2026-08-01T18:43:15.301478Z","steps":["trace[1914157100] 'process raft request' (duration: 720.512564ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:43:15.301532Z","caller":"traceutil/trace.go:171","msg":"trace[1819598027] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"720.914761ms","start":"2026-08-01T18:43:14.580599Z","end":"2026-08-01T18:43:15.301514Z","steps":["trace[1819598027] 'process raft request' (duration: 720.379878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:15.301599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:43:14.580383Z","time spent":"721.168373ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:43:15.301623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:43:14.580007Z","time spent":"721.537188ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:43:15.301579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:43:14.580517Z","time spent":"721.008556ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:43:15.30163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:43:14.580593Z","time spent":"720.987395ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:43:15.301757Z","caller":"traceutil/trace.go:171","msg":"trace[633289305] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"720.241853ms","start":"2026-08-01T18:43:14.581505Z","end":"2026-08-01T18:43:15.301747Z","steps":["trace[633289305] 'process raft request' (duration: 719.539453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:15.301808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:43:14.5815Z","time spent":"720.280024ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:43:15.301865Z","caller":"traceutil/trace.go:171","msg":"trace[1253711170] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"720.796617ms","start":"2026-08-01T18:43:14.581058Z","end":"2026-08-01T18:43:15.301855Z","steps":["trace[1253711170] 'process raft request' (duration: 719.954851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:43:15.301928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:43:14.581052Z","time spent":"720.842798ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:43:15.301882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:43:14.818293Z","time spent":"483.082288ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:43:32.581171Z","caller":"traceutil/trace.go:171","msg":"trace[166227266] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"120.092393ms","start":"2026-08-01T18:43:32.460836Z","end":"2026-08-01T18:43:32.580928Z","steps":["trace[166227266] 'process raft request' (duration: 119.948108ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:46.650331Z","caller":"traceutil/trace.go:171","msg":"trace[918036538] linearizableReadLoop","detail":"{readStateIndex:4203; appliedIndex:4202; }","duration":"220.655928ms","start":"2026-08-01T18:46:46.429632Z","end":"2026-08-01T18:46:46.650288Z","steps":["trace[918036538] 'read index received' (duration: 220.391732ms)","trace[918036538] 'applied index is now lower than readState.Index' (duration: 262.886µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:46:46.650433Z","caller":"traceutil/trace.go:171","msg":"trace[1103768980] transaction","detail":"{read_only:false; response_revision:4036; number_of_response:1; }","duration":"222.311027ms","start":"2026-08-01T18:46:46.428093Z","end":"2026-08-01T18:46:46.650404Z","steps":["trace[1103768980] 'process raft request' (duration: 222.01578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:46.650655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.130424ms","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-08-01T18:46:46.650686Z","caller":"traceutil/trace.go:171","msg":"trace[196636231] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4036; }","duration":"110.172355ms","start":"2026-08-01T18:46:46.540505Z","end":"2026-08-01T18:46:46.650677Z","steps":["trace[196636231] 'agreement among raft nodes before linearized reading' (duration: 110.049122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:46.650905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.445211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-01T18:46:46.650969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.441022ms","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-08-01T18:46:46.650967Z","caller":"traceutil/trace.go:171","msg":"trace[98358036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4036; }","duration":"110.533583ms","start":"2026-08-01T18:46:46.540416Z","end":"2026-08-01T18:46:46.65095Z","steps":["trace[98358036] 'agreement among raft nodes before linearized reading' (duration: 110.419241ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:46.650989Z","caller":"traceutil/trace.go:171","msg":"trace[271591896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4036; }","duration":"110.568374ms","start":"2026-08-01T18:46:46.540416Z","end":"2026-08-01T18:46:46.650984Z","steps":["trace[271591896] 'agreement among raft nodes before linearized reading' (duration: 110.516733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:46.651212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.397811ms","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-08-01T18:46:46.651294Z","caller":"traceutil/trace.go:171","msg":"trace[70527029] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4036; }","duration":"141.518163ms","start":"2026-08-01T18:46:46.509754Z","end":"2026-08-01T18:46:46.651272Z","steps":["trace[70527029] 'agreement among raft nodes before linearized reading' (duration: 141.282927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:46.650623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.946595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-08-01T18:46:46.651352Z","caller":"traceutil/trace.go:171","msg":"trace[1232367172] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4036; }","duration":"221.723923ms","start":"2026-08-01T18:46:46.429616Z","end":"2026-08-01T18:46:46.65134Z","steps":["trace[1232367172] 'agreement among raft nodes before linearized reading' (duration: 220.841112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:46.65078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.282818ms","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-08-01T18:46:46.651396Z","caller":"traceutil/trace.go:171","msg":"trace[453851226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4036; }","duration":"141.959933ms","start":"2026-08-01T18:46:46.509417Z","end":"2026-08-01T18:46:46.651377Z","steps":["trace[453851226] 'agreement among raft nodes before linearized reading' (duration: 141.138064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:46.651707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.259711ms","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-08-01T18:46:46.651911Z","caller":"traceutil/trace.go:171","msg":"trace[553206934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4036; }","duration":"111.432225ms","start":"2026-08-01T18:46:46.54038Z","end":"2026-08-01T18:46:46.651812Z","steps":["trace[553206934] 'agreement among raft nodes before linearized reading' (duration: 110.460392ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:52.426507Z","caller":"traceutil/trace.go:171","msg":"trace[2104304715] transaction","detail":"{read_only:false; response_revision:4380; number_of_response:1; }","duration":"121.725222ms","start":"2026-08-01T18:46:52.304758Z","end":"2026-08-01T18:46:52.426483Z","steps":["trace[2104304715] 'process raft request' (duration: 120.564075ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:53.583199Z","caller":"traceutil/trace.go:171","msg":"trace[1388476670] transaction","detail":"{read_only:false; response_revision:4422; number_of_response:1; }","duration":"215.252139ms","start":"2026-08-01T18:46:53.36793Z","end":"2026-08-01T18:46:53.583182Z","steps":["trace[1388476670] 'process raft request' (duration: 215.129196ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:53.584951Z","caller":"traceutil/trace.go:171","msg":"trace[725098485] transaction","detail":"{read_only:false; response_revision:4424; number_of_response:1; }","duration":"104.383473ms","start":"2026-08-01T18:46:53.480539Z","end":"2026-08-01T18:46:53.584922Z","steps":["trace[725098485] 'process raft request' (duration: 104.304082ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:53.585363Z","caller":"traceutil/trace.go:171","msg":"trace[2043863517] transaction","detail":"{read_only:false; response_revision:4423; number_of_response:1; }","duration":"178.893802ms","start":"2026-08-01T18:46:53.406161Z","end":"2026-08-01T18:46:53.585055Z","steps":["trace[2043863517] 'process raft request' (duration: 178.471072ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:55.009933Z","caller":"traceutil/trace.go:171","msg":"trace[873142867] linearizableReadLoop","detail":"{readStateIndex:4682; appliedIndex:4681; }","duration":"113.023862ms","start":"2026-08-01T18:46:54.896886Z","end":"2026-08-01T18:46:55.00991Z","steps":["trace[873142867] 'read index received' (duration: 28.688748ms)","trace[873142867] 'applied index is now lower than readState.Index' (duration: 84.333734ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:46:55.010059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.483197ms","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-08-01T18:46:55.010116Z","caller":"traceutil/trace.go:171","msg":"trace[267824579] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4504; }","duration":"163.570578ms","start":"2026-08-01T18:46:54.846531Z","end":"2026-08-01T18:46:55.010102Z","steps":["trace[267824579] 'agreement among raft nodes before linearized reading' (duration: 163.440655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:55.010152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.61535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-01T18:46:55.010134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.844763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-01T18:46:55.010181Z","caller":"traceutil/trace.go:171","msg":"trace[232113006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4504; }","duration":"163.675891ms","start":"2026-08-01T18:46:54.846497Z","end":"2026-08-01T18:46:55.010173Z","steps":["trace[232113006] 'agreement among raft nodes before linearized reading' (duration: 163.529888ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:55.010214Z","caller":"traceutil/trace.go:171","msg":"trace[286910546] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4504; }","duration":"135.947435ms","start":"2026-08-01T18:46:54.874237Z","end":"2026-08-01T18:46:55.010185Z","steps":["trace[286910546] 'agreement among raft nodes before linearized reading' (duration: 135.690719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:55.010297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.18836ms","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-08-01T18:46:55.010326Z","caller":"traceutil/trace.go:171","msg":"trace[837060171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4504; }","duration":"163.22748ms","start":"2026-08-01T18:46:54.847089Z","end":"2026-08-01T18:46:55.010317Z","steps":["trace[837060171] 'agreement among raft nodes before linearized reading' (duration: 163.158149ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:55.010411Z","caller":"traceutil/trace.go:171","msg":"trace[1800273557] transaction","detail":"{read_only:false; response_revision:4504; number_of_response:1; }","duration":"137.074232ms","start":"2026-08-01T18:46:54.873311Z","end":"2026-08-01T18:46:55.010385Z","steps":["trace[1800273557] 'process raft request' (duration: 52.314268ms)","trace[1800273557] 'compare' (duration: 84.130609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:46:55.010535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.210284ms","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-08-01T18:46:55.010628Z","caller":"traceutil/trace.go:171","msg":"trace[859976937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4504; }","duration":"164.313776ms","start":"2026-08-01T18:46:54.846278Z","end":"2026-08-01T18:46:55.010592Z","steps":["trace[859976937] 'agreement among raft nodes before linearized reading' (duration: 164.05822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:46:55.010639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.282613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:7 size:61052"} {"level":"info","ts":"2026-08-01T18:46:55.010703Z","caller":"traceutil/trace.go:171","msg":"trace[1687479494] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:7; response_revision:4504; }","duration":"136.391816ms","start":"2026-08-01T18:46:54.874295Z","end":"2026-08-01T18:46:55.010687Z","steps":["trace[1687479494] 'agreement among raft nodes before linearized reading' (duration: 136.102989ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:55.051313Z","caller":"traceutil/trace.go:171","msg":"trace[1297997014] transaction","detail":"{read_only:false; response_revision:4505; number_of_response:1; }","duration":"150.271998ms","start":"2026-08-01T18:46:54.900974Z","end":"2026-08-01T18:46:55.051246Z","steps":["trace[1297997014] 'process raft request' (duration: 150.161565ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:55.072967Z","caller":"traceutil/trace.go:171","msg":"trace[364461502] transaction","detail":"{read_only:false; response_revision:4506; number_of_response:1; }","duration":"131.165212ms","start":"2026-08-01T18:46:54.94178Z","end":"2026-08-01T18:46:55.072946Z","steps":["trace[364461502] 'process raft request' (duration: 130.148078ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:46:57.096146Z","caller":"traceutil/trace.go:171","msg":"trace[528467366] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4539; }","duration":"126.191587ms","start":"2026-08-01T18:46:56.969934Z","end":"2026-08-01T18:46:57.096125Z","steps":["trace[528467366] 'process raft request' (duration: 88.118044ms)","trace[528467366] 'compare' (duration: 37.859888ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:00.35754Z","caller":"traceutil/trace.go:171","msg":"trace[1579664487] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"128.331603ms","start":"2026-08-01T18:47:00.229182Z","end":"2026-08-01T18:47:00.357513Z","steps":["trace[1579664487] 'process raft request' (duration: 128.19249ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:01.257371Z","caller":"traceutil/trace.go:171","msg":"trace[596042416] linearizableReadLoop","detail":"{readStateIndex:4820; appliedIndex:4819; }","duration":"131.154805ms","start":"2026-08-01T18:47:01.12619Z","end":"2026-08-01T18:47:01.257345Z","steps":["trace[596042416] 'read index received' (duration: 130.872888ms)","trace[596042416] 'applied index is now lower than readState.Index' (duration: 280.656µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:01.257427Z","caller":"traceutil/trace.go:171","msg":"trace[103988241] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"132.754771ms","start":"2026-08-01T18:47:01.124629Z","end":"2026-08-01T18:47:01.257384Z","steps":["trace[103988241] 'process raft request' (duration: 132.532016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:01.257621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.695301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-01T18:47:01.257627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.41426ms","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":"warn","ts":"2026-08-01T18:47:01.257649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.133716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:47:01.257685Z","caller":"traceutil/trace.go:171","msg":"trace[227895865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4637; }","duration":"131.529713ms","start":"2026-08-01T18:47:01.12614Z","end":"2026-08-01T18:47:01.25767Z","steps":["trace[227895865] 'agreement among raft nodes before linearized reading' (duration: 131.328178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:01.257706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.747983ms","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-08-01T18:47:01.257712Z","caller":"traceutil/trace.go:171","msg":"trace[76097119] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4637; }","duration":"125.27966ms","start":"2026-08-01T18:47:01.132417Z","end":"2026-08-01T18:47:01.257697Z","steps":["trace[76097119] 'agreement among raft nodes before linearized reading' (duration: 125.123506ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:01.257726Z","caller":"traceutil/trace.go:171","msg":"trace[1696000283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4637; }","duration":"129.792283ms","start":"2026-08-01T18:47:01.127929Z","end":"2026-08-01T18:47:01.257721Z","steps":["trace[1696000283] 'agreement among raft nodes before linearized reading' (duration: 129.735842ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:01.257649Z","caller":"traceutil/trace.go:171","msg":"trace[736492484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4637; }","duration":"129.740322ms","start":"2026-08-01T18:47:01.1279Z","end":"2026-08-01T18:47:01.25764Z","steps":["trace[736492484] 'agreement among raft nodes before linearized reading' (duration: 129.611479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:01.257866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.048443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-01T18:47:01.257985Z","caller":"traceutil/trace.go:171","msg":"trace[2127827023] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4637; }","duration":"128.244407ms","start":"2026-08-01T18:47:01.129707Z","end":"2026-08-01T18:47:01.257952Z","steps":["trace[2127827023] 'agreement among raft nodes before linearized reading' (duration: 127.851338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:01.692169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.944562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:47:01.6923Z","caller":"traceutil/trace.go:171","msg":"trace[571782991] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"431.00011ms","start":"2026-08-01T18:47:01.261277Z","end":"2026-08-01T18:47:01.692277Z","steps":["trace[571782991] 'process raft request' (duration: 197.849113ms)","trace[571782991] 'compare' (duration: 232.808529ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:47:01.692363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:01.261254Z","time spent":"431.081561ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:47:01.692938Z","caller":"traceutil/trace.go:171","msg":"trace[917354122] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"431.155263ms","start":"2026-08-01T18:47:01.261767Z","end":"2026-08-01T18:47:01.692922Z","steps":["trace[917354122] 'process raft request' (duration: 431.086291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:01.69303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:01.26176Z","time spent":"431.227665ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:47:01.693546Z","caller":"traceutil/trace.go:171","msg":"trace[463447724] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"431.4718ms","start":"2026-08-01T18:47:01.26203Z","end":"2026-08-01T18:47:01.693502Z","steps":["trace[463447724] 'process raft request' (duration: 430.859486ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:01.693322Z","caller":"traceutil/trace.go:171","msg":"trace[215754321] linearizableReadLoop","detail":"{readStateIndex:4824; appliedIndex:4820; }","duration":"345.039278ms","start":"2026-08-01T18:47:01.348269Z","end":"2026-08-01T18:47:01.693309Z","steps":["trace[215754321] 'read index received' (duration: 110.859697ms)","trace[215754321] 'applied index is now lower than readState.Index' (duration: 234.17844ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:01.69322Z","caller":"traceutil/trace.go:171","msg":"trace[1059829689] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"431.318517ms","start":"2026-08-01T18:47:01.261648Z","end":"2026-08-01T18:47:01.692966Z","steps":["trace[1059829689] 'process raft request' (duration: 431.124912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:01.693835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.555619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-01T18:47:01.693869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:01.262013Z","time spent":"431.719206ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:47:01.693912Z","caller":"traceutil/trace.go:171","msg":"trace[1417502870] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4641; }","duration":"345.689742ms","start":"2026-08-01T18:47:01.348206Z","end":"2026-08-01T18:47:01.693896Z","steps":["trace[1417502870] 'agreement among raft nodes before linearized reading' (duration: 345.552509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:01.694237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:01.348182Z","time spent":"346.03747ms","remote":"127.0.0.1:52676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:47:01.694416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:01.261617Z","time spent":"432.30063ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:47:07.707893Z","caller":"traceutil/trace.go:171","msg":"trace[1806698999] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4920; }","duration":"402.56569ms","start":"2026-08-01T18:47:07.305306Z","end":"2026-08-01T18:47:07.707871Z","steps":["trace[1806698999] 'read index received' (duration: 402.368026ms)","trace[1806698999] 'applied index is now lower than readState.Index' (duration: 196.584µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:07.707985Z","caller":"traceutil/trace.go:171","msg":"trace[384991647] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"413.01909ms","start":"2026-08-01T18:47:07.294928Z","end":"2026-08-01T18:47:07.707947Z","steps":["trace[384991647] 'process raft request' (duration: 412.804525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:07.708042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.708025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:47:07.708113Z","caller":"traceutil/trace.go:171","msg":"trace[2010156476] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4735; }","duration":"402.828767ms","start":"2026-08-01T18:47:07.305265Z","end":"2026-08-01T18:47:07.708094Z","steps":["trace[2010156476] 'agreement among raft nodes before linearized reading' (duration: 402.693573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:07.708156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:07.305252Z","time spent":"402.894039ms","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:47:07.708158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:07.294911Z","time spent":"413.154163ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:07.708169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.521099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-08-01T18:47:07.70821Z","caller":"traceutil/trace.go:171","msg":"trace[1653149705] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4735; }","duration":"197.647962ms","start":"2026-08-01T18:47:07.510551Z","end":"2026-08-01T18:47:07.708199Z","steps":["trace[1653149705] 'agreement among raft nodes before linearized reading' (duration: 197.461588ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:07.837767Z","caller":"traceutil/trace.go:171","msg":"trace[742933980] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"124.10527ms","start":"2026-08-01T18:47:07.713634Z","end":"2026-08-01T18:47:07.837739Z","steps":["trace[742933980] 'process raft request' (duration: 79.705974ms)","trace[742933980] 'compare' (duration: 44.294233ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:07.859737Z","caller":"traceutil/trace.go:171","msg":"trace[1795637847] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"145.476799ms","start":"2026-08-01T18:47:07.714236Z","end":"2026-08-01T18:47:07.859713Z","steps":["trace[1795637847] 'process raft request' (duration: 145.312345ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:07.859983Z","caller":"traceutil/trace.go:171","msg":"trace[1144849684] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4921; }","duration":"143.340709ms","start":"2026-08-01T18:47:07.716615Z","end":"2026-08-01T18:47:07.859955Z","steps":["trace[1144849684] 'read index received' (duration: 76.740935ms)","trace[1144849684] 'applied index is now lower than readState.Index' (duration: 66.597524ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:07.860065Z","caller":"traceutil/trace.go:171","msg":"trace[551935333] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"115.910292ms","start":"2026-08-01T18:47:07.744146Z","end":"2026-08-01T18:47:07.860056Z","steps":["trace[551935333] 'process raft request' (duration: 115.526743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:07.860384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.74673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-01T18:47:07.860392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.099042ms","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-08-01T18:47:07.860446Z","caller":"traceutil/trace.go:171","msg":"trace[1136176954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4738; }","duration":"142.181333ms","start":"2026-08-01T18:47:07.718251Z","end":"2026-08-01T18:47:07.860432Z","steps":["trace[1136176954] 'agreement among raft nodes before linearized reading' (duration: 141.978639ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:07.860434Z","caller":"traceutil/trace.go:171","msg":"trace[1825104662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4738; }","duration":"143.833261ms","start":"2026-08-01T18:47:07.716589Z","end":"2026-08-01T18:47:07.860422Z","steps":["trace[1825104662] 'agreement among raft nodes before linearized reading' (duration: 143.608916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:07.861263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.572642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-01T18:47:07.861351Z","caller":"traceutil/trace.go:171","msg":"trace[977063920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4738; }","duration":"142.693505ms","start":"2026-08-01T18:47:07.718637Z","end":"2026-08-01T18:47:07.861331Z","steps":["trace[977063920] 'agreement among raft nodes before linearized reading' (duration: 141.663821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:07.861445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.630434ms","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-08-01T18:47:07.861486Z","caller":"traceutil/trace.go:171","msg":"trace[677590705] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4738; }","duration":"142.694965ms","start":"2026-08-01T18:47:07.718778Z","end":"2026-08-01T18:47:07.861473Z","steps":["trace[677590705] 'agreement among raft nodes before linearized reading' (duration: 141.460667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:08.479712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.823385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:47:08.480113Z","caller":"traceutil/trace.go:171","msg":"trace[328547947] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"477.596853ms","start":"2026-08-01T18:47:08.002484Z","end":"2026-08-01T18:47:08.480081Z","steps":["trace[328547947] 'process raft request' (duration: 477.326637ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:08.480234Z","caller":"traceutil/trace.go:171","msg":"trace[1686323135] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"102.737107ms","start":"2026-08-01T18:47:08.377477Z","end":"2026-08-01T18:47:08.480214Z","steps":["trace[1686323135] 'process raft request' (duration: 102.642515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:08.480268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:08.002103Z","time spent":"478.081795ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:47:08.480398Z","caller":"traceutil/trace.go:171","msg":"trace[1058969318] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"476.053969ms","start":"2026-08-01T18:47:08.004269Z","end":"2026-08-01T18:47:08.480323Z","steps":["trace[1058969318] 'process raft request' (duration: 475.729812ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:08.480378Z","caller":"traceutil/trace.go:171","msg":"trace[67796562] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"478.102136ms","start":"2026-08-01T18:47:08.002245Z","end":"2026-08-01T18:47:08.480347Z","steps":["trace[67796562] 'process raft request' (duration: 109.568504ms)","trace[67796562] 'compare' (duration: 367.716413ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:08.480425Z","caller":"traceutil/trace.go:171","msg":"trace[1415693428] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"119.406419ms","start":"2026-08-01T18:47:08.360986Z","end":"2026-08-01T18:47:08.480393Z","steps":["trace[1415693428] 'process raft request' (duration: 119.090151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:08.480612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:08.002222Z","time spent":"478.271689ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:47:08.480658Z","caller":"traceutil/trace.go:171","msg":"trace[2016497266] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4930; }","duration":"476.643542ms","start":"2026-08-01T18:47:08.003963Z","end":"2026-08-01T18:47:08.480606Z","steps":["trace[2016497266] 'read index received' (duration: 107.874056ms)","trace[2016497266] 'applied index is now lower than readState.Index' (duration: 368.768316ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:08.480741Z","caller":"traceutil/trace.go:171","msg":"trace[1508549211] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"477.025871ms","start":"2026-08-01T18:47:08.003701Z","end":"2026-08-01T18:47:08.480727Z","steps":["trace[1508549211] 'process raft request' (duration: 476.203402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:08.480848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:08.003687Z","time spent":"477.090552ms","remote":"127.0.0.1:52772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:08.480931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.96047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d6883be-27e3-43a0-97e0-7b40a86f7cc4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-01T18:47:08.480986Z","caller":"traceutil/trace.go:171","msg":"trace[672523493] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d6883be-27e3-43a0-97e0-7b40a86f7cc4; range_end:; response_count:1; response_revision:4750; }","duration":"477.026881ms","start":"2026-08-01T18:47:08.003945Z","end":"2026-08-01T18:47:08.480972Z","steps":["trace[672523493] 'agreement among raft nodes before linearized reading' (duration: 476.880607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:08.481023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:08.003938Z","time spent":"477.077812ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d6883be-27e3-43a0-97e0-7b40a86f7cc4\" "} {"level":"warn","ts":"2026-08-01T18:47:08.480627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:08.00426Z","time spent":"476.182092ms","remote":"127.0.0.1:52772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:08.481674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.993358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d6883be-27e3-43a0-97e0-7b40a86f7cc4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-01T18:47:08.481723Z","caller":"traceutil/trace.go:171","msg":"trace[2106098741] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d6883be-27e3-43a0-97e0-7b40a86f7cc4; range_end:; response_count:1; response_revision:4750; }","duration":"199.054809ms","start":"2026-08-01T18:47:08.282655Z","end":"2026-08-01T18:47:08.48171Z","steps":["trace[2106098741] 'agreement among raft nodes before linearized reading' (duration: 198.914005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:08.483634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.956798ms","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-08-01T18:47:08.483678Z","caller":"traceutil/trace.go:171","msg":"trace[1194490261] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4750; }","duration":"217.031319ms","start":"2026-08-01T18:47:08.266634Z","end":"2026-08-01T18:47:08.483665Z","steps":["trace[1194490261] 'agreement among raft nodes before linearized reading' (duration: 214.223445ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:08.872141Z","caller":"traceutil/trace.go:171","msg":"trace[1081009691] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4937; }","duration":"353.914117ms","start":"2026-08-01T18:47:08.51821Z","end":"2026-08-01T18:47:08.872124Z","steps":["trace[1081009691] 'read index received' (duration: 334.537435ms)","trace[1081009691] 'applied index is now lower than readState.Index' (duration: 19.376132ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:08.872223Z","caller":"traceutil/trace.go:171","msg":"trace[185182325] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"383.641936ms","start":"2026-08-01T18:47:08.488574Z","end":"2026-08-01T18:47:08.872216Z","steps":["trace[185182325] 'process raft request' (duration: 364.283744ms)","trace[185182325] 'compare' (duration: 19.193248ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:47:08.872307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:08.488553Z","time spent":"383.685058ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:08.872843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.625944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-08-01T18:47:08.873139Z","caller":"traceutil/trace.go:171","msg":"trace[2066818200] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4752; }","duration":"354.958801ms","start":"2026-08-01T18:47:08.51816Z","end":"2026-08-01T18:47:08.873119Z","steps":["trace[2066818200] 'agreement among raft nodes before linearized reading' (duration: 354.518971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:08.873252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:08.518084Z","time spent":"355.151025ms","remote":"127.0.0.1:52804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1289,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"warn","ts":"2026-08-01T18:47:11.192733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.685697ms","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-08-01T18:47:11.192799Z","caller":"traceutil/trace.go:171","msg":"trace[1176252417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4779; }","duration":"210.782528ms","start":"2026-08-01T18:47:10.982Z","end":"2026-08-01T18:47:11.192782Z","steps":["trace[1176252417] 'range keys from in-memory index tree' (duration: 210.530583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:11.192873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.476957ms","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-08-01T18:47:11.192961Z","caller":"traceutil/trace.go:171","msg":"trace[1168915217] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4779; }","duration":"194.6006ms","start":"2026-08-01T18:47:10.99833Z","end":"2026-08-01T18:47:11.192931Z","steps":["trace[1168915217] 'range keys from in-memory index tree' (duration: 194.300153ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:12.097969Z","caller":"traceutil/trace.go:171","msg":"trace[144200244] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"152.822626ms","start":"2026-08-01T18:47:11.945122Z","end":"2026-08-01T18:47:12.097945Z","steps":["trace[144200244] 'process raft request' (duration: 152.688893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:12.367469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.090135ms","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-08-01T18:47:12.367545Z","caller":"traceutil/trace.go:171","msg":"trace[161247541] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4801; }","duration":"147.214047ms","start":"2026-08-01T18:47:12.220315Z","end":"2026-08-01T18:47:12.367529Z","steps":["trace[161247541] 'range keys from in-memory index tree' (duration: 147.014612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:17.352344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.57453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-01T18:47:17.352414Z","caller":"traceutil/trace.go:171","msg":"trace[270186564] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4883; }","duration":"322.654631ms","start":"2026-08-01T18:47:17.029743Z","end":"2026-08-01T18:47:17.352398Z","steps":["trace[270186564] 'range keys from in-memory index tree' (duration: 322.427026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:17.352453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:17.029726Z","time spent":"322.709902ms","remote":"127.0.0.1:52756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-08-01T18:47:17.352531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.617229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13291"} {"level":"info","ts":"2026-08-01T18:47:17.352644Z","caller":"traceutil/trace.go:171","msg":"trace[1569890704] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4883; }","duration":"356.734392ms","start":"2026-08-01T18:47:16.995845Z","end":"2026-08-01T18:47:17.352579Z","steps":["trace[1569890704] 'range keys from in-memory index tree' (duration: 356.028085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:17.352876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:16.995822Z","time spent":"356.851435ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13315,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-08-01T18:47:21.471536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.076561ms","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-08-01T18:47:21.471618Z","caller":"traceutil/trace.go:171","msg":"trace[286900659] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4915; }","duration":"349.237365ms","start":"2026-08-01T18:47:21.122357Z","end":"2026-08-01T18:47:21.471594Z","steps":["trace[286900659] 'range keys from in-memory index tree' (duration: 348.741153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:21.471659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.122336Z","time spent":"349.314316ms","remote":"127.0.0.1:52756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-01T18:47:21.471693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.329969ms","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-08-01T18:47:21.47176Z","caller":"traceutil/trace.go:171","msg":"trace[950688888] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4915; }","duration":"262.440352ms","start":"2026-08-01T18:47:21.2093Z","end":"2026-08-01T18:47:21.47174Z","steps":["trace[950688888] 'range keys from in-memory index tree' (duration: 262.180187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:21.471787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.068879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-08-01T18:47:21.471843Z","caller":"traceutil/trace.go:171","msg":"trace[239987851] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4915; }","duration":"449.149721ms","start":"2026-08-01T18:47:21.022679Z","end":"2026-08-01T18:47:21.471829Z","steps":["trace[239987851] 'range keys from in-memory index tree' (duration: 448.977227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:21.471879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.022662Z","time spent":"449.207133ms","remote":"127.0.0.1:52794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10416,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-01T18:47:21.471967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.800197ms","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-08-01T18:47:21.472018Z","caller":"traceutil/trace.go:171","msg":"trace[991527307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4915; }","duration":"100.886439ms","start":"2026-08-01T18:47:21.371116Z","end":"2026-08-01T18:47:21.472002Z","steps":["trace[991527307] 'range keys from in-memory index tree' (duration: 100.660713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:21.471706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.230379ms","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-08-01T18:47:21.472068Z","caller":"traceutil/trace.go:171","msg":"trace[1495460536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4915; }","duration":"487.644538ms","start":"2026-08-01T18:47:20.984416Z","end":"2026-08-01T18:47:21.472061Z","steps":["trace[1495460536] 'range keys from in-memory index tree' (duration: 487.085706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:21.47209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:20.984397Z","time spent":"487.684249ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-01T18:47:21.668342Z","caller":"traceutil/trace.go:171","msg":"trace[2080709949] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"187.274022ms","start":"2026-08-01T18:47:21.481046Z","end":"2026-08-01T18:47:21.66832Z","steps":["trace[2080709949] 'process raft request' (duration: 187.205551ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:21.668504Z","caller":"traceutil/trace.go:171","msg":"trace[1502312474] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"191.266862ms","start":"2026-08-01T18:47:21.477216Z","end":"2026-08-01T18:47:21.668483Z","steps":["trace[1502312474] 'process raft request' (duration: 190.875493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:21.912104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.25845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:47:21.912314Z","caller":"traceutil/trace.go:171","msg":"trace[60330548] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"235.664ms","start":"2026-08-01T18:47:21.676633Z","end":"2026-08-01T18:47:21.912296Z","steps":["trace[60330548] 'process raft request' (duration: 235.578488ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:21.912438Z","caller":"traceutil/trace.go:171","msg":"trace[268779679] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"235.756852ms","start":"2026-08-01T18:47:21.676633Z","end":"2026-08-01T18:47:21.912389Z","steps":["trace[268779679] 'process raft request' (duration: 129.130874ms)","trace[268779679] 'compare' (duration: 106.115957ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:21.92984Z","caller":"traceutil/trace.go:171","msg":"trace[220406143] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"253.086383ms","start":"2026-08-01T18:47:21.676734Z","end":"2026-08-01T18:47:21.92982Z","steps":["trace[220406143] 'process raft request' (duration: 252.95329ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:21.930105Z","caller":"traceutil/trace.go:171","msg":"trace[223241181] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5109; }","duration":"161.103414ms","start":"2026-08-01T18:47:21.768984Z","end":"2026-08-01T18:47:21.930087Z","steps":["trace[223241181] 'read index received' (duration: 36.751317ms)","trace[223241181] 'applied index is now lower than readState.Index' (duration: 124.350577ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:21.930165Z","caller":"traceutil/trace.go:171","msg":"trace[1223941603] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"247.800833ms","start":"2026-08-01T18:47:21.682338Z","end":"2026-08-01T18:47:21.930139Z","steps":["trace[1223941603] 'process raft request' (duration: 247.414404ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:21.929866Z","caller":"traceutil/trace.go:171","msg":"trace[570412872] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"253.157594ms","start":"2026-08-01T18:47:21.676671Z","end":"2026-08-01T18:47:21.929829Z","steps":["trace[570412872] 'process raft request' (duration: 252.875177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:21.930375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.38877ms","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-08-01T18:47:21.930467Z","caller":"traceutil/trace.go:171","msg":"trace[1632787198] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4923; }","duration":"161.536773ms","start":"2026-08-01T18:47:21.768913Z","end":"2026-08-01T18:47:21.930449Z","steps":["trace[1632787198] 'agreement among raft nodes before linearized reading' (duration: 161.294977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.319251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.089596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:47:22.319689Z","caller":"traceutil/trace.go:171","msg":"trace[559325268] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5115; }","duration":"345.593754ms","start":"2026-08-01T18:47:21.974074Z","end":"2026-08-01T18:47:22.319667Z","steps":["trace[559325268] 'read index received' (duration: 50.792772ms)","trace[559325268] 'applied index is now lower than readState.Index' (duration: 294.799692ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:22.319916Z","caller":"traceutil/trace.go:171","msg":"trace[626401130] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"382.875383ms","start":"2026-08-01T18:47:21.937001Z","end":"2026-08-01T18:47:22.319877Z","steps":["trace[626401130] 'process raft request' (duration: 382.369961ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:22.320002Z","caller":"traceutil/trace.go:171","msg":"trace[1911792937] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"384.123221ms","start":"2026-08-01T18:47:21.935854Z","end":"2026-08-01T18:47:22.319978Z","steps":["trace[1911792937] 'process raft request' (duration: 89.204496ms)","trace[1911792937] 'compare' (duration: 293.734528ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:47:22.32004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.936983Z","time spent":"382.996706ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:22.320114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.935844Z","time spent":"384.213243ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:22.32022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.558248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-01T18:47:22.32022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.490536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-01T18:47:22.320286Z","caller":"traceutil/trace.go:171","msg":"trace[805423550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4928; }","duration":"375.63585ms","start":"2026-08-01T18:47:21.944635Z","end":"2026-08-01T18:47:22.32027Z","steps":["trace[805423550] 'agreement among raft nodes before linearized reading' (duration: 375.455545ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:22.320301Z","caller":"traceutil/trace.go:171","msg":"trace[2105201598] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:4928; }","duration":"286.614679ms","start":"2026-08-01T18:47:22.033667Z","end":"2026-08-01T18:47:22.320282Z","steps":["trace[2105201598] 'agreement among raft nodes before linearized reading' (duration: 286.476336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.320333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.944629Z","time spent":"375.692322ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-01T18:47:22.32037Z","caller":"traceutil/trace.go:171","msg":"trace[1366404160] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"380.404267ms","start":"2026-08-01T18:47:21.939948Z","end":"2026-08-01T18:47:22.320352Z","steps":["trace[1366404160] 'process raft request' (duration: 379.597999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.320122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.342156ms","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-08-01T18:47:22.32045Z","caller":"traceutil/trace.go:171","msg":"trace[1892965164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4928; }","duration":"384.702054ms","start":"2026-08-01T18:47:21.935734Z","end":"2026-08-01T18:47:22.320436Z","steps":["trace[1892965164] 'agreement among raft nodes before linearized reading' (duration: 384.269124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.320476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.93994Z","time spent":"380.484169ms","remote":"127.0.0.1:53092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:22.320503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.935717Z","time spent":"384.772495ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-01T18:47:22.32053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.088285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-01T18:47:22.319803Z","caller":"traceutil/trace.go:171","msg":"trace[1286894927] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"382.137366ms","start":"2026-08-01T18:47:21.937634Z","end":"2026-08-01T18:47:22.319771Z","steps":["trace[1286894927] 'process raft request' (duration: 381.83978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.320253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.347124ms","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-08-01T18:47:22.320713Z","caller":"traceutil/trace.go:171","msg":"trace[76837596] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4928; }","duration":"383.818764ms","start":"2026-08-01T18:47:21.936874Z","end":"2026-08-01T18:47:22.320693Z","steps":["trace[76837596] 'agreement among raft nodes before linearized reading' (duration: 383.053317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.320864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.936867Z","time spent":"383.977767ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-01T18:47:22.320588Z","caller":"traceutil/trace.go:171","msg":"trace[1141221141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4928; }","duration":"378.153846ms","start":"2026-08-01T18:47:21.94242Z","end":"2026-08-01T18:47:22.320574Z","steps":["trace[1141221141] 'agreement among raft nodes before linearized reading' (duration: 378.014173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.321083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.942413Z","time spent":"378.650347ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-01T18:47:22.320617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.554722ms","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-08-01T18:47:22.321222Z","caller":"traceutil/trace.go:171","msg":"trace[2061884397] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4928; }","duration":"236.221626ms","start":"2026-08-01T18:47:22.084978Z","end":"2026-08-01T18:47:22.321199Z","steps":["trace[2061884397] 'agreement among raft nodes before linearized reading' (duration: 235.284875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.320642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:21.937628Z","time spent":"382.964705ms","remote":"127.0.0.1:52772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":991,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:47:22.663876Z","caller":"traceutil/trace.go:171","msg":"trace[2143491879] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"335.965107ms","start":"2026-08-01T18:47:22.327886Z","end":"2026-08-01T18:47:22.663851Z","steps":["trace[2143491879] 'process raft request' (duration: 335.595489ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:22.663902Z","caller":"traceutil/trace.go:171","msg":"trace[2009382142] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"335.715881ms","start":"2026-08-01T18:47:22.328157Z","end":"2026-08-01T18:47:22.663873Z","steps":["trace[2009382142] 'process raft request' (duration: 335.403884ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:22.663956Z","caller":"traceutil/trace.go:171","msg":"trace[773676640] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"331.167539ms","start":"2026-08-01T18:47:22.332758Z","end":"2026-08-01T18:47:22.663925Z","steps":["trace[773676640] 'process raft request' (duration: 330.834092ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:22.663996Z","caller":"traceutil/trace.go:171","msg":"trace[837047217] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"336.10076ms","start":"2026-08-01T18:47:22.327855Z","end":"2026-08-01T18:47:22.663956Z","steps":["trace[837047217] 'process raft request' (duration: 242.342554ms)","trace[837047217] 'compare' (duration: 92.865376ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:47:22.664018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:22.327882Z","time spent":"336.060799ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:22.664093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:22.332747Z","time spent":"331.271042ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10925,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:22.664247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:22.328151Z","time spent":"336.012678ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:47:22.664578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:22.327848Z","time spent":"336.197792ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:47:22.708691Z","caller":"traceutil/trace.go:171","msg":"trace[310284840] linearizableReadLoop","detail":"{readStateIndex:5125; appliedIndex:5120; }","duration":"228.584795ms","start":"2026-08-01T18:47:22.480076Z","end":"2026-08-01T18:47:22.708661Z","steps":["trace[310284840] 'read index received' (duration: 90.136125ms)","trace[310284840] 'applied index is now lower than readState.Index' (duration: 138.44705ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:47:22.708905Z","caller":"traceutil/trace.go:171","msg":"trace[202437965] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"185.458616ms","start":"2026-08-01T18:47:22.523413Z","end":"2026-08-01T18:47:22.708872Z","steps":["trace[202437965] 'process raft request' (duration: 185.20766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:22.708971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.868452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-01T18:47:22.709008Z","caller":"traceutil/trace.go:171","msg":"trace[54024213] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4933; }","duration":"228.952243ms","start":"2026-08-01T18:47:22.480045Z","end":"2026-08-01T18:47:22.708998Z","steps":["trace[54024213] 'agreement among raft nodes before linearized reading' (duration: 228.759578ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:23.550841Z","caller":"traceutil/trace.go:171","msg":"trace[2036564198] linearizableReadLoop","detail":"{readStateIndex:5128; appliedIndex:5127; }","duration":"260.136536ms","start":"2026-08-01T18:47:23.290677Z","end":"2026-08-01T18:47:23.550813Z","steps":["trace[2036564198] 'read index received' (duration: 259.896411ms)","trace[2036564198] 'applied index is now lower than readState.Index' (duration: 238.985µs)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:47:23.551037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.339231ms","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-08-01T18:47:23.551094Z","caller":"traceutil/trace.go:171","msg":"trace[340329482] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:4936; }","duration":"260.418052ms","start":"2026-08-01T18:47:23.29066Z","end":"2026-08-01T18:47:23.551078Z","steps":["trace[340329482] 'agreement among raft nodes before linearized reading' (duration: 260.262779ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:47:23.550919Z","caller":"traceutil/trace.go:171","msg":"trace[1245769615] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"554.796268ms","start":"2026-08-01T18:47:22.996092Z","end":"2026-08-01T18:47:23.550889Z","steps":["trace[1245769615] 'process raft request' (duration: 554.534752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:47:23.551409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:22.996068Z","time spent":"555.191916ms","remote":"127.0.0.1:52662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-01T18:47:57.265647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.424934ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-01T18:47:57.265806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:47:56.914574Z","time spent":"351.228481ms","remote":"127.0.0.1:52622","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-01T18:47:57.415407Z","caller":"traceutil/trace.go:171","msg":"trace[561767440] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"148.360704ms","start":"2026-08-01T18:47:57.26701Z","end":"2026-08-01T18:47:57.415371Z","steps":["trace[561767440] 'process raft request' (duration: 146.81888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:06.281228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.042206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-01T18:48:06.281333Z","caller":"traceutil/trace.go:171","msg":"trace[207352348] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5290; }","duration":"231.178388ms","start":"2026-08-01T18:48:06.050132Z","end":"2026-08-01T18:48:06.281311Z","steps":["trace[207352348] 'count revisions from in-memory index tree' (duration: 230.899612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:06.281435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.084628ms","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-08-01T18:48:06.281554Z","caller":"traceutil/trace.go:171","msg":"trace[987935831] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5290; }","duration":"359.219971ms","start":"2026-08-01T18:48:05.922316Z","end":"2026-08-01T18:48:06.281536Z","steps":["trace[987935831] 'range keys from in-memory index tree' (duration: 359.066288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:06.281837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.466351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-01T18:48:06.28187Z","caller":"traceutil/trace.go:171","msg":"trace[1794537365] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5290; }","duration":"356.525643ms","start":"2026-08-01T18:48:05.925333Z","end":"2026-08-01T18:48:06.281859Z","steps":["trace[1794537365] 'range keys from in-memory index tree' (duration: 356.342659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:06.281901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:05.925321Z","time spent":"356.572154ms","remote":"127.0.0.1:52684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1845,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-08-01T18:48:06.281931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.603226ms","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-08-01T18:48:06.281967Z","caller":"traceutil/trace.go:171","msg":"trace[716265193] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5290; }","duration":"264.675918ms","start":"2026-08-01T18:48:06.017279Z","end":"2026-08-01T18:48:06.281955Z","steps":["trace[716265193] 'count revisions from in-memory index tree' (duration: 264.518914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:06.282146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.789684ms","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-08-01T18:48:06.282174Z","caller":"traceutil/trace.go:171","msg":"trace[1006225433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5290; }","duration":"313.858606ms","start":"2026-08-01T18:48:05.968307Z","end":"2026-08-01T18:48:06.282165Z","steps":["trace[1006225433] 'range keys from in-memory index tree' (duration: 313.683701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:06.282198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:05.968286Z","time spent":"313.905656ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-01T18:48:06.282497Z","caller":"traceutil/trace.go:171","msg":"trace[1851214502] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"142.043953ms","start":"2026-08-01T18:48:06.140439Z","end":"2026-08-01T18:48:06.282483Z","steps":["trace[1851214502] 'process raft request' (duration: 102.628875ms)","trace[1851214502] 'compare' (duration: 37.820213ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:06.491118Z","caller":"traceutil/trace.go:171","msg":"trace[1616730492] linearizableReadLoop","detail":"{readStateIndex:5493; appliedIndex:5492; }","duration":"183.522543ms","start":"2026-08-01T18:48:06.30757Z","end":"2026-08-01T18:48:06.491093Z","steps":["trace[1616730492] 'read index received' (duration: 84.440974ms)","trace[1616730492] 'applied index is now lower than readState.Index' (duration: 99.080449ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:06.491208Z","caller":"traceutil/trace.go:171","msg":"trace[493833085] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"202.290946ms","start":"2026-08-01T18:48:06.28889Z","end":"2026-08-01T18:48:06.491181Z","steps":["trace[493833085] 'process raft request' (duration: 103.139485ms)","trace[493833085] 'compare' (duration: 98.924806ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:06.49133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.758828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-08-01T18:48:06.491377Z","caller":"traceutil/trace.go:171","msg":"trace[392994876] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5292; }","duration":"183.85776ms","start":"2026-08-01T18:48:06.307505Z","end":"2026-08-01T18:48:06.491363Z","steps":["trace[392994876] 'agreement among raft nodes before linearized reading' (duration: 183.685937ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:07.135452Z","caller":"traceutil/trace.go:171","msg":"trace[196156031] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"184.3993ms","start":"2026-08-01T18:48:06.95103Z","end":"2026-08-01T18:48:07.135429Z","steps":["trace[196156031] 'process raft request' (duration: 184.318178ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:07.135494Z","caller":"traceutil/trace.go:171","msg":"trace[190929573] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"184.86709ms","start":"2026-08-01T18:48:06.95059Z","end":"2026-08-01T18:48:07.135457Z","steps":["trace[190929573] 'process raft request' (duration: 184.589114ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:07.13561Z","caller":"traceutil/trace.go:171","msg":"trace[381398797] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"184.312859ms","start":"2026-08-01T18:48:06.951271Z","end":"2026-08-01T18:48:07.135583Z","steps":["trace[381398797] 'process raft request' (duration: 184.122164ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:07.150281Z","caller":"traceutil/trace.go:171","msg":"trace[1479232796] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"190.826069ms","start":"2026-08-01T18:48:06.959429Z","end":"2026-08-01T18:48:07.150255Z","steps":["trace[1479232796] 'process raft request' (duration: 190.676996ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:18.766397Z","caller":"traceutil/trace.go:171","msg":"trace[2013364506] linearizableReadLoop","detail":"{readStateIndex:5586; appliedIndex:5585; }","duration":"112.78497ms","start":"2026-08-01T18:48:18.653586Z","end":"2026-08-01T18:48:18.766371Z","steps":["trace[2013364506] 'read index received' (duration: 112.556115ms)","trace[2013364506] 'applied index is now lower than readState.Index' (duration: 227.715µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:18.766453Z","caller":"traceutil/trace.go:171","msg":"trace[1117281490] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"154.743132ms","start":"2026-08-01T18:48:18.611684Z","end":"2026-08-01T18:48:18.766427Z","steps":["trace[1117281490] 'process raft request' (duration: 154.455476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:18.766624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.993554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:48:18.766673Z","caller":"traceutil/trace.go:171","msg":"trace[826740274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5381; }","duration":"113.112827ms","start":"2026-08-01T18:48:18.653546Z","end":"2026-08-01T18:48:18.766659Z","steps":["trace[826740274] 'agreement among raft nodes before linearized reading' (duration: 112.974734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:19.112689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.615451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:48:19.11281Z","caller":"traceutil/trace.go:171","msg":"trace[1936288488] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"342.314822ms","start":"2026-08-01T18:48:18.770467Z","end":"2026-08-01T18:48:19.112782Z","steps":["trace[1936288488] 'process raft request' (duration: 200.535247ms)","trace[1936288488] 'compare' (duration: 141.380826ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:19.112849Z","caller":"traceutil/trace.go:171","msg":"trace[2044400222] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"131.021666ms","start":"2026-08-01T18:48:18.981811Z","end":"2026-08-01T18:48:19.112833Z","steps":["trace[2044400222] 'process raft request' (duration: 130.965185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:19.112931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:18.770459Z","time spent":"342.404334ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:48:19.113031Z","caller":"traceutil/trace.go:171","msg":"trace[1629180746] linearizableReadLoop","detail":"{readStateIndex:5587; appliedIndex:5586; }","duration":"342.402053ms","start":"2026-08-01T18:48:18.770619Z","end":"2026-08-01T18:48:19.113021Z","steps":["trace[1629180746] 'read index received' (duration: 200.396153ms)","trace[1629180746] 'applied index is now lower than readState.Index' (duration: 142.00381ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:19.113123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.542666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11918"} {"level":"info","ts":"2026-08-01T18:48:19.113145Z","caller":"traceutil/trace.go:171","msg":"trace[500827250] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5383; }","duration":"342.597557ms","start":"2026-08-01T18:48:18.770541Z","end":"2026-08-01T18:48:19.113138Z","steps":["trace[500827250] 'agreement among raft nodes before linearized reading' (duration: 342.505796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:19.113163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:18.770526Z","time spent":"342.632498ms","remote":"127.0.0.1:52782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":11942,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-01T18:48:19.356673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.824225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:48:19.356859Z","caller":"traceutil/trace.go:171","msg":"trace[212980508] linearizableReadLoop","detail":"{readStateIndex:5590; appliedIndex:5588; }","duration":"163.329713ms","start":"2026-08-01T18:48:19.193513Z","end":"2026-08-01T18:48:19.356843Z","steps":["trace[212980508] 'read index received' (duration: 61.12603ms)","trace[212980508] 'applied index is now lower than readState.Index' (duration: 102.202603ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:19.356952Z","caller":"traceutil/trace.go:171","msg":"trace[946171838] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"166.990181ms","start":"2026-08-01T18:48:19.189951Z","end":"2026-08-01T18:48:19.356942Z","steps":["trace[946171838] 'process raft request' (duration: 64.658215ms)","trace[946171838] 'compare' (duration: 101.672122ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:19.357246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.707251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-01T18:48:19.35744Z","caller":"traceutil/trace.go:171","msg":"trace[1104998284] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5385; }","duration":"163.934375ms","start":"2026-08-01T18:48:19.193489Z","end":"2026-08-01T18:48:19.357423Z","steps":["trace[1104998284] 'agreement among raft nodes before linearized reading' (duration: 163.590048ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:19.357307Z","caller":"traceutil/trace.go:171","msg":"trace[278335530] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"166.227015ms","start":"2026-08-01T18:48:19.191038Z","end":"2026-08-01T18:48:19.357265Z","steps":["trace[278335530] 'process raft request' (duration: 165.736984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:19.711618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.13255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:48:19.711883Z","caller":"traceutil/trace.go:171","msg":"trace[593457429] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"349.49941ms","start":"2026-08-01T18:48:19.362309Z","end":"2026-08-01T18:48:19.711809Z","steps":["trace[593457429] 'process raft request' (duration: 177.111625ms)","trace[593457429] 'compare' (duration: 171.886904ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:19.712302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:19.362283Z","time spent":"349.91842ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:48:20.27532Z","caller":"traceutil/trace.go:171","msg":"trace[1969176519] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"109.017676ms","start":"2026-08-01T18:48:20.166272Z","end":"2026-08-01T18:48:20.27529Z","steps":["trace[1969176519] 'process raft request' (duration: 108.906954ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:20.550339Z","caller":"traceutil/trace.go:171","msg":"trace[899511523] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"123.534965ms","start":"2026-08-01T18:48:20.426763Z","end":"2026-08-01T18:48:20.550298Z","steps":["trace[899511523] 'process raft request' (duration: 28.072887ms)","trace[899511523] 'compare' (duration: 95.177701ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:21.001724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.276069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:48:21.001841Z","caller":"traceutil/trace.go:171","msg":"trace[865079723] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"439.118998ms","start":"2026-08-01T18:48:20.562703Z","end":"2026-08-01T18:48:21.001822Z","steps":["trace[865079723] 'process raft request' (duration: 187.664886ms)","trace[865079723] 'compare' (duration: 251.048803ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:21.001915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:20.562675Z","time spent":"439.210809ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:48:21.051904Z","caller":"traceutil/trace.go:171","msg":"trace[254835541] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"416.749022ms","start":"2026-08-01T18:48:20.635122Z","end":"2026-08-01T18:48:21.051871Z","steps":["trace[254835541] 'process raft request' (duration: 416.64431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:21.052045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:20.635098Z","time spent":"416.875044ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:48:21.05232Z","caller":"traceutil/trace.go:171","msg":"trace[259774856] linearizableReadLoop","detail":"{readStateIndex:5596; appliedIndex:5595; }","duration":"280.473198ms","start":"2026-08-01T18:48:20.771833Z","end":"2026-08-01T18:48:21.052306Z","steps":["trace[259774856] 'read index received' (duration: 279.932166ms)","trace[259774856] 'applied index is now lower than readState.Index' (duration: 539.842µs)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:21.052443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.612331ms","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-08-01T18:48:21.052469Z","caller":"traceutil/trace.go:171","msg":"trace[1539725889] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5391; }","duration":"280.678382ms","start":"2026-08-01T18:48:20.771782Z","end":"2026-08-01T18:48:21.052461Z","steps":["trace[1539725889] 'agreement among raft nodes before linearized reading' (duration: 280.56926ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:21.197731Z","caller":"traceutil/trace.go:171","msg":"trace[759571452] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"210.522559ms","start":"2026-08-01T18:48:20.987173Z","end":"2026-08-01T18:48:21.197696Z","steps":["trace[759571452] 'process raft request' (duration: 191.561706ms)","trace[759571452] 'compare' (duration: 18.674347ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:21.237857Z","caller":"traceutil/trace.go:171","msg":"trace[1423085851] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"181.200566ms","start":"2026-08-01T18:48:21.056632Z","end":"2026-08-01T18:48:21.237832Z","steps":["trace[1423085851] 'process raft request' (duration: 180.93061ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:21.238038Z","caller":"traceutil/trace.go:171","msg":"trace[1030597066] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"120.260193ms","start":"2026-08-01T18:48:21.117763Z","end":"2026-08-01T18:48:21.238023Z","steps":["trace[1030597066] 'process raft request' (duration: 119.925516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:21.238329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.331234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:48:21.238375Z","caller":"traceutil/trace.go:171","msg":"trace[1860550019] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5394; }","duration":"152.431196ms","start":"2026-08-01T18:48:21.08593Z","end":"2026-08-01T18:48:21.238361Z","steps":["trace[1860550019] 'agreement among raft nodes before linearized reading' (duration: 152.333684ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:21.238919Z","caller":"traceutil/trace.go:171","msg":"trace[1266808862] linearizableReadLoop","detail":"{readStateIndex:5598; appliedIndex:5596; }","duration":"151.968827ms","start":"2026-08-01T18:48:21.085988Z","end":"2026-08-01T18:48:21.237957Z","steps":["trace[1266808862] 'read index received' (duration: 92.726299ms)","trace[1266808862] 'applied index is now lower than readState.Index' (duration: 59.241628ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:21.239172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.250485ms","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-08-01T18:48:21.239214Z","caller":"traceutil/trace.go:171","msg":"trace[951282454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5394; }","duration":"122.295126ms","start":"2026-08-01T18:48:21.116907Z","end":"2026-08-01T18:48:21.239202Z","steps":["trace[951282454] 'agreement among raft nodes before linearized reading' (duration: 122.139173ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:29.386845Z","caller":"traceutil/trace.go:171","msg":"trace[991331792] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"102.633875ms","start":"2026-08-01T18:48:29.284191Z","end":"2026-08-01T18:48:29.386825Z","steps":["trace[991331792] 'process raft request' (duration: 78.186259ms)","trace[991331792] 'compare' (duration: 23.892725ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:34.693791Z","caller":"traceutil/trace.go:171","msg":"trace[1924225488] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"113.578828ms","start":"2026-08-01T18:48:34.580183Z","end":"2026-08-01T18:48:34.693762Z","steps":["trace[1924225488] 'process raft request' (duration: 113.419735ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:34.739804Z","caller":"traceutil/trace.go:171","msg":"trace[1232430450] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"148.585044ms","start":"2026-08-01T18:48:34.591175Z","end":"2026-08-01T18:48:34.73976Z","steps":["trace[1232430450] 'process raft request' (duration: 148.313239ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:43.966773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2891} {"level":"info","ts":"2026-08-01T18:48:44.054258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2891,"took":"86.211511ms","hash":2276360358,"current-db-size-bytes":53243904,"current-db-size":"53 MB","current-db-size-in-use-bytes":29741056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-01T18:48:44.054374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2276360358,"revision":2891,"compact-revision":-1} {"level":"info","ts":"2026-08-01T18:49:57.066303Z","caller":"traceutil/trace.go:171","msg":"trace[1944003265] transaction","detail":"{read_only:false; response_revision:5988; number_of_response:1; }","duration":"117.994184ms","start":"2026-08-01T18:49:56.948263Z","end":"2026-08-01T18:49:57.066257Z","steps":["trace[1944003265] 'process raft request' (duration: 117.846321ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:54.358054Z","caller":"traceutil/trace.go:171","msg":"trace[923107732] transaction","detail":"{read_only:false; response_revision:6474; number_of_response:1; }","duration":"131.10804ms","start":"2026-08-01T18:50:54.22692Z","end":"2026-08-01T18:50:54.358028Z","steps":["trace[923107732] 'process raft request' (duration: 130.954047ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:54.674248Z","caller":"traceutil/trace.go:171","msg":"trace[248497251] linearizableReadLoop","detail":"{readStateIndex:6718; appliedIndex:6717; }","duration":"220.876834ms","start":"2026-08-01T18:50:54.453354Z","end":"2026-08-01T18:50:54.674231Z","steps":["trace[248497251] 'read index received' (duration: 220.71886ms)","trace[248497251] 'applied index is now lower than readState.Index' (duration: 157.334µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:54.674363Z","caller":"traceutil/trace.go:171","msg":"trace[797607851] transaction","detail":"{read_only:false; response_revision:6476; number_of_response:1; }","duration":"227.890132ms","start":"2026-08-01T18:50:54.446443Z","end":"2026-08-01T18:50:54.674333Z","steps":["trace[797607851] 'process raft request' (duration: 227.670747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:54.674444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.086817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-01T18:50:54.674474Z","caller":"traceutil/trace.go:171","msg":"trace[164522253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6476; }","duration":"221.137858ms","start":"2026-08-01T18:50:54.453329Z","end":"2026-08-01T18:50:54.674467Z","steps":["trace[164522253] 'agreement among raft nodes before linearized reading' (duration: 220.989645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:54.67462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.866351ms","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-08-01T18:50:54.674642Z","caller":"traceutil/trace.go:171","msg":"trace[430347719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6476; }","duration":"187.931183ms","start":"2026-08-01T18:50:54.486706Z","end":"2026-08-01T18:50:54.674637Z","steps":["trace[430347719] 'agreement among raft nodes before linearized reading' (duration: 187.854851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:54.674787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.480261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-01T18:50:54.674806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.769457ms","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-08-01T18:50:54.674835Z","caller":"traceutil/trace.go:171","msg":"trace[123274626] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:6476; }","duration":"157.541952ms","start":"2026-08-01T18:50:54.51728Z","end":"2026-08-01T18:50:54.674822Z","steps":["trace[123274626] 'agreement among raft nodes before linearized reading' (duration: 157.457141ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:54.674862Z","caller":"traceutil/trace.go:171","msg":"trace[1248333952] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6476; }","duration":"152.88849ms","start":"2026-08-01T18:50:54.521957Z","end":"2026-08-01T18:50:54.674846Z","steps":["trace[1248333952] 'agreement among raft nodes before linearized reading' (duration: 152.630395ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:54.933331Z","caller":"traceutil/trace.go:171","msg":"trace[131026449] transaction","detail":"{read_only:false; response_revision:6478; number_of_response:1; }","duration":"254.786634ms","start":"2026-08-01T18:50:54.678515Z","end":"2026-08-01T18:50:54.933302Z","steps":["trace[131026449] 'process raft request' (duration: 254.342886ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:54.933486Z","caller":"traceutil/trace.go:171","msg":"trace[2041252474] transaction","detail":"{read_only:false; response_revision:6479; number_of_response:1; }","duration":"254.905156ms","start":"2026-08-01T18:50:54.678556Z","end":"2026-08-01T18:50:54.933461Z","steps":["trace[2041252474] 'process raft request' (duration: 254.758393ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:58.537153Z","caller":"traceutil/trace.go:171","msg":"trace[2081532631] transaction","detail":"{read_only:false; response_revision:6497; number_of_response:1; }","duration":"112.692304ms","start":"2026-08-01T18:50:58.424437Z","end":"2026-08-01T18:50:58.537129Z","steps":["trace[2081532631] 'process raft request' (duration: 112.540371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:51:04.685202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.596633ms","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-08-01T18:51:04.685264Z","caller":"traceutil/trace.go:171","msg":"trace[236567719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6530; }","duration":"100.672844ms","start":"2026-08-01T18:51:04.584578Z","end":"2026-08-01T18:51:04.68525Z","steps":["trace[236567719] 'range keys from in-memory index tree' (duration: 100.45927ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:51:24.730647Z","caller":"traceutil/trace.go:171","msg":"trace[1636706652] transaction","detail":"{read_only:false; response_revision:6685; number_of_response:1; }","duration":"122.494559ms","start":"2026-08-01T18:51:24.608127Z","end":"2026-08-01T18:51:24.730621Z","steps":["trace[1636706652] 'process raft request' (duration: 122.029379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:51:25.017752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.976294ms","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-08-01T18:51:25.017843Z","caller":"traceutil/trace.go:171","msg":"trace[1105912963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6685; }","duration":"130.132981ms","start":"2026-08-01T18:51:24.887688Z","end":"2026-08-01T18:51:25.017821Z","steps":["trace[1105912963] 'range keys from in-memory index tree' (duration: 129.803586ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:51:25.181427Z","caller":"traceutil/trace.go:171","msg":"trace[1454309726] transaction","detail":"{read_only:false; response_revision:6687; number_of_response:1; }","duration":"157.259663ms","start":"2026-08-01T18:51:25.024124Z","end":"2026-08-01T18:51:25.181383Z","steps":["trace[1454309726] 'process raft request' (duration: 105.852002ms)","trace[1454309726] 'compare' (duration: 51.175061ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:51:36.932059Z","caller":"traceutil/trace.go:171","msg":"trace[279070477] transaction","detail":"{read_only:false; response_revision:6749; number_of_response:1; }","duration":"144.671123ms","start":"2026-08-01T18:51:36.787341Z","end":"2026-08-01T18:51:36.932012Z","steps":["trace[279070477] 'process raft request' (duration: 144.550278ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:51:37.429706Z","caller":"traceutil/trace.go:171","msg":"trace[1828844620] transaction","detail":"{read_only:false; response_revision:6755; number_of_response:1; }","duration":"104.305452ms","start":"2026-08-01T18:51:37.325378Z","end":"2026-08-01T18:51:37.429683Z","steps":["trace[1828844620] 'process raft request' (duration: 104.26641ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:51:37.429732Z","caller":"traceutil/trace.go:171","msg":"trace[1599230430] transaction","detail":"{read_only:false; response_revision:6754; number_of_response:1; }","duration":"104.347984ms","start":"2026-08-01T18:51:37.325356Z","end":"2026-08-01T18:51:37.429704Z","steps":["trace[1599230430] 'process raft request' (duration: 104.137225ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:53:43.971911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5569} {"level":"info","ts":"2026-08-01T18:53:44.048267Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5569,"took":"75.03507ms","hash":199632088,"current-db-size-bytes":53243904,"current-db-size":"53 MB","current-db-size-in-use-bytes":18423808,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-01T18:53:44.048428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":199632088,"revision":5569,"compact-revision":2891}