{"level":"warn","ts":"2026-05-10T00:53:28.443276Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T00:53:28.443408Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-10T00:53:28.443493Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-10T00:53:28.443513Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-10T00:53:28.443543Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-10T00:53:28.444509Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-05-10T00:53:28.444616Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-10T00:53:28.449376Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.477029ms"} {"level":"info","ts":"2026-05-10T00:53:28.455501Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-05-10T00:53:28.455606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T00:53:28.45564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-05-10T00:53:28.45565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T00:53:28.455667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-05-10T00:53:28.45571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-05-10T00:53:28.46158Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T00:53:28.463097Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T00:53:28.464371Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-10T00:53:28.466618Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T00:53:28.466965Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T00:53:28.467043Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T00:53:28.467063Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-10T00:53:28.467139Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T00:53:28.468025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-05-10T00:53:28.469706Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-10T00:53:28.471325Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-10T00:53:28.471404Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-10T00:53:28.472019Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-10T00:53:28.471565Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T00:53:28.4716Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T00:53:28.556953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T00:53:28.55703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T00:53:28.557071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-05-10T00:53:28.557095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-05-10T00:53:28.557109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-10T00:53:28.557128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-05-10T00:53:28.557145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-10T00:53:28.558222Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:28.559057Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:53:28.559074Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T00:53:28.55922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:53:28.559613Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:28.559771Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:28.559823Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:53:28.560095Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T00:53:28.560156Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T00:53:28.562955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-10T00:53:28.563397Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-05-10T00:53:59.897016Z","caller":"traceutil/trace.go:171","msg":"trace[1366846863] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"163.707395ms","start":"2026-05-10T00:53:59.733282Z","end":"2026-05-10T00:53:59.896989Z","steps":["trace[1366846863] 'read index received' (duration: 163.178416ms)","trace[1366846863] 'applied index is now lower than readState.Index' (duration: 527.729µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:53:59.897167Z","caller":"traceutil/trace.go:171","msg":"trace[1709253622] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"171.336921ms","start":"2026-05-10T00:53:59.72582Z","end":"2026-05-10T00:53:59.897157Z","steps":["trace[1709253622] 'process raft request' (duration: 170.605789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:59.897408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.72694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"warn","ts":"2026-05-10T00:53:59.897467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.154093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:53:59.897525Z","caller":"traceutil/trace.go:171","msg":"trace[71187306] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:616; }","duration":"147.888803ms","start":"2026-05-10T00:53:59.749616Z","end":"2026-05-10T00:53:59.897505Z","steps":["trace[71187306] 'agreement among raft nodes before linearized reading' (duration: 147.617559ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:59.897561Z","caller":"traceutil/trace.go:171","msg":"trace[464403738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"164.304746ms","start":"2026-05-10T00:53:59.73323Z","end":"2026-05-10T00:53:59.897534Z","steps":["trace[464403738] 'agreement among raft nodes before linearized reading' (duration: 164.082672ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:55.510328Z","caller":"traceutil/trace.go:171","msg":"trace[586026545] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"265.219823ms","start":"2026-05-10T00:54:55.24507Z","end":"2026-05-10T00:54:55.51029Z","steps":["trace[586026545] 'process raft request' (duration: 265.040779ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:18.281293Z","caller":"traceutil/trace.go:171","msg":"trace[1484523105] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"144.764721ms","start":"2026-05-10T00:55:18.136494Z","end":"2026-05-10T00:55:18.281259Z","steps":["trace[1484523105] 'process raft request' (duration: 128.034862ms)","trace[1484523105] 'compare' (duration: 16.288291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:55:40.083469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.56824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:55:40.083713Z","caller":"traceutil/trace.go:171","msg":"trace[439133573] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"343.036344ms","start":"2026-05-10T00:55:39.74066Z","end":"2026-05-10T00:55:40.083696Z","steps":["trace[439133573] 'process raft request' (duration: 342.973573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:40.08381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:39.740638Z","time spent":"343.122717ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:55:40.083831Z","caller":"traceutil/trace.go:171","msg":"trace[1207021321] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"343.703547ms","start":"2026-05-10T00:55:39.740062Z","end":"2026-05-10T00:55:40.083765Z","steps":["trace[1207021321] 'process raft request' (duration: 71.181348ms)","trace[1207021321] 'compare' (duration: 271.455538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:55:40.084111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:55:39.740036Z","time spent":"343.92503ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:55:40.107145Z","caller":"traceutil/trace.go:171","msg":"trace[521877520] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1924; }","duration":"187.787749ms","start":"2026-05-10T00:55:39.919332Z","end":"2026-05-10T00:55:40.10712Z","steps":["trace[521877520] 'read index received' (duration: 143.790104ms)","trace[521877520] 'applied index is now lower than readState.Index' (duration: 43.996615ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:55:40.1072Z","caller":"traceutil/trace.go:171","msg":"trace[240649751] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"294.543726ms","start":"2026-05-10T00:55:39.812631Z","end":"2026-05-10T00:55:40.107175Z","steps":["trace[240649751] 'process raft request' (duration: 294.357113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:40.107341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.621713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:55:40.10738Z","caller":"traceutil/trace.go:171","msg":"trace[115917756] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1857; }","duration":"148.687175ms","start":"2026-05-10T00:55:39.958682Z","end":"2026-05-10T00:55:40.107369Z","steps":["trace[115917756] 'agreement among raft nodes before linearized reading' (duration: 148.595713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:40.107422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.098754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T00:55:40.107497Z","caller":"traceutil/trace.go:171","msg":"trace[1246357291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1857; }","duration":"188.253388ms","start":"2026-05-10T00:55:39.919226Z","end":"2026-05-10T00:55:40.107479Z","steps":["trace[1246357291] 'agreement among raft nodes before linearized reading' (duration: 188.030274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:40.480703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.74961ms","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-05-10T00:55:40.480761Z","caller":"traceutil/trace.go:171","msg":"trace[1779303582] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"163.58538ms","start":"2026-05-10T00:55:40.317151Z","end":"2026-05-10T00:55:40.480736Z","steps":["trace[1779303582] 'process raft request' (duration: 157.120501ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:40.480791Z","caller":"traceutil/trace.go:171","msg":"trace[1045160187] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"199.877882ms","start":"2026-05-10T00:55:40.280879Z","end":"2026-05-10T00:55:40.480757Z","steps":["trace[1045160187] 'range keys from in-memory index tree' (duration: 199.575317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:40.480859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.716705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ad870fa-4198-445a-8530-293a6b083b9b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-10T00:55:40.480954Z","caller":"traceutil/trace.go:171","msg":"trace[1478571699] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3ad870fa-4198-445a-8530-293a6b083b9b; range_end:; response_count:1; response_revision:1858; }","duration":"277.875327ms","start":"2026-05-10T00:55:40.203056Z","end":"2026-05-10T00:55:40.480931Z","steps":["trace[1478571699] 'range keys from in-memory index tree' (duration: 277.51882ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:40.498642Z","caller":"traceutil/trace.go:171","msg":"trace[1748555812] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"148.728625ms","start":"2026-05-10T00:55:40.349882Z","end":"2026-05-10T00:55:40.498611Z","steps":["trace[1748555812] 'process raft request' (duration: 148.49389ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:24.799809Z","caller":"traceutil/trace.go:171","msg":"trace[605117965] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"146.736569ms","start":"2026-05-10T00:56:24.65304Z","end":"2026-05-10T00:56:24.799777Z","steps":["trace[605117965] 'process raft request' (duration: 109.827351ms)","trace[605117965] 'compare' (duration: 36.542322ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:56:24.799927Z","caller":"traceutil/trace.go:171","msg":"trace[1571170932] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"143.530221ms","start":"2026-05-10T00:56:24.656373Z","end":"2026-05-10T00:56:24.799904Z","steps":["trace[1571170932] 'process raft request' (duration: 143.319227ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:30.812598Z","caller":"traceutil/trace.go:171","msg":"trace[1439275963] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"105.906177ms","start":"2026-05-10T00:56:30.706663Z","end":"2026-05-10T00:56:30.81257Z","steps":["trace[1439275963] 'process raft request' (duration: 105.760635ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:30.963986Z","caller":"traceutil/trace.go:171","msg":"trace[1603278792] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"144.449067ms","start":"2026-05-10T00:56:30.819511Z","end":"2026-05-10T00:56:30.96396Z","steps":["trace[1603278792] 'process raft request' (duration: 129.531963ms)","trace[1603278792] 'compare' (duration: 14.69654ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:56:30.964241Z","caller":"traceutil/trace.go:171","msg":"trace[583252405] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2268; }","duration":"129.579905ms","start":"2026-05-10T00:56:30.834632Z","end":"2026-05-10T00:56:30.964212Z","steps":["trace[583252405] 'read index received' (duration: 114.429195ms)","trace[583252405] 'applied index is now lower than readState.Index' (duration: 15.14889ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:56:30.964386Z","caller":"traceutil/trace.go:171","msg":"trace[2016709846] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"143.101762ms","start":"2026-05-10T00:56:30.821259Z","end":"2026-05-10T00:56:30.964361Z","steps":["trace[2016709846] 'process raft request' (duration: 142.656673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:30.964471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.90029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:56:30.964559Z","caller":"traceutil/trace.go:171","msg":"trace[1358557046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2190; }","duration":"124.040013ms","start":"2026-05-10T00:56:30.840498Z","end":"2026-05-10T00:56:30.964538Z","steps":["trace[1358557046] 'agreement among raft nodes before linearized reading' (duration: 123.90969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:56:30.964613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.812967ms","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":"warn","ts":"2026-05-10T00:56:30.964661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.949481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:56:30.964694Z","caller":"traceutil/trace.go:171","msg":"trace[1279156253] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2190; }","duration":"117.934739ms","start":"2026-05-10T00:56:30.84674Z","end":"2026-05-10T00:56:30.964674Z","steps":["trace[1279156253] 'agreement among raft nodes before linearized reading' (duration: 117.691905ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:56:30.964705Z","caller":"traceutil/trace.go:171","msg":"trace[1751002623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"130.129294ms","start":"2026-05-10T00:56:30.834562Z","end":"2026-05-10T00:56:30.964691Z","steps":["trace[1751002623] 'agreement among raft nodes before linearized reading' (duration: 129.832369ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:57:26.860191Z","caller":"traceutil/trace.go:171","msg":"trace[1617795969] linearizableReadLoop","detail":"{readStateIndex:2759; appliedIndex:2758; }","duration":"138.831492ms","start":"2026-05-10T00:57:26.721339Z","end":"2026-05-10T00:57:26.86017Z","steps":["trace[1617795969] 'read index received' (duration: 138.692829ms)","trace[1617795969] 'applied index is now lower than readState.Index' (duration: 137.413µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:57:26.860268Z","caller":"traceutil/trace.go:171","msg":"trace[210868452] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"155.106365ms","start":"2026-05-10T00:57:26.705118Z","end":"2026-05-10T00:57:26.860224Z","steps":["trace[210868452] 'process raft request' (duration: 154.916061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:57:26.860339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.764735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:57:26.860364Z","caller":"traceutil/trace.go:171","msg":"trace[1720367825] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2661; }","duration":"134.858577ms","start":"2026-05-10T00:57:26.725498Z","end":"2026-05-10T00:57:26.860357Z","steps":["trace[1720367825] 'agreement among raft nodes before linearized reading' (duration: 134.786436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:57:26.860493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.51302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-10T00:57:26.86058Z","caller":"traceutil/trace.go:171","msg":"trace[1853813389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2661; }","duration":"134.634913ms","start":"2026-05-10T00:57:26.725924Z","end":"2026-05-10T00:57:26.860559Z","steps":["trace[1853813389] 'agreement among raft nodes before linearized reading' (duration: 134.422579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:57:26.861078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.740828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-10T00:57:26.861119Z","caller":"traceutil/trace.go:171","msg":"trace[2018806183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2661; }","duration":"139.780119ms","start":"2026-05-10T00:57:26.721327Z","end":"2026-05-10T00:57:26.861107Z","steps":["trace[2018806183] 'agreement among raft nodes before linearized reading' (duration: 138.917813ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:50.854053Z","caller":"traceutil/trace.go:171","msg":"trace[1911102352] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"208.796476ms","start":"2026-05-10T01:01:50.645238Z","end":"2026-05-10T01:01:50.854035Z","steps":["trace[1911102352] 'read index received' (duration: 160.689337ms)","trace[1911102352] 'applied index is now lower than readState.Index' (duration: 48.106199ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:01:50.854141Z","caller":"traceutil/trace.go:171","msg":"trace[1883456471] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"209.56638ms","start":"2026-05-10T01:01:50.644538Z","end":"2026-05-10T01:01:50.854105Z","steps":["trace[1883456471] 'process raft request' (duration: 161.354449ms)","trace[1883456471] 'compare' (duration: 48.029978ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:01:50.854405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.101031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/loki\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T01:01:50.854555Z","caller":"traceutil/trace.go:171","msg":"trace[486286219] range","detail":"{range_begin:/registry/services/specs/monitoring/loki; range_end:; response_count:0; response_revision:4900; }","duration":"209.326007ms","start":"2026-05-10T01:01:50.645196Z","end":"2026-05-10T01:01:50.854522Z","steps":["trace[486286219] 'agreement among raft nodes before linearized reading' (duration: 208.947929ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:01:54.304882Z","caller":"traceutil/trace.go:171","msg":"trace[1427359764] linearizableReadLoop","detail":"{readStateIndex:5226; appliedIndex:5225; }","duration":"105.36251ms","start":"2026-05-10T01:01:54.199489Z","end":"2026-05-10T01:01:54.304851Z","steps":["trace[1427359764] 'read index received' (duration: 37.05676ms)","trace[1427359764] 'applied index is now lower than readState.Index' (duration: 68.30408ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:01:54.305033Z","caller":"traceutil/trace.go:171","msg":"trace[454208915] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"116.999051ms","start":"2026-05-10T01:01:54.188021Z","end":"2026-05-10T01:01:54.30502Z","steps":["trace[454208915] 'process raft request' (duration: 48.577868ms)","trace[454208915] 'compare' (duration: 67.980804ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:01:54.305321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.81882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-10T01:01:54.3054Z","caller":"traceutil/trace.go:171","msg":"trace[557907350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5040; }","duration":"105.954691ms","start":"2026-05-10T01:01:54.199426Z","end":"2026-05-10T01:01:54.305381Z","steps":["trace[557907350] 'agreement among raft nodes before linearized reading' (duration: 105.716597ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:03:28.934858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3021} {"level":"info","ts":"2026-05-10T01:03:29.014594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3021,"took":"78.179368ms","hash":4267399127,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-10T01:03:29.014678Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4267399127,"revision":3021,"compact-revision":-1} {"level":"info","ts":"2026-05-10T01:08:14.440707Z","caller":"traceutil/trace.go:171","msg":"trace[3742550] linearizableReadLoop","detail":"{readStateIndex:8505; appliedIndex:8504; }","duration":"148.03844ms","start":"2026-05-10T01:08:14.292637Z","end":"2026-05-10T01:08:14.440675Z","steps":["trace[3742550] 'read index received' (duration: 147.803335ms)","trace[3742550] 'applied index is now lower than readState.Index' (duration: 234.155µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:08:14.440799Z","caller":"traceutil/trace.go:171","msg":"trace[1734650328] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"172.122202ms","start":"2026-05-10T01:08:14.268656Z","end":"2026-05-10T01:08:14.440778Z","steps":["trace[1734650328] 'process raft request' (duration: 171.864817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:08:14.441024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.356576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-10T01:08:14.441113Z","caller":"traceutil/trace.go:171","msg":"trace[1568229359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8222; }","duration":"148.500389ms","start":"2026-05-10T01:08:14.292594Z","end":"2026-05-10T01:08:14.441094Z","steps":["trace[1568229359] 'agreement among raft nodes before linearized reading' (duration: 148.214673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:08:14.441165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.193523ms","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-05-10T01:08:14.441269Z","caller":"traceutil/trace.go:171","msg":"trace[758087646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8222; }","duration":"147.334925ms","start":"2026-05-10T01:08:14.293911Z","end":"2026-05-10T01:08:14.441246Z","steps":["trace[758087646] 'agreement among raft nodes before linearized reading' (duration: 147.001499ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:14.613835Z","caller":"traceutil/trace.go:171","msg":"trace[1589812415] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"165.668561ms","start":"2026-05-10T01:08:14.448132Z","end":"2026-05-10T01:08:14.613801Z","steps":["trace[1589812415] 'process raft request' (duration: 162.814407ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:14.623264Z","caller":"traceutil/trace.go:171","msg":"trace[2142373650] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"174.330753ms","start":"2026-05-10T01:08:14.448881Z","end":"2026-05-10T01:08:14.623212Z","steps":["trace[2142373650] 'process raft request' (duration: 174.075878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:08:26.821709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.178458ms","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-05-10T01:08:26.821755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.468501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-10T01:08:26.821814Z","caller":"traceutil/trace.go:171","msg":"trace[1832102512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8286; }","duration":"152.360621ms","start":"2026-05-10T01:08:26.669431Z","end":"2026-05-10T01:08:26.821791Z","steps":["trace[1832102512] 'range keys from in-memory index tree' (duration: 151.943694ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:26.821875Z","caller":"traceutil/trace.go:171","msg":"trace[159067506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8286; }","duration":"145.654815ms","start":"2026-05-10T01:08:26.676192Z","end":"2026-05-10T01:08:26.821847Z","steps":["trace[159067506] 'range keys from in-memory index tree' (duration: 145.282728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:08:26.821838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.910635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z47k4\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-10T01:08:26.821967Z","caller":"traceutil/trace.go:171","msg":"trace[1429172704] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z47k4; range_end:; response_count:1; response_revision:8286; }","duration":"110.072198ms","start":"2026-05-10T01:08:26.711863Z","end":"2026-05-10T01:08:26.821936Z","steps":["trace[1429172704] 'range keys from in-memory index tree' (duration: 109.66933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:08:26.822066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.807515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-10T01:08:26.822126Z","caller":"traceutil/trace.go:171","msg":"trace[1659629797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8286; }","duration":"147.927418ms","start":"2026-05-10T01:08:26.674187Z","end":"2026-05-10T01:08:26.822115Z","steps":["trace[1659629797] 'range keys from in-memory index tree' (duration: 146.9956ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:27.604738Z","caller":"traceutil/trace.go:171","msg":"trace[1042351947] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"186.182785ms","start":"2026-05-10T01:08:27.418509Z","end":"2026-05-10T01:08:27.604692Z","steps":["trace[1042351947] 'process raft request' (duration: 142.947153ms)","trace[1042351947] 'compare' (duration: 42.905876ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:08:27.613643Z","caller":"traceutil/trace.go:171","msg":"trace[682499872] transaction","detail":"{read_only:false; response_revision:8293; number_of_response:1; }","duration":"126.641318ms","start":"2026-05-10T01:08:27.486968Z","end":"2026-05-10T01:08:27.613609Z","steps":["trace[682499872] 'process raft request' (duration: 126.484585ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:28.94142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6111} {"level":"info","ts":"2026-05-10T01:08:29.049175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6111,"took":"105.778396ms","hash":660646474,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":23392256,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-10T01:08:29.049265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":660646474,"revision":6111,"compact-revision":3021} {"level":"info","ts":"2026-05-10T01:08:55.92821Z","caller":"traceutil/trace.go:171","msg":"trace[481649070] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"218.741688ms","start":"2026-05-10T01:08:55.709437Z","end":"2026-05-10T01:08:55.928178Z","steps":["trace[481649070] 'process raft request' (duration: 218.539374ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:55.930009Z","caller":"traceutil/trace.go:171","msg":"trace[25970981] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"220.319467ms","start":"2026-05-10T01:08:55.709655Z","end":"2026-05-10T01:08:55.929974Z","steps":["trace[25970981] 'process raft request' (duration: 220.062952ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:08:56.112211Z","caller":"traceutil/trace.go:171","msg":"trace[4267252] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"133.882503ms","start":"2026-05-10T01:08:55.978297Z","end":"2026-05-10T01:08:56.11218Z","steps":["trace[4267252] 'process raft request' (duration: 120.441991ms)","trace[4267252] 'compare' (duration: 13.30639ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:08:59.412052Z","caller":"traceutil/trace.go:171","msg":"trace[1944991463] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"210.001873ms","start":"2026-05-10T01:08:59.202019Z","end":"2026-05-10T01:08:59.412021Z","steps":["trace[1944991463] 'process raft request' (duration: 209.83824ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:09:08.97897Z","caller":"traceutil/trace.go:171","msg":"trace[515327238] linearizableReadLoop","detail":"{readStateIndex:8908; appliedIndex:8907; }","duration":"166.306642ms","start":"2026-05-10T01:09:08.812632Z","end":"2026-05-10T01:09:08.978939Z","steps":["trace[515327238] 'read index received' (duration: 117.523397ms)","trace[515327238] 'applied index is now lower than readState.Index' (duration: 48.781975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:09:08.979312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.65436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-10T01:09:08.979361Z","caller":"traceutil/trace.go:171","msg":"trace[1917118026] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"227.89273ms","start":"2026-05-10T01:09:08.751412Z","end":"2026-05-10T01:09:08.979305Z","steps":["trace[1917118026] 'process raft request' (duration: 178.747627ms)","trace[1917118026] 'compare' (duration: 48.586512ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:09:08.979438Z","caller":"traceutil/trace.go:171","msg":"trace[20768172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8608; }","duration":"166.826863ms","start":"2026-05-10T01:09:08.812584Z","end":"2026-05-10T01:09:08.979411Z","steps":["trace[20768172] 'agreement among raft nodes before linearized reading' (duration: 166.541138ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:09:59.385603Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248b.wal"} {"level":"info","ts":"2026-05-10T01:11:07.669462Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-10T01:11:07.675446Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-10T01:11:07.675602Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-10T01:13:28.947176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8302} {"level":"info","ts":"2026-05-10T01:13:29.023226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8302,"took":"73.918613ms","hash":2707658243,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":25493504,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-10T01:13:29.023334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2707658243,"revision":8302,"compact-revision":6111} {"level":"info","ts":"2026-05-10T01:18:28.956403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10789} {"level":"info","ts":"2026-05-10T01:18:29.028687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10789,"took":"68.754105ms","hash":1994909301,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":27287552,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-10T01:18:29.02875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1994909301,"revision":10789,"compact-revision":8302} {"level":"info","ts":"2026-05-10T01:20:41.727393Z","caller":"traceutil/trace.go:171","msg":"trace[928805367] transaction","detail":"{read_only:false; response_revision:13840; number_of_response:1; }","duration":"180.485986ms","start":"2026-05-10T01:20:41.546873Z","end":"2026-05-10T01:20:41.727359Z","steps":["trace[928805367] 'process raft request' (duration: 180.291872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:20:41.942348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.905235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T01:20:41.942417Z","caller":"traceutil/trace.go:171","msg":"trace[1414162637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13840; }","duration":"103.031838ms","start":"2026-05-10T01:20:41.839372Z","end":"2026-05-10T01:20:41.942404Z","steps":["trace[1414162637] 'range keys from in-memory index tree' (duration: 102.788043ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:21:02.351292Z","caller":"traceutil/trace.go:171","msg":"trace[413422565] linearizableReadLoop","detail":"{readStateIndex:14622; appliedIndex:14620; }","duration":"237.060965ms","start":"2026-05-10T01:21:02.114206Z","end":"2026-05-10T01:21:02.351267Z","steps":["trace[413422565] 'read index received' (duration: 221.667547ms)","trace[413422565] 'applied index is now lower than readState.Index' (duration: 15.392308ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:21:02.351436Z","caller":"traceutil/trace.go:171","msg":"trace[1895346606] transaction","detail":"{read_only:false; response_revision:14136; number_of_response:1; }","duration":"325.102851ms","start":"2026-05-10T01:21:02.02629Z","end":"2026-05-10T01:21:02.351393Z","steps":["trace[1895346606] 'process raft request' (duration: 309.584531ms)","trace[1895346606] 'compare' (duration: 15.182353ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:21:02.351607Z","caller":"traceutil/trace.go:171","msg":"trace[1381868670] transaction","detail":"{read_only:false; response_revision:14137; number_of_response:1; }","duration":"268.438782ms","start":"2026-05-10T01:21:02.08314Z","end":"2026-05-10T01:21:02.351578Z","steps":["trace[1381868670] 'process raft request' (duration: 268.067205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:21:02.351705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.487533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-456f2542-d3cc-41a8-aff6-520841f50128\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-10T01:21:02.351756Z","caller":"traceutil/trace.go:171","msg":"trace[1952263902] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-456f2542-d3cc-41a8-aff6-520841f50128; range_end:; response_count:1; response_revision:14137; }","duration":"237.588755ms","start":"2026-05-10T01:21:02.114154Z","end":"2026-05-10T01:21:02.351743Z","steps":["trace[1952263902] 'agreement among raft nodes before linearized reading' (duration: 237.213718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:21:02.351775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:21:02.02626Z","time spent":"325.413177ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:22:28.937319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.92907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T01:22:28.938187Z","caller":"traceutil/trace.go:171","msg":"trace[1274593535] transaction","detail":"{read_only:false; response_revision:14949; number_of_response:1; }","duration":"273.760296ms","start":"2026-05-10T01:22:28.664376Z","end":"2026-05-10T01:22:28.938136Z","steps":["trace[1274593535] 'process raft request' (duration: 165.903769ms)","trace[1274593535] 'compare' (duration: 106.816988ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:22:28.938408Z","caller":"traceutil/trace.go:171","msg":"trace[403843718] linearizableReadLoop","detail":"{readStateIndex:15459; appliedIndex:15458; }","duration":"217.242074ms","start":"2026-05-10T01:22:28.721147Z","end":"2026-05-10T01:22:28.938389Z","steps":["trace[403843718] 'read index received' (duration: 109.161092ms)","trace[403843718] 'applied index is now lower than readState.Index' (duration: 108.079602ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:22:28.938741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.582441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4d3efa8d-ab73-4004-800f-5c8860d8d4bf\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-05-10T01:22:28.938796Z","caller":"traceutil/trace.go:171","msg":"trace[1246425510] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4d3efa8d-ab73-4004-800f-5c8860d8d4bf; range_end:; response_count:1; response_revision:14951; }","duration":"217.679353ms","start":"2026-05-10T01:22:28.721098Z","end":"2026-05-10T01:22:28.938777Z","steps":["trace[1246425510] 'agreement among raft nodes before linearized reading' (duration: 217.49694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:22:28.938831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.41394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-10T01:22:28.93888Z","caller":"traceutil/trace.go:171","msg":"trace[709088076] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14951; }","duration":"165.495382ms","start":"2026-05-10T01:22:28.77337Z","end":"2026-05-10T01:22:28.938866Z","steps":["trace[709088076] 'agreement among raft nodes before linearized reading' (duration: 165.353559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:22:28.939271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.524462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T01:22:28.939364Z","caller":"traceutil/trace.go:171","msg":"trace[1473134014] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14951; }","duration":"165.634144ms","start":"2026-05-10T01:22:28.773712Z","end":"2026-05-10T01:22:28.939346Z","steps":["trace[1473134014] 'agreement among raft nodes before linearized reading' (duration: 165.474761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:22:28.939728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.521264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-10T01:22:28.939786Z","caller":"traceutil/trace.go:171","msg":"trace[1869180399] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14951; }","duration":"164.622516ms","start":"2026-05-10T01:22:28.775152Z","end":"2026-05-10T01:22:28.939775Z","steps":["trace[1869180399] 'agreement among raft nodes before linearized reading' (duration: 164.378511ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:23:28.963675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12969} {"level":"info","ts":"2026-05-10T01:23:29.038263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12969,"took":"72.075179ms","hash":2089435163,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":30306304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-10T01:23:29.03833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2089435163,"revision":12969,"compact-revision":10789} {"level":"info","ts":"2026-05-10T01:28:16.471491Z","caller":"traceutil/trace.go:171","msg":"trace[680541742] linearizableReadLoop","detail":"{readStateIndex:18125; appliedIndex:18122; }","duration":"253.999744ms","start":"2026-05-10T01:28:16.217469Z","end":"2026-05-10T01:28:16.471469Z","steps":["trace[680541742] 'read index received' (duration: 13.643445ms)","trace[680541742] 'applied index is now lower than readState.Index' (duration: 240.355189ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:28:16.471613Z","caller":"traceutil/trace.go:171","msg":"trace[892670069] transaction","detail":"{read_only:false; response_revision:17531; number_of_response:1; }","duration":"255.567033ms","start":"2026-05-10T01:28:16.216033Z","end":"2026-05-10T01:28:16.4716Z","steps":["trace[892670069] 'process raft request' (duration: 255.38453ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:28:16.471621Z","caller":"traceutil/trace.go:171","msg":"trace[1199361354] transaction","detail":"{read_only:false; response_revision:17530; number_of_response:1; }","duration":"255.629964ms","start":"2026-05-10T01:28:16.215959Z","end":"2026-05-10T01:28:16.471589Z","steps":["trace[1199361354] 'process raft request' (duration: 255.324418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:28:16.471777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.274949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:28:16.47187Z","caller":"traceutil/trace.go:171","msg":"trace[4457968] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:17531; }","duration":"254.422662ms","start":"2026-05-10T01:28:16.217428Z","end":"2026-05-10T01:28:16.471851Z","steps":["trace[4457968] 'agreement among raft nodes before linearized reading' (duration: 254.234888ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:28:16.471954Z","caller":"traceutil/trace.go:171","msg":"trace[509000200] transaction","detail":"{read_only:false; response_revision:17532; number_of_response:1; }","duration":"187.688832ms","start":"2026-05-10T01:28:16.284257Z","end":"2026-05-10T01:28:16.471946Z","steps":["trace[509000200] 'process raft request' (duration: 187.519509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:28:16.472114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.451175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:28:16.472243Z","caller":"traceutil/trace.go:171","msg":"trace[1898734828] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:17532; }","duration":"221.639207ms","start":"2026-05-10T01:28:16.250577Z","end":"2026-05-10T01:28:16.472216Z","steps":["trace[1898734828] 'agreement among raft nodes before linearized reading' (duration: 221.343702ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:28:16.858186Z","caller":"traceutil/trace.go:171","msg":"trace[1835546459] transaction","detail":"{read_only:false; response_revision:17533; number_of_response:1; }","duration":"238.325641ms","start":"2026-05-10T01:28:16.619842Z","end":"2026-05-10T01:28:16.858168Z","steps":["trace[1835546459] 'process raft request' (duration: 238.030586ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:28:17.3122Z","caller":"traceutil/trace.go:171","msg":"trace[1763708034] linearizableReadLoop","detail":"{readStateIndex:18130; appliedIndex:18127; }","duration":"437.568339ms","start":"2026-05-10T01:28:16.874553Z","end":"2026-05-10T01:28:17.312121Z","steps":["trace[1763708034] 'read index received' (duration: 14.883188ms)","trace[1763708034] 'applied index is now lower than readState.Index' (duration: 422.683641ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:28:17.312239Z","caller":"traceutil/trace.go:171","msg":"trace[1231266636] transaction","detail":"{read_only:false; response_revision:17535; number_of_response:1; }","duration":"444.493278ms","start":"2026-05-10T01:28:16.867716Z","end":"2026-05-10T01:28:17.31221Z","steps":["trace[1231266636] 'process raft request' (duration: 444.192443ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:28:17.312335Z","caller":"traceutil/trace.go:171","msg":"trace[1851470473] transaction","detail":"{read_only:false; response_revision:17536; number_of_response:1; }","duration":"439.561566ms","start":"2026-05-10T01:28:16.872756Z","end":"2026-05-10T01:28:17.312318Z","steps":["trace[1851470473] 'process raft request' (duration: 439.305922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:28:17.312371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:28:16.867692Z","time spent":"444.60663ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:28:17.312545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:28:16.872736Z","time spent":"439.645727ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:28:17.312804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.017155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-b6xvc\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-05-10T01:28:17.312841Z","caller":"traceutil/trace.go:171","msg":"trace[1651709712] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-b6xvc; range_end:; response_count:1; response_revision:17536; }","duration":"330.085887ms","start":"2026-05-10T01:28:16.982746Z","end":"2026-05-10T01:28:17.312832Z","steps":["trace[1651709712] 'agreement among raft nodes before linearized reading' (duration: 329.963395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:28:17.312863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:28:16.982721Z","time spent":"330.136867ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-b6xvc\" "} {"level":"warn","ts":"2026-05-10T01:28:17.313022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.475986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hmhsn\" ","response":"range_response_count:1 size:20873"} {"level":"info","ts":"2026-05-10T01:28:17.313047Z","caller":"traceutil/trace.go:171","msg":"trace[1106853244] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-hmhsn; range_end:; response_count:1; response_revision:17536; }","duration":"438.529398ms","start":"2026-05-10T01:28:16.874511Z","end":"2026-05-10T01:28:17.313041Z","steps":["trace[1106853244] 'agreement among raft nodes before linearized reading' (duration: 438.462195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:28:17.313066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:28:16.874501Z","time spent":"438.561518ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20896,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hmhsn\" "} {"level":"info","ts":"2026-05-10T01:28:17.446725Z","caller":"traceutil/trace.go:171","msg":"trace[437403362] transaction","detail":"{read_only:false; response_revision:17538; number_of_response:1; }","duration":"116.348188ms","start":"2026-05-10T01:28:17.330352Z","end":"2026-05-10T01:28:17.4467Z","steps":["trace[437403362] 'process raft request' (duration: 110.412967ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:28:28.970202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15342} {"level":"info","ts":"2026-05-10T01:28:29.06853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15342,"took":"92.838367ms","hash":3820236608,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":32415744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-10T01:28:29.068612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3820236608,"revision":15342,"compact-revision":12969} {"level":"info","ts":"2026-05-10T01:31:57.22298Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-10T01:31:57.227841Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-10T01:31:57.227931Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-10T01:33:28.976176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17738} {"level":"info","ts":"2026-05-10T01:33:29.075175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17738,"took":"95.308925ms","hash":3839421596,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":33898496,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-10T01:33:29.075229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3839421596,"revision":17738,"compact-revision":15342} {"level":"info","ts":"2026-05-10T01:34:13.388814Z","caller":"traceutil/trace.go:171","msg":"trace[1349438547] transaction","detail":"{read_only:false; response_revision:20273; number_of_response:1; }","duration":"308.323231ms","start":"2026-05-10T01:34:13.080462Z","end":"2026-05-10T01:34:13.388785Z","steps":["trace[1349438547] 'process raft request' (duration: 308.25315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:34:13.388986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:34:13.080441Z","time spent":"308.460613ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:34:13.389443Z","caller":"traceutil/trace.go:171","msg":"trace[1328688126] transaction","detail":"{read_only:false; response_revision:20272; number_of_response:1; }","duration":"308.933072ms","start":"2026-05-10T01:34:13.080428Z","end":"2026-05-10T01:34:13.389361Z","steps":["trace[1328688126] 'process raft request' (duration: 236.014927ms)","trace[1328688126] 'compare' (duration: 71.845074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:34:13.38953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:34:13.080393Z","time spent":"309.093135ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:34:13.390986Z","caller":"traceutil/trace.go:171","msg":"trace[1965659425] transaction","detail":"{read_only:false; response_revision:20274; number_of_response:1; }","duration":"141.347505ms","start":"2026-05-10T01:34:13.249609Z","end":"2026-05-10T01:34:13.390956Z","steps":["trace[1965659425] 'process raft request' (duration: 141.213772ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:37:57.328245Z","caller":"traceutil/trace.go:171","msg":"trace[1806134812] linearizableReadLoop","detail":"{readStateIndex:23406; appliedIndex:23405; }","duration":"434.333381ms","start":"2026-05-10T01:37:56.893865Z","end":"2026-05-10T01:37:57.328198Z","steps":["trace[1806134812] 'read index received' (duration: 434.173668ms)","trace[1806134812] 'applied index is now lower than readState.Index' (duration: 158.453µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:37:57.32832Z","caller":"traceutil/trace.go:171","msg":"trace[914987154] transaction","detail":"{read_only:false; response_revision:22651; number_of_response:1; }","duration":"452.054793ms","start":"2026-05-10T01:37:56.876238Z","end":"2026-05-10T01:37:57.328292Z","steps":["trace[914987154] 'process raft request' (duration: 451.768808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:37:57.328417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.537785ms","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:6"} {"level":"info","ts":"2026-05-10T01:37:57.32846Z","caller":"traceutil/trace.go:171","msg":"trace[752197874] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:22651; }","duration":"434.631517ms","start":"2026-05-10T01:37:56.893814Z","end":"2026-05-10T01:37:57.328446Z","steps":["trace[752197874] 'agreement among raft nodes before linearized reading' (duration: 434.546795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:37:57.328502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:37:56.893794Z","time spent":"434.698708ms","remote":"127.0.0.1:34638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:37:57.328992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:37:56.876209Z","time spent":"452.166576ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:37:57.334372Z","caller":"traceutil/trace.go:171","msg":"trace[134925433] transaction","detail":"{read_only:false; response_revision:22652; number_of_response:1; }","duration":"179.259031ms","start":"2026-05-10T01:37:57.155091Z","end":"2026-05-10T01:37:57.33435Z","steps":["trace[134925433] 'process raft request' (duration: 178.921584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:37:57.334397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.679472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-10T01:37:57.334444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.764845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-05-10T01:37:57.334462Z","caller":"traceutil/trace.go:171","msg":"trace[828804124] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:22652; }","duration":"183.782094ms","start":"2026-05-10T01:37:57.150662Z","end":"2026-05-10T01:37:57.334444Z","steps":["trace[828804124] 'agreement among raft nodes before linearized reading' (duration: 183.598411ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:37:57.334478Z","caller":"traceutil/trace.go:171","msg":"trace[1581671902] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22652; }","duration":"151.832486ms","start":"2026-05-10T01:37:57.182635Z","end":"2026-05-10T01:37:57.334468Z","steps":["trace[1581671902] 'agreement among raft nodes before linearized reading' (duration: 151.720834ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:38:28.982099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20044} {"level":"info","ts":"2026-05-10T01:38:29.094344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20044,"took":"108.633069ms","hash":2435560984,"current-db-size-bytes":58486784,"current-db-size":"58 MB","current-db-size-in-use-bytes":40910848,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-10T01:38:29.094428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2435560984,"revision":20044,"compact-revision":17738} {"level":"info","ts":"2026-05-10T01:41:02.998504Z","caller":"traceutil/trace.go:171","msg":"trace[2048969180] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"102.599376ms","start":"2026-05-10T01:41:02.895883Z","end":"2026-05-10T01:41:02.998482Z","steps":["trace[2048969180] 'process raft request' (duration: 102.435723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:03.378811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.053825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:03.379146Z","caller":"traceutil/trace.go:171","msg":"trace[947710467] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"109.292051ms","start":"2026-05-10T01:41:03.269837Z","end":"2026-05-10T01:41:03.379129Z","steps":["trace[947710467] 'process raft request' (duration: 109.23916ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:03.379401Z","caller":"traceutil/trace.go:171","msg":"trace[1005276960] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"483.324151ms","start":"2026-05-10T01:41:02.896065Z","end":"2026-05-10T01:41:03.379389Z","steps":["trace[1005276960] 'process raft request' (duration: 380.604553ms)","trace[1005276960] 'compare' (duration: 101.929122ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:03.37947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:02.896044Z","time spent":"483.387052ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:03.379693Z","caller":"traceutil/trace.go:171","msg":"trace[1899992111] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"149.505146ms","start":"2026-05-10T01:41:03.230163Z","end":"2026-05-10T01:41:03.379668Z","steps":["trace[1899992111] 'process raft request' (duration: 148.845494ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:03.379724Z","caller":"traceutil/trace.go:171","msg":"trace[1164319606] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"115.008688ms","start":"2026-05-10T01:41:03.264702Z","end":"2026-05-10T01:41:03.379711Z","steps":["trace[1164319606] 'process raft request' (duration: 114.341766ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:03.379844Z","caller":"traceutil/trace.go:171","msg":"trace[251711889] linearizableReadLoop","detail":"{readStateIndex:25424; appliedIndex:25422; }","duration":"310.067189ms","start":"2026-05-10T01:41:03.069768Z","end":"2026-05-10T01:41:03.379835Z","steps":["trace[251711889] 'read index received' (duration: 206.908973ms)","trace[251711889] 'applied index is now lower than readState.Index' (duration: 103.157246ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:03.379926Z","caller":"traceutil/trace.go:171","msg":"trace[1257960873] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"394.069796ms","start":"2026-05-10T01:41:02.985846Z","end":"2026-05-10T01:41:03.379916Z","steps":["trace[1257960873] 'process raft request' (duration: 393.066186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:03.379988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:02.985828Z","time spent":"394.122896ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:03.380317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.407732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:41:03.380354Z","caller":"traceutil/trace.go:171","msg":"trace[1958889372] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24622; }","duration":"110.487664ms","start":"2026-05-10T01:41:03.269857Z","end":"2026-05-10T01:41:03.380345Z","steps":["trace[1958889372] 'agreement among raft nodes before linearized reading' (duration: 110.403132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:03.380391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.901023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418399"} {"level":"info","ts":"2026-05-10T01:41:03.380445Z","caller":"traceutil/trace.go:171","msg":"trace[1730002655] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24622; }","duration":"246.995805ms","start":"2026-05-10T01:41:03.133437Z","end":"2026-05-10T01:41:03.380432Z","steps":["trace[1730002655] 'agreement among raft nodes before linearized reading' (duration: 246.780831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:03.380446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.63907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-10T01:41:03.380511Z","caller":"traceutil/trace.go:171","msg":"trace[549357505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24622; }","duration":"310.758451ms","start":"2026-05-10T01:41:03.069736Z","end":"2026-05-10T01:41:03.380494Z","steps":["trace[549357505] 'agreement among raft nodes before linearized reading' (duration: 310.355634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:03.380563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:03.06972Z","time spent":"310.830663ms","remote":"127.0.0.1:51628","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-05-10T01:41:03.587028Z","caller":"traceutil/trace.go:171","msg":"trace[896868431] linearizableReadLoop","detail":"{readStateIndex:25429; appliedIndex:25427; }","duration":"170.450959ms","start":"2026-05-10T01:41:03.41656Z","end":"2026-05-10T01:41:03.587011Z","steps":["trace[896868431] 'read index received' (duration: 101.26169ms)","trace[896868431] 'applied index is now lower than readState.Index' (duration: 69.188439ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:03.587342Z","caller":"traceutil/trace.go:171","msg":"trace[1157350064] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"202.943389ms","start":"2026-05-10T01:41:03.384384Z","end":"2026-05-10T01:41:03.587328Z","steps":["trace[1157350064] 'process raft request' (duration: 133.487195ms)","trace[1157350064] 'compare' (duration: 68.906214ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:03.587935Z","caller":"traceutil/trace.go:171","msg":"trace[583117010] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"203.489279ms","start":"2026-05-10T01:41:03.384431Z","end":"2026-05-10T01:41:03.58792Z","steps":["trace[583117010] 'process raft request' (duration: 202.539991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:03.5878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.211453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:41:03.588104Z","caller":"traceutil/trace.go:171","msg":"trace[678161889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24624; }","duration":"171.552579ms","start":"2026-05-10T01:41:03.416527Z","end":"2026-05-10T01:41:03.588079Z","steps":["trace[678161889] 'agreement among raft nodes before linearized reading' (duration: 171.06163ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:07.932252Z","caller":"traceutil/trace.go:171","msg":"trace[497120014] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"238.891663ms","start":"2026-05-10T01:41:07.693293Z","end":"2026-05-10T01:41:07.932185Z","steps":["trace[497120014] 'process raft request' (duration: 140.906274ms)","trace[497120014] 'compare' (duration: 97.656343ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:07.93256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.750486ms","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:8"} {"level":"info","ts":"2026-05-10T01:41:07.932697Z","caller":"traceutil/trace.go:171","msg":"trace[457527878] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24658; }","duration":"128.95121ms","start":"2026-05-10T01:41:07.803717Z","end":"2026-05-10T01:41:07.932668Z","steps":["trace[457527878] 'agreement among raft nodes before linearized reading' (duration: 128.598933ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:07.932873Z","caller":"traceutil/trace.go:171","msg":"trace[265824537] linearizableReadLoop","detail":"{readStateIndex:25464; appliedIndex:25463; }","duration":"128.346969ms","start":"2026-05-10T01:41:07.803768Z","end":"2026-05-10T01:41:07.932115Z","steps":["trace[265824537] 'read index received' (duration: 30.479582ms)","trace[265824537] 'applied index is now lower than readState.Index' (duration: 97.865657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:08.251532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.528077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:08.251703Z","caller":"traceutil/trace.go:171","msg":"trace[1419529869] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"207.338061ms","start":"2026-05-10T01:41:08.044321Z","end":"2026-05-10T01:41:08.251659Z","steps":["trace[1419529869] 'process raft request' (duration: 66.617161ms)","trace[1419529869] 'compare' (duration: 140.431485ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:08.543517Z","caller":"traceutil/trace.go:171","msg":"trace[1407660557] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"248.060384ms","start":"2026-05-10T01:41:08.295428Z","end":"2026-05-10T01:41:08.543488Z","steps":["trace[1407660557] 'process raft request' (duration: 247.799289ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:08.582875Z","caller":"traceutil/trace.go:171","msg":"trace[192767551] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"158.649678ms","start":"2026-05-10T01:41:08.42419Z","end":"2026-05-10T01:41:08.58284Z","steps":["trace[192767551] 'process raft request' (duration: 158.288531ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:08.786736Z","caller":"traceutil/trace.go:171","msg":"trace[1573630116] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"106.59565ms","start":"2026-05-10T01:41:08.680104Z","end":"2026-05-10T01:41:08.786699Z","steps":["trace[1573630116] 'process raft request' (duration: 106.245574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:09.077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.136257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:09.077065Z","caller":"traceutil/trace.go:171","msg":"trace[1347348833] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24662; }","duration":"223.428674ms","start":"2026-05-10T01:41:08.85362Z","end":"2026-05-10T01:41:09.077049Z","steps":["trace[1347348833] 'range keys from in-memory index tree' (duration: 223.063865ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:09.206893Z","caller":"traceutil/trace.go:171","msg":"trace[1173846465] linearizableReadLoop","detail":"{readStateIndex:25469; appliedIndex:25468; }","duration":"123.491137ms","start":"2026-05-10T01:41:09.083374Z","end":"2026-05-10T01:41:09.206865Z","steps":["trace[1173846465] 'read index received' (duration: 123.321104ms)","trace[1173846465] 'applied index is now lower than readState.Index' (duration: 168.853µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:09.206937Z","caller":"traceutil/trace.go:171","msg":"trace[2012080908] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"125.152949ms","start":"2026-05-10T01:41:09.081749Z","end":"2026-05-10T01:41:09.206902Z","steps":["trace[2012080908] 'process raft request' (duration: 124.950455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:09.207125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.726511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-10T01:41:09.207167Z","caller":"traceutil/trace.go:171","msg":"trace[1248877616] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24663; }","duration":"123.814424ms","start":"2026-05-10T01:41:09.083342Z","end":"2026-05-10T01:41:09.207157Z","steps":["trace[1248877616] 'agreement among raft nodes before linearized reading' (duration: 123.6266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:09.511492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.690448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:09.511618Z","caller":"traceutil/trace.go:171","msg":"trace[1491310846] linearizableReadLoop","detail":"{readStateIndex:25470; appliedIndex:25469; }","duration":"216.891181ms","start":"2026-05-10T01:41:09.294703Z","end":"2026-05-10T01:41:09.511595Z","steps":["trace[1491310846] 'read index received' (duration: 65.00206ms)","trace[1491310846] 'applied index is now lower than readState.Index' (duration: 151.887461ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:09.511714Z","caller":"traceutil/trace.go:171","msg":"trace[1577701828] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"268.714973ms","start":"2026-05-10T01:41:09.242973Z","end":"2026-05-10T01:41:09.511688Z","steps":["trace[1577701828] 'process raft request' (duration: 116.717849ms)","trace[1577701828] 'compare' (duration: 151.115817ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:09.511914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.168056ms","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:8"} {"level":"info","ts":"2026-05-10T01:41:09.511995Z","caller":"traceutil/trace.go:171","msg":"trace[1489653379] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24664; }","duration":"217.315488ms","start":"2026-05-10T01:41:09.294663Z","end":"2026-05-10T01:41:09.511979Z","steps":["trace[1489653379] 'agreement among raft nodes before linearized reading' (duration: 217.146915ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:09.514581Z","caller":"traceutil/trace.go:171","msg":"trace[1515727512] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"176.060555ms","start":"2026-05-10T01:41:09.338497Z","end":"2026-05-10T01:41:09.514558Z","steps":["trace[1515727512] 'process raft request' (duration: 175.886331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:09.515496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.078841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-10T01:41:09.515512Z","caller":"traceutil/trace.go:171","msg":"trace[793631856] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"105.641263ms","start":"2026-05-10T01:41:09.409852Z","end":"2026-05-10T01:41:09.515494Z","steps":["trace[793631856] 'process raft request' (duration: 105.51259ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:09.515543Z","caller":"traceutil/trace.go:171","msg":"trace[849620553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24669; }","duration":"114.159182ms","start":"2026-05-10T01:41:09.401375Z","end":"2026-05-10T01:41:09.515534Z","steps":["trace[849620553] 'agreement among raft nodes before linearized reading' (duration: 114.01958ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:09.515531Z","caller":"traceutil/trace.go:171","msg":"trace[1091151667] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"115.839353ms","start":"2026-05-10T01:41:09.399674Z","end":"2026-05-10T01:41:09.515514Z","steps":["trace[1091151667] 'process raft request' (duration: 115.66776ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:09.515574Z","caller":"traceutil/trace.go:171","msg":"trace[733853187] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"117.106278ms","start":"2026-05-10T01:41:09.398401Z","end":"2026-05-10T01:41:09.515507Z","steps":["trace[733853187] 'process raft request' (duration: 116.910104ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:09.515648Z","caller":"traceutil/trace.go:171","msg":"trace[35758759] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"117.634067ms","start":"2026-05-10T01:41:09.398007Z","end":"2026-05-10T01:41:09.515641Z","steps":["trace[35758759] 'process raft request' (duration: 117.23125ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:09.710353Z","caller":"traceutil/trace.go:171","msg":"trace[223719406] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"189.68597ms","start":"2026-05-10T01:41:09.520634Z","end":"2026-05-10T01:41:09.710319Z","steps":["trace[223719406] 'process raft request' (duration: 160.900399ms)","trace[223719406] 'compare' (duration: 28.589047ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:09.721955Z","caller":"traceutil/trace.go:171","msg":"trace[1298786149] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"112.339598ms","start":"2026-05-10T01:41:09.609582Z","end":"2026-05-10T01:41:09.721921Z","steps":["trace[1298786149] 'process raft request' (duration: 112.118274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:10.112729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.417885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:10.112895Z","caller":"traceutil/trace.go:171","msg":"trace[1951123128] linearizableReadLoop","detail":"{readStateIndex:25478; appliedIndex:25477; }","duration":"377.038125ms","start":"2026-05-10T01:41:09.735832Z","end":"2026-05-10T01:41:10.11287Z","steps":["trace[1951123128] 'read index received' (duration: 123.290844ms)","trace[1951123128] 'applied index is now lower than readState.Index' (duration: 253.745581ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:10.1132Z","caller":"traceutil/trace.go:171","msg":"trace[531593512] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"383.15232ms","start":"2026-05-10T01:41:09.730031Z","end":"2026-05-10T01:41:10.113183Z","steps":["trace[531593512] 'process raft request' (duration: 129.152504ms)","trace[531593512] 'compare' (duration: 253.118919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:10.113292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:09.730007Z","time spent":"383.236871ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:10.113516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.122782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:10.113599Z","caller":"traceutil/trace.go:171","msg":"trace[2504874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24672; }","duration":"276.295646ms","start":"2026-05-10T01:41:09.837278Z","end":"2026-05-10T01:41:10.113574Z","steps":["trace[2504874] 'agreement among raft nodes before linearized reading' (duration: 276.080931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:10.113809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.952533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-10T01:41:10.113894Z","caller":"traceutil/trace.go:171","msg":"trace[312102420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24672; }","duration":"378.071025ms","start":"2026-05-10T01:41:09.735803Z","end":"2026-05-10T01:41:10.113874Z","steps":["trace[312102420] 'agreement among raft nodes before linearized reading' (duration: 377.871442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:10.113937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:09.735784Z","time spent":"378.144326ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T01:41:10.114402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.672745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:41:10.114459Z","caller":"traceutil/trace.go:171","msg":"trace[719710832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24672; }","duration":"178.453209ms","start":"2026-05-10T01:41:09.935992Z","end":"2026-05-10T01:41:10.114445Z","steps":["trace[719710832] 'agreement among raft nodes before linearized reading' (duration: 177.535302ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:10.282004Z","caller":"traceutil/trace.go:171","msg":"trace[2015156418] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"163.878756ms","start":"2026-05-10T01:41:10.118104Z","end":"2026-05-10T01:41:10.281982Z","steps":["trace[2015156418] 'process raft request' (duration: 161.341968ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:10.815546Z","caller":"traceutil/trace.go:171","msg":"trace[2055149381] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"265.785438ms","start":"2026-05-10T01:41:10.549724Z","end":"2026-05-10T01:41:10.815509Z","steps":["trace[2055149381] 'process raft request' (duration: 265.528484ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:10.822345Z","caller":"traceutil/trace.go:171","msg":"trace[1508805456] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"233.780788ms","start":"2026-05-10T01:41:10.588539Z","end":"2026-05-10T01:41:10.82232Z","steps":["trace[1508805456] 'process raft request' (duration: 233.378309ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:11.363124Z","caller":"traceutil/trace.go:171","msg":"trace[341804745] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"146.253986ms","start":"2026-05-10T01:41:11.216843Z","end":"2026-05-10T01:41:11.363097Z","steps":["trace[341804745] 'process raft request' (duration: 146.069372ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:11.651148Z","caller":"traceutil/trace.go:171","msg":"trace[1752459921] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"128.892039ms","start":"2026-05-10T01:41:11.522235Z","end":"2026-05-10T01:41:11.651128Z","steps":["trace[1752459921] 'process raft request' (duration: 128.829858ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:11.651162Z","caller":"traceutil/trace.go:171","msg":"trace[816242281] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"129.124873ms","start":"2026-05-10T01:41:11.522Z","end":"2026-05-10T01:41:11.651125Z","steps":["trace[816242281] 'process raft request' (duration: 128.94667ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:11.651299Z","caller":"traceutil/trace.go:171","msg":"trace[432579243] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"129.044391ms","start":"2026-05-10T01:41:11.522248Z","end":"2026-05-10T01:41:11.651292Z","steps":["trace[432579243] 'process raft request' (duration: 128.854538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:11.915432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.41262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-10T01:41:11.915496Z","caller":"traceutil/trace.go:171","msg":"trace[432347785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24683; }","duration":"201.525131ms","start":"2026-05-10T01:41:11.713955Z","end":"2026-05-10T01:41:11.91548Z","steps":["trace[432347785] 'range keys from in-memory index tree' (duration: 201.227986ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:12.064082Z","caller":"traceutil/trace.go:171","msg":"trace[149059747] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"144.384031ms","start":"2026-05-10T01:41:11.919665Z","end":"2026-05-10T01:41:12.064049Z","steps":["trace[149059747] 'process raft request' (duration: 144.128486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:12.331763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.197068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:12.331846Z","caller":"traceutil/trace.go:171","msg":"trace[1341637993] linearizableReadLoop","detail":"{readStateIndex:25492; appliedIndex:25491; }","duration":"214.279471ms","start":"2026-05-10T01:41:12.117553Z","end":"2026-05-10T01:41:12.331833Z","steps":["trace[1341637993] 'read index received' (duration: 95.892059ms)","trace[1341637993] 'applied index is now lower than readState.Index' (duration: 118.386662ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:12.33202Z","caller":"traceutil/trace.go:171","msg":"trace[423530229] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"231.891551ms","start":"2026-05-10T01:41:12.100118Z","end":"2026-05-10T01:41:12.33201Z","steps":["trace[423530229] 'process raft request' (duration: 113.390138ms)","trace[423530229] 'compare' (duration: 118.099826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:12.332317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.771221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T01:41:12.337193Z","caller":"traceutil/trace.go:171","msg":"trace[514026490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24685; }","duration":"219.697473ms","start":"2026-05-10T01:41:12.117485Z","end":"2026-05-10T01:41:12.337183Z","steps":["trace[514026490] 'agreement among raft nodes before linearized reading' (duration: 214.73439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:12.344911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.825549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-10T01:41:12.344971Z","caller":"traceutil/trace.go:171","msg":"trace[1102478590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24686; }","duration":"167.921541ms","start":"2026-05-10T01:41:12.177036Z","end":"2026-05-10T01:41:12.344958Z","steps":["trace[1102478590] 'agreement among raft nodes before linearized reading' (duration: 167.742988ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:12.600092Z","caller":"traceutil/trace.go:171","msg":"trace[782288197] linearizableReadLoop","detail":"{readStateIndex:25494; appliedIndex:25493; }","duration":"255.366452ms","start":"2026-05-10T01:41:12.3447Z","end":"2026-05-10T01:41:12.600067Z","steps":["trace[782288197] 'read index received' (duration: 234.868388ms)","trace[782288197] 'applied index is now lower than readState.Index' (duration: 20.496814ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:12.600349Z","caller":"traceutil/trace.go:171","msg":"trace[1304198447] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"259.55607ms","start":"2026-05-10T01:41:12.340777Z","end":"2026-05-10T01:41:12.600333Z","steps":["trace[1304198447] 'process raft request' (duration: 238.76898ms)","trace[1304198447] 'compare' (duration: 20.351582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:12.600745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.40193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-10T01:41:12.600767Z","caller":"traceutil/trace.go:171","msg":"trace[349045902] transaction","detail":"{read_only:false; response_revision:24688; number_of_response:1; }","duration":"252.698532ms","start":"2026-05-10T01:41:12.348036Z","end":"2026-05-10T01:41:12.600735Z","steps":["trace[349045902] 'process raft request' (duration: 252.485408ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:12.600792Z","caller":"traceutil/trace.go:171","msg":"trace[1743679518] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"252.394397ms","start":"2026-05-10T01:41:12.34837Z","end":"2026-05-10T01:41:12.600765Z","steps":["trace[1743679518] 'process raft request' (duration: 252.268274ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:12.60085Z","caller":"traceutil/trace.go:171","msg":"trace[806503163] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24687; }","duration":"266.557802ms","start":"2026-05-10T01:41:12.334266Z","end":"2026-05-10T01:41:12.600824Z","steps":["trace[806503163] 'agreement among raft nodes before linearized reading' (duration: 266.257566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:12.911919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.448632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:12.91202Z","caller":"traceutil/trace.go:171","msg":"trace[2062728705] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"243.321165ms","start":"2026-05-10T01:41:12.668679Z","end":"2026-05-10T01:41:12.912Z","steps":["trace[2062728705] 'process raft request' (duration: 91.71862ms)","trace[2062728705] 'compare' (duration: 151.34652ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:13.232497Z","caller":"traceutil/trace.go:171","msg":"trace[561424742] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"403.884089ms","start":"2026-05-10T01:41:12.828594Z","end":"2026-05-10T01:41:13.232478Z","steps":["trace[561424742] 'process raft request' (duration: 403.813697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:13.232604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:12.828563Z","time spent":"403.986231ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:13.232719Z","caller":"traceutil/trace.go:171","msg":"trace[1627331302] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"405.112692ms","start":"2026-05-10T01:41:12.827567Z","end":"2026-05-10T01:41:13.23268Z","steps":["trace[1627331302] 'process raft request' (duration: 392.507365ms)","trace[1627331302] 'compare' (duration: 12.156849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:13.232835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:12.827541Z","time spent":"405.227255ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:13.67808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.349147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-10T01:41:13.678162Z","caller":"traceutil/trace.go:171","msg":"trace[334324195] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24694; }","duration":"137.49436ms","start":"2026-05-10T01:41:13.540646Z","end":"2026-05-10T01:41:13.67814Z","steps":["trace[334324195] 'range keys from in-memory index tree' (duration: 137.113533ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:20.531068Z","caller":"traceutil/trace.go:171","msg":"trace[263394313] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"125.532926ms","start":"2026-05-10T01:41:20.405506Z","end":"2026-05-10T01:41:20.531039Z","steps":["trace[263394313] 'process raft request' (duration: 125.372603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:20.755402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.534101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-10T01:41:20.755445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.934456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-10T01:41:20.755495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.876911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:20.755558Z","caller":"traceutil/trace.go:171","msg":"trace[572011312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24745; }","duration":"124.097008ms","start":"2026-05-10T01:41:20.631432Z","end":"2026-05-10T01:41:20.755529Z","steps":["trace[572011312] 'range keys from in-memory index tree' (duration: 123.657971ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:20.755496Z","caller":"traceutil/trace.go:171","msg":"trace[581196741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24745; }","duration":"121.650892ms","start":"2026-05-10T01:41:20.633816Z","end":"2026-05-10T01:41:20.755467Z","steps":["trace[581196741] 'range keys from in-memory index tree' (duration: 121.317417ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:20.755756Z","caller":"traceutil/trace.go:171","msg":"trace[1369017918] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24745; }","duration":"135.005943ms","start":"2026-05-10T01:41:20.620545Z","end":"2026-05-10T01:41:20.755551Z","steps":["trace[1369017918] 'count revisions from in-memory index tree' (duration: 134.776419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:20.755767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.760664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T01:41:20.755834Z","caller":"traceutil/trace.go:171","msg":"trace[389513238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24745; }","duration":"121.850006ms","start":"2026-05-10T01:41:20.633966Z","end":"2026-05-10T01:41:20.755816Z","steps":["trace[389513238] 'range keys from in-memory index tree' (duration: 121.622751ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:21.317531Z","caller":"traceutil/trace.go:171","msg":"trace[946616764] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"557.770057ms","start":"2026-05-10T01:41:20.759739Z","end":"2026-05-10T01:41:21.317509Z","steps":["trace[946616764] 'process raft request' (duration: 557.632414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:21.317666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:20.759719Z","time spent":"557.877388ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:21.704467Z","caller":"traceutil/trace.go:171","msg":"trace[318886517] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"944.32179ms","start":"2026-05-10T01:41:20.760111Z","end":"2026-05-10T01:41:21.704433Z","steps":["trace[318886517] 'process raft request' (duration: 916.818294ms)","trace[318886517] 'compare' (duration: 26.707232ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:21.704507Z","caller":"traceutil/trace.go:171","msg":"trace[1218374499] linearizableReadLoop","detail":"{readStateIndex:25556; appliedIndex:25553; }","duration":"864.215087ms","start":"2026-05-10T01:41:20.840275Z","end":"2026-05-10T01:41:21.70449Z","steps":["trace[1218374499] 'read index received' (duration: 477.190555ms)","trace[1218374499] 'applied index is now lower than readState.Index' (duration: 387.023532ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:21.70461Z","caller":"traceutil/trace.go:171","msg":"trace[309551795] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"944.502373ms","start":"2026-05-10T01:41:20.760095Z","end":"2026-05-10T01:41:21.704598Z","steps":["trace[309551795] 'process raft request' (duration: 944.196768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:21.704625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:20.760092Z","time spent":"944.450062ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:21.704688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:20.760061Z","time spent":"944.581035ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:21.704807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.542593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:21.704842Z","caller":"traceutil/trace.go:171","msg":"trace[364603010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24751; }","duration":"864.619855ms","start":"2026-05-10T01:41:20.840213Z","end":"2026-05-10T01:41:21.704833Z","steps":["trace[364603010] 'agreement among raft nodes before linearized reading' (duration: 864.563204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:21.70487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:20.840192Z","time spent":"864.672805ms","remote":"127.0.0.1:51370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T01:41:21.705038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.853134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:21.705079Z","caller":"traceutil/trace.go:171","msg":"trace[1442285518] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24751; }","duration":"819.899025ms","start":"2026-05-10T01:41:20.88517Z","end":"2026-05-10T01:41:21.705069Z","steps":["trace[1442285518] 'agreement among raft nodes before linearized reading' (duration: 819.841414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:21.705108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:20.885111Z","time spent":"819.990017ms","remote":"127.0.0.1:39182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-10T01:41:21.705337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.253174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-05-10T01:41:21.705374Z","caller":"traceutil/trace.go:171","msg":"trace[1918542796] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24751; }","duration":"146.319156ms","start":"2026-05-10T01:41:21.559045Z","end":"2026-05-10T01:41:21.705365Z","steps":["trace[1918542796] 'agreement among raft nodes before linearized reading' (duration: 146.248814ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:21.705552Z","caller":"traceutil/trace.go:171","msg":"trace[71015979] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"434.312951ms","start":"2026-05-10T01:41:21.271197Z","end":"2026-05-10T01:41:21.70551Z","steps":["trace[71015979] 'process raft request' (duration: 433.251131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:21.70559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.832113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-10T01:41:21.705635Z","caller":"traceutil/trace.go:171","msg":"trace[2031325018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24751; }","duration":"149.907463ms","start":"2026-05-10T01:41:21.555719Z","end":"2026-05-10T01:41:21.705626Z","steps":["trace[2031325018] 'agreement among raft nodes before linearized reading' (duration: 149.787131ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:21.705649Z","caller":"traceutil/trace.go:171","msg":"trace[1562300960] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"764.380254ms","start":"2026-05-10T01:41:20.941212Z","end":"2026-05-10T01:41:21.705592Z","steps":["trace[1562300960] 'process raft request' (duration: 763.15075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:21.705715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.294221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:21.705695Z","caller":"traceutil/trace.go:171","msg":"trace[1867585993] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"435.680117ms","start":"2026-05-10T01:41:21.269957Z","end":"2026-05-10T01:41:21.705637Z","steps":["trace[1867585993] 'process raft request' (duration: 434.454403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:21.70574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.271185Z","time spent":"434.449404ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:21.70576Z","caller":"traceutil/trace.go:171","msg":"trace[1529108850] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24751; }","duration":"604.396222ms","start":"2026-05-10T01:41:21.101351Z","end":"2026-05-10T01:41:21.705747Z","steps":["trace[1529108850] 'agreement among raft nodes before linearized reading' (duration: 604.271479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:21.705792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.10133Z","time spent":"604.454603ms","remote":"127.0.0.1:60906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:21.705821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:20.941182Z","time spent":"764.555628ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:21.705867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.269928Z","time spent":"435.846409ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:22.246784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.844742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:22.246997Z","caller":"traceutil/trace.go:171","msg":"trace[200249459] linearizableReadLoop","detail":"{readStateIndex:25560; appliedIndex:25559; }","duration":"535.755873ms","start":"2026-05-10T01:41:21.711222Z","end":"2026-05-10T01:41:22.246978Z","steps":["trace[200249459] 'read index received' (duration: 255.667167ms)","trace[200249459] 'applied index is now lower than readState.Index' (duration: 280.086976ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:22.247118Z","caller":"traceutil/trace.go:171","msg":"trace[779610607] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"537.14062ms","start":"2026-05-10T01:41:21.709932Z","end":"2026-05-10T01:41:22.247073Z","steps":["trace[779610607] 'process raft request' (duration: 256.928781ms)","trace[779610607] 'compare' (duration: 279.309782ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:22.247254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.021079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:22.247374Z","caller":"traceutil/trace.go:171","msg":"trace[1947884346] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24752; }","duration":"536.159571ms","start":"2026-05-10T01:41:21.711195Z","end":"2026-05-10T01:41:22.247354Z","steps":["trace[1947884346] 'agreement among raft nodes before linearized reading' (duration: 535.930257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:22.247488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.711184Z","time spent":"536.290123ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-10T01:41:22.247267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.709914Z","time spent":"537.273252ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:22.28558Z","caller":"traceutil/trace.go:171","msg":"trace[1998626643] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"499.357551ms","start":"2026-05-10T01:41:21.786198Z","end":"2026-05-10T01:41:22.285556Z","steps":["trace[1998626643] 'process raft request' (duration: 499.238269ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:22.285592Z","caller":"traceutil/trace.go:171","msg":"trace[1121880640] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"522.718219ms","start":"2026-05-10T01:41:21.762834Z","end":"2026-05-10T01:41:22.285553Z","steps":["trace[1121880640] 'process raft request' (duration: 522.566786ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:22.285615Z","caller":"traceutil/trace.go:171","msg":"trace[1633021366] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"571.868733ms","start":"2026-05-10T01:41:21.71371Z","end":"2026-05-10T01:41:22.285578Z","steps":["trace[1633021366] 'process raft request' (duration: 571.596357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:22.285768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.713702Z","time spent":"571.983995ms","remote":"127.0.0.1:51650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:22.285781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.762807Z","time spent":"522.859392ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:22.28583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.036385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-10T01:41:22.285909Z","caller":"traceutil/trace.go:171","msg":"trace[646195318] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24758; }","duration":"280.131396ms","start":"2026-05-10T01:41:22.00576Z","end":"2026-05-10T01:41:22.285892Z","steps":["trace[646195318] 'agreement among raft nodes before linearized reading' (duration: 279.829171ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:22.28597Z","caller":"traceutil/trace.go:171","msg":"trace[643633306] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"573.063544ms","start":"2026-05-10T01:41:21.71286Z","end":"2026-05-10T01:41:22.285924Z","steps":["trace[643633306] 'process raft request' (duration: 572.306559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:22.286096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.712844Z","time spent":"573.199647ms","remote":"127.0.0.1:51650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:22.286099Z","caller":"traceutil/trace.go:171","msg":"trace[1700194338] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"499.83595ms","start":"2026-05-10T01:41:21.786246Z","end":"2026-05-10T01:41:22.286081Z","steps":["trace[1700194338] 'process raft request' (duration: 499.226419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:22.286206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.786223Z","time spent":"499.918632ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:22.286256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.746388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-10T01:41:22.286384Z","caller":"traceutil/trace.go:171","msg":"trace[613328733] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:24758; }","duration":"223.926202ms","start":"2026-05-10T01:41:22.062435Z","end":"2026-05-10T01:41:22.286361Z","steps":["trace[613328733] 'agreement among raft nodes before linearized reading' (duration: 223.628206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:22.286397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.308117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:22.286435Z","caller":"traceutil/trace.go:171","msg":"trace[1175354346] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:24758; }","duration":"550.414389ms","start":"2026-05-10T01:41:21.736009Z","end":"2026-05-10T01:41:22.286423Z","steps":["trace[1175354346] 'agreement among raft nodes before linearized reading' (duration: 550.318427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:22.286494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.735988Z","time spent":"550.49727ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:22.286748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.786182Z","time spent":"499.459964ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:22.286506Z","caller":"traceutil/trace.go:171","msg":"trace[1565211762] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"571.324061ms","start":"2026-05-10T01:41:21.715168Z","end":"2026-05-10T01:41:22.286492Z","steps":["trace[1565211762] 'process raft request' (duration: 570.185629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:22.287186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:21.715162Z","time spent":"571.984923ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:27.569172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.17574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9fc41811-8c9e-4680-8ff3-f5cb4639a54e\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-10T01:41:27.569277Z","caller":"traceutil/trace.go:171","msg":"trace[1506528586] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9fc41811-8c9e-4680-8ff3-f5cb4639a54e; range_end:; response_count:1; response_revision:24850; }","duration":"181.328393ms","start":"2026-05-10T01:41:27.38792Z","end":"2026-05-10T01:41:27.569248Z","steps":["trace[1506528586] 'range keys from in-memory index tree' (duration: 180.965606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:27.56941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.852649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T01:41:27.569465Z","caller":"traceutil/trace.go:171","msg":"trace[1929767328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24850; }","duration":"225.947111ms","start":"2026-05-10T01:41:27.343504Z","end":"2026-05-10T01:41:27.569451Z","steps":["trace[1929767328] 'range keys from in-memory index tree' (duration: 225.620875ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:27.69714Z","caller":"traceutil/trace.go:171","msg":"trace[674084710] linearizableReadLoop","detail":"{readStateIndex:25661; appliedIndex:25660; }","duration":"222.008076ms","start":"2026-05-10T01:41:27.475103Z","end":"2026-05-10T01:41:27.697111Z","steps":["trace[674084710] 'read index received' (duration: 147.662071ms)","trace[674084710] 'applied index is now lower than readState.Index' (duration: 74.344455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:27.697335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.194709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:27.697403Z","caller":"traceutil/trace.go:171","msg":"trace[1743380415] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:24850; }","duration":"222.336982ms","start":"2026-05-10T01:41:27.47505Z","end":"2026-05-10T01:41:27.697387Z","steps":["trace[1743380415] 'agreement among raft nodes before linearized reading' (duration: 222.183629ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:27.733283Z","caller":"traceutil/trace.go:171","msg":"trace[1158962577] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"158.371772ms","start":"2026-05-10T01:41:27.574894Z","end":"2026-05-10T01:41:27.733266Z","steps":["trace[1158962577] 'process raft request' (duration: 158.220239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:27.733385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.843294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9fc41811-8c9e-4680-8ff3-f5cb4639a54e\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-05-10T01:41:27.733374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.913743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:27.733433Z","caller":"traceutil/trace.go:171","msg":"trace[454586101] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9fc41811-8c9e-4680-8ff3-f5cb4639a54e; range_end:; response_count:1; response_revision:24851; }","duration":"107.929614ms","start":"2026-05-10T01:41:27.625488Z","end":"2026-05-10T01:41:27.733418Z","steps":["trace[454586101] 'agreement among raft nodes before linearized reading' (duration: 107.781872ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:27.733442Z","caller":"traceutil/trace.go:171","msg":"trace[1452482299] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24851; }","duration":"199.032295ms","start":"2026-05-10T01:41:27.534394Z","end":"2026-05-10T01:41:27.733427Z","steps":["trace[1452482299] 'agreement among raft nodes before linearized reading' (duration: 198.897272ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:27.969172Z","caller":"traceutil/trace.go:171","msg":"trace[1479890799] linearizableReadLoop","detail":"{readStateIndex:25666; appliedIndex:25665; }","duration":"206.764569ms","start":"2026-05-10T01:41:27.762388Z","end":"2026-05-10T01:41:27.969153Z","steps":["trace[1479890799] 'read index received' (duration: 148.7121ms)","trace[1479890799] 'applied index is now lower than readState.Index' (duration: 58.051759ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:27.969313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.237914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:41:27.969341Z","caller":"traceutil/trace.go:171","msg":"trace[1372199845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24855; }","duration":"235.302914ms","start":"2026-05-10T01:41:27.734028Z","end":"2026-05-10T01:41:27.969331Z","steps":["trace[1372199845] 'agreement among raft nodes before linearized reading' (duration: 235.195252ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:27.969521Z","caller":"traceutil/trace.go:171","msg":"trace[776754308] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"228.709892ms","start":"2026-05-10T01:41:27.740804Z","end":"2026-05-10T01:41:27.969514Z","steps":["trace[776754308] 'process raft request' (duration: 170.256235ms)","trace[776754308] 'compare' (duration: 57.807984ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:28.021041Z","caller":"traceutil/trace.go:171","msg":"trace[1040627450] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"254.19328ms","start":"2026-05-10T01:41:27.766829Z","end":"2026-05-10T01:41:28.021022Z","steps":["trace[1040627450] 'process raft request' (duration: 254.072857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:29.966742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.839442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:29.96784Z","caller":"traceutil/trace.go:171","msg":"trace[1162961271] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"226.422209ms","start":"2026-05-10T01:41:29.741398Z","end":"2026-05-10T01:41:29.967821Z","steps":["trace[1162961271] 'process raft request' (duration: 92.422114ms)","trace[1162961271] 'compare' (duration: 132.69185ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:30.02221Z","caller":"traceutil/trace.go:171","msg":"trace[1167729852] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"255.567786ms","start":"2026-05-10T01:41:29.766622Z","end":"2026-05-10T01:41:30.02219Z","steps":["trace[1167729852] 'process raft request' (duration: 255.523895ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.022356Z","caller":"traceutil/trace.go:171","msg":"trace[1217562179] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"267.278636ms","start":"2026-05-10T01:41:29.754954Z","end":"2026-05-10T01:41:30.022233Z","steps":["trace[1217562179] 'process raft request' (duration: 267.080852ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.022546Z","caller":"traceutil/trace.go:171","msg":"trace[991446658] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"254.290071ms","start":"2026-05-10T01:41:29.76823Z","end":"2026-05-10T01:41:30.02252Z","steps":["trace[991446658] 'process raft request' (duration: 254.162699ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:30.022564Z","caller":"traceutil/trace.go:171","msg":"trace[1880456820] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"254.433704ms","start":"2026-05-10T01:41:29.768089Z","end":"2026-05-10T01:41:30.022523Z","steps":["trace[1880456820] 'process raft request' (duration: 254.078307ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:35.126718Z","caller":"traceutil/trace.go:171","msg":"trace[1917705972] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"100.025537ms","start":"2026-05-10T01:41:35.026643Z","end":"2026-05-10T01:41:35.126669Z","steps":["trace[1917705972] 'process raft request' (duration: 99.602279ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:35.268608Z","caller":"traceutil/trace.go:171","msg":"trace[1190788990] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"137.363748ms","start":"2026-05-10T01:41:35.131217Z","end":"2026-05-10T01:41:35.268581Z","steps":["trace[1190788990] 'process raft request' (duration: 107.10935ms)","trace[1190788990] 'compare' (duration: 29.935732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:46.766692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.8052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:46.766781Z","caller":"traceutil/trace.go:171","msg":"trace[415112466] linearizableReadLoop","detail":"{readStateIndex:25840; appliedIndex:25839; }","duration":"341.535129ms","start":"2026-05-10T01:41:46.425231Z","end":"2026-05-10T01:41:46.766767Z","steps":["trace[415112466] 'read index received' (duration: 36.579956ms)","trace[415112466] 'applied index is now lower than readState.Index' (duration: 304.953703ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:46.766831Z","caller":"traceutil/trace.go:171","msg":"trace[1143500383] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"344.607997ms","start":"2026-05-10T01:41:46.422212Z","end":"2026-05-10T01:41:46.76682Z","steps":["trace[1143500383] 'process raft request' (duration: 39.611593ms)","trace[1143500383] 'compare' (duration: 304.608036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:46.766867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:46.4222Z","time spent":"344.646677ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:46.767005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.749353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-10T01:41:46.767102Z","caller":"traceutil/trace.go:171","msg":"trace[857875732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25025; }","duration":"341.868986ms","start":"2026-05-10T01:41:46.425214Z","end":"2026-05-10T01:41:46.767083Z","steps":["trace[857875732] 'agreement among raft nodes before linearized reading' (duration: 341.618851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:46.767157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:46.425205Z","time spent":"341.942297ms","remote":"127.0.0.1:51628","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-05-10T01:41:47.058061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.711507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:47.058153Z","caller":"traceutil/trace.go:171","msg":"trace[1906521946] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25840; }","duration":"291.340258ms","start":"2026-05-10T01:41:46.766798Z","end":"2026-05-10T01:41:47.058139Z","steps":["trace[1906521946] 'read index received' (duration: 179.490539ms)","trace[1906521946] 'applied index is now lower than readState.Index' (duration: 111.848809ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:47.05825Z","caller":"traceutil/trace.go:171","msg":"trace[662767350] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"451.953262ms","start":"2026-05-10T01:41:46.606247Z","end":"2026-05-10T01:41:47.058201Z","steps":["trace[662767350] 'process raft request' (duration: 340.027032ms)","trace[662767350] 'compare' (duration: 111.417959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:47.058388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:46.606228Z","time spent":"452.042213ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:47.058556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.65326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-10T01:41:47.05863Z","caller":"traceutil/trace.go:171","msg":"trace[343754111] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25026; }","duration":"512.781762ms","start":"2026-05-10T01:41:46.545829Z","end":"2026-05-10T01:41:47.058611Z","steps":["trace[343754111] 'agreement among raft nodes before linearized reading' (duration: 512.523518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:47.058668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:46.545814Z","time spent":"512.842884ms","remote":"127.0.0.1:51488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-05-10T01:41:47.102704Z","caller":"traceutil/trace.go:171","msg":"trace[168678653] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"332.187815ms","start":"2026-05-10T01:41:46.770487Z","end":"2026-05-10T01:41:47.102675Z","steps":["trace[168678653] 'process raft request' (duration: 332.01019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:47.102869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:46.770467Z","time spent":"332.314717ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:47.526573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.158844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:47.5269Z","caller":"traceutil/trace.go:171","msg":"trace[1150776841] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"482.559185ms","start":"2026-05-10T01:41:47.04431Z","end":"2026-05-10T01:41:47.526869Z","steps":["trace[1150776841] 'process raft request' (duration: 305.023684ms)","trace[1150776841] 'compare' (duration: 177.056423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:47.527036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:47.044285Z","time spent":"482.677777ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:47.526932Z","caller":"traceutil/trace.go:171","msg":"trace[249792379] linearizableReadLoop","detail":"{readStateIndex:25843; appliedIndex:25841; }","duration":"468.739186ms","start":"2026-05-10T01:41:47.058174Z","end":"2026-05-10T01:41:47.526913Z","steps":["trace[249792379] 'read index received' (duration: 44.373012ms)","trace[249792379] 'applied index is now lower than readState.Index' (duration: 424.363634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:47.675456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.774851ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-10T01:41:47.675656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.896621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:41:47.675749Z","caller":"traceutil/trace.go:171","msg":"trace[1419138437] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25028; }","duration":"628.990523ms","start":"2026-05-10T01:41:47.046728Z","end":"2026-05-10T01:41:47.675719Z","steps":["trace[1419138437] 'agreement among raft nodes before linearized reading' (duration: 480.449705ms)","trace[1419138437] 'count revisions from in-memory index tree' (duration: 148.416536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:47.67582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:47.04672Z","time spent":"629.078494ms","remote":"127.0.0.1:51714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":98,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-10T01:41:48.238031Z","caller":"traceutil/trace.go:171","msg":"trace[1022238956] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25845; }","duration":"148.331533ms","start":"2026-05-10T01:41:48.089659Z","end":"2026-05-10T01:41:48.237991Z","steps":["trace[1022238956] 'read index received' (duration: 121.759034ms)","trace[1022238956] 'applied index is now lower than readState.Index' (duration: 26.570469ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:48.238044Z","caller":"traceutil/trace.go:171","msg":"trace[402816549] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"150.488065ms","start":"2026-05-10T01:41:48.087525Z","end":"2026-05-10T01:41:48.238013Z","steps":["trace[402816549] 'process raft request' (duration: 123.822384ms)","trace[402816549] 'compare' (duration: 26.389506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:48.238205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.542398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-05-10T01:41:48.238256Z","caller":"traceutil/trace.go:171","msg":"trace[1633886107] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25030; }","duration":"148.632309ms","start":"2026-05-10T01:41:48.08961Z","end":"2026-05-10T01:41:48.238242Z","steps":["trace[1633886107] 'agreement among raft nodes before linearized reading' (duration: 148.487006ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:48.5288Z","caller":"traceutil/trace.go:171","msg":"trace[1762506837] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"434.242049ms","start":"2026-05-10T01:41:48.09443Z","end":"2026-05-10T01:41:48.528672Z","steps":["trace[1762506837] 'process raft request' (duration: 369.80847ms)","trace[1762506837] 'compare' (duration: 64.079102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:48.528968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:48.094409Z","time spent":"434.473093ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:48.529246Z","caller":"traceutil/trace.go:171","msg":"trace[1391714034] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"111.90621ms","start":"2026-05-10T01:41:48.417319Z","end":"2026-05-10T01:41:48.529225Z","steps":["trace[1391714034] 'process raft request' (duration: 111.31747ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:48.52932Z","caller":"traceutil/trace.go:171","msg":"trace[1474833261] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"109.673698ms","start":"2026-05-10T01:41:48.419628Z","end":"2026-05-10T01:41:48.529302Z","steps":["trace[1474833261] 'process raft request' (duration: 109.106217ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:48.529261Z","caller":"traceutil/trace.go:171","msg":"trace[2033001165] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"109.738549ms","start":"2026-05-10T01:41:48.41951Z","end":"2026-05-10T01:41:48.529248Z","steps":["trace[2033001165] 'process raft request' (duration: 109.174638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:48.529394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.013364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-10T01:41:48.529376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.249098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:48.529422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.389957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-10T01:41:48.529255Z","caller":"traceutil/trace.go:171","msg":"trace[323756904] linearizableReadLoop","detail":"{readStateIndex:25848; appliedIndex:25846; }","duration":"291.124413ms","start":"2026-05-10T01:41:48.238114Z","end":"2026-05-10T01:41:48.529239Z","steps":["trace[323756904] 'read index received' (duration: 226.262416ms)","trace[323756904] 'applied index is now lower than readState.Index' (duration: 64.860077ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:48.529431Z","caller":"traceutil/trace.go:171","msg":"trace[730854847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25036; }","duration":"426.081806ms","start":"2026-05-10T01:41:48.103338Z","end":"2026-05-10T01:41:48.52942Z","steps":["trace[730854847] 'agreement among raft nodes before linearized reading' (duration: 425.954034ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:48.529451Z","caller":"traceutil/trace.go:171","msg":"trace[581118985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25036; }","duration":"422.452697ms","start":"2026-05-10T01:41:48.106988Z","end":"2026-05-10T01:41:48.529441Z","steps":["trace[581118985] 'agreement among raft nodes before linearized reading' (duration: 422.319135ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:48.529455Z","caller":"traceutil/trace.go:171","msg":"trace[140337647] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25036; }","duration":"305.397401ms","start":"2026-05-10T01:41:48.224035Z","end":"2026-05-10T01:41:48.529432Z","steps":["trace[140337647] 'agreement among raft nodes before linearized reading' (duration: 305.222418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:48.529479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:48.106979Z","time spent":"422.495868ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T01:41:48.529507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:48.224011Z","time spent":"305.484622ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:48.529464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:48.103325Z","time spent":"426.132677ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T01:41:48.529262Z","caller":"traceutil/trace.go:171","msg":"trace[1841135983] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"432.65582ms","start":"2026-05-10T01:41:48.096584Z","end":"2026-05-10T01:41:48.52924Z","steps":["trace[1841135983] 'process raft request' (duration: 431.975987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:48.529607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:48.096559Z","time spent":"433.014086ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:48.528858Z","caller":"traceutil/trace.go:171","msg":"trace[1947624194] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"108.291512ms","start":"2026-05-10T01:41:48.420548Z","end":"2026-05-10T01:41:48.528839Z","steps":["trace[1947624194] 'process raft request' (duration: 108.241961ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:53.618134Z","caller":"traceutil/trace.go:171","msg":"trace[118189315] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"137.677033ms","start":"2026-05-10T01:41:53.480428Z","end":"2026-05-10T01:41:53.618105Z","steps":["trace[118189315] 'process raft request' (duration: 137.533351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:54.117992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.884344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:54.118057Z","caller":"traceutil/trace.go:171","msg":"trace[266223102] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25083; }","duration":"206.001506ms","start":"2026-05-10T01:41:53.912039Z","end":"2026-05-10T01:41:54.118041Z","steps":["trace[266223102] 'count revisions from in-memory index tree' (duration: 205.777633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:54.118247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.733288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:41:54.118331Z","caller":"traceutil/trace.go:171","msg":"trace[121549509] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25083; }","duration":"184.001563ms","start":"2026-05-10T01:41:53.93431Z","end":"2026-05-10T01:41:54.118312Z","steps":["trace[121549509] 'count revisions from in-memory index tree' (duration: 183.576835ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:54.516296Z","caller":"traceutil/trace.go:171","msg":"trace[177308182] linearizableReadLoop","detail":"{readStateIndex:25901; appliedIndex:25900; }","duration":"428.897458ms","start":"2026-05-10T01:41:54.087378Z","end":"2026-05-10T01:41:54.516275Z","steps":["trace[177308182] 'read index received' (duration: 428.793017ms)","trace[177308182] 'applied index is now lower than readState.Index' (duration: 103.141µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:54.516375Z","caller":"traceutil/trace.go:171","msg":"trace[474887088] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"494.195104ms","start":"2026-05-10T01:41:54.022143Z","end":"2026-05-10T01:41:54.516338Z","steps":["trace[474887088] 'process raft request' (duration: 493.952069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:54.51653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.022121Z","time spent":"494.321696ms","remote":"127.0.0.1:51808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9529,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:54.516556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.164003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-10T01:41:54.516582Z","caller":"traceutil/trace.go:171","msg":"trace[104272695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25084; }","duration":"429.245865ms","start":"2026-05-10T01:41:54.087328Z","end":"2026-05-10T01:41:54.516574Z","steps":["trace[104272695] 'agreement among raft nodes before linearized reading' (duration: 429.115273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:54.516613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.087307Z","time spent":"429.299816ms","remote":"127.0.0.1:51628","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-05-10T01:41:54.928447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.139468ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:54.928669Z","caller":"traceutil/trace.go:171","msg":"trace[127114197] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25084; }","duration":"134.370512ms","start":"2026-05-10T01:41:54.794279Z","end":"2026-05-10T01:41:54.928649Z","steps":["trace[127114197] 'range keys from in-memory index tree' (duration: 134.123937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:54.928693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.422373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:54.929159Z","caller":"traceutil/trace.go:171","msg":"trace[266243254] linearizableReadLoop","detail":"{readStateIndex:25902; appliedIndex:25901; }","duration":"412.711435ms","start":"2026-05-10T01:41:54.516419Z","end":"2026-05-10T01:41:54.929131Z","steps":["trace[266243254] 'read index received' (duration: 67.738511ms)","trace[266243254] 'applied index is now lower than readState.Index' (duration: 344.971064ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:54.929237Z","caller":"traceutil/trace.go:171","msg":"trace[514024289] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"672.981809ms","start":"2026-05-10T01:41:54.256198Z","end":"2026-05-10T01:41:54.92918Z","steps":["trace[514024289] 'process raft request' (duration: 327.960554ms)","trace[514024289] 'compare' (duration: 343.71221ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:54.929357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.259671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:54.929442Z","caller":"traceutil/trace.go:171","msg":"trace[261199172] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:25085; }","duration":"700.392804ms","start":"2026-05-10T01:41:54.229028Z","end":"2026-05-10T01:41:54.929421Z","steps":["trace[261199172] 'agreement among raft nodes before linearized reading' (duration: 700.243901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:54.929455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.256172Z","time spent":"673.165042ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:54.929495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.229008Z","time spent":"700.473415ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-10T01:41:55.316835Z","caller":"traceutil/trace.go:171","msg":"trace[1764761073] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"793.602843ms","start":"2026-05-10T01:41:54.523195Z","end":"2026-05-10T01:41:55.316798Z","steps":["trace[1764761073] 'process raft request' (duration: 748.440115ms)","trace[1764761073] 'compare' (duration: 44.656739ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:55.316956Z","caller":"traceutil/trace.go:171","msg":"trace[877524871] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"753.760275ms","start":"2026-05-10T01:41:54.563184Z","end":"2026-05-10T01:41:55.316944Z","steps":["trace[877524871] 'process raft request' (duration: 753.584021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.316991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.523173Z","time spent":"793.731876ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:55.31707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.563156Z","time spent":"753.837857ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:55.316833Z","caller":"traceutil/trace.go:171","msg":"trace[1117084257] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"790.554885ms","start":"2026-05-10T01:41:54.526255Z","end":"2026-05-10T01:41:55.31681Z","steps":["trace[1117084257] 'process raft request' (duration: 790.453303ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:55.317283Z","caller":"traceutil/trace.go:171","msg":"trace[1995647977] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"742.800699ms","start":"2026-05-10T01:41:54.574474Z","end":"2026-05-10T01:41:55.317275Z","steps":["trace[1995647977] 'process raft request' (duration: 742.433652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.317256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.526235Z","time spent":"790.933783ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3290,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:55.317343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.574449Z","time spent":"742.86401ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:55.317662Z","caller":"traceutil/trace.go:171","msg":"trace[1582608117] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"742.33946ms","start":"2026-05-10T01:41:54.575299Z","end":"2026-05-10T01:41:55.317639Z","steps":["trace[1582608117] 'process raft request' (duration: 741.935093ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:55.317714Z","caller":"traceutil/trace.go:171","msg":"trace[977273588] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"740.141989ms","start":"2026-05-10T01:41:54.577558Z","end":"2026-05-10T01:41:55.3177Z","steps":["trace[977273588] 'process raft request' (duration: 739.965986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.317735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.575289Z","time spent":"742.411371ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:55.317745Z","caller":"traceutil/trace.go:171","msg":"trace[1089634517] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"740.092738ms","start":"2026-05-10T01:41:54.577625Z","end":"2026-05-10T01:41:55.317718Z","steps":["trace[1089634517] 'process raft request' (duration: 740.012276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.317771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.577524Z","time spent":"740.21641ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:55.317889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.577613Z","time spent":"740.19603ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:55.3179Z","caller":"traceutil/trace.go:171","msg":"trace[429949897] linearizableReadLoop","detail":"{readStateIndex:25910; appliedIndex:25902; }","duration":"388.661414ms","start":"2026-05-10T01:41:54.929212Z","end":"2026-05-10T01:41:55.317873Z","steps":["trace[429949897] 'read index received' (duration: 342.424416ms)","trace[429949897] 'applied index is now lower than readState.Index' (duration: 46.235758ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:55.318023Z","caller":"traceutil/trace.go:171","msg":"trace[1660307612] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"739.622749ms","start":"2026-05-10T01:41:54.578388Z","end":"2026-05-10T01:41:55.318011Z","steps":["trace[1660307612] 'process raft request' (duration: 739.290583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.318049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.869857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-10T01:41:55.318069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.578297Z","time spent":"739.747542ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:55.318092Z","caller":"traceutil/trace.go:171","msg":"trace[561520112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25093; }","duration":"518.941758ms","start":"2026-05-10T01:41:54.79914Z","end":"2026-05-10T01:41:55.318081Z","steps":["trace[561520112] 'agreement among raft nodes before linearized reading' (duration: 518.794446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.318087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.797219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:55.318123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.799121Z","time spent":"518.995859ms","remote":"127.0.0.1:51628","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":"info","ts":"2026-05-10T01:41:55.318129Z","caller":"traceutil/trace.go:171","msg":"trace[84935603] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25093; }","duration":"637.898291ms","start":"2026-05-10T01:41:54.680219Z","end":"2026-05-10T01:41:55.318117Z","steps":["trace[84935603] 'agreement among raft nodes before linearized reading' (duration: 637.783359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.318167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.680197Z","time spent":"637.958862ms","remote":"127.0.0.1:38990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:55.318213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.244304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:55.318275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.6198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:41:55.318278Z","caller":"traceutil/trace.go:171","msg":"trace[1467892027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25093; }","duration":"479.343535ms","start":"2026-05-10T01:41:54.83892Z","end":"2026-05-10T01:41:55.318263Z","steps":["trace[1467892027] 'agreement among raft nodes before linearized reading' (duration: 479.232803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.318286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.041229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-10T01:41:55.318306Z","caller":"traceutil/trace.go:171","msg":"trace[1642830071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25093; }","duration":"739.66927ms","start":"2026-05-10T01:41:54.578625Z","end":"2026-05-10T01:41:55.318295Z","steps":["trace[1642830071] 'agreement among raft nodes before linearized reading' (duration: 739.590789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.318307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.838903Z","time spent":"479.398437ms","remote":"127.0.0.1:51370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T01:41:55.318334Z","caller":"traceutil/trace.go:171","msg":"trace[2113021691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25093; }","duration":"739.10021ms","start":"2026-05-10T01:41:54.579222Z","end":"2026-05-10T01:41:55.318322Z","steps":["trace[2113021691] 'agreement among raft nodes before linearized reading' (duration: 738.960657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.318334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.578616Z","time spent":"739.712541ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T01:41:55.318366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.579216Z","time spent":"739.142131ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-10T01:41:55.318466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.284598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-05-10T01:41:55.318514Z","caller":"traceutil/trace.go:171","msg":"trace[415968538] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:25093; }","duration":"686.37946ms","start":"2026-05-10T01:41:54.632122Z","end":"2026-05-10T01:41:55.318501Z","steps":["trace[415968538] 'agreement among raft nodes before linearized reading' (duration: 686.282638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.318542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:54.632105Z","time spent":"686.429231ms","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-10T01:41:55.986552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.097327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:55.98673Z","caller":"traceutil/trace.go:171","msg":"trace[1181748916] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"954.324269ms","start":"2026-05-10T01:41:55.032387Z","end":"2026-05-10T01:41:55.986711Z","steps":["trace[1181748916] 'process raft request' (duration: 706.983678ms)","trace[1181748916] 'compare' (duration: 246.648298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:55.9868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.032368Z","time spent":"954.39913ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:55.98717Z","caller":"traceutil/trace.go:171","msg":"trace[1338657458] linearizableReadLoop","detail":"{readStateIndex:25913; appliedIndex:25910; }","duration":"669.184777ms","start":"2026-05-10T01:41:55.317973Z","end":"2026-05-10T01:41:55.987158Z","steps":["trace[1338657458] 'read index received' (duration: 421.366317ms)","trace[1338657458] 'applied index is now lower than readState.Index' (duration: 247.81696ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:55.987235Z","caller":"traceutil/trace.go:171","msg":"trace[1407216779] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"839.291541ms","start":"2026-05-10T01:41:55.147932Z","end":"2026-05-10T01:41:55.987223Z","steps":["trace[1407216779] 'process raft request' (duration: 839.168158ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:55.987236Z","caller":"traceutil/trace.go:171","msg":"trace[941027432] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"904.721328ms","start":"2026-05-10T01:41:55.0825Z","end":"2026-05-10T01:41:55.987221Z","steps":["trace[941027432] 'process raft request' (duration: 904.135207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.987283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.147915Z","time spent":"839.337221ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:55.987293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.082475Z","time spent":"904.787539ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:55.987456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.38ms","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-05-10T01:41:55.987515Z","caller":"traceutil/trace.go:171","msg":"trace[454668316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25096; }","duration":"849.471701ms","start":"2026-05-10T01:41:55.138029Z","end":"2026-05-10T01:41:55.9875Z","steps":["trace[454668316] 'agreement among raft nodes before linearized reading' (duration: 849.281607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.987548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.138016Z","time spent":"849.524061ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T01:41:55.987575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.345038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-10T01:41:55.987609Z","caller":"traceutil/trace.go:171","msg":"trace[1302694014] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25096; }","duration":"906.414619ms","start":"2026-05-10T01:41:55.081184Z","end":"2026-05-10T01:41:55.987599Z","steps":["trace[1302694014] 'agreement among raft nodes before linearized reading' (duration: 906.294816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:55.987636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.081165Z","time spent":"906.46342ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-10T01:41:56.300253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.678459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:56.300637Z","caller":"traceutil/trace.go:171","msg":"trace[2074183039] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"978.665535ms","start":"2026-05-10T01:41:55.321947Z","end":"2026-05-10T01:41:56.300612Z","steps":["trace[2074183039] 'process raft request' (duration: 787.565549ms)","trace[2074183039] 'compare' (duration: 190.446154ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:56.300675Z","caller":"traceutil/trace.go:171","msg":"trace[1895467584] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"309.06933ms","start":"2026-05-10T01:41:55.991591Z","end":"2026-05-10T01:41:56.30066Z","steps":["trace[1895467584] 'process raft request' (duration: 309.040079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.300728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.991582Z","time spent":"309.12174ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:56.300754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.321934Z","time spent":"978.758337ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:56.300851Z","caller":"traceutil/trace.go:171","msg":"trace[347962682] linearizableReadLoop","detail":"{readStateIndex:25916; appliedIndex:25913; }","duration":"313.647616ms","start":"2026-05-10T01:41:55.987196Z","end":"2026-05-10T01:41:56.300843Z","steps":["trace[347962682] 'read index received' (duration: 122.306285ms)","trace[347962682] 'applied index is now lower than readState.Index' (duration: 191.339891ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:56.300916Z","caller":"traceutil/trace.go:171","msg":"trace[1284676597] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"978.646584ms","start":"2026-05-10T01:41:55.322263Z","end":"2026-05-10T01:41:56.30091Z","steps":["trace[1284676597] 'process raft request' (duration: 978.335508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.300952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.322254Z","time spent":"978.676255ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:56.300684Z","caller":"traceutil/trace.go:171","msg":"trace[1856651434] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"978.610624ms","start":"2026-05-10T01:41:55.322041Z","end":"2026-05-10T01:41:56.300651Z","steps":["trace[1856651434] 'process raft request' (duration: 978.514253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.30115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.63412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:56.301166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.042899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:56.301181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.785617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-aa9c7528-c6ab-4bdd-a1b3-14dfe09625df\" ","response":"range_response_count:1 size:1451"} {"level":"warn","ts":"2026-05-10T01:41:56.301156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.306774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"warn","ts":"2026-05-10T01:41:56.301194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.322017Z","time spent":"979.083743ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:56.30124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.12965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:56.301262Z","caller":"traceutil/trace.go:171","msg":"trace[556926757] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25100; }","duration":"950.18108ms","start":"2026-05-10T01:41:55.351074Z","end":"2026-05-10T01:41:56.301255Z","steps":["trace[556926757] 'agreement among raft nodes before linearized reading' (duration: 950.14485ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:56.301253Z","caller":"traceutil/trace.go:171","msg":"trace[517590688] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25100; }","duration":"974.429346ms","start":"2026-05-10T01:41:55.326794Z","end":"2026-05-10T01:41:56.301223Z","steps":["trace[517590688] 'agreement among raft nodes before linearized reading' (duration: 974.191261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.301284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.35106Z","time spent":"950.218511ms","remote":"127.0.0.1:39238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:56.301309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.230209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:41:56.301204Z","caller":"traceutil/trace.go:171","msg":"trace[2134746040] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25100; }","duration":"512.724511ms","start":"2026-05-10T01:41:55.788464Z","end":"2026-05-10T01:41:56.301188Z","steps":["trace[2134746040] 'agreement among raft nodes before linearized reading' (duration: 512.63274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.301316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.326785Z","time spent":"974.516267ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-10T01:41:56.301345Z","caller":"traceutil/trace.go:171","msg":"trace[1775297531] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:25100; }","duration":"450.308001ms","start":"2026-05-10T01:41:55.851028Z","end":"2026-05-10T01:41:56.301337Z","steps":["trace[1775297531] 'agreement among raft nodes before linearized reading' (duration: 450.204479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.301351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.788434Z","time spent":"512.908845ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:41:56.301377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.851007Z","time spent":"450.361552ms","remote":"127.0.0.1:51550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":91,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-10T01:41:56.301198Z","caller":"traceutil/trace.go:171","msg":"trace[1076793806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25100; }","duration":"982.106779ms","start":"2026-05-10T01:41:55.319083Z","end":"2026-05-10T01:41:56.30119Z","steps":["trace[1076793806] 'agreement among raft nodes before linearized reading' (duration: 982.054078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.301305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.941247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-05-10T01:41:56.301464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.319067Z","time spent":"982.390144ms","remote":"127.0.0.1:51374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T01:41:56.301481Z","caller":"traceutil/trace.go:171","msg":"trace[1708477659] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25100; }","duration":"310.14322ms","start":"2026-05-10T01:41:55.991323Z","end":"2026-05-10T01:41:56.301466Z","steps":["trace[1708477659] 'agreement among raft nodes before linearized reading' (duration: 309.848005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.301509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.991311Z","time spent":"310.192981ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-10T01:41:56.301204Z","caller":"traceutil/trace.go:171","msg":"trace[1571584870] range","detail":"{range_begin:/registry/persistentvolumes/pvc-aa9c7528-c6ab-4bdd-a1b3-14dfe09625df; range_end:; response_count:1; response_revision:25100; }","duration":"979.842957ms","start":"2026-05-10T01:41:55.321354Z","end":"2026-05-10T01:41:56.301197Z","steps":["trace[1571584870] 'agreement among raft nodes before linearized reading' (duration: 979.729535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.301556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:55.321337Z","time spent":"980.209165ms","remote":"127.0.0.1:51540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1474,"request content":"key:\"/registry/persistentvolumes/pvc-aa9c7528-c6ab-4bdd-a1b3-14dfe09625df\" "} {"level":"warn","ts":"2026-05-10T01:41:56.569335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.312963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:41:56.569402Z","caller":"traceutil/trace.go:171","msg":"trace[1171135395] linearizableReadLoop","detail":"{readStateIndex:25918; appliedIndex:25917; }","duration":"252.903505ms","start":"2026-05-10T01:41:56.316488Z","end":"2026-05-10T01:41:56.569392Z","steps":["trace[1171135395] 'read index received' (duration: 136.560792ms)","trace[1171135395] 'applied index is now lower than readState.Index' (duration: 116.342063ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:56.569457Z","caller":"traceutil/trace.go:171","msg":"trace[151473357] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"253.231202ms","start":"2026-05-10T01:41:56.316218Z","end":"2026-05-10T01:41:56.56945Z","steps":["trace[151473357] 'process raft request' (duration: 136.680125ms)","trace[151473357] 'compare' (duration: 116.015178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:56.569668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.18961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1444"} {"level":"warn","ts":"2026-05-10T01:41:56.569643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.008977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:56.569692Z","caller":"traceutil/trace.go:171","msg":"trace[1343117058] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25101; }","duration":"253.246212ms","start":"2026-05-10T01:41:56.31644Z","end":"2026-05-10T01:41:56.569686Z","steps":["trace[1343117058] 'agreement among raft nodes before linearized reading' (duration: 253.15801ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:56.569714Z","caller":"traceutil/trace.go:171","msg":"trace[1053940393] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25101; }","duration":"157.114748ms","start":"2026-05-10T01:41:56.412581Z","end":"2026-05-10T01:41:56.569696Z","steps":["trace[1053940393] 'agreement among raft nodes before linearized reading' (duration: 156.992746ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:56.828883Z","caller":"traceutil/trace.go:171","msg":"trace[185025864] linearizableReadLoop","detail":"{readStateIndex:25919; appliedIndex:25918; }","duration":"253.238712ms","start":"2026-05-10T01:41:56.575618Z","end":"2026-05-10T01:41:56.828857Z","steps":["trace[185025864] 'read index received' (duration: 162.187834ms)","trace[185025864] 'applied index is now lower than readState.Index' (duration: 91.049468ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:56.82892Z","caller":"traceutil/trace.go:171","msg":"trace[1670782479] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"254.190659ms","start":"2026-05-10T01:41:56.574683Z","end":"2026-05-10T01:41:56.828874Z","steps":["trace[1670782479] 'process raft request' (duration: 163.08591ms)","trace[1670782479] 'compare' (duration: 90.913326ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:56.829148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.496348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-10T01:41:56.829194Z","caller":"traceutil/trace.go:171","msg":"trace[1752790731] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25102; }","duration":"253.574439ms","start":"2026-05-10T01:41:56.575609Z","end":"2026-05-10T01:41:56.829183Z","steps":["trace[1752790731] 'agreement among raft nodes before linearized reading' (duration: 253.378895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.829278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.04045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:56.829345Z","caller":"traceutil/trace.go:171","msg":"trace[2044489471] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25102; }","duration":"163.145513ms","start":"2026-05-10T01:41:56.666183Z","end":"2026-05-10T01:41:56.829328Z","steps":["trace[2044489471] 'agreement among raft nodes before linearized reading' (duration: 163.036711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:56.829382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.512839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-10T01:41:56.829542Z","caller":"traceutil/trace.go:171","msg":"trace[805697076] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25102; }","duration":"252.699062ms","start":"2026-05-10T01:41:56.576785Z","end":"2026-05-10T01:41:56.829484Z","steps":["trace[805697076] 'agreement among raft nodes before linearized reading' (duration: 252.307695ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:57.189456Z","caller":"traceutil/trace.go:171","msg":"trace[98231942] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25920; }","duration":"228.254083ms","start":"2026-05-10T01:41:56.961182Z","end":"2026-05-10T01:41:57.189437Z","steps":["trace[98231942] 'read index received' (duration: 228.10532ms)","trace[98231942] 'applied index is now lower than readState.Index' (duration: 147.453µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:57.189555Z","caller":"traceutil/trace.go:171","msg":"trace[435514389] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"255.364332ms","start":"2026-05-10T01:41:56.934182Z","end":"2026-05-10T01:41:57.189546Z","steps":["trace[435514389] 'process raft request' (duration: 255.126227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:57.189681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.504138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:57.189704Z","caller":"traceutil/trace.go:171","msg":"trace[1676704613] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25104; }","duration":"228.589969ms","start":"2026-05-10T01:41:56.961106Z","end":"2026-05-10T01:41:57.189696Z","steps":["trace[1676704613] 'agreement among raft nodes before linearized reading' (duration: 228.535008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:57.189861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.014276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:57.189881Z","caller":"traceutil/trace.go:171","msg":"trace[227557072] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25104; }","duration":"189.076668ms","start":"2026-05-10T01:41:57.000798Z","end":"2026-05-10T01:41:57.189874Z","steps":["trace[227557072] 'agreement among raft nodes before linearized reading' (duration: 189.045407ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:57.467919Z","caller":"traceutil/trace.go:171","msg":"trace[1598305252] linearizableReadLoop","detail":"{readStateIndex:25922; appliedIndex:25921; }","duration":"143.981623ms","start":"2026-05-10T01:41:57.323913Z","end":"2026-05-10T01:41:57.467895Z","steps":["trace[1598305252] 'read index received' (duration: 111.957081ms)","trace[1598305252] 'applied index is now lower than readState.Index' (duration: 32.023662ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:57.468228Z","caller":"traceutil/trace.go:171","msg":"trace[1508693498] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"144.41156ms","start":"2026-05-10T01:41:57.323801Z","end":"2026-05-10T01:41:57.468212Z","steps":["trace[1508693498] 'process raft request' (duration: 112.091074ms)","trace[1508693498] 'compare' (duration: 31.901399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:41:57.468487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.561783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-10T01:41:57.46853Z","caller":"traceutil/trace.go:171","msg":"trace[153655368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25105; }","duration":"144.653395ms","start":"2026-05-10T01:41:57.323865Z","end":"2026-05-10T01:41:57.468518Z","steps":["trace[153655368] 'agreement among raft nodes before linearized reading' (duration: 144.508482ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:57.490649Z","caller":"traceutil/trace.go:171","msg":"trace[939622943] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"166.686988ms","start":"2026-05-10T01:41:57.32394Z","end":"2026-05-10T01:41:57.490627Z","steps":["trace[939622943] 'process raft request' (duration: 166.603277ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:57.490685Z","caller":"traceutil/trace.go:171","msg":"trace[1461319338] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"166.74485ms","start":"2026-05-10T01:41:57.323903Z","end":"2026-05-10T01:41:57.490648Z","steps":["trace[1461319338] 'process raft request' (duration: 166.520055ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:57.490869Z","caller":"traceutil/trace.go:171","msg":"trace[1511279620] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"166.897142ms","start":"2026-05-10T01:41:57.323952Z","end":"2026-05-10T01:41:57.490849Z","steps":["trace[1511279620] 'process raft request' (duration: 166.638887ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:57.490988Z","caller":"traceutil/trace.go:171","msg":"trace[1908904174] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"166.866001ms","start":"2026-05-10T01:41:57.324102Z","end":"2026-05-10T01:41:57.490968Z","steps":["trace[1908904174] 'process raft request' (duration: 166.79056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:57.49102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.668947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-10T01:41:57.491052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.291475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-05-10T01:41:57.491072Z","caller":"traceutil/trace.go:171","msg":"trace[737287326] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25110; }","duration":"114.361256ms","start":"2026-05-10T01:41:57.376706Z","end":"2026-05-10T01:41:57.491067Z","steps":["trace[737287326] 'agreement among raft nodes before linearized reading' (duration: 114.265495ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:57.491076Z","caller":"traceutil/trace.go:171","msg":"trace[1413061097] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25110; }","duration":"135.938221ms","start":"2026-05-10T01:41:57.355122Z","end":"2026-05-10T01:41:57.49106Z","steps":["trace[1413061097] 'agreement among raft nodes before linearized reading' (duration: 135.815439ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:57.491232Z","caller":"traceutil/trace.go:171","msg":"trace[75578239] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"167.227688ms","start":"2026-05-10T01:41:57.323999Z","end":"2026-05-10T01:41:57.491227Z","steps":["trace[75578239] 'process raft request' (duration: 166.78365ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:58.162096Z","caller":"traceutil/trace.go:171","msg":"trace[1632285717] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"167.656835ms","start":"2026-05-10T01:41:57.994405Z","end":"2026-05-10T01:41:58.162062Z","steps":["trace[1632285717] 'process raft request' (duration: 167.457432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:58.422537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.094045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-10T01:41:58.422609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.358981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-10T01:41:58.422617Z","caller":"traceutil/trace.go:171","msg":"trace[1964768167] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25112; }","duration":"119.223947ms","start":"2026-05-10T01:41:58.303375Z","end":"2026-05-10T01:41:58.422599Z","steps":["trace[1964768167] 'count revisions from in-memory index tree' (duration: 119.004213ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:58.422638Z","caller":"traceutil/trace.go:171","msg":"trace[14874037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25112; }","duration":"118.387251ms","start":"2026-05-10T01:41:58.304238Z","end":"2026-05-10T01:41:58.422625Z","steps":["trace[14874037] 'range keys from in-memory index tree' (duration: 118.293249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:58.422797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.191448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-10T01:41:58.422825Z","caller":"traceutil/trace.go:171","msg":"trace[1152300440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25112; }","duration":"118.238369ms","start":"2026-05-10T01:41:58.304581Z","end":"2026-05-10T01:41:58.42282Z","steps":["trace[1152300440] 'range keys from in-memory index tree' (duration: 118.117387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:58.422912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.000685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:41:58.422925Z","caller":"traceutil/trace.go:171","msg":"trace[2008549590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25112; }","duration":"117.031615ms","start":"2026-05-10T01:41:58.305889Z","end":"2026-05-10T01:41:58.42292Z","steps":["trace[2008549590] 'range keys from in-memory index tree' (duration: 116.942204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:58.422916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.458438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:58.422952Z","caller":"traceutil/trace.go:171","msg":"trace[1869971262] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25112; }","duration":"227.53006ms","start":"2026-05-10T01:41:58.195411Z","end":"2026-05-10T01:41:58.422941Z","steps":["trace[1869971262] 'count revisions from in-memory index tree' (duration: 227.402587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:58.422555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.408962ms","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-05-10T01:41:58.423387Z","caller":"traceutil/trace.go:171","msg":"trace[1049056906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25112; }","duration":"119.283409ms","start":"2026-05-10T01:41:58.304097Z","end":"2026-05-10T01:41:58.42338Z","steps":["trace[1049056906] 'range keys from in-memory index tree' (duration: 118.273539ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:58.578794Z","caller":"traceutil/trace.go:171","msg":"trace[1881532260] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"152.224246ms","start":"2026-05-10T01:41:58.426544Z","end":"2026-05-10T01:41:58.578768Z","steps":["trace[1881532260] 'process raft request' (duration: 152.084054ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:59.033747Z","caller":"traceutil/trace.go:171","msg":"trace[2134987592] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"605.928072ms","start":"2026-05-10T01:41:58.427794Z","end":"2026-05-10T01:41:59.033722Z","steps":["trace[2134987592] 'process raft request' (duration: 605.728437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:59.033892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:58.427772Z","time spent":"606.047323ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:59.03391Z","caller":"traceutil/trace.go:171","msg":"trace[121506035] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"606.021163ms","start":"2026-05-10T01:41:58.427877Z","end":"2026-05-10T01:41:59.033898Z","steps":["trace[121506035] 'process raft request' (duration: 605.802769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:59.03397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:58.42787Z","time spent":"606.067963ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:41:59.034056Z","caller":"traceutil/trace.go:171","msg":"trace[1320287455] linearizableReadLoop","detail":"{readStateIndex:25934; appliedIndex:25930; }","duration":"596.25066ms","start":"2026-05-10T01:41:58.437798Z","end":"2026-05-10T01:41:59.034049Z","steps":["trace[1320287455] 'read index received' (duration: 140.924755ms)","trace[1320287455] 'applied index is now lower than readState.Index' (duration: 455.325214ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:59.034112Z","caller":"traceutil/trace.go:171","msg":"trace[1365631517] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"606.057084ms","start":"2026-05-10T01:41:58.428048Z","end":"2026-05-10T01:41:59.034105Z","steps":["trace[1365631517] 'process raft request' (duration: 605.802599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:59.034148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:58.428039Z","time spent":"606.089874ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T01:41:59.034222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.817466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:59.034302Z","caller":"traceutil/trace.go:171","msg":"trace[1111284027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25116; }","duration":"194.940559ms","start":"2026-05-10T01:41:58.839342Z","end":"2026-05-10T01:41:59.034283Z","steps":["trace[1111284027] 'agreement among raft nodes before linearized reading' (duration: 194.793466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:59.034681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.876241ms","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:9"} {"level":"info","ts":"2026-05-10T01:41:59.03476Z","caller":"traceutil/trace.go:171","msg":"trace[1328023362] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25116; }","duration":"596.984982ms","start":"2026-05-10T01:41:58.437761Z","end":"2026-05-10T01:41:59.034746Z","steps":["trace[1328023362] 'agreement among raft nodes before linearized reading' (duration: 596.465093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:59.034834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:41:58.437747Z","time spent":"597.075615ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":161,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-10T01:41:59.679967Z","caller":"traceutil/trace.go:171","msg":"trace[560923880] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"184.054923ms","start":"2026-05-10T01:41:59.495871Z","end":"2026-05-10T01:41:59.679926Z","steps":["trace[560923880] 'process raft request' (duration: 172.585518ms)","trace[560923880] 'compare' (duration: 11.068688ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:59.680046Z","caller":"traceutil/trace.go:171","msg":"trace[1567543291] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"183.973912ms","start":"2026-05-10T01:41:59.496032Z","end":"2026-05-10T01:41:59.680006Z","steps":["trace[1567543291] 'process raft request' (duration: 183.774068ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:59.680044Z","caller":"traceutil/trace.go:171","msg":"trace[1804832029] linearizableReadLoop","detail":"{readStateIndex:25941; appliedIndex:25935; }","duration":"169.220415ms","start":"2026-05-10T01:41:59.510803Z","end":"2026-05-10T01:41:59.680024Z","steps":["trace[1804832029] 'read index received' (duration: 16.122893ms)","trace[1804832029] 'applied index is now lower than readState.Index' (duration: 153.096472ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:41:59.680045Z","caller":"traceutil/trace.go:171","msg":"trace[1458145253] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"183.533694ms","start":"2026-05-10T01:41:59.496481Z","end":"2026-05-10T01:41:59.680014Z","steps":["trace[1458145253] 'process raft request' (duration: 183.435882ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:59.680069Z","caller":"traceutil/trace.go:171","msg":"trace[408138921] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"183.463212ms","start":"2026-05-10T01:41:59.496569Z","end":"2026-05-10T01:41:59.680032Z","steps":["trace[408138921] 'process raft request' (duration: 183.386481ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:41:59.680203Z","caller":"traceutil/trace.go:171","msg":"trace[1637419377] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"183.166157ms","start":"2026-05-10T01:41:59.497024Z","end":"2026-05-10T01:41:59.68019Z","steps":["trace[1637419377] 'process raft request' (duration: 182.965674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:59.680269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.647215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-10T01:41:59.680308Z","caller":"traceutil/trace.go:171","msg":"trace[1305298405] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25123; }","duration":"111.741397ms","start":"2026-05-10T01:41:59.568554Z","end":"2026-05-10T01:41:59.680295Z","steps":["trace[1305298405] 'agreement among raft nodes before linearized reading' (duration: 111.651806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:41:59.680425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.605852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T01:41:59.68048Z","caller":"traceutil/trace.go:171","msg":"trace[70474125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25123; }","duration":"169.711734ms","start":"2026-05-10T01:41:59.510754Z","end":"2026-05-10T01:41:59.680466Z","steps":["trace[70474125] 'agreement among raft nodes before linearized reading' (duration: 169.555231ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:01.871752Z","caller":"traceutil/trace.go:171","msg":"trace[440612823] linearizableReadLoop","detail":"{readStateIndex:25956; appliedIndex:25951; }","duration":"181.380074ms","start":"2026-05-10T01:42:01.690353Z","end":"2026-05-10T01:42:01.871733Z","steps":["trace[440612823] 'read index received' (duration: 24.825046ms)","trace[440612823] 'applied index is now lower than readState.Index' (duration: 156.553808ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:01.87189Z","caller":"traceutil/trace.go:171","msg":"trace[1339197438] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"186.020401ms","start":"2026-05-10T01:42:01.685842Z","end":"2026-05-10T01:42:01.871862Z","steps":["trace[1339197438] 'process raft request' (duration: 185.848998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:01.872054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.686989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-10T01:42:01.87208Z","caller":"traceutil/trace.go:171","msg":"trace[696056553] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"186.569471ms","start":"2026-05-10T01:42:01.685503Z","end":"2026-05-10T01:42:01.872073Z","steps":["trace[696056553] 'process raft request' (duration: 98.934246ms)","trace[696056553] 'compare' (duration: 86.972722ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:01.872087Z","caller":"traceutil/trace.go:171","msg":"trace[578722668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25138; }","duration":"181.750661ms","start":"2026-05-10T01:42:01.690328Z","end":"2026-05-10T01:42:01.872078Z","steps":["trace[578722668] 'agreement among raft nodes before linearized reading' (duration: 181.636869ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:01.872171Z","caller":"traceutil/trace.go:171","msg":"trace[717804662] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"186.504681ms","start":"2026-05-10T01:42:01.68566Z","end":"2026-05-10T01:42:01.872165Z","steps":["trace[717804662] 'process raft request' (duration: 185.963351ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:01.872263Z","caller":"traceutil/trace.go:171","msg":"trace[1397602715] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"186.558661ms","start":"2026-05-10T01:42:01.685669Z","end":"2026-05-10T01:42:01.872227Z","steps":["trace[1397602715] 'process raft request' (duration: 185.995961ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:13.548179Z","caller":"traceutil/trace.go:171","msg":"trace[1075276027] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"216.674397ms","start":"2026-05-10T01:42:13.331488Z","end":"2026-05-10T01:42:13.548162Z","steps":["trace[1075276027] 'process raft request' (duration: 216.188848ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:19.467721Z","caller":"traceutil/trace.go:171","msg":"trace[1328335174] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"228.682701ms","start":"2026-05-10T01:42:19.238996Z","end":"2026-05-10T01:42:19.467678Z","steps":["trace[1328335174] 'process raft request' (duration: 202.209514ms)","trace[1328335174] 'compare' (duration: 26.023409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:20.058017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.328872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:42:20.059245Z","caller":"traceutil/trace.go:171","msg":"trace[1465615538] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25277; }","duration":"321.608845ms","start":"2026-05-10T01:42:19.737607Z","end":"2026-05-10T01:42:20.059216Z","steps":["trace[1465615538] 'count revisions from in-memory index tree' (duration: 320.217519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:20.05932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:19.737587Z","time spent":"321.698097ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":75,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-10T01:42:20.059489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.587324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-05-10T01:42:20.059628Z","caller":"traceutil/trace.go:171","msg":"trace[1817476206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25277; }","duration":"273.783878ms","start":"2026-05-10T01:42:19.785819Z","end":"2026-05-10T01:42:20.059602Z","steps":["trace[1817476206] 'range keys from in-memory index tree' (duration: 273.380701ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:20.061129Z","caller":"traceutil/trace.go:171","msg":"trace[794993079] linearizableReadLoop","detail":"{readStateIndex:26100; appliedIndex:26099; }","duration":"133.798681ms","start":"2026-05-10T01:42:19.92726Z","end":"2026-05-10T01:42:20.061058Z","steps":["trace[794993079] 'read index received' (duration: 102.123876ms)","trace[794993079] 'applied index is now lower than readState.Index' (duration: 31.660164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:20.061627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.356392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-10T01:42:20.061647Z","caller":"traceutil/trace.go:171","msg":"trace[1646714084] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"258.96034ms","start":"2026-05-10T01:42:19.802637Z","end":"2026-05-10T01:42:20.061597Z","steps":["trace[1646714084] 'process raft request' (duration: 226.732184ms)","trace[1646714084] 'compare' (duration: 31.37221ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:20.061687Z","caller":"traceutil/trace.go:171","msg":"trace[1408572213] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25278; }","duration":"134.474943ms","start":"2026-05-10T01:42:19.927196Z","end":"2026-05-10T01:42:20.061671Z","steps":["trace[1408572213] 'agreement among raft nodes before linearized reading' (duration: 134.027835ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:23.867882Z","caller":"traceutil/trace.go:171","msg":"trace[1529300146] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"245.712242ms","start":"2026-05-10T01:42:23.622118Z","end":"2026-05-10T01:42:23.86783Z","steps":["trace[1529300146] 'process raft request' (duration: 245.464766ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:23.9075Z","caller":"traceutil/trace.go:171","msg":"trace[169885826] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"267.098503ms","start":"2026-05-10T01:42:23.640378Z","end":"2026-05-10T01:42:23.907476Z","steps":["trace[169885826] 'process raft request' (duration: 266.793437ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:29.503239Z","caller":"traceutil/trace.go:171","msg":"trace[1632948055] linearizableReadLoop","detail":"{readStateIndex:26169; appliedIndex:26168; }","duration":"182.576446ms","start":"2026-05-10T01:42:29.320637Z","end":"2026-05-10T01:42:29.503214Z","steps":["trace[1632948055] 'read index received' (duration: 182.285051ms)","trace[1632948055] 'applied index is now lower than readState.Index' (duration: 289.865µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:29.503318Z","caller":"traceutil/trace.go:171","msg":"trace[1124702971] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"225.803627ms","start":"2026-05-10T01:42:29.277475Z","end":"2026-05-10T01:42:29.503279Z","steps":["trace[1124702971] 'process raft request' (duration: 225.569373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:29.503475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.825861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-10T01:42:29.503558Z","caller":"traceutil/trace.go:171","msg":"trace[946861168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25345; }","duration":"182.952913ms","start":"2026-05-10T01:42:29.320585Z","end":"2026-05-10T01:42:29.503538Z","steps":["trace[946861168] 'agreement among raft nodes before linearized reading' (duration: 182.714348ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:29.77642Z","caller":"traceutil/trace.go:171","msg":"trace[1122029276] linearizableReadLoop","detail":"{readStateIndex:26171; appliedIndex:26170; }","duration":"205.545078ms","start":"2026-05-10T01:42:29.570845Z","end":"2026-05-10T01:42:29.77639Z","steps":["trace[1122029276] 'read index received' (duration: 105.815886ms)","trace[1122029276] 'applied index is now lower than readState.Index' (duration: 99.727742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:29.776597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.710851ms","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:8"} {"level":"info","ts":"2026-05-10T01:42:29.77666Z","caller":"traceutil/trace.go:171","msg":"trace[682661012] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25347; }","duration":"205.841443ms","start":"2026-05-10T01:42:29.570799Z","end":"2026-05-10T01:42:29.77664Z","steps":["trace[682661012] 'agreement among raft nodes before linearized reading' (duration: 205.711301ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:29.776807Z","caller":"traceutil/trace.go:171","msg":"trace[641326414] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"218.561592ms","start":"2026-05-10T01:42:29.558207Z","end":"2026-05-10T01:42:29.776769Z","steps":["trace[641326414] 'process raft request' (duration: 118.456213ms)","trace[641326414] 'compare' (duration: 99.569199ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:30.121835Z","caller":"traceutil/trace.go:171","msg":"trace[1363338592] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"235.945597ms","start":"2026-05-10T01:42:29.885862Z","end":"2026-05-10T01:42:30.121808Z","steps":["trace[1363338592] 'process raft request' (duration: 232.506413ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:30.21919Z","caller":"traceutil/trace.go:171","msg":"trace[1970791315] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"115.877965ms","start":"2026-05-10T01:42:30.103281Z","end":"2026-05-10T01:42:30.219159Z","steps":["trace[1970791315] 'process raft request' (duration: 115.800584ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:30.219539Z","caller":"traceutil/trace.go:171","msg":"trace[420141820] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"112.840148ms","start":"2026-05-10T01:42:30.106657Z","end":"2026-05-10T01:42:30.219497Z","steps":["trace[420141820] 'process raft request' (duration: 112.465701ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:30.219685Z","caller":"traceutil/trace.go:171","msg":"trace[1219664705] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"112.43822ms","start":"2026-05-10T01:42:30.107219Z","end":"2026-05-10T01:42:30.219657Z","steps":["trace[1219664705] 'process raft request' (duration: 112.144534ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:30.219756Z","caller":"traceutil/trace.go:171","msg":"trace[87538095] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"111.623695ms","start":"2026-05-10T01:42:30.108119Z","end":"2026-05-10T01:42:30.219742Z","steps":["trace[87538095] 'process raft request' (duration: 111.381991ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:30.219865Z","caller":"traceutil/trace.go:171","msg":"trace[1035484810] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"103.603474ms","start":"2026-05-10T01:42:30.11625Z","end":"2026-05-10T01:42:30.219854Z","steps":["trace[1035484810] 'process raft request' (duration: 103.305939ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T01:42:30.219923Z","caller":"traceutil/trace.go:171","msg":"trace[1762224851] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"292.980018ms","start":"2026-05-10T01:42:29.926263Z","end":"2026-05-10T01:42:30.219243Z","steps":["trace[1762224851] 'process raft request' (duration: 292.682522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T01:42:30.444118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.333076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-10T01:42:30.444251Z","caller":"traceutil/trace.go:171","msg":"trace[1640224373] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"219.760464ms","start":"2026-05-10T01:42:30.224469Z","end":"2026-05-10T01:42:30.44423Z","steps":["trace[1640224373] 'process raft request' (duration: 65.234124ms)","trace[1640224373] 'compare' (duration: 154.147833ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:42:30.73237Z","caller":"traceutil/trace.go:171","msg":"trace[1166835919] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"411.697986ms","start":"2026-05-10T01:42:30.320612Z","end":"2026-05-10T01:42:30.73231Z","steps":["trace[1166835919] 'process raft request' (duration: 395.048553ms)","trace[1166835919] 'compare' (duration: 16.046682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T01:42:30.732794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T01:42:30.320586Z","time spent":"411.880809ms","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T01:42:45.948131Z","caller":"traceutil/trace.go:171","msg":"trace[1776108242] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"102.148237ms","start":"2026-05-10T01:42:45.845945Z","end":"2026-05-10T01:42:45.948093Z","steps":["trace[1776108242] 'process raft request' (duration: 24.4629ms)","trace[1776108242] 'compare' (duration: 77.450382ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:43:28.98891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23010} {"level":"info","ts":"2026-05-10T01:43:29.11917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23010,"took":"125.588907ms","hash":3821476502,"current-db-size-bytes":58912768,"current-db-size":"59 MB","current-db-size-in-use-bytes":35893248,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-10T01:43:29.119243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3821476502,"revision":23010,"compact-revision":20044} {"level":"info","ts":"2026-05-10T01:43:40.107083Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006924.wal"} {"level":"info","ts":"2026-05-10T01:44:18.87941Z","caller":"traceutil/trace.go:171","msg":"trace[2093476812] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"103.483921ms","start":"2026-05-10T01:44:18.775902Z","end":"2026-05-10T01:44:18.879386Z","steps":["trace[2093476812] 'process raft request' (duration: 57.353436ms)","trace[2093476812] 'compare' (duration: 45.683927ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T01:48:28.996543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25964} {"level":"info","ts":"2026-05-10T01:48:29.136426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25964,"took":"136.624906ms","hash":847614303,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":34344960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-10T01:48:29.136523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":847614303,"revision":25964,"compact-revision":23010} {"level":"info","ts":"2026-05-10T01:49:25.887359Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-10T01:49:25.894452Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-10T01:49:25.894546Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-10T01:53:29.003867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28661} {"level":"info","ts":"2026-05-10T01:53:29.137812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28661,"took":"130.325498ms","hash":3251862169,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":30928896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-10T01:53:29.137877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3251862169,"revision":28661,"compact-revision":25964} {"level":"info","ts":"2026-05-10T01:58:29.009542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30891} {"level":"info","ts":"2026-05-10T01:58:29.134881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30891,"took":"121.200379ms","hash":2044270417,"current-db-size-bytes":59334656,"current-db-size":"59 MB","current-db-size-in-use-bytes":32288768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-10T01:58:29.134936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2044270417,"revision":30891,"compact-revision":28661} {"level":"info","ts":"2026-05-10T02:03:29.015606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33156} {"level":"info","ts":"2026-05-10T02:03:29.187404Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33156,"took":"167.172448ms","hash":2901388604,"current-db-size-bytes":61644800,"current-db-size":"62 MB","current-db-size-in-use-bytes":32763904,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-10T02:03:29.187484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2901388604,"revision":33156,"compact-revision":30891} {"level":"info","ts":"2026-05-10T02:08:29.030285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35522} {"level":"info","ts":"2026-05-10T02:08:29.169572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35522,"took":"135.825352ms","hash":1666805892,"current-db-size-bytes":62066688,"current-db-size":"62 MB","current-db-size-in-use-bytes":32137216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-10T02:08:29.169633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1666805892,"revision":35522,"compact-revision":33156} {"level":"info","ts":"2026-05-10T02:10:35.761049Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-10T02:10:35.769075Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-10T02:10:35.7693Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}