{"level":"warn","ts":"2026-09-07T10:50:06.593024Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:50:06.593153Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.38: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://162.253.55.38:2380","--initial-cluster=instance=https://162.253.55.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.38: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-09-07T10:50:06.593348Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:50:06.593374Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.38:2380"]} {"level":"info","ts":"2026-09-07T10:50:06.593402Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T10:50:06.594636Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"]} {"level":"info","ts":"2026-09-07T10:50:06.594853Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.38: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-09-07T10:50:06.598035Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.609907ms"} {"level":"info","ts":"2026-09-07T10:50:06.608914Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30b39d71f9e69e7c","cluster-id":"2afc6404f924db16"} {"level":"info","ts":"2026-09-07T10:50:06.609048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T10:50:06.60909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 0"} {"level":"info","ts":"2026-09-07T10:50:06.609114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30b39d71f9e69e7c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T10:50:06.609127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 1"} {"level":"info","ts":"2026-09-07T10:50:06.609181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"} {"level":"warn","ts":"2026-09-07T10:50:06.615155Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T10:50:06.617178Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T10:50:06.618868Z","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-09-07T10:50:06.62288Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30b39d71f9e69e7c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T10:50:06.623566Z","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-09-07T10:50:06.623704Z","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-09-07T10:50:06.623735Z","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-09-07T10:50:06.623656Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30b39d71f9e69e7c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T10:50:06.625743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"} {"level":"info","ts":"2026-09-07T10:50:06.625993Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","added-peer-id":"30b39d71f9e69e7c","added-peer-peer-urls":["https://162.253.55.38:2380"]} {"level":"info","ts":"2026-09-07T10:50:06.628476Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T10:50:06.628557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.38:2380"} {"level":"info","ts":"2026-09-07T10:50:06.628627Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.38:2380"} {"level":"info","ts":"2026-09-07T10:50:06.628854Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30b39d71f9e69e7c","initial-advertise-peer-urls":["https://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T10:50:06.628922Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T10:50:07.409902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T10:50:07.410022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T10:50:07.410062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgPreVoteResp from 30b39d71f9e69e7c at term 1"} {"level":"info","ts":"2026-09-07T10:50:07.410084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became candidate at term 2"} {"level":"info","ts":"2026-09-07T10:50:07.410099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgVoteResp from 30b39d71f9e69e7c at term 2"} {"level":"info","ts":"2026-09-07T10:50:07.410118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became leader at term 2"} {"level":"info","ts":"2026-09-07T10:50:07.410148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30b39d71f9e69e7c elected leader 30b39d71f9e69e7c at term 2"} {"level":"info","ts":"2026-09-07T10:50:07.411332Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:50:07.412429Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:50:07.412432Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30b39d71f9e69e7c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.38:2379]}","request-path":"/0/members/30b39d71f9e69e7c/attributes","cluster-id":"2afc6404f924db16","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T10:50:07.412542Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:50:07.413098Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T10:50:07.413129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T10:50:07.415149Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:50:07.415407Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:50:07.415457Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:50:07.416151Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T10:50:07.419459Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.38:2379"} {"level":"info","ts":"2026-09-07T10:50:25.077293Z","caller":"traceutil/trace.go:171","msg":"trace[1897859917] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"200.939815ms","start":"2026-09-07T10:50:24.876324Z","end":"2026-09-07T10:50:25.077264Z","steps":["trace[1897859917] 'process raft request' (duration: 200.714458ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:30.845726Z","caller":"traceutil/trace.go:171","msg":"trace[1244941380] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"126.600231ms","start":"2026-09-07T10:50:30.719079Z","end":"2026-09-07T10:50:30.84568Z","steps":["trace[1244941380] 'process raft request' (duration: 86.156309ms)","trace[1244941380] 'compare' (duration: 40.163913ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:50:30.845799Z","caller":"traceutil/trace.go:171","msg":"trace[482223653] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"126.215039ms","start":"2026-09-07T10:50:30.719541Z","end":"2026-09-07T10:50:30.845756Z","steps":["trace[482223653] 'process raft request' (duration: 125.999573ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:30.845974Z","caller":"traceutil/trace.go:171","msg":"trace[1499699600] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"125.187521ms","start":"2026-09-07T10:50:30.720724Z","end":"2026-09-07T10:50:30.845911Z","steps":["trace[1499699600] 'process raft request' (duration: 124.878212ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:55.583032Z","caller":"traceutil/trace.go:171","msg":"trace[1379548986] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"200.108228ms","start":"2026-09-07T10:50:55.382885Z","end":"2026-09-07T10:50:55.582994Z","steps":["trace[1379548986] 'process raft request' (duration: 199.557492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:55.833633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.257936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-07T10:50:55.833771Z","caller":"traceutil/trace.go:171","msg":"trace[1757726865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"182.448312ms","start":"2026-09-07T10:50:55.651301Z","end":"2026-09-07T10:50:55.833749Z","steps":["trace[1757726865] 'range keys from in-memory index tree' (duration: 182.134703ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:56.047997Z","caller":"traceutil/trace.go:171","msg":"trace[397640898] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"385.759563ms","start":"2026-09-07T10:50:55.662204Z","end":"2026-09-07T10:50:56.047963Z","steps":["trace[397640898] 'process raft request' (duration: 385.527816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:56.048686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:50:55.662175Z","time spent":"385.907177ms","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:50:56.070549Z","caller":"traceutil/trace.go:171","msg":"trace[1348328154] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"208.539573ms","start":"2026-09-07T10:50:55.861983Z","end":"2026-09-07T10:50:56.070522Z","steps":["trace[1348328154] 'read index received' (duration: 185.738548ms)","trace[1348328154] 'applied index is now lower than readState.Index' (duration: 22.799995ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:50:56.070677Z","caller":"traceutil/trace.go:171","msg":"trace[1418117289] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"232.71652ms","start":"2026-09-07T10:50:55.837936Z","end":"2026-09-07T10:50:56.070653Z","steps":["trace[1418117289] 'process raft request' (duration: 232.468322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:56.070818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.810732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T10:50:56.070925Z","caller":"traceutil/trace.go:171","msg":"trace[874179848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"208.949236ms","start":"2026-09-07T10:50:55.861952Z","end":"2026-09-07T10:50:56.070902Z","steps":["trace[874179848] 'agreement among raft nodes before linearized reading' (duration: 208.715539ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:50:56.281907Z","caller":"traceutil/trace.go:171","msg":"trace[1057335794] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"145.594098ms","start":"2026-09-07T10:50:56.136284Z","end":"2026-09-07T10:50:56.281878Z","steps":["trace[1057335794] 'process raft request' (duration: 145.432633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:50:56.718137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.265477ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-07T10:50:56.71919Z","caller":"traceutil/trace.go:171","msg":"trace[2118326697] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"202.526959ms","start":"2026-09-07T10:50:56.516638Z","end":"2026-09-07T10:50:56.719165Z","steps":["trace[2118326697] 'process raft request' (duration: 201.993343ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:30.468216Z","caller":"traceutil/trace.go:171","msg":"trace[714906248] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"120.814705ms","start":"2026-09-07T10:51:30.347364Z","end":"2026-09-07T10:51:30.468178Z","steps":["trace[714906248] 'process raft request' (duration: 106.959401ms)","trace[714906248] 'compare' (duration: 13.379869ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:30.468472Z","caller":"traceutil/trace.go:171","msg":"trace[89963935] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"112.837273ms","start":"2026-09-07T10:51:30.355617Z","end":"2026-09-07T10:51:30.468454Z","steps":["trace[89963935] 'process raft request' (duration: 112.282126ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:30.468656Z","caller":"traceutil/trace.go:171","msg":"trace[1062075665] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"111.942936ms","start":"2026-09-07T10:51:30.356691Z","end":"2026-09-07T10:51:30.468634Z","steps":["trace[1062075665] 'process raft request' (duration: 111.334299ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:30.468698Z","caller":"traceutil/trace.go:171","msg":"trace[1374657133] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"111.584966ms","start":"2026-09-07T10:51:30.357081Z","end":"2026-09-07T10:51:30.468666Z","steps":["trace[1374657133] 'process raft request' (duration: 110.985459ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:30.468824Z","caller":"traceutil/trace.go:171","msg":"trace[1366945426] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"112.380798ms","start":"2026-09-07T10:51:30.35641Z","end":"2026-09-07T10:51:30.468791Z","steps":["trace[1366945426] 'process raft request' (duration: 111.559604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:30.468891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.180412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/cert-manager/\" range_end:\"/registry/limitranges/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:51:30.469045Z","caller":"traceutil/trace.go:171","msg":"trace[2015994400] range","detail":"{range_begin:/registry/limitranges/cert-manager/; range_end:/registry/limitranges/cert-manager0; response_count:0; response_revision:1076; }","duration":"113.373827ms","start":"2026-09-07T10:51:30.355617Z","end":"2026-09-07T10:51:30.468991Z","steps":["trace[2015994400] 'agreement among raft nodes before linearized reading' (duration: 113.085079ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:52:31.695921Z","caller":"traceutil/trace.go:171","msg":"trace[997986641] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"174.251654ms","start":"2026-09-07T10:52:31.521629Z","end":"2026-09-07T10:52:31.695881Z","steps":["trace[997986641] 'process raft request' (duration: 173.989446ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:52:32.025461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.922723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:52:32.02611Z","caller":"traceutil/trace.go:171","msg":"trace[182081331] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"171.856845ms","start":"2026-09-07T10:52:31.854227Z","end":"2026-09-07T10:52:32.026084Z","steps":["trace[182081331] 'process raft request' (duration: 61.050065ms)","trace[182081331] 'compare' (duration: 109.792099ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:52:32.026247Z","caller":"traceutil/trace.go:171","msg":"trace[319618181] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"171.459112ms","start":"2026-09-07T10:52:31.854736Z","end":"2026-09-07T10:52:32.026195Z","steps":["trace[319618181] 'process raft request' (duration: 170.879275ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:52:44.843662Z","caller":"traceutil/trace.go:171","msg":"trace[2020268618] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"146.351478ms","start":"2026-09-07T10:52:44.697273Z","end":"2026-09-07T10:52:44.843625Z","steps":["trace[2020268618] 'process raft request' (duration: 128.61161ms)","trace[2020268618] 'compare' (duration: 17.412369ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:53:21.888254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.166106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:53:21.888651Z","caller":"traceutil/trace.go:171","msg":"trace[1177128052] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2291; }","duration":"403.195858ms","start":"2026-09-07T10:53:21.485177Z","end":"2026-09-07T10:53:21.888373Z","steps":["trace[1177128052] 'read index received' (duration: 63.705093ms)","trace[1177128052] 'applied index is now lower than readState.Index' (duration: 339.488965ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:53:21.888652Z","caller":"traceutil/trace.go:171","msg":"trace[1203887804] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"492.110567ms","start":"2026-09-07T10:53:21.396507Z","end":"2026-09-07T10:53:21.888618Z","steps":["trace[1203887804] 'process raft request' (duration: 152.458177ms)","trace[1203887804] 'compare' (duration: 339.026352ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:53:21.888994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:53:21.396482Z","time spent":"492.430456ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:53:21.889018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.813447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:53:21.889113Z","caller":"traceutil/trace.go:171","msg":"trace[1573375766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2210; }","duration":"403.966271ms","start":"2026-09-07T10:53:21.485121Z","end":"2026-09-07T10:53:21.889087Z","steps":["trace[1573375766] 'agreement among raft nodes before linearized reading' (duration: 403.793815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:53:21.889186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:53:21.485091Z","time spent":"404.081825ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-07T10:54:40.446854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.402108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:54:40.446966Z","caller":"traceutil/trace.go:171","msg":"trace[810436062] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2828; }","duration":"109.547743ms","start":"2026-09-07T10:54:40.337402Z","end":"2026-09-07T10:54:40.44695Z","steps":["trace[810436062] 'count revisions from in-memory index tree' (duration: 109.364818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:54:40.447134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.563817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:54:40.447154Z","caller":"traceutil/trace.go:171","msg":"trace[1183859997] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2828; }","duration":"120.626019ms","start":"2026-09-07T10:54:40.326522Z","end":"2026-09-07T10:54:40.447148Z","steps":["trace[1183859997] 'count revisions from in-memory index tree' (duration: 120.501515ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:46.500761Z","caller":"traceutil/trace.go:171","msg":"trace[90458738] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"262.898521ms","start":"2026-09-07T10:54:46.237837Z","end":"2026-09-07T10:54:46.500736Z","steps":["trace[90458738] 'process raft request' (duration: 262.763977ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:46.501758Z","caller":"traceutil/trace.go:171","msg":"trace[1555028818] linearizableReadLoop","detail":"{readStateIndex:2965; appliedIndex:2965; }","duration":"138.41923ms","start":"2026-09-07T10:54:46.362406Z","end":"2026-09-07T10:54:46.500826Z","steps":["trace[1555028818] 'read index received' (duration: 138.407509ms)","trace[1555028818] 'applied index is now lower than readState.Index' (duration: 8.961µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:54:46.502804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.335947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-07T10:54:46.502983Z","caller":"traceutil/trace.go:171","msg":"trace[1291090911] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2859; }","duration":"140.568644ms","start":"2026-09-07T10:54:46.362354Z","end":"2026-09-07T10:54:46.502923Z","steps":["trace[1291090911] 'agreement among raft nodes before linearized reading' (duration: 138.495602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:58:02.858623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.503751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T10:58:02.858741Z","caller":"traceutil/trace.go:171","msg":"trace[494747278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4095; }","duration":"130.674315ms","start":"2026-09-07T10:58:02.728042Z","end":"2026-09-07T10:58:02.858716Z","steps":["trace[494747278] 'range keys from in-memory index tree' (duration: 130.353226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:58:02.859039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.183427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-09-07T10:58:02.85913Z","caller":"traceutil/trace.go:171","msg":"trace[1113315872] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:4095; }","duration":"149.327522ms","start":"2026-09-07T10:58:02.709784Z","end":"2026-09-07T10:58:02.859111Z","steps":["trace[1113315872] 'range keys from in-memory index tree' (duration: 148.9238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:34.43417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.413196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16980"} {"level":"info","ts":"2026-09-07T10:59:34.434319Z","caller":"traceutil/trace.go:171","msg":"trace[796265144] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5193; }","duration":"225.620052ms","start":"2026-09-07T10:59:34.208672Z","end":"2026-09-07T10:59:34.434292Z","steps":["trace[796265144] 'range keys from in-memory index tree' (duration: 225.17925ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:34.435162Z","caller":"traceutil/trace.go:171","msg":"trace[2032692561] linearizableReadLoop","detail":"{readStateIndex:5392; appliedIndex:5391; }","duration":"130.522059ms","start":"2026-09-07T10:59:34.30462Z","end":"2026-09-07T10:59:34.435142Z","steps":["trace[2032692561] 'read index received' (duration: 130.348664ms)","trace[2032692561] 'applied index is now lower than readState.Index' (duration: 172.535µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:59:34.435395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.928857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T10:59:34.435445Z","caller":"traceutil/trace.go:171","msg":"trace[1557432689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5194; }","duration":"124.02467ms","start":"2026-09-07T10:59:34.311406Z","end":"2026-09-07T10:59:34.43543Z","steps":["trace[1557432689] 'agreement among raft nodes before linearized reading' (duration: 123.851915ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:34.435475Z","caller":"traceutil/trace.go:171","msg":"trace[1769371614] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"221.769419ms","start":"2026-09-07T10:59:34.213657Z","end":"2026-09-07T10:59:34.435427Z","steps":["trace[1769371614] 'process raft request' (duration: 221.366277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:34.435986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.26233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T10:59:34.436217Z","caller":"traceutil/trace.go:171","msg":"trace[158744427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5194; }","duration":"131.619751ms","start":"2026-09-07T10:59:34.30456Z","end":"2026-09-07T10:59:34.436179Z","steps":["trace[158744427] 'agreement among raft nodes before linearized reading' (duration: 130.661753ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:38.22707Z","caller":"traceutil/trace.go:171","msg":"trace[1400588137] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"324.458765ms","start":"2026-09-07T10:59:37.902578Z","end":"2026-09-07T10:59:38.227037Z","steps":["trace[1400588137] 'process raft request' (duration: 324.254759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:38.227252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:59:37.902552Z","time spent":"324.608609ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3705,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:59:38.243129Z","caller":"traceutil/trace.go:171","msg":"trace[1946223448] linearizableReadLoop","detail":"{readStateIndex:5547; appliedIndex:5545; }","duration":"310.346092ms","start":"2026-09-07T10:59:37.932757Z","end":"2026-09-07T10:59:38.243103Z","steps":["trace[1946223448] 'read index received' (duration: 294.059775ms)","trace[1946223448] 'applied index is now lower than readState.Index' (duration: 16.285057ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:59:38.243272Z","caller":"traceutil/trace.go:171","msg":"trace[2086728712] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"327.356529ms","start":"2026-09-07T10:59:37.915891Z","end":"2026-09-07T10:59:38.243247Z","steps":["trace[2086728712] 'process raft request' (duration: 327.0801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:38.243359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.587039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-07T10:59:38.243383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:59:37.915857Z","time spent":"327.464182ms","remote":"127.0.0.1:59502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1811,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:59:38.243416Z","caller":"traceutil/trace.go:171","msg":"trace[1036053367] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5345; }","duration":"310.696772ms","start":"2026-09-07T10:59:37.932702Z","end":"2026-09-07T10:59:38.243398Z","steps":["trace[1036053367] 'agreement among raft nodes before linearized reading' (duration: 310.555188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:38.243461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:59:37.932683Z","time spent":"310.762534ms","remote":"127.0.0.1:54022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-09-07T10:59:38.243479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.165798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xg6pg\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-09-07T10:59:38.243525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.748262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-07T10:59:38.24354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.563629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-hc7q7-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-09-07T10:59:38.243595Z","caller":"traceutil/trace.go:171","msg":"trace[23845087] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5345; }","duration":"109.864916ms","start":"2026-09-07T10:59:38.133711Z","end":"2026-09-07T10:59:38.243576Z","steps":["trace[23845087] 'agreement among raft nodes before linearized reading' (duration: 109.67038ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:38.243599Z","caller":"traceutil/trace.go:171","msg":"trace[1627766780] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-hc7q7-tls; range_end:; response_count:1; response_revision:5345; }","duration":"186.667842ms","start":"2026-09-07T10:59:38.056916Z","end":"2026-09-07T10:59:38.243583Z","steps":["trace[1627766780] 'agreement among raft nodes before linearized reading' (duration: 186.456436ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:38.243547Z","caller":"traceutil/trace.go:171","msg":"trace[1274269342] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xg6pg; range_end:; response_count:1; response_revision:5345; }","duration":"280.281211ms","start":"2026-09-07T10:59:37.96325Z","end":"2026-09-07T10:59:38.243532Z","steps":["trace[1274269342] 'agreement among raft nodes before linearized reading' (duration: 280.078416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:38.243645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.239788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c26879b5-fa8d-4063-8580-8bfc172a1e45\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-09-07T10:59:38.243817Z","caller":"traceutil/trace.go:171","msg":"trace[1459106482] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c26879b5-fa8d-4063-8580-8bfc172a1e45; range_end:; response_count:1; response_revision:5345; }","duration":"269.465574ms","start":"2026-09-07T10:59:37.9743Z","end":"2026-09-07T10:59:38.243765Z","steps":["trace[1459106482] 'agreement among raft nodes before linearized reading' (duration: 269.154635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:40.15482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.000121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:26 size:797153"} {"level":"info","ts":"2026-09-07T10:59:40.154956Z","caller":"traceutil/trace.go:171","msg":"trace[1426924751] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:26; response_revision:5384; }","duration":"121.227528ms","start":"2026-09-07T10:59:40.033707Z","end":"2026-09-07T10:59:40.154935Z","steps":["trace[1426924751] 'range keys from in-memory index tree' (duration: 120.424933ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:50.783936Z","caller":"traceutil/trace.go:171","msg":"trace[1417679858] linearizableReadLoop","detail":"{readStateIndex:5789; appliedIndex:5787; }","duration":"150.974258ms","start":"2026-09-07T10:59:50.632938Z","end":"2026-09-07T10:59:50.783913Z","steps":["trace[1417679858] 'read index received' (duration: 30.7529ms)","trace[1417679858] 'applied index is now lower than readState.Index' (duration: 120.220278ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:59:50.783948Z","caller":"traceutil/trace.go:171","msg":"trace[1721194972] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"118.708602ms","start":"2026-09-07T10:59:50.66521Z","end":"2026-09-07T10:59:50.783918Z","steps":["trace[1721194972] 'process raft request' (duration: 118.61297ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:50.784203Z","caller":"traceutil/trace.go:171","msg":"trace[706147448] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"167.445049ms","start":"2026-09-07T10:59:50.616726Z","end":"2026-09-07T10:59:50.784171Z","steps":["trace[706147448] 'process raft request' (duration: 86.108299ms)","trace[706147448] 'compare' (duration: 80.590548ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:59:50.784261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.303108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:59:50.784346Z","caller":"traceutil/trace.go:171","msg":"trace[1875836438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5580; }","duration":"151.427302ms","start":"2026-09-07T10:59:50.632904Z","end":"2026-09-07T10:59:50.784332Z","steps":["trace[1875836438] 'agreement among raft nodes before linearized reading' (duration: 151.270487ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:51.287694Z","caller":"traceutil/trace.go:171","msg":"trace[1088501329] linearizableReadLoop","detail":"{readStateIndex:5796; appliedIndex:5793; }","duration":"179.692908ms","start":"2026-09-07T10:59:51.107973Z","end":"2026-09-07T10:59:51.287666Z","steps":["trace[1088501329] 'read index received' (duration: 110.72532ms)","trace[1088501329] 'applied index is now lower than readState.Index' (duration: 68.966428ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:59:51.287955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.975216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-07T10:59:51.288008Z","caller":"traceutil/trace.go:171","msg":"trace[673925664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5585; }","duration":"180.076429ms","start":"2026-09-07T10:59:51.107915Z","end":"2026-09-07T10:59:51.287992Z","steps":["trace[673925664] 'agreement among raft nodes before linearized reading' (duration: 179.887793ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:51.287994Z","caller":"traceutil/trace.go:171","msg":"trace[288349832] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"241.749234ms","start":"2026-09-07T10:59:51.046168Z","end":"2026-09-07T10:59:51.287918Z","steps":["trace[288349832] 'process raft request' (duration: 241.233879ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:51.2881Z","caller":"traceutil/trace.go:171","msg":"trace[1826373266] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"237.328854ms","start":"2026-09-07T10:59:51.05051Z","end":"2026-09-07T10:59:51.287839Z","steps":["trace[1826373266] 'process raft request' (duration: 237.087507ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:59:59.937393Z","caller":"traceutil/trace.go:171","msg":"trace[1626676885] linearizableReadLoop","detail":"{readStateIndex:5969; appliedIndex:5967; }","duration":"115.149059ms","start":"2026-09-07T10:59:59.822218Z","end":"2026-09-07T10:59:59.937367Z","steps":["trace[1626676885] 'read index received' (duration: 32.390968ms)","trace[1626676885] 'applied index is now lower than readState.Index' (duration: 82.756791ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:59:59.937513Z","caller":"traceutil/trace.go:171","msg":"trace[549108992] transaction","detail":"{read_only:false; response_revision:5754; number_of_response:1; }","duration":"153.192323ms","start":"2026-09-07T10:59:59.784276Z","end":"2026-09-07T10:59:59.937469Z","steps":["trace[549108992] 'process raft request' (duration: 152.954617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:59:59.937722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.485288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cfd0b90-6af1-48cf-8c37-3a8501ed3edc\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-07T10:59:59.937806Z","caller":"traceutil/trace.go:171","msg":"trace[1064278664] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6cfd0b90-6af1-48cf-8c37-3a8501ed3edc; range_end:; response_count:1; response_revision:5754; }","duration":"115.638013ms","start":"2026-09-07T10:59:59.822151Z","end":"2026-09-07T10:59:59.937789Z","steps":["trace[1064278664] 'agreement among raft nodes before linearized reading' (duration: 115.349845ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:00.362696Z","caller":"traceutil/trace.go:171","msg":"trace[1264733447] linearizableReadLoop","detail":"{readStateIndex:5970; appliedIndex:5969; }","duration":"180.774031ms","start":"2026-09-07T11:00:00.181896Z","end":"2026-09-07T11:00:00.36267Z","steps":["trace[1264733447] 'read index received' (duration: 180.563224ms)","trace[1264733447] 'applied index is now lower than readState.Index' (duration: 209.707µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:00:00.362886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.977256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:00:00.362943Z","caller":"traceutil/trace.go:171","msg":"trace[815436867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5755; }","duration":"181.09609ms","start":"2026-09-07T11:00:00.181831Z","end":"2026-09-07T11:00:00.362927Z","steps":["trace[815436867] 'agreement among raft nodes before linearized reading' (duration: 180.994667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:00.363158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.626125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-09-07T11:00:00.363263Z","caller":"traceutil/trace.go:171","msg":"trace[399857334] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5755; }","duration":"153.773529ms","start":"2026-09-07T11:00:00.209473Z","end":"2026-09-07T11:00:00.363247Z","steps":["trace[399857334] 'agreement among raft nodes before linearized reading' (duration: 153.477301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:00.363153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.659013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:00:00.363485Z","caller":"traceutil/trace.go:171","msg":"trace[598664932] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5755; }","duration":"104.041684ms","start":"2026-09-07T11:00:00.259416Z","end":"2026-09-07T11:00:00.363458Z","steps":["trace[598664932] 'agreement among raft nodes before linearized reading' (duration: 103.583041ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:00.363089Z","caller":"traceutil/trace.go:171","msg":"trace[1863188218] transaction","detail":"{read_only:false; response_revision:5755; number_of_response:1; }","duration":"219.822393ms","start":"2026-09-07T11:00:00.142922Z","end":"2026-09-07T11:00:00.362745Z","steps":["trace[1863188218] 'process raft request' (duration: 219.601256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:00.617859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.363365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:00:00.617941Z","caller":"traceutil/trace.go:171","msg":"trace[1280459628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5755; }","duration":"252.494609ms","start":"2026-09-07T11:00:00.365428Z","end":"2026-09-07T11:00:00.617923Z","steps":["trace[1280459628] 'range keys from in-memory index tree' (duration: 252.286572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:00.617979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:00:00.618004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.83405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c26879b5-fa8d-4063-8580-8bfc172a1e45\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-09-07T11:00:00.618074Z","caller":"traceutil/trace.go:171","msg":"trace[922891463] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5755; }","duration":"251.026315ms","start":"2026-09-07T11:00:00.367027Z","end":"2026-09-07T11:00:00.618053Z","steps":["trace[922891463] 'range keys from in-memory index tree' (duration: 250.740957ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:00.618082Z","caller":"traceutil/trace.go:171","msg":"trace[2092937850] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c26879b5-fa8d-4063-8580-8bfc172a1e45; range_end:; response_count:1; response_revision:5755; }","duration":"250.964944ms","start":"2026-09-07T11:00:00.3671Z","end":"2026-09-07T11:00:00.618065Z","steps":["trace[2092937850] 'range keys from in-memory index tree' (duration: 250.683405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:00.618888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.21121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:00:00.619141Z","caller":"traceutil/trace.go:171","msg":"trace[3000432] transaction","detail":"{read_only:false; response_revision:5756; number_of_response:1; }","duration":"248.848302ms","start":"2026-09-07T11:00:00.370267Z","end":"2026-09-07T11:00:00.619115Z","steps":["trace[3000432] 'process raft request' (duration: 119.862677ms)","trace[3000432] 'compare' (duration: 128.108379ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:00:00.619463Z","caller":"traceutil/trace.go:171","msg":"trace[576659436] transaction","detail":"{read_only:false; response_revision:5757; number_of_response:1; }","duration":"249.11439ms","start":"2026-09-07T11:00:00.370325Z","end":"2026-09-07T11:00:00.619439Z","steps":["trace[576659436] 'process raft request' (duration: 248.669177ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:00.619668Z","caller":"traceutil/trace.go:171","msg":"trace[156651697] transaction","detail":"{read_only:false; response_revision:5758; number_of_response:1; }","duration":"249.180022ms","start":"2026-09-07T11:00:00.370468Z","end":"2026-09-07T11:00:00.619648Z","steps":["trace[156651697] 'process raft request' (duration: 248.917794ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:00.619906Z","caller":"traceutil/trace.go:171","msg":"trace[34068586] transaction","detail":"{read_only:false; response_revision:5759; number_of_response:1; }","duration":"249.394587ms","start":"2026-09-07T11:00:00.370487Z","end":"2026-09-07T11:00:00.619881Z","steps":["trace[34068586] 'process raft request' (duration: 249.088769ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:00.620057Z","caller":"traceutil/trace.go:171","msg":"trace[1957932120] transaction","detail":"{read_only:false; response_revision:5760; number_of_response:1; }","duration":"249.528961ms","start":"2026-09-07T11:00:00.370514Z","end":"2026-09-07T11:00:00.620043Z","steps":["trace[1957932120] 'process raft request' (duration: 249.303625ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:00.620069Z","caller":"traceutil/trace.go:171","msg":"trace[798230629] linearizableReadLoop","detail":"{readStateIndex:5976; appliedIndex:5970; }","duration":"249.406778ms","start":"2026-09-07T11:00:00.370643Z","end":"2026-09-07T11:00:00.62005Z","steps":["trace[798230629] 'read index received' (duration: 118.592071ms)","trace[798230629] 'applied index is now lower than readState.Index' (duration: 130.813517ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:00:00.620159Z","caller":"traceutil/trace.go:171","msg":"trace[42325354] transaction","detail":"{read_only:false; response_revision:5761; number_of_response:1; }","duration":"249.632354ms","start":"2026-09-07T11:00:00.370512Z","end":"2026-09-07T11:00:00.620145Z","steps":["trace[42325354] 'process raft request' (duration: 249.436368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:00.620307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.199289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-07T11:00:00.620362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.704278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cfd0b90-6af1-48cf-8c37-3a8501ed3edc\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-07T11:00:00.620395Z","caller":"traceutil/trace.go:171","msg":"trace[1676280481] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5761; }","duration":"213.321373ms","start":"2026-09-07T11:00:00.407056Z","end":"2026-09-07T11:00:00.620377Z","steps":["trace[1676280481] 'agreement among raft nodes before linearized reading' (duration: 213.119406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:00:00.620344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.057809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xg6pg\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-07T11:00:00.620438Z","caller":"traceutil/trace.go:171","msg":"trace[172750073] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6cfd0b90-6af1-48cf-8c37-3a8501ed3edc; range_end:; response_count:1; response_revision:5761; }","duration":"249.806171ms","start":"2026-09-07T11:00:00.37061Z","end":"2026-09-07T11:00:00.620417Z","steps":["trace[172750073] 'agreement among raft nodes before linearized reading' (duration: 249.577543ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:00.620474Z","caller":"traceutil/trace.go:171","msg":"trace[345160510] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xg6pg; range_end:; response_count:1; response_revision:5761; }","duration":"207.205823ms","start":"2026-09-07T11:00:00.413251Z","end":"2026-09-07T11:00:00.620457Z","steps":["trace[345160510] 'agreement among raft nodes before linearized reading' (duration: 207.005387ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:00:07.445937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-09-07T11:00:07.545649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"98.344919ms","hash":3885613323,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":31711232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T11:00:07.545724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3885613323,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-09-07T11:00:52.707426Z","caller":"traceutil/trace.go:171","msg":"trace[1395605052] transaction","detail":"{read_only:false; response_revision:6265; number_of_response:1; }","duration":"119.588324ms","start":"2026-09-07T11:00:52.587812Z","end":"2026-09-07T11:00:52.7074Z","steps":["trace[1395605052] 'process raft request' (duration: 119.319226ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:05:07.453291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5881} {"level":"info","ts":"2026-09-07T11:05:07.549249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5881,"took":"94.012809ms","hash":255039994,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":26542080,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T11:05:07.549428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":255039994,"revision":5881,"compact-revision":2980} {"level":"info","ts":"2026-09-07T11:06:08.773516Z","caller":"traceutil/trace.go:171","msg":"trace[2098929100] linearizableReadLoop","detail":"{readStateIndex:8911; appliedIndex:8910; }","duration":"282.211591ms","start":"2026-09-07T11:06:08.491278Z","end":"2026-09-07T11:06:08.773489Z","steps":["trace[2098929100] 'read index received' (duration: 281.971385ms)","trace[2098929100] 'applied index is now lower than readState.Index' (duration: 239.156µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:06:08.773574Z","caller":"traceutil/trace.go:171","msg":"trace[465230744] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"304.737019ms","start":"2026-09-07T11:06:08.468815Z","end":"2026-09-07T11:06:08.773552Z","steps":["trace[465230744] 'process raft request' (duration: 304.492132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:08.773694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.398028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:06:08.773715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:08.468788Z","time spent":"304.843213ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:06:08.773867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.657528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-07T11:06:08.773737Z","caller":"traceutil/trace.go:171","msg":"trace[677657015] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8605; }","duration":"282.47857ms","start":"2026-09-07T11:06:08.491242Z","end":"2026-09-07T11:06:08.773721Z","steps":["trace[677657015] 'agreement among raft nodes before linearized reading' (duration: 282.402188ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:08.773961Z","caller":"traceutil/trace.go:171","msg":"trace[2147389486] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8605; }","duration":"200.791731ms","start":"2026-09-07T11:06:08.573147Z","end":"2026-09-07T11:06:08.773939Z","steps":["trace[2147389486] 'agreement among raft nodes before linearized reading' (duration: 200.589996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:08.774443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.746379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:06:08.774504Z","caller":"traceutil/trace.go:171","msg":"trace[1401319689] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8605; }","duration":"136.849912ms","start":"2026-09-07T11:06:08.637639Z","end":"2026-09-07T11:06:08.774489Z","steps":["trace[1401319689] 'agreement among raft nodes before linearized reading' (duration: 136.761179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:08.774774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.24151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T11:06:08.774826Z","caller":"traceutil/trace.go:171","msg":"trace[2102406140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8605; }","duration":"155.329582ms","start":"2026-09-07T11:06:08.619484Z","end":"2026-09-07T11:06:08.774813Z","steps":["trace[2102406140] 'agreement among raft nodes before linearized reading' (duration: 155.201299ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:08.899031Z","caller":"traceutil/trace.go:171","msg":"trace[1748418357] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"119.738411ms","start":"2026-09-07T11:06:08.779271Z","end":"2026-09-07T11:06:08.89901Z","steps":["trace[1748418357] 'process raft request' (duration: 116.955109ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:08.901802Z","caller":"traceutil/trace.go:171","msg":"trace[827405079] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"122.066349ms","start":"2026-09-07T11:06:08.779722Z","end":"2026-09-07T11:06:08.901788Z","steps":["trace[827405079] 'process raft request' (duration: 122.006747ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:08.901822Z","caller":"traceutil/trace.go:171","msg":"trace[152065255] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"122.206893ms","start":"2026-09-07T11:06:08.779594Z","end":"2026-09-07T11:06:08.901801Z","steps":["trace[152065255] 'process raft request' (duration: 122.060049ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:08.902034Z","caller":"traceutil/trace.go:171","msg":"trace[1467307766] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"121.519544ms","start":"2026-09-07T11:06:08.780496Z","end":"2026-09-07T11:06:08.902015Z","steps":["trace[1467307766] 'process raft request' (duration: 121.262766ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:21.114391Z","caller":"traceutil/trace.go:171","msg":"trace[1387927041] linearizableReadLoop","detail":"{readStateIndex:8978; appliedIndex:8977; }","duration":"139.386265ms","start":"2026-09-07T11:06:20.974985Z","end":"2026-09-07T11:06:21.114371Z","steps":["trace[1387927041] 'read index received' (duration: 139.22078ms)","trace[1387927041] 'applied index is now lower than readState.Index' (duration: 164.685µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:06:21.11449Z","caller":"traceutil/trace.go:171","msg":"trace[1471035715] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"142.63854ms","start":"2026-09-07T11:06:20.971813Z","end":"2026-09-07T11:06:21.114452Z","steps":["trace[1471035715] 'process raft request' (duration: 142.414814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:21.114568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.565851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T11:06:21.114598Z","caller":"traceutil/trace.go:171","msg":"trace[673348496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8670; }","duration":"139.631293ms","start":"2026-09-07T11:06:20.97496Z","end":"2026-09-07T11:06:21.114591Z","steps":["trace[673348496] 'agreement among raft nodes before linearized reading' (duration: 139.520029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:21.114753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.480282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-07T11:06:21.114838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.232667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T11:06:21.114874Z","caller":"traceutil/trace.go:171","msg":"trace[357029530] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8670; }","duration":"134.626837ms","start":"2026-09-07T11:06:20.980202Z","end":"2026-09-07T11:06:21.114829Z","steps":["trace[357029530] 'agreement among raft nodes before linearized reading' (duration: 134.334538ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:21.11491Z","caller":"traceutil/trace.go:171","msg":"trace[1603865541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8670; }","duration":"131.338781ms","start":"2026-09-07T11:06:20.983553Z","end":"2026-09-07T11:06:21.114892Z","steps":["trace[1603865541] 'agreement among raft nodes before linearized reading' (duration: 131.107584ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:21.254893Z","caller":"traceutil/trace.go:171","msg":"trace[1905373805] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"134.794002ms","start":"2026-09-07T11:06:21.120068Z","end":"2026-09-07T11:06:21.254862Z","steps":["trace[1905373805] 'process raft request' (duration: 134.689469ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:21.255053Z","caller":"traceutil/trace.go:171","msg":"trace[2120216515] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"135.012199ms","start":"2026-09-07T11:06:21.120005Z","end":"2026-09-07T11:06:21.255017Z","steps":["trace[2120216515] 'process raft request' (duration: 79.633659ms)","trace[2120216515] 'compare' (duration: 54.960278ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:06:21.25523Z","caller":"traceutil/trace.go:171","msg":"trace[1915734487] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"135.137122ms","start":"2026-09-07T11:06:21.120068Z","end":"2026-09-07T11:06:21.255205Z","steps":["trace[1915734487] 'process raft request' (duration: 134.747851ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:22.768496Z","caller":"traceutil/trace.go:171","msg":"trace[1278205547] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"146.02156ms","start":"2026-09-07T11:06:22.622447Z","end":"2026-09-07T11:06:22.768469Z","steps":["trace[1278205547] 'process raft request' (duration: 145.814194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:51.91269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.85377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-07T11:06:51.912725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.771964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T11:06:51.912766Z","caller":"traceutil/trace.go:171","msg":"trace[1349870451] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8885; }","duration":"492.948642ms","start":"2026-09-07T11:06:51.419798Z","end":"2026-09-07T11:06:51.912747Z","steps":["trace[1349870451] 'range keys from in-memory index tree' (duration: 492.749657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:51.912743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.906844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-07T11:06:51.912798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:51.419793Z","time spent":"492.996784ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-07T11:06:51.912784Z","caller":"traceutil/trace.go:171","msg":"trace[1048689560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8885; }","duration":"496.838287ms","start":"2026-09-07T11:06:51.415928Z","end":"2026-09-07T11:06:51.912766Z","steps":["trace[1048689560] 'range keys from in-memory index tree' (duration: 496.680992ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:06:51.912828Z","caller":"traceutil/trace.go:171","msg":"trace[844597651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8885; }","duration":"503.031718ms","start":"2026-09-07T11:06:51.409779Z","end":"2026-09-07T11:06:51.912811Z","steps":["trace[844597651] 'range keys from in-memory index tree' (duration: 502.77032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:51.912837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:51.415922Z","time spent":"496.903288ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T11:06:51.912885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:51.409765Z","time spent":"503.092739ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T11:06:51.912972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.019359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T11:06:51.913051Z","caller":"traceutil/trace.go:171","msg":"trace[130096743] transaction","detail":"{read_only:false; response_revision:8886; number_of_response:1; }","duration":"477.620963ms","start":"2026-09-07T11:06:51.435418Z","end":"2026-09-07T11:06:51.913039Z","steps":["trace[130096743] 'process raft request' (duration: 261.45905ms)","trace[130096743] 'compare' (duration: 215.447043ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:06:51.913118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:51.435407Z","time spent":"477.672155ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:06:51.913182Z","caller":"traceutil/trace.go:171","msg":"trace[2034196242] linearizableReadLoop","detail":"{readStateIndex:9203; appliedIndex:9202; }","duration":"468.138915ms","start":"2026-09-07T11:06:51.445022Z","end":"2026-09-07T11:06:51.913161Z","steps":["trace[2034196242] 'read index received' (duration: 251.874989ms)","trace[2034196242] 'applied index is now lower than readState.Index' (duration: 216.262826ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:06:51.913216Z","caller":"traceutil/trace.go:171","msg":"trace[360897166] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"435.202981ms","start":"2026-09-07T11:06:51.478Z","end":"2026-09-07T11:06:51.913203Z","steps":["trace[360897166] 'process raft request' (duration: 435.061007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:51.913278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:51.477991Z","time spent":"435.256202ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:06:51.913441Z","caller":"traceutil/trace.go:171","msg":"trace[1336062077] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"310.731214ms","start":"2026-09-07T11:06:51.602701Z","end":"2026-09-07T11:06:51.913433Z","steps":["trace[1336062077] 'process raft request' (duration: 310.461866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:51.913537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:51.602675Z","time spent":"310.792576ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:06:51.913658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.60134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-07T11:06:51.913794Z","caller":"traceutil/trace.go:171","msg":"trace[484055548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8888; }","duration":"468.751754ms","start":"2026-09-07T11:06:51.445007Z","end":"2026-09-07T11:06:51.913758Z","steps":["trace[484055548] 'agreement among raft nodes before linearized reading' (duration: 468.248399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:51.913793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.934975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:06:51.913847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:51.445002Z","time spent":"468.834676ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-07T11:06:51.913855Z","caller":"traceutil/trace.go:171","msg":"trace[812963641] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8888; }","duration":"431.007518ms","start":"2026-09-07T11:06:51.482828Z","end":"2026-09-07T11:06:51.913835Z","steps":["trace[812963641] 'agreement among raft nodes before linearized reading' (duration: 430.562084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:06:51.9139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:51.482822Z","time spent":"431.064489ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-09-07T11:06:59.426531Z","caller":"traceutil/trace.go:171","msg":"trace[1210087663] transaction","detail":"{read_only:false; response_revision:8928; number_of_response:1; }","duration":"124.854418ms","start":"2026-09-07T11:06:59.301649Z","end":"2026-09-07T11:06:59.426504Z","steps":["trace[1210087663] 'process raft request' (duration: 124.633461ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:00.364342Z","caller":"traceutil/trace.go:171","msg":"trace[588287183] transaction","detail":"{read_only:false; response_revision:8929; number_of_response:1; }","duration":"418.957305ms","start":"2026-09-07T11:06:59.945357Z","end":"2026-09-07T11:07:00.364314Z","steps":["trace[588287183] 'process raft request' (duration: 418.78238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.364688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:59.945331Z","time spent":"419.076387ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:07:00.383054Z","caller":"traceutil/trace.go:171","msg":"trace[15476103] transaction","detail":"{read_only:false; response_revision:8930; number_of_response:1; }","duration":"437.529618ms","start":"2026-09-07T11:06:59.945499Z","end":"2026-09-07T11:07:00.383028Z","steps":["trace[15476103] 'process raft request' (duration: 437.318782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.383206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:59.945461Z","time spent":"437.663003ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:07:00.383255Z","caller":"traceutil/trace.go:171","msg":"trace[1113189770] linearizableReadLoop","detail":"{readStateIndex:9249; appliedIndex:9247; }","duration":"425.144167ms","start":"2026-09-07T11:06:59.958061Z","end":"2026-09-07T11:07:00.383205Z","steps":["trace[1113189770] 'read index received' (duration: 406.082378ms)","trace[1113189770] 'applied index is now lower than readState.Index' (duration: 19.059098ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:00.383427Z","caller":"traceutil/trace.go:171","msg":"trace[1233133035] transaction","detail":"{read_only:false; response_revision:8931; number_of_response:1; }","duration":"244.0151ms","start":"2026-09-07T11:07:00.139319Z","end":"2026-09-07T11:07:00.383334Z","steps":["trace[1233133035] 'process raft request' (duration: 243.662369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.383446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.337052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:07:00.383731Z","caller":"traceutil/trace.go:171","msg":"trace[1582741826] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8931; }","duration":"425.678311ms","start":"2026-09-07T11:06:59.958031Z","end":"2026-09-07T11:07:00.383709Z","steps":["trace[1582741826] 'agreement among raft nodes before linearized reading' (duration: 425.30969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.383629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.293401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-07T11:07:00.383854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.810697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-09-07T11:07:00.383666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.62455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-07T11:07:00.38389Z","caller":"traceutil/trace.go:171","msg":"trace[1261755999] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8931; }","duration":"366.896939ms","start":"2026-09-07T11:07:00.016981Z","end":"2026-09-07T11:07:00.383878Z","steps":["trace[1261755999] 'agreement among raft nodes before linearized reading' (duration: 366.801436ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:00.383892Z","caller":"traceutil/trace.go:171","msg":"trace[1615367460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8931; }","duration":"410.917939ms","start":"2026-09-07T11:06:59.972963Z","end":"2026-09-07T11:07:00.383881Z","steps":["trace[1615367460] 'agreement among raft nodes before linearized reading' (duration: 410.547948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.383909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:00.01695Z","time spent":"366.954071ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-07T11:07:00.383931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.509021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-07T11:07:00.383854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.144103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T11:07:00.383974Z","caller":"traceutil/trace.go:171","msg":"trace[46720157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8931; }","duration":"414.319828ms","start":"2026-09-07T11:06:59.969645Z","end":"2026-09-07T11:07:00.383964Z","steps":["trace[46720157] 'agreement among raft nodes before linearized reading' (duration: 414.135633ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:00.383847Z","caller":"traceutil/trace.go:171","msg":"trace[304846280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8931; }","duration":"424.560188ms","start":"2026-09-07T11:06:59.959269Z","end":"2026-09-07T11:07:00.383829Z","steps":["trace[304846280] 'agreement among raft nodes before linearized reading' (duration: 424.177057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.384004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:59.969625Z","time spent":"414.37308ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-07T11:07:00.383976Z","caller":"traceutil/trace.go:171","msg":"trace[1252632355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8931; }","duration":"418.575363ms","start":"2026-09-07T11:06:59.965387Z","end":"2026-09-07T11:07:00.383962Z","steps":["trace[1252632355] 'agreement among raft nodes before linearized reading' (duration: 418.485971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.384039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:59.965372Z","time spent":"418.661675ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-07T11:07:00.383952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:59.958022Z","time spent":"425.780575ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:07:00.383763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.135382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:07:00.384125Z","caller":"traceutil/trace.go:171","msg":"trace[494695005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8931; }","duration":"202.558594ms","start":"2026-09-07T11:07:00.181559Z","end":"2026-09-07T11:07:00.384118Z","steps":["trace[494695005] 'agreement among raft nodes before linearized reading' (duration: 202.162362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.383938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:59.972928Z","time spent":"410.993631ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T11:07:00.384014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:59.959259Z","time spent":"424.749644ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T11:07:00.383754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.158185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-07T11:07:00.38436Z","caller":"traceutil/trace.go:171","msg":"trace[1694459695] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8931; }","duration":"413.789442ms","start":"2026-09-07T11:06:59.970558Z","end":"2026-09-07T11:07:00.384347Z","steps":["trace[1694459695] 'agreement among raft nodes before linearized reading' (duration: 413.101693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:00.384393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:06:59.970544Z","time spent":"413.839964ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-07T11:07:05.777321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.577924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:07:05.777429Z","caller":"traceutil/trace.go:171","msg":"trace[1094469681] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8973; }","duration":"132.77729ms","start":"2026-09-07T11:07:05.644629Z","end":"2026-09-07T11:07:05.777406Z","steps":["trace[1094469681] 'range keys from in-memory index tree' (duration: 132.39505ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:05.992993Z","caller":"traceutil/trace.go:171","msg":"trace[275337878] linearizableReadLoop","detail":"{readStateIndex:9294; appliedIndex:9293; }","duration":"205.179481ms","start":"2026-09-07T11:07:05.787775Z","end":"2026-09-07T11:07:05.992954Z","steps":["trace[275337878] 'read index received' (duration: 204.993696ms)","trace[275337878] 'applied index is now lower than readState.Index' (duration: 184.465µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:05.993053Z","caller":"traceutil/trace.go:171","msg":"trace[132788031] transaction","detail":"{read_only:false; response_revision:8974; number_of_response:1; }","duration":"205.536502ms","start":"2026-09-07T11:07:05.78747Z","end":"2026-09-07T11:07:05.993007Z","steps":["trace[132788031] 'process raft request' (duration: 205.283714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:05.993399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.587703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-nmmpf\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-09-07T11:07:05.993451Z","caller":"traceutil/trace.go:171","msg":"trace[1187252555] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-nmmpf; range_end:; response_count:1; response_revision:8974; }","duration":"205.709896ms","start":"2026-09-07T11:07:05.787726Z","end":"2026-09-07T11:07:05.993436Z","steps":["trace[1187252555] 'agreement among raft nodes before linearized reading' (duration: 205.395277ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:06.121466Z","caller":"traceutil/trace.go:171","msg":"trace[113517685] linearizableReadLoop","detail":"{readStateIndex:9296; appliedIndex:9294; }","duration":"128.348141ms","start":"2026-09-07T11:07:05.993088Z","end":"2026-09-07T11:07:06.121437Z","steps":["trace[113517685] 'read index received' (duration: 102.526174ms)","trace[113517685] 'applied index is now lower than readState.Index' (duration: 25.820727ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:07:06.12159Z","caller":"traceutil/trace.go:171","msg":"trace[1009949369] transaction","detail":"{read_only:false; response_revision:8976; number_of_response:1; }","duration":"322.005994ms","start":"2026-09-07T11:07:05.799571Z","end":"2026-09-07T11:07:06.121577Z","steps":["trace[1009949369] 'process raft request' (duration: 321.797758ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:06.121608Z","caller":"traceutil/trace.go:171","msg":"trace[1759769802] transaction","detail":"{read_only:false; response_revision:8975; number_of_response:1; }","duration":"329.163813ms","start":"2026-09-07T11:07:05.792394Z","end":"2026-09-07T11:07:06.121557Z","steps":["trace[1759769802] 'process raft request' (duration: 303.342167ms)","trace[1759769802] 'compare' (duration: 25.462176ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:07:06.121703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:05.799553Z","time spent":"322.065296ms","remote":"127.0.0.1:51702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:07:06.121778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:05.792366Z","time spent":"329.319188ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":938,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T11:07:06.121936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.394338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20181"} {"level":"info","ts":"2026-09-07T11:07:06.122001Z","caller":"traceutil/trace.go:171","msg":"trace[1218985101] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8976; }","duration":"333.47861ms","start":"2026-09-07T11:07:05.788495Z","end":"2026-09-07T11:07:06.121973Z","steps":["trace[1218985101] 'agreement among raft nodes before linearized reading' (duration: 333.292015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:07:06.122031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:07:05.788489Z","time spent":"333.534801ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":20203,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-07T11:07:06.122181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.62414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T11:07:06.122202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.038342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:07:06.122235Z","caller":"traceutil/trace.go:171","msg":"trace[1737622440] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8976; }","duration":"238.760024ms","start":"2026-09-07T11:07:05.88346Z","end":"2026-09-07T11:07:06.12222Z","steps":["trace[1737622440] 'agreement among raft nodes before linearized reading' (duration: 238.656851ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:07:06.122256Z","caller":"traceutil/trace.go:171","msg":"trace[1289991855] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8976; }","duration":"283.126465ms","start":"2026-09-07T11:07:05.839097Z","end":"2026-09-07T11:07:06.122223Z","steps":["trace[1289991855] 'agreement among raft nodes before linearized reading' (duration: 283.074364ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:08:03.267232Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000267c.wal"} {"level":"info","ts":"2026-09-07T11:08:10.685542Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:08:10.701779Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T11:08:10.701903Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T11:08:32.763015Z","caller":"traceutil/trace.go:171","msg":"trace[537726142] linearizableReadLoop","detail":"{readStateIndex:10148; appliedIndex:10147; }","duration":"137.406355ms","start":"2026-09-07T11:08:32.625585Z","end":"2026-09-07T11:08:32.762992Z","steps":["trace[537726142] 'read index received' (duration: 137.250731ms)","trace[537726142] 'applied index is now lower than readState.Index' (duration: 154.604µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:08:32.763055Z","caller":"traceutil/trace.go:171","msg":"trace[419663992] transaction","detail":"{read_only:false; response_revision:9805; number_of_response:1; }","duration":"392.025886ms","start":"2026-09-07T11:08:32.371008Z","end":"2026-09-07T11:08:32.763034Z","steps":["trace[419663992] 'process raft request' (duration: 391.81986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:08:32.763181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:08:32.370983Z","time spent":"392.118889ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:08:32.763244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.632153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:08:32.763277Z","caller":"traceutil/trace.go:171","msg":"trace[683993651] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:9805; }","duration":"137.739075ms","start":"2026-09-07T11:08:32.625528Z","end":"2026-09-07T11:08:32.763267Z","steps":["trace[683993651] 'agreement among raft nodes before linearized reading' (duration: 137.577131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:08:32.775157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.840971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:08:32.775256Z","caller":"traceutil/trace.go:171","msg":"trace[1579441573] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9807; }","duration":"133.955325ms","start":"2026-09-07T11:08:32.641257Z","end":"2026-09-07T11:08:32.775212Z","steps":["trace[1579441573] 'agreement among raft nodes before linearized reading' (duration: 133.832301ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:10:07.460717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8054} {"level":"info","ts":"2026-09-07T11:10:07.569181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8054,"took":"106.189294ms","hash":2057439619,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":28311552,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T11:10:07.56927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2057439619,"revision":8054,"compact-revision":5881} {"level":"info","ts":"2026-09-07T11:14:04.487501Z","caller":"traceutil/trace.go:171","msg":"trace[1145762260] transaction","detail":"{read_only:false; response_revision:12208; number_of_response:1; }","duration":"416.336095ms","start":"2026-09-07T11:14:04.071127Z","end":"2026-09-07T11:14:04.487463Z","steps":["trace[1145762260] 'process raft request' (duration: 338.817558ms)","trace[1145762260] 'compare' (duration: 76.616221ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:14:04.487606Z","caller":"traceutil/trace.go:171","msg":"trace[1496899507] transaction","detail":"{read_only:false; response_revision:12210; number_of_response:1; }","duration":"233.419336ms","start":"2026-09-07T11:14:04.254145Z","end":"2026-09-07T11:14:04.487564Z","steps":["trace[1496899507] 'process raft request' (duration: 233.372484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:14:04.487721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:14:04.071107Z","time spent":"416.493969ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:14:04.487776Z","caller":"traceutil/trace.go:171","msg":"trace[144548854] linearizableReadLoop","detail":"{readStateIndex:12630; appliedIndex:12629; }","duration":"394.767673ms","start":"2026-09-07T11:14:04.092996Z","end":"2026-09-07T11:14:04.487764Z","steps":["trace[144548854] 'read index received' (duration: 317.00484ms)","trace[144548854] 'applied index is now lower than readState.Index' (duration: 77.761493ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:14:04.487834Z","caller":"traceutil/trace.go:171","msg":"trace[1770241523] transaction","detail":"{read_only:false; response_revision:12209; number_of_response:1; }","duration":"243.487101ms","start":"2026-09-07T11:14:04.244325Z","end":"2026-09-07T11:14:04.487813Z","steps":["trace[1770241523] 'process raft request' (duration: 243.057237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:14:04.487903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.904738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:14:04.487945Z","caller":"traceutil/trace.go:171","msg":"trace[1531037113] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:12210; }","duration":"395.019022ms","start":"2026-09-07T11:14:04.092912Z","end":"2026-09-07T11:14:04.487931Z","steps":["trace[1531037113] 'agreement among raft nodes before linearized reading' (duration: 394.9287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:14:04.488017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:14:04.092882Z","time spent":"395.123205ms","remote":"127.0.0.1:59482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:14:04.488171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.428411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:14:04.488209Z","caller":"traceutil/trace.go:171","msg":"trace[1447363700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12210; }","duration":"306.519904ms","start":"2026-09-07T11:14:04.181675Z","end":"2026-09-07T11:14:04.488195Z","steps":["trace[1447363700] 'agreement among raft nodes before linearized reading' (duration: 306.459802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:14:04.488235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:14:04.181642Z","time spent":"306.585966ms","remote":"127.0.0.1:51458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T11:14:04.488306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.235603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xg6pg\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-09-07T11:14:04.488303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.331544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:14:04.488408Z","caller":"traceutil/trace.go:171","msg":"trace[1973679595] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:12210; }","duration":"220.502309ms","start":"2026-09-07T11:14:04.267881Z","end":"2026-09-07T11:14:04.488383Z","steps":["trace[1973679595] 'agreement among raft nodes before linearized reading' (duration: 220.280853ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:14:04.488411Z","caller":"traceutil/trace.go:171","msg":"trace[751994355] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xg6pg; range_end:; response_count:1; response_revision:12210; }","duration":"150.425209ms","start":"2026-09-07T11:14:04.337963Z","end":"2026-09-07T11:14:04.488389Z","steps":["trace[751994355] 'agreement among raft nodes before linearized reading' (duration: 150.188752ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:15:07.46775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10477} {"level":"info","ts":"2026-09-07T11:15:07.574365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10477,"took":"104.473355ms","hash":2004471188,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":26058752,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T11:15:07.574447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2004471188,"revision":10477,"compact-revision":8054} {"level":"info","ts":"2026-09-07T11:19:54.863399Z","caller":"traceutil/trace.go:171","msg":"trace[1221896783] transaction","detail":"{read_only:false; response_revision:14602; number_of_response:1; }","duration":"193.187653ms","start":"2026-09-07T11:19:54.670188Z","end":"2026-09-07T11:19:54.863376Z","steps":["trace[1221896783] 'process raft request' (duration: 193.05205ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:19:54.872614Z","caller":"traceutil/trace.go:171","msg":"trace[2023294663] linearizableReadLoop","detail":"{readStateIndex:15109; appliedIndex:15107; }","duration":"123.171914ms","start":"2026-09-07T11:19:54.74942Z","end":"2026-09-07T11:19:54.872592Z","steps":["trace[2023294663] 'read index received' (duration: 113.845691ms)","trace[2023294663] 'applied index is now lower than readState.Index' (duration: 9.325153ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:19:54.8727Z","caller":"traceutil/trace.go:171","msg":"trace[1981876138] transaction","detail":"{read_only:false; response_revision:14603; number_of_response:1; }","duration":"200.685082ms","start":"2026-09-07T11:19:54.671995Z","end":"2026-09-07T11:19:54.87268Z","steps":["trace[1981876138] 'process raft request' (duration: 200.451575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:19:54.872832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.36492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:19:54.872927Z","caller":"traceutil/trace.go:171","msg":"trace[740135665] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14603; }","duration":"123.557775ms","start":"2026-09-07T11:19:54.749349Z","end":"2026-09-07T11:19:54.872907Z","steps":["trace[740135665] 'agreement among raft nodes before linearized reading' (duration: 123.36877ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:07.478781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12654} {"level":"info","ts":"2026-09-07T11:20:07.565594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12654,"took":"84.346528ms","hash":2967334611,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":27496448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T11:20:07.565669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2967334611,"revision":12654,"compact-revision":10477} {"level":"info","ts":"2026-09-07T11:20:15.204936Z","caller":"traceutil/trace.go:171","msg":"trace[1439051086] transaction","detail":"{read_only:false; response_revision:14713; number_of_response:1; }","duration":"153.991617ms","start":"2026-09-07T11:20:15.050917Z","end":"2026-09-07T11:20:15.204908Z","steps":["trace[1439051086] 'process raft request' (duration: 149.222726ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:15.209308Z","caller":"traceutil/trace.go:171","msg":"trace[2122784403] transaction","detail":"{read_only:false; response_revision:14716; number_of_response:1; }","duration":"158.171238ms","start":"2026-09-07T11:20:15.051114Z","end":"2026-09-07T11:20:15.209285Z","steps":["trace[2122784403] 'process raft request' (duration: 158.134387ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:15.209414Z","caller":"traceutil/trace.go:171","msg":"trace[671426691] transaction","detail":"{read_only:false; response_revision:14714; number_of_response:1; }","duration":"158.414896ms","start":"2026-09-07T11:20:15.050962Z","end":"2026-09-07T11:20:15.209377Z","steps":["trace[671426691] 'process raft request' (duration: 158.143948ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:15.209531Z","caller":"traceutil/trace.go:171","msg":"trace[815858729] transaction","detail":"{read_only:false; response_revision:14715; number_of_response:1; }","duration":"158.430636ms","start":"2026-09-07T11:20:15.05109Z","end":"2026-09-07T11:20:15.209521Z","steps":["trace[815858729] 'process raft request' (duration: 158.127808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:20:31.644412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.43122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:20:31.644564Z","caller":"traceutil/trace.go:171","msg":"trace[1086875364] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:14814; }","duration":"166.629886ms","start":"2026-09-07T11:20:31.477879Z","end":"2026-09-07T11:20:31.644509Z","steps":["trace[1086875364] 'count revisions from in-memory index tree' (duration: 166.346758ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:20:31.644593Z","caller":"traceutil/trace.go:171","msg":"trace[2097900271] transaction","detail":"{read_only:false; response_revision:14815; number_of_response:1; }","duration":"155.319676ms","start":"2026-09-07T11:20:31.489234Z","end":"2026-09-07T11:20:31.644554Z","steps":["trace[2097900271] 'process raft request' (duration: 141.485751ms)","trace[2097900271] 'compare' (duration: 13.306549ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:20:32.588685Z","caller":"traceutil/trace.go:171","msg":"trace[852968302] linearizableReadLoop","detail":"{readStateIndex:15336; appliedIndex:15335; }","duration":"457.606172ms","start":"2026-09-07T11:20:32.131047Z","end":"2026-09-07T11:20:32.588653Z","steps":["trace[852968302] 'read index received' (duration: 457.269762ms)","trace[852968302] 'applied index is now lower than readState.Index' (duration: 335.049µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:20:32.588743Z","caller":"traceutil/trace.go:171","msg":"trace[1392211329] transaction","detail":"{read_only:false; response_revision:14819; number_of_response:1; }","duration":"477.980377ms","start":"2026-09-07T11:20:32.110722Z","end":"2026-09-07T11:20:32.588702Z","steps":["trace[1392211329] 'process raft request' (duration: 477.705779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:20:32.588942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:20:32.110688Z","time spent":"478.133651ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:20:32.589002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.848108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T11:20:32.58922Z","caller":"traceutil/trace.go:171","msg":"trace[1552418977] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14819; }","duration":"458.099976ms","start":"2026-09-07T11:20:32.131016Z","end":"2026-09-07T11:20:32.589116Z","steps":["trace[1552418977] 'agreement among raft nodes before linearized reading' (duration: 457.786007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:20:32.589385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:20:32.130998Z","time spent":"458.322693ms","remote":"127.0.0.1:55492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:20:32.589726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.121984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:20:32.589814Z","caller":"traceutil/trace.go:171","msg":"trace[2037991936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14819; }","duration":"408.236297ms","start":"2026-09-07T11:20:32.181557Z","end":"2026-09-07T11:20:32.589794Z","steps":["trace[2037991936] 'agreement among raft nodes before linearized reading' (duration: 408.114974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:20:32.589862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:20:32.181538Z","time spent":"408.313619ms","remote":"127.0.0.1:51464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T11:20:56.617578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.698822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ssjzq\" ","response":"range_response_count:1 size:20656"} {"level":"info","ts":"2026-09-07T11:20:56.61767Z","caller":"traceutil/trace.go:171","msg":"trace[505490455] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-ssjzq; range_end:; response_count:1; response_revision:15138; }","duration":"310.840566ms","start":"2026-09-07T11:20:56.30681Z","end":"2026-09-07T11:20:56.61765Z","steps":["trace[505490455] 'range keys from in-memory index tree' (duration: 310.457345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:20:56.617707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:20:56.306765Z","time spent":"310.933669ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20678,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ssjzq\" "} {"level":"info","ts":"2026-09-07T11:20:56.713633Z","caller":"traceutil/trace.go:171","msg":"trace[2002152401] linearizableReadLoop","detail":"{readStateIndex:15666; appliedIndex:15665; }","duration":"267.449027ms","start":"2026-09-07T11:20:56.446149Z","end":"2026-09-07T11:20:56.713598Z","steps":["trace[2002152401] 'read index received' (duration: 179.220305ms)","trace[2002152401] 'applied index is now lower than readState.Index' (duration: 88.227072ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:20:56.714201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.040574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b95471d-1a19-473f-ab4e-842d0cfe6c07\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-09-07T11:20:56.714261Z","caller":"traceutil/trace.go:171","msg":"trace[1164000945] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b95471d-1a19-473f-ab4e-842d0cfe6c07; range_end:; response_count:1; response_revision:15138; }","duration":"268.116786ms","start":"2026-09-07T11:20:56.446127Z","end":"2026-09-07T11:20:56.714243Z","steps":["trace[1164000945] 'agreement among raft nodes before linearized reading' (duration: 267.91341ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:22:22.724005Z","caller":"traceutil/trace.go:171","msg":"trace[470284294] transaction","detail":"{read_only:false; response_revision:15948; number_of_response:1; }","duration":"244.703021ms","start":"2026-09-07T11:22:22.479001Z","end":"2026-09-07T11:22:22.723704Z","steps":["trace[470284294] 'process raft request' (duration: 160.139946ms)","trace[470284294] 'compare' (duration: 84.404871ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:22:22.737544Z","caller":"traceutil/trace.go:171","msg":"trace[1442561140] linearizableReadLoop","detail":"{readStateIndex:16500; appliedIndex:16498; }","duration":"255.783356ms","start":"2026-09-07T11:22:22.481736Z","end":"2026-09-07T11:22:22.73752Z","steps":["trace[1442561140] 'read index received' (duration: 157.418906ms)","trace[1442561140] 'applied index is now lower than readState.Index' (duration: 98.36344ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:22:22.73793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.175627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:22:22.737948Z","caller":"traceutil/trace.go:171","msg":"trace[781886933] transaction","detail":"{read_only:false; response_revision:15949; number_of_response:1; }","duration":"256.214838ms","start":"2026-09-07T11:22:22.481701Z","end":"2026-09-07T11:22:22.737915Z","steps":["trace[781886933] 'process raft request' (duration: 255.711864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:22.737939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.405772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:22:22.737992Z","caller":"traceutil/trace.go:171","msg":"trace[1492383554] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15949; }","duration":"256.28247ms","start":"2026-09-07T11:22:22.481696Z","end":"2026-09-07T11:22:22.737978Z","steps":["trace[1492383554] 'agreement among raft nodes before linearized reading' (duration: 256.173417ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:22:22.738053Z","caller":"traceutil/trace.go:171","msg":"trace[543447417] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15949; }","duration":"164.537785ms","start":"2026-09-07T11:22:22.573469Z","end":"2026-09-07T11:22:22.738006Z","steps":["trace[543447417] 'agreement among raft nodes before linearized reading' (duration: 164.378791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:22.73833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.602825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-07T11:22:22.738381Z","caller":"traceutil/trace.go:171","msg":"trace[1542882679] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15949; }","duration":"152.684028ms","start":"2026-09-07T11:22:22.585684Z","end":"2026-09-07T11:22:22.738368Z","steps":["trace[1542882679] 'agreement among raft nodes before linearized reading' (duration: 152.518223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:22.738505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.785573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-07T11:22:22.738579Z","caller":"traceutil/trace.go:171","msg":"trace[1496013483] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15949; }","duration":"162.884536ms","start":"2026-09-07T11:22:22.575678Z","end":"2026-09-07T11:22:22.738562Z","steps":["trace[1496013483] 'agreement among raft nodes before linearized reading' (duration: 162.622889ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:22:23.070699Z","caller":"traceutil/trace.go:171","msg":"trace[1615485211] transaction","detail":"{read_only:false; response_revision:15950; number_of_response:1; }","duration":"327.604897ms","start":"2026-09-07T11:22:22.743061Z","end":"2026-09-07T11:22:23.070666Z","steps":["trace[1615485211] 'process raft request' (duration: 242.804095ms)","trace[1615485211] 'compare' (duration: 84.522033ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:22:23.070855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:22:22.743018Z","time spent":"327.74566ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:22:23.070904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.0153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-07T11:22:23.07091Z","caller":"traceutil/trace.go:171","msg":"trace[963382082] linearizableReadLoop","detail":"{readStateIndex:16501; appliedIndex:16500; }","duration":"326.736212ms","start":"2026-09-07T11:22:22.743869Z","end":"2026-09-07T11:22:23.070605Z","steps":["trace[963382082] 'read index received' (duration: 241.950061ms)","trace[963382082] 'applied index is now lower than readState.Index' (duration: 84.784411ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:22:23.070982Z","caller":"traceutil/trace.go:171","msg":"trace[1990307048] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15950; }","duration":"327.116553ms","start":"2026-09-07T11:22:22.743843Z","end":"2026-09-07T11:22:23.07096Z","steps":["trace[1990307048] 'agreement among raft nodes before linearized reading' (duration: 326.901747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:23.071028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:22:22.743835Z","time spent":"327.182645ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-07T11:22:23.07133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.347424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:22:23.071398Z","caller":"traceutil/trace.go:171","msg":"trace[1059442219] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15950; }","duration":"157.444217ms","start":"2026-09-07T11:22:22.913937Z","end":"2026-09-07T11:22:23.071382Z","steps":["trace[1059442219] 'agreement among raft nodes before linearized reading' (duration: 157.329224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:23.071461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.945818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-48wqn\" ","response":"range_response_count:1 size:22128"} {"level":"warn","ts":"2026-09-07T11:22:23.071475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.330189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-48wqn\" ","response":"range_response_count:1 size:22128"} {"level":"warn","ts":"2026-09-07T11:22:23.071481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.87375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:22:23.071506Z","caller":"traceutil/trace.go:171","msg":"trace[1756792258] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-48wqn; range_end:; response_count:1; response_revision:15950; }","duration":"109.01523ms","start":"2026-09-07T11:22:22.962477Z","end":"2026-09-07T11:22:23.071493Z","steps":["trace[1756792258] 'agreement among raft nodes before linearized reading' (duration: 108.926518ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:22:23.071521Z","caller":"traceutil/trace.go:171","msg":"trace[1586433321] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-48wqn; range_end:; response_count:1; response_revision:15950; }","duration":"176.388822ms","start":"2026-09-07T11:22:22.895118Z","end":"2026-09-07T11:22:23.071507Z","steps":["trace[1586433321] 'agreement among raft nodes before linearized reading' (duration: 176.307609ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:22:23.07155Z","caller":"traceutil/trace.go:171","msg":"trace[72745779] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15950; }","duration":"183.978493ms","start":"2026-09-07T11:22:22.887554Z","end":"2026-09-07T11:22:23.071533Z","steps":["trace[72745779] 'agreement among raft nodes before linearized reading' (duration: 183.86386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:23.071547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.142458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:22:23.071634Z","caller":"traceutil/trace.go:171","msg":"trace[1276509877] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15950; }","duration":"184.264541ms","start":"2026-09-07T11:22:22.887346Z","end":"2026-09-07T11:22:23.07161Z","steps":["trace[1276509877] 'agreement among raft nodes before linearized reading' (duration: 184.118817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:23.071679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.610526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-07T11:22:23.071695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.285574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-07T11:22:23.07173Z","caller":"traceutil/trace.go:171","msg":"trace[35298664] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15950; }","duration":"183.338755ms","start":"2026-09-07T11:22:22.888383Z","end":"2026-09-07T11:22:23.071722Z","steps":["trace[35298664] 'agreement among raft nodes before linearized reading' (duration: 183.247472ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:22:23.071728Z","caller":"traceutil/trace.go:171","msg":"trace[2002750547] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15950; }","duration":"152.699258ms","start":"2026-09-07T11:22:22.919015Z","end":"2026-09-07T11:22:23.071714Z","steps":["trace[2002750547] 'agreement among raft nodes before linearized reading' (duration: 152.564155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:23.071738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.481407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T11:22:23.071778Z","caller":"traceutil/trace.go:171","msg":"trace[127677102] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15950; }","duration":"185.742065ms","start":"2026-09-07T11:22:22.886024Z","end":"2026-09-07T11:22:23.071766Z","steps":["trace[127677102] 'agreement among raft nodes before linearized reading' (duration: 185.685443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:23.071824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.524449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-07T11:22:23.071857Z","caller":"traceutil/trace.go:171","msg":"trace[1505943380] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15950; }","duration":"156.591281ms","start":"2026-09-07T11:22:22.915256Z","end":"2026-09-07T11:22:23.071848Z","steps":["trace[1505943380] 'agreement among raft nodes before linearized reading' (duration: 156.495829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:22:23.071398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.620463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-07T11:22:23.072365Z","caller":"traceutil/trace.go:171","msg":"trace[1376718686] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15950; }","duration":"225.642763ms","start":"2026-09-07T11:22:22.846708Z","end":"2026-09-07T11:22:23.072351Z","steps":["trace[1376718686] 'agreement among raft nodes before linearized reading' (duration: 224.54705ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:25:07.485518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14676} {"level":"info","ts":"2026-09-07T11:25:07.56866Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14676,"took":"80.50659ms","hash":1976702218,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":31895552,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T11:25:07.568738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1976702218,"revision":14676,"compact-revision":12654} {"level":"info","ts":"2026-09-07T11:28:09.719663Z","caller":"traceutil/trace.go:171","msg":"trace[155311755] transaction","detail":"{read_only:false; response_revision:18523; number_of_response:1; }","duration":"189.291339ms","start":"2026-09-07T11:28:09.530342Z","end":"2026-09-07T11:28:09.719633Z","steps":["trace[155311755] 'process raft request' (duration: 188.871987ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:16.101265Z","caller":"traceutil/trace.go:171","msg":"trace[1597570809] transaction","detail":"{read_only:false; response_revision:18600; number_of_response:1; }","duration":"131.273162ms","start":"2026-09-07T11:28:15.969969Z","end":"2026-09-07T11:28:16.101243Z","steps":["trace[1597570809] 'process raft request' (duration: 106.181986ms)","trace[1597570809] 'compare' (duration: 25.004124ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:16.169092Z","caller":"traceutil/trace.go:171","msg":"trace[1186387220] transaction","detail":"{read_only:false; response_revision:18601; number_of_response:1; }","duration":"193.777336ms","start":"2026-09-07T11:28:15.975273Z","end":"2026-09-07T11:28:16.16905Z","steps":["trace[1186387220] 'process raft request' (duration: 193.461037ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:16.16929Z","caller":"traceutil/trace.go:171","msg":"trace[1363798357] transaction","detail":"{read_only:false; response_revision:18603; number_of_response:1; }","duration":"191.118328ms","start":"2026-09-07T11:28:15.978136Z","end":"2026-09-07T11:28:16.169254Z","steps":["trace[1363798357] 'process raft request' (duration: 190.896631ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:16.169354Z","caller":"traceutil/trace.go:171","msg":"trace[447611049] transaction","detail":"{read_only:false; response_revision:18602; number_of_response:1; }","duration":"194.055424ms","start":"2026-09-07T11:28:15.975285Z","end":"2026-09-07T11:28:16.169341Z","steps":["trace[447611049] 'process raft request' (duration: 193.57865ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:16.169666Z","caller":"traceutil/trace.go:171","msg":"trace[1244857041] linearizableReadLoop","detail":"{readStateIndex:19241; appliedIndex:19237; }","duration":"189.13759ms","start":"2026-09-07T11:28:15.980048Z","end":"2026-09-07T11:28:16.169185Z","steps":["trace[1244857041] 'read index received' (duration: 96.149971ms)","trace[1244857041] 'applied index is now lower than readState.Index' (duration: 92.986719ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:28:16.169761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.699677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:28:16.169794Z","caller":"traceutil/trace.go:171","msg":"trace[916999898] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:18603; }","duration":"189.77573ms","start":"2026-09-07T11:28:15.980008Z","end":"2026-09-07T11:28:16.169783Z","steps":["trace[916999898] 'agreement among raft nodes before linearized reading' (duration: 189.708268ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:16.188622Z","caller":"traceutil/trace.go:171","msg":"trace[1788959093] transaction","detail":"{read_only:false; response_revision:18604; number_of_response:1; }","duration":"106.820344ms","start":"2026-09-07T11:28:16.081767Z","end":"2026-09-07T11:28:16.188587Z","steps":["trace[1788959093] 'process raft request' (duration: 106.31106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.188907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.765352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-07T11:28:16.188962Z","caller":"traceutil/trace.go:171","msg":"trace[1544791752] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18606; }","duration":"136.865175ms","start":"2026-09-07T11:28:16.052079Z","end":"2026-09-07T11:28:16.188944Z","steps":["trace[1544791752] 'agreement among raft nodes before linearized reading' (duration: 136.704151ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:16.470082Z","caller":"traceutil/trace.go:171","msg":"trace[652783843] linearizableReadLoop","detail":"{readStateIndex:19250; appliedIndex:19248; }","duration":"157.116851ms","start":"2026-09-07T11:28:16.312942Z","end":"2026-09-07T11:28:16.470059Z","steps":["trace[652783843] 'read index received' (duration: 156.871594ms)","trace[652783843] 'applied index is now lower than readState.Index' (duration: 244.437µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:16.47041Z","caller":"traceutil/trace.go:171","msg":"trace[364223742] transaction","detail":"{read_only:false; response_revision:18611; number_of_response:1; }","duration":"190.984414ms","start":"2026-09-07T11:28:16.279411Z","end":"2026-09-07T11:28:16.470395Z","steps":["trace[364223742] 'process raft request' (duration: 190.458779ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:16.470662Z","caller":"traceutil/trace.go:171","msg":"trace[523170727] transaction","detail":"{read_only:false; response_revision:18612; number_of_response:1; }","duration":"182.425273ms","start":"2026-09-07T11:28:16.288227Z","end":"2026-09-07T11:28:16.470652Z","steps":["trace[523170727] 'process raft request' (duration: 181.778164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.470865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.266213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-09-07T11:28:16.470902Z","caller":"traceutil/trace.go:171","msg":"trace[70879862] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18612; }","duration":"191.358495ms","start":"2026-09-07T11:28:16.279536Z","end":"2026-09-07T11:28:16.470894Z","steps":["trace[70879862] 'agreement among raft nodes before linearized reading' (duration: 191.234512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.497262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.778308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-09-07T11:28:16.497378Z","caller":"traceutil/trace.go:171","msg":"trace[2144194064] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:18612; }","duration":"174.936454ms","start":"2026-09-07T11:28:16.322415Z","end":"2026-09-07T11:28:16.497352Z","steps":["trace[2144194064] 'agreement among raft nodes before linearized reading' (duration: 174.700736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.49743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.135224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-07T11:28:16.497495Z","caller":"traceutil/trace.go:171","msg":"trace[100713488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18612; }","duration":"183.231297ms","start":"2026-09-07T11:28:16.314248Z","end":"2026-09-07T11:28:16.49748Z","steps":["trace[100713488] 'agreement among raft nodes before linearized reading' (duration: 183.091813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.497262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.795144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19419"} {"level":"info","ts":"2026-09-07T11:28:16.498044Z","caller":"traceutil/trace.go:171","msg":"trace[1511709559] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18612; }","duration":"183.614358ms","start":"2026-09-07T11:28:16.314414Z","end":"2026-09-07T11:28:16.498028Z","steps":["trace[1511709559] 'agreement among raft nodes before linearized reading' (duration: 182.750803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.925007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.727013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:28:16.925088Z","caller":"traceutil/trace.go:171","msg":"trace[1728007298] linearizableReadLoop","detail":"{readStateIndex:19255; appliedIndex:19254; }","duration":"333.507066ms","start":"2026-09-07T11:28:16.591566Z","end":"2026-09-07T11:28:16.925073Z","steps":["trace[1728007298] 'read index received' (duration: 230.565366ms)","trace[1728007298] 'applied index is now lower than readState.Index' (duration: 102.94023ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:16.925154Z","caller":"traceutil/trace.go:171","msg":"trace[1636634081] transaction","detail":"{read_only:false; response_revision:18616; number_of_response:1; }","duration":"336.255196ms","start":"2026-09-07T11:28:16.588887Z","end":"2026-09-07T11:28:16.925142Z","steps":["trace[1636634081] 'process raft request' (duration: 233.329137ms)","trace[1636634081] 'compare' (duration: 102.530568ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:28:16.925192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:16.588865Z","time spent":"336.307908ms","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T11:28:16.92536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.048193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3226"} {"level":"info","ts":"2026-09-07T11:28:16.92543Z","caller":"traceutil/trace.go:171","msg":"trace[2093226762] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18616; }","duration":"333.174607ms","start":"2026-09-07T11:28:16.592238Z","end":"2026-09-07T11:28:16.925412Z","steps":["trace[2093226762] 'agreement among raft nodes before linearized reading' (duration: 332.9315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.925467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:16.592205Z","time spent":"333.253058ms","remote":"127.0.0.1:55336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":3248,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" "} {"level":"warn","ts":"2026-09-07T11:28:16.925617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.040122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-09-07T11:28:16.925648Z","caller":"traceutil/trace.go:171","msg":"trace[2111018966] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:18616; }","duration":"334.088433ms","start":"2026-09-07T11:28:16.59155Z","end":"2026-09-07T11:28:16.925638Z","steps":["trace[2111018966] 'agreement among raft nodes before linearized reading' (duration: 333.979309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.925667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:16.591533Z","time spent":"334.128604ms","remote":"127.0.0.1:51570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":719,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" "} {"level":"warn","ts":"2026-09-07T11:28:16.925662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.719766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:28:16.925691Z","caller":"traceutil/trace.go:171","msg":"trace[217220419] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18616; }","duration":"311.804839ms","start":"2026-09-07T11:28:16.613877Z","end":"2026-09-07T11:28:16.925681Z","steps":["trace[217220419] 'agreement among raft nodes before linearized reading' (duration: 311.755407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.925715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:16.613851Z","time spent":"311.858851ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-07T11:28:16.925833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.946064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:28:16.925914Z","caller":"traceutil/trace.go:171","msg":"trace[884850884] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:18616; }","duration":"312.093918ms","start":"2026-09-07T11:28:16.6138Z","end":"2026-09-07T11:28:16.925894Z","steps":["trace[884850884] 'agreement among raft nodes before linearized reading' (duration: 311.945644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:16.925956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:16.613773Z","time spent":"312.17315ms","remote":"127.0.0.1:49582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-07T11:28:17.181393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.790891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:28:17.181494Z","caller":"traceutil/trace.go:171","msg":"trace[314843414] linearizableReadLoop","detail":"{readStateIndex:19257; appliedIndex:19256; }","duration":"206.84524ms","start":"2026-09-07T11:28:16.974633Z","end":"2026-09-07T11:28:17.181479Z","steps":["trace[314843414] 'read index received' (duration: 79.780512ms)","trace[314843414] 'applied index is now lower than readState.Index' (duration: 127.063788ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:17.181558Z","caller":"traceutil/trace.go:171","msg":"trace[542304227] transaction","detail":"{read_only:false; response_revision:18618; number_of_response:1; }","duration":"221.86357ms","start":"2026-09-07T11:28:16.959683Z","end":"2026-09-07T11:28:17.181547Z","steps":["trace[542304227] 'process raft request' (duration: 94.815032ms)","trace[542304227] 'compare' (duration: 126.664887ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:28:17.181819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.133948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-heat.18d306625983c543\" ","response":"range_response_count:1 size:728"} {"level":"info","ts":"2026-09-07T11:28:17.181937Z","caller":"traceutil/trace.go:171","msg":"trace[729588412] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-heat.18d306625983c543; range_end:; response_count:1; response_revision:18618; }","duration":"207.311063ms","start":"2026-09-07T11:28:16.974604Z","end":"2026-09-07T11:28:17.181915Z","steps":["trace[729588412] 'agreement among raft nodes before linearized reading' (duration: 206.962243ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:17.544796Z","caller":"traceutil/trace.go:171","msg":"trace[1592183099] transaction","detail":"{read_only:false; response_revision:18619; number_of_response:1; }","duration":"423.969282ms","start":"2026-09-07T11:28:17.120806Z","end":"2026-09-07T11:28:17.544775Z","steps":["trace[1592183099] 'process raft request' (duration: 423.836268ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:17.544835Z","caller":"traceutil/trace.go:171","msg":"trace[1224175476] linearizableReadLoop","detail":"{readStateIndex:19258; appliedIndex:19258; }","duration":"363.279521ms","start":"2026-09-07T11:28:17.181516Z","end":"2026-09-07T11:28:17.544795Z","steps":["trace[1224175476] 'read index received' (duration: 363.262331ms)","trace[1224175476] 'applied index is now lower than readState.Index' (duration: 14.66µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:28:17.545058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:17.120781Z","time spent":"424.096584ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2226,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T11:28:17.545073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.358267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1438"} {"level":"info","ts":"2026-09-07T11:28:17.54527Z","caller":"traceutil/trace.go:171","msg":"trace[1930171348] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:18619; }","duration":"570.602664ms","start":"2026-09-07T11:28:16.974645Z","end":"2026-09-07T11:28:17.545248Z","steps":["trace[1930171348] 'agreement among raft nodes before linearized reading' (duration: 570.250144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:17.545328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:16.974623Z","time spent":"570.694977ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1460,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-09-07T11:28:17.737878Z","caller":"traceutil/trace.go:171","msg":"trace[1228986941] linearizableReadLoop","detail":"{readStateIndex:19261; appliedIndex:19258; }","duration":"192.932451ms","start":"2026-09-07T11:28:17.544927Z","end":"2026-09-07T11:28:17.73786Z","steps":["trace[1228986941] 'read index received' (duration: 102.842717ms)","trace[1228986941] 'applied index is now lower than readState.Index' (duration: 90.088444ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:17.737897Z","caller":"traceutil/trace.go:171","msg":"trace[887209306] transaction","detail":"{read_only:false; response_revision:18622; number_of_response:1; }","duration":"317.763585ms","start":"2026-09-07T11:28:17.420109Z","end":"2026-09-07T11:28:17.737873Z","steps":["trace[887209306] 'process raft request' (duration: 317.662571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:17.738011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:17.420084Z","time spent":"317.865047ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:28:17.737874Z","caller":"traceutil/trace.go:171","msg":"trace[945838631] transaction","detail":"{read_only:false; response_revision:18621; number_of_response:1; }","duration":"415.655107ms","start":"2026-09-07T11:28:17.322194Z","end":"2026-09-07T11:28:17.737849Z","steps":["trace[945838631] 'process raft request' (duration: 415.350737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:17.738085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.660356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ec5d4d6-5ec2-444b-b085-5a659028fe65\" ","response":"range_response_count:1 size:3639"} {"level":"warn","ts":"2026-09-07T11:28:17.73816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.300614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9564"} {"level":"info","ts":"2026-09-07T11:28:17.738183Z","caller":"traceutil/trace.go:171","msg":"trace[263741992] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ec5d4d6-5ec2-444b-b085-5a659028fe65; range_end:; response_count:1; response_revision:18622; }","duration":"463.789909ms","start":"2026-09-07T11:28:17.274368Z","end":"2026-09-07T11:28:17.738157Z","steps":["trace[263741992] 'agreement among raft nodes before linearized reading' (duration: 463.521171ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:17.738206Z","caller":"traceutil/trace.go:171","msg":"trace[1721162056] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18622; }","duration":"176.417917ms","start":"2026-09-07T11:28:17.561774Z","end":"2026-09-07T11:28:17.738192Z","steps":["trace[1721162056] 'agreement among raft nodes before linearized reading' (duration: 176.216011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:17.738192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:17.322171Z","time spent":"415.907365ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:28:17.738225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:17.274358Z","time spent":"463.857382ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3661,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ec5d4d6-5ec2-444b-b085-5a659028fe65\" "} {"level":"warn","ts":"2026-09-07T11:28:17.738943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.819174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-09-07T11:28:17.739016Z","caller":"traceutil/trace.go:171","msg":"trace[150524494] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:18622; }","duration":"489.922797ms","start":"2026-09-07T11:28:17.249073Z","end":"2026-09-07T11:28:17.738996Z","steps":["trace[150524494] 'agreement among raft nodes before linearized reading' (duration: 488.857066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:17.739063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:17.249057Z","time spent":"489.989168ms","remote":"127.0.0.1:51584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225424,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-09-07T11:28:17.737652Z","caller":"traceutil/trace.go:171","msg":"trace[1034828840] transaction","detail":"{read_only:false; response_revision:18620; number_of_response:1; }","duration":"553.289655ms","start":"2026-09-07T11:28:17.184328Z","end":"2026-09-07T11:28:17.737617Z","steps":["trace[1034828840] 'process raft request' (duration: 463.451019ms)","trace[1034828840] 'compare' (duration: 89.543788ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:28:17.744066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:28:17.184272Z","time spent":"559.684794ms","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:28:50.581848Z","caller":"traceutil/trace.go:171","msg":"trace[45221515] linearizableReadLoop","detail":"{readStateIndex:19638; appliedIndex:19623; }","duration":"120.63546ms","start":"2026-09-07T11:28:50.461188Z","end":"2026-09-07T11:28:50.581823Z","steps":["trace[45221515] 'read index received' (duration: 740.102µs)","trace[45221515] 'applied index is now lower than readState.Index' (duration: 119.894308ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:50.582017Z","caller":"traceutil/trace.go:171","msg":"trace[767321292] transaction","detail":"{read_only:false; response_revision:18978; number_of_response:1; }","duration":"123.371021ms","start":"2026-09-07T11:28:50.458608Z","end":"2026-09-07T11:28:50.581979Z","steps":["trace[767321292] 'process raft request' (duration: 122.99064ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.582095Z","caller":"traceutil/trace.go:171","msg":"trace[1892521281] transaction","detail":"{read_only:false; response_revision:18979; number_of_response:1; }","duration":"123.472424ms","start":"2026-09-07T11:28:50.458613Z","end":"2026-09-07T11:28:50.582085Z","steps":["trace[1892521281] 'process raft request' (duration: 123.00983ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.582509Z","caller":"traceutil/trace.go:171","msg":"trace[1739796784] transaction","detail":"{read_only:false; response_revision:18980; number_of_response:1; }","duration":"123.840004ms","start":"2026-09-07T11:28:50.458657Z","end":"2026-09-07T11:28:50.582497Z","steps":["trace[1739796784] 'process raft request' (duration: 122.993469ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.582591Z","caller":"traceutil/trace.go:171","msg":"trace[1770256993] transaction","detail":"{read_only:false; response_revision:18973; number_of_response:1; }","duration":"124.330589ms","start":"2026-09-07T11:28:50.458242Z","end":"2026-09-07T11:28:50.582572Z","steps":["trace[1770256993] 'process raft request' (duration: 69.239171ms)","trace[1770256993] 'compare' (duration: 53.872532ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:50.582632Z","caller":"traceutil/trace.go:171","msg":"trace[2020191649] transaction","detail":"{read_only:false; response_revision:18981; number_of_response:1; }","duration":"123.959538ms","start":"2026-09-07T11:28:50.458664Z","end":"2026-09-07T11:28:50.582623Z","steps":["trace[2020191649] 'process raft request' (duration: 123.00802ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.582712Z","caller":"traceutil/trace.go:171","msg":"trace[1155259044] transaction","detail":"{read_only:false; response_revision:18974; number_of_response:1; }","duration":"124.470713ms","start":"2026-09-07T11:28:50.458229Z","end":"2026-09-07T11:28:50.582699Z","steps":["trace[1155259044] 'process raft request' (duration: 123.255317ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.582753Z","caller":"traceutil/trace.go:171","msg":"trace[133635179] transaction","detail":"{read_only:false; response_revision:18982; number_of_response:1; }","duration":"124.070271ms","start":"2026-09-07T11:28:50.458675Z","end":"2026-09-07T11:28:50.582745Z","steps":["trace[133635179] 'process raft request' (duration: 123.02079ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.582822Z","caller":"traceutil/trace.go:171","msg":"trace[1567071183] transaction","detail":"{read_only:false; response_revision:18983; number_of_response:1; }","duration":"124.119632ms","start":"2026-09-07T11:28:50.458695Z","end":"2026-09-07T11:28:50.582814Z","steps":["trace[1567071183] 'process raft request' (duration: 123.03111ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.582904Z","caller":"traceutil/trace.go:171","msg":"trace[1632241993] transaction","detail":"{read_only:false; response_revision:18984; number_of_response:1; }","duration":"124.184275ms","start":"2026-09-07T11:28:50.45871Z","end":"2026-09-07T11:28:50.582894Z","steps":["trace[1632241993] 'process raft request' (duration: 123.041111ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.582963Z","caller":"traceutil/trace.go:171","msg":"trace[2143515524] transaction","detail":"{read_only:false; response_revision:18975; number_of_response:1; }","duration":"124.655418ms","start":"2026-09-07T11:28:50.458289Z","end":"2026-09-07T11:28:50.582945Z","steps":["trace[2143515524] 'process raft request' (duration: 123.231246ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.583056Z","caller":"traceutil/trace.go:171","msg":"trace[1105156415] transaction","detail":"{read_only:false; response_revision:18985; number_of_response:1; }","duration":"124.307858ms","start":"2026-09-07T11:28:50.458733Z","end":"2026-09-07T11:28:50.583041Z","steps":["trace[1105156415] 'process raft request' (duration: 123.040651ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.583137Z","caller":"traceutil/trace.go:171","msg":"trace[176761112] transaction","detail":"{read_only:false; response_revision:18976; number_of_response:1; }","duration":"124.696339ms","start":"2026-09-07T11:28:50.458426Z","end":"2026-09-07T11:28:50.583122Z","steps":["trace[176761112] 'process raft request' (duration: 123.120643ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.583217Z","caller":"traceutil/trace.go:171","msg":"trace[1375048616] transaction","detail":"{read_only:false; response_revision:18986; number_of_response:1; }","duration":"124.452212ms","start":"2026-09-07T11:28:50.458755Z","end":"2026-09-07T11:28:50.583207Z","steps":["trace[1375048616] 'process raft request' (duration: 123.03997ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.583299Z","caller":"traceutil/trace.go:171","msg":"trace[685464410] transaction","detail":"{read_only:false; response_revision:18977; number_of_response:1; }","duration":"124.801912ms","start":"2026-09-07T11:28:50.458483Z","end":"2026-09-07T11:28:50.583285Z","steps":["trace[685464410] 'process raft request' (duration: 123.088632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:50.582057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.849356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-09-07T11:28:50.58339Z","caller":"traceutil/trace.go:171","msg":"trace[508028959] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:18986; }","duration":"122.220506ms","start":"2026-09-07T11:28:50.461154Z","end":"2026-09-07T11:28:50.583374Z","steps":["trace[508028959] 'agreement among raft nodes before linearized reading' (duration: 120.758934ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.86219Z","caller":"traceutil/trace.go:171","msg":"trace[1239249309] transaction","detail":"{read_only:false; response_revision:18994; number_of_response:1; }","duration":"101.104187ms","start":"2026-09-07T11:28:50.76105Z","end":"2026-09-07T11:28:50.862154Z","steps":["trace[1239249309] 'process raft request' (duration: 100.85226ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.862273Z","caller":"traceutil/trace.go:171","msg":"trace[1331938573] transaction","detail":"{read_only:false; response_revision:18995; number_of_response:1; }","duration":"100.780177ms","start":"2026-09-07T11:28:50.761451Z","end":"2026-09-07T11:28:50.862231Z","steps":["trace[1331938573] 'process raft request' (duration: 100.609332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:50.862601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.542979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fwvpn\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-09-07T11:28:50.862694Z","caller":"traceutil/trace.go:171","msg":"trace[1184150020] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fwvpn; range_end:; response_count:1; response_revision:18998; }","duration":"103.667182ms","start":"2026-09-07T11:28:50.759004Z","end":"2026-09-07T11:28:50.862671Z","steps":["trace[1184150020] 'agreement among raft nodes before linearized reading' (duration: 103.437334ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:50.90269Z","caller":"traceutil/trace.go:171","msg":"trace[1092109093] transaction","detail":"{read_only:false; response_revision:18999; number_of_response:1; }","duration":"127.245015ms","start":"2026-09-07T11:28:50.775415Z","end":"2026-09-07T11:28:50.90266Z","steps":["trace[1092109093] 'process raft request' (duration: 127.057339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:50.902885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.874497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-09-07T11:28:50.902973Z","caller":"traceutil/trace.go:171","msg":"trace[945729968] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:18999; }","duration":"119.999791ms","start":"2026-09-07T11:28:50.782953Z","end":"2026-09-07T11:28:50.902953Z","steps":["trace[945729968] 'agreement among raft nodes before linearized reading' (duration: 119.798985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:51.054174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.971149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-09-07T11:28:51.054409Z","caller":"traceutil/trace.go:171","msg":"trace[87441925] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:19004; }","duration":"121.257438ms","start":"2026-09-07T11:28:50.933117Z","end":"2026-09-07T11:28:51.054374Z","steps":["trace[87441925] 'agreement among raft nodes before linearized reading' (duration: 28.928359ms)","trace[87441925] 'range keys from in-memory index tree' (duration: 91.950027ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:28:51.076184Z","caller":"traceutil/trace.go:171","msg":"trace[351553115] transaction","detail":"{read_only:false; response_revision:19006; number_of_response:1; }","duration":"101.349383ms","start":"2026-09-07T11:28:50.974821Z","end":"2026-09-07T11:28:51.07617Z","steps":["trace[351553115] 'process raft request' (duration: 101.22584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:51.076587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.830594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:28:51.076612Z","caller":"traceutil/trace.go:171","msg":"trace[2001807247] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:19008; }","duration":"105.876625ms","start":"2026-09-07T11:28:50.970728Z","end":"2026-09-07T11:28:51.076605Z","steps":["trace[2001807247] 'agreement among raft nodes before linearized reading' (duration: 105.821264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:28:51.076754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.155395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-09-07T11:28:51.076865Z","caller":"traceutil/trace.go:171","msg":"trace[460485687] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:19008; }","duration":"106.288018ms","start":"2026-09-07T11:28:50.97056Z","end":"2026-09-07T11:28:51.076848Z","steps":["trace[460485687] 'agreement among raft nodes before linearized reading' (duration: 105.899146ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:51.076739Z","caller":"traceutil/trace.go:171","msg":"trace[979147032] transaction","detail":"{read_only:false; response_revision:19007; number_of_response:1; }","duration":"101.490197ms","start":"2026-09-07T11:28:50.97521Z","end":"2026-09-07T11:28:51.0767Z","steps":["trace[979147032] 'process raft request' (duration: 100.931681ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:28:51.076775Z","caller":"traceutil/trace.go:171","msg":"trace[421029915] transaction","detail":"{read_only:false; response_revision:19008; number_of_response:1; }","duration":"100.555331ms","start":"2026-09-07T11:28:50.976191Z","end":"2026-09-07T11:28:51.076746Z","steps":["trace[421029915] 'process raft request' (duration: 100.192409ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:29:21.95994Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T11:29:21.970674Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T11:29:21.970809Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T11:30:07.496131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17140} {"level":"info","ts":"2026-09-07T11:30:07.613848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17140,"took":"111.867003ms","hash":1691828127,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":34242560,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-07T11:30:07.613916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1691828127,"revision":17140,"compact-revision":14676} {"level":"info","ts":"2026-09-07T11:33:53.280948Z","caller":"traceutil/trace.go:171","msg":"trace[1758009334] transaction","detail":"{read_only:false; response_revision:21192; number_of_response:1; }","duration":"201.344832ms","start":"2026-09-07T11:33:53.079555Z","end":"2026-09-07T11:33:53.2809Z","steps":["trace[1758009334] 'process raft request' (duration: 201.066994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:33:53.523772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.913338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:33:53.523887Z","caller":"traceutil/trace.go:171","msg":"trace[694405814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21192; }","duration":"242.063274ms","start":"2026-09-07T11:33:53.281791Z","end":"2026-09-07T11:33:53.523854Z","steps":["trace[694405814] 'range keys from in-memory index tree' (duration: 241.846606ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:33:53.558128Z","caller":"traceutil/trace.go:171","msg":"trace[308054227] transaction","detail":"{read_only:false; response_revision:21193; number_of_response:1; }","duration":"101.097298ms","start":"2026-09-07T11:33:53.457003Z","end":"2026-09-07T11:33:53.5581Z","steps":["trace[308054227] 'process raft request' (duration: 100.920653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:33:53.87546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.200171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T11:33:53.875594Z","caller":"traceutil/trace.go:171","msg":"trace[943155723] linearizableReadLoop","detail":"{readStateIndex:21918; appliedIndex:21917; }","duration":"312.2922ms","start":"2026-09-07T11:33:53.563247Z","end":"2026-09-07T11:33:53.875539Z","steps":["trace[943155723] 'read index received' (duration: 130.016855ms)","trace[943155723] 'applied index is now lower than readState.Index' (duration: 182.274255ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:33:53.875724Z","caller":"traceutil/trace.go:171","msg":"trace[980263569] transaction","detail":"{read_only:false; response_revision:21194; number_of_response:1; }","duration":"347.943612ms","start":"2026-09-07T11:33:53.52772Z","end":"2026-09-07T11:33:53.875663Z","steps":["trace[980263569] 'process raft request' (duration: 165.438851ms)","trace[980263569] 'compare' (duration: 181.859492ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:33:53.875804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.551617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-07T11:33:53.875867Z","caller":"traceutil/trace.go:171","msg":"trace[1029800080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21194; }","duration":"312.64655ms","start":"2026-09-07T11:33:53.563209Z","end":"2026-09-07T11:33:53.875856Z","steps":["trace[1029800080] 'agreement among raft nodes before linearized reading' (duration: 312.492545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:33:53.875869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.737777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-07T11:33:53.875946Z","caller":"traceutil/trace.go:171","msg":"trace[1853575364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21194; }","duration":"305.864511ms","start":"2026-09-07T11:33:53.570064Z","end":"2026-09-07T11:33:53.875928Z","steps":["trace[1853575364] 'agreement among raft nodes before linearized reading' (duration: 305.673215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:33:53.87599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:33:53.57004Z","time spent":"305.940003ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T11:33:53.876073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.502385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-09-07T11:33:53.875891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:33:53.527696Z","time spent":"348.107876ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:33:53.87593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:33:53.563193Z","time spent":"312.700951ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T11:33:53.876224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.591729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T11:33:53.876281Z","caller":"traceutil/trace.go:171","msg":"trace[30256133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21194; }","duration":"134.662931ms","start":"2026-09-07T11:33:53.741586Z","end":"2026-09-07T11:33:53.876249Z","steps":["trace[30256133] 'agreement among raft nodes before linearized reading' (duration: 134.558978ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:33:53.876106Z","caller":"traceutil/trace.go:171","msg":"trace[1479988729] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:21194; }","duration":"272.568647ms","start":"2026-09-07T11:33:53.603529Z","end":"2026-09-07T11:33:53.876098Z","steps":["trace[1479988729] 'agreement among raft nodes before linearized reading' (duration: 272.454773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:33:53.876739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.585881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T11:33:53.876794Z","caller":"traceutil/trace.go:171","msg":"trace[1144053177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21194; }","duration":"131.659603ms","start":"2026-09-07T11:33:53.745121Z","end":"2026-09-07T11:33:53.87678Z","steps":["trace[1144053177] 'agreement among raft nodes before linearized reading' (duration: 130.809029ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:33:54.235236Z","caller":"traceutil/trace.go:171","msg":"trace[1214876305] transaction","detail":"{read_only:false; response_revision:21196; number_of_response:1; }","duration":"353.327549ms","start":"2026-09-07T11:33:53.881871Z","end":"2026-09-07T11:33:54.235198Z","steps":["trace[1214876305] 'process raft request' (duration: 353.067211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:33:54.235411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:33:53.881858Z","time spent":"353.466443ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:33:54.235602Z","caller":"traceutil/trace.go:171","msg":"trace[225501954] transaction","detail":"{read_only:false; response_revision:21197; number_of_response:1; }","duration":"353.505175ms","start":"2026-09-07T11:33:53.881965Z","end":"2026-09-07T11:33:54.23547Z","steps":["trace[225501954] 'process raft request' (duration: 353.179554ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:33:54.235753Z","caller":"traceutil/trace.go:171","msg":"trace[1429740543] linearizableReadLoop","detail":"{readStateIndex:21923; appliedIndex:21919; }","duration":"148.439893ms","start":"2026-09-07T11:33:54.087296Z","end":"2026-09-07T11:33:54.235736Z","steps":["trace[1429740543] 'read index received' (duration: 147.542547ms)","trace[1429740543] 'applied index is now lower than readState.Index' (duration: 896.576µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:33:54.23578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:33:53.881941Z","time spent":"353.749702ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:33:54.235863Z","caller":"traceutil/trace.go:171","msg":"trace[1301777987] transaction","detail":"{read_only:false; response_revision:21199; number_of_response:1; }","duration":"351.68855ms","start":"2026-09-07T11:33:53.884161Z","end":"2026-09-07T11:33:54.23585Z","steps":["trace[1301777987] 'process raft request' (duration: 351.531246ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:33:54.23587Z","caller":"traceutil/trace.go:171","msg":"trace[1362548171] transaction","detail":"{read_only:false; response_revision:21198; number_of_response:1; }","duration":"352.541817ms","start":"2026-09-07T11:33:53.883274Z","end":"2026-09-07T11:33:54.235816Z","steps":["trace[1362548171] 'process raft request' (duration: 352.093634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:33:54.235933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:33:53.884148Z","time spent":"351.750403ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T11:33:54.235971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.633639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-07T11:33:54.236011Z","caller":"traceutil/trace.go:171","msg":"trace[578884439] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21199; }","duration":"148.758313ms","start":"2026-09-07T11:33:54.087238Z","end":"2026-09-07T11:33:54.235997Z","steps":["trace[578884439] 'agreement among raft nodes before linearized reading' (duration: 148.562447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:33:54.236073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T11:33:53.88326Z","time spent":"352.706981ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T11:35:03.817747Z","caller":"traceutil/trace.go:171","msg":"trace[963425175] linearizableReadLoop","detail":"{readStateIndex:22588; appliedIndex:22587; }","duration":"156.572442ms","start":"2026-09-07T11:35:03.661149Z","end":"2026-09-07T11:35:03.817721Z","steps":["trace[963425175] 'read index received' (duration: 156.393127ms)","trace[963425175] 'applied index is now lower than readState.Index' (duration: 178.615µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:35:03.817911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.718226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:35:03.817945Z","caller":"traceutil/trace.go:171","msg":"trace[1463187126] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:21838; }","duration":"156.835819ms","start":"2026-09-07T11:35:03.661098Z","end":"2026-09-07T11:35:03.817933Z","steps":["trace[1463187126] 'agreement among raft nodes before linearized reading' (duration: 156.718905ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:35:03.818264Z","caller":"traceutil/trace.go:171","msg":"trace[1744867851] transaction","detail":"{read_only:false; response_revision:21838; number_of_response:1; }","duration":"200.452166ms","start":"2026-09-07T11:35:03.617798Z","end":"2026-09-07T11:35:03.81825Z","steps":["trace[1744867851] 'process raft request' (duration: 199.810877ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:35:04.795494Z","caller":"traceutil/trace.go:171","msg":"trace[283589575] transaction","detail":"{read_only:false; response_revision:21843; number_of_response:1; }","duration":"135.645699ms","start":"2026-09-07T11:35:04.659822Z","end":"2026-09-07T11:35:04.795467Z","steps":["trace[283589575] 'process raft request' (duration: 135.459134ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:35:07.505602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19682} {"level":"info","ts":"2026-09-07T11:35:07.635925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19682,"took":"126.807141ms","hash":1014857408,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":29921280,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T11:35:07.635979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1014857408,"revision":19682,"compact-revision":17140} {"level":"info","ts":"2026-09-07T11:37:42.440454Z","caller":"traceutil/trace.go:171","msg":"trace[1398996487] transaction","detail":"{read_only:false; response_revision:23128; number_of_response:1; }","duration":"121.811045ms","start":"2026-09-07T11:37:42.318602Z","end":"2026-09-07T11:37:42.440413Z","steps":["trace[1398996487] 'process raft request' (duration: 121.696831ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:37:42.441683Z","caller":"traceutil/trace.go:171","msg":"trace[578046705] linearizableReadLoop","detail":"{readStateIndex:23918; appliedIndex:23916; }","duration":"111.682798ms","start":"2026-09-07T11:37:42.329977Z","end":"2026-09-07T11:37:42.44166Z","steps":["trace[578046705] 'read index received' (duration: 110.331209ms)","trace[578046705] 'applied index is now lower than readState.Index' (duration: 1.350609ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:37:42.441828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.832372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/cert-manager-test/test-selfsigned\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T11:37:42.441874Z","caller":"traceutil/trace.go:171","msg":"trace[326718254] range","detail":"{range_begin:/registry/cert-manager.io/issuers/cert-manager-test/test-selfsigned; range_end:; response_count:0; response_revision:23131; }","duration":"111.926485ms","start":"2026-09-07T11:37:42.329935Z","end":"2026-09-07T11:37:42.441861Z","steps":["trace[326718254] 'agreement among raft nodes before linearized reading' (duration: 111.818602ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:37:42.442138Z","caller":"traceutil/trace.go:171","msg":"trace[671451658] transaction","detail":"{read_only:false; response_revision:23129; number_of_response:1; }","duration":"123.488783ms","start":"2026-09-07T11:37:42.318618Z","end":"2026-09-07T11:37:42.442107Z","steps":["trace[671451658] 'process raft request' (duration: 122.799673ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:40:07.515195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21866} {"level":"info","ts":"2026-09-07T11:40:07.645605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21866,"took":"124.230495ms","hash":638733687,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":44109824,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-07T11:40:07.645716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":638733687,"revision":21866,"compact-revision":19682} {"level":"info","ts":"2026-09-07T11:42:51.905155Z","caller":"traceutil/trace.go:171","msg":"trace[734535598] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"110.315838ms","start":"2026-09-07T11:42:51.7948Z","end":"2026-09-07T11:42:51.905116Z","steps":["trace[734535598] 'process raft request' (duration: 85.310516ms)","trace[734535598] 'compare' (duration: 24.804576ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:43:17.854702Z","caller":"traceutil/trace.go:171","msg":"trace[1077929621] transaction","detail":"{read_only:false; response_revision:26396; number_of_response:1; }","duration":"105.799326ms","start":"2026-09-07T11:43:17.748874Z","end":"2026-09-07T11:43:17.854673Z","steps":["trace[1077929621] 'process raft request' (duration: 105.615401ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:43:17.990289Z","caller":"traceutil/trace.go:171","msg":"trace[1853084037] transaction","detail":"{read_only:false; response_revision:26397; number_of_response:1; }","duration":"113.523392ms","start":"2026-09-07T11:43:17.87674Z","end":"2026-09-07T11:43:17.990264Z","steps":["trace[1853084037] 'process raft request' (duration: 113.10388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:43:18.013115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.603723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T11:43:18.013209Z","caller":"traceutil/trace.go:171","msg":"trace[305257517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26398; }","duration":"100.755058ms","start":"2026-09-07T11:43:17.912428Z","end":"2026-09-07T11:43:18.013183Z","steps":["trace[305257517] 'agreement among raft nodes before linearized reading' (duration: 100.51207ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:43:18.126291Z","caller":"traceutil/trace.go:171","msg":"trace[316659740] linearizableReadLoop","detail":"{readStateIndex:27274; appliedIndex:27272; }","duration":"101.512189ms","start":"2026-09-07T11:43:18.02472Z","end":"2026-09-07T11:43:18.126232Z","steps":["trace[316659740] 'read index received' (duration: 73.049587ms)","trace[316659740] 'applied index is now lower than readState.Index' (duration: 28.461322ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:43:18.126423Z","caller":"traceutil/trace.go:171","msg":"trace[1282085701] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"109.083902ms","start":"2026-09-07T11:43:18.017315Z","end":"2026-09-07T11:43:18.126399Z","steps":["trace[1282085701] 'process raft request' (duration: 108.788944ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:43:18.126411Z","caller":"traceutil/trace.go:171","msg":"trace[1441088052] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"130.314983ms","start":"2026-09-07T11:43:17.996055Z","end":"2026-09-07T11:43:18.12637Z","steps":["trace[1441088052] 'process raft request' (duration: 101.620444ms)","trace[1441088052] 'compare' (duration: 28.197015ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T11:43:18.126721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.962844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-07T11:43:18.12678Z","caller":"traceutil/trace.go:171","msg":"trace[537218391] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26401; }","duration":"102.085328ms","start":"2026-09-07T11:43:18.024678Z","end":"2026-09-07T11:43:18.126764Z","steps":["trace[537218391] 'agreement among raft nodes before linearized reading' (duration: 101.823709ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:43:32.172417Z","caller":"traceutil/trace.go:171","msg":"trace[1527691393] linearizableReadLoop","detail":"{readStateIndex:27376; appliedIndex:27375; }","duration":"106.27755ms","start":"2026-09-07T11:43:32.066106Z","end":"2026-09-07T11:43:32.172383Z","steps":["trace[1527691393] 'read index received' (duration: 106.096625ms)","trace[1527691393] 'applied index is now lower than readState.Index' (duration: 179.845µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T11:43:32.172567Z","caller":"traceutil/trace.go:171","msg":"trace[1100912184] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"110.135682ms","start":"2026-09-07T11:43:32.062343Z","end":"2026-09-07T11:43:32.172479Z","steps":["trace[1100912184] 'process raft request' (duration: 109.894135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T11:43:32.172675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.546868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T11:43:32.172734Z","caller":"traceutil/trace.go:171","msg":"trace[1323900789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26499; }","duration":"106.63702ms","start":"2026-09-07T11:43:32.066079Z","end":"2026-09-07T11:43:32.172716Z","steps":["trace[1323900789] 'agreement among raft nodes before linearized reading' (duration: 106.429074ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T11:45:07.535262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24996} {"level":"info","ts":"2026-09-07T11:45:07.68688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24996,"took":"146.617871ms","hash":3302277951,"current-db-size-bytes":54849536,"current-db-size":"55 MB","current-db-size-in-use-bytes":30597120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T11:45:07.686934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3302277951,"revision":24996,"compact-revision":21866}