{"level":"warn","ts":"2026-08-16T12:53:09.320056Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:53:09.320151Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-08-16T12:53:09.320232Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:53:09.320239Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-08-16T12:53:09.320261Z","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-08-16T12:53:09.320897Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-08-16T12:53:09.320972Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-08-16T12:53:09.324152Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.441644ms"} {"level":"info","ts":"2026-08-16T12:53:09.328617Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-08-16T12:53:09.328674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:53:09.328695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-08-16T12:53:09.328706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-16T12:53:09.328722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-08-16T12:53:09.328762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-08-16T12:53:09.333881Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:53:09.336454Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:53:09.338113Z","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-08-16T12:53:09.341196Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-16T12:53:09.341578Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-16T12:53:09.341669Z","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-08-16T12:53:09.341862Z","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-08-16T12:53:09.341891Z","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-08-16T12:53:09.342417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-08-16T12:53:09.342575Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-08-16T12:53:09.346115Z","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-08-16T12:53:09.346282Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-08-16T12:53:09.346323Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-08-16T12:53:09.346792Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-16T12:53:09.346883Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:53:10.329825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:53:10.329917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:53:10.330039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-08-16T12:53:10.330072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:53:10.330088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-08-16T12:53:10.330112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-08-16T12:53:10.330129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-08-16T12:53:10.331269Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:10.332232Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-08-16T12:53:10.332295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:53:10.332351Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:53:10.332593Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:53:10.332647Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-16T12:53:10.3328Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:10.333024Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:10.333092Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:10.335379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-08-16T12:53:10.335389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:53:51.842207Z","caller":"traceutil/trace.go:171","msg":"trace[1879956264] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"246.437365ms","start":"2026-08-16T12:53:51.595741Z","end":"2026-08-16T12:53:51.842178Z","steps":["trace[1879956264] 'process raft request' (duration: 245.858979ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:52.466219Z","caller":"traceutil/trace.go:171","msg":"trace[1925286691] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"403.414881ms","start":"2026-08-16T12:53:52.062777Z","end":"2026-08-16T12:53:52.466192Z","steps":["trace[1925286691] 'read index received' (duration: 403.272727ms)","trace[1925286691] 'applied index is now lower than readState.Index' (duration: 141.124µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:52.466253Z","caller":"traceutil/trace.go:171","msg":"trace[637701699] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"561.050114ms","start":"2026-08-16T12:53:51.905178Z","end":"2026-08-16T12:53:52.466228Z","steps":["trace[637701699] 'process raft request' (duration: 560.832358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.46651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.709868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vfnc8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-16T12:53:52.466588Z","caller":"traceutil/trace.go:171","msg":"trace[1910500828] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vfnc8; range_end:; response_count:1; response_revision:632; }","duration":"403.843242ms","start":"2026-08-16T12:53:52.062728Z","end":"2026-08-16T12:53:52.466572Z","steps":["trace[1910500828] 'agreement among raft nodes before linearized reading' (duration: 403.590715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.466632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.062711Z","time spent":"403.911633ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vfnc8\" "} {"level":"warn","ts":"2026-08-16T12:53:52.466797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.905158Z","time spent":"561.158747ms","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:53:52.487857Z","caller":"traceutil/trace.go:171","msg":"trace[271415319] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"129.699315ms","start":"2026-08-16T12:53:52.358132Z","end":"2026-08-16T12:53:52.487831Z","steps":["trace[271415319] 'process raft request' (duration: 129.475839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.487974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.41911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-16T12:53:52.487944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.443239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-16T12:53:52.48802Z","caller":"traceutil/trace.go:171","msg":"trace[1534468197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"403.496282ms","start":"2026-08-16T12:53:52.084511Z","end":"2026-08-16T12:53:52.488007Z","steps":["trace[1534468197] 'agreement among raft nodes before linearized reading' (duration: 403.345498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.488048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.084495Z","time spent":"403.547903ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-16T12:53:52.488052Z","caller":"traceutil/trace.go:171","msg":"trace[1932716813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"208.579583ms","start":"2026-08-16T12:53:52.279445Z","end":"2026-08-16T12:53:52.488025Z","steps":["trace[1932716813] 'agreement among raft nodes before linearized reading' (duration: 208.349267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.488188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.026393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-16T12:53:52.488308Z","caller":"traceutil/trace.go:171","msg":"trace[1461909204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"337.170717ms","start":"2026-08-16T12:53:52.151088Z","end":"2026-08-16T12:53:52.488258Z","steps":["trace[1461909204] 'agreement among raft nodes before linearized reading' (duration: 336.823058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.488392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.151067Z","time spent":"337.309431ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-16T12:53:52.627065Z","caller":"traceutil/trace.go:171","msg":"trace[244846536] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"139.316319ms","start":"2026-08-16T12:53:52.487724Z","end":"2026-08-16T12:53:52.62704Z","steps":["trace[244846536] 'read index received' (duration: 118.957559ms)","trace[244846536] 'applied index is now lower than readState.Index' (duration: 20.35766ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:52.627269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.831138ms","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-08-16T12:53:52.627301Z","caller":"traceutil/trace.go:171","msg":"trace[1340882210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"149.910879ms","start":"2026-08-16T12:53:52.477379Z","end":"2026-08-16T12:53:52.62729Z","steps":["trace[1340882210] 'agreement among raft nodes before linearized reading' (duration: 149.749825ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:52.627641Z","caller":"traceutil/trace.go:171","msg":"trace[1387345599] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"147.661299ms","start":"2026-08-16T12:53:52.479937Z","end":"2026-08-16T12:53:52.627599Z","steps":["trace[1387345599] 'process raft request' (duration: 126.713415ms)","trace[1387345599] 'compare' (duration: 20.257986ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:52.627685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.9547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:53:52.627744Z","caller":"traceutil/trace.go:171","msg":"trace[507728200] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:634; }","duration":"150.027873ms","start":"2026-08-16T12:53:52.477697Z","end":"2026-08-16T12:53:52.627725Z","steps":["trace[507728200] 'agreement among raft nodes before linearized reading' (duration: 149.94174ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:52.911994Z","caller":"traceutil/trace.go:171","msg":"trace[346588165] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"421.289904ms","start":"2026-08-16T12:53:52.490678Z","end":"2026-08-16T12:53:52.911968Z","steps":["trace[346588165] 'process raft request' (duration: 397.893205ms)","trace[346588165] 'compare' (duration: 23.0427ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:52.912051Z","caller":"traceutil/trace.go:171","msg":"trace[815934083] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"420.195815ms","start":"2026-08-16T12:53:52.491818Z","end":"2026-08-16T12:53:52.912014Z","steps":["trace[815934083] 'process raft request' (duration: 420.058242ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:52.91218Z","caller":"traceutil/trace.go:171","msg":"trace[2137116659] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"280.390283ms","start":"2026-08-16T12:53:52.631765Z","end":"2026-08-16T12:53:52.912156Z","steps":["trace[2137116659] 'process raft request' (duration: 280.311671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.912407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.491805Z","time spent":"420.324188ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:53:52.912406Z","caller":"traceutil/trace.go:171","msg":"trace[1090164028] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"420.340429ms","start":"2026-08-16T12:53:52.492039Z","end":"2026-08-16T12:53:52.912379Z","steps":["trace[1090164028] 'process raft request' (duration: 419.932278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.912534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.490655Z","time spent":"421.405268ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:53:52.912678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.492006Z","time spent":"420.536704ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:54:56.678019Z","caller":"traceutil/trace.go:171","msg":"trace[1530600944] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"171.225508ms","start":"2026-08-16T12:54:56.506733Z","end":"2026-08-16T12:54:56.677958Z","steps":["trace[1530600944] 'process raft request' (duration: 134.518498ms)","trace[1530600944] 'compare' (duration: 36.527336ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:19.648636Z","caller":"traceutil/trace.go:171","msg":"trace[1466573584] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"121.125532ms","start":"2026-08-16T12:55:19.527472Z","end":"2026-08-16T12:55:19.648598Z","steps":["trace[1466573584] 'process raft request' (duration: 79.727674ms)","trace[1466573584] 'compare' (duration: 41.252114ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:19.654253Z","caller":"traceutil/trace.go:171","msg":"trace[1396175633] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1932; }","duration":"118.359186ms","start":"2026-08-16T12:55:19.535878Z","end":"2026-08-16T12:55:19.654237Z","steps":["trace[1396175633] 'read index received' (duration: 71.338656ms)","trace[1396175633] 'applied index is now lower than readState.Index' (duration: 47.01917ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:19.6544Z","caller":"traceutil/trace.go:171","msg":"trace[1471440528] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"126.509707ms","start":"2026-08-16T12:55:19.527855Z","end":"2026-08-16T12:55:19.654365Z","steps":["trace[1471440528] 'process raft request' (duration: 126.299311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:19.654493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.591983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-173741be-9a7e-4df3-9e83-6280197a3609\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-16T12:55:19.654543Z","caller":"traceutil/trace.go:171","msg":"trace[1577495119] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-173741be-9a7e-4df3-9e83-6280197a3609; range_end:; response_count:1; response_revision:1865; }","duration":"118.661305ms","start":"2026-08-16T12:55:19.535869Z","end":"2026-08-16T12:55:19.65453Z","steps":["trace[1577495119] 'agreement among raft nodes before linearized reading' (duration: 118.448789ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:19.823907Z","caller":"traceutil/trace.go:171","msg":"trace[17278697] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"101.181783ms","start":"2026-08-16T12:55:19.722692Z","end":"2026-08-16T12:55:19.823873Z","steps":["trace[17278697] 'process raft request' (duration: 100.855494ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:20.089471Z","caller":"traceutil/trace.go:171","msg":"trace[590438182] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"259.93785ms","start":"2026-08-16T12:55:19.829496Z","end":"2026-08-16T12:55:20.089434Z","steps":["trace[590438182] 'process raft request' (duration: 259.703764ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:20.089473Z","caller":"traceutil/trace.go:171","msg":"trace[114649830] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"259.152ms","start":"2026-08-16T12:55:19.830291Z","end":"2026-08-16T12:55:20.089443Z","steps":["trace[114649830] 'process raft request' (duration: 259.103548ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:20.089541Z","caller":"traceutil/trace.go:171","msg":"trace[1102513267] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"259.728265ms","start":"2026-08-16T12:55:19.829787Z","end":"2026-08-16T12:55:20.089515Z","steps":["trace[1102513267] 'process raft request' (duration: 259.55316ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:36.278354Z","caller":"traceutil/trace.go:171","msg":"trace[1905545193] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"131.446261ms","start":"2026-08-16T12:55:36.146888Z","end":"2026-08-16T12:55:36.278335Z","steps":["trace[1905545193] 'process raft request' (duration: 131.235135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:08.207741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.615132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:56:08.208038Z","caller":"traceutil/trace.go:171","msg":"trace[1864217578] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2255; }","duration":"183.70562ms","start":"2026-08-16T12:56:08.0243Z","end":"2026-08-16T12:56:08.208005Z","steps":["trace[1864217578] 'read index received' (duration: 64.650276ms)","trace[1864217578] 'applied index is now lower than readState.Index' (duration: 119.054024ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:56:08.20805Z","caller":"traceutil/trace.go:171","msg":"trace[1182169449] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"210.538765ms","start":"2026-08-16T12:56:07.997495Z","end":"2026-08-16T12:56:08.208034Z","steps":["trace[1182169449] 'process raft request' (duration: 210.474113ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:08.208056Z","caller":"traceutil/trace.go:171","msg":"trace[261616190] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"245.741357ms","start":"2026-08-16T12:56:07.962276Z","end":"2026-08-16T12:56:08.208017Z","steps":["trace[261616190] 'process raft request' (duration: 245.631674ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:08.208082Z","caller":"traceutil/trace.go:171","msg":"trace[1820667404] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"246.688512ms","start":"2026-08-16T12:56:07.961353Z","end":"2026-08-16T12:56:08.208042Z","steps":["trace[1820667404] 'process raft request' (duration: 127.529245ms)","trace[1820667404] 'compare' (duration: 118.482529ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:56:08.208165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.842185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:56:08.208246Z","caller":"traceutil/trace.go:171","msg":"trace[839161293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2180; }","duration":"183.928527ms","start":"2026-08-16T12:56:08.024294Z","end":"2026-08-16T12:56:08.208223Z","steps":["trace[839161293] 'agreement among raft nodes before linearized reading' (duration: 183.778573ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:57:23.392719Z","caller":"traceutil/trace.go:171","msg":"trace[932108520] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"132.642253ms","start":"2026-08-16T12:57:23.260047Z","end":"2026-08-16T12:57:23.392689Z","steps":["trace[932108520] 'process raft request' (duration: 132.454738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:23.732521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.304297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:57:23.732627Z","caller":"traceutil/trace.go:171","msg":"trace[273808834] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2784; }","duration":"219.447442ms","start":"2026-08-16T12:57:23.513159Z","end":"2026-08-16T12:57:23.732606Z","steps":["trace[273808834] 'count revisions from in-memory index tree' (duration: 219.208556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:23.73282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.941868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-16T12:57:23.732915Z","caller":"traceutil/trace.go:171","msg":"trace[734525437] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:2784; }","duration":"265.082862ms","start":"2026-08-16T12:57:23.46781Z","end":"2026-08-16T12:57:23.732893Z","steps":["trace[734525437] 'range keys from in-memory index tree' (duration: 264.735953ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:59:53.031535Z","caller":"traceutil/trace.go:171","msg":"trace[1382769046] transaction","detail":"{read_only:false; response_revision:3768; number_of_response:1; }","duration":"122.687081ms","start":"2026-08-16T12:59:52.908822Z","end":"2026-08-16T12:59:53.031509Z","steps":["trace[1382769046] 'process raft request' (duration: 122.525107ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:59:53.16709Z","caller":"traceutil/trace.go:171","msg":"trace[1306057965] transaction","detail":"{read_only:false; response_revision:3769; number_of_response:1; }","duration":"257.101249ms","start":"2026-08-16T12:59:52.90996Z","end":"2026-08-16T12:59:53.167061Z","steps":["trace[1306057965] 'process raft request' (duration: 245.247729ms)","trace[1306057965] 'compare' (duration: 11.741657ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:00:29.211588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767456438547500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T13:00:29.712797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767456438547500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T13:00:30.214038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767456438547500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T13:00:30.785897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767456438547500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T13:00:30.819878Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.24679257s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T13:00:30.821664Z","caller":"traceutil/trace.go:171","msg":"trace[1935284650] transaction","detail":"{read_only:false; response_revision:4040; number_of_response:1; }","duration":"2.249842875s","start":"2026-08-16T13:00:28.571793Z","end":"2026-08-16T13:00:30.821635Z","steps":["trace[1935284650] 'process raft request' (duration: 2.249385092s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.821835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:28.571765Z","time spent":"2.249963198s","remote":"127.0.0.1:35864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":410957,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T13:00:30.8272Z","caller":"traceutil/trace.go:171","msg":"trace[1586289559] transaction","detail":"{read_only:false; response_revision:4042; number_of_response:1; }","duration":"1.283946829s","start":"2026-08-16T13:00:29.543231Z","end":"2026-08-16T13:00:30.827178Z","steps":["trace[1586289559] 'process raft request' (duration: 1.283893317s)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:00:30.827296Z","caller":"traceutil/trace.go:171","msg":"trace[314786888] transaction","detail":"{read_only:false; response_revision:4041; number_of_response:1; }","duration":"1.425966288s","start":"2026-08-16T13:00:29.401284Z","end":"2026-08-16T13:00:30.82725Z","steps":["trace[314786888] 'process raft request' (duration: 1.425747992s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.827343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.543193Z","time spent":"1.284066893s","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:00:30.827395Z","caller":"traceutil/trace.go:171","msg":"trace[833774437] transaction","detail":"{read_only:false; response_revision:4043; number_of_response:1; }","duration":"804.956401ms","start":"2026-08-16T13:00:30.022428Z","end":"2026-08-16T13:00:30.827384Z","steps":["trace[833774437] 'process raft request' (duration: 804.725444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.827456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:30.0224Z","time spent":"805.025273ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:00:30.827501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.401265Z","time spent":"1.426121613s","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:00:30.827629Z","caller":"traceutil/trace.go:171","msg":"trace[1862483360] linearizableReadLoop","detail":"{readStateIndex:4192; appliedIndex:4187; }","duration":"2.116389154s","start":"2026-08-16T13:00:28.71123Z","end":"2026-08-16T13:00:30.827619Z","steps":["trace[1862483360] 'read index received' (duration: 2.108886686s)","trace[1862483360] 'applied index is now lower than readState.Index' (duration: 7.499448ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:00:30.827726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.116497977s","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-08-16T13:00:30.827754Z","caller":"traceutil/trace.go:171","msg":"trace[627728835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4044; }","duration":"2.116567879s","start":"2026-08-16T13:00:28.711178Z","end":"2026-08-16T13:00:30.827746Z","steps":["trace[627728835] 'agreement among raft nodes before linearized reading' (duration: 2.116514367s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.827777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:28.711157Z","time spent":"2.11661321s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-16T13:00:30.827853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097221871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8vrdp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-16T13:00:30.827897Z","caller":"traceutil/trace.go:171","msg":"trace[1532927280] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8vrdp; range_end:; response_count:1; response_revision:4044; }","duration":"2.097298263s","start":"2026-08-16T13:00:28.730589Z","end":"2026-08-16T13:00:30.827887Z","steps":["trace[1532927280] 'agreement among raft nodes before linearized reading' (duration: 2.097162309s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.827917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35124195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T13:00:30.827937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:28.730576Z","time spent":"2.097349015s","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-8vrdp\" "} {"level":"warn","ts":"2026-08-16T13:00:30.827948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626231625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:00:30.82801Z","caller":"traceutil/trace.go:171","msg":"trace[1311835471] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4044; }","duration":"1.626356799s","start":"2026-08-16T13:00:29.201637Z","end":"2026-08-16T13:00:30.827994Z","steps":["trace[1311835471] 'agreement among raft nodes before linearized reading' (duration: 1.626222135s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.828029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353906885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-16T13:00:30.82805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.201614Z","time spent":"1.626428251s","remote":"127.0.0.1:47380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-08-16T13:00:30.828058Z","caller":"traceutil/trace.go:171","msg":"trace[218273214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4044; }","duration":"1.353969136s","start":"2026-08-16T13:00:29.474083Z","end":"2026-08-16T13:00:30.828052Z","steps":["trace[218273214] 'agreement among raft nodes before linearized reading' (duration: 1.353891864s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.828069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.907464ms","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":"info","ts":"2026-08-16T13:00:30.828129Z","caller":"traceutil/trace.go:171","msg":"trace[867676942] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4044; }","duration":"752.977596ms","start":"2026-08-16T13:00:30.075134Z","end":"2026-08-16T13:00:30.828112Z","steps":["trace[867676942] 'agreement among raft nodes before linearized reading' (duration: 752.857002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.828148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.62818633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-16T13:00:30.828176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:30.075121Z","time spent":"753.043997ms","remote":"127.0.0.1:35990","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-08-16T13:00:30.828324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.059282ms","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-08-16T13:00:30.828356Z","caller":"traceutil/trace.go:171","msg":"trace[758867960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4044; }","duration":"801.137545ms","start":"2026-08-16T13:00:30.027211Z","end":"2026-08-16T13:00:30.828348Z","steps":["trace[758867960] 'agreement among raft nodes before linearized reading' (duration: 800.95943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.828358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48903495s","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-08-16T13:00:30.828329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.03494976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-08-16T13:00:30.828085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.474068Z","time spent":"1.354012508s","remote":"127.0.0.1:35990","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-08-16T13:00:30.828156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.177488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-16T13:00:30.828535Z","caller":"traceutil/trace.go:171","msg":"trace[386360545] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4044; }","duration":"964.59335ms","start":"2026-08-16T13:00:29.863924Z","end":"2026-08-16T13:00:30.828518Z","steps":["trace[386360545] 'agreement among raft nodes before linearized reading' (duration: 964.184769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.8286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.863903Z","time spent":"964.679762ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-16T13:00:30.828389Z","caller":"traceutil/trace.go:171","msg":"trace[1747351012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4044; }","duration":"1.489090782s","start":"2026-08-16T13:00:29.339291Z","end":"2026-08-16T13:00:30.828382Z","steps":["trace[1747351012] 'agreement among raft nodes before linearized reading' (duration: 1.488940858s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.828887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.339279Z","time spent":"1.489594756s","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T13:00:30.829062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01855193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:00:30.829086Z","caller":"traceutil/trace.go:171","msg":"trace[1559368780] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4044; }","duration":"1.019146147s","start":"2026-08-16T13:00:29.809933Z","end":"2026-08-16T13:00:30.829079Z","steps":["trace[1559368780] 'agreement among raft nodes before linearized reading' (duration: 1.01854492s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.829103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.809926Z","time spent":"1.019171307s","remote":"127.0.0.1:47360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-08-16T13:00:30.828387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:30.027189Z","time spent":"801.192836ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-16T13:00:30.829225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147467255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-16T13:00:30.82924Z","caller":"traceutil/trace.go:171","msg":"trace[1739901995] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4044; }","duration":"1.147547737s","start":"2026-08-16T13:00:29.681687Z","end":"2026-08-16T13:00:30.829235Z","steps":["trace[1739901995] 'agreement among raft nodes before linearized reading' (duration: 1.147497946s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.829251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.681657Z","time spent":"1.147591298s","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-08-16T13:00:30.828183Z","caller":"traceutil/trace.go:171","msg":"trace[450378451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4044; }","duration":"1.628256452s","start":"2026-08-16T13:00:29.199917Z","end":"2026-08-16T13:00:30.828173Z","steps":["trace[450378451] 'agreement among raft nodes before linearized reading' (duration: 1.628161049s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.829338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.199901Z","time spent":"1.629432815s","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-16T13:00:30.828445Z","caller":"traceutil/trace.go:171","msg":"trace[1521760201] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4044; }","duration":"2.035103854s","start":"2026-08-16T13:00:28.793304Z","end":"2026-08-16T13:00:30.828408Z","steps":["trace[1521760201] 'agreement among raft nodes before linearized reading' (duration: 2.034865097s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.829418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:28.793272Z","time spent":"2.036138993s","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-16T13:00:30.827965Z","caller":"traceutil/trace.go:171","msg":"trace[1725178945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4044; }","duration":"1.351323692s","start":"2026-08-16T13:00:29.476627Z","end":"2026-08-16T13:00:30.827951Z","steps":["trace[1725178945] 'agreement among raft nodes before linearized reading' (duration: 1.351155488s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.829561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.476609Z","time spent":"1.352936718s","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T13:00:30.828488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.806770835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:00:30.829706Z","caller":"traceutil/trace.go:171","msg":"trace[1914384759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4044; }","duration":"1.80801839s","start":"2026-08-16T13:00:29.021676Z","end":"2026-08-16T13:00:30.829695Z","steps":["trace[1914384759] 'agreement among raft nodes before linearized reading' (duration: 1.806793926s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:30.829768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:29.021659Z","time spent":"1.808082041s","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T13:01:13.606421Z","caller":"traceutil/trace.go:171","msg":"trace[781277882] linearizableReadLoop","detail":"{readStateIndex:4984; appliedIndex:4983; }","duration":"114.978579ms","start":"2026-08-16T13:01:13.491418Z","end":"2026-08-16T13:01:13.606397Z","steps":["trace[781277882] 'read index received' (duration: 109.702781ms)","trace[781277882] 'applied index is now lower than readState.Index' (duration: 5.274508ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:01:13.606707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.258877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-08-16T13:01:13.606753Z","caller":"traceutil/trace.go:171","msg":"trace[1341725690] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4805; }","duration":"115.34153ms","start":"2026-08-16T13:01:13.491399Z","end":"2026-08-16T13:01:13.606741Z","steps":["trace[1341725690] 'agreement among raft nodes before linearized reading' (duration: 115.097753ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:13.60707Z","caller":"traceutil/trace.go:171","msg":"trace[622942619] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"116.958294ms","start":"2026-08-16T13:01:13.490096Z","end":"2026-08-16T13:01:13.607054Z","steps":["trace[622942619] 'process raft request' (duration: 111.104711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.194242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.090059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:01:17.194484Z","caller":"traceutil/trace.go:171","msg":"trace[1621703214] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5111; }","duration":"171.040123ms","start":"2026-08-16T13:01:17.023426Z","end":"2026-08-16T13:01:17.194466Z","steps":["trace[1621703214] 'read index received' (duration: 63.644735ms)","trace[1621703214] 'applied index is now lower than readState.Index' (duration: 107.394478ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:17.19458Z","caller":"traceutil/trace.go:171","msg":"trace[1479662580] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"187.267657ms","start":"2026-08-16T13:01:17.0073Z","end":"2026-08-16T13:01:17.194568Z","steps":["trace[1479662580] 'process raft request' (duration: 187.069691ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.19459Z","caller":"traceutil/trace.go:171","msg":"trace[921515832] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"192.38941ms","start":"2026-08-16T13:01:17.002173Z","end":"2026-08-16T13:01:17.194562Z","steps":["trace[921515832] 'process raft request' (duration: 84.886559ms)","trace[921515832] 'compare' (duration: 106.832512ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:17.194758Z","caller":"traceutil/trace.go:171","msg":"trace[896817461] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"185.968701ms","start":"2026-08-16T13:01:17.008779Z","end":"2026-08-16T13:01:17.194747Z","steps":["trace[896817461] 'process raft request' (duration: 185.651292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.19478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.328052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T13:01:17.194869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.327704ms","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-08-16T13:01:17.194876Z","caller":"traceutil/trace.go:171","msg":"trace[1716068819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4933; }","duration":"171.460135ms","start":"2026-08-16T13:01:17.023391Z","end":"2026-08-16T13:01:17.194851Z","steps":["trace[1716068819] 'agreement among raft nodes before linearized reading' (duration: 171.3033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.194876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.017336ms","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-08-16T13:01:17.194916Z","caller":"traceutil/trace.go:171","msg":"trace[1923808301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4933; }","duration":"127.385455ms","start":"2026-08-16T13:01:17.06752Z","end":"2026-08-16T13:01:17.194905Z","steps":["trace[1923808301] 'agreement among raft nodes before linearized reading' (duration: 127.249291ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.194956Z","caller":"traceutil/trace.go:171","msg":"trace[1432764882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4933; }","duration":"136.134939ms","start":"2026-08-16T13:01:17.058801Z","end":"2026-08-16T13:01:17.194936Z","steps":["trace[1432764882] 'agreement among raft nodes before linearized reading' (duration: 135.938624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.195008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.429965ms","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-08-16T13:01:17.195054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.226673ms","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-08-16T13:01:17.195082Z","caller":"traceutil/trace.go:171","msg":"trace[109886322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4933; }","duration":"121.275425ms","start":"2026-08-16T13:01:17.073799Z","end":"2026-08-16T13:01:17.195074Z","steps":["trace[109886322] 'agreement among raft nodes before linearized reading' (duration: 121.199313ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.195095Z","caller":"traceutil/trace.go:171","msg":"trace[1435602707] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4933; }","duration":"128.573809ms","start":"2026-08-16T13:01:17.066498Z","end":"2026-08-16T13:01:17.195072Z","steps":["trace[1435602707] 'agreement among raft nodes before linearized reading' (duration: 128.280751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.194918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.032832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8vrdp\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-08-16T13:01:17.195165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.119751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-16T13:01:17.195225Z","caller":"traceutil/trace.go:171","msg":"trace[1973896537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4933; }","duration":"135.179223ms","start":"2026-08-16T13:01:17.060031Z","end":"2026-08-16T13:01:17.19521Z","steps":["trace[1973896537] 'agreement among raft nodes before linearized reading' (duration: 135.042509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.195259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.63955ms","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-08-16T13:01:17.195175Z","caller":"traceutil/trace.go:171","msg":"trace[1432648329] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8vrdp; range_end:; response_count:1; response_revision:4933; }","duration":"119.31229ms","start":"2026-08-16T13:01:17.075854Z","end":"2026-08-16T13:01:17.195167Z","steps":["trace[1432648329] 'agreement among raft nodes before linearized reading' (duration: 118.981751ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.195283Z","caller":"traceutil/trace.go:171","msg":"trace[1225570299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4933; }","duration":"128.672332ms","start":"2026-08-16T13:01:17.066603Z","end":"2026-08-16T13:01:17.195276Z","steps":["trace[1225570299] 'agreement among raft nodes before linearized reading' (duration: 128.60127ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.331634Z","caller":"traceutil/trace.go:171","msg":"trace[1378611761] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"132.175578ms","start":"2026-08-16T13:01:17.199411Z","end":"2026-08-16T13:01:17.331587Z","steps":["trace[1378611761] 'process raft request' (duration: 100.635198ms)","trace[1378611761] 'compare' (duration: 31.335625ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:17.363533Z","caller":"traceutil/trace.go:171","msg":"trace[1989695467] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"164.033967ms","start":"2026-08-16T13:01:17.199473Z","end":"2026-08-16T13:01:17.363507Z","steps":["trace[1989695467] 'process raft request' (duration: 163.826911ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.363562Z","caller":"traceutil/trace.go:171","msg":"trace[1339029066] linearizableReadLoop","detail":"{readStateIndex:5120; appliedIndex:5114; }","duration":"163.273266ms","start":"2026-08-16T13:01:17.200268Z","end":"2026-08-16T13:01:17.363542Z","steps":["trace[1339029066] 'read index received' (duration: 99.764354ms)","trace[1339029066] 'applied index is now lower than readState.Index' (duration: 63.508252ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:17.363658Z","caller":"traceutil/trace.go:171","msg":"trace[18407701] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"163.881593ms","start":"2026-08-16T13:01:17.199768Z","end":"2026-08-16T13:01:17.36365Z","steps":["trace[18407701] 'process raft request' (duration: 163.649927ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.363705Z","caller":"traceutil/trace.go:171","msg":"trace[1708219104] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"163.459841ms","start":"2026-08-16T13:01:17.200226Z","end":"2026-08-16T13:01:17.363686Z","steps":["trace[1708219104] 'process raft request' (duration: 163.289326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.363884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.603345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62866"} {"level":"info","ts":"2026-08-16T13:01:17.363912Z","caller":"traceutil/trace.go:171","msg":"trace[1226027707] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4939; }","duration":"163.664047ms","start":"2026-08-16T13:01:17.200242Z","end":"2026-08-16T13:01:17.363906Z","steps":["trace[1226027707] 'agreement among raft nodes before linearized reading' (duration: 163.519103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.363889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.04704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:01:17.363951Z","caller":"traceutil/trace.go:171","msg":"trace[1448583284] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:0; response_revision:4939; }","duration":"163.132322ms","start":"2026-08-16T13:01:17.200803Z","end":"2026-08-16T13:01:17.363936Z","steps":["trace[1448583284] 'agreement among raft nodes before linearized reading' (duration: 163.033979ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.363956Z","caller":"traceutil/trace.go:171","msg":"trace[1688611685] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"163.878963ms","start":"2026-08-16T13:01:17.200062Z","end":"2026-08-16T13:01:17.363941Z","steps":["trace[1688611685] 'process raft request' (duration: 163.39395ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:17.364092Z","caller":"traceutil/trace.go:171","msg":"trace[6401124] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"163.949275ms","start":"2026-08-16T13:01:17.200133Z","end":"2026-08-16T13:01:17.364082Z","steps":["trace[6401124] 'process raft request' (duration: 163.352889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:17.364353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.38151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b9630e5-de94-440c-8d07-6cddbc46632b\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-16T13:01:17.364396Z","caller":"traceutil/trace.go:171","msg":"trace[569234828] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4b9630e5-de94-440c-8d07-6cddbc46632b; range_end:; response_count:1; response_revision:4939; }","duration":"129.461443ms","start":"2026-08-16T13:01:17.234922Z","end":"2026-08-16T13:01:17.364384Z","steps":["trace[569234828] 'agreement among raft nodes before linearized reading' (duration: 129.37307ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:19.509079Z","caller":"traceutil/trace.go:171","msg":"trace[87007838] linearizableReadLoop","detail":"{readStateIndex:5160; appliedIndex:5159; }","duration":"134.339048ms","start":"2026-08-16T13:01:19.374716Z","end":"2026-08-16T13:01:19.509055Z","steps":["trace[87007838] 'read index received' (duration: 55.154619ms)","trace[87007838] 'applied index is now lower than readState.Index' (duration: 79.183249ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:19.50914Z","caller":"traceutil/trace.go:171","msg":"trace[1486646533] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"143.530765ms","start":"2026-08-16T13:01:19.365565Z","end":"2026-08-16T13:01:19.509096Z","steps":["trace[1486646533] 'process raft request' (duration: 64.361126ms)","trace[1486646533] 'compare' (duration: 79.018014ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:01:19.509316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.576565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b9630e5-de94-440c-8d07-6cddbc46632b\" ","response":"range_response_count:1 size:3904"} {"level":"info","ts":"2026-08-16T13:01:19.509363Z","caller":"traceutil/trace.go:171","msg":"trace[662394140] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4b9630e5-de94-440c-8d07-6cddbc46632b; range_end:; response_count:1; response_revision:4977; }","duration":"134.667418ms","start":"2026-08-16T13:01:19.374683Z","end":"2026-08-16T13:01:19.50935Z","steps":["trace[662394140] 'agreement among raft nodes before linearized reading' (duration: 134.507623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:19.509409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.451082ms","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-08-16T13:01:19.509457Z","caller":"traceutil/trace.go:171","msg":"trace[1547867186] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4977; }","duration":"134.515513ms","start":"2026-08-16T13:01:19.374929Z","end":"2026-08-16T13:01:19.509444Z","steps":["trace[1547867186] 'agreement among raft nodes before linearized reading' (duration: 134.367589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:19.509557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.561724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-16T13:01:19.509581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.561365ms","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-08-16T13:01:19.509606Z","caller":"traceutil/trace.go:171","msg":"trace[1094365559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4977; }","duration":"134.615545ms","start":"2026-08-16T13:01:19.374977Z","end":"2026-08-16T13:01:19.509593Z","steps":["trace[1094365559] 'agreement among raft nodes before linearized reading' (duration: 134.499122ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:19.509644Z","caller":"traceutil/trace.go:171","msg":"trace[2130388076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4977; }","duration":"134.628586ms","start":"2026-08-16T13:01:19.375Z","end":"2026-08-16T13:01:19.509629Z","steps":["trace[2130388076] 'agreement among raft nodes before linearized reading' (duration: 134.468622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:19.509682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.580861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-16T13:01:19.509786Z","caller":"traceutil/trace.go:171","msg":"trace[811806643] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4977; }","duration":"131.685974ms","start":"2026-08-16T13:01:19.378058Z","end":"2026-08-16T13:01:19.509744Z","steps":["trace[811806643] 'agreement among raft nodes before linearized reading' (duration: 131.458267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:19.509798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.816612ms","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-08-16T13:01:19.509833Z","caller":"traceutil/trace.go:171","msg":"trace[2026294623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4977; }","duration":"134.859313ms","start":"2026-08-16T13:01:19.374965Z","end":"2026-08-16T13:01:19.509824Z","steps":["trace[2026294623] 'agreement among raft nodes before linearized reading' (duration: 134.666478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:19.509915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.908084ms","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":"info","ts":"2026-08-16T13:01:19.510009Z","caller":"traceutil/trace.go:171","msg":"trace[1543140564] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4977; }","duration":"134.967786ms","start":"2026-08-16T13:01:19.37499Z","end":"2026-08-16T13:01:19.509957Z","steps":["trace[1543140564] 'agreement among raft nodes before linearized reading' (duration: 134.541434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:19.509928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.921967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8vrdp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-16T13:01:19.510078Z","caller":"traceutil/trace.go:171","msg":"trace[1961018668] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8vrdp; range_end:; response_count:1; response_revision:4977; }","duration":"134.090502ms","start":"2026-08-16T13:01:19.375978Z","end":"2026-08-16T13:01:19.510068Z","steps":["trace[1961018668] 'agreement among raft nodes before linearized reading' (duration: 133.730442ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:29.662644Z","caller":"traceutil/trace.go:171","msg":"trace[1627786109] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"118.743794ms","start":"2026-08-16T13:01:29.543864Z","end":"2026-08-16T13:01:29.662608Z","steps":["trace[1627786109] 'process raft request' (duration: 118.447596ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:29.662782Z","caller":"traceutil/trace.go:171","msg":"trace[1730033441] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"117.407597ms","start":"2026-08-16T13:01:29.545344Z","end":"2026-08-16T13:01:29.662751Z","steps":["trace[1730033441] 'process raft request' (duration: 117.1713ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:29.707492Z","caller":"traceutil/trace.go:171","msg":"trace[1123864389] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"122.18245ms","start":"2026-08-16T13:01:29.585267Z","end":"2026-08-16T13:01:29.70745Z","steps":["trace[1123864389] 'process raft request' (duration: 121.786189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:29.707576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.890356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-16T13:01:29.707676Z","caller":"traceutil/trace.go:171","msg":"trace[1348719285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5191; }","duration":"101.03823ms","start":"2026-08-16T13:01:29.606614Z","end":"2026-08-16T13:01:29.707652Z","steps":["trace[1348719285] 'agreement among raft nodes before linearized reading' (duration: 100.779103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:30.175032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.22448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:01:30.175105Z","caller":"traceutil/trace.go:171","msg":"trace[873490526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5206; }","duration":"151.343804ms","start":"2026-08-16T13:01:30.023747Z","end":"2026-08-16T13:01:30.175091Z","steps":["trace[873490526] 'range keys from in-memory index tree' (duration: 151.163799ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:37.714114Z","caller":"traceutil/trace.go:171","msg":"trace[114637083] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"170.676744ms","start":"2026-08-16T13:01:37.543411Z","end":"2026-08-16T13:01:37.714088Z","steps":["trace[114637083] 'process raft request' (duration: 113.292172ms)","trace[114637083] 'compare' (duration: 57.250838ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:38.073727Z","caller":"traceutil/trace.go:171","msg":"trace[885197518] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"353.865765ms","start":"2026-08-16T13:01:37.719837Z","end":"2026-08-16T13:01:38.073703Z","steps":["trace[885197518] 'process raft request' (duration: 292.354369ms)","trace[885197518] 'compare' (duration: 61.29343ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:38.073834Z","caller":"traceutil/trace.go:171","msg":"trace[1769842745] linearizableReadLoop","detail":"{readStateIndex:5578; appliedIndex:5576; }","duration":"308.88772ms","start":"2026-08-16T13:01:37.764915Z","end":"2026-08-16T13:01:38.073802Z","steps":["trace[1769842745] 'read index received' (duration: 35.429959ms)","trace[1769842745] 'applied index is now lower than readState.Index' (duration: 273.455641ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:01:38.073872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:01:37.719814Z","time spent":"353.985739ms","remote":"127.0.0.1:35992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:01:38.073848Z","caller":"traceutil/trace.go:171","msg":"trace[452370786] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"349.287878ms","start":"2026-08-16T13:01:37.724532Z","end":"2026-08-16T13:01:38.07382Z","steps":["trace[452370786] 'process raft request' (duration: 349.105853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:38.074058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:01:37.724519Z","time spent":"349.499424ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:01:38.074137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.194499ms","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-08-16T13:01:38.074188Z","caller":"traceutil/trace.go:171","msg":"trace[95815752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5385; }","duration":"309.296122ms","start":"2026-08-16T13:01:37.764881Z","end":"2026-08-16T13:01:38.074177Z","steps":["trace[95815752] 'agreement among raft nodes before linearized reading' (duration: 309.065335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:38.074224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:01:37.764864Z","time spent":"309.351324ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T13:01:38.074391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.961572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8vrdp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-16T13:01:38.074435Z","caller":"traceutil/trace.go:171","msg":"trace[1529884586] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8vrdp; range_end:; response_count:1; response_revision:5385; }","duration":"309.036995ms","start":"2026-08-16T13:01:37.765388Z","end":"2026-08-16T13:01:38.074425Z","steps":["trace[1529884586] 'agreement among raft nodes before linearized reading' (duration: 308.88579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:38.074427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.5931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-16T13:01:38.074467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:01:37.765375Z","time spent":"309.084166ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-8vrdp\" "} {"level":"info","ts":"2026-08-16T13:01:38.074478Z","caller":"traceutil/trace.go:171","msg":"trace[330240738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5385; }","duration":"223.663322ms","start":"2026-08-16T13:01:37.8508Z","end":"2026-08-16T13:01:38.074463Z","steps":["trace[330240738] 'agreement among raft nodes before linearized reading' (duration: 223.524828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:38.074546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.496615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-16T13:01:38.074592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.855098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T13:01:38.074619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.42904ms","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-08-16T13:01:38.07464Z","caller":"traceutil/trace.go:171","msg":"trace[1740913834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5385; }","duration":"219.66097ms","start":"2026-08-16T13:01:37.854956Z","end":"2026-08-16T13:01:38.074617Z","steps":["trace[1740913834] 'agreement among raft nodes before linearized reading' (duration: 219.376612ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:38.074648Z","caller":"traceutil/trace.go:171","msg":"trace[1635561491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5385; }","duration":"237.93718ms","start":"2026-08-16T13:01:37.836693Z","end":"2026-08-16T13:01:38.07463Z","steps":["trace[1635561491] 'agreement among raft nodes before linearized reading' (duration: 237.809977ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:38.074662Z","caller":"traceutil/trace.go:171","msg":"trace[612006511] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5385; }","duration":"240.512872ms","start":"2026-08-16T13:01:37.834139Z","end":"2026-08-16T13:01:38.074652Z","steps":["trace[612006511] 'agreement among raft nodes before linearized reading' (duration: 240.403629ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:10.366116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3038} {"level":"info","ts":"2026-08-16T13:03:10.447398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3038,"took":"79.963828ms","hash":3680675098,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":32260096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-16T13:03:10.447537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3680675098,"revision":3038,"compact-revision":-1} {"level":"info","ts":"2026-08-16T13:07:40.139824Z","caller":"traceutil/trace.go:171","msg":"trace[2011824496] linearizableReadLoop","detail":"{readStateIndex:8446; appliedIndex:8445; }","duration":"181.051995ms","start":"2026-08-16T13:07:39.958747Z","end":"2026-08-16T13:07:40.139799Z","steps":["trace[2011824496] 'read index received' (duration: 180.8632ms)","trace[2011824496] 'applied index is now lower than readState.Index' (duration: 187.475µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:07:40.139958Z","caller":"traceutil/trace.go:171","msg":"trace[326755112] transaction","detail":"{read_only:false; response_revision:8164; number_of_response:1; }","duration":"201.994519ms","start":"2026-08-16T13:07:39.937928Z","end":"2026-08-16T13:07:40.139923Z","steps":["trace[326755112] 'process raft request' (duration: 201.65028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:07:40.140009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.582941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-16T13:07:40.140055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.63176ms","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-08-16T13:07:40.140092Z","caller":"traceutil/trace.go:171","msg":"trace[2133148356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8164; }","duration":"210.727213ms","start":"2026-08-16T13:07:39.929355Z","end":"2026-08-16T13:07:40.140082Z","steps":["trace[2133148356] 'agreement among raft nodes before linearized reading' (duration: 210.567288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:07:40.140099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.41765ms","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-08-16T13:07:40.1401Z","caller":"traceutil/trace.go:171","msg":"trace[1845943127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8164; }","duration":"209.705055ms","start":"2026-08-16T13:07:39.930372Z","end":"2026-08-16T13:07:40.140077Z","steps":["trace[1845943127] 'agreement among raft nodes before linearized reading' (duration: 209.461938ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:07:40.140149Z","caller":"traceutil/trace.go:171","msg":"trace[1072386653] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8164; }","duration":"212.522233ms","start":"2026-08-16T13:07:39.927614Z","end":"2026-08-16T13:07:40.140136Z","steps":["trace[1072386653] 'agreement among raft nodes before linearized reading' (duration: 212.331608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:07:40.161713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.718437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:07:40.16173Z","caller":"traceutil/trace.go:171","msg":"trace[463042018] transaction","detail":"{read_only:false; response_revision:8165; number_of_response:1; }","duration":"199.359396ms","start":"2026-08-16T13:07:39.962342Z","end":"2026-08-16T13:07:40.161702Z","steps":["trace[463042018] 'process raft request' (duration: 199.15039ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:07:40.161796Z","caller":"traceutil/trace.go:171","msg":"trace[1383064589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8165; }","duration":"136.84854ms","start":"2026-08-16T13:07:40.024927Z","end":"2026-08-16T13:07:40.161775Z","steps":["trace[1383064589] 'agreement among raft nodes before linearized reading' (duration: 136.703306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:07:40.161853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.966667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8vrdp\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-08-16T13:07:40.161851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.995027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T13:07:40.161895Z","caller":"traceutil/trace.go:171","msg":"trace[1241457902] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8vrdp; range_end:; response_count:1; response_revision:8165; }","duration":"126.054249ms","start":"2026-08-16T13:07:40.035832Z","end":"2026-08-16T13:07:40.161886Z","steps":["trace[1241457902] 'agreement among raft nodes before linearized reading' (duration: 125.875804ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:07:40.161928Z","caller":"traceutil/trace.go:171","msg":"trace[697533575] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8165; }","duration":"126.107461ms","start":"2026-08-16T13:07:40.035797Z","end":"2026-08-16T13:07:40.161905Z","steps":["trace[697533575] 'agreement among raft nodes before linearized reading' (duration: 125.957907ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:08:07.25274Z","caller":"traceutil/trace.go:171","msg":"trace[224637181] transaction","detail":"{read_only:false; response_revision:8366; number_of_response:1; }","duration":"441.170767ms","start":"2026-08-16T13:08:06.811548Z","end":"2026-08-16T13:08:07.252719Z","steps":["trace[224637181] 'process raft request' (duration: 441.026233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:08:07.252893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:08:06.811524Z","time spent":"441.29866ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:08:07.253449Z","caller":"traceutil/trace.go:171","msg":"trace[288791260] transaction","detail":"{read_only:false; response_revision:8367; number_of_response:1; }","duration":"380.154534ms","start":"2026-08-16T13:08:06.873264Z","end":"2026-08-16T13:08:07.253419Z","steps":["trace[288791260] 'process raft request' (duration: 380.051461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:08:07.253593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:08:06.873244Z","time spent":"380.273267ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:08:10.37161Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6158} {"level":"info","ts":"2026-08-16T13:08:10.477279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6158,"took":"103.783107ms","hash":1462029520,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":23457792,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-16T13:08:10.47736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1462029520,"revision":6158,"compact-revision":3038} {"level":"info","ts":"2026-08-16T13:08:20.026053Z","caller":"traceutil/trace.go:171","msg":"trace[1598225631] linearizableReadLoop","detail":"{readStateIndex:8771; appliedIndex:8770; }","duration":"130.357395ms","start":"2026-08-16T13:08:19.895674Z","end":"2026-08-16T13:08:20.026031Z","steps":["trace[1598225631] 'read index received' (duration: 130.137268ms)","trace[1598225631] 'applied index is now lower than readState.Index' (duration: 219.067µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:08:20.026148Z","caller":"traceutil/trace.go:171","msg":"trace[434206352] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"155.205797ms","start":"2026-08-16T13:08:19.870921Z","end":"2026-08-16T13:08:20.026127Z","steps":["trace[434206352] 'process raft request' (duration: 154.93432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:08:20.026323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.628512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414394"} {"level":"info","ts":"2026-08-16T13:08:20.026365Z","caller":"traceutil/trace.go:171","msg":"trace[2055932402] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8475; }","duration":"130.719705ms","start":"2026-08-16T13:08:19.895634Z","end":"2026-08-16T13:08:20.026354Z","steps":["trace[2055932402] 'agreement among raft nodes before linearized reading' (duration: 130.498898ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:08:20.510275Z","caller":"traceutil/trace.go:171","msg":"trace[913676264] linearizableReadLoop","detail":"{readStateIndex:8775; appliedIndex:8774; }","duration":"109.237545ms","start":"2026-08-16T13:08:20.401014Z","end":"2026-08-16T13:08:20.510252Z","steps":["trace[913676264] 'read index received' (duration: 109.035499ms)","trace[913676264] 'applied index is now lower than readState.Index' (duration: 201.036µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:08:20.510486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.763926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T13:08:20.510525Z","caller":"traceutil/trace.go:171","msg":"trace[977921693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8479; }","duration":"139.851398ms","start":"2026-08-16T13:08:20.370662Z","end":"2026-08-16T13:08:20.510513Z","steps":["trace[977921693] 'agreement among raft nodes before linearized reading' (duration: 139.683484ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:08:20.51108Z","caller":"traceutil/trace.go:171","msg":"trace[206382597] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"116.974911ms","start":"2026-08-16T13:08:20.394093Z","end":"2026-08-16T13:08:20.511068Z","steps":["trace[206382597] 'process raft request' (duration: 115.855629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:08:20.511272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.034827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8vrdp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-16T13:08:20.511307Z","caller":"traceutil/trace.go:171","msg":"trace[1455949800] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8vrdp; range_end:; response_count:1; response_revision:8479; }","duration":"129.107799ms","start":"2026-08-16T13:08:20.382191Z","end":"2026-08-16T13:08:20.511299Z","steps":["trace[1455949800] 'agreement among raft nodes before linearized reading' (duration: 129.008906ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:08:20.626513Z","caller":"traceutil/trace.go:171","msg":"trace[1372168290] linearizableReadLoop","detail":"{readStateIndex:8776; appliedIndex:8775; }","duration":"106.984412ms","start":"2026-08-16T13:08:20.519506Z","end":"2026-08-16T13:08:20.62649Z","steps":["trace[1372168290] 'read index received' (duration: 55.553019ms)","trace[1372168290] 'applied index is now lower than readState.Index' (duration: 51.430243ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:08:20.626565Z","caller":"traceutil/trace.go:171","msg":"trace[147226131] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"221.239118ms","start":"2026-08-16T13:08:20.405291Z","end":"2026-08-16T13:08:20.62653Z","steps":["trace[147226131] 'process raft request' (duration: 169.755972ms)","trace[147226131] 'compare' (duration: 51.26964ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:08:20.626647Z","caller":"traceutil/trace.go:171","msg":"trace[1306778904] transaction","detail":"{read_only:false; response_revision:8481; number_of_response:1; }","duration":"106.581731ms","start":"2026-08-16T13:08:20.520047Z","end":"2026-08-16T13:08:20.626629Z","steps":["trace[1306778904] 'process raft request' (duration: 106.406966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:08:20.628297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.67722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228462"} {"level":"info","ts":"2026-08-16T13:08:20.628531Z","caller":"traceutil/trace.go:171","msg":"trace[2144018382] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8481; }","duration":"108.963007ms","start":"2026-08-16T13:08:20.519489Z","end":"2026-08-16T13:08:20.628452Z","steps":["trace[2144018382] 'agreement among raft nodes before linearized reading' (duration: 107.090245ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:08:59.566257Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b9.wal"} {"level":"warn","ts":"2026-08-16T13:09:34.970314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.336395ms","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-08-16T13:09:34.970393Z","caller":"traceutil/trace.go:171","msg":"trace[1863594479] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9217; }","duration":"195.469609ms","start":"2026-08-16T13:09:34.774907Z","end":"2026-08-16T13:09:34.970377Z","steps":["trace[1863594479] 'range keys from in-memory index tree' (duration: 195.081448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:09:34.970414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.79505ms","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-08-16T13:09:34.970483Z","caller":"traceutil/trace.go:171","msg":"trace[390139234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9217; }","duration":"194.919653ms","start":"2026-08-16T13:09:34.775549Z","end":"2026-08-16T13:09:34.970468Z","steps":["trace[390139234] 'range keys from in-memory index tree' (duration: 194.632596ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:09:35.084013Z","caller":"traceutil/trace.go:171","msg":"trace[1148195488] transaction","detail":"{read_only:false; response_revision:9218; number_of_response:1; }","duration":"110.12662ms","start":"2026-08-16T13:09:34.973859Z","end":"2026-08-16T13:09:35.083986Z","steps":["trace[1148195488] 'process raft request' (duration: 109.976106ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:09:35.094556Z","caller":"traceutil/trace.go:171","msg":"trace[954661456] transaction","detail":"{read_only:false; response_revision:9219; number_of_response:1; }","duration":"119.931503ms","start":"2026-08-16T13:09:34.9746Z","end":"2026-08-16T13:09:35.094531Z","steps":["trace[954661456] 'process raft request' (duration: 119.527082ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:10:39.403474Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:10:39.408901Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-16T13:10:39.408994Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-16T13:13:10.378013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8384} {"level":"info","ts":"2026-08-16T13:13:10.455775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8384,"took":"75.634789ms","hash":3557940993,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":25468928,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-16T13:13:10.455845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3557940993,"revision":8384,"compact-revision":6158} {"level":"info","ts":"2026-08-16T13:13:46.021495Z","caller":"traceutil/trace.go:171","msg":"trace[1306937759] transaction","detail":"{read_only:false; response_revision:11208; number_of_response:1; }","duration":"124.321527ms","start":"2026-08-16T13:13:45.897136Z","end":"2026-08-16T13:13:46.021457Z","steps":["trace[1306937759] 'process raft request' (duration: 124.130642ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:18:10.386915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10826} {"level":"info","ts":"2026-08-16T13:18:10.464129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10826,"took":"74.65542ms","hash":4217496177,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":26501120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-16T13:18:10.464279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4217496177,"revision":10826,"compact-revision":8384} {"level":"info","ts":"2026-08-16T13:19:27.316027Z","caller":"traceutil/trace.go:171","msg":"trace[1990735929] transaction","detail":"{read_only:false; response_revision:13553; number_of_response:1; }","duration":"107.098404ms","start":"2026-08-16T13:19:27.2089Z","end":"2026-08-16T13:19:27.315998Z","steps":["trace[1990735929] 'process raft request' (duration: 106.9434ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:19:27.327845Z","caller":"traceutil/trace.go:171","msg":"trace[447190542] transaction","detail":"{read_only:false; response_revision:13554; number_of_response:1; }","duration":"104.681067ms","start":"2026-08-16T13:19:27.223137Z","end":"2026-08-16T13:19:27.327818Z","steps":["trace[447190542] 'process raft request' (duration: 104.535503ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:19:49.527363Z","caller":"traceutil/trace.go:171","msg":"trace[1458456682] transaction","detail":"{read_only:false; response_revision:13673; number_of_response:1; }","duration":"132.196264ms","start":"2026-08-16T13:19:49.395137Z","end":"2026-08-16T13:19:49.527334Z","steps":["trace[1458456682] 'process raft request' (duration: 132.020709ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:20:04.104834Z","caller":"traceutil/trace.go:171","msg":"trace[1486735187] transaction","detail":"{read_only:false; response_revision:13764; number_of_response:1; }","duration":"160.128273ms","start":"2026-08-16T13:20:03.94468Z","end":"2026-08-16T13:20:04.104808Z","steps":["trace[1486735187] 'process raft request' (duration: 122.411542ms)","trace[1486735187] 'compare' (duration: 37.460134ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:20:04.992042Z","caller":"traceutil/trace.go:171","msg":"trace[1697304873] transaction","detail":"{read_only:false; response_revision:13770; number_of_response:1; }","duration":"196.401334ms","start":"2026-08-16T13:20:04.795619Z","end":"2026-08-16T13:20:04.99202Z","steps":["trace[1697304873] 'process raft request' (duration: 196.26483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:20:14.037191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.529867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:20:14.037308Z","caller":"traceutil/trace.go:171","msg":"trace[1431880663] linearizableReadLoop","detail":"{readStateIndex:14403; appliedIndex:14402; }","duration":"167.744185ms","start":"2026-08-16T13:20:13.869547Z","end":"2026-08-16T13:20:14.037292Z","steps":["trace[1431880663] 'read index received' (duration: 3.954561ms)","trace[1431880663] 'applied index is now lower than readState.Index' (duration: 163.788234ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:20:14.037416Z","caller":"traceutil/trace.go:171","msg":"trace[1128392802] transaction","detail":"{read_only:false; response_revision:13934; number_of_response:1; }","duration":"196.848915ms","start":"2026-08-16T13:20:13.840556Z","end":"2026-08-16T13:20:14.037405Z","steps":["trace[1128392802] 'process raft request' (duration: 33.002599ms)","trace[1128392802] 'compare' (duration: 163.245509ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:20:14.037702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.163856ms","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-08-16T13:20:14.037731Z","caller":"traceutil/trace.go:171","msg":"trace[1363584973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13934; }","duration":"168.235298ms","start":"2026-08-16T13:20:13.869487Z","end":"2026-08-16T13:20:14.037722Z","steps":["trace[1363584973] 'agreement among raft nodes before linearized reading' (duration: 168.110444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:20:14.3163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.890154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:20:14.316421Z","caller":"traceutil/trace.go:171","msg":"trace[918503479] transaction","detail":"{read_only:false; response_revision:13935; number_of_response:1; }","duration":"275.741622ms","start":"2026-08-16T13:20:14.040655Z","end":"2026-08-16T13:20:14.316396Z","steps":["trace[918503479] 'process raft request' (duration: 133.664403ms)","trace[918503479] 'compare' (duration: 141.76107ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:20:14.335231Z","caller":"traceutil/trace.go:171","msg":"trace[1664822449] linearizableReadLoop","detail":"{readStateIndex:14405; appliedIndex:14404; }","duration":"159.401412ms","start":"2026-08-16T13:20:14.175807Z","end":"2026-08-16T13:20:14.335208Z","steps":["trace[1664822449] 'read index received' (duration: 159.233398ms)","trace[1664822449] 'applied index is now lower than readState.Index' (duration: 167.004µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:20:14.335358Z","caller":"traceutil/trace.go:171","msg":"trace[2092404528] transaction","detail":"{read_only:false; response_revision:13936; number_of_response:1; }","duration":"193.817441ms","start":"2026-08-16T13:20:14.14153Z","end":"2026-08-16T13:20:14.335348Z","steps":["trace[2092404528] 'process raft request' (duration: 193.531593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:20:14.335584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.764462ms","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-08-16T13:20:14.335631Z","caller":"traceutil/trace.go:171","msg":"trace[353439805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13936; }","duration":"159.820963ms","start":"2026-08-16T13:20:14.175794Z","end":"2026-08-16T13:20:14.335615Z","steps":["trace[353439805] 'agreement among raft nodes before linearized reading' (duration: 159.6776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:20:14.335921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.624627ms","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-08-16T13:20:14.33596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.137098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-16T13:20:14.336023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.828138ms","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-08-16T13:20:14.336033Z","caller":"traceutil/trace.go:171","msg":"trace[1734185708] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13936; }","duration":"103.780361ms","start":"2026-08-16T13:20:14.232224Z","end":"2026-08-16T13:20:14.336004Z","steps":["trace[1734185708] 'agreement among raft nodes before linearized reading' (duration: 103.539415ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:20:14.336075Z","caller":"traceutil/trace.go:171","msg":"trace[883030188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13936; }","duration":"134.277132ms","start":"2026-08-16T13:20:14.201748Z","end":"2026-08-16T13:20:14.336025Z","steps":["trace[883030188] 'agreement among raft nodes before linearized reading' (duration: 134.020005ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:20:14.33611Z","caller":"traceutil/trace.go:171","msg":"trace[1477425916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13936; }","duration":"129.961451ms","start":"2026-08-16T13:20:14.20612Z","end":"2026-08-16T13:20:14.336081Z","steps":["trace[1477425916] 'agreement among raft nodes before linearized reading' (duration: 129.736565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.101641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.859459ms","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-08-16T13:21:45.101749Z","caller":"traceutil/trace.go:171","msg":"trace[490387258] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14971; }","duration":"523.002554ms","start":"2026-08-16T13:21:44.578724Z","end":"2026-08-16T13:21:45.101727Z","steps":["trace[490387258] 'range keys from in-memory index tree' (duration: 522.758486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.101805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.578712Z","time spent":"523.083376ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-16T13:21:45.101825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.957755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-16T13:21:45.102104Z","caller":"traceutil/trace.go:171","msg":"trace[714252313] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14971; }","duration":"526.304275ms","start":"2026-08-16T13:21:44.575775Z","end":"2026-08-16T13:21:45.10208Z","steps":["trace[714252313] 'range keys from in-memory index tree' (duration: 525.715828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.102216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.57919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-08-16T13:21:45.102262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.575752Z","time spent":"526.389547ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-16T13:21:45.102276Z","caller":"traceutil/trace.go:171","msg":"trace[1553209285] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14971; }","duration":"523.675343ms","start":"2026-08-16T13:21:44.578587Z","end":"2026-08-16T13:21:45.102262Z","steps":["trace[1553209285] 'range keys from in-memory index tree' (duration: 523.439096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.114046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.578569Z","time spent":"535.45351ms","remote":"127.0.0.1:35916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-16T13:21:45.114737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.49256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769828"} {"level":"info","ts":"2026-08-16T13:21:45.114812Z","caller":"traceutil/trace.go:171","msg":"trace[1718252052] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:14971; }","duration":"632.601633ms","start":"2026-08-16T13:21:44.482188Z","end":"2026-08-16T13:21:45.11479Z","steps":["trace[1718252052] 'range keys from in-memory index tree' (duration: 619.641743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.114843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.482167Z","time spent":"632.668544ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769851,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-16T13:21:45.118184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.262024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pgfjf\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-16T13:21:45.118263Z","caller":"traceutil/trace.go:171","msg":"trace[9325893] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pgfjf; range_end:; response_count:1; response_revision:14971; }","duration":"632.349786ms","start":"2026-08-16T13:21:44.485882Z","end":"2026-08-16T13:21:45.118231Z","steps":["trace[9325893] 'range keys from in-memory index tree' (duration: 616.192386ms)","trace[9325893] 'range keys from bolt db' (duration: 15.961464ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:21:45.1183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.485874Z","time spent":"632.415779ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22148,"request content":"key:\"/registry/pods/openstack/nova-compute-default-pgfjf\" "} {"level":"warn","ts":"2026-08-16T13:21:45.102179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.039406ms","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-08-16T13:21:45.118678Z","caller":"traceutil/trace.go:171","msg":"trace[744567340] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14971; }","duration":"538.582777ms","start":"2026-08-16T13:21:44.580073Z","end":"2026-08-16T13:21:45.118656Z","steps":["trace[744567340] 'range keys from in-memory index tree' (duration: 521.772729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.118739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.580053Z","time spent":"538.6652ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-08-16T13:21:45.101653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.639781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8vrdp\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-08-16T13:21:45.118954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.397134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:21:45.118974Z","caller":"traceutil/trace.go:171","msg":"trace[1037705865] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8vrdp; range_end:; response_count:1; response_revision:14971; }","duration":"471.957583ms","start":"2026-08-16T13:21:44.646955Z","end":"2026-08-16T13:21:45.118913Z","steps":["trace[1037705865] 'range keys from in-memory index tree' (duration: 454.409415ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:21:45.119059Z","caller":"traceutil/trace.go:171","msg":"trace[2134495623] transaction","detail":"{read_only:false; response_revision:14972; number_of_response:1; }","duration":"448.190761ms","start":"2026-08-16T13:21:44.670845Z","end":"2026-08-16T13:21:45.119036Z","steps":["trace[2134495623] 'process raft request' (duration: 39.644723ms)","trace[2134495623] 'compare' (duration: 391.144224ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:21:45.119054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.646935Z","time spent":"472.093247ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-8vrdp\" "} {"level":"info","ts":"2026-08-16T13:21:45.119064Z","caller":"traceutil/trace.go:171","msg":"trace[399447532] linearizableReadLoop","detail":"{readStateIndex:15472; appliedIndex:15471; }","duration":"371.289192ms","start":"2026-08-16T13:21:44.747731Z","end":"2026-08-16T13:21:45.11902Z","steps":["trace[399447532] 'read index received' (duration: 45.752µs)","trace[399447532] 'applied index is now lower than readState.Index' (duration: 371.24155ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:21:45.119167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.439726ms","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-08-16T13:21:45.119263Z","caller":"traceutil/trace.go:171","msg":"trace[1121678318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14972; }","duration":"371.518968ms","start":"2026-08-16T13:21:44.747681Z","end":"2026-08-16T13:21:45.1192Z","steps":["trace[1121678318] 'agreement among raft nodes before linearized reading' (duration: 371.402915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.119295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.880875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T13:21:45.119313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.74766Z","time spent":"371.632631ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-16T13:21:45.119344Z","caller":"traceutil/trace.go:171","msg":"trace[1404040546] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14972; }","duration":"326.962849ms","start":"2026-08-16T13:21:44.792368Z","end":"2026-08-16T13:21:45.119331Z","steps":["trace[1404040546] 'agreement among raft nodes before linearized reading' (duration: 326.882426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.119371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.792333Z","time spent":"327.026839ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-16T13:21:45.11945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.805828ms","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-08-16T13:21:45.119485Z","caller":"traceutil/trace.go:171","msg":"trace[2031010606] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14972; }","duration":"195.874241ms","start":"2026-08-16T13:21:44.9236Z","end":"2026-08-16T13:21:45.119474Z","steps":["trace[2031010606] 'agreement among raft nodes before linearized reading' (duration: 195.808509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.119587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.29527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-16T13:21:45.119615Z","caller":"traceutil/trace.go:171","msg":"trace[645195462] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14972; }","duration":"197.359341ms","start":"2026-08-16T13:21:44.922248Z","end":"2026-08-16T13:21:45.119607Z","steps":["trace[645195462] 'agreement among raft nodes before linearized reading' (duration: 197.243328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.119686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.222585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-16T13:21:45.119715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.861045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-08-16T13:21:45.119774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.682972ms","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-08-16T13:21:45.11976Z","caller":"traceutil/trace.go:171","msg":"trace[653434667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14972; }","duration":"327.316408ms","start":"2026-08-16T13:21:44.792412Z","end":"2026-08-16T13:21:45.119729Z","steps":["trace[653434667] 'agreement among raft nodes before linearized reading' (duration: 327.163623ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:21:45.119841Z","caller":"traceutil/trace.go:171","msg":"trace[1546339176] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14972; }","duration":"238.756894ms","start":"2026-08-16T13:21:44.881046Z","end":"2026-08-16T13:21:45.119803Z","steps":["trace[1546339176] 'agreement among raft nodes before linearized reading' (duration: 238.686292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.119888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.792392Z","time spent":"327.452652ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-16T13:21:45.119963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.341993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-08-16T13:21:45.120052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.967808ms","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-08-16T13:21:45.119687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.507202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-16T13:21:45.120135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.548773ms","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-08-16T13:21:45.12013Z","caller":"traceutil/trace.go:171","msg":"trace[958069474] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14972; }","duration":"189.05296ms","start":"2026-08-16T13:21:44.931058Z","end":"2026-08-16T13:21:45.12011Z","steps":["trace[958069474] 'agreement among raft nodes before linearized reading' (duration: 188.885906ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:21:45.120168Z","caller":"traceutil/trace.go:171","msg":"trace[206921006] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14972; }","duration":"323.603824ms","start":"2026-08-16T13:21:44.796549Z","end":"2026-08-16T13:21:45.120153Z","steps":["trace[206921006] 'agreement among raft nodes before linearized reading' (duration: 323.546433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.120185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.796534Z","time spent":"323.645765ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-08-16T13:21:45.120249Z","caller":"traceutil/trace.go:171","msg":"trace[2133628707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14972; }","duration":"323.978705ms","start":"2026-08-16T13:21:44.796143Z","end":"2026-08-16T13:21:45.120122Z","steps":["trace[2133628707] 'agreement among raft nodes before linearized reading' (duration: 323.423199ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:21:45.12027Z","caller":"traceutil/trace.go:171","msg":"trace[416561609] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14972; }","duration":"325.57355ms","start":"2026-08-16T13:21:44.794559Z","end":"2026-08-16T13:21:45.120133Z","steps":["trace[416561609] 'agreement among raft nodes before linearized reading' (duration: 325.011874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.12038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.359119ms","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-08-16T13:21:45.120404Z","caller":"traceutil/trace.go:171","msg":"trace[582355994] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14972; }","duration":"189.40405ms","start":"2026-08-16T13:21:44.930993Z","end":"2026-08-16T13:21:45.120397Z","steps":["trace[582355994] 'agreement among raft nodes before linearized reading' (duration: 189.361859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.120362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.794551Z","time spent":"325.798816ms","remote":"127.0.0.1:35916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-16T13:21:45.120397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.796134Z","time spent":"324.15826ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-16T13:21:45.12072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.668178ms","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-08-16T13:21:45.120747Z","caller":"traceutil/trace.go:171","msg":"trace[476980042] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14972; }","duration":"189.700719ms","start":"2026-08-16T13:21:44.931035Z","end":"2026-08-16T13:21:45.120736Z","steps":["trace[476980042] 'agreement among raft nodes before linearized reading' (duration: 189.430201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.120033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.032022ms","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-08-16T13:21:45.120786Z","caller":"traceutil/trace.go:171","msg":"trace[622728179] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14972; }","duration":"303.811344ms","start":"2026-08-16T13:21:44.816967Z","end":"2026-08-16T13:21:45.120779Z","steps":["trace[622728179] 'agreement among raft nodes before linearized reading' (duration: 303.017162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.120804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.816954Z","time spent":"303.845184ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-16T13:21:45.120084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.544585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T13:21:45.12092Z","caller":"traceutil/trace.go:171","msg":"trace[1759609381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14972; }","duration":"309.400019ms","start":"2026-08-16T13:21:44.811508Z","end":"2026-08-16T13:21:45.120908Z","steps":["trace[1759609381] 'agreement among raft nodes before linearized reading' (duration: 308.538305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.120956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.811494Z","time spent":"309.45248ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T13:21:45.120946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.708181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:21:45.121003Z","caller":"traceutil/trace.go:171","msg":"trace[1844882473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14972; }","duration":"165.783294ms","start":"2026-08-16T13:21:44.955203Z","end":"2026-08-16T13:21:45.120986Z","steps":["trace[1844882473] 'agreement among raft nodes before linearized reading' (duration: 165.684411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.121066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.40555ms","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-08-16T13:21:45.121096Z","caller":"traceutil/trace.go:171","msg":"trace[1948123344] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14972; }","duration":"194.459481ms","start":"2026-08-16T13:21:44.92663Z","end":"2026-08-16T13:21:45.12109Z","steps":["trace[1948123344] 'agreement among raft nodes before linearized reading' (duration: 194.219175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.121404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.79641ms","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-08-16T13:21:45.121484Z","caller":"traceutil/trace.go:171","msg":"trace[1084933734] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14972; }","duration":"194.922364ms","start":"2026-08-16T13:21:44.926534Z","end":"2026-08-16T13:21:45.121456Z","steps":["trace[1084933734] 'agreement among raft nodes before linearized reading' (duration: 194.334147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.122185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.670825Z","time spent":"448.266134ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:21:45.119818Z","caller":"traceutil/trace.go:171","msg":"trace[134832620] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14972; }","duration":"326.976268ms","start":"2026-08-16T13:21:44.792803Z","end":"2026-08-16T13:21:45.119779Z","steps":["trace[134832620] 'agreement among raft nodes before linearized reading' (duration: 326.766232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:21:45.122687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:21:44.792796Z","time spent":"329.876979ms","remote":"127.0.0.1:35916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-08-16T13:23:10.392803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13021} {"level":"info","ts":"2026-08-16T13:23:10.469869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13021,"took":"74.486195ms","hash":1593431831,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":30720000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-16T13:23:10.469927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1593431831,"revision":13021,"compact-revision":10826} {"level":"info","ts":"2026-08-16T13:27:36.775181Z","caller":"traceutil/trace.go:171","msg":"trace[1267635192] transaction","detail":"{read_only:false; response_revision:17763; number_of_response:1; }","duration":"100.652916ms","start":"2026-08-16T13:27:36.674503Z","end":"2026-08-16T13:27:36.775156Z","steps":["trace[1267635192] 'process raft request' (duration: 100.120451ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:27:36.915388Z","caller":"traceutil/trace.go:171","msg":"trace[2142422896] transaction","detail":"{read_only:false; response_revision:17764; number_of_response:1; }","duration":"135.773414ms","start":"2026-08-16T13:27:36.779586Z","end":"2026-08-16T13:27:36.91536Z","steps":["trace[2142422896] 'process raft request' (duration: 70.528686ms)","trace[2142422896] 'compare' (duration: 65.097834ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:27:36.939366Z","caller":"traceutil/trace.go:171","msg":"trace[391376467] linearizableReadLoop","detail":"{readStateIndex:18353; appliedIndex:18351; }","duration":"140.219758ms","start":"2026-08-16T13:27:36.799123Z","end":"2026-08-16T13:27:36.939342Z","steps":["trace[391376467] 'read index received' (duration: 51.007781ms)","trace[391376467] 'applied index is now lower than readState.Index' (duration: 89.210707ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:27:36.939463Z","caller":"traceutil/trace.go:171","msg":"trace[1209532601] transaction","detail":"{read_only:false; response_revision:17765; number_of_response:1; }","duration":"158.061075ms","start":"2026-08-16T13:27:36.781368Z","end":"2026-08-16T13:27:36.939429Z","steps":["trace[1209532601] 'process raft request' (duration: 157.86738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:36.939621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.488176ms","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-08-16T13:27:36.939687Z","caller":"traceutil/trace.go:171","msg":"trace[1487686543] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:17765; }","duration":"140.592299ms","start":"2026-08-16T13:27:36.79908Z","end":"2026-08-16T13:27:36.939672Z","steps":["trace[1487686543] 'agreement among raft nodes before linearized reading' (duration: 140.365693ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:27:37.351678Z","caller":"traceutil/trace.go:171","msg":"trace[1578529344] transaction","detail":"{read_only:false; response_revision:17774; number_of_response:1; }","duration":"282.620447ms","start":"2026-08-16T13:27:37.069025Z","end":"2026-08-16T13:27:37.351645Z","steps":["trace[1578529344] 'process raft request' (duration: 257.016903ms)","trace[1578529344] 'compare' (duration: 25.397498ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:27:37.408308Z","caller":"traceutil/trace.go:171","msg":"trace[403619908] transaction","detail":"{read_only:false; response_revision:17775; number_of_response:1; }","duration":"332.820836ms","start":"2026-08-16T13:27:37.075456Z","end":"2026-08-16T13:27:37.408277Z","steps":["trace[403619908] 'process raft request' (duration: 332.59433ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:27:37.408486Z","caller":"traceutil/trace.go:171","msg":"trace[1982909350] linearizableReadLoop","detail":"{readStateIndex:18363; appliedIndex:18361; }","duration":"318.539198ms","start":"2026-08-16T13:27:37.089916Z","end":"2026-08-16T13:27:37.408455Z","steps":["trace[1982909350] 'read index received' (duration: 236.465481ms)","trace[1982909350] 'applied index is now lower than readState.Index' (duration: 82.070997ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:27:37.408613Z","caller":"traceutil/trace.go:171","msg":"trace[475610872] transaction","detail":"{read_only:false; response_revision:17776; number_of_response:1; }","duration":"272.167016ms","start":"2026-08-16T13:27:37.136431Z","end":"2026-08-16T13:27:37.408598Z","steps":["trace[475610872] 'process raft request' (duration: 271.772755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:37.408753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:27:37.075438Z","time spent":"332.96186ms","remote":"127.0.0.1:35920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20871,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:27:37.408828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.913828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-16T13:27:37.409152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.06524ms","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-08-16T13:27:37.409176Z","caller":"traceutil/trace.go:171","msg":"trace[1247191182] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17776; }","duration":"319.32243ms","start":"2026-08-16T13:27:37.089841Z","end":"2026-08-16T13:27:37.409163Z","steps":["trace[1247191182] 'agreement among raft nodes before linearized reading' (duration: 318.910888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:37.409224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:27:37.089819Z","time spent":"319.391771ms","remote":"127.0.0.1:36182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-16T13:27:37.409226Z","caller":"traceutil/trace.go:171","msg":"trace[753600598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17776; }","duration":"264.359118ms","start":"2026-08-16T13:27:37.144846Z","end":"2026-08-16T13:27:37.409205Z","steps":["trace[753600598] 'agreement among raft nodes before linearized reading' (duration: 263.969347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:37.409142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.069936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-s8ghs\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-08-16T13:27:37.409641Z","caller":"traceutil/trace.go:171","msg":"trace[985333231] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-s8ghs; range_end:; response_count:1; response_revision:17776; }","duration":"233.603281ms","start":"2026-08-16T13:27:37.176015Z","end":"2026-08-16T13:27:37.409618Z","steps":["trace[985333231] 'agreement among raft nodes before linearized reading' (duration: 232.915701ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:27:37.944106Z","caller":"traceutil/trace.go:171","msg":"trace[2144014169] linearizableReadLoop","detail":"{readStateIndex:18366; appliedIndex:18365; }","duration":"152.055988ms","start":"2026-08-16T13:27:37.792017Z","end":"2026-08-16T13:27:37.944073Z","steps":["trace[2144014169] 'read index received' (duration: 151.832642ms)","trace[2144014169] 'applied index is now lower than readState.Index' (duration: 221.996µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:27:37.944208Z","caller":"traceutil/trace.go:171","msg":"trace[2010150278] transaction","detail":"{read_only:false; response_revision:17778; number_of_response:1; }","duration":"311.346517ms","start":"2026-08-16T13:27:37.632824Z","end":"2026-08-16T13:27:37.94417Z","steps":["trace[2010150278] 'process raft request' (duration: 311.057569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:37.944321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.260473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-16T13:27:37.944381Z","caller":"traceutil/trace.go:171","msg":"trace[2123325864] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17778; }","duration":"152.400037ms","start":"2026-08-16T13:27:37.791966Z","end":"2026-08-16T13:27:37.944366Z","steps":["trace[2123325864] 'agreement among raft nodes before linearized reading' (duration: 152.261653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:37.944381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:27:37.632806Z","time spent":"311.482121ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:28:10.399554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15545} {"level":"info","ts":"2026-08-16T13:28:10.49956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15545,"took":"96.628213ms","hash":1957072077,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":33730560,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-16T13:28:10.499621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1957072077,"revision":15545,"compact-revision":13021} {"level":"info","ts":"2026-08-16T13:28:33.470124Z","caller":"traceutil/trace.go:171","msg":"trace[537966822] linearizableReadLoop","detail":"{readStateIndex:18925; appliedIndex:18924; }","duration":"104.832902ms","start":"2026-08-16T13:28:33.365268Z","end":"2026-08-16T13:28:33.470101Z","steps":["trace[537966822] 'read index received' (duration: 104.75455ms)","trace[537966822] 'applied index is now lower than readState.Index' (duration: 77.442µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:28:33.470666Z","caller":"traceutil/trace.go:171","msg":"trace[520904147] transaction","detail":"{read_only:false; response_revision:18318; number_of_response:1; }","duration":"106.321333ms","start":"2026-08-16T13:28:33.364329Z","end":"2026-08-16T13:28:33.47065Z","steps":["trace[520904147] 'process raft request' (duration: 105.616953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:28:33.470911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.641945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-16T13:28:33.470949Z","caller":"traceutil/trace.go:171","msg":"trace[92422827] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18318; }","duration":"105.718856ms","start":"2026-08-16T13:28:33.365218Z","end":"2026-08-16T13:28:33.470936Z","steps":["trace[92422827] 'agreement among raft nodes before linearized reading' (duration: 105.595763ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:28:33.475916Z","caller":"traceutil/trace.go:171","msg":"trace[120383689] transaction","detail":"{read_only:false; response_revision:18319; number_of_response:1; }","duration":"110.228752ms","start":"2026-08-16T13:28:33.36567Z","end":"2026-08-16T13:28:33.475899Z","steps":["trace[120383689] 'process raft request' (duration: 110.013326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:28:33.476505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.746428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-16T13:28:33.476602Z","caller":"traceutil/trace.go:171","msg":"trace[1581627897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18319; }","duration":"109.871092ms","start":"2026-08-16T13:28:33.36671Z","end":"2026-08-16T13:28:33.476581Z","steps":["trace[1581627897] 'agreement among raft nodes before linearized reading' (duration: 109.209824ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:30:59.12883Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:30:59.134028Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-16T13:30:59.134153Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-16T13:32:28.858912Z","caller":"traceutil/trace.go:171","msg":"trace[1413068476] transaction","detail":"{read_only:false; response_revision:19936; number_of_response:1; }","duration":"195.5068ms","start":"2026-08-16T13:32:28.66338Z","end":"2026-08-16T13:32:28.858887Z","steps":["trace[1413068476] 'process raft request' (duration: 138.344306ms)","trace[1413068476] 'compare' (duration: 57.071841ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:32:28.888551Z","caller":"traceutil/trace.go:171","msg":"trace[842123266] transaction","detail":"{read_only:false; response_revision:19938; number_of_response:1; }","duration":"222.538273ms","start":"2026-08-16T13:32:28.665987Z","end":"2026-08-16T13:32:28.888525Z","steps":["trace[842123266] 'process raft request' (duration: 222.458561ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:32:28.888576Z","caller":"traceutil/trace.go:171","msg":"trace[186000108] transaction","detail":"{read_only:false; response_revision:19937; number_of_response:1; }","duration":"224.973781ms","start":"2026-08-16T13:32:28.663553Z","end":"2026-08-16T13:32:28.888527Z","steps":["trace[186000108] 'process raft request' (duration: 224.726924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:32:29.066003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.465867ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-16T13:32:29.066359Z","caller":"traceutil/trace.go:171","msg":"trace[1075573174] linearizableReadLoop","detail":"{readStateIndex:20603; appliedIndex:20602; }","duration":"164.949538ms","start":"2026-08-16T13:32:28.901369Z","end":"2026-08-16T13:32:29.066319Z","steps":["trace[1075573174] 'read index received' (duration: 31.211µs)","trace[1075573174] 'applied index is now lower than readState.Index' (duration: 164.915237ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:32:29.066565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.174974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-16T13:32:29.066604Z","caller":"traceutil/trace.go:171","msg":"trace[693577606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19939; }","duration":"165.267597ms","start":"2026-08-16T13:32:28.901325Z","end":"2026-08-16T13:32:29.066593Z","steps":["trace[693577606] 'agreement among raft nodes before linearized reading' (duration: 165.108012ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:33:10.405033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18126} {"level":"info","ts":"2026-08-16T13:33:10.509778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18126,"took":"101.295625ms","hash":199209241,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":30535680,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-16T13:33:10.509865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":199209241,"revision":18126,"compact-revision":15545} {"level":"info","ts":"2026-08-16T13:36:02.354454Z","caller":"traceutil/trace.go:171","msg":"trace[666736846] transaction","detail":"{read_only:false; response_revision:22036; number_of_response:1; }","duration":"107.638553ms","start":"2026-08-16T13:36:02.246787Z","end":"2026-08-16T13:36:02.354425Z","steps":["trace[666736846] 'process raft request' (duration: 104.460985ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:36:02.354642Z","caller":"traceutil/trace.go:171","msg":"trace[1324844732] transaction","detail":"{read_only:false; response_revision:22037; number_of_response:1; }","duration":"100.817403ms","start":"2026-08-16T13:36:02.253814Z","end":"2026-08-16T13:36:02.354632Z","steps":["trace[1324844732] 'process raft request' (duration: 100.525175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:36:02.354834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.047909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-08-16T13:36:02.354888Z","caller":"traceutil/trace.go:171","msg":"trace[1700143900] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:22038; }","duration":"101.143892ms","start":"2026-08-16T13:36:02.253732Z","end":"2026-08-16T13:36:02.354876Z","steps":["trace[1700143900] 'agreement among raft nodes before linearized reading' (duration: 100.974307ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:36:10.380769Z","caller":"traceutil/trace.go:171","msg":"trace[1014469104] transaction","detail":"{read_only:false; response_revision:22308; number_of_response:1; }","duration":"183.301453ms","start":"2026-08-16T13:36:10.197444Z","end":"2026-08-16T13:36:10.380746Z","steps":["trace[1014469104] 'process raft request' (duration: 183.044176ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:38:10.410198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20411} {"level":"info","ts":"2026-08-16T13:38:10.517844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20411,"took":"103.900559ms","hash":1256780979,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":43298816,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-16T13:38:10.517909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1256780979,"revision":20411,"compact-revision":18126} {"level":"info","ts":"2026-08-16T13:43:10.416384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23482} {"level":"info","ts":"2026-08-16T13:43:10.542761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23482,"took":"123.312563ms","hash":2216217894,"current-db-size-bytes":58953728,"current-db-size":"59 MB","current-db-size-in-use-bytes":30990336,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-16T13:43:10.542821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2216217894,"revision":23482,"compact-revision":20411}