{"level":"warn","ts":"2026-07-24T21:25:10.251029Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T21:25:10.251106Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-24T21:25:10.251161Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T21:25:10.25117Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-07-24T21:25:10.251188Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T21:25:10.251815Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-07-24T21:25:10.25193Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-24T21:25:10.255592Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.471651ms"} {"level":"info","ts":"2026-07-24T21:25:10.26651Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-07-24T21:25:10.266594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T21:25:10.266613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-07-24T21:25:10.266621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T21:25:10.266631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-07-24T21:25:10.266663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-07-24T21:25:10.275566Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T21:25:10.28041Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T21:25:10.282343Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-24T21:25:10.285602Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T21:25:10.287785Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T21:25:10.287896Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T21:25:10.288004Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T21:25:10.288017Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T21:25:10.288442Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T21:25:10.288565Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-07-24T21:25:10.288616Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-07-24T21:25:10.288738Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T21:25:10.288787Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T21:25:10.288902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-07-24T21:25:10.289045Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-07-24T21:25:10.76767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T21:25:10.767747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T21:25:10.767767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-07-24T21:25:10.767782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-07-24T21:25:10.767791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-07-24T21:25:10.767802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-07-24T21:25:10.767831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-07-24T21:25:10.76873Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:10.769875Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T21:25:10.769865Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T21:25:10.769929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T21:25:10.770258Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T21:25:10.770317Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T21:25:10.772652Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-07-24T21:25:10.773077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T21:25:10.7802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:10.780268Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:10.780285Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:25:14.992502Z","caller":"traceutil/trace.go:171","msg":"trace[1342132791] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"118.036079ms","start":"2026-07-24T21:25:14.874419Z","end":"2026-07-24T21:25:14.992455Z","steps":["trace[1342132791] 'process raft request' (duration: 94.198125ms)","trace[1342132791] 'compare' (duration: 23.574809ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:55.640353Z","caller":"traceutil/trace.go:171","msg":"trace[175765816] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"166.573726ms","start":"2026-07-24T21:25:55.473754Z","end":"2026-07-24T21:25:55.640328Z","steps":["trace[175765816] 'read index received' (duration: 165.594633ms)","trace[175765816] 'applied index is now lower than readState.Index' (duration: 978.153µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:55.640467Z","caller":"traceutil/trace.go:171","msg":"trace[79676879] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"185.000891ms","start":"2026-07-24T21:25:55.455458Z","end":"2026-07-24T21:25:55.640459Z","steps":["trace[79676879] 'process raft request' (duration: 184.15101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:55.640782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.933705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:55.640956Z","caller":"traceutil/trace.go:171","msg":"trace[2016202569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"167.230492ms","start":"2026-07-24T21:25:55.473698Z","end":"2026-07-24T21:25:55.640928Z","steps":["trace[2016202569] 'agreement among raft nodes before linearized reading' (duration: 166.856864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:55.640905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.822319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-flj4h\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-24T21:25:55.641105Z","caller":"traceutil/trace.go:171","msg":"trace[323995321] range","detail":"{range_begin:/registry/pods/kube-system/cilium-flj4h; range_end:; response_count:1; response_revision:596; }","duration":"102.092295ms","start":"2026-07-24T21:25:55.538981Z","end":"2026-07-24T21:25:55.641074Z","steps":["trace[323995321] 'agreement among raft nodes before linearized reading' (duration: 101.563653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:55.791048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.353684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:25:55.791189Z","caller":"traceutil/trace.go:171","msg":"trace[670062232] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"145.568944ms","start":"2026-07-24T21:25:55.645599Z","end":"2026-07-24T21:25:55.791167Z","steps":["trace[670062232] 'process raft request' (duration: 11.382041ms)","trace[670062232] 'compare' (duration: 133.19811ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:55.976429Z","caller":"traceutil/trace.go:171","msg":"trace[1872977683] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"318.946545ms","start":"2026-07-24T21:25:55.657442Z","end":"2026-07-24T21:25:55.976388Z","steps":["trace[1872977683] 'process raft request' (duration: 292.301284ms)","trace[1872977683] 'compare' (duration: 26.438276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:55.976733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:55.657419Z","time spent":"319.11452ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:25:55.983362Z","caller":"traceutil/trace.go:171","msg":"trace[697498934] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"284.506666ms","start":"2026-07-24T21:25:55.698818Z","end":"2026-07-24T21:25:55.983325Z","steps":["trace[697498934] 'process raft request' (duration: 284.387604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:56.414293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.227966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:25:56.414413Z","caller":"traceutil/trace.go:171","msg":"trace[159500257] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"330.75264ms","start":"2026-07-24T21:25:56.083639Z","end":"2026-07-24T21:25:56.414391Z","steps":["trace[159500257] 'process raft request' (duration: 187.33838ms)","trace[159500257] 'compare' (duration: 143.050302ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:56.414474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:56.083615Z","time spent":"330.831162ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:26:10.788065Z","caller":"traceutil/trace.go:171","msg":"trace[482114071] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"147.596136ms","start":"2026-07-24T21:26:10.64044Z","end":"2026-07-24T21:26:10.788037Z","steps":["trace[482114071] 'process raft request' (duration: 147.399132ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:04.351293Z","caller":"traceutil/trace.go:171","msg":"trace[2074887725] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"142.787463ms","start":"2026-07-24T21:27:04.208477Z","end":"2026-07-24T21:27:04.351264Z","steps":["trace[2074887725] 'process raft request' (duration: 142.538367ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:39.287856Z","caller":"traceutil/trace.go:171","msg":"trace[646787496] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"127.190166ms","start":"2026-07-24T21:27:39.160641Z","end":"2026-07-24T21:27:39.287831Z","steps":["trace[646787496] 'process raft request' (duration: 126.944209ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:00.95442Z","caller":"traceutil/trace.go:171","msg":"trace[1530985692] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"123.812098ms","start":"2026-07-24T21:29:00.830578Z","end":"2026-07-24T21:29:00.95439Z","steps":["trace[1530985692] 'process raft request' (duration: 123.630115ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:21.229899Z","caller":"traceutil/trace.go:171","msg":"trace[2069259238] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"102.911937ms","start":"2026-07-24T21:29:21.126957Z","end":"2026-07-24T21:29:21.229869Z","steps":["trace[2069259238] 'process raft request' (duration: 102.719393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:22.050078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.139289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T21:29:22.050173Z","caller":"traceutil/trace.go:171","msg":"trace[357635923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1490; }","duration":"114.293472ms","start":"2026-07-24T21:29:21.935856Z","end":"2026-07-24T21:29:22.05015Z","steps":["trace[357635923] 'range keys from in-memory index tree' (duration: 113.939585ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:22.882965Z","caller":"traceutil/trace.go:171","msg":"trace[346183857] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"109.41643ms","start":"2026-07-24T21:29:22.77352Z","end":"2026-07-24T21:29:22.882937Z","steps":["trace[346183857] 'process raft request' (duration: 109.252657ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:48.530103Z","caller":"traceutil/trace.go:171","msg":"trace[478381296] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"153.269315ms","start":"2026-07-24T21:33:48.376811Z","end":"2026-07-24T21:33:48.53008Z","steps":["trace[478381296] 'process raft request' (duration: 150.096546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:33:57.28677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.463182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-nw7pm\" ","response":"range_response_count:1 size:7665"} {"level":"info","ts":"2026-07-24T21:33:57.286869Z","caller":"traceutil/trace.go:171","msg":"trace[1366115117] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-nw7pm; range_end:; response_count:1; response_revision:2636; }","duration":"150.580034ms","start":"2026-07-24T21:33:57.136265Z","end":"2026-07-24T21:33:57.286845Z","steps":["trace[1366115117] 'range keys from in-memory index tree' (duration: 150.214218ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:33:57.312359Z","caller":"traceutil/trace.go:171","msg":"trace[544685001] transaction","detail":"{read_only:false; response_revision:2637; number_of_response:1; }","duration":"165.69771ms","start":"2026-07-24T21:33:57.14662Z","end":"2026-07-24T21:33:57.312318Z","steps":["trace[544685001] 'process raft request' (duration: 165.462145ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:04.515435Z","caller":"traceutil/trace.go:171","msg":"trace[427819899] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"130.738199ms","start":"2026-07-24T21:34:04.384666Z","end":"2026-07-24T21:34:04.515404Z","steps":["trace[427819899] 'process raft request' (duration: 113.260599ms)","trace[427819899] 'compare' (duration: 17.327047ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:22.708226Z","caller":"traceutil/trace.go:171","msg":"trace[546050573] linearizableReadLoop","detail":"{readStateIndex:3187; appliedIndex:3185; }","duration":"155.326335ms","start":"2026-07-24T21:34:22.552852Z","end":"2026-07-24T21:34:22.708179Z","steps":["trace[546050573] 'read index received' (duration: 155.08591ms)","trace[546050573] 'applied index is now lower than readState.Index' (duration: 239.885µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:22.708365Z","caller":"traceutil/trace.go:171","msg":"trace[1822639049] transaction","detail":"{read_only:false; response_revision:3037; number_of_response:1; }","duration":"198.984659ms","start":"2026-07-24T21:34:22.509373Z","end":"2026-07-24T21:34:22.708358Z","steps":["trace[1822639049] 'process raft request' (duration: 198.625422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:22.708605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.731282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-cluster-operator.18c55757ee121918\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-07-24T21:34:22.7087Z","caller":"traceutil/trace.go:171","msg":"trace[832734120] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-cluster-operator.18c55757ee121918; range_end:; response_count:1; response_revision:3038; }","duration":"155.874865ms","start":"2026-07-24T21:34:22.552805Z","end":"2026-07-24T21:34:22.70868Z","steps":["trace[832734120] 'agreement among raft nodes before linearized reading' (duration: 155.662361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:22.708722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.447068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129326"} {"level":"info","ts":"2026-07-24T21:34:22.708747Z","caller":"traceutil/trace.go:171","msg":"trace[1147842783] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:3038; }","duration":"154.504548ms","start":"2026-07-24T21:34:22.554236Z","end":"2026-07-24T21:34:22.70874Z","steps":["trace[1147842783] 'agreement among raft nodes before linearized reading' (duration: 154.361536ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:22.708914Z","caller":"traceutil/trace.go:171","msg":"trace[766167470] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"195.790938ms","start":"2026-07-24T21:34:22.513111Z","end":"2026-07-24T21:34:22.708902Z","steps":["trace[766167470] 'process raft request' (duration: 195.011104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:26.754541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.250092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:34:26.754659Z","caller":"traceutil/trace.go:171","msg":"trace[1174888401] linearizableReadLoop","detail":"{readStateIndex:3319; appliedIndex:3318; }","duration":"283.337842ms","start":"2026-07-24T21:34:26.471303Z","end":"2026-07-24T21:34:26.754641Z","steps":["trace[1174888401] 'read index received' (duration: 30.08µs)","trace[1174888401] 'applied index is now lower than readState.Index' (duration: 283.305902ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:26.75475Z","caller":"traceutil/trace.go:171","msg":"trace[692365620] transaction","detail":"{read_only:false; response_revision:3162; number_of_response:1; }","duration":"328.685879ms","start":"2026-07-24T21:34:26.42605Z","end":"2026-07-24T21:34:26.754736Z","steps":["trace[692365620] 'process raft request' (duration: 37.141791ms)","trace[692365620] 'compare' (duration: 290.919896ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:34:26.754804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.495945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/pxc-webhook-ssl\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:34:26.754827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:26.426036Z","time spent":"328.75691ms","remote":"127.0.0.1:57892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2107,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:34:26.754847Z","caller":"traceutil/trace.go:171","msg":"trace[875276986] range","detail":"{range_begin:/registry/secrets/openstack/pxc-webhook-ssl; range_end:; response_count:0; response_revision:3162; }","duration":"283.578166ms","start":"2026-07-24T21:34:26.471256Z","end":"2026-07-24T21:34:26.754835Z","steps":["trace[875276986] 'agreement among raft nodes before linearized reading' (duration: 283.496695ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:26.755628Z","caller":"traceutil/trace.go:171","msg":"trace[97093352] transaction","detail":"{read_only:false; response_revision:3163; number_of_response:1; }","duration":"216.415817ms","start":"2026-07-24T21:34:26.539173Z","end":"2026-07-24T21:34:26.755589Z","steps":["trace[97093352] 'process raft request' (duration: 216.211793ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:39.050567Z","caller":"traceutil/trace.go:171","msg":"trace[141112727] linearizableReadLoop","detail":"{readStateIndex:3643; appliedIndex:3642; }","duration":"121.826716ms","start":"2026-07-24T21:34:38.928716Z","end":"2026-07-24T21:34:39.050542Z","steps":["trace[141112727] 'read index received' (duration: 121.685183ms)","trace[141112727] 'applied index is now lower than readState.Index' (duration: 139.903µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:39.050731Z","caller":"traceutil/trace.go:171","msg":"trace[957157226] transaction","detail":"{read_only:false; response_revision:3481; number_of_response:1; }","duration":"182.556099ms","start":"2026-07-24T21:34:38.868121Z","end":"2026-07-24T21:34:39.050677Z","steps":["trace[957157226] 'process raft request' (duration: 182.210533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:39.050772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.023699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:34:39.051065Z","caller":"traceutil/trace.go:171","msg":"trace[39742331] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:3481; }","duration":"122.365696ms","start":"2026-07-24T21:34:38.928675Z","end":"2026-07-24T21:34:39.05104Z","steps":["trace[39742331] 'agreement among raft nodes before linearized reading' (duration: 122.004998ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:39.121606Z","caller":"traceutil/trace.go:171","msg":"trace[2012598727] transaction","detail":"{read_only:false; response_revision:3482; number_of_response:1; }","duration":"108.125385ms","start":"2026-07-24T21:34:39.013235Z","end":"2026-07-24T21:34:39.121361Z","steps":["trace[2012598727] 'process raft request' (duration: 107.892861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:39.121675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.094971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T21:34:39.121778Z","caller":"traceutil/trace.go:171","msg":"trace[711757368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3482; }","duration":"112.216283ms","start":"2026-07-24T21:34:39.009535Z","end":"2026-07-24T21:34:39.121751Z","steps":["trace[711757368] 'agreement among raft nodes before linearized reading' (duration: 111.764204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:39.121795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.216466ms","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-07-24T21:34:39.121892Z","caller":"traceutil/trace.go:171","msg":"trace[1739960823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3482; }","duration":"110.382639ms","start":"2026-07-24T21:34:39.011485Z","end":"2026-07-24T21:34:39.121868Z","steps":["trace[1739960823] 'agreement among raft nodes before linearized reading' (duration: 109.87634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:46.031843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.539118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/libvirt\" ","response":"range_response_count:1 size:517"} {"level":"info","ts":"2026-07-24T21:34:46.031911Z","caller":"traceutil/trace.go:171","msg":"trace[1619215366] range","detail":"{range_begin:/registry/serviceaccounts/openstack/libvirt; range_end:; response_count:1; response_revision:3608; }","duration":"153.675172ms","start":"2026-07-24T21:34:45.878221Z","end":"2026-07-24T21:34:46.031897Z","steps":["trace[1619215366] 'range keys from in-memory index tree' (duration: 153.366214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:46.032006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.380402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:34:46.032086Z","caller":"traceutil/trace.go:171","msg":"trace[1370233548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3608; }","duration":"167.529535ms","start":"2026-07-24T21:34:45.864537Z","end":"2026-07-24T21:34:46.032066Z","steps":["trace[1370233548] 'range keys from in-memory index tree' (duration: 167.127037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:46.032113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.157771ms","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-07-24T21:34:46.032241Z","caller":"traceutil/trace.go:171","msg":"trace[1758193011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3608; }","duration":"164.339064ms","start":"2026-07-24T21:34:45.867878Z","end":"2026-07-24T21:34:46.032217Z","steps":["trace[1758193011] 'range keys from in-memory index tree' (duration: 163.924107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:46.033563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.802172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:14 size:318753"} {"level":"info","ts":"2026-07-24T21:34:46.036599Z","caller":"traceutil/trace.go:171","msg":"trace[1474241864] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:14; response_revision:3608; }","duration":"266.970925ms","start":"2026-07-24T21:34:45.766702Z","end":"2026-07-24T21:34:46.033673Z","steps":["trace[1474241864] 'range keys from in-memory index tree' (duration: 265.094159ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:46.181255Z","caller":"traceutil/trace.go:171","msg":"trace[1472370565] transaction","detail":"{read_only:false; response_revision:3610; number_of_response:1; }","duration":"140.356208ms","start":"2026-07-24T21:34:46.040861Z","end":"2026-07-24T21:34:46.181218Z","steps":["trace[1472370565] 'process raft request' (duration: 140.179054ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:47.424814Z","caller":"traceutil/trace.go:171","msg":"trace[191145391] linearizableReadLoop","detail":"{readStateIndex:3784; appliedIndex:3783; }","duration":"338.485534ms","start":"2026-07-24T21:34:47.086307Z","end":"2026-07-24T21:34:47.424792Z","steps":["trace[191145391] 'read index received' (duration: 338.26325ms)","trace[191145391] 'applied index is now lower than readState.Index' (duration: 221.414µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:47.424908Z","caller":"traceutil/trace.go:171","msg":"trace[217401638] transaction","detail":"{read_only:false; response_revision:3613; number_of_response:1; }","duration":"621.138687ms","start":"2026-07-24T21:34:46.803735Z","end":"2026-07-24T21:34:47.424874Z","steps":["trace[217401638] 'process raft request' (duration: 620.894433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:47.425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.677138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T21:34:47.425035Z","caller":"traceutil/trace.go:171","msg":"trace[868889673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3613; }","duration":"338.762159ms","start":"2026-07-24T21:34:47.086263Z","end":"2026-07-24T21:34:47.425025Z","steps":["trace[868889673] 'agreement among raft nodes before linearized reading' (duration: 338.632617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:47.425034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:46.803712Z","time spent":"621.252299ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:34:47.42507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:47.086241Z","time spent":"338.819611ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T21:34:47.490236Z","caller":"traceutil/trace.go:171","msg":"trace[1653982012] transaction","detail":"{read_only:false; response_revision:3614; number_of_response:1; }","duration":"342.762445ms","start":"2026-07-24T21:34:47.147432Z","end":"2026-07-24T21:34:47.490194Z","steps":["trace[1653982012] 'process raft request' (duration: 342.480509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:47.49041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.545819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-24T21:34:47.490398Z","caller":"traceutil/trace.go:171","msg":"trace[1888613540] transaction","detail":"{read_only:false; response_revision:3615; number_of_response:1; }","duration":"309.312629ms","start":"2026-07-24T21:34:47.181038Z","end":"2026-07-24T21:34:47.490351Z","steps":["trace[1888613540] 'process raft request' (duration: 309.052264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:47.49045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.617303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T21:34:47.490488Z","caller":"traceutil/trace.go:171","msg":"trace[222347117] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3616; }","duration":"112.653701ms","start":"2026-07-24T21:34:47.377811Z","end":"2026-07-24T21:34:47.490465Z","steps":["trace[222347117] 'agreement among raft nodes before linearized reading' (duration: 112.385646ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:47.490473Z","caller":"traceutil/trace.go:171","msg":"trace[433810589] transaction","detail":"{read_only:false; response_revision:3616; number_of_response:1; }","duration":"105.402963ms","start":"2026-07-24T21:34:47.385006Z","end":"2026-07-24T21:34:47.490409Z","steps":["trace[433810589] 'process raft request' (duration: 105.113997ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:47.490554Z","caller":"traceutil/trace.go:171","msg":"trace[917830970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3616; }","duration":"311.780236ms","start":"2026-07-24T21:34:47.178749Z","end":"2026-07-24T21:34:47.490529Z","steps":["trace[917830970] 'agreement among raft nodes before linearized reading' (duration: 311.45018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:47.490567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:47.18103Z","time spent":"309.456852ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:34:47.490614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:47.178738Z","time spent":"311.866797ms","remote":"127.0.0.1:37116","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-07-24T21:34:47.490652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:47.14741Z","time spent":"342.914568ms","remote":"127.0.0.1:37116","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-07-24T21:34:47.490698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.672488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T21:34:47.490831Z","caller":"traceutil/trace.go:171","msg":"trace[2061567659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3616; }","duration":"318.859911ms","start":"2026-07-24T21:34:47.171939Z","end":"2026-07-24T21:34:47.490798Z","steps":["trace[2061567659] 'agreement among raft nodes before linearized reading' (duration: 318.363482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:47.490889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:47.171927Z","time spent":"318.951732ms","remote":"127.0.0.1:37116","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-07-24T21:34:47.597102Z","caller":"traceutil/trace.go:171","msg":"trace[124495349] linearizableReadLoop","detail":"{readStateIndex:3789; appliedIndex:3787; }","duration":"106.908302ms","start":"2026-07-24T21:34:47.490159Z","end":"2026-07-24T21:34:47.597067Z","steps":["trace[124495349] 'read index received' (duration: 16.994743ms)","trace[124495349] 'applied index is now lower than readState.Index' (duration: 89.911919ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:34:47.597256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.888702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:34:47.597354Z","caller":"traceutil/trace.go:171","msg":"trace[1568599232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3617; }","duration":"127.032685ms","start":"2026-07-24T21:34:47.470302Z","end":"2026-07-24T21:34:47.597335Z","steps":["trace[1568599232] 'agreement among raft nodes before linearized reading' (duration: 126.870662ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:47.597137Z","caller":"traceutil/trace.go:171","msg":"trace[1824604927] transaction","detail":"{read_only:false; response_revision:3617; number_of_response:1; }","duration":"167.835781ms","start":"2026-07-24T21:34:47.429224Z","end":"2026-07-24T21:34:47.59706Z","steps":["trace[1824604927] 'process raft request' (duration: 77.918231ms)","trace[1824604927] 'compare' (duration: 89.445321ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:47.610782Z","caller":"traceutil/trace.go:171","msg":"trace[2012213804] transaction","detail":"{read_only:false; response_revision:3618; number_of_response:1; }","duration":"116.522544ms","start":"2026-07-24T21:34:47.494233Z","end":"2026-07-24T21:34:47.610755Z","steps":["trace[2012213804] 'process raft request' (duration: 116.229029ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:47.610837Z","caller":"traceutil/trace.go:171","msg":"trace[1120627152] transaction","detail":"{read_only:false; response_revision:3619; number_of_response:1; }","duration":"115.942593ms","start":"2026-07-24T21:34:47.49483Z","end":"2026-07-24T21:34:47.610773Z","steps":["trace[1120627152] 'process raft request' (duration: 115.836891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:49.917917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.918519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:34:49.918095Z","caller":"traceutil/trace.go:171","msg":"trace[934752514] transaction","detail":"{read_only:false; response_revision:3637; number_of_response:1; }","duration":"301.022312ms","start":"2026-07-24T21:34:49.617054Z","end":"2026-07-24T21:34:49.918076Z","steps":["trace[934752514] 'process raft request' (duration: 27.862079ms)","trace[934752514] 'compare' (duration: 272.787986ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:34:49.918162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:49.617039Z","time spent":"301.093213ms","remote":"127.0.0.1:37116","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-07-24T21:34:49.918218Z","caller":"traceutil/trace.go:171","msg":"trace[492961799] transaction","detail":"{read_only:false; response_revision:3638; number_of_response:1; }","duration":"299.912141ms","start":"2026-07-24T21:34:49.618283Z","end":"2026-07-24T21:34:49.918195Z","steps":["trace[492961799] 'process raft request' (duration: 299.741017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:49.918324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:34:49.618265Z","time spent":"300.002783ms","remote":"127.0.0.1:37116","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-07-24T21:34:55.583852Z","caller":"traceutil/trace.go:171","msg":"trace[676713225] linearizableReadLoop","detail":"{readStateIndex:4037; appliedIndex:4036; }","duration":"110.474079ms","start":"2026-07-24T21:34:55.473336Z","end":"2026-07-24T21:34:55.58381Z","steps":["trace[676713225] 'read index received' (duration: 110.166443ms)","trace[676713225] 'applied index is now lower than readState.Index' (duration: 305.976µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:34:55.583882Z","caller":"traceutil/trace.go:171","msg":"trace[685186100] transaction","detail":"{read_only:false; response_revision:3859; number_of_response:1; }","duration":"160.745605ms","start":"2026-07-24T21:34:55.423091Z","end":"2026-07-24T21:34:55.583836Z","steps":["trace[685186100] 'process raft request' (duration: 160.456109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:34:55.584063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.713023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:34:55.584143Z","caller":"traceutil/trace.go:171","msg":"trace[1618490569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3859; }","duration":"110.803645ms","start":"2026-07-24T21:34:55.47332Z","end":"2026-07-24T21:34:55.584123Z","steps":["trace[1618490569] 'agreement among raft nodes before linearized reading' (duration: 110.628812ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:08.829896Z","caller":"traceutil/trace.go:171","msg":"trace[473593609] transaction","detail":"{read_only:false; response_revision:4063; number_of_response:1; }","duration":"221.076422ms","start":"2026-07-24T21:35:08.608767Z","end":"2026-07-24T21:35:08.829844Z","steps":["trace[473593609] 'process raft request' (duration: 122.606411ms)","trace[473593609] 'compare' (duration: 98.144345ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:08.829882Z","caller":"traceutil/trace.go:171","msg":"trace[1471105454] linearizableReadLoop","detail":"{readStateIndex:4247; appliedIndex:4245; }","duration":"126.872422ms","start":"2026-07-24T21:35:08.702973Z","end":"2026-07-24T21:35:08.829845Z","steps":["trace[1471105454] 'read index received' (duration: 28.37843ms)","trace[1471105454] 'applied index is now lower than readState.Index' (duration: 98.492372ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:08.830064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.052115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:35:08.830175Z","caller":"traceutil/trace.go:171","msg":"trace[815430830] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4064; }","duration":"127.241739ms","start":"2026-07-24T21:35:08.702911Z","end":"2026-07-24T21:35:08.830153Z","steps":["trace[815430830] 'agreement among raft nodes before linearized reading' (duration: 127.033525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:08.830352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.431825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-24T21:35:08.830385Z","caller":"traceutil/trace.go:171","msg":"trace[1732748924] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4064; }","duration":"114.508507ms","start":"2026-07-24T21:35:08.715864Z","end":"2026-07-24T21:35:08.830373Z","steps":["trace[1732748924] 'agreement among raft nodes before linearized reading' (duration: 114.341644ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:08.830593Z","caller":"traceutil/trace.go:171","msg":"trace[765908154] transaction","detail":"{read_only:false; response_revision:4064; number_of_response:1; }","duration":"163.948487ms","start":"2026-07-24T21:35:08.665959Z","end":"2026-07-24T21:35:08.829907Z","steps":["trace[765908154] 'process raft request' (duration: 163.805804ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:10.855361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1651} {"level":"info","ts":"2026-07-24T21:35:10.896183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1651,"took":"39.822397ms","hash":1931708541,"current-db-size-bytes":38297600,"current-db-size":"38 MB","current-db-size-in-use-bytes":20905984,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-07-24T21:35:10.896243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1931708541,"revision":1651,"compact-revision":-1} {"level":"info","ts":"2026-07-24T21:35:17.488765Z","caller":"traceutil/trace.go:171","msg":"trace[1880009005] transaction","detail":"{read_only:false; response_revision:4217; number_of_response:1; }","duration":"308.548186ms","start":"2026-07-24T21:35:17.180184Z","end":"2026-07-24T21:35:17.488732Z","steps":["trace[1880009005] 'process raft request' (duration: 256.886303ms)","trace[1880009005] 'compare' (duration: 51.51245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:17.488965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:35:17.180155Z","time spent":"308.699648ms","remote":"127.0.0.1:47638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:35:17.6821Z","caller":"traceutil/trace.go:171","msg":"trace[1152940662] linearizableReadLoop","detail":"{readStateIndex:4408; appliedIndex:4407; }","duration":"178.353661ms","start":"2026-07-24T21:35:17.503718Z","end":"2026-07-24T21:35:17.682071Z","steps":["trace[1152940662] 'read index received' (duration: 117.919502ms)","trace[1152940662] 'applied index is now lower than readState.Index' (duration: 60.432579ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:17.682115Z","caller":"traceutil/trace.go:171","msg":"trace[1412775196] transaction","detail":"{read_only:false; response_revision:4218; number_of_response:1; }","duration":"185.124659ms","start":"2026-07-24T21:35:17.496947Z","end":"2026-07-24T21:35:17.682072Z","steps":["trace[1412775196] 'process raft request' (duration: 124.806832ms)","trace[1412775196] 'compare' (duration: 59.301237ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:17.682361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.616016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-24T21:35:17.682401Z","caller":"traceutil/trace.go:171","msg":"trace[265947300] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:4218; }","duration":"178.712487ms","start":"2026-07-24T21:35:17.503675Z","end":"2026-07-24T21:35:17.682388Z","steps":["trace[265947300] 'agreement among raft nodes before linearized reading' (duration: 178.498843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:18.2061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.289366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T21:35:18.206191Z","caller":"traceutil/trace.go:171","msg":"trace[338747088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4227; }","duration":"131.443568ms","start":"2026-07-24T21:35:18.074726Z","end":"2026-07-24T21:35:18.206169Z","steps":["trace[338747088] 'range keys from in-memory index tree' (duration: 131.062291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:18.206294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.471159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T21:35:18.206383Z","caller":"traceutil/trace.go:171","msg":"trace[2121144730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4227; }","duration":"132.628752ms","start":"2026-07-24T21:35:18.073731Z","end":"2026-07-24T21:35:18.20636Z","steps":["trace[2121144730] 'range keys from in-memory index tree' (duration: 132.160592ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:18.346266Z","caller":"traceutil/trace.go:171","msg":"trace[1518660912] transaction","detail":"{read_only:false; response_revision:4228; number_of_response:1; }","duration":"164.014498ms","start":"2026-07-24T21:35:18.18222Z","end":"2026-07-24T21:35:18.346234Z","steps":["trace[1518660912] 'process raft request' (duration: 163.786864ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:18.481662Z","caller":"traceutil/trace.go:171","msg":"trace[1263144815] linearizableReadLoop","detail":"{readStateIndex:4420; appliedIndex:4417; }","duration":"260.232126ms","start":"2026-07-24T21:35:18.221389Z","end":"2026-07-24T21:35:18.481621Z","steps":["trace[1263144815] 'read index received' (duration: 124.666ms)","trace[1263144815] 'applied index is now lower than readState.Index' (duration: 135.564946ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:18.481841Z","caller":"traceutil/trace.go:171","msg":"trace[1973190594] transaction","detail":"{read_only:false; response_revision:4229; number_of_response:1; }","duration":"269.748557ms","start":"2026-07-24T21:35:18.212078Z","end":"2026-07-24T21:35:18.481827Z","steps":["trace[1973190594] 'process raft request' (duration: 262.298655ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:18.482155Z","caller":"traceutil/trace.go:171","msg":"trace[1825515251] transaction","detail":"{read_only:false; response_revision:4230; number_of_response:1; }","duration":"260.909019ms","start":"2026-07-24T21:35:18.221211Z","end":"2026-07-24T21:35:18.48212Z","steps":["trace[1825515251] 'process raft request' (duration: 260.286327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:18.482284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.9255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:11 size:107342"} {"level":"info","ts":"2026-07-24T21:35:18.482327Z","caller":"traceutil/trace.go:171","msg":"trace[898758844] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:11; response_revision:4230; }","duration":"261.011691ms","start":"2026-07-24T21:35:18.221301Z","end":"2026-07-24T21:35:18.482312Z","steps":["trace[898758844] 'agreement among raft nodes before linearized reading' (duration: 260.533562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:18.482204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.654974ms","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-07-24T21:35:18.482522Z","caller":"traceutil/trace.go:171","msg":"trace[994363915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4230; }","duration":"130.021181ms","start":"2026-07-24T21:35:18.352477Z","end":"2026-07-24T21:35:18.482498Z","steps":["trace[994363915] 'agreement among raft nodes before linearized reading' (duration: 129.568972ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:19.486779Z","caller":"traceutil/trace.go:171","msg":"trace[1507437773] transaction","detail":"{read_only:false; response_revision:4241; number_of_response:1; }","duration":"109.505431ms","start":"2026-07-24T21:35:19.377249Z","end":"2026-07-24T21:35:19.486754Z","steps":["trace[1507437773] 'process raft request' (duration: 108.998752ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:19.486941Z","caller":"traceutil/trace.go:171","msg":"trace[1043848021] transaction","detail":"{read_only:false; response_revision:4242; number_of_response:1; }","duration":"104.260261ms","start":"2026-07-24T21:35:19.382653Z","end":"2026-07-24T21:35:19.486914Z","steps":["trace[1043848021] 'process raft request' (duration: 103.801803ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:19.715853Z","caller":"traceutil/trace.go:171","msg":"trace[527633115] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4244; }","duration":"130.125304ms","start":"2026-07-24T21:35:19.585699Z","end":"2026-07-24T21:35:19.715825Z","steps":["trace[527633115] 'process raft request' (duration: 129.875459ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:19.782968Z","caller":"traceutil/trace.go:171","msg":"trace[2134273557] transaction","detail":"{read_only:false; response_revision:4246; number_of_response:1; }","duration":"114.956255ms","start":"2026-07-24T21:35:19.667944Z","end":"2026-07-24T21:35:19.782901Z","steps":["trace[2134273557] 'process raft request' (duration: 114.710269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:19.783958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.359193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/valkey-server-certs\" ","response":"range_response_count:1 size:5406"} {"level":"info","ts":"2026-07-24T21:35:19.784071Z","caller":"traceutil/trace.go:171","msg":"trace[653365229] range","detail":"{range_begin:/registry/secrets/openstack/valkey-server-certs; range_end:; response_count:1; response_revision:4249; }","duration":"114.517447ms","start":"2026-07-24T21:35:19.669526Z","end":"2026-07-24T21:35:19.784044Z","steps":["trace[653365229] 'agreement among raft nodes before linearized reading' (duration: 113.888515ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:21.916054Z","caller":"traceutil/trace.go:171","msg":"trace[58280396] transaction","detail":"{read_only:false; response_revision:4261; number_of_response:1; }","duration":"127.012304ms","start":"2026-07-24T21:35:21.789013Z","end":"2026-07-24T21:35:21.916025Z","steps":["trace[58280396] 'process raft request' (duration: 126.82126ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:21.931119Z","caller":"traceutil/trace.go:171","msg":"trace[147216295] linearizableReadLoop","detail":"{readStateIndex:4455; appliedIndex:4452; }","duration":"129.069304ms","start":"2026-07-24T21:35:21.802022Z","end":"2026-07-24T21:35:21.931092Z","steps":["trace[147216295] 'read index received' (duration: 113.776544ms)","trace[147216295] 'applied index is now lower than readState.Index' (duration: 15.29188ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:21.931629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.855002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d6453b1e-a79e-4c18-b6c0-9c375d50dde4\" ","response":"range_response_count:1 size:3736"} {"level":"info","ts":"2026-07-24T21:35:21.931709Z","caller":"traceutil/trace.go:171","msg":"trace[1429781476] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d6453b1e-a79e-4c18-b6c0-9c375d50dde4; range_end:; response_count:1; response_revision:4263; }","duration":"126.010585ms","start":"2026-07-24T21:35:21.805682Z","end":"2026-07-24T21:35:21.931693Z","steps":["trace[1429781476] 'agreement among raft nodes before linearized reading' (duration: 125.802831ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:21.932445Z","caller":"traceutil/trace.go:171","msg":"trace[318386065] transaction","detail":"{read_only:false; response_revision:4262; number_of_response:1; }","duration":"142.346566ms","start":"2026-07-24T21:35:21.790078Z","end":"2026-07-24T21:35:21.932424Z","steps":["trace[318386065] 'process raft request' (duration: 140.836017ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:21.932452Z","caller":"traceutil/trace.go:171","msg":"trace[1426727863] transaction","detail":"{read_only:false; response_revision:4263; number_of_response:1; }","duration":"142.341716ms","start":"2026-07-24T21:35:21.790089Z","end":"2026-07-24T21:35:21.932431Z","steps":["trace[1426727863] 'process raft request' (duration: 140.962109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:21.931545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.511373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-24T21:35:21.934467Z","caller":"traceutil/trace.go:171","msg":"trace[578324195] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:4263; }","duration":"132.444169ms","start":"2026-07-24T21:35:21.801964Z","end":"2026-07-24T21:35:21.934408Z","steps":["trace[578324195] 'agreement among raft nodes before linearized reading' (duration: 129.341689ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:22.807671Z","caller":"traceutil/trace.go:171","msg":"trace[1732487527] linearizableReadLoop","detail":"{readStateIndex:4457; appliedIndex:4456; }","duration":"213.916986ms","start":"2026-07-24T21:35:22.593711Z","end":"2026-07-24T21:35:22.807628Z","steps":["trace[1732487527] 'read index received' (duration: 213.668642ms)","trace[1732487527] 'applied index is now lower than readState.Index' (duration: 247.414µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:22.807963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.296696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"warn","ts":"2026-07-24T21:35:22.807945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.221282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T21:35:22.808004Z","caller":"traceutil/trace.go:171","msg":"trace[1358811355] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:4265; }","duration":"211.387988ms","start":"2026-07-24T21:35:22.596605Z","end":"2026-07-24T21:35:22.807993Z","steps":["trace[1358811355] 'agreement among raft nodes before linearized reading' (duration: 211.173834ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:22.808054Z","caller":"traceutil/trace.go:171","msg":"trace[336543798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4265; }","duration":"214.398735ms","start":"2026-07-24T21:35:22.593627Z","end":"2026-07-24T21:35:22.808026Z","steps":["trace[336543798] 'agreement among raft nodes before linearized reading' (duration: 214.12456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:22.808187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.543186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:35:22.808288Z","caller":"traceutil/trace.go:171","msg":"trace[1699278428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4265; }","duration":"145.72147ms","start":"2026-07-24T21:35:22.662543Z","end":"2026-07-24T21:35:22.808264Z","steps":["trace[1699278428] 'agreement among raft nodes before linearized reading' (duration: 145.409193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:22.808316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.850563ms","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-07-24T21:35:22.808414Z","caller":"traceutil/trace.go:171","msg":"trace[525731309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4265; }","duration":"146.041386ms","start":"2026-07-24T21:35:22.662348Z","end":"2026-07-24T21:35:22.808389Z","steps":["trace[525731309] 'agreement among raft nodes before linearized reading' (duration: 145.657748ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:22.808617Z","caller":"traceutil/trace.go:171","msg":"trace[520325703] transaction","detail":"{read_only:false; response_revision:4265; number_of_response:1; }","duration":"260.480162ms","start":"2026-07-24T21:35:22.548017Z","end":"2026-07-24T21:35:22.808497Z","steps":["trace[520325703] 'process raft request' (duration: 259.444621ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:23.054642Z","caller":"traceutil/trace.go:171","msg":"trace[1817631382] transaction","detail":"{read_only:false; response_revision:4269; number_of_response:1; }","duration":"142.499868ms","start":"2026-07-24T21:35:22.912106Z","end":"2026-07-24T21:35:23.054606Z","steps":["trace[1817631382] 'process raft request' (duration: 79.317167ms)","trace[1817631382] 'compare' (duration: 62.930896ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:23.054823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.733658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d6453b1e-a79e-4c18-b6c0-9c375d50dde4\" ","response":"range_response_count:1 size:3736"} {"level":"info","ts":"2026-07-24T21:35:23.054903Z","caller":"traceutil/trace.go:171","msg":"trace[372744945] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d6453b1e-a79e-4c18-b6c0-9c375d50dde4; range_end:; response_count:1; response_revision:4269; }","duration":"118.84645ms","start":"2026-07-24T21:35:22.936036Z","end":"2026-07-24T21:35:23.054882Z","steps":["trace[372744945] 'agreement among raft nodes before linearized reading' (duration: 118.648416ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:23.05501Z","caller":"traceutil/trace.go:171","msg":"trace[16645268] linearizableReadLoop","detail":"{readStateIndex:4461; appliedIndex:4460; }","duration":"118.465541ms","start":"2026-07-24T21:35:22.936065Z","end":"2026-07-24T21:35:23.05453Z","steps":["trace[16645268] 'read index received' (duration: 55.386683ms)","trace[16645268] 'applied index is now lower than readState.Index' (duration: 63.077048ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:35:23.0818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.108689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-24T21:35:23.081846Z","caller":"traceutil/trace.go:171","msg":"trace[2026779405] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4270; }","duration":"144.154539ms","start":"2026-07-24T21:35:22.937679Z","end":"2026-07-24T21:35:23.081833Z","steps":["trace[2026779405] 'agreement among raft nodes before linearized reading' (duration: 144.019707ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:26.1246Z","caller":"traceutil/trace.go:171","msg":"trace[97668704] transaction","detail":"{read_only:false; response_revision:4300; number_of_response:1; }","duration":"185.105838ms","start":"2026-07-24T21:35:25.93946Z","end":"2026-07-24T21:35:26.124566Z","steps":["trace[97668704] 'process raft request' (duration: 184.903374ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:26.154127Z","caller":"traceutil/trace.go:171","msg":"trace[1868052070] transaction","detail":"{read_only:false; response_revision:4301; number_of_response:1; }","duration":"156.136957ms","start":"2026-07-24T21:35:25.997957Z","end":"2026-07-24T21:35:26.154094Z","steps":["trace[1868052070] 'process raft request' (duration: 136.106177ms)","trace[1868052070] 'compare' (duration: 19.819716ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:26.154407Z","caller":"traceutil/trace.go:171","msg":"trace[995450053] transaction","detail":"{read_only:false; response_revision:4302; number_of_response:1; }","duration":"148.501623ms","start":"2026-07-24T21:35:26.00583Z","end":"2026-07-24T21:35:26.154332Z","steps":["trace[995450053] 'process raft request' (duration: 148.208287ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:27.005656Z","caller":"traceutil/trace.go:171","msg":"trace[724436548] linearizableReadLoop","detail":"{readStateIndex:4498; appliedIndex:4497; }","duration":"165.218541ms","start":"2026-07-24T21:35:26.840411Z","end":"2026-07-24T21:35:27.005629Z","steps":["trace[724436548] 'read index received' (duration: 164.963946ms)","trace[724436548] 'applied index is now lower than readState.Index' (duration: 253.275µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:27.005778Z","caller":"traceutil/trace.go:171","msg":"trace[115713910] transaction","detail":"{read_only:false; response_revision:4305; number_of_response:1; }","duration":"172.304226ms","start":"2026-07-24T21:35:26.833432Z","end":"2026-07-24T21:35:27.005736Z","steps":["trace[115713910] 'process raft request' (duration: 172.00887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:27.005915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.017432ms","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-07-24T21:35:27.006013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.849191ms","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-07-24T21:35:27.006072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.533169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-07-24T21:35:27.006105Z","caller":"traceutil/trace.go:171","msg":"trace[1943363559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4305; }","duration":"149.261697ms","start":"2026-07-24T21:35:26.856814Z","end":"2026-07-24T21:35:27.006075Z","steps":["trace[1943363559] 'agreement among raft nodes before linearized reading' (duration: 148.964021ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:27.00619Z","caller":"traceutil/trace.go:171","msg":"trace[936867247] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:4305; }","duration":"142.678952ms","start":"2026-07-24T21:35:26.863482Z","end":"2026-07-24T21:35:27.006161Z","steps":["trace[936867247] 'agreement among raft nodes before linearized reading' (duration: 142.362057ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:27.006103Z","caller":"traceutil/trace.go:171","msg":"trace[1639131779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4305; }","duration":"146.966283ms","start":"2026-07-24T21:35:26.859113Z","end":"2026-07-24T21:35:27.00608Z","steps":["trace[1639131779] 'agreement among raft nodes before linearized reading' (duration: 146.734019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:27.006694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.28935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T21:35:27.006735Z","caller":"traceutil/trace.go:171","msg":"trace[1695713829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4305; }","duration":"166.384392ms","start":"2026-07-24T21:35:26.840339Z","end":"2026-07-24T21:35:27.006724Z","steps":["trace[1695713829] 'agreement among raft nodes before linearized reading' (duration: 165.409444ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:27.110938Z","caller":"traceutil/trace.go:171","msg":"trace[597026292] transaction","detail":"{read_only:false; response_revision:4307; number_of_response:1; }","duration":"100.450308ms","start":"2026-07-24T21:35:27.010454Z","end":"2026-07-24T21:35:27.110904Z","steps":["trace[597026292] 'process raft request' (duration: 97.109745ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:27.111255Z","caller":"traceutil/trace.go:171","msg":"trace[1366617315] transaction","detail":"{read_only:false; response_revision:4308; number_of_response:1; }","duration":"100.203865ms","start":"2026-07-24T21:35:27.011033Z","end":"2026-07-24T21:35:27.111237Z","steps":["trace[1366617315] 'process raft request' (duration: 99.805627ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:35:58.486859Z","caller":"traceutil/trace.go:171","msg":"trace[216717805] transaction","detail":"{read_only:false; response_revision:4557; number_of_response:1; }","duration":"186.659562ms","start":"2026-07-24T21:35:58.300165Z","end":"2026-07-24T21:35:58.486825Z","steps":["trace[216717805] 'process raft request' (duration: 97.921982ms)","trace[216717805] 'compare' (duration: 88.591037ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:58.509699Z","caller":"traceutil/trace.go:171","msg":"trace[1608222846] linearizableReadLoop","detail":"{readStateIndex:4760; appliedIndex:4758; }","duration":"121.607761ms","start":"2026-07-24T21:35:58.38804Z","end":"2026-07-24T21:35:58.509648Z","steps":["trace[1608222846] 'read index received' (duration: 10.0389ms)","trace[1608222846] 'applied index is now lower than readState.Index' (duration: 111.566461ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:35:58.509773Z","caller":"traceutil/trace.go:171","msg":"trace[1126302675] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"205.536559ms","start":"2026-07-24T21:35:58.304207Z","end":"2026-07-24T21:35:58.509743Z","steps":["trace[1126302675] 'process raft request' (duration: 205.215963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:35:58.509972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.915207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-24T21:35:58.510081Z","caller":"traceutil/trace.go:171","msg":"trace[53791406] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4558; }","duration":"122.090971ms","start":"2026-07-24T21:35:58.387967Z","end":"2026-07-24T21:35:58.510058Z","steps":["trace[53791406] 'agreement among raft nodes before linearized reading' (duration: 121.832476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:03.959132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.544777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-24T21:36:03.959588Z","caller":"traceutil/trace.go:171","msg":"trace[79888168] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:4589; }","duration":"179.904704ms","start":"2026-07-24T21:36:03.779477Z","end":"2026-07-24T21:36:03.959382Z","steps":["trace[79888168] 'range keys from in-memory index tree' (duration: 178.901386ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.234056Z","caller":"traceutil/trace.go:171","msg":"trace[1453038903] linearizableReadLoop","detail":"{readStateIndex:4794; appliedIndex:4792; }","duration":"204.804555ms","start":"2026-07-24T21:36:04.029223Z","end":"2026-07-24T21:36:04.234027Z","steps":["trace[1453038903] 'read index received' (duration: 83.307866ms)","trace[1453038903] 'applied index is now lower than readState.Index' (duration: 121.495769ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:36:04.234303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.07242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18745"} {"level":"info","ts":"2026-07-24T21:36:04.234339Z","caller":"traceutil/trace.go:171","msg":"trace[1266115201] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4590; }","duration":"205.170762ms","start":"2026-07-24T21:36:04.029159Z","end":"2026-07-24T21:36:04.23433Z","steps":["trace[1266115201] 'agreement among raft nodes before linearized reading' (duration: 204.964749ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.23436Z","caller":"traceutil/trace.go:171","msg":"trace[1045065226] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"267.573923ms","start":"2026-07-24T21:36:03.966768Z","end":"2026-07-24T21:36:04.234342Z","steps":["trace[1045065226] 'process raft request' (duration: 265.347171ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.252981Z","caller":"traceutil/trace.go:171","msg":"trace[1967350314] transaction","detail":"{read_only:false; response_revision:4591; number_of_response:1; }","duration":"138.939469ms","start":"2026-07-24T21:36:04.114014Z","end":"2026-07-24T21:36:04.252953Z","steps":["trace[1967350314] 'process raft request' (duration: 138.748186ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.402323Z","caller":"traceutil/trace.go:171","msg":"trace[984860582] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"160.67953ms","start":"2026-07-24T21:36:04.241611Z","end":"2026-07-24T21:36:04.402291Z","steps":["trace[984860582] 'process raft request' (duration: 160.390145ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.402417Z","caller":"traceutil/trace.go:171","msg":"trace[672004935] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"160.65408ms","start":"2026-07-24T21:36:04.241708Z","end":"2026-07-24T21:36:04.402362Z","steps":["trace[672004935] 'process raft request' (duration: 160.468546ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.402591Z","caller":"traceutil/trace.go:171","msg":"trace[1101222730] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"160.73074ms","start":"2026-07-24T21:36:04.241839Z","end":"2026-07-24T21:36:04.402569Z","steps":["trace[1101222730] 'process raft request' (duration: 160.385304ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.402697Z","caller":"traceutil/trace.go:171","msg":"trace[2016523212] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"158.805804ms","start":"2026-07-24T21:36:04.243859Z","end":"2026-07-24T21:36:04.402665Z","steps":["trace[2016523212] 'process raft request' (duration: 158.737573ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.402755Z","caller":"traceutil/trace.go:171","msg":"trace[1687163351] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"159.09304ms","start":"2026-07-24T21:36:04.243594Z","end":"2026-07-24T21:36:04.402687Z","steps":["trace[1687163351] 'process raft request' (duration: 158.895596ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.403304Z","caller":"traceutil/trace.go:171","msg":"trace[1165020826] linearizableReadLoop","detail":"{readStateIndex:4800; appliedIndex:4800; }","duration":"148.591412ms","start":"2026-07-24T21:36:04.254699Z","end":"2026-07-24T21:36:04.40329Z","steps":["trace[1165020826] 'read index received' (duration: 148.583502ms)","trace[1165020826] 'applied index is now lower than readState.Index' (duration: 5.35µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:36:04.40352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.799525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-24T21:36:04.403559Z","caller":"traceutil/trace.go:171","msg":"trace[29937258] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4596; }","duration":"148.857437ms","start":"2026-07-24T21:36:04.254692Z","end":"2026-07-24T21:36:04.40355Z","steps":["trace[29937258] 'agreement among raft nodes before linearized reading' (duration: 148.651323ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.452712Z","caller":"traceutil/trace.go:171","msg":"trace[1212373634] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"150.467267ms","start":"2026-07-24T21:36:04.302219Z","end":"2026-07-24T21:36:04.452686Z","steps":["trace[1212373634] 'process raft request' (duration: 150.211142ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.611245Z","caller":"traceutil/trace.go:171","msg":"trace[1572177993] linearizableReadLoop","detail":"{readStateIndex:4802; appliedIndex:4801; }","duration":"156.271827ms","start":"2026-07-24T21:36:04.45494Z","end":"2026-07-24T21:36:04.611212Z","steps":["trace[1572177993] 'read index received' (duration: 156.055753ms)","trace[1572177993] 'applied index is now lower than readState.Index' (duration: 214.554µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:36:04.611446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.498452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:36:04.611451Z","caller":"traceutil/trace.go:171","msg":"trace[2013673964] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"197.884945ms","start":"2026-07-24T21:36:04.413473Z","end":"2026-07-24T21:36:04.611358Z","steps":["trace[2013673964] 'process raft request' (duration: 197.493667ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.611528Z","caller":"traceutil/trace.go:171","msg":"trace[576983878] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:4598; }","duration":"156.665045ms","start":"2026-07-24T21:36:04.454844Z","end":"2026-07-24T21:36:04.611509Z","steps":["trace[576983878] 'agreement among raft nodes before linearized reading' (duration: 156.492811ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:04.619938Z","caller":"traceutil/trace.go:171","msg":"trace[888437930] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"114.738041ms","start":"2026-07-24T21:36:04.505181Z","end":"2026-07-24T21:36:04.619919Z","steps":["trace[888437930] 'process raft request' (duration: 114.429886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:04.620669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.439249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:36:04.620718Z","caller":"traceutil/trace.go:171","msg":"trace[548707747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4600; }","duration":"147.530801ms","start":"2026-07-24T21:36:04.473174Z","end":"2026-07-24T21:36:04.620704Z","steps":["trace[548707747] 'agreement among raft nodes before linearized reading' (duration: 146.648665ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:40.968172Z","caller":"traceutil/trace.go:171","msg":"trace[1210421688] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"155.568293ms","start":"2026-07-24T21:36:40.812567Z","end":"2026-07-24T21:36:40.968136Z","steps":["trace[1210421688] 'process raft request' (duration: 155.464311ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:40.968202Z","caller":"traceutil/trace.go:171","msg":"trace[1245814815] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"167.5063ms","start":"2026-07-24T21:36:40.800644Z","end":"2026-07-24T21:36:40.968151Z","steps":["trace[1245814815] 'process raft request' (duration: 167.196234ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:41.028793Z","caller":"traceutil/trace.go:171","msg":"trace[596478914] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5073; }","duration":"167.306936ms","start":"2026-07-24T21:36:40.861454Z","end":"2026-07-24T21:36:41.02876Z","steps":["trace[596478914] 'read index received' (duration: 106.501445ms)","trace[596478914] 'applied index is now lower than readState.Index' (duration: 60.803851ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:36:41.028956Z","caller":"traceutil/trace.go:171","msg":"trace[734269590] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"193.756076ms","start":"2026-07-24T21:36:40.835185Z","end":"2026-07-24T21:36:41.028941Z","steps":["trace[734269590] 'process raft request' (duration: 193.330878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:41.029189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.721524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-24T21:36:41.029206Z","caller":"traceutil/trace.go:171","msg":"trace[1277056537] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"142.71024ms","start":"2026-07-24T21:36:40.88645Z","end":"2026-07-24T21:36:41.029161Z","steps":["trace[1277056537] 'process raft request' (duration: 142.231601ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:41.029304Z","caller":"traceutil/trace.go:171","msg":"trace[650958124] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:4866; }","duration":"167.884886ms","start":"2026-07-24T21:36:40.861395Z","end":"2026-07-24T21:36:41.02928Z","steps":["trace[650958124] 'agreement among raft nodes before linearized reading' (duration: 167.55589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:41.029395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.947985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:36:41.029442Z","caller":"traceutil/trace.go:171","msg":"trace[1568915837] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4866; }","duration":"161.039867ms","start":"2026-07-24T21:36:40.868388Z","end":"2026-07-24T21:36:41.029428Z","steps":["trace[1568915837] 'agreement among raft nodes before linearized reading' (duration: 160.938295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:41.029604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.374203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T21:36:41.029721Z","caller":"traceutil/trace.go:171","msg":"trace[1099160360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4866; }","duration":"133.497925ms","start":"2026-07-24T21:36:40.896195Z","end":"2026-07-24T21:36:41.029693Z","steps":["trace[1099160360] 'agreement among raft nodes before linearized reading' (duration: 133.22361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:41.624646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.463647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:36:41.624887Z","caller":"traceutil/trace.go:171","msg":"trace[930597840] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"391.024969ms","start":"2026-07-24T21:36:41.233808Z","end":"2026-07-24T21:36:41.624833Z","steps":["trace[930597840] 'process raft request' (duration: 202.289637ms)","trace[930597840] 'compare' (duration: 188.294963ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:36:41.624949Z","caller":"traceutil/trace.go:171","msg":"trace[841966518] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"163.534305ms","start":"2026-07-24T21:36:41.461393Z","end":"2026-07-24T21:36:41.624927Z","steps":["trace[841966518] 'process raft request' (duration: 163.451194ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:41.624947Z","caller":"traceutil/trace.go:171","msg":"trace[951707238] linearizableReadLoop","detail":"{readStateIndex:5081; appliedIndex:5080; }","duration":"317.328335ms","start":"2026-07-24T21:36:41.307589Z","end":"2026-07-24T21:36:41.624917Z","steps":["trace[951707238] 'read index received' (duration: 128.526672ms)","trace[951707238] 'applied index is now lower than readState.Index' (duration: 188.798943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:36:41.62519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.579129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:36:41.625246Z","caller":"traceutil/trace.go:171","msg":"trace[932015680] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4871; }","duration":"317.682322ms","start":"2026-07-24T21:36:41.307547Z","end":"2026-07-24T21:36:41.625229Z","steps":["trace[932015680] 'agreement among raft nodes before linearized reading' (duration: 317.520369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:41.625281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:36:41.307534Z","time spent":"317.737193ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":16,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-24T21:36:41.625696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:36:41.233784Z","time spent":"391.208062ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1250,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:36:43.278894Z","caller":"traceutil/trace.go:171","msg":"trace[437586319] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"204.576381ms","start":"2026-07-24T21:36:43.074263Z","end":"2026-07-24T21:36:43.278839Z","steps":["trace[437586319] 'process raft request' (duration: 120.112173ms)","trace[437586319] 'compare' (duration: 84.230484ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:36:43.542279Z","caller":"traceutil/trace.go:171","msg":"trace[1050390836] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"257.036853ms","start":"2026-07-24T21:36:43.285218Z","end":"2026-07-24T21:36:43.542255Z","steps":["trace[1050390836] 'process raft request' (duration: 256.8528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:44.038359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.904562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"warn","ts":"2026-07-24T21:36:44.038382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.631306ms","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-07-24T21:36:44.03847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.304009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-24T21:36:44.038482Z","caller":"traceutil/trace.go:171","msg":"trace[1957293537] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:4936; }","duration":"228.085525ms","start":"2026-07-24T21:36:43.810372Z","end":"2026-07-24T21:36:44.038457Z","steps":["trace[1957293537] 'range keys from in-memory index tree' (duration: 227.669618ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:44.038504Z","caller":"traceutil/trace.go:171","msg":"trace[390730336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4936; }","duration":"256.836479ms","start":"2026-07-24T21:36:43.781641Z","end":"2026-07-24T21:36:44.038477Z","steps":["trace[390730336] 'range keys from in-memory index tree' (duration: 256.500453ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:44.038519Z","caller":"traceutil/trace.go:171","msg":"trace[1364441628] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:4936; }","duration":"257.38415ms","start":"2026-07-24T21:36:43.78112Z","end":"2026-07-24T21:36:44.038504Z","steps":["trace[1364441628] 'range keys from in-memory index tree' (duration: 257.136696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:44.038482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.275968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:36:44.038605Z","caller":"traceutil/trace.go:171","msg":"trace[1922040245] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4936; }","duration":"202.469381ms","start":"2026-07-24T21:36:43.83611Z","end":"2026-07-24T21:36:44.038579Z","steps":["trace[1922040245] 'range keys from in-memory index tree' (duration: 202.114684ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:45.074639Z","caller":"traceutil/trace.go:171","msg":"trace[288892960] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"225.199706ms","start":"2026-07-24T21:36:44.849411Z","end":"2026-07-24T21:36:45.07461Z","steps":["trace[288892960] 'process raft request' (duration: 139.118324ms)","trace[288892960] 'compare' (duration: 85.864488ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:36:45.821011Z","caller":"traceutil/trace.go:171","msg":"trace[450125535] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"157.440514ms","start":"2026-07-24T21:36:45.663538Z","end":"2026-07-24T21:36:45.820979Z","steps":["trace[450125535] 'process raft request' (duration: 157.225939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:46.016168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.973951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:36:46.016296Z","caller":"traceutil/trace.go:171","msg":"trace[1648485980] linearizableReadLoop","detail":"{readStateIndex:5156; appliedIndex:5154; }","duration":"319.609166ms","start":"2026-07-24T21:36:45.696665Z","end":"2026-07-24T21:36:46.016274Z","steps":["trace[1648485980] 'read index received' (duration: 124.129897ms)","trace[1648485980] 'applied index is now lower than readState.Index' (duration: 195.477729ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:36:46.016602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.377679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:36:46.016649Z","caller":"traceutil/trace.go:171","msg":"trace[1403555538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4944; }","duration":"310.485721ms","start":"2026-07-24T21:36:45.706148Z","end":"2026-07-24T21:36:46.016634Z","steps":["trace[1403555538] 'agreement among raft nodes before linearized reading' (duration: 310.296797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:46.016683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:36:45.706125Z","time spent":"310.550682ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T21:36:46.016754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.054994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:36:46.01684Z","caller":"traceutil/trace.go:171","msg":"trace[155718370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4944; }","duration":"320.202807ms","start":"2026-07-24T21:36:45.696614Z","end":"2026-07-24T21:36:46.016817Z","steps":["trace[155718370] 'agreement among raft nodes before linearized reading' (duration: 319.931052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:46.01685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.015512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-24T21:36:46.016907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:36:45.696593Z","time spent":"320.297818ms","remote":"127.0.0.1:37116","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-07-24T21:36:46.01694Z","caller":"traceutil/trace.go:171","msg":"trace[1407500632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4944; }","duration":"310.190036ms","start":"2026-07-24T21:36:45.706727Z","end":"2026-07-24T21:36:46.016917Z","steps":["trace[1407500632] 'agreement among raft nodes before linearized reading' (duration: 309.88752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:46.016992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:36:45.706717Z","time spent":"310.266257ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T21:36:46.016965Z","caller":"traceutil/trace.go:171","msg":"trace[1595577616] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"331.083694ms","start":"2026-07-24T21:36:45.685864Z","end":"2026-07-24T21:36:46.016947Z","steps":["trace[1595577616] 'process raft request' (duration: 148.222847ms)","trace[1595577616] 'compare' (duration: 181.641105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:36:46.017108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:36:45.685828Z","time spent":"331.204848ms","remote":"127.0.0.1:37116","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-07-24T21:36:48.266228Z","caller":"traceutil/trace.go:171","msg":"trace[4882611] linearizableReadLoop","detail":"{readStateIndex:5170; appliedIndex:5168; }","duration":"141.618701ms","start":"2026-07-24T21:36:48.124578Z","end":"2026-07-24T21:36:48.266197Z","steps":["trace[4882611] 'read index received' (duration: 88.972297ms)","trace[4882611] 'applied index is now lower than readState.Index' (duration: 52.644854ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:36:48.266321Z","caller":"traceutil/trace.go:171","msg":"trace[79447692] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"148.756807ms","start":"2026-07-24T21:36:48.117512Z","end":"2026-07-24T21:36:48.266268Z","steps":["trace[79447692] 'process raft request' (duration: 148.425591ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:48.266543Z","caller":"traceutil/trace.go:171","msg":"trace[1819774026] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"147.841039ms","start":"2026-07-24T21:36:48.118641Z","end":"2026-07-24T21:36:48.266482Z","steps":["trace[1819774026] 'process raft request' (duration: 147.501873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:48.26685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.247643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T21:36:48.266913Z","caller":"traceutil/trace.go:171","msg":"trace[1580041421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4958; }","duration":"142.338925ms","start":"2026-07-24T21:36:48.124559Z","end":"2026-07-24T21:36:48.266898Z","steps":["trace[1580041421] 'agreement among raft nodes before linearized reading' (duration: 142.07794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:48.266924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.157909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-354dddfe-41cf-4f7d-b366-b29277c3e0c0\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-07-24T21:36:48.266969Z","caller":"traceutil/trace.go:171","msg":"trace[1239515490] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-354dddfe-41cf-4f7d-b366-b29277c3e0c0; range_end:; response_count:1; response_revision:4958; }","duration":"135.2481ms","start":"2026-07-24T21:36:48.131706Z","end":"2026-07-24T21:36:48.266954Z","steps":["trace[1239515490] 'agreement among raft nodes before linearized reading' (duration: 135.055156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:36:48.267362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.905496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-07-24T21:36:48.267587Z","caller":"traceutil/trace.go:171","msg":"trace[1110726836] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:4958; }","duration":"133.14339ms","start":"2026-07-24T21:36:48.134414Z","end":"2026-07-24T21:36:48.267558Z","steps":["trace[1110726836] 'agreement among raft nodes before linearized reading' (duration: 132.691452ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:36:58.983972Z","caller":"traceutil/trace.go:171","msg":"trace[351953288] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"218.671591ms","start":"2026-07-24T21:36:58.76527Z","end":"2026-07-24T21:36:58.983941Z","steps":["trace[351953288] 'process raft request' (duration: 218.330015ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:37:47.8498Z","caller":"traceutil/trace.go:171","msg":"trace[2099550614] linearizableReadLoop","detail":"{readStateIndex:5555; appliedIndex:5554; }","duration":"110.13197ms","start":"2026-07-24T21:37:47.739634Z","end":"2026-07-24T21:37:47.849766Z","steps":["trace[2099550614] 'read index received' (duration: 109.871395ms)","trace[2099550614] 'applied index is now lower than readState.Index' (duration: 259.485µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:37:47.849871Z","caller":"traceutil/trace.go:171","msg":"trace[473562333] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"204.415059ms","start":"2026-07-24T21:37:47.645408Z","end":"2026-07-24T21:37:47.849823Z","steps":["trace[473562333] 'process raft request' (duration: 204.177035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:37:47.850168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.505367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T21:37:47.850274Z","caller":"traceutil/trace.go:171","msg":"trace[144300737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5330; }","duration":"110.69158ms","start":"2026-07-24T21:37:47.73956Z","end":"2026-07-24T21:37:47.850251Z","steps":["trace[144300737] 'agreement among raft nodes before linearized reading' (duration: 110.369214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:37:48.087389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.242644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:37:48.087525Z","caller":"traceutil/trace.go:171","msg":"trace[1825891499] range","detail":"{range_begin:/registry/configmaps/openstack/keystone-openid-metadata; range_end:; response_count:0; response_revision:5331; }","duration":"108.644732ms","start":"2026-07-24T21:37:47.97885Z","end":"2026-07-24T21:37:48.087495Z","steps":["trace[1825891499] 'range keys from in-memory index tree' (duration: 108.039861ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:37:54.057741Z","caller":"traceutil/trace.go:171","msg":"trace[1576574661] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"132.511968ms","start":"2026-07-24T21:37:53.925201Z","end":"2026-07-24T21:37:54.057713Z","steps":["trace[1576574661] 'process raft request' (duration: 132.345145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:05.5254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.601322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:38:05.525504Z","caller":"traceutil/trace.go:171","msg":"trace[1484959191] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5423; }","duration":"106.755685ms","start":"2026-07-24T21:38:05.418721Z","end":"2026-07-24T21:38:05.525477Z","steps":["trace[1484959191] 'count revisions from in-memory index tree' (duration: 106.523051ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:05.624686Z","caller":"traceutil/trace.go:171","msg":"trace[696053215] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"137.563925ms","start":"2026-07-24T21:38:05.487062Z","end":"2026-07-24T21:38:05.624626Z","steps":["trace[696053215] 'process raft request' (duration: 137.30061ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:05.830947Z","caller":"traceutil/trace.go:171","msg":"trace[2062446709] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"154.075041ms","start":"2026-07-24T21:38:05.676822Z","end":"2026-07-24T21:38:05.830897Z","steps":["trace[2062446709] 'process raft request' (duration: 153.825206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:46.024082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.180881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:38:46.02525Z","caller":"traceutil/trace.go:171","msg":"trace[512146518] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5635; }","duration":"122.441296ms","start":"2026-07-24T21:38:45.90278Z","end":"2026-07-24T21:38:46.025222Z","steps":["trace[512146518] 'count revisions from in-memory index tree' (duration: 121.052168ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:19.812993Z","caller":"traceutil/trace.go:171","msg":"trace[2040438710] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"105.191226ms","start":"2026-07-24T21:39:19.707777Z","end":"2026-07-24T21:39:19.812968Z","steps":["trace[2040438710] 'process raft request' (duration: 105.034423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:25.205489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.556231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:25.205615Z","caller":"traceutil/trace.go:171","msg":"trace[1030214173] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5883; }","duration":"119.749954ms","start":"2026-07-24T21:39:25.085838Z","end":"2026-07-24T21:39:25.205588Z","steps":["trace[1030214173] 'count revisions from in-memory index tree' (duration: 119.371867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:40.190775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.399094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:39:40.191143Z","caller":"traceutil/trace.go:171","msg":"trace[574731010] transaction","detail":"{read_only:false; response_revision:6013; number_of_response:1; }","duration":"158.604154ms","start":"2026-07-24T21:39:40.032518Z","end":"2026-07-24T21:39:40.191122Z","steps":["trace[574731010] 'process raft request' (duration: 158.536823ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:40.19111Z","caller":"traceutil/trace.go:171","msg":"trace[1943950413] transaction","detail":"{read_only:false; response_revision:6011; number_of_response:1; }","duration":"166.884932ms","start":"2026-07-24T21:39:40.024187Z","end":"2026-07-24T21:39:40.191072Z","steps":["trace[1943950413] 'process raft request' (duration: 166.75242ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:40.191277Z","caller":"traceutil/trace.go:171","msg":"trace[1608733366] transaction","detail":"{read_only:false; response_revision:6012; number_of_response:1; }","duration":"166.893222ms","start":"2026-07-24T21:39:40.024334Z","end":"2026-07-24T21:39:40.191228Z","steps":["trace[1608733366] 'process raft request' (duration: 166.681258ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:40.191575Z","caller":"traceutil/trace.go:171","msg":"trace[602899119] transaction","detail":"{read_only:false; response_revision:6010; number_of_response:1; }","duration":"167.079255ms","start":"2026-07-24T21:39:40.023946Z","end":"2026-07-24T21:39:40.191025Z","steps":["trace[602899119] 'process raft request' (duration: 23.269683ms)","trace[602899119] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/rolebindings/openstack/keystone-fernet-setup; req_size:717; } (duration: 142.677801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:41.663278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.043131ms","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-07-24T21:39:41.663377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.006384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:41.663417Z","caller":"traceutil/trace.go:171","msg":"trace[190548627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6051; }","duration":"107.335996ms","start":"2026-07-24T21:39:41.55605Z","end":"2026-07-24T21:39:41.663386Z","steps":["trace[190548627] 'range keys from in-memory index tree' (duration: 106.717865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:41.663385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.099252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:39:41.663502Z","caller":"traceutil/trace.go:171","msg":"trace[36682062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6051; }","duration":"107.282395ms","start":"2026-07-24T21:39:41.556194Z","end":"2026-07-24T21:39:41.663477Z","steps":["trace[36682062] 'range keys from in-memory index tree' (duration: 106.829877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:41.663549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.444723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:41.663571Z","caller":"traceutil/trace.go:171","msg":"trace[802471828] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:6051; }","duration":"200.520245ms","start":"2026-07-24T21:39:41.463044Z","end":"2026-07-24T21:39:41.663565Z","steps":["trace[802471828] 'count revisions from in-memory index tree' (duration: 200.353381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:41.663581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.986945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T21:39:41.663681Z","caller":"traceutil/trace.go:171","msg":"trace[387336806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6051; }","duration":"167.137197ms","start":"2026-07-24T21:39:41.496523Z","end":"2026-07-24T21:39:41.663661Z","steps":["trace[387336806] 'range keys from in-memory index tree' (duration: 166.807271ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:41.663449Z","caller":"traceutil/trace.go:171","msg":"trace[1931883795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6051; }","duration":"190.249158ms","start":"2026-07-24T21:39:41.473183Z","end":"2026-07-24T21:39:41.663432Z","steps":["trace[1931883795] 'range keys from in-memory index tree' (duration: 189.894571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:41.663748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.53615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:39:41.663834Z","caller":"traceutil/trace.go:171","msg":"trace[2014541721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6051; }","duration":"107.676343ms","start":"2026-07-24T21:39:41.556135Z","end":"2026-07-24T21:39:41.663811Z","steps":["trace[2014541721] 'range keys from in-memory index tree' (duration: 107.292455ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:41.803839Z","caller":"traceutil/trace.go:171","msg":"trace[2010542887] transaction","detail":"{read_only:false; response_revision:6052; number_of_response:1; }","duration":"134.77792ms","start":"2026-07-24T21:39:41.669003Z","end":"2026-07-24T21:39:41.803781Z","steps":["trace[2010542887] 'process raft request' (duration: 134.457884ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:41.935762Z","caller":"traceutil/trace.go:171","msg":"trace[957729899] transaction","detail":"{read_only:false; response_revision:6053; number_of_response:1; }","duration":"266.176695ms","start":"2026-07-24T21:39:41.669559Z","end":"2026-07-24T21:39:41.935736Z","steps":["trace[957729899] 'process raft request' (duration: 265.962451ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:41.936235Z","caller":"traceutil/trace.go:171","msg":"trace[294564834] transaction","detail":"{read_only:false; response_revision:6055; number_of_response:1; }","duration":"266.581484ms","start":"2026-07-24T21:39:41.669642Z","end":"2026-07-24T21:39:41.936223Z","steps":["trace[294564834] 'process raft request' (duration: 266.348209ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:41.936348Z","caller":"traceutil/trace.go:171","msg":"trace[789995591] transaction","detail":"{read_only:false; response_revision:6054; number_of_response:1; }","duration":"266.751786ms","start":"2026-07-24T21:39:41.669559Z","end":"2026-07-24T21:39:41.936311Z","steps":["trace[789995591] 'process raft request' (duration: 266.130725ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:41.93656Z","caller":"traceutil/trace.go:171","msg":"trace[27013188] transaction","detail":"{read_only:false; response_revision:6056; number_of_response:1; }","duration":"263.426353ms","start":"2026-07-24T21:39:41.673124Z","end":"2026-07-24T21:39:41.93655Z","steps":["trace[27013188] 'process raft request' (duration: 263.035555ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:41.936562Z","caller":"traceutil/trace.go:171","msg":"trace[87660815] transaction","detail":"{read_only:false; response_revision:6057; number_of_response:1; }","duration":"245.019401ms","start":"2026-07-24T21:39:41.691504Z","end":"2026-07-24T21:39:41.936523Z","steps":["trace[87660815] 'process raft request' (duration: 244.781537ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:41.936637Z","caller":"traceutil/trace.go:171","msg":"trace[1210383668] linearizableReadLoop","detail":"{readStateIndex:6311; appliedIndex:6305; }","duration":"211.249658ms","start":"2026-07-24T21:39:41.725316Z","end":"2026-07-24T21:39:41.936566Z","steps":["trace[1210383668] 'read index received' (duration: 78.180261ms)","trace[1210383668] 'applied index is now lower than readState.Index' (duration: 133.068177ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:41.936997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.612285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-07-24T21:39:41.937097Z","caller":"traceutil/trace.go:171","msg":"trace[923218394] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:6058; }","duration":"211.81808ms","start":"2026-07-24T21:39:41.725263Z","end":"2026-07-24T21:39:41.937081Z","steps":["trace[923218394] 'agreement among raft nodes before linearized reading' (duration: 211.551664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:42.257474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.519013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:39:42.258138Z","caller":"traceutil/trace.go:171","msg":"trace[2057046230] transaction","detail":"{read_only:false; response_revision:6059; number_of_response:1; }","duration":"224.236166ms","start":"2026-07-24T21:39:42.033863Z","end":"2026-07-24T21:39:42.258099Z","steps":["trace[2057046230] 'process raft request' (duration: 96.97811ms)","trace[2057046230] 'compare' (duration: 126.242757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:43.092861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.607942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:39:43.093131Z","caller":"traceutil/trace.go:171","msg":"trace[619656894] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6061; }","duration":"204.941958ms","start":"2026-07-24T21:39:42.888159Z","end":"2026-07-24T21:39:43.093101Z","steps":["trace[619656894] 'count revisions from in-memory index tree' (duration: 204.245635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:43.092971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.705596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:39:43.093326Z","caller":"traceutil/trace.go:171","msg":"trace[914300657] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6061; }","duration":"117.286537ms","start":"2026-07-24T21:39:42.976009Z","end":"2026-07-24T21:39:43.093296Z","steps":["trace[914300657] 'range keys from in-memory index tree' (duration: 116.497842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:43.093389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.16594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:43.093452Z","caller":"traceutil/trace.go:171","msg":"trace[533178478] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:6061; }","duration":"154.261191ms","start":"2026-07-24T21:39:42.93917Z","end":"2026-07-24T21:39:43.093431Z","steps":["trace[533178478] 'count revisions from in-memory index tree' (duration: 154.035947ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:43.371001Z","caller":"traceutil/trace.go:171","msg":"trace[1451270145] linearizableReadLoop","detail":"{readStateIndex:6316; appliedIndex:6315; }","duration":"203.730824ms","start":"2026-07-24T21:39:43.167245Z","end":"2026-07-24T21:39:43.370976Z","steps":["trace[1451270145] 'read index received' (duration: 203.683973ms)","trace[1451270145] 'applied index is now lower than readState.Index' (duration: 44.691µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:43.371155Z","caller":"traceutil/trace.go:171","msg":"trace[2111745265] transaction","detail":"{read_only:false; response_revision:6062; number_of_response:1; }","duration":"251.012576ms","start":"2026-07-24T21:39:43.120073Z","end":"2026-07-24T21:39:43.371086Z","steps":["trace[2111745265] 'process raft request' (duration: 250.756371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:43.371203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.950538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:43.37139Z","caller":"traceutil/trace.go:171","msg":"trace[744795435] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6062; }","duration":"204.210994ms","start":"2026-07-24T21:39:43.167166Z","end":"2026-07-24T21:39:43.371377Z","steps":["trace[744795435] 'agreement among raft nodes before linearized reading' (duration: 203.977839ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:43.42723Z","caller":"traceutil/trace.go:171","msg":"trace[1591510983] transaction","detail":"{read_only:false; response_revision:6063; number_of_response:1; }","duration":"140.841565ms","start":"2026-07-24T21:39:43.28635Z","end":"2026-07-24T21:39:43.427192Z","steps":["trace[1591510983] 'process raft request' (duration: 138.794547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:43.427498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.105718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:43.427565Z","caller":"traceutil/trace.go:171","msg":"trace[8124144] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:6063; }","duration":"155.236561ms","start":"2026-07-24T21:39:43.272309Z","end":"2026-07-24T21:39:43.427545Z","steps":["trace[8124144] 'agreement among raft nodes before linearized reading' (duration: 155.137049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:43.427748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.389572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:43.428056Z","caller":"traceutil/trace.go:171","msg":"trace[1601137357] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:6063; }","duration":"104.717238ms","start":"2026-07-24T21:39:43.323315Z","end":"2026-07-24T21:39:43.428032Z","steps":["trace[1601137357] 'agreement among raft nodes before linearized reading' (duration: 104.370261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:43.733688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.02729ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:43.733855Z","caller":"traceutil/trace.go:171","msg":"trace[165794935] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6063; }","duration":"205.208953ms","start":"2026-07-24T21:39:43.528624Z","end":"2026-07-24T21:39:43.733833Z","steps":["trace[165794935] 'range keys from in-memory index tree' (duration: 205.000359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:43.73387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.338639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:39:43.733959Z","caller":"traceutil/trace.go:171","msg":"trace[96714126] linearizableReadLoop","detail":"{readStateIndex:6318; appliedIndex:6317; }","duration":"150.020841ms","start":"2026-07-24T21:39:43.583923Z","end":"2026-07-24T21:39:43.733944Z","steps":["trace[96714126] 'read index received' (duration: 23.541249ms)","trace[96714126] 'applied index is now lower than readState.Index' (duration: 126.478282ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:43.73403Z","caller":"traceutil/trace.go:171","msg":"trace[1788263553] transaction","detail":"{read_only:false; response_revision:6064; number_of_response:1; }","duration":"259.428797ms","start":"2026-07-24T21:39:43.474527Z","end":"2026-07-24T21:39:43.733956Z","steps":["trace[1788263553] 'process raft request' (duration: 132.924115ms)","trace[1788263553] 'compare' (duration: 126.189016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:43.734265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.333407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-api-65bf69fdd7-4qs52\" ","response":"range_response_count:1 size:11743"} {"level":"info","ts":"2026-07-24T21:39:43.734346Z","caller":"traceutil/trace.go:171","msg":"trace[1362820430] range","detail":"{range_begin:/registry/pods/openstack/keystone-api-65bf69fdd7-4qs52; range_end:; response_count:1; response_revision:6064; }","duration":"150.471349ms","start":"2026-07-24T21:39:43.583857Z","end":"2026-07-24T21:39:43.734328Z","steps":["trace[1362820430] 'agreement among raft nodes before linearized reading' (duration: 150.210794ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:43.899828Z","caller":"traceutil/trace.go:171","msg":"trace[798212777] linearizableReadLoop","detail":"{readStateIndex:6319; appliedIndex:6318; }","duration":"107.486709ms","start":"2026-07-24T21:39:43.792323Z","end":"2026-07-24T21:39:43.89981Z","steps":["trace[798212777] 'read index received' (duration: 107.246004ms)","trace[798212777] 'applied index is now lower than readState.Index' (duration: 239.955µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:43.899912Z","caller":"traceutil/trace.go:171","msg":"trace[683855673] transaction","detail":"{read_only:false; response_revision:6065; number_of_response:1; }","duration":"154.532217ms","start":"2026-07-24T21:39:43.745341Z","end":"2026-07-24T21:39:43.899873Z","steps":["trace[683855673] 'process raft request' (duration: 154.341223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:43.900022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.687413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-24T21:39:43.900054Z","caller":"traceutil/trace.go:171","msg":"trace[1799629265] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:6065; }","duration":"107.776284ms","start":"2026-07-24T21:39:43.79227Z","end":"2026-07-24T21:39:43.900047Z","steps":["trace[1799629265] 'agreement among raft nodes before linearized reading' (duration: 107.633142ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.066973Z","caller":"traceutil/trace.go:171","msg":"trace[1134534162] transaction","detail":"{read_only:false; response_revision:6066; number_of_response:1; }","duration":"162.075511ms","start":"2026-07-24T21:39:43.904856Z","end":"2026-07-24T21:39:44.066931Z","steps":["trace[1134534162] 'process raft request' (duration: 161.801656ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.067027Z","caller":"traceutil/trace.go:171","msg":"trace[2137530353] linearizableReadLoop","detail":"{readStateIndex:6323; appliedIndex:6319; }","duration":"125.640146ms","start":"2026-07-24T21:39:43.94136Z","end":"2026-07-24T21:39:44.067Z","steps":["trace[2137530353] 'read index received' (duration: 64.618922ms)","trace[2137530353] 'applied index is now lower than readState.Index' (duration: 61.020454ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:44.067052Z","caller":"traceutil/trace.go:171","msg":"trace[1144025517] transaction","detail":"{read_only:false; response_revision:6067; number_of_response:1; }","duration":"160.102843ms","start":"2026-07-24T21:39:43.906903Z","end":"2026-07-24T21:39:44.067006Z","steps":["trace[1144025517] 'process raft request' (duration: 159.94776ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.067372Z","caller":"traceutil/trace.go:171","msg":"trace[189847248] transaction","detail":"{read_only:false; response_revision:6068; number_of_response:1; }","duration":"160.355777ms","start":"2026-07-24T21:39:43.907004Z","end":"2026-07-24T21:39:44.06736Z","steps":["trace[189847248] 'process raft request' (duration: 159.903829ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.067603Z","caller":"traceutil/trace.go:171","msg":"trace[1788775941] transaction","detail":"{read_only:false; response_revision:6069; number_of_response:1; }","duration":"123.603966ms","start":"2026-07-24T21:39:43.943985Z","end":"2026-07-24T21:39:44.067589Z","steps":["trace[1788775941] 'process raft request' (duration: 122.979015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.067688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.269528ms","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-07-24T21:39:44.067732Z","caller":"traceutil/trace.go:171","msg":"trace[511913411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6069; }","duration":"126.40907ms","start":"2026-07-24T21:39:43.941312Z","end":"2026-07-24T21:39:44.067721Z","steps":["trace[511913411] 'agreement among raft nodes before linearized reading' (duration: 126.220096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.317073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.389254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:39:44.317212Z","caller":"traceutil/trace.go:171","msg":"trace[2063577638] linearizableReadLoop","detail":"{readStateIndex:6325; appliedIndex:6324; }","duration":"250.093239ms","start":"2026-07-24T21:39:44.067097Z","end":"2026-07-24T21:39:44.31719Z","steps":["trace[2063577638] 'read index received' (duration: 116.460031ms)","trace[2063577638] 'applied index is now lower than readState.Index' (duration: 133.630178ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:44.317313Z","caller":"traceutil/trace.go:171","msg":"trace[407709660] transaction","detail":"{read_only:false; response_revision:6070; number_of_response:1; }","duration":"308.512423ms","start":"2026-07-24T21:39:44.008787Z","end":"2026-07-24T21:39:44.317299Z","steps":["trace[407709660] 'process raft request' (duration: 174.783483ms)","trace[407709660] 'compare' (duration: 133.110669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:44.317385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.00877Z","time spent":"308.583964ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:39:44.317554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.427606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-07-24T21:39:44.317629Z","caller":"traceutil/trace.go:171","msg":"trace[1572384940] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:6070; }","duration":"368.538077ms","start":"2026-07-24T21:39:43.949075Z","end":"2026-07-24T21:39:44.317613Z","steps":["trace[1572384940] 'agreement among raft nodes before linearized reading' (duration: 368.282413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.317665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:43.949065Z","time spent":"368.593698ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20585,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" "} {"level":"warn","ts":"2026-07-24T21:39:44.317632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.893619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T21:39:44.317645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.94957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:39:44.317734Z","caller":"traceutil/trace.go:171","msg":"trace[1532706630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6070; }","duration":"375.041182ms","start":"2026-07-24T21:39:43.942664Z","end":"2026-07-24T21:39:44.317705Z","steps":["trace[1532706630] 'agreement among raft nodes before linearized reading' (duration: 374.666225ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.317758Z","caller":"traceutil/trace.go:171","msg":"trace[1187012154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6070; }","duration":"375.115694ms","start":"2026-07-24T21:39:43.942619Z","end":"2026-07-24T21:39:44.317735Z","steps":["trace[1187012154] 'agreement among raft nodes before linearized reading' (duration: 374.741617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.317806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:43.942603Z","time spent":"375.193785ms","remote":"127.0.0.1:37116","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":"warn","ts":"2026-07-24T21:39:44.317804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:43.942634Z","time spent":"375.158605ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T21:39:44.366728Z","caller":"traceutil/trace.go:171","msg":"trace[1638650712] transaction","detail":"{read_only:false; response_revision:6071; number_of_response:1; }","duration":"293.377935ms","start":"2026-07-24T21:39:44.073324Z","end":"2026-07-24T21:39:44.366702Z","steps":["trace[1638650712] 'process raft request' (duration: 293.208932ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.646608Z","caller":"traceutil/trace.go:171","msg":"trace[1757308802] transaction","detail":"{read_only:false; response_revision:6073; number_of_response:1; }","duration":"382.451014ms","start":"2026-07-24T21:39:44.264122Z","end":"2026-07-24T21:39:44.646573Z","steps":["trace[1757308802] 'process raft request' (duration: 382.340102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.646833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.264107Z","time spent":"382.607157ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:39:44.646906Z","caller":"traceutil/trace.go:171","msg":"trace[1612997568] transaction","detail":"{read_only:false; response_revision:6074; number_of_response:1; }","duration":"367.636241ms","start":"2026-07-24T21:39:44.279234Z","end":"2026-07-24T21:39:44.64687Z","steps":["trace[1612997568] 'process raft request' (duration: 367.277114ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.647025Z","caller":"traceutil/trace.go:171","msg":"trace[499895579] transaction","detail":"{read_only:false; response_revision:6075; number_of_response:1; }","duration":"324.739613ms","start":"2026-07-24T21:39:44.322262Z","end":"2026-07-24T21:39:44.647002Z","steps":["trace[499895579] 'process raft request' (duration: 324.531229ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.647137Z","caller":"traceutil/trace.go:171","msg":"trace[956262643] transaction","detail":"{read_only:false; response_revision:6076; number_of_response:1; }","duration":"324.462227ms","start":"2026-07-24T21:39:44.32265Z","end":"2026-07-24T21:39:44.647112Z","steps":["trace[956262643] 'process raft request' (duration: 324.310704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.647249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.322634Z","time spent":"324.55811ms","remote":"127.0.0.1:37116","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-07-24T21:39:44.647238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.322235Z","time spent":"324.899616ms","remote":"127.0.0.1:37116","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-07-24T21:39:44.647077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.279222Z","time spent":"367.761863ms","remote":"127.0.0.1:37116","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-07-24T21:39:44.647265Z","caller":"traceutil/trace.go:171","msg":"trace[1745682844] transaction","detail":"{read_only:false; response_revision:6072; number_of_response:1; }","duration":"383.242359ms","start":"2026-07-24T21:39:44.263387Z","end":"2026-07-24T21:39:44.646629Z","steps":["trace[1745682844] 'process raft request' (duration: 382.879702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.647373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.716432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-24T21:39:44.647421Z","caller":"traceutil/trace.go:171","msg":"trace[1229903561] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:6076; }","duration":"378.795174ms","start":"2026-07-24T21:39:44.268612Z","end":"2026-07-24T21:39:44.647407Z","steps":["trace[1229903561] 'agreement among raft nodes before linearized reading' (duration: 378.58854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.647465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.268599Z","time spent":"378.846044ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6471,"request content":"key:\"/registry/pods/openstack/keepalived-lw946\" "} {"level":"warn","ts":"2026-07-24T21:39:44.647747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.26335Z","time spent":"384.023803ms","remote":"127.0.0.1:37116","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-07-24T21:39:44.647144Z","caller":"traceutil/trace.go:171","msg":"trace[755468362] linearizableReadLoop","detail":"{readStateIndex:6329; appliedIndex:6325; }","duration":"329.863141ms","start":"2026-07-24T21:39:44.317241Z","end":"2026-07-24T21:39:44.647105Z","steps":["trace[755468362] 'read index received' (duration: 49.25661ms)","trace[755468362] 'applied index is now lower than readState.Index' (duration: 280.605411ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:44.648169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.548937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:39:44.648195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.184198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-24T21:39:44.648215Z","caller":"traceutil/trace.go:171","msg":"trace[1548977765] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:6076; }","duration":"326.637229ms","start":"2026-07-24T21:39:44.321564Z","end":"2026-07-24T21:39:44.648202Z","steps":["trace[1548977765] 'agreement among raft nodes before linearized reading' (duration: 326.558577ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.648246Z","caller":"traceutil/trace.go:171","msg":"trace[737090003] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:6076; }","duration":"328.29696ms","start":"2026-07-24T21:39:44.319937Z","end":"2026-07-24T21:39:44.648234Z","steps":["trace[737090003] 'agreement among raft nodes before linearized reading' (duration: 328.089826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.648253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.321544Z","time spent":"326.701369ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-24T21:39:44.64829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.319912Z","time spent":"328.367572ms","remote":"127.0.0.1:37040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-24T21:39:44.648517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.180091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:14 size:139710"} {"level":"warn","ts":"2026-07-24T21:39:44.64809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.794382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:44.648621Z","caller":"traceutil/trace.go:171","msg":"trace[6791191] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:14; response_revision:6076; }","duration":"325.321524ms","start":"2026-07-24T21:39:44.323276Z","end":"2026-07-24T21:39:44.648598Z","steps":["trace[6791191] 'agreement among raft nodes before linearized reading' (duration: 324.824945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:44.64868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:44.323266Z","time spent":"325.403555ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":14,"response size":139734,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-24T21:39:44.648634Z","caller":"traceutil/trace.go:171","msg":"trace[2053824676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6076; }","duration":"176.416424ms","start":"2026-07-24T21:39:44.472208Z","end":"2026-07-24T21:39:44.648625Z","steps":["trace[2053824676] 'agreement among raft nodes before linearized reading' (duration: 175.813823ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:44.924109Z","caller":"traceutil/trace.go:171","msg":"trace[19535408] transaction","detail":"{read_only:false; response_revision:6077; number_of_response:1; }","duration":"106.010022ms","start":"2026-07-24T21:39:44.818068Z","end":"2026-07-24T21:39:44.924078Z","steps":["trace[19535408] 'process raft request' (duration: 105.761088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:45.658734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.60763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:45.658865Z","caller":"traceutil/trace.go:171","msg":"trace[296569468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6078; }","duration":"185.826414ms","start":"2026-07-24T21:39:45.473015Z","end":"2026-07-24T21:39:45.658841Z","steps":["trace[296569468] 'range keys from in-memory index tree' (duration: 185.474866ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:46.232309Z","caller":"traceutil/trace.go:171","msg":"trace[1655355840] transaction","detail":"{read_only:false; response_revision:6079; number_of_response:1; }","duration":"159.064653ms","start":"2026-07-24T21:39:46.073218Z","end":"2026-07-24T21:39:46.232283Z","steps":["trace[1655355840] 'process raft request' (duration: 158.859799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:46.443282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.509341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:39:46.443693Z","caller":"traceutil/trace.go:171","msg":"trace[734678523] transaction","detail":"{read_only:false; response_revision:6081; number_of_response:1; }","duration":"334.428787ms","start":"2026-07-24T21:39:46.109247Z","end":"2026-07-24T21:39:46.443675Z","steps":["trace[734678523] 'process raft request' (duration: 334.205013ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:46.443526Z","caller":"traceutil/trace.go:171","msg":"trace[808641534] transaction","detail":"{read_only:false; response_revision:6080; number_of_response:1; }","duration":"366.601681ms","start":"2026-07-24T21:39:46.076877Z","end":"2026-07-24T21:39:46.443479Z","steps":["trace[808641534] 'process raft request' (duration: 185.787734ms)","trace[808641534] 'compare' (duration: 180.284807ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:46.443783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:46.109234Z","time spent":"334.508308ms","remote":"127.0.0.1:37116","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-07-24T21:39:46.443882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:46.076853Z","time spent":"366.948687ms","remote":"127.0.0.1:37116","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-07-24T21:39:46.960492Z","caller":"traceutil/trace.go:171","msg":"trace[394729666] linearizableReadLoop","detail":"{readStateIndex:6339; appliedIndex:6337; }","duration":"304.561689ms","start":"2026-07-24T21:39:46.655904Z","end":"2026-07-24T21:39:46.960465Z","steps":["trace[394729666] 'read index received' (duration: 43.042601ms)","trace[394729666] 'applied index is now lower than readState.Index' (duration: 261.517167ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:46.960642Z","caller":"traceutil/trace.go:171","msg":"trace[1251124064] transaction","detail":"{read_only:false; response_revision:6084; number_of_response:1; }","duration":"305.908674ms","start":"2026-07-24T21:39:46.654721Z","end":"2026-07-24T21:39:46.960629Z","steps":["trace[1251124064] 'process raft request' (duration: 305.320023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:46.960772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:46.654691Z","time spent":"305.986214ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:39:46.960838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.920135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-07-24T21:39:46.96094Z","caller":"traceutil/trace.go:171","msg":"trace[1919990411] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:6086; }","duration":"305.079418ms","start":"2026-07-24T21:39:46.655842Z","end":"2026-07-24T21:39:46.960922Z","steps":["trace[1919990411] 'agreement among raft nodes before linearized reading' (duration: 304.852104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:46.960981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:46.655816Z","time spent":"305.155969ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20585,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" "} {"level":"info","ts":"2026-07-24T21:39:46.961208Z","caller":"traceutil/trace.go:171","msg":"trace[1713292458] transaction","detail":"{read_only:false; response_revision:6085; number_of_response:1; }","duration":"303.179561ms","start":"2026-07-24T21:39:46.658016Z","end":"2026-07-24T21:39:46.961195Z","steps":["trace[1713292458] 'process raft request' (duration: 302.256924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:46.961315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:46.657991Z","time spent":"303.263273ms","remote":"127.0.0.1:37116","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-07-24T21:39:46.961437Z","caller":"traceutil/trace.go:171","msg":"trace[760161534] transaction","detail":"{read_only:false; response_revision:6086; number_of_response:1; }","duration":"301.49639ms","start":"2026-07-24T21:39:46.659804Z","end":"2026-07-24T21:39:46.9613Z","steps":["trace[760161534] 'process raft request' (duration: 300.603513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:46.961533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.872721ms","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-07-24T21:39:46.961605Z","caller":"traceutil/trace.go:171","msg":"trace[2102150463] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:6086; }","duration":"178.984964ms","start":"2026-07-24T21:39:46.782603Z","end":"2026-07-24T21:39:46.961588Z","steps":["trace[2102150463] 'agreement among raft nodes before linearized reading' (duration: 178.743939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:46.961782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:46.659778Z","time spent":"301.794875ms","remote":"127.0.0.1:37116","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-07-24T21:39:46.961434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.072959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-24T21:39:46.962238Z","caller":"traceutil/trace.go:171","msg":"trace[731903509] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:6086; }","duration":"303.965386ms","start":"2026-07-24T21:39:46.658178Z","end":"2026-07-24T21:39:46.962144Z","steps":["trace[731903509] 'agreement among raft nodes before linearized reading' (duration: 302.710312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:46.962731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:46.658164Z","time spent":"304.517007ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6471,"request content":"key:\"/registry/pods/openstack/keepalived-lw946\" "} {"level":"warn","ts":"2026-07-24T21:39:47.172432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.7987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:39:47.172578Z","caller":"traceutil/trace.go:171","msg":"trace[145367677] linearizableReadLoop","detail":"{readStateIndex:6342; appliedIndex:6341; }","duration":"203.898827ms","start":"2026-07-24T21:39:46.968654Z","end":"2026-07-24T21:39:47.172553Z","steps":["trace[145367677] 'read index received' (duration: 47.657719ms)","trace[145367677] 'applied index is now lower than readState.Index' (duration: 156.238928ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:47.172652Z","caller":"traceutil/trace.go:171","msg":"trace[848389481] transaction","detail":"{read_only:false; response_revision:6087; number_of_response:1; }","duration":"205.61783ms","start":"2026-07-24T21:39:46.966993Z","end":"2026-07-24T21:39:47.172611Z","steps":["trace[848389481] 'process raft request' (duration: 49.383322ms)","trace[848389481] 'compare' (duration: 155.630617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:47.173161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.47857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:14 size:139710"} {"level":"info","ts":"2026-07-24T21:39:47.173269Z","caller":"traceutil/trace.go:171","msg":"trace[598941551] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:14; response_revision:6087; }","duration":"204.656532ms","start":"2026-07-24T21:39:46.968591Z","end":"2026-07-24T21:39:47.173248Z","steps":["trace[598941551] 'agreement among raft nodes before linearized reading' (duration: 204.06896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:47.173531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.234485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:14 size:139710"} {"level":"info","ts":"2026-07-24T21:39:47.173651Z","caller":"traceutil/trace.go:171","msg":"trace[789277077] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:14; response_revision:6087; }","duration":"204.350187ms","start":"2026-07-24T21:39:46.96925Z","end":"2026-07-24T21:39:47.1736Z","steps":["trace[789277077] 'agreement among raft nodes before linearized reading' (duration: 203.47106ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:48.367154Z","caller":"traceutil/trace.go:171","msg":"trace[1236512885] transaction","detail":"{read_only:false; response_revision:6089; number_of_response:1; }","duration":"129.865936ms","start":"2026-07-24T21:39:48.237255Z","end":"2026-07-24T21:39:48.367121Z","steps":["trace[1236512885] 'process raft request' (duration: 129.584471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:48.570811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.965789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:39:48.570879Z","caller":"traceutil/trace.go:171","msg":"trace[600179415] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6089; }","duration":"119.097312ms","start":"2026-07-24T21:39:48.451766Z","end":"2026-07-24T21:39:48.570863Z","steps":["trace[600179415] 'range keys from in-memory index tree' (duration: 118.727045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:48.570986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.094741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-24T21:39:48.571066Z","caller":"traceutil/trace.go:171","msg":"trace[374816157] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:6089; }","duration":"108.235134ms","start":"2026-07-24T21:39:48.462811Z","end":"2026-07-24T21:39:48.571047Z","steps":["trace[374816157] 'range keys from in-memory index tree' (duration: 107.890207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:48.57112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.076839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T21:39:48.571217Z","caller":"traceutil/trace.go:171","msg":"trace[773961158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6089; }","duration":"122.225531ms","start":"2026-07-24T21:39:48.448967Z","end":"2026-07-24T21:39:48.571193Z","steps":["trace[773961158] 'range keys from in-memory index tree' (duration: 121.809264ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:48.88059Z","caller":"traceutil/trace.go:171","msg":"trace[1732934368] linearizableReadLoop","detail":"{readStateIndex:6345; appliedIndex:6344; }","duration":"304.793733ms","start":"2026-07-24T21:39:48.575768Z","end":"2026-07-24T21:39:48.880562Z","steps":["trace[1732934368] 'read index received' (duration: 304.669601ms)","trace[1732934368] 'applied index is now lower than readState.Index' (duration: 123.072µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:48.8807Z","caller":"traceutil/trace.go:171","msg":"trace[1886561313] transaction","detail":"{read_only:false; response_revision:6090; number_of_response:1; }","duration":"305.230301ms","start":"2026-07-24T21:39:48.575434Z","end":"2026-07-24T21:39:48.880664Z","steps":["trace[1886561313] 'process raft request' (duration: 304.928096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:48.88084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.040128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18034"} {"level":"warn","ts":"2026-07-24T21:39:48.880866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:48.575416Z","time spent":"305.353323ms","remote":"127.0.0.1:37116","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-07-24T21:39:48.880884Z","caller":"traceutil/trace.go:171","msg":"trace[966604979] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6090; }","duration":"305.117079ms","start":"2026-07-24T21:39:48.575756Z","end":"2026-07-24T21:39:48.880873Z","steps":["trace[966604979] 'agreement among raft nodes before linearized reading' (duration: 304.913115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:48.88092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:48.57575Z","time spent":"305.16131ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18058,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-24T21:39:48.929373Z","caller":"traceutil/trace.go:171","msg":"trace[1707160450] transaction","detail":"{read_only:false; response_revision:6092; number_of_response:1; }","duration":"224.711616ms","start":"2026-07-24T21:39:48.704617Z","end":"2026-07-24T21:39:48.929329Z","steps":["trace[1707160450] 'process raft request' (duration: 224.537941ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:48.929377Z","caller":"traceutil/trace.go:171","msg":"trace[891874729] transaction","detail":"{read_only:false; response_revision:6091; number_of_response:1; }","duration":"353.446621ms","start":"2026-07-24T21:39:48.575851Z","end":"2026-07-24T21:39:48.929298Z","steps":["trace[891874729] 'process raft request' (duration: 353.174085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:48.929778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:39:48.57582Z","time spent":"353.681186ms","remote":"127.0.0.1:37116","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-07-24T21:39:49.074065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.480456ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-24T21:39:49.074538Z","caller":"traceutil/trace.go:171","msg":"trace[1853947211] linearizableReadLoop","detail":"{readStateIndex:6348; appliedIndex:6345; }","duration":"191.142525ms","start":"2026-07-24T21:39:48.883359Z","end":"2026-07-24T21:39:49.074501Z","steps":["trace[1853947211] 'read index received' (duration: 45.66839ms)","trace[1853947211] 'applied index is now lower than readState.Index' (duration: 145.467534ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:49.074567Z","caller":"traceutil/trace.go:171","msg":"trace[684435589] transaction","detail":"{read_only:false; response_revision:6093; number_of_response:1; }","duration":"107.787105ms","start":"2026-07-24T21:39:48.966733Z","end":"2026-07-24T21:39:49.07452Z","steps":["trace[684435589] 'process raft request' (duration: 107.443848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:49.07476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.855888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:39:49.074793Z","caller":"traceutil/trace.go:171","msg":"trace[1903986665] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6094; }","duration":"128.95091ms","start":"2026-07-24T21:39:48.945832Z","end":"2026-07-24T21:39:49.074783Z","steps":["trace[1903986665] 'agreement among raft nodes before linearized reading' (duration: 128.848858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:49.074759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.36656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T21:39:49.074816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.818174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:39:49.074844Z","caller":"traceutil/trace.go:171","msg":"trace[1078436077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6094; }","duration":"108.916447ms","start":"2026-07-24T21:39:48.96592Z","end":"2026-07-24T21:39:49.074836Z","steps":["trace[1078436077] 'agreement among raft nodes before linearized reading' (duration: 108.783144ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:49.074849Z","caller":"traceutil/trace.go:171","msg":"trace[1675239643] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:6094; }","duration":"191.533793ms","start":"2026-07-24T21:39:48.88329Z","end":"2026-07-24T21:39:49.074824Z","steps":["trace[1675239643] 'agreement among raft nodes before linearized reading' (duration: 191.329389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:49.075107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.274914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:39:49.075157Z","caller":"traceutil/trace.go:171","msg":"trace[1808543258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6094; }","duration":"109.389797ms","start":"2026-07-24T21:39:48.965755Z","end":"2026-07-24T21:39:49.075145Z","steps":["trace[1808543258] 'agreement among raft nodes before linearized reading' (duration: 109.200073ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:49.074659Z","caller":"traceutil/trace.go:171","msg":"trace[605459199] transaction","detail":"{read_only:false; response_revision:6094; number_of_response:1; }","duration":"107.655193ms","start":"2026-07-24T21:39:48.966987Z","end":"2026-07-24T21:39:49.074643Z","steps":["trace[605459199] 'process raft request' (duration: 107.300967ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:49.298149Z","caller":"traceutil/trace.go:171","msg":"trace[1594280397] linearizableReadLoop","detail":"{readStateIndex:6352; appliedIndex:6350; }","duration":"216.840334ms","start":"2026-07-24T21:39:49.08126Z","end":"2026-07-24T21:39:49.2981Z","steps":["trace[1594280397] 'read index received' (duration: 19.625454ms)","trace[1594280397] 'applied index is now lower than readState.Index' (duration: 197.21337ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:49.298228Z","caller":"traceutil/trace.go:171","msg":"trace[1858805384] transaction","detail":"{read_only:false; response_revision:6096; number_of_response:1; }","duration":"218.898834ms","start":"2026-07-24T21:39:49.079282Z","end":"2026-07-24T21:39:49.298181Z","steps":["trace[1858805384] 'process raft request' (duration: 140.56211ms)","trace[1858805384] 'compare' (duration: 78.100469ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:39:49.298364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.617271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:39:49.298411Z","caller":"traceutil/trace.go:171","msg":"trace[778569186] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:6096; }","duration":"172.736173ms","start":"2026-07-24T21:39:49.125661Z","end":"2026-07-24T21:39:49.298397Z","steps":["trace[778569186] 'agreement among raft nodes before linearized reading' (duration: 172.629351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:49.298455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.188681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:39:49.298517Z","caller":"traceutil/trace.go:171","msg":"trace[1656132882] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6096; }","duration":"217.296783ms","start":"2026-07-24T21:39:49.081206Z","end":"2026-07-24T21:39:49.298503Z","steps":["trace[1656132882] 'agreement among raft nodes before linearized reading' (duration: 217.049708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:49.298571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.315957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"warn","ts":"2026-07-24T21:39:49.298676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.497517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T21:39:49.29868Z","caller":"traceutil/trace.go:171","msg":"trace[1668327264] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:6096; }","duration":"116.45171ms","start":"2026-07-24T21:39:49.182202Z","end":"2026-07-24T21:39:49.298654Z","steps":["trace[1668327264] 'agreement among raft nodes before linearized reading' (duration: 116.120443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:49.298676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.9911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-07-24T21:39:49.298724Z","caller":"traceutil/trace.go:171","msg":"trace[249949050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6096; }","duration":"121.608059ms","start":"2026-07-24T21:39:49.177105Z","end":"2026-07-24T21:39:49.298713Z","steps":["trace[249949050] 'agreement among raft nodes before linearized reading' (duration: 121.377274ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:49.298782Z","caller":"traceutil/trace.go:171","msg":"trace[141092578] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:6096; }","duration":"117.136743ms","start":"2026-07-24T21:39:49.181621Z","end":"2026-07-24T21:39:49.298757Z","steps":["trace[141092578] 'agreement among raft nodes before linearized reading' (duration: 116.770436ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:49.55907Z","caller":"traceutil/trace.go:171","msg":"trace[597776570] transaction","detail":"{read_only:false; response_revision:6098; number_of_response:1; }","duration":"100.914384ms","start":"2026-07-24T21:39:49.458127Z","end":"2026-07-24T21:39:49.559042Z","steps":["trace[597776570] 'process raft request' (duration: 100.416895ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:56.520671Z","caller":"traceutil/trace.go:171","msg":"trace[203895707] transaction","detail":"{read_only:false; response_revision:6138; number_of_response:1; }","duration":"130.859585ms","start":"2026-07-24T21:39:56.389781Z","end":"2026-07-24T21:39:56.52064Z","steps":["trace[203895707] 'process raft request' (duration: 130.62062ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:57.100754Z","caller":"traceutil/trace.go:171","msg":"trace[1750852803] linearizableReadLoop","detail":"{readStateIndex:6397; appliedIndex:6395; }","duration":"143.254382ms","start":"2026-07-24T21:39:56.957469Z","end":"2026-07-24T21:39:57.100724Z","steps":["trace[1750852803] 'read index received' (duration: 22.202093ms)","trace[1750852803] 'applied index is now lower than readState.Index' (duration: 121.051279ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:39:57.100774Z","caller":"traceutil/trace.go:171","msg":"trace[1813381429] transaction","detail":"{read_only:false; response_revision:6140; number_of_response:1; }","duration":"145.908692ms","start":"2026-07-24T21:39:56.954836Z","end":"2026-07-24T21:39:57.100745Z","steps":["trace[1813381429] 'process raft request' (duration: 145.729679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:39:57.100956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.459177ms","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-07-24T21:39:57.100989Z","caller":"traceutil/trace.go:171","msg":"trace[1067379804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6140; }","duration":"143.532658ms","start":"2026-07-24T21:39:56.957447Z","end":"2026-07-24T21:39:57.10098Z","steps":["trace[1067379804] 'agreement among raft nodes before linearized reading' (duration: 143.376005ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:04.426991Z","caller":"traceutil/trace.go:171","msg":"trace[1879412262] linearizableReadLoop","detail":"{readStateIndex:6459; appliedIndex:6458; }","duration":"274.465054ms","start":"2026-07-24T21:40:04.152496Z","end":"2026-07-24T21:40:04.426961Z","steps":["trace[1879412262] 'read index received' (duration: 274.26291ms)","trace[1879412262] 'applied index is now lower than readState.Index' (duration: 200.394µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:40:04.427047Z","caller":"traceutil/trace.go:171","msg":"trace[1817192814] transaction","detail":"{read_only:false; response_revision:6200; number_of_response:1; }","duration":"346.404755ms","start":"2026-07-24T21:40:04.080587Z","end":"2026-07-24T21:40:04.426992Z","steps":["trace[1817192814] 'process raft request' (duration: 346.14144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:04.42725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:04.080555Z","time spent":"346.579187ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:40:04.427363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.830021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18034"} {"level":"info","ts":"2026-07-24T21:40:04.427467Z","caller":"traceutil/trace.go:171","msg":"trace[296360554] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6200; }","duration":"274.997964ms","start":"2026-07-24T21:40:04.152447Z","end":"2026-07-24T21:40:04.427445Z","steps":["trace[296360554] 'agreement among raft nodes before linearized reading' (duration: 274.640627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:04.611859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.790592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:40:04.611943Z","caller":"traceutil/trace.go:171","msg":"trace[1386778033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6200; }","duration":"138.933685ms","start":"2026-07-24T21:40:04.472989Z","end":"2026-07-24T21:40:04.611923Z","steps":["trace[1386778033] 'range keys from in-memory index tree' (duration: 138.726292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:04.611982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.554362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-24T21:40:04.612066Z","caller":"traceutil/trace.go:171","msg":"trace[1194606369] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6200; }","duration":"102.718845ms","start":"2026-07-24T21:40:04.509328Z","end":"2026-07-24T21:40:04.612047Z","steps":["trace[1194606369] 'range keys from in-memory index tree' (duration: 102.352099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:04.612094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.549416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T21:40:04.612088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.861244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18034"} {"level":"info","ts":"2026-07-24T21:40:04.612139Z","caller":"traceutil/trace.go:171","msg":"trace[543195176] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:6200; }","duration":"181.650728ms","start":"2026-07-24T21:40:04.430477Z","end":"2026-07-24T21:40:04.612128Z","steps":["trace[543195176] 'count revisions from in-memory index tree' (duration: 181.450115ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:04.612175Z","caller":"traceutil/trace.go:171","msg":"trace[532531238] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6200; }","duration":"178.999067ms","start":"2026-07-24T21:40:04.433155Z","end":"2026-07-24T21:40:04.612154Z","steps":["trace[532531238] 'range keys from in-memory index tree' (duration: 178.675802ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:05.940787Z","caller":"traceutil/trace.go:171","msg":"trace[2070233212] transaction","detail":"{read_only:false; response_revision:6215; number_of_response:1; }","duration":"177.163512ms","start":"2026-07-24T21:40:05.763589Z","end":"2026-07-24T21:40:05.940752Z","steps":["trace[2070233212] 'process raft request' (duration: 176.948888ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:10.955777Z","caller":"traceutil/trace.go:171","msg":"trace[64716631] transaction","detail":"{read_only:false; response_revision:6257; number_of_response:1; }","duration":"150.920163ms","start":"2026-07-24T21:40:10.804832Z","end":"2026-07-24T21:40:10.955752Z","steps":["trace[64716631] 'process raft request' (duration: 150.732889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:11.194297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.784065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:40:11.194562Z","caller":"traceutil/trace.go:171","msg":"trace[477903882] transaction","detail":"{read_only:false; response_revision:6259; number_of_response:1; }","duration":"263.975766ms","start":"2026-07-24T21:40:10.930564Z","end":"2026-07-24T21:40:11.19454Z","steps":["trace[477903882] 'process raft request' (duration: 263.872964ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:11.194602Z","caller":"traceutil/trace.go:171","msg":"trace[1273907697] transaction","detail":"{read_only:false; response_revision:6258; number_of_response:1; }","duration":"337.894873ms","start":"2026-07-24T21:40:10.856643Z","end":"2026-07-24T21:40:11.194538Z","steps":["trace[1273907697] 'process raft request' (duration: 203.76813ms)","trace[1273907697] 'compare' (duration: 133.282996ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:40:11.19482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:10.856596Z","time spent":"338.107007ms","remote":"127.0.0.1:36882","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-07-24T21:40:11.279491Z","caller":"traceutil/trace.go:171","msg":"trace[1985703212] transaction","detail":"{read_only:false; response_revision:6260; number_of_response:1; }","duration":"131.514263ms","start":"2026-07-24T21:40:11.147944Z","end":"2026-07-24T21:40:11.279458Z","steps":["trace[1985703212] 'process raft request' (duration: 131.300799ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:11.290135Z","caller":"traceutil/trace.go:171","msg":"trace[1657329275] transaction","detail":"{read_only:false; response_revision:6261; number_of_response:1; }","duration":"141.011845ms","start":"2026-07-24T21:40:11.149078Z","end":"2026-07-24T21:40:11.29009Z","steps":["trace[1657329275] 'process raft request' (duration: 140.808121ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:11.292547Z","caller":"traceutil/trace.go:171","msg":"trace[30366323] linearizableReadLoop","detail":"{readStateIndex:6521; appliedIndex:6519; }","duration":"138.441196ms","start":"2026-07-24T21:40:11.15408Z","end":"2026-07-24T21:40:11.292521Z","steps":["trace[30366323] 'read index received' (duration: 125.191623ms)","trace[30366323] 'applied index is now lower than readState.Index' (duration: 13.247813ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:40:11.292742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4090} {"level":"warn","ts":"2026-07-24T21:40:11.29282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.710081ms","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-07-24T21:40:11.29284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.31207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T21:40:11.292891Z","caller":"traceutil/trace.go:171","msg":"trace[631659439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6261; }","duration":"121.433772ms","start":"2026-07-24T21:40:11.171444Z","end":"2026-07-24T21:40:11.292878Z","steps":["trace[631659439] 'agreement among raft nodes before linearized reading' (duration: 121.221308ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:11.292896Z","caller":"traceutil/trace.go:171","msg":"trace[653211161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6261; }","duration":"138.837934ms","start":"2026-07-24T21:40:11.154035Z","end":"2026-07-24T21:40:11.292873Z","steps":["trace[653211161] 'agreement among raft nodes before linearized reading' (duration: 138.61523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:11.292941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.8455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T21:40:11.293012Z","caller":"traceutil/trace.go:171","msg":"trace[18981104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6261; }","duration":"101.945061ms","start":"2026-07-24T21:40:11.19105Z","end":"2026-07-24T21:40:11.292992Z","steps":["trace[18981104] 'agreement among raft nodes before linearized reading' (duration: 101.721807ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:11.352578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4090,"took":"56.409164ms","hash":2654132945,"current-db-size-bytes":38297600,"current-db-size":"38 MB","current-db-size-in-use-bytes":21426176,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-07-24T21:40:11.352645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2654132945,"revision":4090,"compact-revision":1651} {"level":"info","ts":"2026-07-24T21:40:17.559023Z","caller":"traceutil/trace.go:171","msg":"trace[1213705775] transaction","detail":"{read_only:false; response_revision:6299; number_of_response:1; }","duration":"258.574502ms","start":"2026-07-24T21:40:17.30042Z","end":"2026-07-24T21:40:17.558994Z","steps":["trace[1213705775] 'process raft request' (duration: 258.390048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.686075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.823779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:40:17.68617Z","caller":"traceutil/trace.go:171","msg":"trace[1025941336] linearizableReadLoop","detail":"{readStateIndex:6562; appliedIndex:6560; }","duration":"360.163486ms","start":"2026-07-24T21:40:17.32599Z","end":"2026-07-24T21:40:17.686153Z","steps":["trace[1025941336] 'read index received' (duration: 232.904803ms)","trace[1025941336] 'applied index is now lower than readState.Index' (duration: 127.257653ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:40:17.686231Z","caller":"traceutil/trace.go:171","msg":"trace[1631228621] transaction","detail":"{read_only:false; response_revision:6300; number_of_response:1; }","duration":"376.469967ms","start":"2026-07-24T21:40:17.309753Z","end":"2026-07-24T21:40:17.686223Z","steps":["trace[1631228621] 'process raft request' (duration: 275.416134ms)","trace[1631228621] 'compare' (duration: 100.593525ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:40:17.686267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:17.309717Z","time spent":"376.530928ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:40:17.686351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.33834ms","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-07-24T21:40:17.686456Z","caller":"traceutil/trace.go:171","msg":"trace[2025340806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6300; }","duration":"360.518403ms","start":"2026-07-24T21:40:17.325915Z","end":"2026-07-24T21:40:17.686434Z","steps":["trace[2025340806] 'agreement among raft nodes before linearized reading' (duration: 360.304919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.686497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:17.325887Z","time spent":"360.602674ms","remote":"127.0.0.1:37116","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-07-24T21:40:17.736695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.303237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-24T21:40:17.736729Z","caller":"traceutil/trace.go:171","msg":"trace[1005482506] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:6301; }","duration":"289.359767ms","start":"2026-07-24T21:40:17.447361Z","end":"2026-07-24T21:40:17.736721Z","steps":["trace[1005482506] 'agreement among raft nodes before linearized reading' (duration: 289.274566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.736782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.003493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T21:40:17.736853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.210609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:40:17.736876Z","caller":"traceutil/trace.go:171","msg":"trace[1223973727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6301; }","duration":"264.241219ms","start":"2026-07-24T21:40:17.472627Z","end":"2026-07-24T21:40:17.736868Z","steps":["trace[1223973727] 'agreement among raft nodes before linearized reading' (duration: 264.207869ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:17.73687Z","caller":"traceutil/trace.go:171","msg":"trace[250538570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6301; }","duration":"401.115046ms","start":"2026-07-24T21:40:17.33573Z","end":"2026-07-24T21:40:17.736845Z","steps":["trace[250538570] 'agreement among raft nodes before linearized reading' (duration: 400.857061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.736967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:17.33572Z","time spent":"401.235668ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T21:40:17.737097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.788146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:1 size:5922"} {"level":"warn","ts":"2026-07-24T21:40:17.737082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.97286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:40:17.73713Z","caller":"traceutil/trace.go:171","msg":"trace[98163699] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:1; response_revision:6301; }","duration":"112.865078ms","start":"2026-07-24T21:40:17.624258Z","end":"2026-07-24T21:40:17.737123Z","steps":["trace[98163699] 'agreement among raft nodes before linearized reading' (duration: 112.788056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.73711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.097449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:40:17.737164Z","caller":"traceutil/trace.go:171","msg":"trace[1801370435] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:6301; }","duration":"373.077121ms","start":"2026-07-24T21:40:17.364062Z","end":"2026-07-24T21:40:17.73714Z","steps":["trace[1801370435] 'agreement among raft nodes before linearized reading' (duration: 372.820446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.737209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.043609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:40:17.737223Z","caller":"traceutil/trace.go:171","msg":"trace[131908245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6301; }","duration":"304.301062ms","start":"2026-07-24T21:40:17.432896Z","end":"2026-07-24T21:40:17.737197Z","steps":["trace[131908245] 'agreement among raft nodes before linearized reading' (duration: 303.911895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.737222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:17.364055Z","time spent":"373.154733ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":17,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-07-24T21:40:17.737263Z","caller":"traceutil/trace.go:171","msg":"trace[1439949587] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:6301; }","duration":"115.121481ms","start":"2026-07-24T21:40:17.622117Z","end":"2026-07-24T21:40:17.737239Z","steps":["trace[1439949587] 'agreement among raft nodes before linearized reading' (duration: 115.06643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.737268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:17.43287Z","time spent":"304.391615ms","remote":"127.0.0.1:37116","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":"warn","ts":"2026-07-24T21:40:17.73736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.283288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-24T21:40:17.737116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.373093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-07-24T21:40:17.737415Z","caller":"traceutil/trace.go:171","msg":"trace[51397226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6301; }","duration":"401.36768ms","start":"2026-07-24T21:40:17.336032Z","end":"2026-07-24T21:40:17.7374Z","steps":["trace[51397226] 'agreement among raft nodes before linearized reading' (duration: 401.235318ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:17.737428Z","caller":"traceutil/trace.go:171","msg":"trace[157374947] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:6301; }","duration":"242.763331ms","start":"2026-07-24T21:40:17.494657Z","end":"2026-07-24T21:40:17.73742Z","steps":["trace[157374947] 'agreement among raft nodes before linearized reading' (duration: 242.183271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.737455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:17.33602Z","time spent":"401.425951ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T21:40:17.737447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.726751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T21:40:17.737541Z","caller":"traceutil/trace.go:171","msg":"trace[77151558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6301; }","duration":"292.869144ms","start":"2026-07-24T21:40:17.444651Z","end":"2026-07-24T21:40:17.73752Z","steps":["trace[77151558] 'agreement among raft nodes before linearized reading' (duration: 292.590399ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:17.871555Z","caller":"traceutil/trace.go:171","msg":"trace[1148758617] linearizableReadLoop","detail":"{readStateIndex:6564; appliedIndex:6563; }","duration":"132.753417ms","start":"2026-07-24T21:40:17.738777Z","end":"2026-07-24T21:40:17.871531Z","steps":["trace[1148758617] 'read index received' (duration: 50.889698ms)","trace[1148758617] 'applied index is now lower than readState.Index' (duration: 81.862679ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:40:17.871605Z","caller":"traceutil/trace.go:171","msg":"trace[1328684556] transaction","detail":"{read_only:false; response_revision:6302; number_of_response:1; }","duration":"180.852734ms","start":"2026-07-24T21:40:17.690727Z","end":"2026-07-24T21:40:17.87158Z","steps":["trace[1328684556] 'process raft request' (duration: 98.922644ms)","trace[1328684556] 'compare' (duration: 81.749736ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:40:17.87181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.020382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:40:17.871875Z","caller":"traceutil/trace.go:171","msg":"trace[1860865051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6302; }","duration":"133.099854ms","start":"2026-07-24T21:40:17.738757Z","end":"2026-07-24T21:40:17.871857Z","steps":["trace[1860865051] 'agreement among raft nodes before linearized reading' (duration: 132.92017ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:17.874755Z","caller":"traceutil/trace.go:171","msg":"trace[737497002] transaction","detail":"{read_only:false; response_revision:6303; number_of_response:1; }","duration":"132.349269ms","start":"2026-07-24T21:40:17.742382Z","end":"2026-07-24T21:40:17.874732Z","steps":["trace[737497002] 'process raft request' (duration: 132.075294ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:17.87475Z","caller":"traceutil/trace.go:171","msg":"trace[611592820] transaction","detail":"{read_only:false; response_revision:6304; number_of_response:1; }","duration":"131.798109ms","start":"2026-07-24T21:40:17.74292Z","end":"2026-07-24T21:40:17.874718Z","steps":["trace[611592820] 'process raft request' (duration: 131.692647ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:17.875117Z","caller":"traceutil/trace.go:171","msg":"trace[453527690] transaction","detail":"{read_only:false; response_revision:6305; number_of_response:1; }","duration":"132.146305ms","start":"2026-07-24T21:40:17.742948Z","end":"2026-07-24T21:40:17.875094Z","steps":["trace[453527690] 'process raft request' (duration: 131.716767ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:17.875288Z","caller":"traceutil/trace.go:171","msg":"trace[2110083593] transaction","detail":"{read_only:false; response_revision:6306; number_of_response:1; }","duration":"132.134134ms","start":"2026-07-24T21:40:17.743141Z","end":"2026-07-24T21:40:17.875275Z","steps":["trace[2110083593] 'process raft request' (duration: 131.848509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.875578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.458631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:16 size:154604"} {"level":"info","ts":"2026-07-24T21:40:17.87568Z","caller":"traceutil/trace.go:171","msg":"trace[130256373] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:16; response_revision:6306; }","duration":"131.586154ms","start":"2026-07-24T21:40:17.74408Z","end":"2026-07-24T21:40:17.875666Z","steps":["trace[130256373] 'agreement among raft nodes before linearized reading' (duration: 131.195256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:17.875941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.840169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:16 size:154604"} {"level":"info","ts":"2026-07-24T21:40:17.876037Z","caller":"traceutil/trace.go:171","msg":"trace[1672899706] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:16; response_revision:6306; }","duration":"132.963641ms","start":"2026-07-24T21:40:17.743053Z","end":"2026-07-24T21:40:17.876016Z","steps":["trace[1672899706] 'agreement among raft nodes before linearized reading' (duration: 132.127745ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:36.9712Z","caller":"traceutil/trace.go:171","msg":"trace[1188928727] transaction","detail":"{read_only:false; response_revision:6428; number_of_response:1; }","duration":"219.301635ms","start":"2026-07-24T21:40:36.751874Z","end":"2026-07-24T21:40:36.971175Z","steps":["trace[1188928727] 'process raft request' (duration: 219.142722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.128103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.276934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:40:38.128281Z","caller":"traceutil/trace.go:171","msg":"trace[1320928148] transaction","detail":"{read_only:false; response_revision:6431; number_of_response:1; }","duration":"322.67297ms","start":"2026-07-24T21:40:37.805593Z","end":"2026-07-24T21:40:38.128266Z","steps":["trace[1320928148] 'process raft request' (duration: 322.618739ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:38.128287Z","caller":"traceutil/trace.go:171","msg":"trace[154825066] linearizableReadLoop","detail":"{readStateIndex:6697; appliedIndex:6696; }","duration":"334.029647ms","start":"2026-07-24T21:40:37.794234Z","end":"2026-07-24T21:40:38.128264Z","steps":["trace[154825066] 'read index received' (duration: 115.60764ms)","trace[154825066] 'applied index is now lower than readState.Index' (duration: 218.420637ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:40:38.128366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:37.805571Z","time spent":"322.768312ms","remote":"127.0.0.1:37116","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-07-24T21:40:38.128451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.375183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:40:38.128488Z","caller":"traceutil/trace.go:171","msg":"trace[982256941] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:6431; }","duration":"169.500845ms","start":"2026-07-24T21:40:37.958978Z","end":"2026-07-24T21:40:38.128478Z","steps":["trace[982256941] 'agreement among raft nodes before linearized reading' (duration: 169.407023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.128566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.326982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8241"} {"level":"info","ts":"2026-07-24T21:40:38.128623Z","caller":"traceutil/trace.go:171","msg":"trace[384068686] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:6431; }","duration":"334.445444ms","start":"2026-07-24T21:40:37.794163Z","end":"2026-07-24T21:40:38.128609Z","steps":["trace[384068686] 'agreement among raft nodes before linearized reading' (duration: 334.20988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.128665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:37.794133Z","time spent":"334.523676ms","remote":"127.0.0.1:37088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":8265,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-07-24T21:40:38.128722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.942376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T21:40:38.128994Z","caller":"traceutil/trace.go:171","msg":"trace[1211091307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6431; }","duration":"158.247471ms","start":"2026-07-24T21:40:37.970728Z","end":"2026-07-24T21:40:38.128976Z","steps":["trace[1211091307] 'agreement among raft nodes before linearized reading' (duration: 157.802603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.128977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.204995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"warn","ts":"2026-07-24T21:40:38.128722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.098986ms","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-07-24T21:40:38.129053Z","caller":"traceutil/trace.go:171","msg":"trace[157785899] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:6431; }","duration":"123.47961ms","start":"2026-07-24T21:40:38.005559Z","end":"2026-07-24T21:40:38.129038Z","steps":["trace[157785899] 'agreement among raft nodes before linearized reading' (duration: 123.060492ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:38.129011Z","caller":"traceutil/trace.go:171","msg":"trace[887217437] transaction","detail":"{read_only:false; response_revision:6430; number_of_response:1; }","duration":"349.530311ms","start":"2026-07-24T21:40:37.779464Z","end":"2026-07-24T21:40:38.128994Z","steps":["trace[887217437] 'process raft request' (duration: 130.270319ms)","trace[887217437] 'compare' (duration: 218.121021ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:40:38.129064Z","caller":"traceutil/trace.go:171","msg":"trace[43654147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6431; }","duration":"161.517063ms","start":"2026-07-24T21:40:37.967536Z","end":"2026-07-24T21:40:38.129053Z","steps":["trace[43654147] 'agreement among raft nodes before linearized reading' (duration: 161.088195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.129144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.648583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"warn","ts":"2026-07-24T21:40:38.128723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.447611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-24T21:40:38.129137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.256211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:40:38.129198Z","caller":"traceutil/trace.go:171","msg":"trace[2007753673] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:6431; }","duration":"153.712395ms","start":"2026-07-24T21:40:37.975466Z","end":"2026-07-24T21:40:38.129179Z","steps":["trace[2007753673] 'agreement among raft nodes before linearized reading' (duration: 153.170815ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:38.129211Z","caller":"traceutil/trace.go:171","msg":"trace[640311814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6431; }","duration":"153.97179ms","start":"2026-07-24T21:40:37.975221Z","end":"2026-07-24T21:40:38.129193Z","steps":["trace[640311814] 'agreement among raft nodes before linearized reading' (duration: 153.281857ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:38.129234Z","caller":"traceutil/trace.go:171","msg":"trace[1288279305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6431; }","duration":"158.422144ms","start":"2026-07-24T21:40:37.970787Z","end":"2026-07-24T21:40:38.129209Z","steps":["trace[1288279305] 'agreement among raft nodes before linearized reading' (duration: 157.912445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.129261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:37.779443Z","time spent":"349.652743ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:40:38.129278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.457965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T21:40:38.129403Z","caller":"traceutil/trace.go:171","msg":"trace[38810249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6431; }","duration":"158.670029ms","start":"2026-07-24T21:40:37.970704Z","end":"2026-07-24T21:40:38.129374Z","steps":["trace[38810249] 'agreement among raft nodes before linearized reading' (duration: 158.008677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.406864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.177196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:40:38.406995Z","caller":"traceutil/trace.go:171","msg":"trace[1123807406] transaction","detail":"{read_only:false; response_revision:6432; number_of_response:1; }","duration":"272.264933ms","start":"2026-07-24T21:40:38.134707Z","end":"2026-07-24T21:40:38.406972Z","steps":["trace[1123807406] 'process raft request' (duration: 150.865062ms)","trace[1123807406] 'compare' (duration: 120.644266ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:40:38.43489Z","caller":"traceutil/trace.go:171","msg":"trace[679882037] transaction","detail":"{read_only:false; response_revision:6434; number_of_response:1; }","duration":"300.002472ms","start":"2026-07-24T21:40:38.134871Z","end":"2026-07-24T21:40:38.434874Z","steps":["trace[679882037] 'process raft request' (duration: 299.925309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.434986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:38.134847Z","time spent":"300.092923ms","remote":"127.0.0.1:37116","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-07-24T21:40:38.434949Z","caller":"traceutil/trace.go:171","msg":"trace[236519049] transaction","detail":"{read_only:false; response_revision:6433; number_of_response:1; }","duration":"300.130964ms","start":"2026-07-24T21:40:38.134741Z","end":"2026-07-24T21:40:38.434872Z","steps":["trace[236519049] 'process raft request' (duration: 299.938129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.435225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:38.134722Z","time spent":"300.339818ms","remote":"127.0.0.1:37116","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-07-24T21:40:38.435279Z","caller":"traceutil/trace.go:171","msg":"trace[927187149] transaction","detail":"{read_only:false; response_revision:6435; number_of_response:1; }","duration":"297.707748ms","start":"2026-07-24T21:40:38.137479Z","end":"2026-07-24T21:40:38.435187Z","steps":["trace[927187149] 'process raft request' (duration: 297.36016ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:38.435404Z","caller":"traceutil/trace.go:171","msg":"trace[1378001176] linearizableReadLoop","detail":"{readStateIndex:6701; appliedIndex:6698; }","duration":"299.709765ms","start":"2026-07-24T21:40:38.135661Z","end":"2026-07-24T21:40:38.435371Z","steps":["trace[1378001176] 'read index received' (duration: 149.928933ms)","trace[1378001176] 'applied index is now lower than readState.Index' (duration: 149.779622ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:40:38.43541Z","caller":"traceutil/trace.go:171","msg":"trace[1927215259] transaction","detail":"{read_only:false; response_revision:6436; number_of_response:1; }","duration":"297.81677ms","start":"2026-07-24T21:40:38.137563Z","end":"2026-07-24T21:40:38.43538Z","steps":["trace[1927215259] 'process raft request' (duration: 297.533075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.435771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.056198ms","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-07-24T21:40:38.435821Z","caller":"traceutil/trace.go:171","msg":"trace[353694156] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:6436; }","duration":"242.14575ms","start":"2026-07-24T21:40:38.193658Z","end":"2026-07-24T21:40:38.435803Z","steps":["trace[353694156] 'agreement among raft nodes before linearized reading' (duration: 241.959747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.435957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.289286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:17 size:164653"} {"level":"warn","ts":"2026-07-24T21:40:38.436016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.789578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:17 size:164653"} {"level":"info","ts":"2026-07-24T21:40:38.436066Z","caller":"traceutil/trace.go:171","msg":"trace[621687156] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:17; response_revision:6436; }","duration":"300.420878ms","start":"2026-07-24T21:40:38.135602Z","end":"2026-07-24T21:40:38.436023Z","steps":["trace[621687156] 'agreement among raft nodes before linearized reading' (duration: 299.906399ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:40:38.436072Z","caller":"traceutil/trace.go:171","msg":"trace[1401153183] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:17; response_revision:6436; }","duration":"298.87977ms","start":"2026-07-24T21:40:38.137178Z","end":"2026-07-24T21:40:38.436058Z","steps":["trace[1401153183] 'agreement among raft nodes before linearized reading' (duration: 298.402551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:38.436119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:40:38.135593Z","time spent":"300.516421ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":17,"response size":164677,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-24T21:40:40.336541Z","caller":"traceutil/trace.go:171","msg":"trace[425592542] linearizableReadLoop","detail":"{readStateIndex:6711; appliedIndex:6710; }","duration":"200.769021ms","start":"2026-07-24T21:40:40.135742Z","end":"2026-07-24T21:40:40.336511Z","steps":["trace[425592542] 'read index received' (duration: 73.718073ms)","trace[425592542] 'applied index is now lower than readState.Index' (duration: 127.049618ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:40:40.336647Z","caller":"traceutil/trace.go:171","msg":"trace[134057046] transaction","detail":"{read_only:false; response_revision:6443; number_of_response:1; }","duration":"201.338873ms","start":"2026-07-24T21:40:40.135275Z","end":"2026-07-24T21:40:40.336614Z","steps":["trace[134057046] 'process raft request' (duration: 201.063328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:40:40.336794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.033696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8241"} {"level":"info","ts":"2026-07-24T21:40:40.336836Z","caller":"traceutil/trace.go:171","msg":"trace[1064951554] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:6443; }","duration":"201.115128ms","start":"2026-07-24T21:40:40.13571Z","end":"2026-07-24T21:40:40.336825Z","steps":["trace[1064951554] 'agreement among raft nodes before linearized reading' (duration: 200.953345ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:41:34.762024Z","caller":"traceutil/trace.go:171","msg":"trace[1397922449] transaction","detail":"{read_only:false; response_revision:6769; number_of_response:1; }","duration":"163.171566ms","start":"2026-07-24T21:41:34.598819Z","end":"2026-07-24T21:41:34.761991Z","steps":["trace[1397922449] 'process raft request' (duration: 162.809708ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:41:35.489101Z","caller":"traceutil/trace.go:171","msg":"trace[1303525332] transaction","detail":"{read_only:false; response_revision:6775; number_of_response:1; }","duration":"165.703984ms","start":"2026-07-24T21:41:35.323361Z","end":"2026-07-24T21:41:35.489065Z","steps":["trace[1303525332] 'process raft request' (duration: 165.46902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:41:41.098653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.034841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:41:41.098788Z","caller":"traceutil/trace.go:171","msg":"trace[1449531247] transaction","detail":"{read_only:false; response_revision:6801; number_of_response:1; }","duration":"223.992373ms","start":"2026-07-24T21:41:40.874767Z","end":"2026-07-24T21:41:41.098759Z","steps":["trace[1449531247] 'process raft request' (duration: 100.747567ms)","trace[1449531247] 'compare' (duration: 122.932439ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:41:41.4269Z","caller":"traceutil/trace.go:171","msg":"trace[1305641727] transaction","detail":"{read_only:false; response_revision:6802; number_of_response:1; }","duration":"551.957186ms","start":"2026-07-24T21:41:40.874913Z","end":"2026-07-24T21:41:41.42687Z","steps":["trace[1305641727] 'process raft request' (duration: 551.65968ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:41:41.426962Z","caller":"traceutil/trace.go:171","msg":"trace[1230123835] linearizableReadLoop","detail":"{readStateIndex:7084; appliedIndex:7080; }","duration":"541.848953ms","start":"2026-07-24T21:41:40.885099Z","end":"2026-07-24T21:41:41.426948Z","steps":["trace[1230123835] 'read index received' (duration: 90.425102ms)","trace[1230123835] 'applied index is now lower than readState.Index' (duration: 451.423221ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:41:41.427022Z","caller":"traceutil/trace.go:171","msg":"trace[1771614593] transaction","detail":"{read_only:false; response_revision:6803; number_of_response:1; }","duration":"551.938715ms","start":"2026-07-24T21:41:40.875075Z","end":"2026-07-24T21:41:41.427014Z","steps":["trace[1771614593] 'process raft request' (duration: 551.614479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:41:41.427068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:41:40.875049Z","time spent":"551.988786ms","remote":"127.0.0.1:37116","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-07-24T21:41:41.427214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.115649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw946\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-24T21:41:41.427251Z","caller":"traceutil/trace.go:171","msg":"trace[920338537] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw946; range_end:; response_count:1; response_revision:6804; }","duration":"542.18753ms","start":"2026-07-24T21:41:40.885055Z","end":"2026-07-24T21:41:41.427243Z","steps":["trace[920338537] 'agreement among raft nodes before linearized reading' (duration: 542.038957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:41:41.427063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:41:40.874883Z","time spent":"552.096528ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:41:41.427287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:41:40.88504Z","time spent":"542.237041ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6471,"request content":"key:\"/registry/pods/openstack/keepalived-lw946\" "} {"level":"warn","ts":"2026-07-24T21:41:41.427399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.082067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-07-24T21:41:41.427426Z","caller":"traceutil/trace.go:171","msg":"trace[1129781824] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6804; }","duration":"170.135079ms","start":"2026-07-24T21:41:41.257284Z","end":"2026-07-24T21:41:41.427419Z","steps":["trace[1129781824] 'agreement among raft nodes before linearized reading' (duration: 170.074378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:41:41.427539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.735978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:41:41.427584Z","caller":"traceutil/trace.go:171","msg":"trace[933799705] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:6804; }","duration":"389.83764ms","start":"2026-07-24T21:41:41.037735Z","end":"2026-07-24T21:41:41.427572Z","steps":["trace[933799705] 'agreement among raft nodes before linearized reading' (duration: 389.753868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:41:41.427618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:41:41.037715Z","time spent":"389.895231ms","remote":"127.0.0.1:52942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-07-24T21:41:41.427708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.14038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:41:41.427812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.31244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:41:41.42785Z","caller":"traceutil/trace.go:171","msg":"trace[1990411804] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6804; }","duration":"337.389201ms","start":"2026-07-24T21:41:41.090451Z","end":"2026-07-24T21:41:41.427841Z","steps":["trace[1990411804] 'agreement among raft nodes before linearized reading' (duration: 337.3271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:41:41.427883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:41:41.090429Z","time spent":"337.444372ms","remote":"127.0.0.1:42096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-24T21:41:41.427258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.181821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-07-24T21:41:41.427963Z","caller":"traceutil/trace.go:171","msg":"trace[57516985] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-md4bq; range_end:; response_count:1; response_revision:6804; }","duration":"530.904474ms","start":"2026-07-24T21:41:40.89705Z","end":"2026-07-24T21:41:41.427955Z","steps":["trace[57516985] 'agreement among raft nodes before linearized reading' (duration: 530.15678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:41:41.427992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:41:40.897041Z","time spent":"530.943585ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20585,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-md4bq\" "} {"level":"info","ts":"2026-07-24T21:41:41.427819Z","caller":"traceutil/trace.go:171","msg":"trace[1039517392] transaction","detail":"{read_only:false; response_revision:6804; number_of_response:1; }","duration":"552.667948ms","start":"2026-07-24T21:41:40.875144Z","end":"2026-07-24T21:41:41.427812Z","steps":["trace[1039517392] 'process raft request' (duration: 551.768241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:41:41.428067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:41:40.875133Z","time spent":"552.913273ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:41:41.427738Z","caller":"traceutil/trace.go:171","msg":"trace[2037464407] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:6804; }","duration":"231.218102ms","start":"2026-07-24T21:41:41.196512Z","end":"2026-07-24T21:41:41.427731Z","steps":["trace[2037464407] 'agreement among raft nodes before linearized reading' (duration: 231.169521ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:44:23.563506Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245c.wal"} {"level":"info","ts":"2026-07-24T21:45:02.843161Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T21:45:02.847865Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-24T21:45:02.848046Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-24T21:45:11.298821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6258} {"level":"info","ts":"2026-07-24T21:45:11.366132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6258,"took":"65.106041ms","hash":1068435864,"current-db-size-bytes":44306432,"current-db-size":"44 MB","current-db-size-in-use-bytes":39600128,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-07-24T21:45:11.366219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1068435864,"revision":6258,"compact-revision":4090} {"level":"info","ts":"2026-07-24T21:50:11.304728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9742} {"level":"info","ts":"2026-07-24T21:50:11.421853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9742,"took":"113.780067ms","hash":1826526444,"current-db-size-bytes":58363904,"current-db-size":"58 MB","current-db-size-in-use-bytes":36982784,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-24T21:50:11.421922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1826526444,"revision":9742,"compact-revision":6258} {"level":"info","ts":"2026-07-24T21:51:23.12581Z","caller":"traceutil/trace.go:171","msg":"trace[705814772] transaction","detail":"{read_only:false; response_revision:15093; number_of_response:1; }","duration":"128.185531ms","start":"2026-07-24T21:51:22.997601Z","end":"2026-07-24T21:51:23.125787Z","steps":["trace[705814772] 'process raft request' (duration: 128.055999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:51:23.396819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.952776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-07-24T21:51:23.396926Z","caller":"traceutil/trace.go:171","msg":"trace[2132136949] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15093; }","duration":"204.102069ms","start":"2026-07-24T21:51:23.192802Z","end":"2026-07-24T21:51:23.396904Z","steps":["trace[2132136949] 'range keys from in-memory index tree' (duration: 203.741632ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:51:23.401348Z","caller":"traceutil/trace.go:171","msg":"trace[12667096] transaction","detail":"{read_only:false; response_revision:15094; number_of_response:1; }","duration":"166.071725ms","start":"2026-07-24T21:51:23.235251Z","end":"2026-07-24T21:51:23.401323Z","steps":["trace[12667096] 'process raft request' (duration: 165.778439ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:51:23.704989Z","caller":"traceutil/trace.go:171","msg":"trace[80367080] linearizableReadLoop","detail":"{readStateIndex:15583; appliedIndex:15582; }","duration":"251.243257ms","start":"2026-07-24T21:51:23.453719Z","end":"2026-07-24T21:51:23.704963Z","steps":["trace[80367080] 'read index received' (duration: 157.97393ms)","trace[80367080] 'applied index is now lower than readState.Index' (duration: 93.268037ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:51:23.705108Z","caller":"traceutil/trace.go:171","msg":"trace[940374270] transaction","detail":"{read_only:false; response_revision:15095; number_of_response:1; }","duration":"296.318785ms","start":"2026-07-24T21:51:23.408776Z","end":"2026-07-24T21:51:23.705095Z","steps":["trace[940374270] 'process raft request' (duration: 202.395406ms)","trace[940374270] 'compare' (duration: 93.535102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:51:23.705295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.577322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-07-24T21:51:23.705322Z","caller":"traceutil/trace.go:171","msg":"trace[2010406699] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15095; }","duration":"251.674445ms","start":"2026-07-24T21:51:23.453641Z","end":"2026-07-24T21:51:23.705315Z","steps":["trace[2010406699] 'agreement among raft nodes before linearized reading' (duration: 251.549563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:51:23.704701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.267589ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:51:23.705509Z","caller":"traceutil/trace.go:171","msg":"trace[239750379] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15094; }","duration":"176.100604ms","start":"2026-07-24T21:51:23.529388Z","end":"2026-07-24T21:51:23.705488Z","steps":["trace[239750379] 'range keys from in-memory index tree' (duration: 175.237498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:51:23.705546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.199028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-system/controller-leader-election-capi\" ","response":"range_response_count:1 size:537"} {"level":"info","ts":"2026-07-24T21:51:23.705568Z","caller":"traceutil/trace.go:171","msg":"trace[54619078] range","detail":"{range_begin:/registry/leases/capi-system/controller-leader-election-capi; range_end:; response_count:1; response_revision:15095; }","duration":"174.254239ms","start":"2026-07-24T21:51:23.531306Z","end":"2026-07-24T21:51:23.70556Z","steps":["trace[54619078] 'agreement among raft nodes before linearized reading' (duration: 174.195868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:51:23.705458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.071335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:51:23.705789Z","caller":"traceutil/trace.go:171","msg":"trace[669689655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15095; }","duration":"228.436452ms","start":"2026-07-24T21:51:23.477342Z","end":"2026-07-24T21:51:23.705779Z","steps":["trace[669689655] 'agreement among raft nodes before linearized reading' (duration: 228.096106ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:51:23.848161Z","caller":"traceutil/trace.go:171","msg":"trace[1353390820] transaction","detail":"{read_only:false; response_revision:15097; number_of_response:1; }","duration":"129.381505ms","start":"2026-07-24T21:51:23.71874Z","end":"2026-07-24T21:51:23.848122Z","steps":["trace[1353390820] 'process raft request' (duration: 128.776094ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:51:23.848821Z","caller":"traceutil/trace.go:171","msg":"trace[1957387259] transaction","detail":"{read_only:false; response_revision:15098; number_of_response:1; }","duration":"126.354437ms","start":"2026-07-24T21:51:23.722448Z","end":"2026-07-24T21:51:23.848803Z","steps":["trace[1957387259] 'process raft request' (duration: 125.599863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:51:23.84923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.897386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-2vrzq\" ","response":"range_response_count:1 size:9708"} {"level":"info","ts":"2026-07-24T21:51:23.849301Z","caller":"traceutil/trace.go:171","msg":"trace[237067251] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-2vrzq; range_end:; response_count:1; response_revision:15098; }","duration":"129.000909ms","start":"2026-07-24T21:51:23.720283Z","end":"2026-07-24T21:51:23.849284Z","steps":["trace[237067251] 'agreement among raft nodes before linearized reading' (duration: 128.770793ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:55:11.310705Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13634} {"level":"info","ts":"2026-07-24T21:55:11.438785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13634,"took":"122.692015ms","hash":3583092665,"current-db-size-bytes":63971328,"current-db-size":"64 MB","current-db-size-in-use-bytes":50192384,"current-db-size-in-use":"50 MB"} {"level":"info","ts":"2026-07-24T21:55:11.438845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3583092665,"revision":13634,"compact-revision":9742} {"level":"info","ts":"2026-07-24T21:56:24.492151Z","caller":"traceutil/trace.go:171","msg":"trace[268938401] transaction","detail":"{read_only:false; response_revision:18934; number_of_response:1; }","duration":"243.684327ms","start":"2026-07-24T21:56:24.248442Z","end":"2026-07-24T21:56:24.492126Z","steps":["trace[268938401] 'process raft request' (duration: 243.587595ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:56:24.492189Z","caller":"traceutil/trace.go:171","msg":"trace[767475576] transaction","detail":"{read_only:false; response_revision:18933; number_of_response:1; }","duration":"253.021006ms","start":"2026-07-24T21:56:24.239091Z","end":"2026-07-24T21:56:24.492112Z","steps":["trace[767475576] 'process raft request' (duration: 226.134522ms)","trace[767475576] 'compare' (duration: 26.589718ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:56:24.512984Z","caller":"traceutil/trace.go:171","msg":"trace[870013898] transaction","detail":"{read_only:false; response_revision:18935; number_of_response:1; }","duration":"163.961473ms","start":"2026-07-24T21:56:24.348989Z","end":"2026-07-24T21:56:24.512951Z","steps":["trace[870013898] 'process raft request' (duration: 163.483384ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:56:24.513661Z","caller":"traceutil/trace.go:171","msg":"trace[880284768] transaction","detail":"{read_only:false; response_revision:18936; number_of_response:1; }","duration":"158.547439ms","start":"2026-07-24T21:56:24.354895Z","end":"2026-07-24T21:56:24.513442Z","steps":["trace[880284768] 'process raft request' (duration: 157.722914ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:56:25.146945Z","caller":"traceutil/trace.go:171","msg":"trace[1429067600] transaction","detail":"{read_only:false; response_revision:18938; number_of_response:1; }","duration":"116.093318ms","start":"2026-07-24T21:56:25.030816Z","end":"2026-07-24T21:56:25.146909Z","steps":["trace[1429067600] 'process raft request' (duration: 115.828943ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:56:25.173361Z","caller":"traceutil/trace.go:171","msg":"trace[2057052038] transaction","detail":"{read_only:false; response_revision:18939; number_of_response:1; }","duration":"131.733138ms","start":"2026-07-24T21:56:25.041579Z","end":"2026-07-24T21:56:25.173312Z","steps":["trace[2057052038] 'process raft request' (duration: 120.688497ms)","trace[2057052038] 'compare' (duration: 10.863238ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:56:25.508307Z","caller":"traceutil/trace.go:171","msg":"trace[2025865547] transaction","detail":"{read_only:false; response_revision:18940; number_of_response:1; }","duration":"118.617147ms","start":"2026-07-24T21:56:25.389668Z","end":"2026-07-24T21:56:25.508285Z","steps":["trace[2025865547] 'process raft request' (duration: 118.490644ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:56:25.51186Z","caller":"traceutil/trace.go:171","msg":"trace[1100882983] transaction","detail":"{read_only:false; response_revision:18941; number_of_response:1; }","duration":"115.801374ms","start":"2026-07-24T21:56:25.396039Z","end":"2026-07-24T21:56:25.511841Z","steps":["trace[1100882983] 'process raft request' (duration: 115.416577ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:57:05.335116Z","caller":"traceutil/trace.go:171","msg":"trace[2096705353] transaction","detail":"{read_only:false; response_revision:19404; number_of_response:1; }","duration":"122.303297ms","start":"2026-07-24T21:57:05.212789Z","end":"2026-07-24T21:57:05.335092Z","steps":["trace[2096705353] 'process raft request' (duration: 122.140793ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:57:05.528783Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T21:57:05.533986Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-24T21:57:05.534114Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-24T21:57:11.467027Z","caller":"traceutil/trace.go:171","msg":"trace[1365457347] transaction","detail":"{read_only:false; response_revision:19460; number_of_response:1; }","duration":"108.564694ms","start":"2026-07-24T21:57:11.358445Z","end":"2026-07-24T21:57:11.46701Z","steps":["trace[1365457347] 'process raft request' (duration: 108.441032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:57:12.008937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.867546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T21:57:12.00912Z","caller":"traceutil/trace.go:171","msg":"trace[472308178] transaction","detail":"{read_only:false; response_revision:19464; number_of_response:1; }","duration":"231.193189ms","start":"2026-07-24T21:57:11.777913Z","end":"2026-07-24T21:57:12.009106Z","steps":["trace[472308178] 'process raft request' (duration: 231.118578ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:57:12.009111Z","caller":"traceutil/trace.go:171","msg":"trace[711105664] transaction","detail":"{read_only:false; response_revision:19463; number_of_response:1; }","duration":"252.752721ms","start":"2026-07-24T21:57:11.756334Z","end":"2026-07-24T21:57:12.009087Z","steps":["trace[711105664] 'process raft request' (duration: 46.662222ms)","trace[711105664] 'compare' (duration: 205.517248ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T22:00:11.317471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17892} {"level":"info","ts":"2026-07-24T22:00:11.495798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17892,"took":"149.91825ms","hash":2950890602,"current-db-size-bytes":64815104,"current-db-size":"65 MB","current-db-size-in-use-bytes":39247872,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-07-24T22:00:11.495957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2950890602,"revision":17892,"compact-revision":13634} {"level":"info","ts":"2026-07-24T22:05:11.326951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21464} {"level":"info","ts":"2026-07-24T22:05:11.478508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21464,"took":"147.145229ms","hash":884602265,"current-db-size-bytes":64815104,"current-db-size":"65 MB","current-db-size-in-use-bytes":33148928,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T22:05:11.478584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":884602265,"revision":21464,"compact-revision":17892} {"level":"info","ts":"2026-07-24T22:10:11.338148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23810} {"level":"info","ts":"2026-07-24T22:10:11.459623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23810,"took":"118.106834ms","hash":619740213,"current-db-size-bytes":64815104,"current-db-size":"65 MB","current-db-size-in-use-bytes":32837632,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T22:10:11.459701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":619740213,"revision":23810,"compact-revision":21464} {"level":"info","ts":"2026-07-24T22:11:31.142278Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b37.wal"} {"level":"info","ts":"2026-07-24T22:15:11.344692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26053} {"level":"info","ts":"2026-07-24T22:15:11.473723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26053,"took":"119.565411ms","hash":1089119695,"current-db-size-bytes":64815104,"current-db-size":"65 MB","current-db-size-in-use-bytes":33984512,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T22:15:11.473808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1089119695,"revision":26053,"compact-revision":23810} {"level":"info","ts":"2026-07-24T22:16:58.029528Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T22:16:58.035173Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-24T22:16:58.0353Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-24T22:20:11.353213Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28361} {"level":"info","ts":"2026-07-24T22:20:11.486763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28361,"took":"129.570402ms","hash":3006885310,"current-db-size-bytes":64815104,"current-db-size":"65 MB","current-db-size-in-use-bytes":32845824,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T22:20:11.486833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3006885310,"revision":28361,"compact-revision":26053}