{"level":"warn","ts":"2026-06-08T08:45:23.047754Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T08:45:23.047860Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-06-08T08:45:23.048052Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T08:45:23.048090Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-08T08:45:23.048405Z","caller":"embed/etcd.go:528","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-06-08T08:45:23.050329Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-06-08T08:45:23.050467Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.5.24","git-sha":"e72f3c2","go-version":"go1.24.9","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-06-08T08:45:23.054652Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.906585ms"} {"level":"info","ts":"2026-06-08T08:45:23.059933Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-06-08T08:45:23.059999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-06-08T08:45:23.060040Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-06-08T08:45:23.060062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-08T08:45:23.060081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-06-08T08:45:23.060130Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-06-08T08:45:23.067483Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-08T08:45:23.069530Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-08T08:45:23.069610Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} {"level":"info","ts":"2026-06-08T08:45:23.075772Z","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-06-08T08:45:23.078148Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.24","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-08T08:45:23.078554Z","caller":"etcdserver/server.go:759","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-08T08:45:23.078649Z","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-06-08T08:45:23.079106Z","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-06-08T08:45:23.078637Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-06-08T08:45:23.079204Z","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-06-08T08:45:23.079856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-06-08T08:45:23.080087Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"],"added-peer-is-learner":false} {"level":"info","ts":"2026-06-08T08:45:23.082138Z","caller":"embed/etcd.go:762","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-06-08T08:45:23.082291Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-08T08:45:23.082341Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-08T08:45:23.082713Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-08T08:45:23.082783Z","caller":"embed/etcd.go:908","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-08T08:45:23.561526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-06-08T08:45:23.561582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-08T08:45:23.561650Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-06-08T08:45:23.561664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-06-08T08:45:23.561670Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-08T08:45:23.561679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-06-08T08:45:23.561686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-08T08:45:23.565387Z","caller":"etcdserver/server.go:2158","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-06-08T08:45:23.565425Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T08:45:23.565412Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T08:45:23.565471Z","caller":"etcdserver/server.go:2711","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T08:45:23.566416Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-06-08T08:45:23.566809Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} {"level":"info","ts":"2026-06-08T08:45:23.567069Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T08:45:23.567208Z","caller":"embed/serve.go:275","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-08T08:45:23.567318Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T08:45:23.567390Z","caller":"etcdserver/server.go:2735","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T08:45:23.568039Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-08T08:45:23.568174Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-08T08:45:23.568849Z","caller":"embed/serve.go:275","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"warn","ts":"2026-06-08T08:46:10.097272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.394726ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T08:46:10.097502Z","caller":"traceutil/trace.go:171","msg":"trace[386608575] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:601; }","duration":"172.642422ms","start":"2026-06-08T08:46:09.924825Z","end":"2026-06-08T08:46:10.097468Z","steps":["trace[386608575] 'range keys from in-memory index tree' (duration: 172.366556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:46:10.097725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.711486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T08:46:10.098082Z","caller":"traceutil/trace.go:171","msg":"trace[1502271741] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"963.597353ms","start":"2026-06-08T08:46:09.134441Z","end":"2026-06-08T08:46:10.098038Z","steps":["trace[1502271741] 'process raft request' (duration: 111.095968ms)","trace[1502271741] 'compare' (duration: 851.553142ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T08:46:10.098262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T08:46:09.134419Z","time spent":"963.747137ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T08:46:10.098558Z","caller":"traceutil/trace.go:171","msg":"trace[424244617] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"565.486834ms","start":"2026-06-08T08:46:09.533058Z","end":"2026-06-08T08:46:10.098544Z","steps":["trace[424244617] 'read index received' (duration: 46.772925ms)","trace[424244617] 'applied index is now lower than readState.Index' (duration: 518.713239ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T08:46:10.098667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.615887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h5ddb\" limit:1 ","response":"range_response_count:1 size:18441"} {"level":"info","ts":"2026-06-08T08:46:10.098684Z","caller":"traceutil/trace.go:171","msg":"trace[1570361403] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h5ddb; range_end:; response_count:1; response_revision:603; }","duration":"565.639757ms","start":"2026-06-08T08:46:09.533038Z","end":"2026-06-08T08:46:10.098678Z","steps":["trace[1570361403] 'agreement among raft nodes before linearized reading' (duration: 565.581476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:46:10.098708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T08:46:09.533024Z","time spent":"565.677899ms","remote":"127.0.0.1:45386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":18464,"request content":"key:\"/registry/pods/kube-system/cilium-h5ddb\" limit:1 "} {"level":"info","ts":"2026-06-08T08:46:10.098670Z","caller":"traceutil/trace.go:171","msg":"trace[1392809970] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"572.954476ms","start":"2026-06-08T08:46:09.525663Z","end":"2026-06-08T08:46:10.098617Z","steps":["trace[1392809970] 'process raft request' (duration: 572.425102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:46:10.098987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T08:46:09.525627Z","time spent":"573.12054ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T08:46:10.108470Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.625475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T08:46:10.108533Z","caller":"traceutil/trace.go:171","msg":"trace[508049666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"182.679557ms","start":"2026-06-08T08:46:09.925832Z","end":"2026-06-08T08:46:10.108512Z","steps":["trace[508049666] 'agreement among raft nodes before linearized reading' (duration: 182.590614ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:46:10.108749Z","caller":"traceutil/trace.go:171","msg":"trace[311982060] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"289.647374ms","start":"2026-06-08T08:46:09.819093Z","end":"2026-06-08T08:46:10.108741Z","steps":["trace[311982060] 'process raft request' (duration: 289.240314ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:46:52.394560Z","caller":"traceutil/trace.go:171","msg":"trace[2020561979] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"127.873547ms","start":"2026-06-08T08:46:52.266649Z","end":"2026-06-08T08:46:52.394522Z","steps":["trace[2020561979] 'process raft request' (duration: 127.60929ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:47:37.889069Z","caller":"traceutil/trace.go:171","msg":"trace[557325878] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"113.231543ms","start":"2026-06-08T08:47:37.775804Z","end":"2026-06-08T08:47:37.889035Z","steps":["trace[557325878] 'process raft request' (duration: 113.056328ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:47:38.044431Z","caller":"traceutil/trace.go:171","msg":"trace[890734203] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"147.989696ms","start":"2026-06-08T08:47:37.896417Z","end":"2026-06-08T08:47:38.044407Z","steps":["trace[890734203] 'read index received' (duration: 134.227692ms)","trace[890734203] 'applied index is now lower than readState.Index' (duration: 13.760964ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T08:47:38.044497Z","caller":"traceutil/trace.go:171","msg":"trace[1255127544] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"150.304253ms","start":"2026-06-08T08:47:37.894152Z","end":"2026-06-08T08:47:38.044456Z","steps":["trace[1255127544] 'process raft request' (duration: 136.585799ms)","trace[1255127544] 'compare' (duration: 13.55399ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T08:47:38.044651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.212612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" limit:1 ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-08T08:47:38.044704Z","caller":"traceutil/trace.go:171","msg":"trace[660063622] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1830; }","duration":"148.285163ms","start":"2026-06-08T08:47:37.896410Z","end":"2026-06-08T08:47:38.044695Z","steps":["trace[660063622] 'agreement among raft nodes before linearized reading' (duration: 148.15228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:47:38.044709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.18382ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T08:47:38.044749Z","caller":"traceutil/trace.go:171","msg":"trace[1127375135] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1830; }","duration":"120.237621ms","start":"2026-06-08T08:47:37.924502Z","end":"2026-06-08T08:47:38.044739Z","steps":["trace[1127375135] 'agreement among raft nodes before linearized reading' (duration: 120.16382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:47:38.044814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.90655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T08:47:38.044889Z","caller":"traceutil/trace.go:171","msg":"trace[1290716718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"118.984253ms","start":"2026-06-08T08:47:37.925889Z","end":"2026-06-08T08:47:38.044873Z","steps":["trace[1290716718] 'agreement among raft nodes before linearized reading' (duration: 118.879659ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:47:38.582081Z","caller":"traceutil/trace.go:171","msg":"trace[1406541526] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"135.37612ms","start":"2026-06-08T08:47:38.446677Z","end":"2026-06-08T08:47:38.582053Z","steps":["trace[1406541526] 'process raft request' (duration: 135.146984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:47:49.560666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.11686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T08:47:49.560906Z","caller":"traceutil/trace.go:171","msg":"trace[1325596135] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"228.109103ms","start":"2026-06-08T08:47:49.332771Z","end":"2026-06-08T08:47:49.560880Z","steps":["trace[1325596135] 'process raft request' (duration: 44.689886ms)","trace[1325596135] 'compare' (duration: 182.851474ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T08:47:49.561148Z","caller":"traceutil/trace.go:171","msg":"trace[462220059] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"219.925045ms","start":"2026-06-08T08:47:49.341173Z","end":"2026-06-08T08:47:49.561098Z","steps":["trace[462220059] 'process raft request' (duration: 219.611377ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:48:31.973565Z","caller":"traceutil/trace.go:171","msg":"trace[359507863] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"182.209776ms","start":"2026-06-08T08:48:31.791323Z","end":"2026-06-08T08:48:31.973533Z","steps":["trace[359507863] 'process raft request' (duration: 181.95749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:48:32.227912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.329314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T08:48:32.228012Z","caller":"traceutil/trace.go:171","msg":"trace[1965560458] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2254; }","duration":"304.134816ms","start":"2026-06-08T08:48:31.923862Z","end":"2026-06-08T08:48:32.227997Z","steps":["trace[1965560458] 'read index received' (duration: 49.535373ms)","trace[1965560458] 'applied index is now lower than readState.Index' (duration: 254.598053ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T08:48:32.228064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.213237ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T08:48:32.228087Z","caller":"traceutil/trace.go:171","msg":"trace[1253238341] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2171; }","duration":"304.236158ms","start":"2026-06-08T08:48:31.923843Z","end":"2026-06-08T08:48:32.228079Z","steps":["trace[1253238341] 'agreement among raft nodes before linearized reading' (duration: 304.197567ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:48:32.228199Z","caller":"traceutil/trace.go:171","msg":"trace[1267702170] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"428.708701ms","start":"2026-06-08T08:48:31.799440Z","end":"2026-06-08T08:48:32.228149Z","steps":["trace[1267702170] 'process raft request' (duration: 295.734623ms)","trace[1267702170] 'compare' (duration: 132.18943ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T08:48:32.228436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T08:48:31.799416Z","time spent":"428.901166ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T08:48:32.269067Z","caller":"traceutil/trace.go:171","msg":"trace[1532160407] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"185.366602ms","start":"2026-06-08T08:48:32.083670Z","end":"2026-06-08T08:48:32.269037Z","steps":["trace[1532160407] 'process raft request' (duration: 185.222169ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:49:58.535578Z","caller":"traceutil/trace.go:171","msg":"trace[673380613] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"118.243155ms","start":"2026-06-08T08:49:58.417298Z","end":"2026-06-08T08:49:58.535541Z","steps":["trace[673380613] 'process raft request' (duration: 118.03582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:49:58.839610Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.875129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T08:49:58.840051Z","caller":"traceutil/trace.go:171","msg":"trace[998538693] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"125.693746ms","start":"2026-06-08T08:49:58.714338Z","end":"2026-06-08T08:49:58.840032Z","steps":["trace[998538693] 'process raft request' (duration: 125.481281ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:49:58.840024Z","caller":"traceutil/trace.go:171","msg":"trace[1986908734] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"219.898809ms","start":"2026-06-08T08:49:58.620087Z","end":"2026-06-08T08:49:58.839986Z","steps":["trace[1986908734] 'process raft request' (duration: 13.551359ms)","trace[1986908734] 'compare' (duration: 205.54679ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T08:52:40.359948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.193998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T08:52:40.360090Z","caller":"traceutil/trace.go:171","msg":"trace[623242760] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3904; }","duration":"112.356581ms","start":"2026-06-08T08:52:40.247700Z","end":"2026-06-08T08:52:40.360056Z","steps":["trace[623242760] 'range keys from in-memory index tree' (duration: 112.103475ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:54:09.542485Z","caller":"traceutil/trace.go:171","msg":"trace[1569501607] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"226.637048ms","start":"2026-06-08T08:54:09.315794Z","end":"2026-06-08T08:54:09.542431Z","steps":["trace[1569501607] 'process raft request' (duration: 219.802423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:54:09.542597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.706094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/loki-runtime\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T08:54:09.542689Z","caller":"traceutil/trace.go:171","msg":"trace[1511782015] range","detail":"{range_begin:/registry/configmaps/monitoring/loki-runtime; range_end:; response_count:0; response_revision:4981; }","duration":"225.806807ms","start":"2026-06-08T08:54:09.316864Z","end":"2026-06-08T08:54:09.542670Z","steps":["trace[1511782015] 'agreement among raft nodes before linearized reading' (duration: 225.636592ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:54:09.542474Z","caller":"traceutil/trace.go:171","msg":"trace[2133908914] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5174; }","duration":"225.53635ms","start":"2026-06-08T08:54:09.316867Z","end":"2026-06-08T08:54:09.542403Z","steps":["trace[2133908914] 'read index received' (duration: 218.643034ms)","trace[2133908914] 'applied index is now lower than readState.Index' (duration: 6.890636ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T08:54:09.542894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.655451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" limit:1 ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-08T08:54:09.542953Z","caller":"traceutil/trace.go:171","msg":"trace[335816330] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4981; }","duration":"189.724034ms","start":"2026-06-08T08:54:09.353208Z","end":"2026-06-08T08:54:09.542932Z","steps":["trace[335816330] 'agreement among raft nodes before linearized reading' (duration: 189.60606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:54:09.543042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.010642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dqwx4\" limit:1 ","response":"range_response_count:1 size:6997"} {"level":"info","ts":"2026-06-08T08:54:09.543094Z","caller":"traceutil/trace.go:171","msg":"trace[45666180] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dqwx4; range_end:; response_count:1; response_revision:4981; }","duration":"107.073953ms","start":"2026-06-08T08:54:09.436006Z","end":"2026-06-08T08:54:09.543080Z","steps":["trace[45666180] 'agreement among raft nodes before linearized reading' (duration: 106.94484ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:54:25.738791Z","caller":"traceutil/trace.go:171","msg":"trace[1767527250] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"117.552777ms","start":"2026-06-08T08:54:25.621214Z","end":"2026-06-08T08:54:25.738767Z","steps":["trace[1767527250] 'process raft request' (duration: 32.567959ms)","trace[1767527250] 'compare' (duration: 84.398054ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T08:54:25.739159Z","caller":"traceutil/trace.go:171","msg":"trace[302344468] linearizableReadLoop","detail":"{readStateIndex:5575; appliedIndex:5573; }","duration":"112.697659ms","start":"2026-06-08T08:54:25.626451Z","end":"2026-06-08T08:54:25.739149Z","steps":["trace[302344468] 'read index received' (duration: 11.089298ms)","trace[302344468] 'applied index is now lower than readState.Index' (duration: 101.607431ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T08:54:25.739267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.808062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dqwx4\" limit:1 ","response":"range_response_count:1 size:6997"} {"level":"info","ts":"2026-06-08T08:54:25.739300Z","caller":"traceutil/trace.go:171","msg":"trace[1338219189] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dqwx4; range_end:; response_count:1; response_revision:5372; }","duration":"112.857253ms","start":"2026-06-08T08:54:25.626434Z","end":"2026-06-08T08:54:25.739291Z","steps":["trace[1338219189] 'agreement among raft nodes before linearized reading' (duration: 112.751791ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:54:26.304535Z","caller":"traceutil/trace.go:171","msg":"trace[1371913727] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"113.014367ms","start":"2026-06-08T08:54:26.191498Z","end":"2026-06-08T08:54:26.304513Z","steps":["trace[1371913727] 'process raft request' (duration: 112.855283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T08:54:34.587390Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.278398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-06-08T08:54:34.587445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.126666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T08:54:34.587500Z","caller":"traceutil/trace.go:171","msg":"trace[96940670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5550; }","duration":"100.402991ms","start":"2026-06-08T08:54:34.487074Z","end":"2026-06-08T08:54:34.587477Z","steps":["trace[96940670] 'range keys from in-memory index tree' (duration: 100.135385ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:54:34.587571Z","caller":"traceutil/trace.go:171","msg":"trace[24363967] range","detail":"{range_begin:/registry/configmaps; range_end:; response_count:0; response_revision:5550; }","duration":"136.2763ms","start":"2026-06-08T08:54:34.451268Z","end":"2026-06-08T08:54:34.587544Z","steps":["trace[24363967] 'range keys from in-memory index tree' (duration: 136.037134ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T08:55:23.953103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2976} {"level":"info","ts":"2026-06-08T08:55:24.039769Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":2976,"took":"83.831199ms","hash":3151768834,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":33738752,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-08T08:55:24.039854Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":3151768834,"revision":2976,"compact-revision":-1} {"level":"info","ts":"2026-06-08T09:00:23.958333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6033} {"level":"info","ts":"2026-06-08T09:00:24.056207Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":6033,"took":"95.520047ms","hash":429634369,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":22114304,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-08T09:00:24.056280Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":429634369,"revision":6033,"compact-revision":2976} {"level":"info","ts":"2026-06-08T09:00:40.865349Z","caller":"traceutil/trace.go:171","msg":"trace[2062324293] linearizableReadLoop","detail":"{readStateIndex:8673; appliedIndex:8672; }","duration":"139.549386ms","start":"2026-06-08T09:00:40.725775Z","end":"2026-06-08T09:00:40.865324Z","steps":["trace[2062324293] 'read index received' (duration: 139.28977ms)","trace[2062324293] 'applied index is now lower than readState.Index' (duration: 258.876µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:00:40.865396Z","caller":"traceutil/trace.go:171","msg":"trace[707632654] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"248.717356ms","start":"2026-06-08T09:00:40.616652Z","end":"2026-06-08T09:00:40.865370Z","steps":["trace[707632654] 'process raft request' (duration: 248.48322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:00:40.865543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.758182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" limit:1 ","response":"range_response_count:1 size:13059"} {"level":"info","ts":"2026-06-08T09:00:40.865571Z","caller":"traceutil/trace.go:171","msg":"trace[785642130] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8372; }","duration":"139.804253ms","start":"2026-06-08T09:00:40.725759Z","end":"2026-06-08T09:00:40.865564Z","steps":["trace[785642130] 'agreement among raft nodes before linearized reading' (duration: 139.711271ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:00:41.033470Z","caller":"traceutil/trace.go:171","msg":"trace[572918341] linearizableReadLoop","detail":"{readStateIndex:8674; appliedIndex:8673; }","duration":"161.630163ms","start":"2026-06-08T09:00:40.871794Z","end":"2026-06-08T09:00:41.033424Z","steps":["trace[572918341] 'read index received' (duration: 133.020519ms)","trace[572918341] 'applied index is now lower than readState.Index' (duration: 28.608574ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:00:41.033708Z","caller":"traceutil/trace.go:171","msg":"trace[768092866] transaction","detail":"{read_only:false; response_revision:8373; number_of_response:1; }","duration":"163.315353ms","start":"2026-06-08T09:00:40.870375Z","end":"2026-06-08T09:00:41.033690Z","steps":["trace[768092866] 'process raft request' (duration: 134.526055ms)","trace[768092866] 'compare' (duration: 27.903367ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:00:41.034174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.281882ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T09:00:41.034325Z","caller":"traceutil/trace.go:171","msg":"trace[1402700489] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8373; }","duration":"109.603149ms","start":"2026-06-08T09:00:40.924665Z","end":"2026-06-08T09:00:41.034268Z","steps":["trace[1402700489] 'agreement among raft nodes before linearized reading' (duration: 109.240251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:00:41.034475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.655837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" limit:1 ","response":"range_response_count:1 size:19540"} {"level":"info","ts":"2026-06-08T09:00:41.034536Z","caller":"traceutil/trace.go:171","msg":"trace[1717452969] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8373; }","duration":"162.738979ms","start":"2026-06-08T09:00:40.871785Z","end":"2026-06-08T09:00:41.034524Z","steps":["trace[1717452969] 'agreement among raft nodes before linearized reading' (duration: 162.593046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:00:41.034680Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.97592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T09:00:41.034745Z","caller":"traceutil/trace.go:171","msg":"trace[1465543914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8373; }","duration":"108.041331ms","start":"2026-06-08T09:00:40.926689Z","end":"2026-06-08T09:00:41.034730Z","steps":["trace[1465543914] 'agreement among raft nodes before linearized reading' (duration: 107.945619ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:00:51.978089Z","caller":"traceutil/trace.go:171","msg":"trace[1780658506] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"102.330994ms","start":"2026-06-08T09:00:51.875706Z","end":"2026-06-08T09:00:51.978037Z","steps":["trace[1780658506] 'process raft request' (duration: 102.027326ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:00:52.432867Z","caller":"traceutil/trace.go:171","msg":"trace[998366027] linearizableReadLoop","detail":"{readStateIndex:8737; appliedIndex:8735; }","duration":"132.022694ms","start":"2026-06-08T09:00:52.300815Z","end":"2026-06-08T09:00:52.432838Z","steps":["trace[998366027] 'read index received' (duration: 56.181163ms)","trace[998366027] 'applied index is now lower than readState.Index' (duration: 75.840591ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:00:52.432931Z","caller":"traceutil/trace.go:171","msg":"trace[416349067] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"135.397655ms","start":"2026-06-08T09:00:52.297478Z","end":"2026-06-08T09:00:52.432876Z","steps":["trace[416349067] 'process raft request' (duration: 135.196461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:00:52.433093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.251629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T09:00:52.433211Z","caller":"traceutil/trace.go:171","msg":"trace[1427316484] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8433; }","duration":"132.382862ms","start":"2026-06-08T09:00:52.300806Z","end":"2026-06-08T09:00:52.433189Z","steps":["trace[1427316484] 'agreement among raft nodes before linearized reading' (duration: 132.202908ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:01:20.146865Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022f9.wal"} {"level":"info","ts":"2026-06-08T09:01:25.497302Z","caller":"traceutil/trace.go:171","msg":"trace[484678111] linearizableReadLoop","detail":"{readStateIndex:8974; appliedIndex:8973; }","duration":"207.846373ms","start":"2026-06-08T09:01:25.289430Z","end":"2026-06-08T09:01:25.497276Z","steps":["trace[484678111] 'read index received' (duration: 207.577567ms)","trace[484678111] 'applied index is now lower than readState.Index' (duration: 268.116µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:01:25.497450Z","caller":"traceutil/trace.go:171","msg":"trace[45054576] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"345.064703ms","start":"2026-06-08T09:01:25.152328Z","end":"2026-06-08T09:01:25.497393Z","steps":["trace[45054576] 'process raft request' (duration: 344.736105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:01:25.497499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.045198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-08T09:01:25.497656Z","caller":"traceutil/trace.go:171","msg":"trace[721049095] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8662; }","duration":"208.221332ms","start":"2026-06-08T09:01:25.289423Z","end":"2026-06-08T09:01:25.497644Z","steps":["trace[721049095] 'agreement among raft nodes before linearized reading' (duration: 208.008647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:01:25.498898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:01:25.152303Z","time spent":"346.407576ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:01:38.320942Z","caller":"traceutil/trace.go:171","msg":"trace[2103730973] transaction","detail":"{read_only:false; response_revision:8776; number_of_response:1; }","duration":"223.979815ms","start":"2026-06-08T09:01:38.096870Z","end":"2026-06-08T09:01:38.320850Z","steps":["trace[2103730973] 'process raft request' (duration: 151.089406ms)","trace[2103730973] 'compare' (duration: 72.619801ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:01:38.326232Z","caller":"traceutil/trace.go:171","msg":"trace[987129329] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8777; }","duration":"228.904964ms","start":"2026-06-08T09:01:38.097306Z","end":"2026-06-08T09:01:38.326211Z","steps":["trace[987129329] 'process raft request' (duration: 228.3192ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:01:38.326514Z","caller":"traceutil/trace.go:171","msg":"trace[748740100] transaction","detail":"{read_only:false; response_revision:8778; number_of_response:1; }","duration":"118.052726ms","start":"2026-06-08T09:01:38.208447Z","end":"2026-06-08T09:01:38.326500Z","steps":["trace[748740100] 'process raft request' (duration: 117.312417ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:01:39.160379Z","caller":"traceutil/trace.go:171","msg":"trace[919999849] transaction","detail":"{read_only:false; response_revision:8782; number_of_response:1; }","duration":"110.669785ms","start":"2026-06-08T09:01:39.049682Z","end":"2026-06-08T09:01:39.160352Z","steps":["trace[919999849] 'process raft request' (duration: 110.504041ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:03:11.675570Z","caller":"traceutil/trace.go:171","msg":"trace[1926248029] transaction","detail":"{read_only:false; response_revision:9615; number_of_response:1; }","duration":"199.123794ms","start":"2026-06-08T09:03:11.476423Z","end":"2026-06-08T09:03:11.675547Z","steps":["trace[1926248029] 'process raft request' (duration: 198.96124ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:03:18.212643Z","caller":"etcdserver/server.go:1490","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T09:03:18.219375Z","caller":"etcdserver/server.go:2553","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-08T09:03:18.219506Z","caller":"etcdserver/server.go:2583","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-08T09:05:23.964359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8149} {"level":"info","ts":"2026-06-08T09:05:24.029970Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":8149,"took":"62.819494ms","hash":2007875577,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":28729344,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-08T09:05:24.030097Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":2007875577,"revision":8149,"compact-revision":6033} {"level":"warn","ts":"2026-06-08T09:08:09.062070Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.93509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T09:08:09.062190Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.992543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" limit:1 ","response":"range_response_count:1 size:5098"} {"level":"info","ts":"2026-06-08T09:08:09.062313Z","caller":"traceutil/trace.go:171","msg":"trace[394358993] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11850; }","duration":"158.31469ms","start":"2026-06-08T09:08:08.903988Z","end":"2026-06-08T09:08:09.062303Z","steps":["trace[394358993] 'range keys from in-memory index tree' (duration: 157.827409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:08:09.062390Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.736265ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T09:08:09.062439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.292649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T09:08:09.062526Z","caller":"traceutil/trace.go:171","msg":"trace[404320577] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:11850; }","duration":"138.88342ms","start":"2026-06-08T09:08:08.923600Z","end":"2026-06-08T09:08:09.062483Z","steps":["trace[404320577] 'range keys from in-memory index tree' (duration: 138.685595ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:08:09.062562Z","caller":"traceutil/trace.go:171","msg":"trace[29352165] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11850; }","duration":"160.439683ms","start":"2026-06-08T09:08:08.902108Z","end":"2026-06-08T09:08:09.062548Z","steps":["trace[29352165] 'range keys from in-memory index tree' (duration: 160.142716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:08:09.062619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.417953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T09:08:09.062644Z","caller":"traceutil/trace.go:171","msg":"trace[317643016] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11850; }","duration":"160.442793ms","start":"2026-06-08T09:08:08.902194Z","end":"2026-06-08T09:08:09.062636Z","steps":["trace[317643016] 'range keys from in-memory index tree' (duration: 160.350902ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:08:09.062188Z","caller":"traceutil/trace.go:171","msg":"trace[100910814] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11850; }","duration":"160.059423ms","start":"2026-06-08T09:08:08.902103Z","end":"2026-06-08T09:08:09.062162Z","steps":["trace[100910814] 'range keys from in-memory index tree' (duration: 159.690635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:08:09.062234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.118785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T09:08:09.062787Z","caller":"traceutil/trace.go:171","msg":"trace[720134598] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11850; }","duration":"160.673599ms","start":"2026-06-08T09:08:08.902107Z","end":"2026-06-08T09:08:09.062781Z","steps":["trace[720134598] 'range keys from in-memory index tree' (duration: 160.031033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:08:09.062773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.987158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T09:08:09.062847Z","caller":"traceutil/trace.go:171","msg":"trace[63634196] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:11850; }","duration":"302.07498ms","start":"2026-06-08T09:08:08.760755Z","end":"2026-06-08T09:08:09.062830Z","steps":["trace[63634196] 'count revisions from in-memory index tree' (duration: 301.897036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:08:09.062880Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.947897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T09:08:09.062929Z","caller":"traceutil/trace.go:171","msg":"trace[1591577164] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:11850; }","duration":"430.997018ms","start":"2026-06-08T09:08:08.631917Z","end":"2026-06-08T09:08:09.062914Z","steps":["trace[1591577164] 'count revisions from in-memory index tree' (duration: 430.835364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:08:09.062967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:08:08.631895Z","time spent":"431.061349ms","remote":"127.0.0.1:46344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-06-08T09:08:09.062885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:08:08.760732Z","time spent":"302.147651ms","remote":"127.0.0.1:46416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-08T09:08:09.062849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.686685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dqwx4\" limit:1 ","response":"range_response_count:1 size:6997"} {"level":"info","ts":"2026-06-08T09:08:09.063213Z","caller":"traceutil/trace.go:171","msg":"trace[600758197] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dqwx4; range_end:; response_count:1; response_revision:11850; }","duration":"390.041274ms","start":"2026-06-08T09:08:08.673152Z","end":"2026-06-08T09:08:09.063193Z","steps":["trace[600758197] 'range keys from in-memory index tree' (duration: 389.552982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:08:09.063258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:08:08.673125Z","time spent":"390.120726ms","remote":"127.0.0.1:45386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7020,"request content":"key:\"/registry/pods/openstack/keepalived-dqwx4\" limit:1 "} {"level":"info","ts":"2026-06-08T09:08:34.600475Z","caller":"traceutil/trace.go:171","msg":"trace[1216262352] transaction","detail":"{read_only:false; response_revision:11989; number_of_response:1; }","duration":"122.467592ms","start":"2026-06-08T09:08:34.477974Z","end":"2026-06-08T09:08:34.600441Z","steps":["trace[1216262352] 'process raft request' (duration: 91.862129ms)","trace[1216262352] 'compare' (duration: 30.177903ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:10:23.971741Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10522} {"level":"info","ts":"2026-06-08T09:10:24.052545Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":10522,"took":"78.578887ms","hash":1673129846,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":26505216,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-08T09:10:24.052637Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":1673129846,"revision":10522,"compact-revision":8149} {"level":"info","ts":"2026-06-08T09:15:23.977242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12726} {"level":"warn","ts":"2026-06-08T09:15:24.250735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.956878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" limit:1 ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-06-08T09:15:24.251158Z","caller":"traceutil/trace.go:171","msg":"trace[1127540695] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14708; }","duration":"136.387179ms","start":"2026-06-08T09:15:24.114757Z","end":"2026-06-08T09:15:24.251144Z","steps":["trace[1127540695] 'range keys from in-memory index tree' (duration: 135.860146ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:15:24.289928Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":12726,"took":"309.692525ms","hash":2215722843,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":27762688,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-08T09:15:24.289999Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":2215722843,"revision":12726,"compact-revision":10522} {"level":"info","ts":"2026-06-08T09:15:50.682589Z","caller":"traceutil/trace.go:171","msg":"trace[778405642] transaction","detail":"{read_only:false; response_revision:14857; number_of_response:1; }","duration":"422.620205ms","start":"2026-06-08T09:15:50.259945Z","end":"2026-06-08T09:15:50.682565Z","steps":["trace[778405642] 'process raft request' (duration: 422.468572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:15:50.682819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:15:50.259917Z","time spent":"422.778379ms","remote":"127.0.0.1:45386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12828,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:15:50.702738Z","caller":"traceutil/trace.go:171","msg":"trace[1680988161] linearizableReadLoop","detail":"{readStateIndex:15379; appliedIndex:15377; }","duration":"249.834412ms","start":"2026-06-08T09:15:50.452882Z","end":"2026-06-08T09:15:50.702716Z","steps":["trace[1680988161] 'read index received' (duration: 229.53627ms)","trace[1680988161] 'applied index is now lower than readState.Index' (duration: 20.297012ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:15:50.702876Z","caller":"traceutil/trace.go:171","msg":"trace[1216542450] transaction","detail":"{read_only:false; response_revision:14858; number_of_response:1; }","duration":"282.550176ms","start":"2026-06-08T09:15:50.420306Z","end":"2026-06-08T09:15:50.702856Z","steps":["trace[1216542450] 'process raft request' (duration: 282.25891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:15:50.702918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.020106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T09:15:50.702957Z","caller":"traceutil/trace.go:171","msg":"trace[674298346] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:14858; }","duration":"250.080068ms","start":"2026-06-08T09:15:50.452865Z","end":"2026-06-08T09:15:50.702945Z","steps":["trace[674298346] 'agreement among raft nodes before linearized reading' (duration: 249.968975ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:16:20.449642Z","caller":"traceutil/trace.go:171","msg":"trace[1532686735] transaction","detail":"{read_only:false; response_revision:15136; number_of_response:1; }","duration":"389.314737ms","start":"2026-06-08T09:16:20.060276Z","end":"2026-06-08T09:16:20.449591Z","steps":["trace[1532686735] 'process raft request' (duration: 355.828325ms)","trace[1532686735] 'compare' (duration: 33.156214ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:16:20.449868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:16:20.060249Z","time spent":"389.501291ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:16:20.453395Z","caller":"traceutil/trace.go:171","msg":"trace[626018348] linearizableReadLoop","detail":"{readStateIndex:15666; appliedIndex:15664; }","duration":"168.659223ms","start":"2026-06-08T09:16:20.284721Z","end":"2026-06-08T09:16:20.453380Z","steps":["trace[626018348] 'read index received' (duration: 131.410019ms)","trace[626018348] 'applied index is now lower than readState.Index' (duration: 37.248374ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:16:20.453478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.765105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-pnxl5.18b7105f35854033\" limit:1 ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-06-08T09:16:20.453506Z","caller":"traceutil/trace.go:171","msg":"trace[94808369] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-pnxl5.18b7105f35854033; range_end:; response_count:1; response_revision:15137; }","duration":"168.793386ms","start":"2026-06-08T09:16:20.284704Z","end":"2026-06-08T09:16:20.453497Z","steps":["trace[94808369] 'agreement among raft nodes before linearized reading' (duration: 168.724634ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:16:20.453528Z","caller":"traceutil/trace.go:171","msg":"trace[269066455] transaction","detail":"{read_only:false; response_revision:15137; number_of_response:1; }","duration":"324.515325ms","start":"2026-06-08T09:16:20.128946Z","end":"2026-06-08T09:16:20.453462Z","steps":["trace[269066455] 'process raft request' (duration: 324.33091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:16:20.453799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:16:20.128922Z","time spent":"324.740631ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:17:39.448878Z","caller":"traceutil/trace.go:171","msg":"trace[612864453] transaction","detail":"{read_only:false; response_revision:16111; number_of_response:1; }","duration":"106.13746ms","start":"2026-06-08T09:17:39.342709Z","end":"2026-06-08T09:17:39.448847Z","steps":["trace[612864453] 'process raft request' (duration: 105.893574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:17:39.663381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.779356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-08T09:17:39.663495Z","caller":"traceutil/trace.go:171","msg":"trace[1703608578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16112; }","duration":"116.899089ms","start":"2026-06-08T09:17:39.546573Z","end":"2026-06-08T09:17:39.663472Z","steps":["trace[1703608578] 'range keys from in-memory index tree' (duration: 116.651253ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:17:40.876671Z","caller":"traceutil/trace.go:171","msg":"trace[647687893] linearizableReadLoop","detail":"{readStateIndex:16680; appliedIndex:16679; }","duration":"113.699142ms","start":"2026-06-08T09:17:40.762948Z","end":"2026-06-08T09:17:40.876647Z","steps":["trace[647687893] 'read index received' (duration: 113.61232ms)","trace[647687893] 'applied index is now lower than readState.Index' (duration: 85.582µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:17:40.876872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.632942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" limit:1 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T09:17:40.876905Z","caller":"traceutil/trace.go:171","msg":"trace[1642249261] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16118; }","duration":"113.689173ms","start":"2026-06-08T09:17:40.763207Z","end":"2026-06-08T09:17:40.876896Z","steps":["trace[1642249261] 'agreement among raft nodes before linearized reading' (duration: 113.602681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:17:40.877012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.96431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" limit:1 ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-06-08T09:17:40.877098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.327685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" limit:1 ","response":"range_response_count:1 size:5542"} {"level":"info","ts":"2026-06-08T09:17:40.877225Z","caller":"traceutil/trace.go:171","msg":"trace[663693576] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16118; }","duration":"113.446008ms","start":"2026-06-08T09:17:40.763731Z","end":"2026-06-08T09:17:40.877177Z","steps":["trace[663693576] 'agreement among raft nodes before linearized reading' (duration: 113.183632ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:17:40.877330Z","caller":"traceutil/trace.go:171","msg":"trace[1303324481] transaction","detail":"{read_only:false; response_revision:16118; number_of_response:1; }","duration":"138.240868ms","start":"2026-06-08T09:17:40.739069Z","end":"2026-06-08T09:17:40.877310Z","steps":["trace[1303324481] 'process raft request' (duration: 137.393727ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:17:40.877197Z","caller":"traceutil/trace.go:171","msg":"trace[2083148421] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16118; }","duration":"114.220026ms","start":"2026-06-08T09:17:40.762941Z","end":"2026-06-08T09:17:40.877161Z","steps":["trace[2083148421] 'agreement among raft nodes before linearized reading' (duration: 113.844087ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:20:23.984683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14708} {"level":"info","ts":"2026-06-08T09:20:24.062263Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":14708,"took":"74.259838ms","hash":2753983420,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":32333824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T09:20:24.062352Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":2753983420,"revision":14708,"compact-revision":12726} {"level":"warn","ts":"2026-06-08T09:21:33.994337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.419326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" limit:1 ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-06-08T09:21:33.994458Z","caller":"traceutil/trace.go:171","msg":"trace[816397835] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:17854; }","duration":"115.559669ms","start":"2026-06-08T09:21:33.878878Z","end":"2026-06-08T09:21:33.994438Z","steps":["trace[816397835] 'range keys from in-memory index tree' (duration: 115.22303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:21:33.994822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.896257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:21:33.994865Z","caller":"traceutil/trace.go:171","msg":"trace[1747951037] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17854; }","duration":"115.937988ms","start":"2026-06-08T09:21:33.878916Z","end":"2026-06-08T09:21:33.994854Z","steps":["trace[1747951037] 'range keys from in-memory index tree' (duration: 115.819485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:21:33.994819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.083171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:21:33.994990Z","caller":"traceutil/trace.go:171","msg":"trace[2142981851] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17854; }","duration":"116.278016ms","start":"2026-06-08T09:21:33.878679Z","end":"2026-06-08T09:21:33.994957Z","steps":["trace[2142981851] 'range keys from in-memory index tree' (duration: 115.978799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:21:33.995691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.046341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:21:33.995854Z","caller":"traceutil/trace.go:171","msg":"trace[424948796] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17854; }","duration":"117.121497ms","start":"2026-06-08T09:21:33.878710Z","end":"2026-06-08T09:21:33.995831Z","steps":["trace[424948796] 'range keys from in-memory index tree' (duration: 115.912697ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:23:51.564023Z","caller":"traceutil/trace.go:171","msg":"trace[639096869] linearizableReadLoop","detail":"{readStateIndex:19546; appliedIndex:19545; }","duration":"103.681471ms","start":"2026-06-08T09:23:51.460307Z","end":"2026-06-08T09:23:51.563988Z","steps":["trace[639096869] 'read index received' (duration: 73.952671ms)","trace[639096869] 'applied index is now lower than readState.Index' (duration: 29.72748ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:23:51.564550Z","caller":"traceutil/trace.go:171","msg":"trace[1861801571] transaction","detail":"{read_only:false; response_revision:18896; number_of_response:1; }","duration":"105.956606ms","start":"2026-06-08T09:23:51.458221Z","end":"2026-06-08T09:23:51.564177Z","steps":["trace[1861801571] 'process raft request' (duration: 76.020891ms)","trace[1861801571] 'compare' (duration: 29.466304ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:23:51.564603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.274196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" limit:1 ","response":"range_response_count:1 size:3299"} {"level":"info","ts":"2026-06-08T09:23:51.565178Z","caller":"traceutil/trace.go:171","msg":"trace[1341590992] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18898; }","duration":"104.85385ms","start":"2026-06-08T09:23:51.460297Z","end":"2026-06-08T09:23:51.565151Z","steps":["trace[1341590992] 'agreement among raft nodes before linearized reading' (duration: 103.869006ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:23:52.379989Z","caller":"traceutil/trace.go:171","msg":"trace[2126032842] transaction","detail":"{read_only:false; response_revision:18905; number_of_response:1; }","duration":"215.753976ms","start":"2026-06-08T09:23:52.164194Z","end":"2026-06-08T09:23:52.379948Z","steps":["trace[2126032842] 'process raft request' (duration: 215.508091ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:23:52.420435Z","caller":"traceutil/trace.go:171","msg":"trace[1720937403] transaction","detail":"{read_only:false; response_revision:18906; number_of_response:1; }","duration":"140.926073ms","start":"2026-06-08T09:23:52.279480Z","end":"2026-06-08T09:23:52.420406Z","steps":["trace[1720937403] 'process raft request' (duration: 122.332063ms)","trace[1720937403] 'compare' (duration: 18.470297ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:23:52.945341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.200789ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-08T09:23:52.946195Z","caller":"traceutil/trace.go:171","msg":"trace[363415701] linearizableReadLoop","detail":"{readStateIndex:19558; appliedIndex:19556; }","duration":"131.495505ms","start":"2026-06-08T09:23:52.814663Z","end":"2026-06-08T09:23:52.946158Z","steps":["trace[363415701] 'read index received' (duration: 104.994154ms)","trace[363415701] 'applied index is now lower than readState.Index' (duration: 26.499511ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:23:52.946386Z","caller":"traceutil/trace.go:171","msg":"trace[1129584126] transaction","detail":"{read_only:false; response_revision:18907; number_of_response:1; }","duration":"336.03851ms","start":"2026-06-08T09:23:52.610329Z","end":"2026-06-08T09:23:52.946368Z","steps":["trace[1129584126] 'process raft request' (duration: 335.120508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:23:52.946532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:23:52.610296Z","time spent":"336.141233ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:23:53.001325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.547234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-ws4lh\" limit:1 ","response":"range_response_count:1 size:10420"} {"level":"info","ts":"2026-06-08T09:23:53.001416Z","caller":"traceutil/trace.go:171","msg":"trace[786758371] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-ws4lh; range_end:; response_count:1; response_revision:18907; }","duration":"147.657477ms","start":"2026-06-08T09:23:52.853734Z","end":"2026-06-08T09:23:53.001392Z","steps":["trace[786758371] 'agreement among raft nodes before linearized reading' (duration: 92.722726ms)","trace[786758371] 'range keys from in-memory index tree' (duration: 54.757117ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:23:53.001795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.099722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fcp4t\" limit:1 ","response":"range_response_count:1 size:24583"} {"level":"info","ts":"2026-06-08T09:23:53.001986Z","caller":"traceutil/trace.go:171","msg":"trace[1480444810] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fcp4t; range_end:; response_count:1; response_revision:18907; }","duration":"187.309137ms","start":"2026-06-08T09:23:52.814643Z","end":"2026-06-08T09:23:53.001952Z","steps":["trace[1480444810] 'agreement among raft nodes before linearized reading' (duration: 131.834693ms)","trace[1480444810] 'range keys from in-memory index tree' (duration: 55.094085ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:24:20.290256Z","caller":"etcdserver/server.go:1490","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T09:24:20.294207Z","caller":"etcdserver/server.go:2553","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-08T09:24:20.294282Z","caller":"etcdserver/server.go:2583","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-08T09:25:23.990966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17269} {"level":"info","ts":"2026-06-08T09:25:24.094194Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":17269,"took":"99.134421ms","hash":1364697514,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":35241984,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-08T09:25:24.094294Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":1364697514,"revision":17269,"compact-revision":14708} {"level":"warn","ts":"2026-06-08T09:28:59.023762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.396308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:28:59.024034Z","caller":"traceutil/trace.go:171","msg":"trace[1633713683] linearizableReadLoop","detail":"{readStateIndex:22032; appliedIndex:22030; }","duration":"107.776071ms","start":"2026-06-08T09:28:58.916238Z","end":"2026-06-08T09:28:59.024014Z","steps":["trace[1633713683] 'read index received' (duration: 3.030114ms)","trace[1633713683] 'applied index is now lower than readState.Index' (duration: 104.744747ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:28:59.024087Z","caller":"traceutil/trace.go:171","msg":"trace[366075272] transaction","detail":"{read_only:false; response_revision:21301; number_of_response:1; }","duration":"217.806497ms","start":"2026-06-08T09:28:58.806202Z","end":"2026-06-08T09:28:59.024008Z","steps":["trace[366075272] 'process raft request' (duration: 113.0576ms)","trace[366075272] 'compare' (duration: 103.938018ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:28:59.024358Z","caller":"traceutil/trace.go:171","msg":"trace[1578252307] transaction","detail":"{read_only:false; response_revision:21302; number_of_response:1; }","duration":"165.249893ms","start":"2026-06-08T09:28:58.859050Z","end":"2026-06-08T09:28:59.024300Z","steps":["trace[1578252307] 'process raft request' (duration: 164.861724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:28:59.024440Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.188221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:28:59.024487Z","caller":"traceutil/trace.go:171","msg":"trace[948545417] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:21302; }","duration":"108.258653ms","start":"2026-06-08T09:28:58.916218Z","end":"2026-06-08T09:28:59.024477Z","steps":["trace[948545417] 'agreement among raft nodes before linearized reading' (duration: 108.13838ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:29:20.240872Z","caller":"traceutil/trace.go:171","msg":"trace[1187177020] linearizableReadLoop","detail":"{readStateIndex:22144; appliedIndex:22143; }","duration":"131.244969ms","start":"2026-06-08T09:29:20.109596Z","end":"2026-06-08T09:29:20.240841Z","steps":["trace[1187177020] 'read index received' (duration: 130.982483ms)","trace[1187177020] 'applied index is now lower than readState.Index' (duration: 261.406µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:29:20.241029Z","caller":"traceutil/trace.go:171","msg":"trace[1254387111] transaction","detail":"{read_only:false; response_revision:21410; number_of_response:1; }","duration":"207.68946ms","start":"2026-06-08T09:29:20.033329Z","end":"2026-06-08T09:29:20.241018Z","steps":["trace[1254387111] 'process raft request' (duration: 207.26951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:29:20.241280Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.169677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T09:29:20.241404Z","caller":"traceutil/trace.go:171","msg":"trace[706173787] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:21410; }","duration":"112.284ms","start":"2026-06-08T09:29:20.129077Z","end":"2026-06-08T09:29:20.241361Z","steps":["trace[706173787] 'agreement among raft nodes before linearized reading' (duration: 112.111436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:29:20.241309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.69149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T09:29:20.241526Z","caller":"traceutil/trace.go:171","msg":"trace[1837668215] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:21410; }","duration":"131.932196ms","start":"2026-06-08T09:29:20.109577Z","end":"2026-06-08T09:29:20.241509Z","steps":["trace[1837668215] 'agreement among raft nodes before linearized reading' (duration: 131.629359ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:29:21.401505Z","caller":"traceutil/trace.go:171","msg":"trace[2074967550] transaction","detail":"{read_only:false; response_revision:21415; number_of_response:1; }","duration":"289.469952ms","start":"2026-06-08T09:29:21.112001Z","end":"2026-06-08T09:29:21.401471Z","steps":["trace[2074967550] 'process raft request' (duration: 289.259957ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:29:21.401533Z","caller":"traceutil/trace.go:171","msg":"trace[205702857] linearizableReadLoop","detail":"{readStateIndex:22149; appliedIndex:22149; }","duration":"286.535601ms","start":"2026-06-08T09:29:21.114943Z","end":"2026-06-08T09:29:21.401478Z","steps":["trace[205702857] 'read index received' (duration: 286.519201ms)","trace[205702857] 'applied index is now lower than readState.Index' (duration: 14.09µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:29:21.401782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.781677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:29:21.401832Z","caller":"traceutil/trace.go:171","msg":"trace[2098185338] range","detail":"{range_begin:/registry/deployments; range_end:; response_count:0; response_revision:21415; }","duration":"286.90359ms","start":"2026-06-08T09:29:21.114919Z","end":"2026-06-08T09:29:21.401822Z","steps":["trace[2098185338] 'agreement among raft nodes before linearized reading' (duration: 286.725215ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:30:23.998354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19829} {"level":"info","ts":"2026-06-08T09:30:24.116715Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":19829,"took":"112.932638ms","hash":1848722231,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-08T09:30:24.116784Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":1848722231,"revision":19829,"compact-revision":17269} {"level":"info","ts":"2026-06-08T09:33:19.047421Z","caller":"traceutil/trace.go:171","msg":"trace[1880149179] transaction","detail":"{read_only:false; response_revision:23908; number_of_response:1; }","duration":"202.799238ms","start":"2026-06-08T09:33:18.844588Z","end":"2026-06-08T09:33:19.047387Z","steps":["trace[1880149179] 'process raft request' (duration: 202.596743ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:33:19.048541Z","caller":"traceutil/trace.go:171","msg":"trace[55147092] linearizableReadLoop","detail":"{readStateIndex:24722; appliedIndex:24718; }","duration":"124.295953ms","start":"2026-06-08T09:33:18.924218Z","end":"2026-06-08T09:33:19.048514Z","steps":["trace[55147092] 'read index received' (duration: 123.090174ms)","trace[55147092] 'applied index is now lower than readState.Index' (duration: 1.204859ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:33:19.048997Z","caller":"traceutil/trace.go:171","msg":"trace[1055641533] transaction","detail":"{read_only:false; response_revision:23909; number_of_response:1; }","duration":"203.130835ms","start":"2026-06-08T09:33:18.845854Z","end":"2026-06-08T09:33:19.048985Z","steps":["trace[1055641533] 'process raft request' (duration: 202.429208ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:33:19.049301Z","caller":"traceutil/trace.go:171","msg":"trace[1372302736] transaction","detail":"{read_only:false; response_revision:23910; number_of_response:1; }","duration":"203.063473ms","start":"2026-06-08T09:33:18.846190Z","end":"2026-06-08T09:33:19.049253Z","steps":["trace[1372302736] 'process raft request' (duration: 202.247014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:33:19.049370Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.130263ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:33:19.049425Z","caller":"traceutil/trace.go:171","msg":"trace[1560275780] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23911; }","duration":"125.207255ms","start":"2026-06-08T09:33:18.924207Z","end":"2026-06-08T09:33:19.049415Z","steps":["trace[1560275780] 'agreement among raft nodes before linearized reading' (duration: 124.99035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:33:19.049421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.341155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:33:19.049536Z","caller":"traceutil/trace.go:171","msg":"trace[1360785105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23911; }","duration":"122.468309ms","start":"2026-06-08T09:33:18.927044Z","end":"2026-06-08T09:33:19.049512Z","steps":["trace[1360785105] 'agreement among raft nodes before linearized reading' (duration: 122.278804ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:33:19.049629Z","caller":"traceutil/trace.go:171","msg":"trace[1468785524] transaction","detail":"{read_only:false; response_revision:23911; number_of_response:1; }","duration":"159.521077ms","start":"2026-06-08T09:33:18.890095Z","end":"2026-06-08T09:33:19.049616Z","steps":["trace[1468785524] 'process raft request' (duration: 158.383129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:33:19.050063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.771265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-08T09:33:19.050115Z","caller":"traceutil/trace.go:171","msg":"trace[424770016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23911; }","duration":"117.823966ms","start":"2026-06-08T09:33:18.932277Z","end":"2026-06-08T09:33:19.050101Z","steps":["trace[424770016] 'agreement among raft nodes before linearized reading' (duration: 117.697333ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:35:24.005612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22042} {"level":"info","ts":"2026-06-08T09:35:24.112108Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":22042,"took":"102.626378ms","hash":966486946,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":45289472,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-06-08T09:35:24.112184Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":966486946,"revision":22042,"compact-revision":19829} {"level":"info","ts":"2026-06-08T09:35:29.489114Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006616.wal"} {"level":"info","ts":"2026-06-08T09:36:22.265246Z","caller":"traceutil/trace.go:171","msg":"trace[978128146] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"107.699312ms","start":"2026-06-08T09:36:22.157513Z","end":"2026-06-08T09:36:22.265212Z","steps":["trace[978128146] 'process raft request' (duration: 70.205852ms)","trace[978128146] 'compare' (duration: 36.976587ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:22.265429Z","caller":"traceutil/trace.go:171","msg":"trace[1467967071] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"107.754943ms","start":"2026-06-08T09:36:22.157632Z","end":"2026-06-08T09:36:22.265386Z","steps":["trace[1467967071] 'process raft request' (duration: 107.23388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:23.034578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.497108ms","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-06-08T09:36:23.034711Z","caller":"traceutil/trace.go:171","msg":"trace[1950684014] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25831; }","duration":"110.654342ms","start":"2026-06-08T09:36:22.924026Z","end":"2026-06-08T09:36:23.034680Z","steps":["trace[1950684014] 'range keys from in-memory index tree' (duration: 110.424586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:23.034766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.446099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:36:23.034901Z","caller":"traceutil/trace.go:171","msg":"trace[1965985520] linearizableReadLoop","detail":"{readStateIndex:26693; appliedIndex:26692; }","duration":"131.052578ms","start":"2026-06-08T09:36:22.903830Z","end":"2026-06-08T09:36:23.034883Z","steps":["trace[1965985520] 'read index received' (duration: 8.319212ms)","trace[1965985520] 'applied index is now lower than readState.Index' (duration: 122.731776ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:23.035019Z","caller":"traceutil/trace.go:171","msg":"trace[289527193] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"174.199124ms","start":"2026-06-08T09:36:22.860803Z","end":"2026-06-08T09:36:23.035002Z","steps":["trace[289527193] 'process raft request' (duration: 51.407076ms)","trace[289527193] 'compare' (duration: 122.158063ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:36:23.035079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.257602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:23.035115Z","caller":"traceutil/trace.go:171","msg":"trace[888903443] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25832; }","duration":"131.294793ms","start":"2026-06-08T09:36:22.903811Z","end":"2026-06-08T09:36:23.035106Z","steps":["trace[888903443] 'agreement among raft nodes before linearized reading' (duration: 131.217481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:23.035387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.751349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:23.035423Z","caller":"traceutil/trace.go:171","msg":"trace[652492294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25832; }","duration":"111.786749ms","start":"2026-06-08T09:36:22.923628Z","end":"2026-06-08T09:36:23.035415Z","steps":["trace[652492294] 'agreement among raft nodes before linearized reading' (duration: 111.723588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:23.321506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.581059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:23.321638Z","caller":"traceutil/trace.go:171","msg":"trace[2017677909] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25832; }","duration":"180.742053ms","start":"2026-06-08T09:36:23.140864Z","end":"2026-06-08T09:36:23.321606Z","steps":["trace[2017677909] 'count revisions from in-memory index tree' (duration: 180.461876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:23.321891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.807308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:23.322065Z","caller":"traceutil/trace.go:171","msg":"trace[823374442] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:25832; }","duration":"129.932321ms","start":"2026-06-08T09:36:23.192038Z","end":"2026-06-08T09:36:23.321971Z","steps":["trace[823374442] 'count revisions from in-memory index tree' (duration: 129.666075ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:23.322064Z","caller":"traceutil/trace.go:171","msg":"trace[1078424518] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"118.243177ms","start":"2026-06-08T09:36:23.203788Z","end":"2026-06-08T09:36:23.322031Z","steps":["trace[1078424518] 'process raft request' (duration: 117.982091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:27.702285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.569873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" limit:1 ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-06-08T09:36:27.702387Z","caller":"traceutil/trace.go:171","msg":"trace[483178847] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:25867; }","duration":"179.684996ms","start":"2026-06-08T09:36:27.522679Z","end":"2026-06-08T09:36:27.702363Z","steps":["trace[483178847] 'range keys from in-memory index tree' (duration: 179.451781ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:28.148992Z","caller":"traceutil/trace.go:171","msg":"trace[1689957796] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"277.122959ms","start":"2026-06-08T09:36:27.871835Z","end":"2026-06-08T09:36:28.148958Z","steps":["trace[1689957796] 'process raft request' (duration: 276.906094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:28.470222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.995899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:36:28.470412Z","caller":"traceutil/trace.go:171","msg":"trace[1460167454] linearizableReadLoop","detail":"{readStateIndex:26731; appliedIndex:26729; }","duration":"559.393853ms","start":"2026-06-08T09:36:27.911002Z","end":"2026-06-08T09:36:28.470396Z","steps":["trace[1460167454] 'read index received' (duration: 237.725374ms)","trace[1460167454] 'applied index is now lower than readState.Index' (duration: 321.667719ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:28.470630Z","caller":"traceutil/trace.go:171","msg":"trace[1012851119] transaction","detail":"{read_only:false; response_revision:25869; number_of_response:1; }","duration":"598.651425ms","start":"2026-06-08T09:36:27.871963Z","end":"2026-06-08T09:36:28.470615Z","steps":["trace[1012851119] 'process raft request' (duration: 398.152073ms)","trace[1012851119] 'compare' (duration: 199.086857ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:36:28.470654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.658599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:28.470686Z","caller":"traceutil/trace.go:171","msg":"trace[1541083987] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25869; }","duration":"559.690869ms","start":"2026-06-08T09:36:27.910985Z","end":"2026-06-08T09:36:28.470676Z","steps":["trace[1541083987] 'agreement among raft nodes before linearized reading' (duration: 559.630218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:28.470706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:27.871914Z","time spent":"598.763507ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:28.470717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:27.910963Z","time spent":"559.744941ms","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" limit:1 "} {"level":"info","ts":"2026-06-08T09:36:28.470951Z","caller":"traceutil/trace.go:171","msg":"trace[918045518] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"141.382308ms","start":"2026-06-08T09:36:28.329530Z","end":"2026-06-08T09:36:28.470912Z","steps":["trace[918045518] 'process raft request' (duration: 141.274556ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:28.470998Z","caller":"traceutil/trace.go:171","msg":"trace[242742833] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"307.067505ms","start":"2026-06-08T09:36:28.163923Z","end":"2026-06-08T09:36:28.470990Z","steps":["trace[242742833] 'process raft request' (duration: 306.42832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:28.471344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.163893Z","time spent":"307.311761ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:28.530446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.706355ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:28.530588Z","caller":"traceutil/trace.go:171","msg":"trace[1224464872] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25872; }","duration":"605.872779ms","start":"2026-06-08T09:36:27.924689Z","end":"2026-06-08T09:36:28.530562Z","steps":["trace[1224464872] 'agreement among raft nodes before linearized reading' (duration: 605.637663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:28.530609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.492761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:28.530705Z","caller":"traceutil/trace.go:171","msg":"trace[455795652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25872; }","duration":"603.601204ms","start":"2026-06-08T09:36:27.927080Z","end":"2026-06-08T09:36:28.530681Z","steps":["trace[455795652] 'agreement among raft nodes before linearized reading' (duration: 603.403089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:28.530765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:27.927063Z","time spent":"603.692707ms","remote":"127.0.0.1:45220","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-06-08T09:36:28.530820Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.960047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-08T09:36:28.530900Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.039545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-08T09:36:28.530931Z","caller":"traceutil/trace.go:171","msg":"trace[1304393165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25872; }","duration":"235.08027ms","start":"2026-06-08T09:36:28.295824Z","end":"2026-06-08T09:36:28.530904Z","steps":["trace[1304393165] 'agreement among raft nodes before linearized reading' (duration: 234.930037ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:28.530939Z","caller":"traceutil/trace.go:171","msg":"trace[457995006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25872; }","duration":"239.080106ms","start":"2026-06-08T09:36:28.291851Z","end":"2026-06-08T09:36:28.530931Z","steps":["trace[457995006] 'agreement among raft nodes before linearized reading' (duration: 238.982243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:28.531178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.326205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-08T09:36:28.531217Z","caller":"traceutil/trace.go:171","msg":"trace[1527683256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25872; }","duration":"360.366176ms","start":"2026-06-08T09:36:28.170842Z","end":"2026-06-08T09:36:28.531208Z","steps":["trace[1527683256] 'agreement among raft nodes before linearized reading' (duration: 359.454815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:28.531243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.170824Z","time spent":"360.410358ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:28.784186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.303616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:36:28.784353Z","caller":"traceutil/trace.go:171","msg":"trace[2034486167] transaction","detail":"{read_only:false; response_revision:25873; number_of_response:1; }","duration":"249.51545ms","start":"2026-06-08T09:36:28.534762Z","end":"2026-06-08T09:36:28.784278Z","steps":["trace[2034486167] 'process raft request' (duration: 68.005719ms)","trace[2034486167] 'compare' (duration: 181.154471ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:36:29.054044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.038334ms","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-06-08T09:36:29.054169Z","caller":"traceutil/trace.go:171","msg":"trace[1763979482] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25873; }","duration":"128.200208ms","start":"2026-06-08T09:36:28.925940Z","end":"2026-06-08T09:36:29.054140Z","steps":["trace[1763979482] 'range keys from in-memory index tree' (duration: 127.964042ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:29.054394Z","caller":"traceutil/trace.go:171","msg":"trace[528801040] transaction","detail":"{read_only:false; response_revision:25875; number_of_response:1; }","duration":"518.616854ms","start":"2026-06-08T09:36:28.535765Z","end":"2026-06-08T09:36:29.054382Z","steps":["trace[528801040] 'process raft request' (duration: 518.428869ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:29.054375Z","caller":"traceutil/trace.go:171","msg":"trace[1786873333] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"519.414882ms","start":"2026-06-08T09:36:28.534907Z","end":"2026-06-08T09:36:29.054321Z","steps":["trace[1786873333] 'process raft request' (duration: 484.28069ms)","trace[1786873333] 'compare' (duration: 34.61068ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:29.054472Z","caller":"traceutil/trace.go:171","msg":"trace[1275031684] transaction","detail":"{read_only:false; response_revision:25876; number_of_response:1; }","duration":"515.412156ms","start":"2026-06-08T09:36:28.539037Z","end":"2026-06-08T09:36:29.054449Z","steps":["trace[1275031684] 'process raft request' (duration: 515.269852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:29.054500Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.535756Z","time spent":"518.676565ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:29.054561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.539022Z","time spent":"515.499578ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:29.054586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.534899Z","time spent":"519.571576ms","remote":"127.0.0.1:45486","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-06-08T09:36:29.054602Z","caller":"traceutil/trace.go:171","msg":"trace[1871223331] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"238.651316ms","start":"2026-06-08T09:36:28.815923Z","end":"2026-06-08T09:36:29.054574Z","steps":["trace[1871223331] 'process raft request' (duration: 238.605174ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:29.054675Z","caller":"traceutil/trace.go:171","msg":"trace[2002704471] transaction","detail":"{read_only:false; response_revision:25878; number_of_response:1; }","duration":"474.077124ms","start":"2026-06-08T09:36:28.580586Z","end":"2026-06-08T09:36:29.054663Z","steps":["trace[2002704471] 'process raft request' (duration: 473.814117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:29.054763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.580559Z","time spent":"474.158736ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:36:29.054798Z","caller":"traceutil/trace.go:171","msg":"trace[907074732] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"489.518239ms","start":"2026-06-08T09:36:28.565271Z","end":"2026-06-08T09:36:29.054789Z","steps":["trace[907074732] 'process raft request' (duration: 489.092308ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:29.054804Z","caller":"traceutil/trace.go:171","msg":"trace[1250196331] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"473.277865ms","start":"2026-06-08T09:36:28.581517Z","end":"2026-06-08T09:36:29.054795Z","steps":["trace[1250196331] 'process raft request' (duration: 472.961607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:29.054845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.565245Z","time spent":"489.578219ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:36:29.054835Z","caller":"traceutil/trace.go:171","msg":"trace[994751043] linearizableReadLoop","detail":"{readStateIndex:26737; appliedIndex:26734; }","duration":"518.974022ms","start":"2026-06-08T09:36:28.535846Z","end":"2026-06-08T09:36:29.054820Z","steps":["trace[994751043] 'read index received' (duration: 66.939173ms)","trace[994751043] 'applied index is now lower than readState.Index' (duration: 452.032319ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:36:29.054920Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.068945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" limit:1 ","response":"range_response_count:1 size:18528"} {"level":"info","ts":"2026-06-08T09:36:29.056638Z","caller":"traceutil/trace.go:171","msg":"trace[835494497] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25880; }","duration":"520.785787ms","start":"2026-06-08T09:36:28.535841Z","end":"2026-06-08T09:36:29.056627Z","steps":["trace[835494497] 'agreement among raft nodes before linearized reading' (duration: 519.020633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:29.056680Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.535831Z","time spent":"520.840538ms","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":18551,"request content":"key:\"/registry/minions/instance\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:29.054915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.581509Z","time spent":"473.322726ms","remote":"127.0.0.1:45486","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-06-08T09:36:29.055019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.095678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T09:36:29.056855Z","caller":"traceutil/trace.go:171","msg":"trace[1196487696] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25880; }","duration":"483.922253ms","start":"2026-06-08T09:36:28.572912Z","end":"2026-06-08T09:36:29.056834Z","steps":["trace[1196487696] 'agreement among raft nodes before linearized reading' (duration: 482.052947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:29.056913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:28.572887Z","time spent":"484.008755ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-06-08T09:36:29.332729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.469461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:36:29.332839Z","caller":"traceutil/trace.go:171","msg":"trace[392180009] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"264.591234ms","start":"2026-06-08T09:36:29.068227Z","end":"2026-06-08T09:36:29.332819Z","steps":["trace[392180009] 'process raft request' (duration: 143.935669ms)","trace[392180009] 'compare' (duration: 120.206765ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:29.593560Z","caller":"traceutil/trace.go:171","msg":"trace[2014339890] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"158.257466ms","start":"2026-06-08T09:36:29.435272Z","end":"2026-06-08T09:36:29.593530Z","steps":["trace[2014339890] 'process raft request' (duration: 158.073292ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:30.552394Z","caller":"traceutil/trace.go:171","msg":"trace[94236822] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"395.827238ms","start":"2026-06-08T09:36:30.156536Z","end":"2026-06-08T09:36:30.552363Z","steps":["trace[94236822] 'process raft request' (duration: 395.643683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:30.552591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:30.156506Z","time spent":"395.992772ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:30.552630Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.118601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:30.552675Z","caller":"traceutil/trace.go:171","msg":"trace[473005345] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25884; }","duration":"320.193013ms","start":"2026-06-08T09:36:30.232471Z","end":"2026-06-08T09:36:30.552664Z","steps":["trace[473005345] 'agreement among raft nodes before linearized reading' (duration: 320.013049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:30.552711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:30.232448Z","time spent":"320.256335ms","remote":"127.0.0.1:38614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-06-08T09:36:30.552418Z","caller":"traceutil/trace.go:171","msg":"trace[184548087] linearizableReadLoop","detail":"{readStateIndex:26746; appliedIndex:26746; }","duration":"319.906516ms","start":"2026-06-08T09:36:30.232479Z","end":"2026-06-08T09:36:30.552385Z","steps":["trace[184548087] 'read index received' (duration: 319.896106ms)","trace[184548087] 'applied index is now lower than readState.Index' (duration: 8.73µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:30.565606Z","caller":"traceutil/trace.go:171","msg":"trace[1580120103] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"102.31129ms","start":"2026-06-08T09:36:30.463251Z","end":"2026-06-08T09:36:30.565562Z","steps":["trace[1580120103] 'process raft request' (duration: 102.045284ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:31.078486Z","caller":"traceutil/trace.go:171","msg":"trace[959637016] linearizableReadLoop","detail":"{readStateIndex:26751; appliedIndex:26750; }","duration":"153.671896ms","start":"2026-06-08T09:36:30.924787Z","end":"2026-06-08T09:36:31.078458Z","steps":["trace[959637016] 'read index received' (duration: 153.514121ms)","trace[959637016] 'applied index is now lower than readState.Index' (duration: 156.765µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:31.078556Z","caller":"traceutil/trace.go:171","msg":"trace[1919557143] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"285.020991ms","start":"2026-06-08T09:36:30.793507Z","end":"2026-06-08T09:36:31.078528Z","steps":["trace[1919557143] 'process raft request' (duration: 284.750083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:31.078664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.84552ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:31.078762Z","caller":"traceutil/trace.go:171","msg":"trace[417201042] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25889; }","duration":"153.960893ms","start":"2026-06-08T09:36:30.924779Z","end":"2026-06-08T09:36:31.078740Z","steps":["trace[417201042] 'agreement among raft nodes before linearized reading' (duration: 153.792069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:31.361978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.07323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:36:31.362257Z","caller":"traceutil/trace.go:171","msg":"trace[2135256626] linearizableReadLoop","detail":"{readStateIndex:26755; appliedIndex:26751; }","duration":"283.667847ms","start":"2026-06-08T09:36:31.078571Z","end":"2026-06-08T09:36:31.362239Z","steps":["trace[2135256626] 'read index received' (duration: 128.170766ms)","trace[2135256626] 'applied index is now lower than readState.Index' (duration: 155.495801ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:31.362434Z","caller":"traceutil/trace.go:171","msg":"trace[1878857290] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"296.946519ms","start":"2026-06-08T09:36:31.065446Z","end":"2026-06-08T09:36:31.362392Z","steps":["trace[1878857290] 'process raft request' (duration: 296.710944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:31.362531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.578519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T09:36:31.362562Z","caller":"traceutil/trace.go:171","msg":"trace[754386119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25893; }","duration":"298.60874ms","start":"2026-06-08T09:36:31.063944Z","end":"2026-06-08T09:36:31.362553Z","steps":["trace[754386119] 'agreement among raft nodes before linearized reading' (duration: 298.543779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:31.362556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.317194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-08T09:36:31.362700Z","caller":"traceutil/trace.go:171","msg":"trace[1662735609] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"297.791979ms","start":"2026-06-08T09:36:31.064900Z","end":"2026-06-08T09:36:31.362692Z","steps":["trace[1662735609] 'process raft request' (duration: 297.205715ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:31.362683Z","caller":"traceutil/trace.go:171","msg":"trace[146975614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25893; }","duration":"296.461448ms","start":"2026-06-08T09:36:31.066192Z","end":"2026-06-08T09:36:31.362654Z","steps":["trace[146975614] 'agreement among raft nodes before linearized reading' (duration: 296.188941ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:31.362718Z","caller":"traceutil/trace.go:171","msg":"trace[747531572] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"299.864819ms","start":"2026-06-08T09:36:31.062827Z","end":"2026-06-08T09:36:31.362692Z","steps":["trace[747531572] 'process raft request' (duration: 143.943109ms)","trace[747531572] 'compare' (duration: 154.935947ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:31.362742Z","caller":"traceutil/trace.go:171","msg":"trace[205870518] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"297.213175ms","start":"2026-06-08T09:36:31.065475Z","end":"2026-06-08T09:36:31.362689Z","steps":["trace[205870518] 'process raft request' (duration: 296.718984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:31.362876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.692379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T09:36:31.362959Z","caller":"traceutil/trace.go:171","msg":"trace[7224477] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25894; }","duration":"236.77989ms","start":"2026-06-08T09:36:31.126161Z","end":"2026-06-08T09:36:31.362941Z","steps":["trace[7224477] 'agreement among raft nodes before linearized reading' (duration: 236.653737ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:31.486421Z","caller":"traceutil/trace.go:171","msg":"trace[186948930] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"117.823307ms","start":"2026-06-08T09:36:31.368562Z","end":"2026-06-08T09:36:31.486386Z","steps":["trace[186948930] 'process raft request' (duration: 117.237913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:31.835067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.04375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:36:31.835209Z","caller":"traceutil/trace.go:171","msg":"trace[499034178] transaction","detail":"{read_only:false; response_revision:25898; number_of_response:1; }","duration":"235.170612ms","start":"2026-06-08T09:36:31.600012Z","end":"2026-06-08T09:36:31.835183Z","steps":["trace[499034178] 'process raft request' (duration: 100.894246ms)","trace[499034178] 'compare' (duration: 133.859606ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:32.897708Z","caller":"traceutil/trace.go:171","msg":"trace[1788628169] transaction","detail":"{read_only:false; response_revision:25900; number_of_response:1; }","duration":"327.121901ms","start":"2026-06-08T09:36:32.570559Z","end":"2026-06-08T09:36:32.897681Z","steps":["trace[1788628169] 'process raft request' (duration: 327.022809ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:32.897758Z","caller":"traceutil/trace.go:171","msg":"trace[1533591118] transaction","detail":"{read_only:false; response_revision:25899; number_of_response:1; }","duration":"338.792834ms","start":"2026-06-08T09:36:32.558917Z","end":"2026-06-08T09:36:32.897709Z","steps":["trace[1533591118] 'process raft request' (duration: 338.513677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:32.897892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:32.570550Z","time spent":"327.240804ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:32.898025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:32.558893Z","time spent":"338.969798ms","remote":"127.0.0.1:45486","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-06-08T09:36:32.898140Z","caller":"traceutil/trace.go:171","msg":"trace[789692829] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"314.239778ms","start":"2026-06-08T09:36:32.583864Z","end":"2026-06-08T09:36:32.898104Z","steps":["trace[789692829] 'process raft request' (duration: 314.040893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:32.898416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:32.583851Z","time spent":"314.380371ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:36:32.898402Z","caller":"traceutil/trace.go:171","msg":"trace[1372335883] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"325.738516ms","start":"2026-06-08T09:36:32.572372Z","end":"2026-06-08T09:36:32.898111Z","steps":["trace[1372335883] 'process raft request' (duration: 325.267625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:32.898638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:32.572366Z","time spent":"326.146417ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:43.669812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.525734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T09:36:43.669910Z","caller":"traceutil/trace.go:171","msg":"trace[256941566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25980; }","duration":"189.650967ms","start":"2026-06-08T09:36:43.480239Z","end":"2026-06-08T09:36:43.669890Z","steps":["trace[256941566] 'range keys from in-memory index tree' (duration: 189.33154ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:43.682960Z","caller":"traceutil/trace.go:171","msg":"trace[1618084950] linearizableReadLoop","detail":"{readStateIndex:26846; appliedIndex:26845; }","duration":"135.498404ms","start":"2026-06-08T09:36:43.547416Z","end":"2026-06-08T09:36:43.682914Z","steps":["trace[1618084950] 'read index received' (duration: 135.187807ms)","trace[1618084950] 'applied index is now lower than readState.Index' (duration: 309.697µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:43.683066Z","caller":"traceutil/trace.go:171","msg":"trace[2110366077] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"189.31028ms","start":"2026-06-08T09:36:43.493722Z","end":"2026-06-08T09:36:43.683033Z","steps":["trace[2110366077] 'process raft request' (duration: 188.974972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:43.683290Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.761951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-08T09:36:43.683406Z","caller":"traceutil/trace.go:171","msg":"trace[1998444836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25981; }","duration":"135.937795ms","start":"2026-06-08T09:36:43.547394Z","end":"2026-06-08T09:36:43.683332Z","steps":["trace[1998444836] 'agreement among raft nodes before linearized reading' (duration: 135.675249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:46.513085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.753944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" limit:1 ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-06-08T09:36:46.513213Z","caller":"traceutil/trace.go:171","msg":"trace[482358799] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:26022; }","duration":"310.910878ms","start":"2026-06-08T09:36:46.202266Z","end":"2026-06-08T09:36:46.513177Z","steps":["trace[482358799] 'range keys from in-memory index tree' (duration: 310.627511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:46.513281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.202246Z","time spent":"311.0196ms","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":723,"request content":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:46.513262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.827583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:46.513567Z","caller":"traceutil/trace.go:171","msg":"trace[351356144] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26022; }","duration":"275.140591ms","start":"2026-06-08T09:36:46.238396Z","end":"2026-06-08T09:36:46.513536Z","steps":["trace[351356144] 'count revisions from in-memory index tree' (duration: 274.731321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:46.513449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.616363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:46.513692Z","caller":"traceutil/trace.go:171","msg":"trace[345140105] range","detail":"{range_begin:/registry/configmaps; range_end:; response_count:0; response_revision:26022; }","duration":"162.868129ms","start":"2026-06-08T09:36:46.350795Z","end":"2026-06-08T09:36:46.513663Z","steps":["trace[345140105] 'range keys from in-memory index tree' (duration: 162.5269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:47.020882Z","caller":"etcdserver/v3_server.go:920","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266376984497509,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-08T09:36:47.094714Z","caller":"traceutil/trace.go:171","msg":"trace[220336740] transaction","detail":"{read_only:false; response_revision:26023; number_of_response:1; }","duration":"575.735269ms","start":"2026-06-08T09:36:46.518954Z","end":"2026-06-08T09:36:47.094689Z","steps":["trace[220336740] 'process raft request' (duration: 575.544075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:47.094854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.518923Z","time spent":"575.859862ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:47.527762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.224089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:36:47.528032Z","caller":"traceutil/trace.go:171","msg":"trace[1098407479] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"552.513856ms","start":"2026-06-08T09:36:46.975475Z","end":"2026-06-08T09:36:47.527989Z","steps":["trace[1098407479] 'process raft request' (duration: 371.951808ms)","trace[1098407479] 'compare' (duration: 180.084726ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:47.528258Z","caller":"traceutil/trace.go:171","msg":"trace[2133689750] linearizableReadLoop","detail":"{readStateIndex:26892; appliedIndex:26887; }","duration":"1.007973418s","start":"2026-06-08T09:36:46.520071Z","end":"2026-06-08T09:36:47.528045Z","steps":["trace[2133689750] 'read index received' (duration: 574.55745ms)","trace[2133689750] 'applied index is now lower than readState.Index' (duration: 433.414978ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:47.528303Z","caller":"traceutil/trace.go:171","msg":"trace[979717227] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"551.9063ms","start":"2026-06-08T09:36:46.976363Z","end":"2026-06-08T09:36:47.528269Z","steps":["trace[979717227] 'process raft request' (duration: 551.634784ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:47.528221Z","caller":"traceutil/trace.go:171","msg":"trace[722578583] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"552.439495ms","start":"2026-06-08T09:36:46.975636Z","end":"2026-06-08T09:36:47.528076Z","steps":["trace[722578583] 'process raft request' (duration: 552.26914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:47.528431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008367448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" limit:1 ","response":"range_response_count:1 size:1190"} {"level":"info","ts":"2026-06-08T09:36:47.528978Z","caller":"traceutil/trace.go:171","msg":"trace[1815861623] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"552.70914ms","start":"2026-06-08T09:36:46.976247Z","end":"2026-06-08T09:36:47.528956Z","steps":["trace[1815861623] 'process raft request' (duration: 551.718885ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:47.529022Z","caller":"traceutil/trace.go:171","msg":"trace[1658133617] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:26027; }","duration":"1.008953932s","start":"2026-06-08T09:36:46.520051Z","end":"2026-06-08T09:36:47.529005Z","steps":["trace[1658133617] 'agreement among raft nodes before linearized reading' (duration: 1.008270306s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:47.529056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.976238Z","time spent":"552.778931ms","remote":"127.0.0.1:45486","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-06-08T09:36:47.529087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.520037Z","time spent":"1.009033883s","remote":"127.0.0.1:45550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1213,"request content":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:47.528265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.975439Z","time spent":"552.69839ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:47.528489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.976331Z","time spent":"552.073414ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:47.529674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.975586Z","time spent":"553.321125ms","remote":"127.0.0.1:45486","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-06-08T09:36:47.901582Z","caller":"traceutil/trace.go:171","msg":"trace[574939017] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"473.854239ms","start":"2026-06-08T09:36:47.427703Z","end":"2026-06-08T09:36:47.901558Z","steps":["trace[574939017] 'process raft request' (duration: 473.696435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:47.901767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.427678Z","time spent":"473.994002ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:36:48.209927Z","caller":"traceutil/trace.go:171","msg":"trace[1897043383] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"747.658807ms","start":"2026-06-08T09:36:47.462248Z","end":"2026-06-08T09:36:48.209907Z","steps":["trace[1897043383] 'process raft request' (duration: 704.117661ms)","trace[1897043383] 'compare' (duration: 43.034134ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:36:48.210166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.462205Z","time spent":"747.828971ms","remote":"127.0.0.1:45486","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-06-08T09:36:48.210365Z","caller":"traceutil/trace.go:171","msg":"trace[1729098461] linearizableReadLoop","detail":"{readStateIndex:26897; appliedIndex:26893; }","duration":"682.022736ms","start":"2026-06-08T09:36:47.528318Z","end":"2026-06-08T09:36:48.210341Z","steps":["trace[1729098461] 'read index received' (duration: 373.299191ms)","trace[1729098461] 'applied index is now lower than readState.Index' (duration: 308.722035ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:36:48.210524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.564424ms","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":"info","ts":"2026-06-08T09:36:48.210555Z","caller":"traceutil/trace.go:171","msg":"trace[802679853] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:26034; }","duration":"702.597245ms","start":"2026-06-08T09:36:47.507949Z","end":"2026-06-08T09:36:48.210546Z","steps":["trace[802679853] 'agreement among raft nodes before linearized reading' (duration: 702.513043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.210564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024782335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-08T09:36:48.210579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.507913Z","time spent":"702.660096ms","remote":"127.0.0.1:38660","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":"info","ts":"2026-06-08T09:36:48.210598Z","caller":"traceutil/trace.go:171","msg":"trace[2039376280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26034; }","duration":"1.024830157s","start":"2026-06-08T09:36:47.185758Z","end":"2026-06-08T09:36:48.210589Z","steps":["trace[2039376280] 'agreement among raft nodes before linearized reading' (duration: 1.024721164s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.210633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.185746Z","time spent":"1.024878948s","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 "} {"level":"info","ts":"2026-06-08T09:36:48.210711Z","caller":"traceutil/trace.go:171","msg":"trace[1644410462] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"747.045001ms","start":"2026-06-08T09:36:47.463657Z","end":"2026-06-08T09:36:48.210702Z","steps":["trace[1644410462] 'process raft request' (duration: 746.16608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.210754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.463640Z","time spent":"747.089202ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:36:48.210855Z","caller":"traceutil/trace.go:171","msg":"trace[1812246342] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"314.874734ms","start":"2026-06-08T09:36:47.895932Z","end":"2026-06-08T09:36:48.210807Z","steps":["trace[1812246342] 'process raft request' (duration: 314.361492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.210954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677626714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" limit:1 ","response":"range_response_count:1 size:1822"} {"level":"warn","ts":"2026-06-08T09:36:48.210932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440036223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" limit:1 ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-08T09:36:48.210965Z","caller":"traceutil/trace.go:171","msg":"trace[1399633886] transaction","detail":"{read_only:false; response_revision:26033; number_of_response:1; }","duration":"678.549841ms","start":"2026-06-08T09:36:47.532381Z","end":"2026-06-08T09:36:48.210931Z","steps":["trace[1399633886] 'process raft request' (duration: 677.862634ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.211011Z","caller":"traceutil/trace.go:171","msg":"trace[1512410319] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"747.280457ms","start":"2026-06-08T09:36:47.463724Z","end":"2026-06-08T09:36:48.211005Z","steps":["trace[1512410319] 'process raft request' (duration: 746.363885ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.211021Z","caller":"traceutil/trace.go:171","msg":"trace[823763884] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26034; }","duration":"1.440139596s","start":"2026-06-08T09:36:46.770865Z","end":"2026-06-08T09:36:48.211005Z","steps":["trace[823763884] 'agreement among raft nodes before linearized reading' (duration: 1.439965001s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.211052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.463717Z","time spent":"747.314068ms","remote":"127.0.0.1:45486","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-06-08T09:36:48.211044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.895903Z","time spent":"315.041838ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T09:36:48.211055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045526368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" limit:1 ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-08T09:36:48.211070Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.770835Z","time spent":"1.440223248s","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" limit:1 "} {"level":"info","ts":"2026-06-08T09:36:48.211101Z","caller":"traceutil/trace.go:171","msg":"trace[546582237] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:26034; }","duration":"1.045570259s","start":"2026-06-08T09:36:47.165517Z","end":"2026-06-08T09:36:48.211087Z","steps":["trace[546582237] 'agreement among raft nodes before linearized reading' (duration: 1.045449687s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.211102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.532349Z","time spent":"678.684834ms","remote":"127.0.0.1:45564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":880,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T09:36:48.211125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.165488Z","time spent":"1.045630941s","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.3\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:48.211106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.555195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-06-08T09:36:48.211132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194887129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-08T09:36:48.211180Z","caller":"traceutil/trace.go:171","msg":"trace[272055208] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:26034; }","duration":"1.19493971s","start":"2026-06-08T09:36:47.016230Z","end":"2026-06-08T09:36:48.211170Z","steps":["trace[272055208] 'agreement among raft nodes before linearized reading' (duration: 1.194698475s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.211208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.016211Z","time spent":"1.194990722s","remote":"127.0.0.1:45386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":176,"response size":32,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-06-08T09:36:48.210905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.28779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T09:36:48.210978Z","caller":"traceutil/trace.go:171","msg":"trace[358523066] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:26034; }","duration":"1.677650445s","start":"2026-06-08T09:36:46.533320Z","end":"2026-06-08T09:36:48.210971Z","steps":["trace[358523066] 'agreement among raft nodes before linearized reading' (duration: 1.677601814s)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.211353Z","caller":"traceutil/trace.go:171","msg":"trace[1161388844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26034; }","duration":"512.7268ms","start":"2026-06-08T09:36:47.698612Z","end":"2026-06-08T09:36:48.211339Z","steps":["trace[1161388844] 'agreement among raft nodes before linearized reading' (duration: 512.261659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.211363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287224618s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:48.211400Z","caller":"traceutil/trace.go:171","msg":"trace[2039690686] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26034; }","duration":"1.287259839s","start":"2026-06-08T09:36:46.924130Z","end":"2026-06-08T09:36:48.211390Z","steps":["trace[2039690686] 'agreement among raft nodes before linearized reading' (duration: 1.287201877s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.211400Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.533299Z","time spent":"1.678081044s","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1845,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:48.211411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283543269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-08T09:36:48.211406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.698589Z","time spent":"512.802403ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 "} {"level":"info","ts":"2026-06-08T09:36:48.211406Z","caller":"traceutil/trace.go:171","msg":"trace[949008565] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"747.071372ms","start":"2026-06-08T09:36:47.464300Z","end":"2026-06-08T09:36:48.211371Z","steps":["trace[949008565] 'process raft request' (duration: 745.901264ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.211460Z","caller":"traceutil/trace.go:171","msg":"trace[56747376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26034; }","duration":"1.2835874s","start":"2026-06-08T09:36:46.927858Z","end":"2026-06-08T09:36:48.211445Z","steps":["trace[56747376] 'agreement among raft nodes before linearized reading' (duration: 1.283505088s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.211502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:46.927838Z","time spent":"1.283655162s","remote":"127.0.0.1:45220","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-06-08T09:36:48.211571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.464276Z","time spent":"747.204895ms","remote":"127.0.0.1:45486","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-06-08T09:36:48.211187Z","caller":"traceutil/trace.go:171","msg":"trace[1742656339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26034; }","duration":"500.638107ms","start":"2026-06-08T09:36:47.710524Z","end":"2026-06-08T09:36:48.211162Z","steps":["trace[1742656339] 'agreement among raft nodes before linearized reading' (duration: 500.472653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.211779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:47.710501Z","time spent":"501.264744ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:48.716703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.097783ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-06-08T09:36:48.716840Z","caller":"traceutil/trace.go:171","msg":"trace[770608019] linearizableReadLoop","detail":"{readStateIndex:26900; appliedIndex:26899; }","duration":"502.844432ms","start":"2026-06-08T09:36:48.213975Z","end":"2026-06-08T09:36:48.716820Z","steps":["trace[770608019] 'read index received' (duration: 278.532174ms)","trace[770608019] 'applied index is now lower than readState.Index' (duration: 224.310268ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:36:48.716860Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.212621Z","time spent":"504.229815ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-08T09:36:48.716967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.985895ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:48.717001Z","caller":"traceutil/trace.go:171","msg":"trace[1933963486] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26034; }","duration":"503.025656ms","start":"2026-06-08T09:36:48.213966Z","end":"2026-06-08T09:36:48.716992Z","steps":["trace[1933963486] 'agreement among raft nodes before linearized reading' (duration: 502.957375ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.721224Z","caller":"traceutil/trace.go:171","msg":"trace[666673788] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"504.145903ms","start":"2026-06-08T09:36:48.217035Z","end":"2026-06-08T09:36:48.721181Z","steps":["trace[666673788] 'process raft request' (duration: 503.907767ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.721285Z","caller":"traceutil/trace.go:171","msg":"trace[500040856] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"499.208873ms","start":"2026-06-08T09:36:48.222059Z","end":"2026-06-08T09:36:48.721268Z","steps":["trace[500040856] 'process raft request' (duration: 499.145531ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.721311Z","caller":"traceutil/trace.go:171","msg":"trace[1151859352] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"500.570396ms","start":"2026-06-08T09:36:48.220712Z","end":"2026-06-08T09:36:48.721282Z","steps":["trace[1151859352] 'process raft request' (duration: 500.441733ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.721328Z","caller":"traceutil/trace.go:171","msg":"trace[1530727109] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"503.022495ms","start":"2026-06-08T09:36:48.218267Z","end":"2026-06-08T09:36:48.721289Z","steps":["trace[1530727109] 'process raft request' (duration: 502.82023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.721393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.217010Z","time spent":"504.305417ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":729,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T09:36:48.721434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.222045Z","time spent":"499.291165ms","remote":"127.0.0.1:45486","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-06-08T09:36:48.721485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.220690Z","time spent":"500.70369ms","remote":"127.0.0.1:45486","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-06-08T09:36:48.721500Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.218234Z","time spent":"503.17929ms","remote":"127.0.0.1:45486","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-06-08T09:36:48.987927Z","caller":"traceutil/trace.go:171","msg":"trace[1106623518] transaction","detail":"{read_only:false; response_revision:26039; number_of_response:1; }","duration":"297.480753ms","start":"2026-06-08T09:36:48.690419Z","end":"2026-06-08T09:36:48.987900Z","steps":["trace[1106623518] 'process raft request' (duration: 297.224257ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.988318Z","caller":"traceutil/trace.go:171","msg":"trace[745583773] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"269.72764ms","start":"2026-06-08T09:36:48.718563Z","end":"2026-06-08T09:36:48.988290Z","steps":["trace[745583773] 'process raft request' (duration: 269.269499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.988319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.369993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-08T09:36:48.988396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.063475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" limit:1 ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-06-08T09:36:48.988422Z","caller":"traceutil/trace.go:171","msg":"trace[1245847406] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:26040; }","duration":"710.487716ms","start":"2026-06-08T09:36:48.277914Z","end":"2026-06-08T09:36:48.988402Z","steps":["trace[1245847406] 'agreement among raft nodes before linearized reading' (duration: 710.331662ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.988447Z","caller":"traceutil/trace.go:171","msg":"trace[1347752972] 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:26040; }","duration":"486.141427ms","start":"2026-06-08T09:36:48.502292Z","end":"2026-06-08T09:36:48.988434Z","steps":["trace[1347752972] 'agreement among raft nodes before linearized reading' (duration: 485.998863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.988435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.023885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" limit:1 ","response":"range_response_count:1 size:3685"} {"level":"warn","ts":"2026-06-08T09:36:48.988479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.502262Z","time spent":"486.209779ms","remote":"127.0.0.1:45364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":149,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:48.988484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.685232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:48.988492Z","caller":"traceutil/trace.go:171","msg":"trace[1336877918] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:26040; }","duration":"750.075256ms","start":"2026-06-08T09:36:48.238403Z","end":"2026-06-08T09:36:48.988478Z","steps":["trace[1336877918] 'agreement among raft nodes before linearized reading' (duration: 749.990694ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:48.988530Z","caller":"traceutil/trace.go:171","msg":"trace[1496076947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26040; }","duration":"767.737464ms","start":"2026-06-08T09:36:48.220780Z","end":"2026-06-08T09:36:48.988517Z","steps":["trace[1496076947] 'agreement among raft nodes before linearized reading' (duration: 767.655912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.988573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.220772Z","time spent":"767.794325ms","remote":"127.0.0.1:45220","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-06-08T09:36:48.988489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.277892Z","time spent":"710.582999ms","remote":"127.0.0.1:39390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-06-08T09:36:48.988535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.238380Z","time spent":"750.147147ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":3708,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:48.988703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.259334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:48.988759Z","caller":"traceutil/trace.go:171","msg":"trace[236578042] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:0; response_revision:26040; }","duration":"770.334097ms","start":"2026-06-08T09:36:48.218408Z","end":"2026-06-08T09:36:48.988742Z","steps":["trace[236578042] 'agreement among raft nodes before linearized reading' (duration: 770.231765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.988321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.014818ms","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":"warn","ts":"2026-06-08T09:36:48.988773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.86396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T09:36:48.988909Z","caller":"traceutil/trace.go:171","msg":"trace[1835040025] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26040; }","duration":"435.618563ms","start":"2026-06-08T09:36:48.553270Z","end":"2026-06-08T09:36:48.988888Z","steps":["trace[1835040025] 'agreement among raft nodes before linearized reading' (duration: 434.943966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.988812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.363031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:36:48.989071Z","caller":"traceutil/trace.go:171","msg":"trace[1386180800] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:26040; }","duration":"580.857362ms","start":"2026-06-08T09:36:48.408197Z","end":"2026-06-08T09:36:48.989054Z","steps":["trace[1386180800] 'agreement among raft nodes before linearized reading' (duration: 579.990212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.989077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.553252Z","time spent":"435.803856ms","remote":"127.0.0.1:57318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true "} {"level":"info","ts":"2026-06-08T09:36:48.989044Z","caller":"traceutil/trace.go:171","msg":"trace[1283168354] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:26040; }","duration":"637.161157ms","start":"2026-06-08T09:36:48.351801Z","end":"2026-06-08T09:36:48.988962Z","steps":["trace[1283168354] 'agreement among raft nodes before linearized reading' (duration: 636.572823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:48.989174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.218400Z","time spent":"770.764337ms","remote":"127.0.0.1:45664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" limit:1 "} {"level":"warn","ts":"2026-06-08T09:36:48.989207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.351777Z","time spent":"637.412804ms","remote":"127.0.0.1:46348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":31,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-06-08T09:36:48.989118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:36:48.408172Z","time spent":"580.933105ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"info","ts":"2026-06-08T09:36:48.989885Z","caller":"traceutil/trace.go:171","msg":"trace[1877379418] linearizableReadLoop","detail":"{readStateIndex:26905; appliedIndex:26904; }","duration":"271.255867ms","start":"2026-06-08T09:36:48.716888Z","end":"2026-06-08T09:36:48.988144Z","steps":["trace[1877379418] 'read index received' (duration: 5.151925ms)","trace[1877379418] 'applied index is now lower than readState.Index' (duration: 266.101352ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:36:50.341494Z","caller":"traceutil/trace.go:171","msg":"trace[1582997076] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"125.495853ms","start":"2026-06-08T09:36:50.215853Z","end":"2026-06-08T09:36:50.341349Z","steps":["trace[1582997076] 'process raft request' (duration: 125.216316ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:50.404688Z","caller":"traceutil/trace.go:171","msg":"trace[1848290369] transaction","detail":"{read_only:false; response_revision:26082; number_of_response:1; }","duration":"182.578236ms","start":"2026-06-08T09:36:50.222082Z","end":"2026-06-08T09:36:50.404660Z","steps":["trace[1848290369] 'process raft request' (duration: 182.535445ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:50.404779Z","caller":"traceutil/trace.go:171","msg":"trace[1698942409] transaction","detail":"{read_only:false; response_revision:26079; number_of_response:1; }","duration":"185.822295ms","start":"2026-06-08T09:36:50.218916Z","end":"2026-06-08T09:36:50.404739Z","steps":["trace[1698942409] 'process raft request' (duration: 185.456896ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:50.405170Z","caller":"traceutil/trace.go:171","msg":"trace[517267274] transaction","detail":"{read_only:false; response_revision:26081; number_of_response:1; }","duration":"183.583171ms","start":"2026-06-08T09:36:50.221539Z","end":"2026-06-08T09:36:50.405122Z","steps":["trace[517267274] 'process raft request' (duration: 183.044087ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:50.405245Z","caller":"traceutil/trace.go:171","msg":"trace[1911901414] transaction","detail":"{read_only:false; response_revision:26080; number_of_response:1; }","duration":"184.381851ms","start":"2026-06-08T09:36:50.220849Z","end":"2026-06-08T09:36:50.405231Z","steps":["trace[1911901414] 'process raft request' (duration: 183.687123ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:56.563710Z","caller":"traceutil/trace.go:171","msg":"trace[466440872] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"138.801605ms","start":"2026-06-08T09:36:56.424867Z","end":"2026-06-08T09:36:56.563669Z","steps":["trace[466440872] 'process raft request' (duration: 136.963951ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:56.596055Z","caller":"traceutil/trace.go:171","msg":"trace[1278835276] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"168.839283ms","start":"2026-06-08T09:36:56.427183Z","end":"2026-06-08T09:36:56.596022Z","steps":["trace[1278835276] 'process raft request' (duration: 168.610828ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:56.596420Z","caller":"traceutil/trace.go:171","msg":"trace[158159695] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"166.975228ms","start":"2026-06-08T09:36:56.429421Z","end":"2026-06-08T09:36:56.596396Z","steps":["trace[158159695] 'process raft request' (duration: 166.558297ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:36:56.596652Z","caller":"traceutil/trace.go:171","msg":"trace[260786365] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"165.747648ms","start":"2026-06-08T09:36:56.430896Z","end":"2026-06-08T09:36:56.596644Z","steps":["trace[260786365] 'process raft request' (duration: 165.456341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:56.907749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.431049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T09:36:56.907873Z","caller":"traceutil/trace.go:171","msg":"trace[1649394330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26149; }","duration":"148.580882ms","start":"2026-06-08T09:36:56.759262Z","end":"2026-06-08T09:36:56.907843Z","steps":["trace[1649394330] 'range keys from in-memory index tree' (duration: 148.267615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:56.908298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.108199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-08T09:36:56.908420Z","caller":"traceutil/trace.go:171","msg":"trace[257099902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26149; }","duration":"145.246482ms","start":"2026-06-08T09:36:56.763143Z","end":"2026-06-08T09:36:56.908389Z","steps":["trace[257099902] 'range keys from in-memory index tree' (duration: 144.914284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:56.908539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.267754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T09:36:56.908596Z","caller":"traceutil/trace.go:171","msg":"trace[769515607] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:26149; }","duration":"209.330446ms","start":"2026-06-08T09:36:56.699253Z","end":"2026-06-08T09:36:56.908584Z","steps":["trace[769515607] 'count revisions from in-memory index tree' (duration: 209.146791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:36:56.909094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.081078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-08T09:36:56.909217Z","caller":"traceutil/trace.go:171","msg":"trace[59549034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26149; }","duration":"145.230451ms","start":"2026-06-08T09:36:56.763962Z","end":"2026-06-08T09:36:56.909193Z","steps":["trace[59549034] 'range keys from in-memory index tree' (duration: 144.965315ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:09.234050Z","caller":"traceutil/trace.go:171","msg":"trace[803205581] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"259.335458ms","start":"2026-06-08T09:37:08.974683Z","end":"2026-06-08T09:37:09.234018Z","steps":["trace[803205581] 'process raft request' (duration: 259.128893ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:09.414588Z","caller":"traceutil/trace.go:171","msg":"trace[34603581] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"379.716635ms","start":"2026-06-08T09:37:09.034836Z","end":"2026-06-08T09:37:09.414552Z","steps":["trace[34603581] 'process raft request' (duration: 379.649414ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:09.414622Z","caller":"traceutil/trace.go:171","msg":"trace[2047557939] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"436.79229ms","start":"2026-06-08T09:37:08.977793Z","end":"2026-06-08T09:37:09.414585Z","steps":["trace[2047557939] 'process raft request' (duration: 436.610066ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:09.414582Z","caller":"traceutil/trace.go:171","msg":"trace[2132513551] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"437.6748ms","start":"2026-06-08T09:37:08.976851Z","end":"2026-06-08T09:37:09.414525Z","steps":["trace[2132513551] 'process raft request' (duration: 377.995154ms)","trace[2132513551] 'compare' (duration: 59.333758ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:37:09.414803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:37:08.977786Z","time spent":"436.915973ms","remote":"127.0.0.1:45486","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-06-08T09:37:09.414816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:37:09.034808Z","time spent":"379.8872ms","remote":"127.0.0.1:45486","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-06-08T09:37:09.414955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:37:08.976841Z","time spent":"438.006979ms","remote":"127.0.0.1:45486","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-06-08T09:37:09.786421Z","caller":"traceutil/trace.go:171","msg":"trace[1320937517] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"160.649234ms","start":"2026-06-08T09:37:09.625729Z","end":"2026-06-08T09:37:09.786378Z","steps":["trace[1320937517] 'process raft request' (duration: 160.05163ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:09.786469Z","caller":"traceutil/trace.go:171","msg":"trace[1972436208] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"160.568063ms","start":"2026-06-08T09:37:09.625861Z","end":"2026-06-08T09:37:09.786429Z","steps":["trace[1972436208] 'process raft request' (duration: 160.398689ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:09.786667Z","caller":"traceutil/trace.go:171","msg":"trace[1032184685] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"160.728987ms","start":"2026-06-08T09:37:09.625918Z","end":"2026-06-08T09:37:09.786647Z","steps":["trace[1032184685] 'process raft request' (duration: 160.407799ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.031735Z","caller":"traceutil/trace.go:171","msg":"trace[471636369] linearizableReadLoop","detail":"{readStateIndex:27125; appliedIndex:27124; }","duration":"220.573288ms","start":"2026-06-08T09:37:09.811134Z","end":"2026-06-08T09:37:10.031707Z","steps":["trace[471636369] 'read index received' (duration: 220.437484ms)","trace[471636369] 'applied index is now lower than readState.Index' (duration: 134.414µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:10.031784Z","caller":"traceutil/trace.go:171","msg":"trace[1250961357] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"230.641973ms","start":"2026-06-08T09:37:09.801102Z","end":"2026-06-08T09:37:10.031744Z","steps":["trace[1250961357] 'process raft request' (duration: 230.402727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.031960Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.805464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" limit:1 ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-08T09:37:10.032028Z","caller":"traceutil/trace.go:171","msg":"trace[1045838741] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26255; }","duration":"220.895776ms","start":"2026-06-08T09:37:09.811118Z","end":"2026-06-08T09:37:10.032014Z","steps":["trace[1045838741] 'agreement among raft nodes before linearized reading' (duration: 220.713392ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.065564Z","caller":"traceutil/trace.go:171","msg":"trace[2066535911] transaction","detail":"{read_only:false; response_revision:26256; number_of_response:1; }","duration":"241.977638ms","start":"2026-06-08T09:37:09.823555Z","end":"2026-06-08T09:37:10.065532Z","steps":["trace[2066535911] 'process raft request' (duration: 241.728702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.065837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.651914ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:10.065922Z","caller":"traceutil/trace.go:171","msg":"trace[1846020529] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26257; }","duration":"141.743287ms","start":"2026-06-08T09:37:09.924158Z","end":"2026-06-08T09:37:10.065901Z","steps":["trace[1846020529] 'agreement among raft nodes before linearized reading' (duration: 141.621444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.065901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.766509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-08T09:37:10.065957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.186176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" limit:1 ","response":"range_response_count:1 size:13060"} {"level":"info","ts":"2026-06-08T09:37:10.066084Z","caller":"traceutil/trace.go:171","msg":"trace[2032806470] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26257; }","duration":"196.325509ms","start":"2026-06-08T09:37:09.869738Z","end":"2026-06-08T09:37:10.066063Z","steps":["trace[2032806470] 'agreement among raft nodes before linearized reading' (duration: 196.068793ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.066032Z","caller":"traceutil/trace.go:171","msg":"trace[416180805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26257; }","duration":"139.904172ms","start":"2026-06-08T09:37:09.926099Z","end":"2026-06-08T09:37:10.066003Z","steps":["trace[416180805] 'agreement among raft nodes before linearized reading' (duration: 139.686707ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.326569Z","caller":"traceutil/trace.go:171","msg":"trace[1881023330] linearizableReadLoop","detail":"{readStateIndex:27131; appliedIndex:27127; }","duration":"260.801203ms","start":"2026-06-08T09:37:10.065743Z","end":"2026-06-08T09:37:10.326545Z","steps":["trace[1881023330] 'read index received' (duration: 240.732346ms)","trace[1881023330] 'applied index is now lower than readState.Index' (duration: 20.067987ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:10.326813Z","caller":"traceutil/trace.go:171","msg":"trace[525796287] transaction","detail":"{read_only:false; response_revision:26261; number_of_response:1; }","duration":"277.958219ms","start":"2026-06-08T09:37:10.048845Z","end":"2026-06-08T09:37:10.326803Z","steps":["trace[525796287] 'process raft request' (duration: 277.642791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.327018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.386037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d94649b9-9f1f-41b7-b12e-3967a76a6142\" limit:1 ","response":"range_response_count:1 size:1332"} {"level":"info","ts":"2026-06-08T09:37:10.327077Z","caller":"traceutil/trace.go:171","msg":"trace[757590815] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d94649b9-9f1f-41b7-b12e-3967a76a6142; range_end:; response_count:1; response_revision:26261; }","duration":"287.500551ms","start":"2026-06-08T09:37:10.039567Z","end":"2026-06-08T09:37:10.327068Z","steps":["trace[757590815] 'agreement among raft nodes before linearized reading' (duration: 287.354628ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.327115Z","caller":"traceutil/trace.go:171","msg":"trace[1202072586] transaction","detail":"{read_only:false; response_revision:26258; number_of_response:1; }","duration":"285.00348ms","start":"2026-06-08T09:37:10.042079Z","end":"2026-06-08T09:37:10.327083Z","steps":["trace[1202072586] 'process raft request' (duration: 264.36903ms)","trace[1202072586] 'compare' (duration: 19.358669ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:37:10.327223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.665316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:10.327246Z","caller":"traceutil/trace.go:171","msg":"trace[1189316859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26261; }","duration":"259.687116ms","start":"2026-06-08T09:37:10.067552Z","end":"2026-06-08T09:37:10.327239Z","steps":["trace[1189316859] 'agreement among raft nodes before linearized reading' (duration: 259.646525ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.327261Z","caller":"traceutil/trace.go:171","msg":"trace[291772452] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"283.197315ms","start":"2026-06-08T09:37:10.044008Z","end":"2026-06-08T09:37:10.327205Z","steps":["trace[291772452] 'process raft request' (duration: 282.445047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.327373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.840847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" limit:1 ","response":"range_response_count:1 size:13060"} {"level":"warn","ts":"2026-06-08T09:37:10.327372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.971846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:10.327286Z","caller":"traceutil/trace.go:171","msg":"trace[1623342829] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"284.22448ms","start":"2026-06-08T09:37:10.043011Z","end":"2026-06-08T09:37:10.327235Z","steps":["trace[1623342829] 'process raft request' (duration: 283.346879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.327437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.883322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:10.327505Z","caller":"traceutil/trace.go:171","msg":"trace[887717912] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:26261; }","duration":"224.960214ms","start":"2026-06-08T09:37:10.102527Z","end":"2026-06-08T09:37:10.327487Z","steps":["trace[887717912] 'agreement among raft nodes before linearized reading' (duration: 224.841731ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.327395Z","caller":"traceutil/trace.go:171","msg":"trace[535441613] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26261; }","duration":"256.862598ms","start":"2026-06-08T09:37:10.070527Z","end":"2026-06-08T09:37:10.327389Z","steps":["trace[535441613] 'agreement among raft nodes before linearized reading' (duration: 256.810307ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.327444Z","caller":"traceutil/trace.go:171","msg":"trace[1660943043] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters; range_end:; response_count:0; response_revision:26261; }","duration":"242.045098ms","start":"2026-06-08T09:37:10.085377Z","end":"2026-06-08T09:37:10.327422Z","steps":["trace[1660943043] 'agreement among raft nodes before linearized reading' (duration: 241.921405ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.504747Z","caller":"traceutil/trace.go:171","msg":"trace[1066045156] linearizableReadLoop","detail":"{readStateIndex:27132; appliedIndex:27131; }","duration":"159.365393ms","start":"2026-06-08T09:37:10.345352Z","end":"2026-06-08T09:37:10.504718Z","steps":["trace[1066045156] 'read index received' (duration: 158.41152ms)","trace[1066045156] 'applied index is now lower than readState.Index' (duration: 953.093µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:10.504856Z","caller":"traceutil/trace.go:171","msg":"trace[352025849] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"160.688466ms","start":"2026-06-08T09:37:10.344159Z","end":"2026-06-08T09:37:10.504847Z","steps":["trace[352025849] 'process raft request' (duration: 159.59763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.505103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.721603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:10.505221Z","caller":"traceutil/trace.go:171","msg":"trace[1843732138] range","detail":"{range_begin:/registry/endpointslices; range_end:; response_count:0; response_revision:26262; }","duration":"159.849626ms","start":"2026-06-08T09:37:10.345349Z","end":"2026-06-08T09:37:10.505199Z","steps":["trace[1843732138] 'agreement among raft nodes before linearized reading' (duration: 159.657301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.540048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.277978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:10.540137Z","caller":"traceutil/trace.go:171","msg":"trace[1701226784] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26263; }","duration":"151.387151ms","start":"2026-06-08T09:37:10.388731Z","end":"2026-06-08T09:37:10.540119Z","steps":["trace[1701226784] 'agreement among raft nodes before linearized reading' (duration: 151.217557ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.763213Z","caller":"traceutil/trace.go:171","msg":"trace[2131790617] linearizableReadLoop","detail":"{readStateIndex:27135; appliedIndex:27134; }","duration":"197.699113ms","start":"2026-06-08T09:37:10.565479Z","end":"2026-06-08T09:37:10.763178Z","steps":["trace[2131790617] 'read index received' (duration: 141.865609ms)","trace[2131790617] 'applied index is now lower than readState.Index' (duration: 55.832714ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:10.763546Z","caller":"traceutil/trace.go:171","msg":"trace[460549712] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"198.283598ms","start":"2026-06-08T09:37:10.565244Z","end":"2026-06-08T09:37:10.763527Z","steps":["trace[460549712] 'process raft request' (duration: 141.996763ms)","trace[460549712] 'compare' (duration: 55.427524ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:37:10.763732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.204265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" limit:1 ","response":"range_response_count:1 size:9650"} {"level":"info","ts":"2026-06-08T09:37:10.763844Z","caller":"traceutil/trace.go:171","msg":"trace[1452557464] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26265; }","duration":"198.343259ms","start":"2026-06-08T09:37:10.565477Z","end":"2026-06-08T09:37:10.763820Z","steps":["trace[1452557464] 'agreement among raft nodes before linearized reading' (duration: 198.073872ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.767820Z","caller":"traceutil/trace.go:171","msg":"trace[709329509] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"154.584398ms","start":"2026-06-08T09:37:10.613211Z","end":"2026-06-08T09:37:10.767796Z","steps":["trace[709329509] 'process raft request' (duration: 154.320341ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.767890Z","caller":"traceutil/trace.go:171","msg":"trace[1727039063] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"118.459961ms","start":"2026-06-08T09:37:10.649363Z","end":"2026-06-08T09:37:10.767823Z","steps":["trace[1727039063] 'process raft request' (duration: 118.333368ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.767914Z","caller":"traceutil/trace.go:171","msg":"trace[908613727] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"116.367391ms","start":"2026-06-08T09:37:10.651510Z","end":"2026-06-08T09:37:10.767877Z","steps":["trace[908613727] 'process raft request' (duration: 116.263358ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:10.767960Z","caller":"traceutil/trace.go:171","msg":"trace[617490118] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"116.649788ms","start":"2026-06-08T09:37:10.651274Z","end":"2026-06-08T09:37:10.767924Z","steps":["trace[617490118] 'process raft request' (duration: 116.462123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:10.768244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.06954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T09:37:10.768292Z","caller":"traceutil/trace.go:171","msg":"trace[171380503] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:26269; }","duration":"188.133562ms","start":"2026-06-08T09:37:10.580144Z","end":"2026-06-08T09:37:10.768278Z","steps":["trace[171380503] 'agreement among raft nodes before linearized reading' (duration: 187.996988ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:11.997192Z","caller":"traceutil/trace.go:171","msg":"trace[1048687691] linearizableReadLoop","detail":"{readStateIndex:27148; appliedIndex:27145; }","duration":"173.021586ms","start":"2026-06-08T09:37:11.824146Z","end":"2026-06-08T09:37:11.997168Z","steps":["trace[1048687691] 'read index received' (duration: 31.424263ms)","trace[1048687691] 'applied index is now lower than readState.Index' (duration: 141.596133ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:11.997310Z","caller":"traceutil/trace.go:171","msg":"trace[1576848181] transaction","detail":"{read_only:false; response_revision:26277; number_of_response:1; }","duration":"203.691938ms","start":"2026-06-08T09:37:11.793607Z","end":"2026-06-08T09:37:11.997298Z","steps":["trace[1576848181] 'process raft request' (duration: 172.958232ms)","trace[1576848181] 'compare' (duration: 30.387158ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:11.997412Z","caller":"traceutil/trace.go:171","msg":"trace[1626822634] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"203.251547ms","start":"2026-06-08T09:37:11.794067Z","end":"2026-06-08T09:37:11.997318Z","steps":["trace[1626822634] 'process raft request' (duration: 203.052903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:11.997549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.385054ms","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-06-08T09:37:11.997666Z","caller":"traceutil/trace.go:171","msg":"trace[100817508] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26278; }","duration":"173.513107ms","start":"2026-06-08T09:37:11.824126Z","end":"2026-06-08T09:37:11.997639Z","steps":["trace[100817508] 'agreement among raft nodes before linearized reading' (duration: 173.324143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:12.064015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.729033ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:12.064138Z","caller":"traceutil/trace.go:171","msg":"trace[1718849991] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26279; }","duration":"138.889157ms","start":"2026-06-08T09:37:11.925222Z","end":"2026-06-08T09:37:12.064111Z","steps":["trace[1718849991] 'agreement among raft nodes before linearized reading' (duration: 138.670671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:12.064359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.717302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:12.064472Z","caller":"traceutil/trace.go:171","msg":"trace[1922227445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26279; }","duration":"140.832695ms","start":"2026-06-08T09:37:11.923614Z","end":"2026-06-08T09:37:12.064447Z","steps":["trace[1922227445] 'agreement among raft nodes before linearized reading' (duration: 140.656751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:12.064647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.520647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" limit:1 ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-06-08T09:37:12.064713Z","caller":"traceutil/trace.go:171","msg":"trace[2135828420] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26279; }","duration":"213.619699ms","start":"2026-06-08T09:37:11.851059Z","end":"2026-06-08T09:37:12.064679Z","steps":["trace[2135828420] 'agreement among raft nodes before linearized reading' (duration: 213.418094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:12.065535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.087796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-08T09:37:12.065643Z","caller":"traceutil/trace.go:171","msg":"trace[272200382] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26279; }","duration":"222.216419ms","start":"2026-06-08T09:37:11.843404Z","end":"2026-06-08T09:37:12.065621Z","steps":["trace[272200382] 'agreement among raft nodes before linearized reading' (duration: 220.420665ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:12.188708Z","caller":"traceutil/trace.go:171","msg":"trace[332816680] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"114.919856ms","start":"2026-06-08T09:37:12.073758Z","end":"2026-06-08T09:37:12.188678Z","steps":["trace[332816680] 'process raft request' (duration: 114.677039ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:12.191539Z","caller":"traceutil/trace.go:171","msg":"trace[1559943302] transaction","detail":"{read_only:false; response_revision:26281; number_of_response:1; }","duration":"114.870655ms","start":"2026-06-08T09:37:12.076641Z","end":"2026-06-08T09:37:12.191512Z","steps":["trace[1559943302] 'process raft request' (duration: 114.732632ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:16.350573Z","caller":"traceutil/trace.go:171","msg":"trace[303897676] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"102.592776ms","start":"2026-06-08T09:37:16.247943Z","end":"2026-06-08T09:37:16.350536Z","steps":["trace[303897676] 'process raft request' (duration: 102.405432ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:18.176802Z","caller":"traceutil/trace.go:171","msg":"trace[2112934893] transaction","detail":"{read_only:false; response_revision:26325; number_of_response:1; }","duration":"154.67971ms","start":"2026-06-08T09:37:18.022094Z","end":"2026-06-08T09:37:18.176774Z","steps":["trace[2112934893] 'process raft request' (duration: 154.494886ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:18.179639Z","caller":"traceutil/trace.go:171","msg":"trace[1381224600] linearizableReadLoop","detail":"{readStateIndex:27198; appliedIndex:27196; }","duration":"119.22454ms","start":"2026-06-08T09:37:18.060389Z","end":"2026-06-08T09:37:18.179613Z","steps":["trace[1381224600] 'read index received' (duration: 116.204587ms)","trace[1381224600] 'applied index is now lower than readState.Index' (duration: 3.019273ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:37:18.179801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.407105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:18.179719Z","caller":"traceutil/trace.go:171","msg":"trace[833158555] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"155.902989ms","start":"2026-06-08T09:37:18.023798Z","end":"2026-06-08T09:37:18.179701Z","steps":["trace[833158555] 'process raft request' (duration: 155.696084ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:18.179839Z","caller":"traceutil/trace.go:171","msg":"trace[1786721667] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26326; }","duration":"119.468696ms","start":"2026-06-08T09:37:18.060358Z","end":"2026-06-08T09:37:18.179827Z","steps":["trace[1786721667] 'agreement among raft nodes before linearized reading' (duration: 119.352163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:29.142525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.030496ms","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-06-08T09:37:29.142630Z","caller":"traceutil/trace.go:171","msg":"trace[2033228210] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26403; }","duration":"218.153089ms","start":"2026-06-08T09:37:28.924454Z","end":"2026-06-08T09:37:29.142607Z","steps":["trace[2033228210] 'range keys from in-memory index tree' (duration: 217.970145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:29.142946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.813725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:37:29.143103Z","caller":"traceutil/trace.go:171","msg":"trace[648463978] linearizableReadLoop","detail":"{readStateIndex:27281; appliedIndex:27278; }","duration":"216.90782ms","start":"2026-06-08T09:37:28.926187Z","end":"2026-06-08T09:37:29.143095Z","steps":["trace[648463978] 'read index received' (duration: 108.843699ms)","trace[648463978] 'applied index is now lower than readState.Index' (duration: 108.063631ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:29.143185Z","caller":"traceutil/trace.go:171","msg":"trace[2138412355] transaction","detail":"{read_only:false; response_revision:26405; number_of_response:1; }","duration":"295.807983ms","start":"2026-06-08T09:37:28.847372Z","end":"2026-06-08T09:37:29.143180Z","steps":["trace[2138412355] 'process raft request' (duration: 295.642139ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:29.143272Z","caller":"traceutil/trace.go:171","msg":"trace[1193796062] transaction","detail":"{read_only:false; response_revision:26404; number_of_response:1; }","duration":"295.917465ms","start":"2026-06-08T09:37:28.847321Z","end":"2026-06-08T09:37:29.143238Z","steps":["trace[1193796062] 'process raft request' (duration: 187.743222ms)","trace[1193796062] 'compare' (duration: 107.689642ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:29.143370Z","caller":"traceutil/trace.go:171","msg":"trace[1689278143] transaction","detail":"{read_only:false; response_revision:26406; number_of_response:1; }","duration":"295.921885ms","start":"2026-06-08T09:37:28.847439Z","end":"2026-06-08T09:37:29.143361Z","steps":["trace[1689278143] 'process raft request' (duration: 295.619608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:29.143519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.33378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:29.143535Z","caller":"traceutil/trace.go:171","msg":"trace[1742999739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26406; }","duration":"217.35098ms","start":"2026-06-08T09:37:28.926180Z","end":"2026-06-08T09:37:29.143531Z","steps":["trace[1742999739] 'agreement among raft nodes before linearized reading' (duration: 217.308599ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:29.595293Z","caller":"traceutil/trace.go:171","msg":"trace[1145549116] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"124.821985ms","start":"2026-06-08T09:37:29.470450Z","end":"2026-06-08T09:37:29.595272Z","steps":["trace[1145549116] 'process raft request' (duration: 124.581779ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:29.723966Z","caller":"traceutil/trace.go:171","msg":"trace[763710631] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"121.926036ms","start":"2026-06-08T09:37:29.602018Z","end":"2026-06-08T09:37:29.723944Z","steps":["trace[763710631] 'process raft request' (duration: 89.879869ms)","trace[763710631] 'compare' (duration: 31.214637ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:29.724454Z","caller":"traceutil/trace.go:171","msg":"trace[1736192406] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"122.400057ms","start":"2026-06-08T09:37:29.602043Z","end":"2026-06-08T09:37:29.724443Z","steps":["trace[1736192406] 'process raft request' (duration: 121.870764ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:29.724546Z","caller":"traceutil/trace.go:171","msg":"trace[375658940] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"122.445918ms","start":"2026-06-08T09:37:29.602094Z","end":"2026-06-08T09:37:29.724540Z","steps":["trace[375658940] 'process raft request' (duration: 122.191252ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:29.887528Z","caller":"traceutil/trace.go:171","msg":"trace[561770580] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"112.299413ms","start":"2026-06-08T09:37:29.775199Z","end":"2026-06-08T09:37:29.887498Z","steps":["trace[561770580] 'process raft request' (duration: 109.229538ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:33.859856Z","caller":"traceutil/trace.go:171","msg":"trace[590710204] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"115.834528ms","start":"2026-06-08T09:37:33.743992Z","end":"2026-06-08T09:37:33.859826Z","steps":["trace[590710204] 'process raft request' (duration: 115.647454ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:33.874845Z","caller":"traceutil/trace.go:171","msg":"trace[1110268740] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"128.539236ms","start":"2026-06-08T09:37:33.746283Z","end":"2026-06-08T09:37:33.874822Z","steps":["trace[1110268740] 'process raft request' (duration: 128.432844ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:33.874893Z","caller":"traceutil/trace.go:171","msg":"trace[1856420536] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"129.86845ms","start":"2026-06-08T09:37:33.745011Z","end":"2026-06-08T09:37:33.874880Z","steps":["trace[1856420536] 'process raft request' (duration: 129.601343ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:39.753062Z","caller":"traceutil/trace.go:171","msg":"trace[2038552115] transaction","detail":"{read_only:false; response_revision:26481; number_of_response:1; }","duration":"144.057092ms","start":"2026-06-08T09:37:39.608918Z","end":"2026-06-08T09:37:39.752975Z","steps":["trace[2038552115] 'process raft request' (duration: 143.823817ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:40.218864Z","caller":"traceutil/trace.go:171","msg":"trace[1889369495] transaction","detail":"{read_only:false; response_revision:26484; number_of_response:1; }","duration":"254.653373ms","start":"2026-06-08T09:37:39.964173Z","end":"2026-06-08T09:37:40.218826Z","steps":["trace[1889369495] 'process raft request' (duration: 192.623229ms)","trace[1889369495] 'compare' (duration: 61.740607ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:37:40.219646Z","caller":"traceutil/trace.go:171","msg":"trace[1782113319] transaction","detail":"{read_only:false; response_revision:26486; number_of_response:1; }","duration":"221.244914ms","start":"2026-06-08T09:37:39.998387Z","end":"2026-06-08T09:37:40.219632Z","steps":["trace[1782113319] 'process raft request' (duration: 221.160412ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:40.219666Z","caller":"traceutil/trace.go:171","msg":"trace[795128092] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"239.419085ms","start":"2026-06-08T09:37:39.980221Z","end":"2026-06-08T09:37:40.219640Z","steps":["trace[795128092] 'process raft request' (duration: 238.760939ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:40.568033Z","caller":"traceutil/trace.go:171","msg":"trace[1691497435] transaction","detail":"{read_only:false; response_revision:26487; number_of_response:1; }","duration":"320.236734ms","start":"2026-06-08T09:37:40.247763Z","end":"2026-06-08T09:37:40.568000Z","steps":["trace[1691497435] 'process raft request' (duration: 320.029259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:40.568244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:37:40.247733Z","time spent":"320.393818ms","remote":"127.0.0.1:45486","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-06-08T09:37:40.706444Z","caller":"traceutil/trace.go:171","msg":"trace[719922845] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"262.3739ms","start":"2026-06-08T09:37:40.444038Z","end":"2026-06-08T09:37:40.706412Z","steps":["trace[719922845] 'process raft request' (duration: 262.247347ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:40.706543Z","caller":"traceutil/trace.go:171","msg":"trace[612337556] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"445.853279ms","start":"2026-06-08T09:37:40.260644Z","end":"2026-06-08T09:37:40.706498Z","steps":["trace[612337556] 'process raft request' (duration: 396.629426ms)","trace[612337556] 'compare' (duration: 48.519216ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:37:40.706731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T09:37:40.260590Z","time spent":"446.036555ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T09:37:40.730806Z","caller":"traceutil/trace.go:171","msg":"trace[1430770772] linearizableReadLoop","detail":"{readStateIndex:27367; appliedIndex:27364; }","duration":"138.792804ms","start":"2026-06-08T09:37:40.591993Z","end":"2026-06-08T09:37:40.730786Z","steps":["trace[1430770772] 'read index received' (duration: 65.307923ms)","trace[1430770772] 'applied index is now lower than readState.Index' (duration: 73.484011ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:37:40.730943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.936078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:37:40.730976Z","caller":"traceutil/trace.go:171","msg":"trace[1062644567] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters; range_end:; response_count:0; response_revision:26490; }","duration":"138.98695ms","start":"2026-06-08T09:37:40.591976Z","end":"2026-06-08T09:37:40.730963Z","steps":["trace[1062644567] 'agreement among raft nodes before linearized reading' (duration: 138.883467ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:37:40.731129Z","caller":"traceutil/trace.go:171","msg":"trace[1796824626] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"149.33411ms","start":"2026-06-08T09:37:40.581739Z","end":"2026-06-08T09:37:40.731073Z","steps":["trace[1796824626] 'process raft request' (duration: 148.952701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:37:41.468940Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.255393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-08T09:37:41.469021Z","caller":"traceutil/trace.go:171","msg":"trace[745987207] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:26495; }","duration":"136.354596ms","start":"2026-06-08T09:37:41.332648Z","end":"2026-06-08T09:37:41.469002Z","steps":["trace[745987207] 'count revisions from in-memory index tree' (duration: 136.158732ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:38:06.266892Z","caller":"traceutil/trace.go:171","msg":"trace[504422133] transaction","detail":"{read_only:false; response_revision:26825; number_of_response:1; }","duration":"166.898296ms","start":"2026-06-08T09:38:06.099969Z","end":"2026-06-08T09:38:06.266867Z","steps":["trace[504422133] 'process raft request' (duration: 166.529798ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:38:06.269721Z","caller":"traceutil/trace.go:171","msg":"trace[1058615946] transaction","detail":"{read_only:false; response_revision:26826; number_of_response:1; }","duration":"166.346193ms","start":"2026-06-08T09:38:06.103333Z","end":"2026-06-08T09:38:06.269680Z","steps":["trace[1058615946] 'process raft request' (duration: 166.015626ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:38:06.580169Z","caller":"traceutil/trace.go:171","msg":"trace[1565352545] linearizableReadLoop","detail":"{readStateIndex:27711; appliedIndex:27709; }","duration":"228.220684ms","start":"2026-06-08T09:38:06.351919Z","end":"2026-06-08T09:38:06.580139Z","steps":["trace[1565352545] 'read index received' (duration: 130.763961ms)","trace[1565352545] 'applied index is now lower than readState.Index' (duration: 97.455273ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:38:06.580322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.379587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" limit:1 ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-08T09:38:06.580381Z","caller":"traceutil/trace.go:171","msg":"trace[1658925982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26828; }","duration":"228.458029ms","start":"2026-06-08T09:38:06.351912Z","end":"2026-06-08T09:38:06.580370Z","steps":["trace[1658925982] 'agreement among raft nodes before linearized reading' (duration: 228.339456ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:38:06.580475Z","caller":"traceutil/trace.go:171","msg":"trace[749685022] transaction","detail":"{read_only:false; response_revision:26827; number_of_response:1; }","duration":"253.728562ms","start":"2026-06-08T09:38:06.326704Z","end":"2026-06-08T09:38:06.580433Z","steps":["trace[749685022] 'process raft request' (duration: 155.959971ms)","trace[749685022] 'compare' (duration: 96.835147ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:38:06.580508Z","caller":"traceutil/trace.go:171","msg":"trace[1267924215] transaction","detail":"{read_only:false; response_revision:26828; number_of_response:1; }","duration":"253.768253ms","start":"2026-06-08T09:38:06.326711Z","end":"2026-06-08T09:38:06.580479Z","steps":["trace[1267924215] 'process raft request' (duration: 253.366573ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:38:06.884503Z","caller":"traceutil/trace.go:171","msg":"trace[1121190949] linearizableReadLoop","detail":"{readStateIndex:27712; appliedIndex:27711; }","duration":"259.415749ms","start":"2026-06-08T09:38:06.625042Z","end":"2026-06-08T09:38:06.884458Z","steps":["trace[1121190949] 'read index received' (duration: 238.982034ms)","trace[1121190949] 'applied index is now lower than readState.Index' (duration: 20.431475ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:38:06.884638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.576843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:38:06.884737Z","caller":"traceutil/trace.go:171","msg":"trace[1728546926] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:26829; }","duration":"259.680045ms","start":"2026-06-08T09:38:06.625034Z","end":"2026-06-08T09:38:06.884714Z","steps":["trace[1728546926] 'agreement among raft nodes before linearized reading' (duration: 259.509941ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:38:06.885020Z","caller":"traceutil/trace.go:171","msg":"trace[607834137] transaction","detail":"{read_only:false; response_revision:26829; number_of_response:1; }","duration":"290.345269ms","start":"2026-06-08T09:38:06.594650Z","end":"2026-06-08T09:38:06.884995Z","steps":["trace[607834137] 'process raft request' (duration: 269.225988ms)","trace[607834137] 'compare' (duration: 20.347243ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:38:06.895283Z","caller":"traceutil/trace.go:171","msg":"trace[2085377772] transaction","detail":"{read_only:false; response_revision:26830; number_of_response:1; }","duration":"238.262506ms","start":"2026-06-08T09:38:06.656991Z","end":"2026-06-08T09:38:06.895253Z","steps":["trace[2085377772] 'process raft request' (duration: 238.055051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:38:06.895334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.744729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:38:06.895374Z","caller":"traceutil/trace.go:171","msg":"trace[217901951] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26833; }","duration":"269.802761ms","start":"2026-06-08T09:38:06.625562Z","end":"2026-06-08T09:38:06.895365Z","steps":["trace[217901951] 'agreement among raft nodes before linearized reading' (duration: 269.697688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:38:06.895473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.440422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:38:06.895500Z","caller":"traceutil/trace.go:171","msg":"trace[471416112] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26833; }","duration":"269.468553ms","start":"2026-06-08T09:38:06.626024Z","end":"2026-06-08T09:38:06.895492Z","steps":["trace[471416112] 'agreement among raft nodes before linearized reading' (duration: 269.424582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:38:06.895484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.273028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" limit:1 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-08T09:38:06.895569Z","caller":"traceutil/trace.go:171","msg":"trace[1570544241] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26833; }","duration":"269.375071ms","start":"2026-06-08T09:38:06.626175Z","end":"2026-06-08T09:38:06.895551Z","steps":["trace[1570544241] 'agreement among raft nodes before linearized reading' (duration: 269.223548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:39:25.875755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.527036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-08T09:39:25.875884Z","caller":"traceutil/trace.go:171","msg":"trace[2009570806] transaction","detail":"{read_only:false; response_revision:27544; number_of_response:1; }","duration":"133.320442ms","start":"2026-06-08T09:39:25.742547Z","end":"2026-06-08T09:39:25.875867Z","steps":["trace[2009570806] 'process raft request' (duration: 32.59824ms)","trace[2009570806] 'compare' (duration: 100.432294ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:40:24.013518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25251} {"level":"info","ts":"2026-06-08T09:40:24.159623Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":25251,"took":"140.474294ms","hash":1743459487,"current-db-size-bytes":59039744,"current-db-size":"59 MB","current-db-size-in-use-bytes":37982208,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-06-08T09:40:24.159722Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":1743459487,"revision":25251,"compact-revision":22042} {"level":"info","ts":"2026-06-08T09:42:00.140188Z","caller":"etcdserver/server.go:1490","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T09:42:00.145264Z","caller":"etcdserver/server.go:2553","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-08T09:42:00.145403Z","caller":"etcdserver/server.go:2583","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-08T09:45:24.020163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28094} {"level":"info","ts":"2026-06-08T09:45:24.161253Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":28094,"took":"136.54983ms","hash":10109644,"current-db-size-bytes":59039744,"current-db-size":"59 MB","current-db-size-in-use-bytes":33263616,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-08T09:45:24.161333Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":10109644,"revision":28094,"compact-revision":25251} {"level":"info","ts":"2026-06-08T09:49:57.958295Z","caller":"traceutil/trace.go:171","msg":"trace[327432602] transaction","detail":"{read_only:false; response_revision:32622; number_of_response:1; }","duration":"111.593902ms","start":"2026-06-08T09:49:57.846665Z","end":"2026-06-08T09:49:57.958259Z","steps":["trace[327432602] 'process raft request' (duration: 111.224123ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:49:58.467919Z","caller":"traceutil/trace.go:171","msg":"trace[1496652815] linearizableReadLoop","detail":"{readStateIndex:33672; appliedIndex:33670; }","duration":"104.208962ms","start":"2026-06-08T09:49:58.363683Z","end":"2026-06-08T09:49:58.467891Z","steps":["trace[1496652815] 'read index received' (duration: 16.53789ms)","trace[1496652815] 'applied index is now lower than readState.Index' (duration: 87.669682ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T09:49:58.467971Z","caller":"traceutil/trace.go:171","msg":"trace[403504942] transaction","detail":"{read_only:false; response_revision:32627; number_of_response:1; }","duration":"112.258858ms","start":"2026-06-08T09:49:58.355656Z","end":"2026-06-08T09:49:58.467915Z","steps":["trace[403504942] 'process raft request' (duration: 50.144174ms)","trace[403504942] 'compare' (duration: 61.855348ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:49:58.468092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.403278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-08T09:49:58.468180Z","caller":"traceutil/trace.go:171","msg":"trace[1983187189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32627; }","duration":"104.5007ms","start":"2026-06-08T09:49:58.363661Z","end":"2026-06-08T09:49:58.468162Z","steps":["trace[1983187189] 'agreement among raft nodes before linearized reading' (duration: 104.363607ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.352257Z","caller":"traceutil/trace.go:171","msg":"trace[823786937] transaction","detail":"{read_only:false; response_revision:32641; number_of_response:1; }","duration":"187.666444ms","start":"2026-06-08T09:50:00.164543Z","end":"2026-06-08T09:50:00.352210Z","steps":["trace[823786937] 'process raft request' (duration: 187.433188ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.352715Z","caller":"traceutil/trace.go:171","msg":"trace[1024987231] transaction","detail":"{read_only:false; response_revision:32642; number_of_response:1; }","duration":"185.589283ms","start":"2026-06-08T09:50:00.167055Z","end":"2026-06-08T09:50:00.352645Z","steps":["trace[1024987231] 'process raft request' (duration: 185.079871ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.483540Z","caller":"traceutil/trace.go:171","msg":"trace[787103685] transaction","detail":"{read_only:false; response_revision:32643; number_of_response:1; }","duration":"124.315291ms","start":"2026-06-08T09:50:00.359194Z","end":"2026-06-08T09:50:00.483510Z","steps":["trace[787103685] 'process raft request' (duration: 124.137507ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.603082Z","caller":"traceutil/trace.go:171","msg":"trace[228534404] transaction","detail":"{read_only:false; response_revision:32647; number_of_response:1; }","duration":"127.161349ms","start":"2026-06-08T09:50:00.475892Z","end":"2026-06-08T09:50:00.603053Z","steps":["trace[228534404] 'process raft request' (duration: 127.121308ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.603211Z","caller":"traceutil/trace.go:171","msg":"trace[1406630694] transaction","detail":"{read_only:false; response_revision:32646; number_of_response:1; }","duration":"217.065905ms","start":"2026-06-08T09:50:00.386099Z","end":"2026-06-08T09:50:00.603165Z","steps":["trace[1406630694] 'process raft request' (duration: 216.877501ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.603252Z","caller":"traceutil/trace.go:171","msg":"trace[787537607] transaction","detail":"{read_only:false; response_revision:32645; number_of_response:1; }","duration":"240.687307ms","start":"2026-06-08T09:50:00.362536Z","end":"2026-06-08T09:50:00.603224Z","steps":["trace[787537607] 'process raft request' (duration: 240.381359ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.603348Z","caller":"traceutil/trace.go:171","msg":"trace[1516338690] transaction","detail":"{read_only:false; response_revision:32644; number_of_response:1; }","duration":"243.934976ms","start":"2026-06-08T09:50:00.359339Z","end":"2026-06-08T09:50:00.603274Z","steps":["trace[1516338690] 'process raft request' (duration: 243.396953ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.669632Z","caller":"traceutil/trace.go:171","msg":"trace[1963362666] linearizableReadLoop","detail":"{readStateIndex:33694; appliedIndex:33689; }","duration":"163.236372ms","start":"2026-06-08T09:50:00.506370Z","end":"2026-06-08T09:50:00.669606Z","steps":["trace[1963362666] 'read index received' (duration: 96.354903ms)","trace[1963362666] 'applied index is now lower than readState.Index' (duration: 66.880809ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:50:00.670096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.702673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29681870-z9sj5\" limit:1 ","response":"range_response_count:1 size:5603"} {"level":"info","ts":"2026-06-08T09:50:00.670183Z","caller":"traceutil/trace.go:171","msg":"trace[1897269405] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29681870-z9sj5; range_end:; response_count:1; response_revision:32650; }","duration":"163.802656ms","start":"2026-06-08T09:50:00.506361Z","end":"2026-06-08T09:50:00.670164Z","steps":["trace[1897269405] 'agreement among raft nodes before linearized reading' (duration: 163.602931ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.670073Z","caller":"traceutil/trace.go:171","msg":"trace[695100296] transaction","detail":"{read_only:false; response_revision:32650; number_of_response:1; }","duration":"128.135192ms","start":"2026-06-08T09:50:00.541824Z","end":"2026-06-08T09:50:00.669959Z","steps":["trace[695100296] 'process raft request' (duration: 127.744952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:50:00.670507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.204652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" limit:1 ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-08T09:50:00.670581Z","caller":"traceutil/trace.go:171","msg":"trace[1405707258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32650; }","duration":"163.277683ms","start":"2026-06-08T09:50:00.507292Z","end":"2026-06-08T09:50:00.670570Z","steps":["trace[1405707258] 'agreement among raft nodes before linearized reading' (duration: 163.173751ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.670713Z","caller":"traceutil/trace.go:171","msg":"trace[1882620410] transaction","detail":"{read_only:false; response_revision:32648; number_of_response:1; }","duration":"181.980076ms","start":"2026-06-08T09:50:00.488717Z","end":"2026-06-08T09:50:00.670697Z","steps":["trace[1882620410] 'process raft request' (duration: 180.618353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:50:00.670883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.600781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-08T09:50:00.670938Z","caller":"traceutil/trace.go:171","msg":"trace[1732920568] transaction","detail":"{read_only:false; response_revision:32649; number_of_response:1; }","duration":"144.855828ms","start":"2026-06-08T09:50:00.526066Z","end":"2026-06-08T09:50:00.670922Z","steps":["trace[1732920568] 'process raft request' (duration: 143.442953ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:00.671001Z","caller":"traceutil/trace.go:171","msg":"trace[1149437194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:32650; }","duration":"146.687383ms","start":"2026-06-08T09:50:00.524272Z","end":"2026-06-08T09:50:00.670959Z","steps":["trace[1149437194] 'agreement among raft nodes before linearized reading' (duration: 146.57013ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:02.831095Z","caller":"traceutil/trace.go:171","msg":"trace[1917694001] transaction","detail":"{read_only:false; response_revision:32669; number_of_response:1; }","duration":"220.009158ms","start":"2026-06-08T09:50:02.611059Z","end":"2026-06-08T09:50:02.831068Z","steps":["trace[1917694001] 'process raft request' (duration: 218.081581ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:02.857025Z","caller":"traceutil/trace.go:171","msg":"trace[1647998539] transaction","detail":"{read_only:false; response_revision:32670; number_of_response:1; }","duration":"180.012028ms","start":"2026-06-08T09:50:02.676991Z","end":"2026-06-08T09:50:02.857003Z","steps":["trace[1647998539] 'process raft request' (duration: 179.825674ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:02.857031Z","caller":"traceutil/trace.go:171","msg":"trace[1738591359] linearizableReadLoop","detail":"{readStateIndex:33717; appliedIndex:33715; }","duration":"149.264223ms","start":"2026-06-08T09:50:02.707733Z","end":"2026-06-08T09:50:02.856998Z","steps":["trace[1738591359] 'read index received' (duration: 121.47097ms)","trace[1738591359] 'applied index is now lower than readState.Index' (duration: 27.792213ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T09:50:02.857269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.489709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" limit:1 ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-06-08T09:50:02.857338Z","caller":"traceutil/trace.go:171","msg":"trace[1302134253] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:32670; }","duration":"149.611832ms","start":"2026-06-08T09:50:02.707710Z","end":"2026-06-08T09:50:02.857322Z","steps":["trace[1302134253] 'agreement among raft nodes before linearized reading' (duration: 149.393227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T09:50:02.857436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.860685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-08T09:50:02.857498Z","caller":"traceutil/trace.go:171","msg":"trace[1130648411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:32670; }","duration":"115.986338ms","start":"2026-06-08T09:50:02.741498Z","end":"2026-06-08T09:50:02.857485Z","steps":["trace[1130648411] 'agreement among raft nodes before linearized reading' (duration: 115.806254ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T09:50:24.026976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30598} {"level":"info","ts":"2026-06-08T09:50:24.164358Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":30598,"took":"130.679464ms","hash":725170489,"current-db-size-bytes":59039744,"current-db-size":"59 MB","current-db-size-in-use-bytes":32124928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T09:50:24.164458Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":725170489,"revision":30598,"compact-revision":28094} {"level":"info","ts":"2026-06-08T09:55:24.034725Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32847} {"level":"info","ts":"2026-06-08T09:55:24.183473Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":32847,"took":"143.494034ms","hash":4235274179,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":31834112,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T09:55:24.183539Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":4235274179,"revision":32847,"compact-revision":30598} {"level":"info","ts":"2026-06-08T09:57:31.143233Z","caller":"traceutil/trace.go:171","msg":"trace[1227830955] transaction","detail":"{read_only:false; response_revision:36067; number_of_response:1; }","duration":"110.501675ms","start":"2026-06-08T09:57:31.032697Z","end":"2026-06-08T09:57:31.143199Z","steps":["trace[1227830955] 'process raft request' (duration: 110.28561ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T10:00:24.042136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35079} {"level":"info","ts":"2026-06-08T10:00:24.184974Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":35079,"took":"137.815467ms","hash":4011963075,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":32432128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-08T10:00:24.185053Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":4011963075,"revision":35079,"compact-revision":32847} {"level":"info","ts":"2026-06-08T10:03:21.779194Z","caller":"etcdserver/server.go:1490","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-08T10:03:21.785795Z","caller":"etcdserver/server.go:2553","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-08T10:03:21.786068Z","caller":"etcdserver/server.go:2583","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-08T10:05:24.048216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37425} {"level":"info","ts":"2026-06-08T10:05:24.179214Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":37425,"took":"125.092471ms","hash":3369014367,"current-db-size-bytes":60506112,"current-db-size":"60 MB","current-db-size-in-use-bytes":32878592,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-08T10:05:24.179282Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":3369014367,"revision":37425,"compact-revision":35079} {"level":"info","ts":"2026-06-08T10:10:24.053984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39742} {"level":"info","ts":"2026-06-08T10:10:24.181544Z","caller":"mvcc/kvstore_compaction.go:72","msg":"finished scheduled compaction","compact-revision":39742,"took":"122.416646ms","hash":4007785211,"current-db-size-bytes":60506112,"current-db-size":"60 MB","current-db-size-in-use-bytes":32681984,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-08T10:10:24.181611Z","caller":"mvcc/hash.go:151","msg":"storing new hash","hash":4007785211,"revision":39742,"compact-revision":37425}