{"level":"warn","ts":"2026-05-19T02:53:49.396381Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T02:53:49.39646Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.171:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.171:2380","--initial-cluster=instance=https://199.19.213.171:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.171:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.171:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T02:53:49.39652Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T02:53:49.39653Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-05-19T02:53:49.396545Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T02:53:49.397143Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"]} {"level":"info","ts":"2026-05-19T02:53:49.397209Z","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.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.171:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T02:53:49.401169Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.761342ms"} {"level":"info","ts":"2026-05-19T02:53:49.411903Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"837e89131cc6bd81","cluster-id":"8af92e75ab8f3e69"} {"level":"info","ts":"2026-05-19T02:53:49.411988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T02:53:49.412015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 0"} {"level":"info","ts":"2026-05-19T02:53:49.412027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 837e89131cc6bd81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T02:53:49.412036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 1"} {"level":"info","ts":"2026-05-19T02:53:49.412068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"warn","ts":"2026-05-19T02:53:49.418807Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T02:53:49.420073Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T02:53:49.421313Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T02:53:49.422986Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"837e89131cc6bd81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T02:53:49.42328Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"837e89131cc6bd81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T02:53:49.423327Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T02:53:49.42335Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T02:53:49.423356Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T02:53:49.423765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"info","ts":"2026-05-19T02:53:49.423829Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","added-peer-id":"837e89131cc6bd81","added-peer-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-05-19T02:53:49.42773Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T02:53:49.427871Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-05-19T02:53:49.427881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-05-19T02:53:49.428086Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T02:53:49.428062Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"837e89131cc6bd81","initial-advertise-peer-urls":["https://199.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T02:53:50.312631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T02:53:50.312706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T02:53:50.312744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgPreVoteResp from 837e89131cc6bd81 at term 1"} {"level":"info","ts":"2026-05-19T02:53:50.312764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became candidate at term 2"} {"level":"info","ts":"2026-05-19T02:53:50.312783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgVoteResp from 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-05-19T02:53:50.312849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became leader at term 2"} {"level":"info","ts":"2026-05-19T02:53:50.312865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 837e89131cc6bd81 elected leader 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-05-19T02:53:50.314135Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T02:53:50.314546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T02:53:50.314544Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"837e89131cc6bd81","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.171:2379]}","request-path":"/0/members/837e89131cc6bd81/attributes","cluster-id":"8af92e75ab8f3e69","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T02:53:50.314814Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T02:53:50.315275Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T02:53:50.315327Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T02:53:50.316211Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T02:53:50.316344Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T02:53:50.316405Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T02:53:50.317611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T02:53:50.318757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.171:2379"} {"level":"info","ts":"2026-05-19T02:54:19.644161Z","caller":"traceutil/trace.go:171","msg":"trace[871655420] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"105.531952ms","start":"2026-05-19T02:54:19.538613Z","end":"2026-05-19T02:54:19.644145Z","steps":["trace[871655420] 'process raft request' (duration: 60.167334ms)","trace[871655420] 'compare' (duration: 44.974126ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:54:19.644205Z","caller":"traceutil/trace.go:171","msg":"trace[1968587667] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"105.303261ms","start":"2026-05-19T02:54:19.538868Z","end":"2026-05-19T02:54:19.644172Z","steps":["trace[1968587667] 'process raft request' (duration: 105.03445ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.498328Z","caller":"traceutil/trace.go:171","msg":"trace[1775394] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"179.658702ms","start":"2026-05-19T02:54:21.318649Z","end":"2026-05-19T02:54:21.498307Z","steps":["trace[1775394] 'process raft request' (duration: 179.548952ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.506784Z","caller":"traceutil/trace.go:171","msg":"trace[731492721] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"186.468464ms","start":"2026-05-19T02:54:21.320288Z","end":"2026-05-19T02:54:21.506756Z","steps":["trace[731492721] 'process raft request' (duration: 186.079861ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.506882Z","caller":"traceutil/trace.go:171","msg":"trace[348416317] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"176.346291ms","start":"2026-05-19T02:54:21.330518Z","end":"2026-05-19T02:54:21.506864Z","steps":["trace[348416317] 'process raft request' (duration: 175.938788ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.507353Z","caller":"traceutil/trace.go:171","msg":"trace[2110796036] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"171.237877ms","start":"2026-05-19T02:54:21.336103Z","end":"2026-05-19T02:54:21.507341Z","steps":["trace[2110796036] 'process raft request' (duration: 170.704254ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.507662Z","caller":"traceutil/trace.go:171","msg":"trace[1073344673] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"171.429649ms","start":"2026-05-19T02:54:21.336185Z","end":"2026-05-19T02:54:21.507615Z","steps":["trace[1073344673] 'process raft request' (duration: 171.034547ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.50769Z","caller":"traceutil/trace.go:171","msg":"trace[35267189] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"171.435429ms","start":"2026-05-19T02:54:21.336244Z","end":"2026-05-19T02:54:21.50768Z","steps":["trace[35267189] 'process raft request' (duration: 171.270468ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.507946Z","caller":"traceutil/trace.go:171","msg":"trace[1515139873] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"167.250944ms","start":"2026-05-19T02:54:21.340685Z","end":"2026-05-19T02:54:21.507936Z","steps":["trace[1515139873] 'process raft request' (duration: 166.878401ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.508313Z","caller":"traceutil/trace.go:171","msg":"trace[1336304435] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"156.665437ms","start":"2026-05-19T02:54:21.351624Z","end":"2026-05-19T02:54:21.50829Z","steps":["trace[1336304435] 'process raft request' (duration: 156.203103ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.509133Z","caller":"traceutil/trace.go:171","msg":"trace[1862137986] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"152.395638ms","start":"2026-05-19T02:54:21.356719Z","end":"2026-05-19T02:54:21.509115Z","steps":["trace[1862137986] 'process raft request' (duration: 151.194192ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.509264Z","caller":"traceutil/trace.go:171","msg":"trace[1641546500] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"135.620664ms","start":"2026-05-19T02:54:21.373634Z","end":"2026-05-19T02:54:21.509254Z","steps":["trace[1641546500] 'process raft request' (duration: 135.05169ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:21.509478Z","caller":"traceutil/trace.go:171","msg":"trace[945902981] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"121.659846ms","start":"2026-05-19T02:54:21.387794Z","end":"2026-05-19T02:54:21.509454Z","steps":["trace[945902981] 'process raft request' (duration: 120.949891ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:22.799069Z","caller":"traceutil/trace.go:171","msg":"trace[1071690940] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"333.358896ms","start":"2026-05-19T02:54:22.465677Z","end":"2026-05-19T02:54:22.799036Z","steps":["trace[1071690940] 'read index received' (duration: 332.053368ms)","trace[1071690940] 'applied index is now lower than readState.Index' (duration: 1.304428ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:54:22.799143Z","caller":"traceutil/trace.go:171","msg":"trace[1554166012] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"379.229374ms","start":"2026-05-19T02:54:22.419872Z","end":"2026-05-19T02:54:22.799102Z","steps":["trace[1554166012] 'process raft request' (duration: 377.846045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:22.799351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.612658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:54:22.799525Z","caller":"traceutil/trace.go:171","msg":"trace[233559142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"333.830189ms","start":"2026-05-19T02:54:22.465644Z","end":"2026-05-19T02:54:22.799474Z","steps":["trace[233559142] 'agreement among raft nodes before linearized reading' (duration: 333.497827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:22.799606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:22.465627Z","time spent":"333.94235ms","remote":"127.0.0.1:51988","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-05-19T02:54:22.800114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:22.419857Z","time spent":"379.369594ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:54:22.835869Z","caller":"traceutil/trace.go:171","msg":"trace[353242301] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"355.254384ms","start":"2026-05-19T02:54:22.48059Z","end":"2026-05-19T02:54:22.835844Z","steps":["trace[353242301] 'process raft request' (duration: 355.127953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:22.835976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:22.480573Z","time spent":"355.353345ms","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T02:54:28.042813Z","caller":"traceutil/trace.go:171","msg":"trace[370942694] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"104.124812ms","start":"2026-05-19T02:54:27.938659Z","end":"2026-05-19T02:54:28.042783Z","steps":["trace[370942694] 'read index received' (duration: 102.830454ms)","trace[370942694] 'applied index is now lower than readState.Index' (duration: 1.293288ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:54:28.042847Z","caller":"traceutil/trace.go:171","msg":"trace[61291008] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"198.300566ms","start":"2026-05-19T02:54:27.844525Z","end":"2026-05-19T02:54:28.042826Z","steps":["trace[61291008] 'process raft request' (duration: 197.255699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:28.043035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.346793ms","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-05-19T02:54:28.043127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.391924ms","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-05-19T02:54:28.043195Z","caller":"traceutil/trace.go:171","msg":"trace[739448969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"104.477384ms","start":"2026-05-19T02:54:27.938701Z","end":"2026-05-19T02:54:28.043179Z","steps":["trace[739448969] 'agreement among raft nodes before linearized reading' (duration: 104.272343ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:28.043293Z","caller":"traceutil/trace.go:171","msg":"trace[342405041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"104.546904ms","start":"2026-05-19T02:54:27.938622Z","end":"2026-05-19T02:54:28.043169Z","steps":["trace[342405041] 'agreement among raft nodes before linearized reading' (duration: 104.251183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:28.826578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.618252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T02:54:28.826846Z","caller":"traceutil/trace.go:171","msg":"trace[682194489] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"751.643082ms","start":"2026-05-19T02:54:28.075181Z","end":"2026-05-19T02:54:28.826824Z","steps":["trace[682194489] 'read index received' (duration: 349.549877ms)","trace[682194489] 'applied index is now lower than readState.Index' (duration: 402.091605ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:54:28.827017Z","caller":"traceutil/trace.go:171","msg":"trace[282513240] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"778.691115ms","start":"2026-05-19T02:54:28.048311Z","end":"2026-05-19T02:54:28.827002Z","steps":["trace[282513240] 'process raft request' (duration: 778.359973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:28.827112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:28.048304Z","time spent":"778.760725ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T02:54:28.827311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.427962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T02:54:28.827355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.152945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f2cft\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T02:54:28.827425Z","caller":"traceutil/trace.go:171","msg":"trace[1506255506] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f2cft; range_end:; response_count:1; response_revision:626; }","duration":"752.266205ms","start":"2026-05-19T02:54:28.075141Z","end":"2026-05-19T02:54:28.827407Z","steps":["trace[1506255506] 'agreement among raft nodes before linearized reading' (duration: 751.889603ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:28.827425Z","caller":"traceutil/trace.go:171","msg":"trace[400606971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"360.583773ms","start":"2026-05-19T02:54:28.466814Z","end":"2026-05-19T02:54:28.827397Z","steps":["trace[400606971] 'agreement among raft nodes before linearized reading' (duration: 360.405242ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:28.827412Z","caller":"traceutil/trace.go:171","msg":"trace[1868569182] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"723.101458ms","start":"2026-05-19T02:54:28.104267Z","end":"2026-05-19T02:54:28.827369Z","steps":["trace[1868569182] 'process raft request' (duration: 722.517925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:28.827465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:28.07512Z","time spent":"752.337515ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-f2cft\" "} {"level":"warn","ts":"2026-05-19T02:54:28.827486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:28.466789Z","time spent":"360.684453ms","remote":"127.0.0.1:51988","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-05-19T02:54:28.82755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:28.104239Z","time spent":"723.243478ms","remote":"127.0.0.1:52476","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-05-19T02:54:28.827902Z","caller":"traceutil/trace.go:171","msg":"trace[1133362644] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"779.4994ms","start":"2026-05-19T02:54:28.047512Z","end":"2026-05-19T02:54:28.827011Z","steps":["trace[1133362644] 'process raft request' (duration: 377.202654ms)","trace[1133362644] 'compare' (duration: 401.4023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:54:28.828071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:28.047491Z","time spent":"780.500716ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:54:28.968784Z","caller":"traceutil/trace.go:171","msg":"trace[2094457212] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"127.21089ms","start":"2026-05-19T02:54:28.841545Z","end":"2026-05-19T02:54:28.968756Z","steps":["trace[2094457212] 'process raft request' (duration: 96.629895ms)","trace[2094457212] 'compare' (duration: 30.467284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:54:30.337529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.156119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:54:30.33767Z","caller":"traceutil/trace.go:171","msg":"trace[2079519165] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:656; }","duration":"113.28276ms","start":"2026-05-19T02:54:30.224325Z","end":"2026-05-19T02:54:30.337608Z","steps":["trace[2079519165] 'range keys from in-memory index tree' (duration: 113.071768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:30.337865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.4019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:54:30.337938Z","caller":"traceutil/trace.go:171","msg":"trace[364603042] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:656; }","duration":"113.48062ms","start":"2026-05-19T02:54:30.224436Z","end":"2026-05-19T02:54:30.337917Z","steps":["trace[364603042] 'count revisions from in-memory index tree' (duration: 113.32196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:43.321661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.035539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T02:54:43.32184Z","caller":"traceutil/trace.go:171","msg":"trace[404018120] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"431.316104ms","start":"2026-05-19T02:54:42.890508Z","end":"2026-05-19T02:54:43.321824Z","steps":["trace[404018120] 'read index received' (duration: 188.039866ms)","trace[404018120] 'applied index is now lower than readState.Index' (duration: 243.275018ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:54:43.321841Z","caller":"traceutil/trace.go:171","msg":"trace[115581597] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"431.485556ms","start":"2026-05-19T02:54:42.890315Z","end":"2026-05-19T02:54:43.321801Z","steps":["trace[115581597] 'process raft request' (duration: 188.223289ms)","trace[115581597] 'compare' (duration: 242.818482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:54:43.322036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:42.890293Z","time spent":"431.62689ms","remote":"127.0.0.1:52254","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-05-19T02:54:43.322085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.120012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gfkct\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-19T02:54:43.322116Z","caller":"traceutil/trace.go:171","msg":"trace[1882121195] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gfkct; range_end:; response_count:1; response_revision:779; }","duration":"158.180243ms","start":"2026-05-19T02:54:43.163926Z","end":"2026-05-19T02:54:43.322107Z","steps":["trace[1882121195] 'agreement among raft nodes before linearized reading' (duration: 158.014168ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:54:43.322046Z","caller":"traceutil/trace.go:171","msg":"trace[1648359490] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"399.277739ms","start":"2026-05-19T02:54:42.922727Z","end":"2026-05-19T02:54:43.322005Z","steps":["trace[1648359490] 'process raft request' (duration: 399.054302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:43.322278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:42.922711Z","time spent":"399.504656ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T02:54:43.322253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.735168ms","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-05-19T02:54:43.322435Z","caller":"traceutil/trace.go:171","msg":"trace[823324879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"431.958713ms","start":"2026-05-19T02:54:42.890459Z","end":"2026-05-19T02:54:43.322417Z","steps":["trace[823324879] 'agreement among raft nodes before linearized reading' (duration: 431.552631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:54:43.322559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:54:42.890441Z","time spent":"432.074246ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T02:55:15.864631Z","caller":"traceutil/trace.go:171","msg":"trace[1186987520] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"398.041271ms","start":"2026-05-19T02:55:15.466569Z","end":"2026-05-19T02:55:15.86461Z","steps":["trace[1186987520] 'read index received' (duration: 397.848206ms)","trace[1186987520] 'applied index is now lower than readState.Index' (duration: 192.105µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:55:15.864694Z","caller":"traceutil/trace.go:171","msg":"trace[1507031769] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"401.665603ms","start":"2026-05-19T02:55:15.463006Z","end":"2026-05-19T02:55:15.864671Z","steps":["trace[1507031769] 'process raft request' (duration: 401.479859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:15.864783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.212674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:55:15.864813Z","caller":"traceutil/trace.go:171","msg":"trace[426696131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"398.282196ms","start":"2026-05-19T02:55:15.466522Z","end":"2026-05-19T02:55:15.864804Z","steps":["trace[426696131] 'agreement among raft nodes before linearized reading' (duration: 398.209034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:15.864815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:15.462973Z","time spent":"401.767184ms","remote":"127.0.0.1:52254","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-05-19T02:55:15.864839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:15.466509Z","time spent":"398.323996ms","remote":"127.0.0.1:51988","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-05-19T02:55:15.865018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.53983ms","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-05-19T02:55:15.865049Z","caller":"traceutil/trace.go:171","msg":"trace[810049688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1190; }","duration":"366.613522ms","start":"2026-05-19T02:55:15.498424Z","end":"2026-05-19T02:55:15.865038Z","steps":["trace[810049688] 'agreement among raft nodes before linearized reading' (duration: 366.51416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:15.865076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:15.498393Z","time spent":"366.675923ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T02:55:59.000927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.638391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T02:55:59.001079Z","caller":"traceutil/trace.go:171","msg":"trace[54297926] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"514.317483ms","start":"2026-05-19T02:55:58.486747Z","end":"2026-05-19T02:55:59.001065Z","steps":["trace[54297926] 'process raft request' (duration: 273.449948ms)","trace[54297926] 'compare' (duration: 240.475329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:55:59.001157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:58.48673Z","time spent":"514.401304ms","remote":"127.0.0.1:52254","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-05-19T02:55:59.001505Z","caller":"traceutil/trace.go:171","msg":"trace[2064381698] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"492.898513ms","start":"2026-05-19T02:55:58.508108Z","end":"2026-05-19T02:55:59.001006Z","steps":["trace[2064381698] 'read index received' (duration: 252.087859ms)","trace[2064381698] 'applied index is now lower than readState.Index' (duration: 240.809314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:55:59.001576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.443111ms","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-05-19T02:55:59.00166Z","caller":"traceutil/trace.go:171","msg":"trace[1297720091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1833; }","duration":"493.570412ms","start":"2026-05-19T02:55:58.508072Z","end":"2026-05-19T02:55:59.001643Z","steps":["trace[1297720091] 'agreement among raft nodes before linearized reading' (duration: 493.327479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.001702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:58.508061Z","time spent":"493.632503ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T02:55:59.093773Z","caller":"traceutil/trace.go:171","msg":"trace[1420668655] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"532.133767ms","start":"2026-05-19T02:55:58.561616Z","end":"2026-05-19T02:55:59.09375Z","steps":["trace[1420668655] 'process raft request' (duration: 531.963145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.093904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:58.561594Z","time spent":"532.240569ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T02:55:59.093912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.384668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T02:55:59.093931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.719248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T02:55:59.094007Z","caller":"traceutil/trace.go:171","msg":"trace[453589175] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"503.201141ms","start":"2026-05-19T02:55:58.590796Z","end":"2026-05-19T02:55:59.093997Z","steps":["trace[453589175] 'process raft request' (duration: 502.903926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:55:59.094025Z","caller":"traceutil/trace.go:171","msg":"trace[851693513] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1835; }","duration":"453.82125ms","start":"2026-05-19T02:55:58.64018Z","end":"2026-05-19T02:55:59.094001Z","steps":["trace[851693513] 'agreement among raft nodes before linearized reading' (duration: 453.588486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.094077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.07122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T02:55:59.094077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:58.59077Z","time spent":"503.270361ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:55:59.094135Z","caller":"traceutil/trace.go:171","msg":"trace[1472171054] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1835; }","duration":"223.211403ms","start":"2026-05-19T02:55:58.870911Z","end":"2026-05-19T02:55:59.094123Z","steps":["trace[1472171054] 'agreement among raft nodes before linearized reading' (duration: 223.105651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.094078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:58.640121Z","time spent":"453.939592ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":67,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-19T02:55:59.094146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.53618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:55:59.094176Z","caller":"traceutil/trace.go:171","msg":"trace[102562821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1835; }","duration":"548.58768ms","start":"2026-05-19T02:55:58.545578Z","end":"2026-05-19T02:55:59.094166Z","steps":["trace[102562821] 'agreement among raft nodes before linearized reading' (duration: 548.54116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.094212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:58.545567Z","time spent":"548.637472ms","remote":"127.0.0.1:52298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T02:55:59.093964Z","caller":"traceutil/trace.go:171","msg":"trace[1428024707] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1835; }","duration":"360.476709ms","start":"2026-05-19T02:55:58.733474Z","end":"2026-05-19T02:55:59.09395Z","steps":["trace[1428024707] 'agreement among raft nodes before linearized reading' (duration: 360.289166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.094369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.92303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8b933cbc-5671-475d-b7bc-9694ce3c68c5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T02:55:59.094393Z","caller":"traceutil/trace.go:171","msg":"trace[1089431382] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8b933cbc-5671-475d-b7bc-9694ce3c68c5; range_end:; response_count:1; response_revision:1835; }","duration":"565.998961ms","start":"2026-05-19T02:55:58.528386Z","end":"2026-05-19T02:55:59.094384Z","steps":["trace[1089431382] 'agreement among raft nodes before linearized reading' (duration: 565.910299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.094385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:58.733461Z","time spent":"360.911827ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-19T02:55:59.094419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:55:58.528359Z","time spent":"566.055142ms","remote":"127.0.0.1:52176","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-8b933cbc-5671-475d-b7bc-9694ce3c68c5\" "} {"level":"info","ts":"2026-05-19T02:55:59.139869Z","caller":"traceutil/trace.go:171","msg":"trace[214007969] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"134.827663ms","start":"2026-05-19T02:55:59.005001Z","end":"2026-05-19T02:55:59.139829Z","steps":["trace[214007969] 'process raft request' (duration: 112.127576ms)","trace[214007969] 'compare' (duration: 22.464284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:55:59.30535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.617245ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T02:55:59.305584Z","caller":"traceutil/trace.go:171","msg":"trace[1159460559] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1904; }","duration":"211.814792ms","start":"2026-05-19T02:55:59.093743Z","end":"2026-05-19T02:55:59.305558Z","steps":["trace[1159460559] 'read index received' (duration: 23.433579ms)","trace[1159460559] 'applied index is now lower than readState.Index' (duration: 188.378263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:55:59.305814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.171035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-19T02:55:59.305852Z","caller":"traceutil/trace.go:171","msg":"trace[1428307378] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1836; }","duration":"298.238085ms","start":"2026-05-19T02:55:59.007603Z","end":"2026-05-19T02:55:59.305841Z","steps":["trace[1428307378] 'agreement among raft nodes before linearized reading' (duration: 298.076302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.306084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.46087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8b933cbc-5671-475d-b7bc-9694ce3c68c5\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-05-19T02:55:59.306655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.98505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-05-19T02:55:59.306138Z","caller":"traceutil/trace.go:171","msg":"trace[2101502253] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8b933cbc-5671-475d-b7bc-9694ce3c68c5; range_end:; response_count:1; response_revision:1837; }","duration":"155.557621ms","start":"2026-05-19T02:55:59.150565Z","end":"2026-05-19T02:55:59.306122Z","steps":["trace[2101502253] 'agreement among raft nodes before linearized reading' (duration: 155.46769ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:55:59.306716Z","caller":"traceutil/trace.go:171","msg":"trace[1208275857] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1837; }","duration":"115.087432ms","start":"2026-05-19T02:55:59.191615Z","end":"2026-05-19T02:55:59.306703Z","steps":["trace[1208275857] 'agreement among raft nodes before linearized reading' (duration: 114.628305ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:55:59.306125Z","caller":"traceutil/trace.go:171","msg":"trace[450950831] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"206.545282ms","start":"2026-05-19T02:55:59.099545Z","end":"2026-05-19T02:55:59.30609Z","steps":["trace[450950831] 'process raft request' (duration: 205.973323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.692961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.548526ms","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-05-19T02:55:59.693033Z","caller":"traceutil/trace.go:171","msg":"trace[1087721134] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1839; }","duration":"171.665247ms","start":"2026-05-19T02:55:59.521351Z","end":"2026-05-19T02:55:59.693016Z","steps":["trace[1087721134] 'range keys from in-memory index tree' (duration: 171.381853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:55:59.693033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.331126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:55:59.693126Z","caller":"traceutil/trace.go:171","msg":"trace[297777953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1839; }","duration":"226.487917ms","start":"2026-05-19T02:55:59.466612Z","end":"2026-05-19T02:55:59.6931Z","steps":["trace[297777953] 'range keys from in-memory index tree' (duration: 226.217222ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:56:12.303605Z","caller":"traceutil/trace.go:171","msg":"trace[525070491] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"174.705501ms","start":"2026-05-19T02:56:12.128877Z","end":"2026-05-19T02:56:12.303583Z","steps":["trace[525070491] 'process raft request' (duration: 174.569659ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:56:12.304363Z","caller":"traceutil/trace.go:171","msg":"trace[1954836080] linearizableReadLoop","detail":"{readStateIndex:2022; appliedIndex:2020; }","duration":"115.068548ms","start":"2026-05-19T02:56:12.189272Z","end":"2026-05-19T02:56:12.304341Z","steps":["trace[1954836080] 'read index received' (duration: 114.205716ms)","trace[1954836080] 'applied index is now lower than readState.Index' (duration: 861.642µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:56:12.304437Z","caller":"traceutil/trace.go:171","msg":"trace[2117797173] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"173.733997ms","start":"2026-05-19T02:56:12.130677Z","end":"2026-05-19T02:56:12.304411Z","steps":["trace[2117797173] 'process raft request' (duration: 173.531634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:56:12.304582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.299762ms","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-05-19T02:56:12.304619Z","caller":"traceutil/trace.go:171","msg":"trace[2095774012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1950; }","duration":"115.390592ms","start":"2026-05-19T02:56:12.189218Z","end":"2026-05-19T02:56:12.304609Z","steps":["trace[2095774012] 'agreement among raft nodes before linearized reading' (duration: 115.208599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:56:34.731548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.649147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T02:56:34.73174Z","caller":"traceutil/trace.go:171","msg":"trace[374588064] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"253.08612ms","start":"2026-05-19T02:56:34.478631Z","end":"2026-05-19T02:56:34.731717Z","steps":["trace[374588064] 'process raft request' (duration: 253.017618ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:56:34.7318Z","caller":"traceutil/trace.go:171","msg":"trace[730985464] linearizableReadLoop","detail":"{readStateIndex:2164; appliedIndex:2163; }","duration":"257.474413ms","start":"2026-05-19T02:56:34.474301Z","end":"2026-05-19T02:56:34.731776Z","steps":["trace[730985464] 'read index received' (duration: 16.529932ms)","trace[730985464] 'applied index is now lower than readState.Index' (duration: 240.942581ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:56:34.7319Z","caller":"traceutil/trace.go:171","msg":"trace[225084403] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"266.114619ms","start":"2026-05-19T02:56:34.465751Z","end":"2026-05-19T02:56:34.731866Z","steps":["trace[225084403] 'process raft request' (duration: 25.080637ms)","trace[225084403] 'compare' (duration: 240.345923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:56:34.731956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.381974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T02:56:34.731981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.210364ms","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-05-19T02:56:34.73201Z","caller":"traceutil/trace.go:171","msg":"trace[858600622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2087; }","duration":"267.479616ms","start":"2026-05-19T02:56:34.464515Z","end":"2026-05-19T02:56:34.731994Z","steps":["trace[858600622] 'agreement among raft nodes before linearized reading' (duration: 267.277683ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:56:34.732027Z","caller":"traceutil/trace.go:171","msg":"trace[659376439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2087; }","duration":"321.294795ms","start":"2026-05-19T02:56:34.410717Z","end":"2026-05-19T02:56:34.732012Z","steps":["trace[659376439] 'agreement among raft nodes before linearized reading' (duration: 321.144933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:56:34.732075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:56:34.410695Z","time spent":"321.371736ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T02:56:34.731973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.857421ms","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-05-19T02:56:34.732237Z","caller":"traceutil/trace.go:171","msg":"trace[1804529286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2087; }","duration":"322.128174ms","start":"2026-05-19T02:56:34.410087Z","end":"2026-05-19T02:56:34.732216Z","steps":["trace[1804529286] 'agreement among raft nodes before linearized reading' (duration: 321.805841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:56:34.732275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:56:34.41008Z","time spent":"322.184156ms","remote":"127.0.0.1:52254","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-05-19T02:56:35.088576Z","caller":"traceutil/trace.go:171","msg":"trace[1724736452] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"351.062012ms","start":"2026-05-19T02:56:34.737308Z","end":"2026-05-19T02:56:35.08837Z","steps":["trace[1724736452] 'process raft request' (duration: 343.925286ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:56:35.088672Z","caller":"traceutil/trace.go:171","msg":"trace[549552967] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"290.532542ms","start":"2026-05-19T02:56:34.798118Z","end":"2026-05-19T02:56:35.088651Z","steps":["trace[549552967] 'read index received' (duration: 283.129722ms)","trace[549552967] 'applied index is now lower than readState.Index' (duration: 7.40162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:56:35.089141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.000958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:56:35.089194Z","caller":"traceutil/trace.go:171","msg":"trace[1953653792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2090; }","duration":"291.085889ms","start":"2026-05-19T02:56:34.798095Z","end":"2026-05-19T02:56:35.089181Z","steps":["trace[1953653792] 'agreement among raft nodes before linearized reading' (duration: 290.985938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:56:35.089296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:56:34.73728Z","time spent":"351.856243ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:57:40.203626Z","caller":"traceutil/trace.go:171","msg":"trace[879145183] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"196.107113ms","start":"2026-05-19T02:57:40.007499Z","end":"2026-05-19T02:57:40.203606Z","steps":["trace[879145183] 'process raft request' (duration: 195.981242ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:57:40.579466Z","caller":"traceutil/trace.go:171","msg":"trace[2138290871] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"562.336212ms","start":"2026-05-19T02:57:40.016428Z","end":"2026-05-19T02:57:40.578764Z","steps":["trace[2138290871] 'process raft request' (duration: 538.845287ms)","trace[2138290871] 'compare' (duration: 23.209142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:57:40.579663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:57:40.016422Z","time spent":"563.14237ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:57:40.580142Z","caller":"traceutil/trace.go:171","msg":"trace[1697871735] linearizableReadLoop","detail":"{readStateIndex:2631; appliedIndex:2629; }","duration":"115.350601ms","start":"2026-05-19T02:57:40.464746Z","end":"2026-05-19T02:57:40.580096Z","steps":["trace[1697871735] 'read index received' (duration: 90.363053ms)","trace[1697871735] 'applied index is now lower than readState.Index' (duration: 24.780647ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:57:40.581442Z","caller":"traceutil/trace.go:171","msg":"trace[1508534741] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"544.147158ms","start":"2026-05-19T02:57:40.037262Z","end":"2026-05-19T02:57:40.581409Z","steps":["trace[1508534741] 'process raft request' (duration: 541.448282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:57:40.581602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.840515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:57:40.581674Z","caller":"traceutil/trace.go:171","msg":"trace[2032101833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2538; }","duration":"116.979127ms","start":"2026-05-19T02:57:40.464676Z","end":"2026-05-19T02:57:40.581655Z","steps":["trace[2032101833] 'agreement among raft nodes before linearized reading' (duration: 116.091078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:57:40.582302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:57:40.037248Z","time spent":"544.311249ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:58:02.686569Z","caller":"traceutil/trace.go:171","msg":"trace[680776621] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"137.127981ms","start":"2026-05-19T02:58:02.549409Z","end":"2026-05-19T02:58:02.686537Z","steps":["trace[680776621] 'process raft request' (duration: 136.958339ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:03.332172Z","caller":"traceutil/trace.go:171","msg":"trace[1571332503] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"126.253889ms","start":"2026-05-19T02:58:03.205867Z","end":"2026-05-19T02:58:03.332121Z","steps":["trace[1571332503] 'process raft request' (duration: 63.203795ms)","trace[1571332503] 'compare' (duration: 62.883332ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:03.602312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.809377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:58:03.60243Z","caller":"traceutil/trace.go:171","msg":"trace[1615676514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2666; }","duration":"135.996949ms","start":"2026-05-19T02:58:03.466411Z","end":"2026-05-19T02:58:03.602408Z","steps":["trace[1615676514] 'range keys from in-memory index tree' (duration: 135.711486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:03.602693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.916892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-19T02:58:03.602762Z","caller":"traceutil/trace.go:171","msg":"trace[1296579439] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2666; }","duration":"142.034343ms","start":"2026-05-19T02:58:03.460712Z","end":"2026-05-19T02:58:03.602746Z","steps":["trace[1296579439] 'range keys from in-memory index tree' (duration: 141.71345ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:03.673899Z","caller":"traceutil/trace.go:171","msg":"trace[464738656] linearizableReadLoop","detail":"{readStateIndex:2765; appliedIndex:2764; }","duration":"146.779217ms","start":"2026-05-19T02:58:03.527095Z","end":"2026-05-19T02:58:03.673874Z","steps":["trace[464738656] 'read index received' (duration: 146.695336ms)","trace[464738656] 'applied index is now lower than readState.Index' (duration: 82.591µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:03.674024Z","caller":"traceutil/trace.go:171","msg":"trace[1416100269] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"155.439545ms","start":"2026-05-19T02:58:03.518547Z","end":"2026-05-19T02:58:03.673986Z","steps":["trace[1416100269] 'process raft request' (duration: 155.189403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:03.67422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.074939ms","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-05-19T02:58:03.674271Z","caller":"traceutil/trace.go:171","msg":"trace[1880111358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2667; }","duration":"147.189351ms","start":"2026-05-19T02:58:03.527065Z","end":"2026-05-19T02:58:03.674254Z","steps":["trace[1880111358] 'agreement among raft nodes before linearized reading' (duration: 146.923968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:04.006579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.685325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T02:58:04.006842Z","caller":"traceutil/trace.go:171","msg":"trace[235459943] linearizableReadLoop","detail":"{readStateIndex:2766; appliedIndex:2765; }","duration":"332.863671ms","start":"2026-05-19T02:58:03.673965Z","end":"2026-05-19T02:58:04.006829Z","steps":["trace[235459943] 'read index received' (duration: 61.697002ms)","trace[235459943] 'applied index is now lower than readState.Index' (duration: 271.165769ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:04.006822Z","caller":"traceutil/trace.go:171","msg":"trace[1212885708] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"399.221615ms","start":"2026-05-19T02:58:03.607536Z","end":"2026-05-19T02:58:04.006758Z","steps":["trace[1212885708] 'process raft request' (duration: 128.116136ms)","trace[1212885708] 'compare' (duration: 270.099999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:04.007051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:03.607514Z","time spent":"399.411626ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T02:58:04.007175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.348712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b0d81d64a219cd\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-19T02:58:04.007216Z","caller":"traceutil/trace.go:171","msg":"trace[1104377309] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b0d81d64a219cd; range_end:; response_count:1; response_revision:2669; }","duration":"436.407082ms","start":"2026-05-19T02:58:03.570798Z","end":"2026-05-19T02:58:04.007205Z","steps":["trace[1104377309] 'agreement among raft nodes before linearized reading' (duration: 436.11731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:04.00726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:03.57079Z","time spent":"436.462233ms","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b0d81d64a219cd\" "} {"level":"warn","ts":"2026-05-19T02:58:04.00732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.138368ms","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-05-19T02:58:04.007393Z","caller":"traceutil/trace.go:171","msg":"trace[1423658650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2669; }","duration":"249.25077ms","start":"2026-05-19T02:58:03.758124Z","end":"2026-05-19T02:58:04.007375Z","steps":["trace[1423658650] 'agreement among raft nodes before linearized reading' (duration: 249.073448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:04.007431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.179986ms","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-05-19T02:58:04.007488Z","caller":"traceutil/trace.go:171","msg":"trace[1953758142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2669; }","duration":"464.262967ms","start":"2026-05-19T02:58:03.543213Z","end":"2026-05-19T02:58:04.007476Z","steps":["trace[1953758142] 'agreement among raft nodes before linearized reading' (duration: 464.071605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:04.007527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:03.543202Z","time spent":"464.318307ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T02:58:04.006969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.883299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:58:04.007772Z","caller":"traceutil/trace.go:171","msg":"trace[1875239973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2669; }","duration":"403.690776ms","start":"2026-05-19T02:58:03.604002Z","end":"2026-05-19T02:58:04.007692Z","steps":["trace[1875239973] 'agreement among raft nodes before linearized reading' (duration: 402.849018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:04.007846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:03.603987Z","time spent":"403.844448ms","remote":"127.0.0.1:51986","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-05-19T02:58:04.009252Z","caller":"traceutil/trace.go:171","msg":"trace[1493642871] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"328.998566ms","start":"2026-05-19T02:58:03.677792Z","end":"2026-05-19T02:58:04.006791Z","steps":["trace[1493642871] 'process raft request' (duration: 328.907225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:04.009335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:03.677775Z","time spent":"331.519779ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T02:58:04.367247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.413618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T02:58:04.367419Z","caller":"traceutil/trace.go:171","msg":"trace[1553070620] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"348.76701ms","start":"2026-05-19T02:58:04.018632Z","end":"2026-05-19T02:58:04.367399Z","steps":["trace[1553070620] 'process raft request' (duration: 221.093599ms)","trace[1553070620] 'compare' (duration: 127.268807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:04.367484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:04.018622Z","time spent":"348.832801ms","remote":"127.0.0.1:52254","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-05-19T02:58:04.367737Z","caller":"traceutil/trace.go:171","msg":"trace[210542442] linearizableReadLoop","detail":"{readStateIndex:2770; appliedIndex:2767; }","duration":"344.729384ms","start":"2026-05-19T02:58:04.022974Z","end":"2026-05-19T02:58:04.367703Z","steps":["trace[210542442] 'read index received' (duration: 40.191516ms)","trace[210542442] 'applied index is now lower than readState.Index' (duration: 304.535408ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:04.367793Z","caller":"traceutil/trace.go:171","msg":"trace[604217382] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"348.933652ms","start":"2026-05-19T02:58:04.018832Z","end":"2026-05-19T02:58:04.367765Z","steps":["trace[604217382] 'process raft request' (duration: 348.518527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:04.367977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.978856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.171\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-19T02:58:04.368022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:04.018825Z","time spent":"349.033972ms","remote":"127.0.0.1:52254","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-05-19T02:58:04.368062Z","caller":"traceutil/trace.go:171","msg":"trace[1129495845] range","detail":"{range_begin:/registry/masterleases/199.19.213.171; range_end:; response_count:1; response_revision:2672; }","duration":"345.121057ms","start":"2026-05-19T02:58:04.022922Z","end":"2026-05-19T02:58:04.368044Z","steps":["trace[1129495845] 'agreement among raft nodes before linearized reading' (duration: 344.912785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:04.368103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:04.022909Z","time spent":"345.185767ms","remote":"127.0.0.1:52018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.171\" "} {"level":"info","ts":"2026-05-19T02:58:04.576418Z","caller":"traceutil/trace.go:171","msg":"trace[1624174555] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"175.403095ms","start":"2026-05-19T02:58:04.400979Z","end":"2026-05-19T02:58:04.576382Z","steps":["trace[1624174555] 'process raft request' (duration: 77.668226ms)","trace[1624174555] 'compare' (duration: 97.542747ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:04.873628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.938975ms","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-05-19T02:58:04.873726Z","caller":"traceutil/trace.go:171","msg":"trace[365877613] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2674; }","duration":"115.107116ms","start":"2026-05-19T02:58:04.7586Z","end":"2026-05-19T02:58:04.873707Z","steps":["trace[365877613] 'range keys from in-memory index tree' (duration: 114.757053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:06.479765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.108056ms","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-05-19T02:58:06.47984Z","caller":"traceutil/trace.go:171","msg":"trace[1158845024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2679; }","duration":"462.242816ms","start":"2026-05-19T02:58:06.017583Z","end":"2026-05-19T02:58:06.479826Z","steps":["trace[1158845024] 'range keys from in-memory index tree' (duration: 461.980544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:06.479872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:06.017559Z","time spent":"462.305377ms","remote":"127.0.0.1:52254","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":"warn","ts":"2026-05-19T02:58:06.48008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.18263ms","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":"warn","ts":"2026-05-19T02:58:06.480097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.20164ms","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-05-19T02:58:06.480165Z","caller":"traceutil/trace.go:171","msg":"trace[66704028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2679; }","duration":"107.325821ms","start":"2026-05-19T02:58:06.37282Z","end":"2026-05-19T02:58:06.480146Z","steps":["trace[66704028] 'range keys from in-memory index tree' (duration: 107.013009ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:06.480208Z","caller":"traceutil/trace.go:171","msg":"trace[2114003837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2679; }","duration":"107.333781ms","start":"2026-05-19T02:58:06.37282Z","end":"2026-05-19T02:58:06.480154Z","steps":["trace[2114003837] 'range keys from in-memory index tree' (duration: 106.964678ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:06.596471Z","caller":"traceutil/trace.go:171","msg":"trace[1258256479] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"111.48291ms","start":"2026-05-19T02:58:06.484966Z","end":"2026-05-19T02:58:06.596449Z","steps":["trace[1258256479] 'process raft request' (duration: 111.372729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:07.278398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950079165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T02:58:07.779249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950079165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T02:58:08.280315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950079165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T02:58:08.781052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950079165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T02:58:09.281731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950079165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T02:58:09.364451Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.76804938s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-19T02:58:09.467357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00040645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-19T02:58:09.467465Z","caller":"traceutil/trace.go:171","msg":"trace[571198738] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000585761s","start":"2026-05-19T02:58:07.466857Z","end":"2026-05-19T02:58:09.467443Z","steps":["trace[571198738] 'agreement among raft nodes before linearized reading' (duration: 2.00045s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.467932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:07.466826Z","time spent":"2.001086506s","remote":"127.0.0.1:51988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 02:58:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T02:58:09.526551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.638987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T02:58:09.527071Z","caller":"traceutil/trace.go:171","msg":"trace[456510759] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"3.041177657s","start":"2026-05-19T02:58:06.485872Z","end":"2026-05-19T02:58:09.52705Z","steps":["trace[456510759] 'process raft request' (duration: 3.041118686s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.528554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:06.48585Z","time spent":"3.042593789s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:58:09.528362Z","caller":"traceutil/trace.go:171","msg":"trace[913391838] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"3.043060064s","start":"2026-05-19T02:58:06.485267Z","end":"2026-05-19T02:58:09.528327Z","steps":["trace[913391838] 'process raft request' (duration: 2.87955277s)","trace[913391838] 'compare' (duration: 161.381205ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:09.528907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:06.48525Z","time spent":"3.043548918s","remote":"127.0.0.1:52254","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-05-19T02:58:09.528349Z","caller":"traceutil/trace.go:171","msg":"trace[1397432245] transaction","detail":"{read_only:false; response_revision:2682; number_of_response:1; }","duration":"3.042907703s","start":"2026-05-19T02:58:06.485405Z","end":"2026-05-19T02:58:09.528313Z","steps":["trace[1397432245] 'process raft request' (duration: 3.041445159s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.529098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:06.485387Z","time spent":"3.043633949s","remote":"127.0.0.1:52254","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-05-19T02:58:09.548626Z","caller":"traceutil/trace.go:171","msg":"trace[608848720] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"2.667992467s","start":"2026-05-19T02:58:06.880607Z","end":"2026-05-19T02:58:09.5486Z","steps":["trace[608848720] 'process raft request' (duration: 2.667705914s)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:09.548649Z","caller":"traceutil/trace.go:171","msg":"trace[299302987] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"2.429178845s","start":"2026-05-19T02:58:07.119453Z","end":"2026-05-19T02:58:09.548632Z","steps":["trace[299302987] 'process raft request' (duration: 2.428974923s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.548737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:06.880582Z","time spent":"2.668098597s","remote":"127.0.0.1:52254","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-05-19T02:58:09.548737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:07.119428Z","time spent":"2.429254325s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:58:09.548805Z","caller":"traceutil/trace.go:171","msg":"trace[1581626496] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"1.765302412s","start":"2026-05-19T02:58:07.783461Z","end":"2026-05-19T02:58:09.548763Z","steps":["trace[1581626496] 'process raft request' (duration: 1.76500821s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.549029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:07.783429Z","time spent":"1.765486803s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:58:09.58222Z","caller":"traceutil/trace.go:171","msg":"trace[383818569] linearizableReadLoop","detail":"{readStateIndex:2786; appliedIndex:2779; }","duration":"2.804680532s","start":"2026-05-19T02:58:06.777513Z","end":"2026-05-19T02:58:09.582194Z","steps":["trace[383818569] 'read index received' (duration: 2.587218173s)","trace[383818569] 'applied index is now lower than readState.Index' (duration: 217.460689ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:09.582537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.040473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T02:58:09.582556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626620776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T02:58:09.58257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.805019025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-05-19T02:58:09.582677Z","caller":"traceutil/trace.go:171","msg":"trace[1050434087] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2686; }","duration":"2.805190256s","start":"2026-05-19T02:58:06.777464Z","end":"2026-05-19T02:58:09.582655Z","steps":["trace[1050434087] 'agreement among raft nodes before linearized reading' (duration: 2.804857393s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.582713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.996476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T02:58:09.582801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.08929754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T02:58:09.582845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.648159975s","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-05-19T02:58:09.58289Z","caller":"traceutil/trace.go:171","msg":"trace[789229914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2686; }","duration":"2.089443921s","start":"2026-05-19T02:58:07.493426Z","end":"2026-05-19T02:58:09.58287Z","steps":["trace[789229914] 'agreement among raft nodes before linearized reading' (duration: 2.089201859s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.582979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:07.493405Z","time spent":"2.089564043s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T02:58:09.582533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.170589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:58:09.583168Z","caller":"traceutil/trace.go:171","msg":"trace[1837912784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2686; }","duration":"114.866435ms","start":"2026-05-19T02:58:09.468279Z","end":"2026-05-19T02:58:09.583146Z","steps":["trace[1837912784] 'agreement among raft nodes before linearized reading' (duration: 114.155218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.582748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:06.777442Z","time spent":"2.805295437s","remote":"127.0.0.1:52192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-19T02:58:09.58342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.726373ms","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-05-19T02:58:09.582896Z","caller":"traceutil/trace.go:171","msg":"trace[131627050] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2686; }","duration":"2.648249585s","start":"2026-05-19T02:58:06.93463Z","end":"2026-05-19T02:58:09.58288Z","steps":["trace[131627050] 'agreement among raft nodes before linearized reading' (duration: 2.647723511s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.583559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:06.934605Z","time spent":"2.648942912s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-19T02:58:09.58284Z","caller":"traceutil/trace.go:171","msg":"trace[2004927417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2686; }","duration":"735.184799ms","start":"2026-05-19T02:58:08.84763Z","end":"2026-05-19T02:58:09.582814Z","steps":["trace[2004927417] 'agreement among raft nodes before linearized reading' (duration: 734.957557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.583711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:08.847614Z","time spent":"736.078877ms","remote":"127.0.0.1:51988","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-05-19T02:58:09.582603Z","caller":"traceutil/trace.go:171","msg":"trace[668245283] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2686; }","duration":"698.167514ms","start":"2026-05-19T02:58:08.88442Z","end":"2026-05-19T02:58:09.582587Z","steps":["trace[668245283] 'agreement among raft nodes before linearized reading' (duration: 698.049673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.583925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:08.884391Z","time spent":"699.513876ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-05-19T02:58:09.582612Z","caller":"traceutil/trace.go:171","msg":"trace[1821356293] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2686; }","duration":"1.626721038s","start":"2026-05-19T02:58:07.955873Z","end":"2026-05-19T02:58:09.582594Z","steps":["trace[1821356293] 'agreement among raft nodes before linearized reading' (duration: 1.626633157s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:07.955852Z","time spent":"1.62813133s","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T02:58:09.582752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.293201263s","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-05-19T02:58:09.584178Z","caller":"traceutil/trace.go:171","msg":"trace[814038076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2686; }","duration":"2.294674177s","start":"2026-05-19T02:58:07.289493Z","end":"2026-05-19T02:58:09.584167Z","steps":["trace[814038076] 'agreement among raft nodes before linearized reading' (duration: 2.293148033s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.58421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:07.289464Z","time spent":"2.294737678s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T02:58:09.582752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014452179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b0d81d64a219cd\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-19T02:58:09.584335Z","caller":"traceutil/trace.go:171","msg":"trace[1559561605] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b0d81d64a219cd; range_end:; response_count:1; response_revision:2686; }","duration":"1.016079975s","start":"2026-05-19T02:58:08.568245Z","end":"2026-05-19T02:58:09.584325Z","steps":["trace[1559561605] 'agreement among raft nodes before linearized reading' (duration: 1.014392709s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.584365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:08.568223Z","time spent":"1.016133525s","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b0d81d64a219cd\" "} {"level":"info","ts":"2026-05-19T02:58:09.583513Z","caller":"traceutil/trace.go:171","msg":"trace[1584326405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2686; }","duration":"982.858864ms","start":"2026-05-19T02:58:08.600641Z","end":"2026-05-19T02:58:09.5835Z","steps":["trace[1584326405] 'agreement among raft nodes before linearized reading' (duration: 981.757764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.5857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:08.600598Z","time spent":"985.085434ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T02:58:09.692448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.821568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-05-19T02:58:09.692711Z","caller":"traceutil/trace.go:171","msg":"trace[521034399] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2687; }","duration":"102.152981ms","start":"2026-05-19T02:58:09.590532Z","end":"2026-05-19T02:58:09.692685Z","steps":["trace[521034399] 'agreement among raft nodes before linearized reading' (duration: 35.148037ms)","trace[521034399] 'range keys from in-memory index tree' (duration: 66.546541ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:09.981999Z","caller":"traceutil/trace.go:171","msg":"trace[440822579] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"391.102256ms","start":"2026-05-19T02:58:09.590867Z","end":"2026-05-19T02:58:09.981969Z","steps":["trace[440822579] 'process raft request' (duration: 390.908483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.982172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:09.590847Z","time spent":"391.233097ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:58:09.982301Z","caller":"traceutil/trace.go:171","msg":"trace[1738174404] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"391.363448ms","start":"2026-05-19T02:58:09.590887Z","end":"2026-05-19T02:58:09.982251Z","steps":["trace[1738174404] 'process raft request' (duration: 391.035276ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:09.982426Z","caller":"traceutil/trace.go:171","msg":"trace[1726115945] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"389.094377ms","start":"2026-05-19T02:58:09.593271Z","end":"2026-05-19T02:58:09.982365Z","steps":["trace[1726115945] 'process raft request' (duration: 388.913296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.982566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:09.590856Z","time spent":"391.58649ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T02:58:09.982581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:09.593246Z","time spent":"389.256429ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:58:09.982572Z","caller":"traceutil/trace.go:171","msg":"trace[1677270433] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"391.565839ms","start":"2026-05-19T02:58:09.5909Z","end":"2026-05-19T02:58:09.982466Z","steps":["trace[1677270433] 'process raft request' (duration: 391.189636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:09.982765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:09.590888Z","time spent":"391.747252ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:58:10.057183Z","caller":"traceutil/trace.go:171","msg":"trace[1654590364] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"426.812416ms","start":"2026-05-19T02:58:09.630349Z","end":"2026-05-19T02:58:10.057161Z","steps":["trace[1654590364] 'process raft request' (duration: 366.374863ms)","trace[1654590364] 'compare' (duration: 60.302022ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:10.057339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:09.630319Z","time spent":"426.949417ms","remote":"127.0.0.1:52070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T02:58:10.057184Z","caller":"traceutil/trace.go:171","msg":"trace[579560682] linearizableReadLoop","detail":"{readStateIndex:2792; appliedIndex:2787; }","duration":"360.622829ms","start":"2026-05-19T02:58:09.696536Z","end":"2026-05-19T02:58:10.057159Z","steps":["trace[579560682] 'read index received' (duration: 285.20869ms)","trace[579560682] 'applied index is now lower than readState.Index' (duration: 75.412849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:10.057495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.879341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-05-19T02:58:10.057568Z","caller":"traceutil/trace.go:171","msg":"trace[1856169482] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2692; }","duration":"361.042644ms","start":"2026-05-19T02:58:09.696501Z","end":"2026-05-19T02:58:10.057543Z","steps":["trace[1856169482] 'agreement among raft nodes before linearized reading' (duration: 360.73772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:10.057707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:09.696481Z","time spent":"361.209185ms","remote":"127.0.0.1:52192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1339,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-05-19T02:58:15.722021Z","caller":"traceutil/trace.go:171","msg":"trace[1583174551] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"105.35623ms","start":"2026-05-19T02:58:15.616635Z","end":"2026-05-19T02:58:15.721991Z","steps":["trace[1583174551] 'process raft request' (duration: 105.183009ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:15.838294Z","caller":"traceutil/trace.go:171","msg":"trace[329385725] linearizableReadLoop","detail":"{readStateIndex:2818; appliedIndex:2815; }","duration":"199.619161ms","start":"2026-05-19T02:58:15.638643Z","end":"2026-05-19T02:58:15.838262Z","steps":["trace[329385725] 'read index received' (duration: 83.163802ms)","trace[329385725] 'applied index is now lower than readState.Index' (duration: 116.453809ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:15.8388Z","caller":"traceutil/trace.go:171","msg":"trace[283805697] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"202.007675ms","start":"2026-05-19T02:58:15.636774Z","end":"2026-05-19T02:58:15.838782Z","steps":["trace[283805697] 'process raft request' (duration: 201.155087ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:15.838868Z","caller":"traceutil/trace.go:171","msg":"trace[1091617729] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"200.873534ms","start":"2026-05-19T02:58:15.637932Z","end":"2026-05-19T02:58:15.838806Z","steps":["trace[1091617729] 'process raft request' (duration: 200.203517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:15.83925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.419804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T02:58:15.83934Z","caller":"traceutil/trace.go:171","msg":"trace[546025877] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2717; }","duration":"187.548175ms","start":"2026-05-19T02:58:15.65177Z","end":"2026-05-19T02:58:15.839318Z","steps":["trace[546025877] 'agreement among raft nodes before linearized reading' (duration: 187.233652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:15.83933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.085161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-19T02:58:15.839428Z","caller":"traceutil/trace.go:171","msg":"trace[1252502221] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2717; }","duration":"196.232972ms","start":"2026-05-19T02:58:15.643169Z","end":"2026-05-19T02:58:15.839402Z","steps":["trace[1252502221] 'agreement among raft nodes before linearized reading' (duration: 195.753808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:15.839529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.865002ms","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-05-19T02:58:15.839608Z","caller":"traceutil/trace.go:171","msg":"trace[2141609616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2717; }","duration":"201.021264ms","start":"2026-05-19T02:58:15.638568Z","end":"2026-05-19T02:58:15.839589Z","steps":["trace[2141609616] 'agreement among raft nodes before linearized reading' (duration: 199.916164ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:17.98049Z","caller":"traceutil/trace.go:171","msg":"trace[1469774398] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"124.61721ms","start":"2026-05-19T02:58:17.855837Z","end":"2026-05-19T02:58:17.980454Z","steps":["trace[1469774398] 'process raft request' (duration: 32.663242ms)","trace[1469774398] 'compare' (duration: 91.799586ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:17.982344Z","caller":"traceutil/trace.go:171","msg":"trace[929903138] linearizableReadLoop","detail":"{readStateIndex:2828; appliedIndex:2826; }","duration":"123.257907ms","start":"2026-05-19T02:58:17.859047Z","end":"2026-05-19T02:58:17.982305Z","steps":["trace[929903138] 'read index received' (duration: 29.518952ms)","trace[929903138] 'applied index is now lower than readState.Index' (duration: 93.737495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:17.98255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.476348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-19T02:58:17.982622Z","caller":"traceutil/trace.go:171","msg":"trace[32896233] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2727; }","duration":"123.59581ms","start":"2026-05-19T02:58:17.859015Z","end":"2026-05-19T02:58:17.982611Z","steps":["trace[32896233] 'agreement among raft nodes before linearized reading' (duration: 123.389598ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:17.982378Z","caller":"traceutil/trace.go:171","msg":"trace[1044431211] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"126.439086ms","start":"2026-05-19T02:58:17.855914Z","end":"2026-05-19T02:58:17.982353Z","steps":["trace[1044431211] 'process raft request' (duration: 126.234913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:17.982882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.097637ms","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-05-19T02:58:17.983028Z","caller":"traceutil/trace.go:171","msg":"trace[500410963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2727; }","duration":"122.353878ms","start":"2026-05-19T02:58:17.860661Z","end":"2026-05-19T02:58:17.983015Z","steps":["trace[500410963] 'agreement among raft nodes before linearized reading' (duration: 121.932575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:19.885283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.807304ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T02:58:20.025568Z","caller":"traceutil/trace.go:171","msg":"trace[1572192025] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"133.755358ms","start":"2026-05-19T02:58:19.89179Z","end":"2026-05-19T02:58:20.025545Z","steps":["trace[1572192025] 'process raft request' (duration: 133.598786ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:20.132624Z","caller":"traceutil/trace.go:171","msg":"trace[1100457278] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"144.811284ms","start":"2026-05-19T02:58:19.987787Z","end":"2026-05-19T02:58:20.132598Z","steps":["trace[1100457278] 'process raft request' (duration: 73.148239ms)","trace[1100457278] 'compare' (duration: 71.356903ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:58:20.132803Z","caller":"traceutil/trace.go:171","msg":"trace[1929078288] linearizableReadLoop","detail":"{readStateIndex:2842; appliedIndex:2839; }","duration":"125.528692ms","start":"2026-05-19T02:58:20.007246Z","end":"2026-05-19T02:58:20.132775Z","steps":["trace[1929078288] 'read index received' (duration: 18.16065ms)","trace[1929078288] 'applied index is now lower than readState.Index' (duration: 107.366782ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:20.13384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.647812ms","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-05-19T02:58:20.133885Z","caller":"traceutil/trace.go:171","msg":"trace[133952597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2740; }","duration":"108.714824ms","start":"2026-05-19T02:58:20.02516Z","end":"2026-05-19T02:58:20.133875Z","steps":["trace[133952597] 'agreement among raft nodes before linearized reading' (duration: 108.610723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:20.133017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.757585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-19T02:58:20.134097Z","caller":"traceutil/trace.go:171","msg":"trace[1637081260] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2740; }","duration":"126.894654ms","start":"2026-05-19T02:58:20.007185Z","end":"2026-05-19T02:58:20.134079Z","steps":["trace[1637081260] 'agreement among raft nodes before linearized reading' (duration: 125.666454ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:20.133126Z","caller":"traceutil/trace.go:171","msg":"trace[1205116230] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"145.054706ms","start":"2026-05-19T02:58:19.988053Z","end":"2026-05-19T02:58:20.133107Z","steps":["trace[1205116230] 'process raft request' (duration: 144.487211ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:20.460643Z","caller":"traceutil/trace.go:171","msg":"trace[562367802] linearizableReadLoop","detail":"{readStateIndex:2848; appliedIndex:2843; }","duration":"174.973691ms","start":"2026-05-19T02:58:20.285642Z","end":"2026-05-19T02:58:20.460615Z","steps":["trace[562367802] 'read index received' (duration: 86.145963ms)","trace[562367802] 'applied index is now lower than readState.Index' (duration: 88.826638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:20.46083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.157833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T02:58:20.460882Z","caller":"traceutil/trace.go:171","msg":"trace[704979257] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2746; }","duration":"175.260513ms","start":"2026-05-19T02:58:20.285601Z","end":"2026-05-19T02:58:20.460862Z","steps":["trace[704979257] 'agreement among raft nodes before linearized reading' (duration: 175.141052ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:20.460891Z","caller":"traceutil/trace.go:171","msg":"trace[1112068564] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"321.959254ms","start":"2026-05-19T02:58:20.138903Z","end":"2026-05-19T02:58:20.460863Z","steps":["trace[1112068564] 'process raft request' (duration: 321.557891ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:58:20.461118Z","caller":"traceutil/trace.go:171","msg":"trace[750238966] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"322.002714ms","start":"2026-05-19T02:58:20.139104Z","end":"2026-05-19T02:58:20.461107Z","steps":["trace[750238966] 'process raft request' (duration: 321.410909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:20.461213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:20.139085Z","time spent":"322.067905ms","remote":"127.0.0.1:52254","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-05-19T02:58:20.461309Z","caller":"traceutil/trace.go:171","msg":"trace[123438579] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"322.201756ms","start":"2026-05-19T02:58:20.139099Z","end":"2026-05-19T02:58:20.461301Z","steps":["trace[123438579] 'process raft request' (duration: 321.44949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:20.46136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:20.139063Z","time spent":"322.271896ms","remote":"127.0.0.1:52254","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-05-19T02:58:20.46144Z","caller":"traceutil/trace.go:171","msg":"trace[937253341] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"318.801135ms","start":"2026-05-19T02:58:20.142631Z","end":"2026-05-19T02:58:20.461433Z","steps":["trace[937253341] 'process raft request' (duration: 317.949148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T02:58:20.461492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:20.142615Z","time spent":"318.850925ms","remote":"127.0.0.1:52254","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-05-19T02:58:20.461603Z","caller":"traceutil/trace.go:171","msg":"trace[1670913786] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"323.487067ms","start":"2026-05-19T02:58:20.138099Z","end":"2026-05-19T02:58:20.461586Z","steps":["trace[1670913786] 'process raft request' (duration: 233.765922ms)","trace[1670913786] 'compare' (duration: 88.484264ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T02:58:20.46166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:20.138089Z","time spent":"323.545237ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T02:58:20.461765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T02:58:20.13889Z","time spent":"322.066085ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T02:59:36.621501Z","caller":"traceutil/trace.go:171","msg":"trace[1131417834] transaction","detail":"{read_only:false; response_revision:3140; number_of_response:1; }","duration":"103.006742ms","start":"2026-05-19T02:59:36.518349Z","end":"2026-05-19T02:59:36.621356Z","steps":["trace[1131417834] 'process raft request' (duration: 89.039307ms)","trace[1131417834] 'compare' (duration: 13.696759ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T02:59:37.078075Z","caller":"traceutil/trace.go:171","msg":"trace[1677855021] transaction","detail":"{read_only:false; response_revision:3145; number_of_response:1; }","duration":"131.032339ms","start":"2026-05-19T02:59:36.947018Z","end":"2026-05-19T02:59:37.078051Z","steps":["trace[1677855021] 'process raft request' (duration: 130.810574ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T02:59:37.078448Z","caller":"traceutil/trace.go:171","msg":"trace[926151551] transaction","detail":"{read_only:false; response_revision:3146; number_of_response:1; }","duration":"131.327567ms","start":"2026-05-19T02:59:36.947103Z","end":"2026-05-19T02:59:37.078431Z","steps":["trace[926151551] 'process raft request' (duration: 130.903276ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:05.067261Z","caller":"traceutil/trace.go:171","msg":"trace[461097947] transaction","detail":"{read_only:false; response_revision:3284; number_of_response:1; }","duration":"106.477183ms","start":"2026-05-19T03:00:04.960736Z","end":"2026-05-19T03:00:05.067213Z","steps":["trace[461097947] 'process raft request' (duration: 87.778504ms)","trace[461097947] 'compare' (duration: 18.482004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:00:05.448019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.822654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T03:00:05.44811Z","caller":"traceutil/trace.go:171","msg":"trace[504802040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3288; }","duration":"110.923637ms","start":"2026-05-19T03:00:05.337166Z","end":"2026-05-19T03:00:05.44809Z","steps":["trace[504802040] 'range keys from in-memory index tree' (duration: 110.66165ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:14.849549Z","caller":"traceutil/trace.go:171","msg":"trace[1166870769] transaction","detail":"{read_only:false; response_revision:3329; number_of_response:1; }","duration":"157.432761ms","start":"2026-05-19T03:00:14.692078Z","end":"2026-05-19T03:00:14.849511Z","steps":["trace[1166870769] 'process raft request' (duration: 157.163525ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:15.467426Z","caller":"traceutil/trace.go:171","msg":"trace[522904520] linearizableReadLoop","detail":"{readStateIndex:3460; appliedIndex:3459; }","duration":"121.610135ms","start":"2026-05-19T03:00:15.345789Z","end":"2026-05-19T03:00:15.467399Z","steps":["trace[522904520] 'read index received' (duration: 121.40841ms)","trace[522904520] 'applied index is now lower than readState.Index' (duration: 200.784µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:00:15.467596Z","caller":"traceutil/trace.go:171","msg":"trace[43924252] transaction","detail":"{read_only:false; response_revision:3334; number_of_response:1; }","duration":"128.01896ms","start":"2026-05-19T03:00:15.339554Z","end":"2026-05-19T03:00:15.467573Z","steps":["trace[43924252] 'process raft request' (duration: 127.696292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:00:15.467706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.895231ms","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-05-19T03:00:15.467742Z","caller":"traceutil/trace.go:171","msg":"trace[2135323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3334; }","duration":"121.971583ms","start":"2026-05-19T03:00:15.345758Z","end":"2026-05-19T03:00:15.467729Z","steps":["trace[2135323] 'agreement among raft nodes before linearized reading' (duration: 121.799559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:00:15.467846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.272596ms","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-05-19T03:00:15.467927Z","caller":"traceutil/trace.go:171","msg":"trace[1914768394] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3334; }","duration":"118.387768ms","start":"2026-05-19T03:00:15.349517Z","end":"2026-05-19T03:00:15.467904Z","steps":["trace[1914768394] 'agreement among raft nodes before linearized reading' (duration: 118.177574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:00:15.467933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.580633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T03:00:15.468015Z","caller":"traceutil/trace.go:171","msg":"trace[1985415916] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3334; }","duration":"110.693005ms","start":"2026-05-19T03:00:15.357302Z","end":"2026-05-19T03:00:15.467995Z","steps":["trace[1985415916] 'agreement among raft nodes before linearized reading' (duration: 110.504531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:00:15.596454Z","caller":"traceutil/trace.go:171","msg":"trace[416231197] transaction","detail":"{read_only:false; response_revision:3336; number_of_response:1; }","duration":"123.557607ms","start":"2026-05-19T03:00:15.472873Z","end":"2026-05-19T03:00:15.596431Z","steps":["trace[416231197] 'process raft request' (duration: 88.577912ms)","trace[416231197] 'compare' (duration: 34.766031ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:00:15.596965Z","caller":"traceutil/trace.go:171","msg":"trace[1474185652] transaction","detail":"{read_only:false; response_revision:3337; number_of_response:1; }","duration":"124.041867ms","start":"2026-05-19T03:00:15.47291Z","end":"2026-05-19T03:00:15.596952Z","steps":["trace[1474185652] 'process raft request' (duration: 123.473725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:01:23.857645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950082349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:01:24.088366Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.584425329s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T03:01:24.088827Z","caller":"traceutil/trace.go:171","msg":"trace[1067252124] transaction","detail":"{read_only:false; response_revision:3783; number_of_response:1; }","duration":"1.584972597s","start":"2026-05-19T03:01:22.503832Z","end":"2026-05-19T03:01:24.088804Z","steps":["trace[1067252124] 'process raft request' (duration: 1.584843015s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:01:24.088963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:01:22.503806Z","time spent":"1.585096349s","remote":"127.0.0.1:37728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2372,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T03:01:24.094012Z","caller":"traceutil/trace.go:171","msg":"trace[1663084743] linearizableReadLoop","detail":"{readStateIndex:3929; appliedIndex:3925; }","duration":"737.173989ms","start":"2026-05-19T03:01:23.356816Z","end":"2026-05-19T03:01:24.09399Z","steps":["trace[1663084743] 'read index received' (duration: 731.890231ms)","trace[1663084743] 'applied index is now lower than readState.Index' (duration: 5.282158ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:01:24.094051Z","caller":"traceutil/trace.go:171","msg":"trace[707843082] transaction","detail":"{read_only:false; response_revision:3785; number_of_response:1; }","duration":"842.642004ms","start":"2026-05-19T03:01:23.251378Z","end":"2026-05-19T03:01:24.09402Z","steps":["trace[707843082] 'process raft request' (duration: 842.537543ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:01:24.09412Z","caller":"traceutil/trace.go:171","msg":"trace[1166670587] transaction","detail":"{read_only:false; response_revision:3786; number_of_response:1; }","duration":"597.036405ms","start":"2026-05-19T03:01:23.497072Z","end":"2026-05-19T03:01:24.094109Z","steps":["trace[1166670587] 'process raft request' (duration: 596.878982ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:01:24.094093Z","caller":"traceutil/trace.go:171","msg":"trace[1372264992] transaction","detail":"{read_only:false; response_revision:3784; number_of_response:1; }","duration":"942.403715ms","start":"2026-05-19T03:01:23.151659Z","end":"2026-05-19T03:01:24.094063Z","steps":["trace[1372264992] 'process raft request' (duration: 942.10583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:01:24.094233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:01:23.151626Z","time spent":"942.539997ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:01:24.09426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.679283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T03:01:24.094296Z","caller":"traceutil/trace.go:171","msg":"trace[642628454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3786; }","duration":"550.760735ms","start":"2026-05-19T03:01:23.543526Z","end":"2026-05-19T03:01:24.094286Z","steps":["trace[642628454] 'agreement among raft nodes before linearized reading' (duration: 550.640163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:01:24.094318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:01:23.543502Z","time spent":"550.811635ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T03:01:24.094413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.735731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T03:01:24.094392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.999016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T03:01:24.094454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.641996ms","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-05-19T03:01:24.094479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.37815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T03:01:24.094468Z","caller":"traceutil/trace.go:171","msg":"trace[1398127423] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:3786; }","duration":"203.112867ms","start":"2026-05-19T03:01:23.891338Z","end":"2026-05-19T03:01:24.094451Z","steps":["trace[1398127423] 'agreement among raft nodes before linearized reading' (duration: 203.005605ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:01:24.094495Z","caller":"traceutil/trace.go:171","msg":"trace[1956321708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3786; }","duration":"284.430991ms","start":"2026-05-19T03:01:23.810059Z","end":"2026-05-19T03:01:24.09449Z","steps":["trace[1956321708] 'agreement among raft nodes before linearized reading' (duration: 284.3863ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:01:24.094505Z","caller":"traceutil/trace.go:171","msg":"trace[37424980] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3786; }","duration":"737.730488ms","start":"2026-05-19T03:01:23.356758Z","end":"2026-05-19T03:01:24.094489Z","steps":["trace[37424980] 'agreement among raft nodes before linearized reading' (duration: 737.598686ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:01:24.094433Z","caller":"traceutil/trace.go:171","msg":"trace[1420743200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3786; }","duration":"211.809132ms","start":"2026-05-19T03:01:23.882617Z","end":"2026-05-19T03:01:24.094426Z","steps":["trace[1420743200] 'agreement among raft nodes before linearized reading' (duration: 211.752121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:01:24.094548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:01:23.35674Z","time spent":"737.797219ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T03:01:24.094541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.549935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T03:01:24.094597Z","caller":"traceutil/trace.go:171","msg":"trace[74990680] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3786; }","duration":"137.645117ms","start":"2026-05-19T03:01:23.956938Z","end":"2026-05-19T03:01:24.094583Z","steps":["trace[74990680] 'agreement among raft nodes before linearized reading' (duration: 137.524685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:01:24.095117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:01:23.497048Z","time spent":"597.109857ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:01:24.095502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:01:23.25135Z","time spent":"842.768126ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:02:16.488668Z","caller":"traceutil/trace.go:171","msg":"trace[1441383474] transaction","detail":"{read_only:false; response_revision:4161; number_of_response:1; }","duration":"120.681364ms","start":"2026-05-19T03:02:16.367959Z","end":"2026-05-19T03:02:16.488641Z","steps":["trace[1441383474] 'process raft request' (duration: 119.988285ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:16.488829Z","caller":"traceutil/trace.go:171","msg":"trace[1889847547] linearizableReadLoop","detail":"{readStateIndex:4330; appliedIndex:4327; }","duration":"119.73439ms","start":"2026-05-19T03:02:16.36906Z","end":"2026-05-19T03:02:16.488795Z","steps":["trace[1889847547] 'read index received' (duration: 3.188456ms)","trace[1889847547] 'applied index is now lower than readState.Index' (duration: 116.543734ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:02:16.488877Z","caller":"traceutil/trace.go:171","msg":"trace[1471427000] transaction","detail":"{read_only:false; response_revision:4162; number_of_response:1; }","duration":"120.826757ms","start":"2026-05-19T03:02:16.368015Z","end":"2026-05-19T03:02:16.488841Z","steps":["trace[1471427000] 'process raft request' (duration: 120.584914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:16.489878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.788037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T03:02:16.490052Z","caller":"traceutil/trace.go:171","msg":"trace[1284254685] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4162; }","duration":"120.974249ms","start":"2026-05-19T03:02:16.368984Z","end":"2026-05-19T03:02:16.489958Z","steps":["trace[1284254685] 'agreement among raft nodes before linearized reading' (duration: 120.001474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:16.490301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.878075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T03:02:16.490435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.737311ms","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-05-19T03:02:16.490629Z","caller":"traceutil/trace.go:171","msg":"trace[939036994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4162; }","duration":"108.838202ms","start":"2026-05-19T03:02:16.381649Z","end":"2026-05-19T03:02:16.490488Z","steps":["trace[939036994] 'agreement among raft nodes before linearized reading' (duration: 107.480533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:16.490798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.249881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T03:02:16.490901Z","caller":"traceutil/trace.go:171","msg":"trace[769582180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4162; }","duration":"116.359302ms","start":"2026-05-19T03:02:16.374502Z","end":"2026-05-19T03:02:16.490861Z","steps":["trace[769582180] 'agreement among raft nodes before linearized reading' (duration: 116.127369ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:16.490624Z","caller":"traceutil/trace.go:171","msg":"trace[1516480275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4162; }","duration":"116.013666ms","start":"2026-05-19T03:02:16.37436Z","end":"2026-05-19T03:02:16.490373Z","steps":["trace[1516480275] 'agreement among raft nodes before linearized reading' (duration: 114.684677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:27.773206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.674782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:530982"} {"level":"info","ts":"2026-05-19T03:02:27.773296Z","caller":"traceutil/trace.go:171","msg":"trace[1131374035] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4679; }","duration":"206.042436ms","start":"2026-05-19T03:02:27.567234Z","end":"2026-05-19T03:02:27.773276Z","steps":["trace[1131374035] 'range keys from in-memory index tree' (duration: 204.818979ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:29.813534Z","caller":"traceutil/trace.go:171","msg":"trace[1649195521] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4920; }","duration":"123.299875ms","start":"2026-05-19T03:02:29.690203Z","end":"2026-05-19T03:02:29.813503Z","steps":["trace[1649195521] 'read index received' (duration: 73.766124ms)","trace[1649195521] 'applied index is now lower than readState.Index' (duration: 49.532391ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:02:29.813574Z","caller":"traceutil/trace.go:171","msg":"trace[1464498759] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"123.649079ms","start":"2026-05-19T03:02:29.689866Z","end":"2026-05-19T03:02:29.813515Z","steps":["trace[1464498759] 'process raft request' (duration: 74.025297ms)","trace[1464498759] 'compare' (duration: 49.46203ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:02:29.813881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.641589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-xlr99-tls-srfsx\" ","response":"range_response_count:1 size:2629"} {"level":"info","ts":"2026-05-19T03:02:29.814017Z","caller":"traceutil/trace.go:171","msg":"trace[1805696100] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-xlr99-tls-srfsx; range_end:; response_count:1; response_revision:4740; }","duration":"123.838821ms","start":"2026-05-19T03:02:29.690158Z","end":"2026-05-19T03:02:29.813996Z","steps":["trace[1805696100] 'agreement among raft nodes before linearized reading' (duration: 123.467266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:29.827839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.71699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-19T03:02:29.827881Z","caller":"traceutil/trace.go:171","msg":"trace[333750939] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4741; }","duration":"135.777121ms","start":"2026-05-19T03:02:29.69209Z","end":"2026-05-19T03:02:29.827867Z","steps":["trace[333750939] 'agreement among raft nodes before linearized reading' (duration: 135.419756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:36.50948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.130474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T03:02:36.509629Z","caller":"traceutil/trace.go:171","msg":"trace[782632101] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4858; }","duration":"411.275277ms","start":"2026-05-19T03:02:36.098317Z","end":"2026-05-19T03:02:36.509592Z","steps":["trace[782632101] 'range keys from in-memory index tree' (duration: 410.940791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:36.509672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.098256Z","time spent":"411.405168ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T03:02:36.707338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950084763,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T03:02:36.773351Z","caller":"traceutil/trace.go:171","msg":"trace[928158961] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"611.276157ms","start":"2026-05-19T03:02:36.162045Z","end":"2026-05-19T03:02:36.773321Z","steps":["trace[928158961] 'process raft request' (duration: 611.111335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:36.773594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.162019Z","time spent":"611.416418ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10303,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:02:37.045583Z","caller":"traceutil/trace.go:171","msg":"trace[158236338] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"649.975935ms","start":"2026-05-19T03:02:36.395556Z","end":"2026-05-19T03:02:37.045532Z","steps":["trace[158236338] 'process raft request' (duration: 649.740372ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:37.045618Z","caller":"traceutil/trace.go:171","msg":"trace[286354446] linearizableReadLoop","detail":"{readStateIndex:5044; appliedIndex:5041; }","duration":"839.334466ms","start":"2026-05-19T03:02:36.206258Z","end":"2026-05-19T03:02:37.045592Z","steps":["trace[286354446] 'read index received' (duration: 566.901708ms)","trace[286354446] 'applied index is now lower than readState.Index' (duration: 272.430398ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:02:37.04558Z","caller":"traceutil/trace.go:171","msg":"trace[520376831] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"651.879281ms","start":"2026-05-19T03:02:36.39366Z","end":"2026-05-19T03:02:37.045539Z","steps":["trace[520376831] 'process raft request' (duration: 599.612774ms)","trace[520376831] 'compare' (duration: 51.794061ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:02:37.045771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.395525Z","time spent":"650.148147ms","remote":"127.0.0.1:52254","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-05-19T03:02:37.045842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.393621Z","time spent":"652.145754ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:02:37.045962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.598812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4aa3e60a-0c24-4903-a1a1-282167e85ac9\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-05-19T03:02:37.046038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.592243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T03:02:37.04606Z","caller":"traceutil/trace.go:171","msg":"trace[870743909] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4862; }","duration":"531.681095ms","start":"2026-05-19T03:02:36.514371Z","end":"2026-05-19T03:02:37.046052Z","steps":["trace[870743909] 'agreement among raft nodes before linearized reading' (duration: 531.567133ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:37.04605Z","caller":"traceutil/trace.go:171","msg":"trace[24296389] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4aa3e60a-0c24-4903-a1a1-282167e85ac9; range_end:; response_count:1; response_revision:4862; }","duration":"419.749183ms","start":"2026-05-19T03:02:36.626282Z","end":"2026-05-19T03:02:37.046031Z","steps":["trace[24296389] 'agreement among raft nodes before linearized reading' (duration: 419.53014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.046077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.514339Z","time spent":"531.734766ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T03:02:37.046091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.62626Z","time spent":"419.822825ms","remote":"127.0.0.1:52176","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-4aa3e60a-0c24-4903-a1a1-282167e85ac9\" "} {"level":"warn","ts":"2026-05-19T03:02:37.046103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.392934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-19T03:02:37.046146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.797278ms","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-05-19T03:02:37.046203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.628386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T03:02:37.046188Z","caller":"traceutil/trace.go:171","msg":"trace[644695522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4862; }","duration":"448.542566ms","start":"2026-05-19T03:02:36.597624Z","end":"2026-05-19T03:02:37.046166Z","steps":["trace[644695522] 'agreement among raft nodes before linearized reading' (duration: 448.297393ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:37.046222Z","caller":"traceutil/trace.go:171","msg":"trace[1298671657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4862; }","duration":"453.675407ms","start":"2026-05-19T03:02:36.592541Z","end":"2026-05-19T03:02:37.046217Z","steps":["trace[1298671657] 'agreement among raft nodes before linearized reading' (duration: 453.622056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.046243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.592522Z","time spent":"453.715757ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T03:02:37.046237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.597604Z","time spent":"448.625127ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T03:02:37.046229Z","caller":"traceutil/trace.go:171","msg":"trace[813579328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4862; }","duration":"415.90594ms","start":"2026-05-19T03:02:36.630299Z","end":"2026-05-19T03:02:37.046205Z","steps":["trace[813579328] 'agreement among raft nodes before linearized reading' (duration: 415.643986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.046298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.630289Z","time spent":"416.000041ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T03:02:37.046308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.152345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T03:02:37.046345Z","caller":"traceutil/trace.go:171","msg":"trace[1080045993] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4862; }","duration":"419.188325ms","start":"2026-05-19T03:02:36.627146Z","end":"2026-05-19T03:02:37.046334Z","steps":["trace[1080045993] 'agreement among raft nodes before linearized reading' (duration: 419.082414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.046375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.627076Z","time spent":"419.293058ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-19T03:02:37.046399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.66006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T03:02:37.046419Z","caller":"traceutil/trace.go:171","msg":"trace[352636360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4862; }","duration":"421.70705ms","start":"2026-05-19T03:02:36.624706Z","end":"2026-05-19T03:02:37.046413Z","steps":["trace[352636360] 'agreement among raft nodes before linearized reading' (duration: 421.65669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.046434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.624692Z","time spent":"421.739361ms","remote":"127.0.0.1:52254","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":"warn","ts":"2026-05-19T03:02:37.046426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.170667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2351250"} {"level":"warn","ts":"2026-05-19T03:02:37.046474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.247388ms","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-05-19T03:02:37.046476Z","caller":"traceutil/trace.go:171","msg":"trace[2074159775] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4862; }","duration":"840.289899ms","start":"2026-05-19T03:02:36.206171Z","end":"2026-05-19T03:02:37.046461Z","steps":["trace[2074159775] 'agreement among raft nodes before linearized reading' (duration: 839.500508ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:37.046498Z","caller":"traceutil/trace.go:171","msg":"trace[1607841417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4862; }","duration":"432.292848ms","start":"2026-05-19T03:02:36.614199Z","end":"2026-05-19T03:02:37.046492Z","steps":["trace[1607841417] 'agreement among raft nodes before linearized reading' (duration: 432.219557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.046493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.372961ms","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-05-19T03:02:37.046509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.206146Z","time spent":"840.354951ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2351274,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-05-19T03:02:37.046532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.614189Z","time spent":"432.327089ms","remote":"127.0.0.1:52254","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-05-19T03:02:37.046537Z","caller":"traceutil/trace.go:171","msg":"trace[689223112] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4862; }","duration":"559.419612ms","start":"2026-05-19T03:02:36.487104Z","end":"2026-05-19T03:02:37.046523Z","steps":["trace[689223112] 'agreement among raft nodes before linearized reading' (duration: 559.320211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.046573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.487024Z","time spent":"559.539313ms","remote":"127.0.0.1:52254","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-05-19T03:02:37.050794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.624689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:02:37.050875Z","caller":"traceutil/trace.go:171","msg":"trace[2110521449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4862; }","duration":"583.787181ms","start":"2026-05-19T03:02:36.46707Z","end":"2026-05-19T03:02:37.050857Z","steps":["trace[2110521449] 'agreement among raft nodes before linearized reading' (duration: 578.727661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.05091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:36.467041Z","time spent":"583.863162ms","remote":"127.0.0.1:51988","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-05-19T03:02:37.239408Z","caller":"traceutil/trace.go:171","msg":"trace[672334809] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"102.204893ms","start":"2026-05-19T03:02:37.137168Z","end":"2026-05-19T03:02:37.239373Z","steps":["trace[672334809] 'process raft request' (duration: 86.280771ms)","trace[672334809] 'compare' (duration: 15.78283ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:02:37.29807Z","caller":"traceutil/trace.go:171","msg":"trace[1403555222] linearizableReadLoop","detail":"{readStateIndex:5058; appliedIndex:5054; }","duration":"158.66193ms","start":"2026-05-19T03:02:37.139383Z","end":"2026-05-19T03:02:37.298045Z","steps":["trace[1403555222] 'read index received' (duration: 84.146052ms)","trace[1403555222] 'applied index is now lower than readState.Index' (duration: 74.515058ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:02:37.298308Z","caller":"traceutil/trace.go:171","msg":"trace[1061988852] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"160.981951ms","start":"2026-05-19T03:02:37.137317Z","end":"2026-05-19T03:02:37.298299Z","steps":["trace[1061988852] 'process raft request' (duration: 160.527125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.298571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.513966ms","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-05-19T03:02:37.298598Z","caller":"traceutil/trace.go:171","msg":"trace[601079183] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4875; }","duration":"140.604257ms","start":"2026-05-19T03:02:37.157986Z","end":"2026-05-19T03:02:37.29859Z","steps":["trace[601079183] 'agreement among raft nodes before linearized reading' (duration: 140.473126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:37.298683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.260898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T03:02:37.298779Z","caller":"traceutil/trace.go:171","msg":"trace[1434242304] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4875; }","duration":"159.40931ms","start":"2026-05-19T03:02:37.139347Z","end":"2026-05-19T03:02:37.298757Z","steps":["trace[1434242304] 'agreement among raft nodes before linearized reading' (duration: 159.096095ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:37.298987Z","caller":"traceutil/trace.go:171","msg":"trace[1634634430] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"161.64631ms","start":"2026-05-19T03:02:37.137332Z","end":"2026-05-19T03:02:37.298979Z","steps":["trace[1634634430] 'process raft request' (duration: 160.623397ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:37.299058Z","caller":"traceutil/trace.go:171","msg":"trace[1231482700] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"160.830128ms","start":"2026-05-19T03:02:37.138195Z","end":"2026-05-19T03:02:37.299025Z","steps":["trace[1231482700] 'process raft request' (duration: 159.805904ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:37.527506Z","caller":"traceutil/trace.go:171","msg":"trace[1917658598] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"182.819546ms","start":"2026-05-19T03:02:37.344645Z","end":"2026-05-19T03:02:37.527465Z","steps":["trace[1917658598] 'process raft request' (duration: 167.687415ms)","trace[1917658598] 'compare' (duration: 14.652334ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:02:42.660209Z","caller":"traceutil/trace.go:171","msg":"trace[1618453815] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"106.251044ms","start":"2026-05-19T03:02:42.553932Z","end":"2026-05-19T03:02:42.660183Z","steps":["trace[1618453815] 'process raft request' (duration: 103.135422ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:48.71656Z","caller":"traceutil/trace.go:171","msg":"trace[461993365] linearizableReadLoop","detail":"{readStateIndex:5206; appliedIndex:5205; }","duration":"206.354953ms","start":"2026-05-19T03:02:48.510173Z","end":"2026-05-19T03:02:48.716528Z","steps":["trace[461993365] 'read index received' (duration: 206.321712ms)","trace[461993365] 'applied index is now lower than readState.Index' (duration: 31.741µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:02:48.716674Z","caller":"traceutil/trace.go:171","msg":"trace[1476894227] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"235.814655ms","start":"2026-05-19T03:02:48.4808Z","end":"2026-05-19T03:02:48.716615Z","steps":["trace[1476894227] 'process raft request' (duration: 235.518831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:48.716731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.512945ms","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-05-19T03:02:48.716778Z","caller":"traceutil/trace.go:171","msg":"trace[506022551] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5018; }","duration":"206.633877ms","start":"2026-05-19T03:02:48.51013Z","end":"2026-05-19T03:02:48.716764Z","steps":["trace[506022551] 'agreement among raft nodes before linearized reading' (duration: 206.520725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.456544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.156205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T03:02:49.457298Z","caller":"traceutil/trace.go:171","msg":"trace[457642290] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"859.741271ms","start":"2026-05-19T03:02:48.597513Z","end":"2026-05-19T03:02:49.457254Z","steps":["trace[457642290] 'process raft request' (duration: 246.757174ms)","trace[457642290] 'compare' (duration: 611.903872ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:02:49.457311Z","caller":"traceutil/trace.go:171","msg":"trace[1426769402] linearizableReadLoop","detail":"{readStateIndex:5207; appliedIndex:5206; }","duration":"609.59668ms","start":"2026-05-19T03:02:48.847685Z","end":"2026-05-19T03:02:49.457282Z","steps":["trace[1426769402] 'read index received' (duration: 61.341µs)","trace[1426769402] 'applied index is now lower than readState.Index' (duration: 609.533529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:02:49.457466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:48.597493Z","time spent":"859.889643ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:02:49.504591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.721622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:02:49.504676Z","caller":"traceutil/trace.go:171","msg":"trace[467285666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5019; }","duration":"657.031046ms","start":"2026-05-19T03:02:48.847605Z","end":"2026-05-19T03:02:49.504636Z","steps":["trace[467285666] 'agreement among raft nodes before linearized reading' (duration: 609.804973ms)","trace[467285666] 'range keys from in-memory index tree' (duration: 46.93931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:02:49.504723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:48.847574Z","time spent":"657.138547ms","remote":"127.0.0.1:51988","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-05-19T03:02:49.504605Z","caller":"traceutil/trace.go:171","msg":"trace[568853240] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"368.947503ms","start":"2026-05-19T03:02:49.135631Z","end":"2026-05-19T03:02:49.504579Z","steps":["trace[568853240] 'process raft request' (duration: 368.76664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.504899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.135604Z","time spent":"369.228186ms","remote":"127.0.0.1:52254","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-05-19T03:02:49.504953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.574273ms","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-05-19T03:02:49.505021Z","caller":"traceutil/trace.go:171","msg":"trace[641171604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5021; }","duration":"309.678345ms","start":"2026-05-19T03:02:49.195327Z","end":"2026-05-19T03:02:49.505005Z","steps":["trace[641171604] 'agreement among raft nodes before linearized reading' (duration: 309.506523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.195314Z","time spent":"309.744066ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T03:02:49.505092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.242844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-19T03:02:49.50518Z","caller":"traceutil/trace.go:171","msg":"trace[1100742787] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5021; }","duration":"183.399356ms","start":"2026-05-19T03:02:49.321764Z","end":"2026-05-19T03:02:49.505163Z","steps":["trace[1100742787] 'agreement among raft nodes before linearized reading' (duration: 183.186463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.60391ms","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":"warn","ts":"2026-05-19T03:02:49.50533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.921068ms","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-05-19T03:02:49.505363Z","caller":"traceutil/trace.go:171","msg":"trace[268175565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5021; }","duration":"298.980929ms","start":"2026-05-19T03:02:49.206373Z","end":"2026-05-19T03:02:49.505354Z","steps":["trace[268175565] 'agreement among raft nodes before linearized reading' (duration: 298.889027ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:49.505397Z","caller":"traceutil/trace.go:171","msg":"trace[500541039] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"369.491378ms","start":"2026-05-19T03:02:49.135897Z","end":"2026-05-19T03:02:49.505388Z","steps":["trace[500541039] 'process raft request' (duration: 368.628707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.838377ms","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-05-19T03:02:49.505475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.135877Z","time spent":"369.546318ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:02:49.50549Z","caller":"traceutil/trace.go:171","msg":"trace[1652126710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5021; }","duration":"287.911668ms","start":"2026-05-19T03:02:49.21757Z","end":"2026-05-19T03:02:49.505481Z","steps":["trace[1652126710] 'agreement among raft nodes before linearized reading' (duration: 287.815587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.861481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T03:02:49.505692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.515121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-19T03:02:49.505732Z","caller":"traceutil/trace.go:171","msg":"trace[1263667715] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5021; }","duration":"311.609751ms","start":"2026-05-19T03:02:49.194109Z","end":"2026-05-19T03:02:49.505719Z","steps":["trace[1263667715] 'agreement among raft nodes before linearized reading' (duration: 311.52047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.660193ms","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-05-19T03:02:49.505773Z","caller":"traceutil/trace.go:171","msg":"trace[909513921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5021; }","duration":"311.726263ms","start":"2026-05-19T03:02:49.194042Z","end":"2026-05-19T03:02:49.505768Z","steps":["trace[909513921] 'agreement among raft nodes before linearized reading' (duration: 311.673402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.194013Z","time spent":"311.778604ms","remote":"127.0.0.1:52254","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-05-19T03:02:49.505362Z","caller":"traceutil/trace.go:171","msg":"trace[603156665] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5021; }","duration":"294.68639ms","start":"2026-05-19T03:02:49.210664Z","end":"2026-05-19T03:02:49.50535Z","steps":["trace[603156665] 'agreement among raft nodes before linearized reading' (duration: 294.491048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.471941ms","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-05-19T03:02:49.505885Z","caller":"traceutil/trace.go:171","msg":"trace[801340709] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5021; }","duration":"411.544941ms","start":"2026-05-19T03:02:49.094332Z","end":"2026-05-19T03:02:49.505877Z","steps":["trace[801340709] 'agreement among raft nodes before linearized reading' (duration: 411.45985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.252486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-19T03:02:49.50595Z","caller":"traceutil/trace.go:171","msg":"trace[1573341840] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5021; }","duration":"380.323147ms","start":"2026-05-19T03:02:49.125618Z","end":"2026-05-19T03:02:49.505941Z","steps":["trace[1573341840] 'agreement among raft nodes before linearized reading' (duration: 380.259096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.505971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.125596Z","time spent":"380.370037ms","remote":"127.0.0.1:52152","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-05-19T03:02:49.506216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.094304Z","time spent":"411.605883ms","remote":"127.0.0.1:52176","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-05-19T03:02:49.505769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.194076Z","time spent":"311.686733ms","remote":"127.0.0.1:52254","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":"info","ts":"2026-05-19T03:02:49.505701Z","caller":"traceutil/trace.go:171","msg":"trace[559403876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5021; }","duration":"365.919911ms","start":"2026-05-19T03:02:49.139775Z","end":"2026-05-19T03:02:49.505695Z","steps":["trace[559403876] 'agreement among raft nodes before linearized reading' (duration: 365.85199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.506563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.139761Z","time spent":"366.790102ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T03:02:49.506661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.927901ms","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-05-19T03:02:49.506753Z","caller":"traceutil/trace.go:171","msg":"trace[960384604] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:5021; }","duration":"416.020222ms","start":"2026-05-19T03:02:49.090709Z","end":"2026-05-19T03:02:49.506729Z","steps":["trace[960384604] 'agreement among raft nodes before linearized reading' (duration: 415.893181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:02:49.506811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:02:49.090666Z","time spent":"416.129403ms","remote":"127.0.0.1:52328","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":"info","ts":"2026-05-19T03:02:49.674775Z","caller":"traceutil/trace.go:171","msg":"trace[21944771] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"161.571758ms","start":"2026-05-19T03:02:49.513157Z","end":"2026-05-19T03:02:49.674729Z","steps":["trace[21944771] 'process raft request' (duration: 161.324925ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:49.674808Z","caller":"traceutil/trace.go:171","msg":"trace[1799852191] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"161.008171ms","start":"2026-05-19T03:02:49.513769Z","end":"2026-05-19T03:02:49.674778Z","steps":["trace[1799852191] 'process raft request' (duration: 160.89492ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:49.674922Z","caller":"traceutil/trace.go:171","msg":"trace[99955787] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"160.663825ms","start":"2026-05-19T03:02:49.514211Z","end":"2026-05-19T03:02:49.674875Z","steps":["trace[99955787] 'process raft request' (duration: 160.494093ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:49.674986Z","caller":"traceutil/trace.go:171","msg":"trace[325639770] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"160.080179ms","start":"2026-05-19T03:02:49.514875Z","end":"2026-05-19T03:02:49.674956Z","steps":["trace[325639770] 'process raft request' (duration: 159.866416ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:49.675015Z","caller":"traceutil/trace.go:171","msg":"trace[1384208713] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"153.106205ms","start":"2026-05-19T03:02:49.521884Z","end":"2026-05-19T03:02:49.67499Z","steps":["trace[1384208713] 'process raft request' (duration: 153.016043ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:49.675235Z","caller":"traceutil/trace.go:171","msg":"trace[1071758340] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"159.561762ms","start":"2026-05-19T03:02:49.515661Z","end":"2026-05-19T03:02:49.675223Z","steps":["trace[1071758340] 'process raft request' (duration: 159.120285ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:49.675237Z","caller":"traceutil/trace.go:171","msg":"trace[1677851417] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"159.39588ms","start":"2026-05-19T03:02:49.515826Z","end":"2026-05-19T03:02:49.675222Z","steps":["trace[1677851417] 'process raft request' (duration: 158.993624ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:02:49.675434Z","caller":"traceutil/trace.go:171","msg":"trace[887059047] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"161.985164ms","start":"2026-05-19T03:02:49.513431Z","end":"2026-05-19T03:02:49.675416Z","steps":["trace[887059047] 'process raft request' (duration: 161.183853ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:03:50.34379Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2899} {"level":"info","ts":"2026-05-19T03:03:50.425215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2899,"took":"79.841021ms","hash":1817530898,"current-db-size-bytes":53768192,"current-db-size":"54 MB","current-db-size-in-use-bytes":29417472,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T03:03:50.425278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1817530898,"revision":2899,"compact-revision":-1} {"level":"info","ts":"2026-05-19T03:06:34.476397Z","caller":"traceutil/trace.go:171","msg":"trace[2027908384] transaction","detail":"{read_only:false; response_revision:6713; number_of_response:1; }","duration":"521.834798ms","start":"2026-05-19T03:06:33.954534Z","end":"2026-05-19T03:06:34.476369Z","steps":["trace[2027908384] 'process raft request' (duration: 521.686187ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:06:34.476424Z","caller":"traceutil/trace.go:171","msg":"trace[141550228] linearizableReadLoop","detail":"{readStateIndex:6958; appliedIndex:6958; }","duration":"423.299625ms","start":"2026-05-19T03:06:34.053071Z","end":"2026-05-19T03:06:34.476371Z","steps":["trace[141550228] 'read index received' (duration: 423.280445ms)","trace[141550228] 'applied index is now lower than readState.Index' (duration: 15.54µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T03:06:34.476584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:06:33.954514Z","time spent":"521.97509ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:06:34.476901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.76769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.171\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T03:06:34.47694Z","caller":"traceutil/trace.go:171","msg":"trace[1135302470] range","detail":"{range_begin:/registry/masterleases/199.19.213.171; range_end:; response_count:1; response_revision:6713; }","duration":"423.893871ms","start":"2026-05-19T03:06:34.053036Z","end":"2026-05-19T03:06:34.47693Z","steps":["trace[1135302470] 'agreement among raft nodes before linearized reading' (duration: 423.577978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:06:34.47698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:06:34.053018Z","time spent":"423.953261ms","remote":"127.0.0.1:52018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.171\" "} {"level":"info","ts":"2026-05-19T03:06:34.544921Z","caller":"traceutil/trace.go:171","msg":"trace[169099558] transaction","detail":"{read_only:false; response_revision:6714; number_of_response:1; }","duration":"211.710147ms","start":"2026-05-19T03:06:34.333181Z","end":"2026-05-19T03:06:34.544892Z","steps":["trace[169099558] 'process raft request' (duration: 188.926406ms)","trace[169099558] 'compare' (duration: 22.282675ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:06:41.347928Z","caller":"traceutil/trace.go:171","msg":"trace[1258687405] transaction","detail":"{read_only:false; response_revision:6755; number_of_response:1; }","duration":"205.842948ms","start":"2026-05-19T03:06:41.14206Z","end":"2026-05-19T03:06:41.347903Z","steps":["trace[1258687405] 'process raft request' (duration: 136.499107ms)","trace[1258687405] 'compare' (duration: 68.989006ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:06:41.348202Z","caller":"traceutil/trace.go:171","msg":"trace[655307162] transaction","detail":"{read_only:false; response_revision:6757; number_of_response:1; }","duration":"193.009806ms","start":"2026-05-19T03:06:41.155161Z","end":"2026-05-19T03:06:41.348171Z","steps":["trace[655307162] 'process raft request' (duration: 192.875405ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:06:41.34842Z","caller":"traceutil/trace.go:171","msg":"trace[672369063] transaction","detail":"{read_only:false; response_revision:6756; number_of_response:1; }","duration":"205.022968ms","start":"2026-05-19T03:06:41.143378Z","end":"2026-05-19T03:06:41.348401Z","steps":["trace[672369063] 'process raft request' (duration: 204.451922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:26.19386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:26.694297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:27.194455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:27.695189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:28.196083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:28.467876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000639478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-19T03:08:28.467967Z","caller":"traceutil/trace.go:171","msg":"trace[588360555] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000803333s","start":"2026-05-19T03:08:26.467142Z","end":"2026-05-19T03:08:28.467945Z","steps":["trace[588360555] 'agreement among raft nodes before linearized reading' (duration: 2.00068462s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:28.468013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:26.467116Z","time spent":"2.000889606s","remote":"127.0.0.1:51988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 03:08:28 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T03:08:28.696963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:29.19807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:29.276541Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.610117907s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T03:08:29.280105Z","caller":"traceutil/trace.go:171","msg":"trace[1001393681] transaction","detail":"{read_only:false; response_revision:7308; number_of_response:1; }","duration":"3.613777037s","start":"2026-05-19T03:08:25.666298Z","end":"2026-05-19T03:08:29.280075Z","steps":["trace[1001393681] 'process raft request' (duration: 3.610467269s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.28026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:25.666282Z","time spent":"3.613882741s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:29.28053Z","caller":"traceutil/trace.go:171","msg":"trace[1034458473] linearizableReadLoop","detail":"{readStateIndex:7580; appliedIndex:7575; }","duration":"3.58724377s","start":"2026-05-19T03:08:25.693263Z","end":"2026-05-19T03:08:29.280507Z","steps":["trace[1034458473] 'read index received' (duration: 3.583515208s)","trace[1034458473] 'applied index is now lower than readState.Index' (duration: 3.727402ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:08:29.280618Z","caller":"traceutil/trace.go:171","msg":"trace[936915221] transaction","detail":"{read_only:false; response_revision:7310; number_of_response:1; }","duration":"2.18808576s","start":"2026-05-19T03:08:27.092516Z","end":"2026-05-19T03:08:29.280602Z","steps":["trace[936915221] 'process raft request' (duration: 2.187894454s)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:08:29.280688Z","caller":"traceutil/trace.go:171","msg":"trace[655713301] transaction","detail":"{read_only:false; response_revision:7311; number_of_response:1; }","duration":"2.13860759s","start":"2026-05-19T03:08:27.142068Z","end":"2026-05-19T03:08:29.280675Z","steps":["trace[655713301] 'process raft request' (duration: 2.138375923s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.280818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:27.142043Z","time spent":"2.138695682s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:29.280898Z","caller":"traceutil/trace.go:171","msg":"trace[1467331778] transaction","detail":"{read_only:false; response_revision:7309; number_of_response:1; }","duration":"2.258155387s","start":"2026-05-19T03:08:27.022731Z","end":"2026-05-19T03:08:29.280886Z","steps":["trace[1467331778] 'process raft request' (duration: 2.257514838s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.280963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:27.022698Z","time spent":"2.258236069s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:29.281104Z","caller":"traceutil/trace.go:171","msg":"trace[102209775] transaction","detail":"{read_only:false; response_revision:7312; number_of_response:1; }","duration":"2.059570274s","start":"2026-05-19T03:08:27.221521Z","end":"2026-05-19T03:08:29.281091Z","steps":["trace[102209775] 'process raft request' (duration: 2.058953556s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.281206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:27.221501Z","time spent":"2.059670406s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:08:29.280691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:27.092485Z","time spent":"2.188173072s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:08:29.28146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.390094332s","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-05-19T03:08:29.281511Z","caller":"traceutil/trace.go:171","msg":"trace[2106088447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7312; }","duration":"3.390144324s","start":"2026-05-19T03:08:25.891355Z","end":"2026-05-19T03:08:29.2815Z","steps":["trace[2106088447] 'agreement among raft nodes before linearized reading' (duration: 3.390000539s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.281548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:25.891261Z","time spent":"3.390280069s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T03:08:29.281681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.588418475s","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-05-19T03:08:29.281719Z","caller":"traceutil/trace.go:171","msg":"trace[306012637] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7312; }","duration":"3.588495597s","start":"2026-05-19T03:08:25.69321Z","end":"2026-05-19T03:08:29.281706Z","steps":["trace[306012637] 'agreement among raft nodes before linearized reading' (duration: 3.588406544s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.281756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:25.69319Z","time spent":"3.588559749s","remote":"127.0.0.1:52254","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-05-19T03:08:29.281878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.654567ms","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-05-19T03:08:29.281914Z","caller":"traceutil/trace.go:171","msg":"trace[1782618432] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7312; }","duration":"206.74436ms","start":"2026-05-19T03:08:29.075159Z","end":"2026-05-19T03:08:29.281903Z","steps":["trace[1782618432] 'agreement among raft nodes before linearized reading' (duration: 206.682389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.282001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.940849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:08:29.282033Z","caller":"traceutil/trace.go:171","msg":"trace[2092083438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7312; }","duration":"434.016462ms","start":"2026-05-19T03:08:28.848006Z","end":"2026-05-19T03:08:29.282023Z","steps":["trace[2092083438] 'agreement among raft nodes before linearized reading' (duration: 433.975001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.282064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:28.847937Z","time spent":"434.121925ms","remote":"127.0.0.1:51988","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-05-19T03:08:29.282295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.015179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:08:29.282333Z","caller":"traceutil/trace.go:171","msg":"trace[93772276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7312; }","duration":"814.104851ms","start":"2026-05-19T03:08:28.468219Z","end":"2026-05-19T03:08:29.282324Z","steps":["trace[93772276] 'agreement among raft nodes before linearized reading' (duration: 814.050839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.282364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:28.468166Z","time spent":"814.188244ms","remote":"127.0.0.1:51986","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-05-19T03:08:29.282514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.53226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T03:08:29.282545Z","caller":"traceutil/trace.go:171","msg":"trace[1151321730] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:7312; }","duration":"979.645234ms","start":"2026-05-19T03:08:28.302891Z","end":"2026-05-19T03:08:29.282537Z","steps":["trace[1151321730] 'agreement among raft nodes before linearized reading' (duration: 979.598802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.28257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:28.302855Z","time spent":"979.706996ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:08:29.282717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61072964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T03:08:29.28274Z","caller":"traceutil/trace.go:171","msg":"trace[959991233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7312; }","duration":"1.610793583s","start":"2026-05-19T03:08:27.671941Z","end":"2026-05-19T03:08:29.282735Z","steps":["trace[959991233] 'agreement among raft nodes before linearized reading' (duration: 1.61071798s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.282757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:27.671922Z","time spent":"1.610830313s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T03:08:29.282835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669119918s","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-05-19T03:08:29.282855Z","caller":"traceutil/trace.go:171","msg":"trace[2141429348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7312; }","duration":"1.66918256s","start":"2026-05-19T03:08:27.613667Z","end":"2026-05-19T03:08:29.28285Z","steps":["trace[2141429348] 'agreement among raft nodes before linearized reading' (duration: 1.669131418s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.282872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:27.613644Z","time spent":"1.669224561s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T03:08:29.283023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670969104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T03:08:29.283049Z","caller":"traceutil/trace.go:171","msg":"trace[246021925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7312; }","duration":"1.670996335s","start":"2026-05-19T03:08:27.612047Z","end":"2026-05-19T03:08:29.283043Z","steps":["trace[246021925] 'agreement among raft nodes before linearized reading' (duration: 1.6708709s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.283071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:27.611971Z","time spent":"1.671096278s","remote":"127.0.0.1:52254","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":"warn","ts":"2026-05-19T03:08:29.283188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.074535422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T03:08:29.283212Z","caller":"traceutil/trace.go:171","msg":"trace[1829855519] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:7312; }","duration":"2.074617704s","start":"2026-05-19T03:08:27.208587Z","end":"2026-05-19T03:08:29.283205Z","steps":["trace[1829855519] 'agreement among raft nodes before linearized reading' (duration: 2.074533582s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.283234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:27.20856Z","time spent":"2.074668665s","remote":"127.0.0.1:52030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":71,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-19T03:08:29.283356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.190453678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/keystone-api\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-05-19T03:08:29.283436Z","caller":"traceutil/trace.go:171","msg":"trace[1579984331] range","detail":"{range_begin:/registry/deployments/openstack/keystone-api; range_end:; response_count:1; response_revision:7312; }","duration":"3.190589812s","start":"2026-05-19T03:08:26.092826Z","end":"2026-05-19T03:08:29.283416Z","steps":["trace[1579984331] 'agreement among raft nodes before linearized reading' (duration: 3.190365856s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.283475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:26.092796Z","time spent":"3.190673214s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":11521,"request content":"key:\"/registry/deployments/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-19T03:08:29.283695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.390455402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T03:08:29.283776Z","caller":"traceutil/trace.go:171","msg":"trace[1749935444] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7312; }","duration":"3.390582056s","start":"2026-05-19T03:08:25.893175Z","end":"2026-05-19T03:08:29.283758Z","steps":["trace[1749935444] 'agreement among raft nodes before linearized reading' (duration: 3.390181985s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:29.285441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:25.893163Z","time spent":"3.392261466s","remote":"127.0.0.1:52254","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-05-19T03:08:47.633715Z","caller":"traceutil/trace.go:171","msg":"trace[1132609844] linearizableReadLoop","detail":"{readStateIndex:7675; appliedIndex:7674; }","duration":"240.087941ms","start":"2026-05-19T03:08:47.393599Z","end":"2026-05-19T03:08:47.633687Z","steps":["trace[1132609844] 'read index received' (duration: 239.417911ms)","trace[1132609844] 'applied index is now lower than readState.Index' (duration: 669.03µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:08:47.633834Z","caller":"traceutil/trace.go:171","msg":"trace[1491308115] transaction","detail":"{read_only:false; response_revision:7402; number_of_response:1; }","duration":"253.198855ms","start":"2026-05-19T03:08:47.380623Z","end":"2026-05-19T03:08:47.633821Z","steps":["trace[1491308115] 'process raft request' (duration: 252.380652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:47.634054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.43228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T03:08:47.634089Z","caller":"traceutil/trace.go:171","msg":"trace[1499557130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7402; }","duration":"240.543464ms","start":"2026-05-19T03:08:47.393535Z","end":"2026-05-19T03:08:47.634078Z","steps":["trace[1499557130] 'agreement among raft nodes before linearized reading' (duration: 240.391219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:47.6344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.636516ms","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-05-19T03:08:47.634452Z","caller":"traceutil/trace.go:171","msg":"trace[2078452814] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7403; }","duration":"240.738969ms","start":"2026-05-19T03:08:47.393692Z","end":"2026-05-19T03:08:47.634431Z","steps":["trace[2078452814] 'agreement among raft nodes before linearized reading' (duration: 240.617245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:47.635404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.852932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T03:08:47.63552Z","caller":"traceutil/trace.go:171","msg":"trace[1562192218] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7403; }","duration":"241.016996ms","start":"2026-05-19T03:08:47.394473Z","end":"2026-05-19T03:08:47.63549Z","steps":["trace[1562192218] 'agreement among raft nodes before linearized reading' (duration: 240.648846ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T03:08:47.97721Z","caller":"traceutil/trace.go:171","msg":"trace[1637864551] transaction","detail":"{read_only:false; response_revision:7408; number_of_response:1; }","duration":"334.454607ms","start":"2026-05-19T03:08:47.642733Z","end":"2026-05-19T03:08:47.977188Z","steps":["trace[1637864551] 'process raft request' (duration: 334.291012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:47.977351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:47.642724Z","time spent":"334.5498ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:08:50.146979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:50.647957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655369534950091699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T03:08:50.895051Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.249336536s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T03:08:50.896476Z","caller":"traceutil/trace.go:171","msg":"trace[1581316035] transaction","detail":"{read_only:false; response_revision:7413; number_of_response:1; }","duration":"1.250862999s","start":"2026-05-19T03:08:49.645589Z","end":"2026-05-19T03:08:50.896452Z","steps":["trace[1581316035] 'process raft request' (duration: 1.249837869s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.896595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.645571Z","time spent":"1.25094059s","remote":"127.0.0.1:52254","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-05-19T03:08:50.898693Z","caller":"traceutil/trace.go:171","msg":"trace[2015134380] linearizableReadLoop","detail":"{readStateIndex:7689; appliedIndex:7686; }","duration":"1.252446584s","start":"2026-05-19T03:08:49.646225Z","end":"2026-05-19T03:08:50.898672Z","steps":["trace[2015134380] 'read index received' (duration: 1.249218852s)","trace[2015134380] 'applied index is now lower than readState.Index' (duration: 3.226602ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T03:08:50.898827Z","caller":"traceutil/trace.go:171","msg":"trace[351669445] transaction","detail":"{read_only:false; response_revision:7414; number_of_response:1; }","duration":"1.230416415s","start":"2026-05-19T03:08:49.668397Z","end":"2026-05-19T03:08:50.898813Z","steps":["trace[351669445] 'process raft request' (duration: 1.230110097s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.899008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.668374Z","time spent":"1.230492828s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T03:08:50.899384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25300412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-19T03:08:50.899446Z","caller":"traceutil/trace.go:171","msg":"trace[1663420409] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7415; }","duration":"1.253107612s","start":"2026-05-19T03:08:49.646323Z","end":"2026-05-19T03:08:50.89943Z","steps":["trace[1663420409] 'agreement among raft nodes before linearized reading' (duration: 1.252911647s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.89949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.646304Z","time spent":"1.253174205s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-19T03:08:50.899361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253081492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-19T03:08:50.899667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.747417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T03:08:50.899724Z","caller":"traceutil/trace.go:171","msg":"trace[1251232157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7415; }","duration":"915.85598ms","start":"2026-05-19T03:08:49.98386Z","end":"2026-05-19T03:08:50.899716Z","steps":["trace[1251232157] 'agreement among raft nodes before linearized reading' (duration: 915.771028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.899744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.448274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-19T03:08:50.899773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.983831Z","time spent":"915.933753ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T03:08:50.899799Z","caller":"traceutil/trace.go:171","msg":"trace[74544490] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7415; }","duration":"925.540356ms","start":"2026-05-19T03:08:49.974245Z","end":"2026-05-19T03:08:50.899786Z","steps":["trace[74544490] 'agreement among raft nodes before linearized reading' (duration: 925.399192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.899837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.974226Z","time spent":"925.601029ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-19T03:08:50.899705Z","caller":"traceutil/trace.go:171","msg":"trace[271366447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7415; }","duration":"1.253466013s","start":"2026-05-19T03:08:49.646208Z","end":"2026-05-19T03:08:50.899674Z","steps":["trace[271366447] 'agreement among raft nodes before linearized reading' (duration: 1.252689282s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.900022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.646202Z","time spent":"1.253800023s","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T03:08:50.899499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.218769ms","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-05-19T03:08:50.900119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253266307s","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-05-19T03:08:50.900177Z","caller":"traceutil/trace.go:171","msg":"trace[1807776505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7415; }","duration":"918.024742ms","start":"2026-05-19T03:08:49.982086Z","end":"2026-05-19T03:08:50.90011Z","steps":["trace[1807776505] 'agreement among raft nodes before linearized reading' (duration: 917.351743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.900231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.982075Z","time spent":"918.142046ms","remote":"127.0.0.1:52254","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-05-19T03:08:50.900244Z","caller":"traceutil/trace.go:171","msg":"trace[1604795896] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7415; }","duration":"1.253470333s","start":"2026-05-19T03:08:49.64674Z","end":"2026-05-19T03:08:50.90021Z","steps":["trace[1604795896] 'agreement among raft nodes before linearized reading' (duration: 1.253111583s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.900318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.646716Z","time spent":"1.253586736s","remote":"127.0.0.1:52254","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-05-19T03:08:50.900428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.338311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-19T03:08:50.900484Z","caller":"traceutil/trace.go:171","msg":"trace[1831007224] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7415; }","duration":"939.390662ms","start":"2026-05-19T03:08:49.961079Z","end":"2026-05-19T03:08:50.90047Z","steps":["trace[1831007224] 'agreement among raft nodes before linearized reading' (duration: 939.248508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.900523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252584858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T03:08:50.900522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.961057Z","time spent":"939.455463ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-19T03:08:50.900554Z","caller":"traceutil/trace.go:171","msg":"trace[660253358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7415; }","duration":"1.252612579s","start":"2026-05-19T03:08:49.64793Z","end":"2026-05-19T03:08:50.900543Z","steps":["trace[660253358] 'agreement among raft nodes before linearized reading' (duration: 1.252515797s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.899617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.076881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T03:08:50.900588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:49.64791Z","time spent":"1.25267265s","remote":"127.0.0.1:52254","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-05-19T03:08:50.900619Z","caller":"traceutil/trace.go:171","msg":"trace[294505093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7415; }","duration":"434.127152ms","start":"2026-05-19T03:08:50.46648Z","end":"2026-05-19T03:08:50.900607Z","steps":["trace[294505093] 'agreement among raft nodes before linearized reading' (duration: 433.090242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.900663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:50.46646Z","time spent":"434.194423ms","remote":"127.0.0.1:51988","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-05-19T03:08:50.900633Z","caller":"traceutil/trace.go:171","msg":"trace[519327465] transaction","detail":"{read_only:false; response_revision:7415; number_of_response:1; }","duration":"537.738552ms","start":"2026-05-19T03:08:50.362687Z","end":"2026-05-19T03:08:50.900426Z","steps":["trace[519327465] 'process raft request' (duration: 535.931101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T03:08:50.90087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T03:08:50.362647Z","time spent":"538.129073ms","remote":"127.0.0.1:52000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T03:08:50.906226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5558} {"level":"info","ts":"2026-05-19T03:08:51.001442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5558,"took":"93.666777ms","hash":1656716858,"current-db-size-bytes":53768192,"current-db-size":"54 MB","current-db-size-in-use-bytes":18522112,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T03:08:51.001515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1656716858,"revision":5558,"compact-revision":2899}