{"level":"warn","ts":"2026-10-14T17:46:06.354369Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:46:06.354564Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.213:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.213:2380","--initial-cluster=instance=https://162.253.55.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.213: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-10-14T17:46:06.354819Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:46:06.354854Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-10-14T17:46:06.354909Z","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-10-14T17:46:06.356439Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"]} {"level":"info","ts":"2026-10-14T17:46:06.356618Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.213: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-10-14T17:46:06.362244Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.237479ms"} {"level":"info","ts":"2026-10-14T17:46:06.369397Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"43ce62d0a10d51da","cluster-id":"25e0c2268a6b42c6"} {"level":"info","ts":"2026-10-14T17:46:06.369516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:46:06.369625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 0"} {"level":"info","ts":"2026-10-14T17:46:06.369749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 43ce62d0a10d51da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:46:06.369774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 1"} {"level":"info","ts":"2026-10-14T17:46:06.369835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"warn","ts":"2026-10-14T17:46:06.378726Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:46:06.380932Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:46:06.382854Z","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-10-14T17:46:06.386805Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"43ce62d0a10d51da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:46:06.38708Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"43ce62d0a10d51da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:46:06.387119Z","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-10-14T17:46:06.387257Z","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-10-14T17:46:06.38727Z","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-10-14T17:46:06.391143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"info","ts":"2026-10-14T17:46:06.391391Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","added-peer-id":"43ce62d0a10d51da","added-peer-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-10-14T17:46:06.392673Z","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-10-14T17:46:06.392768Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-10-14T17:46:06.392839Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-10-14T17:46:06.393218Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"43ce62d0a10d51da","initial-advertise-peer-urls":["https://162.253.55.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:46:06.393317Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:46:06.871133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:46:06.871344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:46:06.871454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgPreVoteResp from 43ce62d0a10d51da at term 1"} {"level":"info","ts":"2026-10-14T17:46:06.871507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:46:06.871554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgVoteResp from 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-10-14T17:46:06.871606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became leader at term 2"} {"level":"info","ts":"2026-10-14T17:46:06.871645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 43ce62d0a10d51da elected leader 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-10-14T17:46:06.896279Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:46:06.903044Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:46:06.903046Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"43ce62d0a10d51da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.213:2379]}","request-path":"/0/members/43ce62d0a10d51da/attributes","cluster-id":"25e0c2268a6b42c6","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:46:06.903122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:46:06.903446Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:46:06.903492Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:46:06.907234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:46:06.909223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.213:2379"} {"level":"info","ts":"2026-10-14T17:46:06.909762Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:46:06.909988Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:46:06.91003Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:46:39.838435Z","caller":"traceutil/trace.go:171","msg":"trace[1727152183] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"106.755688ms","start":"2026-10-14T17:46:39.731632Z","end":"2026-10-14T17:46:39.838388Z","steps":["trace[1727152183] 'process raft request' (duration: 106.390728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:46:59.672329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.843259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:46:59.672519Z","caller":"traceutil/trace.go:171","msg":"trace[1273146555] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"223.467773ms","start":"2026-10-14T17:46:59.449026Z","end":"2026-10-14T17:46:59.672494Z","steps":["trace[1273146555] 'read index received' (duration: 105.748919ms)","trace[1273146555] 'applied index is now lower than readState.Index' (duration: 117.717344ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:46:59.672698Z","caller":"traceutil/trace.go:171","msg":"trace[453608587] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"264.486458ms","start":"2026-10-14T17:46:59.408174Z","end":"2026-10-14T17:46:59.67266Z","steps":["trace[453608587] 'process raft request' (duration: 146.737374ms)","trace[453608587] 'compare' (duration: 116.627974ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:46:59.672832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.770011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s4vsd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T17:46:59.672883Z","caller":"traceutil/trace.go:171","msg":"trace[1177871333] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s4vsd; range_end:; response_count:1; response_revision:651; }","duration":"223.918214ms","start":"2026-10-14T17:46:59.448952Z","end":"2026-10-14T17:46:59.672871Z","steps":["trace[1177871333] 'agreement among raft nodes before linearized reading' (duration: 223.597036ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:46:59.938429Z","caller":"traceutil/trace.go:171","msg":"trace[1979626696] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"254.336721ms","start":"2026-10-14T17:46:59.684047Z","end":"2026-10-14T17:46:59.938384Z","steps":["trace[1979626696] 'read index received' (duration: 181.813072ms)","trace[1979626696] 'applied index is now lower than readState.Index' (duration: 72.522239ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:46:59.938489Z","caller":"traceutil/trace.go:171","msg":"trace[1984665680] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"340.869167ms","start":"2026-10-14T17:46:59.597568Z","end":"2026-10-14T17:46:59.938437Z","steps":["trace[1984665680] 'process raft request' (duration: 268.273667ms)","trace[1984665680] 'compare' (duration: 72.372295ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:46:59.938575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.513965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:46:59.93869Z","caller":"traceutil/trace.go:171","msg":"trace[1078009479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"254.703031ms","start":"2026-10-14T17:46:59.683963Z","end":"2026-10-14T17:46:59.938666Z","steps":["trace[1078009479] 'agreement among raft nodes before linearized reading' (duration: 254.504485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:46:59.939379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:46:59.597545Z","time spent":"341.076473ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:47:00.439636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898203939009767269,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-14T17:47:00.488873Z","caller":"traceutil/trace.go:171","msg":"trace[1575614117] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"550.294933ms","start":"2026-10-14T17:46:59.938537Z","end":"2026-10-14T17:47:00.488832Z","steps":["trace[1575614117] 'read index received' (duration: 536.660726ms)","trace[1575614117] 'applied index is now lower than readState.Index' (duration: 13.632727ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:00.488944Z","caller":"traceutil/trace.go:171","msg":"trace[762228] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"791.85904ms","start":"2026-10-14T17:46:59.697038Z","end":"2026-10-14T17:47:00.488897Z","steps":["trace[762228] 'process raft request' (duration: 778.233383ms)","trace[762228] 'compare' (duration: 13.35063ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:47:00.489061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.569737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:47:00.489126Z","caller":"traceutil/trace.go:171","msg":"trace[189679873] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:653; }","duration":"803.72036ms","start":"2026-10-14T17:46:59.685382Z","end":"2026-10-14T17:47:00.489102Z","steps":["trace[189679873] 'agreement among raft nodes before linearized reading' (duration: 803.592927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:00.489137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:46:59.697003Z","time spent":"792.033765ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:47:00.489165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:46:59.685353Z","time spent":"803.802793ms","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-10-14T17:47:00.489174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.547264ms","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-10-14T17:47:00.489229Z","caller":"traceutil/trace.go:171","msg":"trace[679386521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"478.683377ms","start":"2026-10-14T17:47:00.010529Z","end":"2026-10-14T17:47:00.489212Z","steps":["trace[679386521] 'agreement among raft nodes before linearized reading' (duration: 478.538533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:00.48928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:47:00.010504Z","time spent":"478.763499ms","remote":"127.0.0.1:38058","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":"warn","ts":"2026-10-14T17:47:00.489506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.510296ms","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-10-14T17:47:00.489548Z","caller":"traceutil/trace.go:171","msg":"trace[1616712761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"414.600298ms","start":"2026-10-14T17:47:00.074937Z","end":"2026-10-14T17:47:00.489537Z","steps":["trace[1616712761] 'agreement among raft nodes before linearized reading' (duration: 414.523396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:00.48958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:47:00.074908Z","time spent":"414.66237ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-14T17:47:00.489574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.408287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.213\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T17:47:00.489693Z","caller":"traceutil/trace.go:171","msg":"trace[976962261] range","detail":"{range_begin:/registry/masterleases/162.253.55.213; range_end:; response_count:1; response_revision:653; }","duration":"119.599902ms","start":"2026-10-14T17:47:00.370067Z","end":"2026-10-14T17:47:00.489667Z","steps":["trace[976962261] 'agreement among raft nodes before linearized reading' (duration: 119.314934ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:01.71586Z","caller":"traceutil/trace.go:171","msg":"trace[1015926684] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"122.288474ms","start":"2026-10-14T17:47:01.593539Z","end":"2026-10-14T17:47:01.715828Z","steps":["trace[1015926684] 'process raft request' (duration: 122.113469ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:47:16.736356Z","caller":"traceutil/trace.go:171","msg":"trace[1050294853] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"127.844209ms","start":"2026-10-14T17:47:16.608491Z","end":"2026-10-14T17:47:16.736335Z","steps":["trace[1050294853] 'read index received' (duration: 127.633444ms)","trace[1050294853] 'applied index is now lower than readState.Index' (duration: 209.355µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:47:16.736406Z","caller":"traceutil/trace.go:171","msg":"trace[194427727] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"163.634626ms","start":"2026-10-14T17:47:16.572745Z","end":"2026-10-14T17:47:16.73638Z","steps":["trace[194427727] 'process raft request' (duration: 163.430841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:16.736644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.530869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:47:16.736778Z","caller":"traceutil/trace.go:171","msg":"trace[1834483374] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:820; }","duration":"109.632811ms","start":"2026-10-14T17:47:16.627057Z","end":"2026-10-14T17:47:16.73669Z","steps":["trace[1834483374] 'agreement among raft nodes before linearized reading' (duration: 109.516528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:16.736875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.162835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:47:16.736989Z","caller":"traceutil/trace.go:171","msg":"trace[587383932] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:820; }","duration":"110.326569ms","start":"2026-10-14T17:47:16.626633Z","end":"2026-10-14T17:47:16.73696Z","steps":["trace[587383932] 'agreement among raft nodes before linearized reading' (duration: 110.063932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:16.737038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.151775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:47:16.738418Z","caller":"traceutil/trace.go:171","msg":"trace[1833719367] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:820; }","duration":"111.619593ms","start":"2026-10-14T17:47:16.626758Z","end":"2026-10-14T17:47:16.738377Z","steps":["trace[1833719367] 'agreement among raft nodes before linearized reading' (duration: 110.086613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:16.737107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.116464ms","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-10-14T17:47:16.738653Z","caller":"traceutil/trace.go:171","msg":"trace[1216152909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:820; }","duration":"124.699307ms","start":"2026-10-14T17:47:16.613943Z","end":"2026-10-14T17:47:16.738642Z","steps":["trace[1216152909] 'agreement among raft nodes before linearized reading' (duration: 123.045303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:16.737302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.780213ms","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-10-14T17:47:16.738877Z","caller":"traceutil/trace.go:171","msg":"trace[447697892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:820; }","duration":"130.427326ms","start":"2026-10-14T17:47:16.60844Z","end":"2026-10-14T17:47:16.738867Z","steps":["trace[447697892] 'agreement among raft nodes before linearized reading' (duration: 128.027234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:47:16.737407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.002597ms","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-10-14T17:47:16.738975Z","caller":"traceutil/trace.go:171","msg":"trace[1793335303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"128.592148ms","start":"2026-10-14T17:47:16.610374Z","end":"2026-10-14T17:47:16.738966Z","steps":["trace[1793335303] 'agreement among raft nodes before linearized reading' (duration: 126.188735ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:36.478688Z","caller":"traceutil/trace.go:171","msg":"trace[1034905836] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"154.049359ms","start":"2026-10-14T17:48:36.324566Z","end":"2026-10-14T17:48:36.478616Z","steps":["trace[1034905836] 'process raft request' (duration: 60.644164ms)","trace[1034905836] 'compare' (duration: 93.253862ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:48:36.866675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.642209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:48:36.866841Z","caller":"traceutil/trace.go:171","msg":"trace[1451353765] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"238.48284ms","start":"2026-10-14T17:48:36.62833Z","end":"2026-10-14T17:48:36.866813Z","steps":["trace[1451353765] 'read index received' (duration: 54.212µs)","trace[1451353765] 'applied index is now lower than readState.Index' (duration: 238.426858ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:36.866965Z","caller":"traceutil/trace.go:171","msg":"trace[492815551] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"292.473009ms","start":"2026-10-14T17:48:36.574471Z","end":"2026-10-14T17:48:36.866944Z","steps":["trace[492815551] 'process raft request' (duration: 30.402279ms)","trace[492815551] 'compare' (duration: 261.33416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:48:36.867081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.465047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:48:36.867164Z","caller":"traceutil/trace.go:171","msg":"trace[532007348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1894; }","duration":"182.657901ms","start":"2026-10-14T17:48:36.684488Z","end":"2026-10-14T17:48:36.867146Z","steps":["trace[532007348] 'agreement among raft nodes before linearized reading' (duration: 182.521628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:48:36.867366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.026264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84a83e07-939e-48c3-aa87-eb0fbb5344f6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-14T17:48:36.867518Z","caller":"traceutil/trace.go:171","msg":"trace[862815620] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84a83e07-939e-48c3-aa87-eb0fbb5344f6; range_end:; response_count:1; response_revision:1894; }","duration":"239.222559ms","start":"2026-10-14T17:48:36.628279Z","end":"2026-10-14T17:48:36.867501Z","steps":["trace[862815620] 'agreement among raft nodes before linearized reading' (duration: 238.804059ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:37.219485Z","caller":"traceutil/trace.go:171","msg":"trace[1320531722] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"146.480652ms","start":"2026-10-14T17:48:37.072976Z","end":"2026-10-14T17:48:37.219457Z","steps":["trace[1320531722] 'process raft request' (duration: 146.252265ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:37.443842Z","caller":"traceutil/trace.go:171","msg":"trace[536456000] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"143.78299ms","start":"2026-10-14T17:48:37.300024Z","end":"2026-10-14T17:48:37.443807Z","steps":["trace[536456000] 'process raft request' (duration: 143.530954ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:37.574221Z","caller":"traceutil/trace.go:171","msg":"trace[259068112] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1967; }","duration":"236.380743ms","start":"2026-10-14T17:48:37.337813Z","end":"2026-10-14T17:48:37.574193Z","steps":["trace[259068112] 'read index received' (duration: 105.75773ms)","trace[259068112] 'applied index is now lower than readState.Index' (duration: 130.621833ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:37.574343Z","caller":"traceutil/trace.go:171","msg":"trace[1144936618] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"274.164557ms","start":"2026-10-14T17:48:37.300165Z","end":"2026-10-14T17:48:37.574329Z","steps":["trace[1144936618] 'process raft request' (duration: 221.953945ms)","trace[1144936618] 'compare' (duration: 51.849052ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:48:37.574773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.899106ms","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-10-14T17:48:37.574868Z","caller":"traceutil/trace.go:171","msg":"trace[1147887732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1897; }","duration":"237.083652ms","start":"2026-10-14T17:48:37.33776Z","end":"2026-10-14T17:48:37.574843Z","steps":["trace[1147887732] 'agreement among raft nodes before linearized reading' (duration: 236.681182ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:27.901157Z","caller":"traceutil/trace.go:171","msg":"trace[37164901] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"145.854398ms","start":"2026-10-14T17:49:27.755278Z","end":"2026-10-14T17:49:27.901132Z","steps":["trace[37164901] 'process raft request' (duration: 145.729525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:28.067404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.689045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-10-14T17:49:28.067463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.295913ms","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-10-14T17:49:28.067502Z","caller":"traceutil/trace.go:171","msg":"trace[392913869] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2225; }","duration":"148.821018ms","start":"2026-10-14T17:49:27.918656Z","end":"2026-10-14T17:49:28.067477Z","steps":["trace[392913869] 'range keys from in-memory index tree' (duration: 148.54795ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:28.067554Z","caller":"traceutil/trace.go:171","msg":"trace[447762497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2225; }","duration":"151.469688ms","start":"2026-10-14T17:49:27.916065Z","end":"2026-10-14T17:49:28.067534Z","steps":["trace[447762497] 'range keys from in-memory index tree' (duration: 151.090617ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:28.067583Z","caller":"traceutil/trace.go:171","msg":"trace[1581823697] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"147.244276ms","start":"2026-10-14T17:49:27.920271Z","end":"2026-10-14T17:49:28.067516Z","steps":["trace[1581823697] 'process raft request' (duration: 102.359745ms)","trace[1581823697] 'compare' (duration: 44.536321ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:50:16.598842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.006275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:50:16.598994Z","caller":"traceutil/trace.go:171","msg":"trace[277215175] linearizableReadLoop","detail":"{readStateIndex:2763; appliedIndex:2762; }","duration":"246.660923ms","start":"2026-10-14T17:50:16.352312Z","end":"2026-10-14T17:50:16.598973Z","steps":["trace[277215175] 'read index received' (duration: 23.314058ms)","trace[277215175] 'applied index is now lower than readState.Index' (duration: 223.345285ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:50:16.599117Z","caller":"traceutil/trace.go:171","msg":"trace[226455322] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"251.44268ms","start":"2026-10-14T17:50:16.347648Z","end":"2026-10-14T17:50:16.599091Z","steps":["trace[226455322] 'process raft request' (duration: 28.029584ms)","trace[226455322] 'compare' (duration: 222.621754ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:50:16.599238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.922929ms","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-10-14T17:50:16.599284Z","caller":"traceutil/trace.go:171","msg":"trace[2057790787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2661; }","duration":"246.992272ms","start":"2026-10-14T17:50:16.352277Z","end":"2026-10-14T17:50:16.599269Z","steps":["trace[2057790787] 'agreement among raft nodes before linearized reading' (duration: 246.766486ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:16.600551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.773814ms","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-10-14T17:50:16.600627Z","caller":"traceutil/trace.go:171","msg":"trace[2042409037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2663; }","duration":"229.889438ms","start":"2026-10-14T17:50:16.370718Z","end":"2026-10-14T17:50:16.600607Z","steps":["trace[2042409037] 'agreement among raft nodes before linearized reading' (duration: 229.683932ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:50:50.496626Z","caller":"traceutil/trace.go:171","msg":"trace[2082512584] linearizableReadLoop","detail":"{readStateIndex:2971; appliedIndex:2970; }","duration":"113.011027ms","start":"2026-10-14T17:50:50.38359Z","end":"2026-10-14T17:50:50.496601Z","steps":["trace[2082512584] 'read index received' (duration: 112.850943ms)","trace[2082512584] 'applied index is now lower than readState.Index' (duration: 159.004µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:50:50.49675Z","caller":"traceutil/trace.go:171","msg":"trace[195091683] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"147.262046ms","start":"2026-10-14T17:50:50.349455Z","end":"2026-10-14T17:50:50.496717Z","steps":["trace[195091683] 'process raft request' (duration: 146.985679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:50.496996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.374067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.213\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-14T17:50:50.497062Z","caller":"traceutil/trace.go:171","msg":"trace[310664824] range","detail":"{range_begin:/registry/masterleases/162.253.55.213; range_end:; response_count:1; response_revision:2863; }","duration":"113.49893ms","start":"2026-10-14T17:50:50.383546Z","end":"2026-10-14T17:50:50.497045Z","steps":["trace[310664824] 'agreement among raft nodes before linearized reading' (duration: 113.272434ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:54:08.09537Z","caller":"traceutil/trace.go:171","msg":"trace[103218471] transaction","detail":"{read_only:false; response_revision:4106; number_of_response:1; }","duration":"175.60677ms","start":"2026-10-14T17:54:07.919736Z","end":"2026-10-14T17:54:08.095343Z","steps":["trace[103218471] 'process raft request' (duration: 169.843178ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:29.249868Z","caller":"traceutil/trace.go:171","msg":"trace[1186425732] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"103.379021ms","start":"2026-10-14T17:55:29.146439Z","end":"2026-10-14T17:55:29.249818Z","steps":["trace[1186425732] 'process raft request' (duration: 80.971888ms)","trace[1186425732] 'compare' (duration: 22.182286ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:55:36.577051Z","caller":"traceutil/trace.go:171","msg":"trace[1942928111] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"198.474255ms","start":"2026-10-14T17:55:36.378529Z","end":"2026-10-14T17:55:36.577003Z","steps":["trace[1942928111] 'process raft request' (duration: 198.213689ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:38.535864Z","caller":"traceutil/trace.go:171","msg":"trace[1777892445] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"132.213781ms","start":"2026-10-14T17:55:38.403628Z","end":"2026-10-14T17:55:38.535842Z","steps":["trace[1777892445] 'process raft request' (duration: 132.072108ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:38.818403Z","caller":"traceutil/trace.go:171","msg":"trace[1487811913] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"233.875356ms","start":"2026-10-14T17:55:38.5845Z","end":"2026-10-14T17:55:38.818376Z","steps":["trace[1487811913] 'process raft request' (duration: 233.715902ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:38.819408Z","caller":"traceutil/trace.go:171","msg":"trace[924777643] linearizableReadLoop","detail":"{readStateIndex:5494; appliedIndex:5492; }","duration":"180.5853ms","start":"2026-10-14T17:55:38.638807Z","end":"2026-10-14T17:55:38.819392Z","steps":["trace[924777643] 'read index received' (duration: 179.529702ms)","trace[924777643] 'applied index is now lower than readState.Index' (duration: 1.054628ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:55:38.819636Z","caller":"traceutil/trace.go:171","msg":"trace[1112329286] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"233.084107ms","start":"2026-10-14T17:55:38.58654Z","end":"2026-10-14T17:55:38.819625Z","steps":["trace[1112329286] 'process raft request' (duration: 232.77781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:55:38.819963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.136655ms","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-10-14T17:55:38.819967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.166059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:55:38.82004Z","caller":"traceutil/trace.go:171","msg":"trace[1711445233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5295; }","duration":"181.261159ms","start":"2026-10-14T17:55:38.638762Z","end":"2026-10-14T17:55:38.820023Z","steps":["trace[1711445233] 'agreement among raft nodes before linearized reading' (duration: 181.019522ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:38.820058Z","caller":"traceutil/trace.go:171","msg":"trace[31098435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5295; }","duration":"135.354574ms","start":"2026-10-14T17:55:38.684681Z","end":"2026-10-14T17:55:38.820035Z","steps":["trace[31098435] 'agreement among raft nodes before linearized reading' (duration: 135.147249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:55:38.820435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.308644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728747"} {"level":"info","ts":"2026-10-14T17:55:38.820509Z","caller":"traceutil/trace.go:171","msg":"trace[2043371812] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5295; }","duration":"157.437538ms","start":"2026-10-14T17:55:38.663052Z","end":"2026-10-14T17:55:38.82049Z","steps":["trace[2043371812] 'agreement among raft nodes before linearized reading' (duration: 156.828342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:55:38.820542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.76165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16981"} {"level":"info","ts":"2026-10-14T17:55:38.820585Z","caller":"traceutil/trace.go:171","msg":"trace[226770019] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5295; }","duration":"171.836323ms","start":"2026-10-14T17:55:38.648737Z","end":"2026-10-14T17:55:38.820573Z","steps":["trace[226770019] 'agreement among raft nodes before linearized reading' (duration: 171.009841ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:40.69287Z","caller":"traceutil/trace.go:171","msg":"trace[668169130] linearizableReadLoop","detail":"{readStateIndex:5527; appliedIndex:5526; }","duration":"183.445755ms","start":"2026-10-14T17:55:40.509408Z","end":"2026-10-14T17:55:40.692854Z","steps":["trace[668169130] 'read index received' (duration: 183.295561ms)","trace[668169130] 'applied index is now lower than readState.Index' (duration: 149.494µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:55:40.693017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.605348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:55:40.693039Z","caller":"traceutil/trace.go:171","msg":"trace[1793182520] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5326; }","duration":"183.704271ms","start":"2026-10-14T17:55:40.509328Z","end":"2026-10-14T17:55:40.693033Z","steps":["trace[1793182520] 'agreement among raft nodes before linearized reading' (duration: 183.63595ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:40.692994Z","caller":"traceutil/trace.go:171","msg":"trace[1977413602] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"187.084779ms","start":"2026-10-14T17:55:40.505847Z","end":"2026-10-14T17:55:40.692932Z","steps":["trace[1977413602] 'process raft request' (duration: 186.868263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:55:40.693135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.997128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T17:55:40.693222Z","caller":"traceutil/trace.go:171","msg":"trace[2078056480] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5326; }","duration":"154.144652ms","start":"2026-10-14T17:55:40.539054Z","end":"2026-10-14T17:55:40.693199Z","steps":["trace[2078056480] 'agreement among raft nodes before linearized reading' (duration: 153.988198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:55:40.693261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.259234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T17:55:40.693329Z","caller":"traceutil/trace.go:171","msg":"trace[1481022435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5326; }","duration":"149.376247ms","start":"2026-10-14T17:55:40.543934Z","end":"2026-10-14T17:55:40.693311Z","steps":["trace[1481022435] 'agreement among raft nodes before linearized reading' (duration: 149.176332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:56:02.376317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.830286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:56:02.376456Z","caller":"traceutil/trace.go:171","msg":"trace[403179059] linearizableReadLoop","detail":"{readStateIndex:5945; appliedIndex:5944; }","duration":"208.909718ms","start":"2026-10-14T17:56:02.167524Z","end":"2026-10-14T17:56:02.376433Z","steps":["trace[403179059] 'read index received' (duration: 47.794604ms)","trace[403179059] 'applied index is now lower than readState.Index' (duration: 161.113514ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:56:02.376719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.189275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xhbck\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-14T17:56:02.37676Z","caller":"traceutil/trace.go:171","msg":"trace[1178662214] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xhbck; range_end:; response_count:1; response_revision:5731; }","duration":"209.250977ms","start":"2026-10-14T17:56:02.167496Z","end":"2026-10-14T17:56:02.376747Z","steps":["trace[1178662214] 'agreement among raft nodes before linearized reading' (duration: 208.99453ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:56:02.376894Z","caller":"traceutil/trace.go:171","msg":"trace[1126354729] transaction","detail":"{read_only:false; response_revision:5731; number_of_response:1; }","duration":"233.777396ms","start":"2026-10-14T17:56:02.143049Z","end":"2026-10-14T17:56:02.376827Z","steps":["trace[1126354729] 'process raft request' (duration: 72.316953ms)","trace[1126354729] 'compare' (duration: 160.686112ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:56:02.877349Z","caller":"traceutil/trace.go:171","msg":"trace[564481576] linearizableReadLoop","detail":"{readStateIndex:5946; appliedIndex:5945; }","duration":"192.49718ms","start":"2026-10-14T17:56:02.684829Z","end":"2026-10-14T17:56:02.877327Z","steps":["trace[564481576] 'read index received' (duration: 192.293354ms)","trace[564481576] 'applied index is now lower than readState.Index' (duration: 202.946µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:56:02.877469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.628233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:56:02.877502Z","caller":"traceutil/trace.go:171","msg":"trace[1469732700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5732; }","duration":"192.726835ms","start":"2026-10-14T17:56:02.684763Z","end":"2026-10-14T17:56:02.87749Z","steps":["trace[1469732700] 'agreement among raft nodes before linearized reading' (duration: 192.651183ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:56:02.877465Z","caller":"traceutil/trace.go:171","msg":"trace[1315969126] transaction","detail":"{read_only:false; response_revision:5732; number_of_response:1; }","duration":"299.048863ms","start":"2026-10-14T17:56:02.578363Z","end":"2026-10-14T17:56:02.877412Z","steps":["trace[1315969126] 'process raft request' (duration: 298.820137ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:56:06.935433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2958} {"level":"info","ts":"2026-10-14T17:56:07.021177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2958,"took":"84.063068ms","hash":2574323636,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T17:56:07.0213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2574323636,"revision":2958,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:56:15.768772Z","caller":"traceutil/trace.go:171","msg":"trace[2146206321] transaction","detail":"{read_only:false; response_revision:5890; number_of_response:1; }","duration":"157.845688ms","start":"2026-10-14T17:56:15.610901Z","end":"2026-10-14T17:56:15.768746Z","steps":["trace[2146206321] 'process raft request' (duration: 157.655423ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:56:15.768997Z","caller":"traceutil/trace.go:171","msg":"trace[1078553604] transaction","detail":"{read_only:false; response_revision:5891; number_of_response:1; }","duration":"157.829538ms","start":"2026-10-14T17:56:15.611149Z","end":"2026-10-14T17:56:15.768978Z","steps":["trace[1078553604] 'process raft request' (duration: 157.558231ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:56:15.792308Z","caller":"traceutil/trace.go:171","msg":"trace[710517320] linearizableReadLoop","detail":"{readStateIndex:6109; appliedIndex:6106; }","duration":"161.880373ms","start":"2026-10-14T17:56:15.630389Z","end":"2026-10-14T17:56:15.792269Z","steps":["trace[710517320] 'read index received' (duration: 138.084584ms)","trace[710517320] 'applied index is now lower than readState.Index' (duration: 23.792299ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:56:15.792535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.124739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.18de76fbc2b43ef8\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-10-14T17:56:15.792592Z","caller":"traceutil/trace.go:171","msg":"trace[1719173517] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-alertmanager.18de76fbc2b43ef8; range_end:; response_count:1; response_revision:5891; }","duration":"162.221952ms","start":"2026-10-14T17:56:15.630353Z","end":"2026-10-14T17:56:15.792575Z","steps":["trace[1719173517] 'agreement among raft nodes before linearized reading' (duration: 162.010957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:56:15.792667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.736441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-14T17:56:15.792726Z","caller":"traceutil/trace.go:171","msg":"trace[980501190] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5891; }","duration":"108.832633ms","start":"2026-10-14T17:56:15.683872Z","end":"2026-10-14T17:56:15.792705Z","steps":["trace[980501190] 'agreement among raft nodes before linearized reading' (duration: 108.591356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:56:15.793297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.271194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-10-14T17:56:15.793407Z","caller":"traceutil/trace.go:171","msg":"trace[2055653420] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5891; }","duration":"109.406438ms","start":"2026-10-14T17:56:15.683974Z","end":"2026-10-14T17:56:15.793381Z","steps":["trace[2055653420] 'agreement among raft nodes before linearized reading' (duration: 109.07802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:56:15.793334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.421724ms","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-10-14T17:56:15.793566Z","caller":"traceutil/trace.go:171","msg":"trace[1384375504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5891; }","duration":"105.700941ms","start":"2026-10-14T17:56:15.68784Z","end":"2026-10-14T17:56:15.793541Z","steps":["trace[1384375504] 'agreement among raft nodes before linearized reading' (duration: 105.240029ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:01:06.943422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5778} {"level":"info","ts":"2026-10-14T18:01:07.03325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5778,"took":"88.014211ms","hash":596397915,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":26181632,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T18:01:07.033316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":596397915,"revision":5778,"compact-revision":2958} {"level":"info","ts":"2026-10-14T18:02:04.914087Z","caller":"traceutil/trace.go:171","msg":"trace[318815566] linearizableReadLoop","detail":"{readStateIndex:8853; appliedIndex:8851; }","duration":"102.227523ms","start":"2026-10-14T18:02:04.811823Z","end":"2026-10-14T18:02:04.91405Z","steps":["trace[318815566] 'read index received' (duration: 86.065598ms)","trace[318815566] 'applied index is now lower than readState.Index' (duration: 16.160675ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:02:04.914235Z","caller":"traceutil/trace.go:171","msg":"trace[1040720018] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"102.538151ms","start":"2026-10-14T18:02:04.811664Z","end":"2026-10-14T18:02:04.914202Z","steps":["trace[1040720018] 'process raft request' (duration: 102.207852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:04.914335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.49869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T18:02:04.91438Z","caller":"traceutil/trace.go:171","msg":"trace[592714911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8552; }","duration":"102.634424ms","start":"2026-10-14T18:02:04.811734Z","end":"2026-10-14T18:02:04.914368Z","steps":["trace[592714911] 'agreement among raft nodes before linearized reading' (duration: 102.437158ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:17.290193Z","caller":"traceutil/trace.go:171","msg":"trace[2131799207] transaction","detail":"{read_only:false; response_revision:8619; number_of_response:1; }","duration":"100.824625ms","start":"2026-10-14T18:02:17.18934Z","end":"2026-10-14T18:02:17.290165Z","steps":["trace[2131799207] 'process raft request' (duration: 100.644461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:17.643559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.34558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:02:17.643678Z","caller":"traceutil/trace.go:171","msg":"trace[499604711] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8924; }","duration":"271.743758ms","start":"2026-10-14T18:02:17.371913Z","end":"2026-10-14T18:02:17.643657Z","steps":["trace[499604711] 'read index received' (duration: 61.153151ms)","trace[499604711] 'applied index is now lower than readState.Index' (duration: 210.589077ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:02:17.643695Z","caller":"traceutil/trace.go:171","msg":"trace[1270876568] transaction","detail":"{read_only:false; response_revision:8620; number_of_response:1; }","duration":"287.164463ms","start":"2026-10-14T18:02:17.3565Z","end":"2026-10-14T18:02:17.643665Z","steps":["trace[1270876568] 'process raft request' (duration: 76.603007ms)","trace[1270876568] 'compare' (duration: 210.215497ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:02:17.643866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.955754ms","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-10-14T18:02:17.643904Z","caller":"traceutil/trace.go:171","msg":"trace[576858355] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8620; }","duration":"271.996115ms","start":"2026-10-14T18:02:17.371894Z","end":"2026-10-14T18:02:17.64389Z","steps":["trace[576858355] 'agreement among raft nodes before linearized reading' (duration: 271.846821ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:17.665221Z","caller":"traceutil/trace.go:171","msg":"trace[167255247] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"170.748327ms","start":"2026-10-14T18:02:17.494446Z","end":"2026-10-14T18:02:17.665194Z","steps":["trace[167255247] 'process raft request' (duration: 170.554692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:17.66527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.638097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:02:17.665351Z","caller":"traceutil/trace.go:171","msg":"trace[79342152] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8621; }","duration":"232.77902ms","start":"2026-10-14T18:02:17.432551Z","end":"2026-10-14T18:02:17.66533Z","steps":["trace[79342152] 'agreement among raft nodes before linearized reading' (duration: 232.587955ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:43.859881Z","caller":"traceutil/trace.go:171","msg":"trace[1000183580] linearizableReadLoop","detail":"{readStateIndex:9123; appliedIndex:9122; }","duration":"107.353858ms","start":"2026-10-14T18:02:43.752504Z","end":"2026-10-14T18:02:43.859858Z","steps":["trace[1000183580] 'read index received' (duration: 107.173834ms)","trace[1000183580] 'applied index is now lower than readState.Index' (duration: 178.864µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:02:43.859975Z","caller":"traceutil/trace.go:171","msg":"trace[144255652] transaction","detail":"{read_only:false; response_revision:8811; number_of_response:1; }","duration":"107.705227ms","start":"2026-10-14T18:02:43.752219Z","end":"2026-10-14T18:02:43.859924Z","steps":["trace[144255652] 'process raft request' (duration: 107.479891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:43.860125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.579624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/csiaddonsnodes.csiaddons.openshift.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:02:43.860228Z","caller":"traceutil/trace.go:171","msg":"trace[1483296931] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/csiaddonsnodes.csiaddons.openshift.io; range_end:; response_count:0; response_revision:8811; }","duration":"107.763419ms","start":"2026-10-14T18:02:43.752444Z","end":"2026-10-14T18:02:43.860208Z","steps":["trace[1483296931] 'agreement among raft nodes before linearized reading' (duration: 107.550633ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:46.742876Z","caller":"traceutil/trace.go:171","msg":"trace[49522944] linearizableReadLoop","detail":"{readStateIndex:9141; appliedIndex:9140; }","duration":"110.12857ms","start":"2026-10-14T18:02:46.63272Z","end":"2026-10-14T18:02:46.742849Z","steps":["trace[49522944] 'read index received' (duration: 109.828632ms)","trace[49522944] 'applied index is now lower than readState.Index' (duration: 298.798µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:02:46.742929Z","caller":"traceutil/trace.go:171","msg":"trace[561136833] transaction","detail":"{read_only:false; response_revision:8828; number_of_response:1; }","duration":"273.590787ms","start":"2026-10-14T18:02:46.469291Z","end":"2026-10-14T18:02:46.742882Z","steps":["trace[561136833] 'process raft request' (duration: 273.394202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:46.743104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.355707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:02:46.743215Z","caller":"traceutil/trace.go:171","msg":"trace[794063410] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8828; }","duration":"110.50438ms","start":"2026-10-14T18:02:46.632689Z","end":"2026-10-14T18:02:46.743193Z","steps":["trace[794063410] 'agreement among raft nodes before linearized reading' (duration: 110.346576ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:46.973075Z","caller":"traceutil/trace.go:171","msg":"trace[271652077] transaction","detail":"{read_only:false; response_revision:8829; number_of_response:1; }","duration":"132.667114ms","start":"2026-10-14T18:02:46.840381Z","end":"2026-10-14T18:02:46.973048Z","steps":["trace[271652077] 'process raft request' (duration: 128.685989ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:55.419547Z","caller":"traceutil/trace.go:171","msg":"trace[2128694431] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"199.414111ms","start":"2026-10-14T18:02:55.220104Z","end":"2026-10-14T18:02:55.419518Z","steps":["trace[2128694431] 'process raft request' (duration: 199.247027ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:55.48569Z","caller":"traceutil/trace.go:171","msg":"trace[1785795495] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"264.762774ms","start":"2026-10-14T18:02:55.220901Z","end":"2026-10-14T18:02:55.485663Z","steps":["trace[1785795495] 'process raft request' (duration: 235.921455ms)","trace[1785795495] 'compare' (duration: 28.595712ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:03:00.019798Z","caller":"traceutil/trace.go:171","msg":"trace[383110836] transaction","detail":"{read_only:false; response_revision:8910; number_of_response:1; }","duration":"103.433325ms","start":"2026-10-14T18:02:59.916347Z","end":"2026-10-14T18:03:00.01978Z","steps":["trace[383110836] 'process raft request' (duration: 103.302461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:03:00.313436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.333922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:03:00.313556Z","caller":"traceutil/trace.go:171","msg":"trace[44950798] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:8913; }","duration":"119.490727ms","start":"2026-10-14T18:03:00.194035Z","end":"2026-10-14T18:03:00.313525Z","steps":["trace[44950798] 'count revisions from in-memory index tree' (duration: 119.22929ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:03:15.987345Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b7.wal"} {"level":"info","ts":"2026-10-14T18:04:12.289337Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:04:12.297665Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T18:04:12.297779Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-14T18:04:24.109523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.183902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:04:24.109618Z","caller":"traceutil/trace.go:171","msg":"trace[2082427199] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:9727; }","duration":"112.325007ms","start":"2026-10-14T18:04:23.997273Z","end":"2026-10-14T18:04:24.109598Z","steps":["trace[2082427199] 'count revisions from in-memory index tree' (duration: 112.046789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:24.109624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.938818ms","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-10-14T18:04:24.109725Z","caller":"traceutil/trace.go:171","msg":"trace[1047426422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9727; }","duration":"178.087552ms","start":"2026-10-14T18:04:23.931614Z","end":"2026-10-14T18:04:24.109702Z","steps":["trace[1047426422] 'range keys from in-memory index tree' (duration: 177.713372ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:24.237532Z","caller":"traceutil/trace.go:171","msg":"trace[452091504] transaction","detail":"{read_only:false; response_revision:9728; number_of_response:1; }","duration":"125.336446ms","start":"2026-10-14T18:04:24.11216Z","end":"2026-10-14T18:04:24.237497Z","steps":["trace[452091504] 'process raft request' (duration: 125.126581ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:24.265814Z","caller":"traceutil/trace.go:171","msg":"trace[1701263074] transaction","detail":"{read_only:false; response_revision:9729; number_of_response:1; }","duration":"151.883064ms","start":"2026-10-14T18:04:24.113903Z","end":"2026-10-14T18:04:24.265787Z","steps":["trace[1701263074] 'process raft request' (duration: 151.702239ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:24.467689Z","caller":"traceutil/trace.go:171","msg":"trace[1574989819] transaction","detail":"{read_only:false; response_revision:9731; number_of_response:1; }","duration":"166.093406ms","start":"2026-10-14T18:04:24.30157Z","end":"2026-10-14T18:04:24.467664Z","steps":["trace[1574989819] 'process raft request' (duration: 90.056332ms)","trace[1574989819] 'compare' (duration: 75.88172ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:24.467828Z","caller":"traceutil/trace.go:171","msg":"trace[1856061532] linearizableReadLoop","detail":"{readStateIndex:10073; appliedIndex:10071; }","duration":"102.688732ms","start":"2026-10-14T18:04:24.365105Z","end":"2026-10-14T18:04:24.467793Z","steps":["trace[1856061532] 'read index received' (duration: 26.534945ms)","trace[1856061532] 'applied index is now lower than readState.Index' (duration: 76.152127ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:24.46801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.859798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:04:24.468051Z","caller":"traceutil/trace.go:171","msg":"trace[1856884397] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:9732; }","duration":"102.994181ms","start":"2026-10-14T18:04:24.365045Z","end":"2026-10-14T18:04:24.468039Z","steps":["trace[1856884397] 'agreement among raft nodes before linearized reading' (duration: 102.853577ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:24.468237Z","caller":"traceutil/trace.go:171","msg":"trace[1412258806] transaction","detail":"{read_only:false; response_revision:9732; number_of_response:1; }","duration":"137.963779ms","start":"2026-10-14T18:04:24.330237Z","end":"2026-10-14T18:04:24.4682Z","steps":["trace[1412258806] 'process raft request' (duration: 137.394583ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:06:06.948707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8013} {"level":"info","ts":"2026-10-14T18:06:07.02018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8013,"took":"69.131109ms","hash":4243026781,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":28971008,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T18:06:07.020255Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4243026781,"revision":8013,"compact-revision":5778} {"level":"warn","ts":"2026-10-14T18:07:47.525796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.677157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:07:47.526055Z","caller":"traceutil/trace.go:171","msg":"trace[1135231101] transaction","detail":"{read_only:false; response_revision:11467; number_of_response:1; }","duration":"200.209878ms","start":"2026-10-14T18:07:47.325827Z","end":"2026-10-14T18:07:47.526037Z","steps":["trace[1135231101] 'process raft request' (duration: 200.085085ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:47.526112Z","caller":"traceutil/trace.go:171","msg":"trace[538846528] linearizableReadLoop","detail":"{readStateIndex:11857; appliedIndex:11856; }","duration":"253.986443ms","start":"2026-10-14T18:07:47.272088Z","end":"2026-10-14T18:07:47.526075Z","steps":["trace[538846528] 'read index received' (duration: 97.902987ms)","trace[538846528] 'applied index is now lower than readState.Index' (duration: 156.079896ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:07:47.526371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.267751ms","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-10-14T18:07:47.526439Z","caller":"traceutil/trace.go:171","msg":"trace[1132127207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11467; }","duration":"254.395734ms","start":"2026-10-14T18:07:47.272027Z","end":"2026-10-14T18:07:47.526423Z","steps":["trace[1132127207] 'agreement among raft nodes before linearized reading' (duration: 254.164928ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:47.526687Z","caller":"traceutil/trace.go:171","msg":"trace[366190577] transaction","detail":"{read_only:false; response_revision:11466; number_of_response:1; }","duration":"274.682128ms","start":"2026-10-14T18:07:47.251899Z","end":"2026-10-14T18:07:47.526581Z","steps":["trace[366190577] 'process raft request' (duration: 118.134469ms)","trace[366190577] 'compare' (duration: 155.393319ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:08:05.851287Z","caller":"traceutil/trace.go:171","msg":"trace[980037033] linearizableReadLoop","detail":"{readStateIndex:11965; appliedIndex:11964; }","duration":"100.928786ms","start":"2026-10-14T18:08:05.750333Z","end":"2026-10-14T18:08:05.851262Z","steps":["trace[980037033] 'read index received' (duration: 96.298374ms)","trace[980037033] 'applied index is now lower than readState.Index' (duration: 4.629382ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:08:05.851367Z","caller":"traceutil/trace.go:171","msg":"trace[1863129226] transaction","detail":"{read_only:false; response_revision:11570; number_of_response:1; }","duration":"128.8163ms","start":"2026-10-14T18:08:05.722516Z","end":"2026-10-14T18:08:05.851332Z","steps":["trace[1863129226] 'process raft request' (duration: 124.169957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:08:05.851492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.148231ms","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-10-14T18:08:05.851532Z","caller":"traceutil/trace.go:171","msg":"trace[305108848] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11570; }","duration":"101.242324ms","start":"2026-10-14T18:08:05.750277Z","end":"2026-10-14T18:08:05.851519Z","steps":["trace[305108848] 'agreement among raft nodes before linearized reading' (duration: 101.10327ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:11:06.955999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10459} {"level":"info","ts":"2026-10-14T18:11:07.046811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10459,"took":"88.595894ms","hash":1089922447,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":27738112,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:11:07.046916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1089922447,"revision":10459,"compact-revision":8013} {"level":"info","ts":"2026-10-14T18:13:59.858489Z","caller":"traceutil/trace.go:171","msg":"trace[718594570] linearizableReadLoop","detail":"{readStateIndex:14459; appliedIndex:14458; }","duration":"153.554454ms","start":"2026-10-14T18:13:59.704911Z","end":"2026-10-14T18:13:59.858466Z","steps":["trace[718594570] 'read index received' (duration: 153.39345ms)","trace[718594570] 'applied index is now lower than readState.Index' (duration: 159.744µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:13:59.858653Z","caller":"traceutil/trace.go:171","msg":"trace[307998790] transaction","detail":"{read_only:false; response_revision:13980; number_of_response:1; }","duration":"157.804114ms","start":"2026-10-14T18:13:59.700797Z","end":"2026-10-14T18:13:59.858601Z","steps":["trace[307998790] 'process raft request' (duration: 157.523227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:59.858855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.894412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T18:13:59.858909Z","caller":"traceutil/trace.go:171","msg":"trace[667007328] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:13980; }","duration":"154.046847ms","start":"2026-10-14T18:13:59.704849Z","end":"2026-10-14T18:13:59.858896Z","steps":["trace[667007328] 'agreement among raft nodes before linearized reading' (duration: 153.842032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:13:59.858959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.83573ms","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-10-14T18:13:59.859064Z","caller":"traceutil/trace.go:171","msg":"trace[1779544811] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13980; }","duration":"114.018416ms","start":"2026-10-14T18:13:59.74502Z","end":"2026-10-14T18:13:59.859038Z","steps":["trace[1779544811] 'agreement among raft nodes before linearized reading' (duration: 113.751849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:21.170552Z","caller":"traceutil/trace.go:171","msg":"trace[1991223493] transaction","detail":"{read_only:false; response_revision:14097; number_of_response:1; }","duration":"156.743967ms","start":"2026-10-14T18:14:21.013787Z","end":"2026-10-14T18:14:21.170531Z","steps":["trace[1991223493] 'process raft request' (duration: 144.910449ms)","trace[1991223493] 'compare' (duration: 11.5271ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:14:29.443489Z","caller":"traceutil/trace.go:171","msg":"trace[1058133339] linearizableReadLoop","detail":"{readStateIndex:14624; appliedIndex:14623; }","duration":"162.98623ms","start":"2026-10-14T18:14:29.280488Z","end":"2026-10-14T18:14:29.443474Z","steps":["trace[1058133339] 'read index received' (duration: 162.826176ms)","trace[1058133339] 'applied index is now lower than readState.Index' (duration: 159.424µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:14:29.443654Z","caller":"traceutil/trace.go:171","msg":"trace[446956010] transaction","detail":"{read_only:false; response_revision:14139; number_of_response:1; }","duration":"176.4514ms","start":"2026-10-14T18:14:29.267165Z","end":"2026-10-14T18:14:29.443617Z","steps":["trace[446956010] 'process raft request' (duration: 176.196343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:29.443705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.198285ms","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-10-14T18:14:29.443798Z","caller":"traceutil/trace.go:171","msg":"trace[1115043158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14139; }","duration":"163.311898ms","start":"2026-10-14T18:14:29.280444Z","end":"2026-10-14T18:14:29.443756Z","steps":["trace[1115043158] 'agreement among raft nodes before linearized reading' (duration: 163.103213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:37.984177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.785555ms","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-10-14T18:14:37.984266Z","caller":"traceutil/trace.go:171","msg":"trace[789534976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14196; }","duration":"226.934459ms","start":"2026-10-14T18:14:37.757312Z","end":"2026-10-14T18:14:37.984247Z","steps":["trace[789534976] 'range keys from in-memory index tree' (duration: 226.557849ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:38.221706Z","caller":"traceutil/trace.go:171","msg":"trace[2046376118] linearizableReadLoop","detail":"{readStateIndex:14687; appliedIndex:14686; }","duration":"144.071706ms","start":"2026-10-14T18:14:38.077609Z","end":"2026-10-14T18:14:38.221681Z","steps":["trace[2046376118] 'read index received' (duration: 142.140406ms)","trace[2046376118] 'applied index is now lower than readState.Index' (duration: 1.92999ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:14:38.221864Z","caller":"traceutil/trace.go:171","msg":"trace[248742833] transaction","detail":"{read_only:false; response_revision:14198; number_of_response:1; }","duration":"151.075561ms","start":"2026-10-14T18:14:38.070664Z","end":"2026-10-14T18:14:38.22174Z","steps":["trace[248742833] 'process raft request' (duration: 149.13471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:38.221951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.387884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19754"} {"level":"warn","ts":"2026-10-14T18:14:38.221952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.324393ms","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-10-14T18:14:38.22199Z","caller":"traceutil/trace.go:171","msg":"trace[2133151104] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14198; }","duration":"131.482696ms","start":"2026-10-14T18:14:38.090498Z","end":"2026-10-14T18:14:38.221981Z","steps":["trace[2133151104] 'agreement among raft nodes before linearized reading' (duration: 131.323862ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:38.222024Z","caller":"traceutil/trace.go:171","msg":"trace[681816937] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14198; }","duration":"144.474497ms","start":"2026-10-14T18:14:38.077531Z","end":"2026-10-14T18:14:38.222006Z","steps":["trace[681816937] 'agreement among raft nodes before linearized reading' (duration: 144.256711ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:49.220365Z","caller":"traceutil/trace.go:171","msg":"trace[83655083] linearizableReadLoop","detail":"{readStateIndex:14868; appliedIndex:14867; }","duration":"203.025972ms","start":"2026-10-14T18:14:49.01731Z","end":"2026-10-14T18:14:49.220336Z","steps":["trace[83655083] 'read index received' (duration: 202.876268ms)","trace[83655083] 'applied index is now lower than readState.Index' (duration: 148.374µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:14:49.220431Z","caller":"traceutil/trace.go:171","msg":"trace[1531144160] transaction","detail":"{read_only:false; response_revision:14376; number_of_response:1; }","duration":"391.564846ms","start":"2026-10-14T18:14:48.828836Z","end":"2026-10-14T18:14:49.220401Z","steps":["trace[1531144160] 'process raft request' (duration: 391.33845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:49.22066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.326039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-hsw2r.18de780a53a4f598\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-14T18:14:49.220773Z","caller":"traceutil/trace.go:171","msg":"trace[1892785584] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-hsw2r.18de780a53a4f598; range_end:; response_count:1; response_revision:14376; }","duration":"203.775951ms","start":"2026-10-14T18:14:49.016969Z","end":"2026-10-14T18:14:49.220745Z","steps":["trace[1892785584] 'agreement among raft nodes before linearized reading' (duration: 203.472284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:49.221248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:14:48.828811Z","time spent":"391.689638ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:15:54.731461Z","caller":"traceutil/trace.go:171","msg":"trace[1596441501] transaction","detail":"{read_only:false; response_revision:15284; number_of_response:1; }","duration":"104.188632ms","start":"2026-10-14T18:15:54.627246Z","end":"2026-10-14T18:15:54.731434Z","steps":["trace[1596441501] 'process raft request' (duration: 103.972376ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:16:06.963509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12763} {"level":"info","ts":"2026-10-14T18:16:07.051069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12763,"took":"84.807243ms","hash":2749641479,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":31055872,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:16:07.051144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2749641479,"revision":12763,"compact-revision":10459} {"level":"info","ts":"2026-10-14T18:16:19.816517Z","caller":"traceutil/trace.go:171","msg":"trace[1333678321] transaction","detail":"{read_only:false; response_revision:15417; number_of_response:1; }","duration":"310.12316ms","start":"2026-10-14T18:16:19.506353Z","end":"2026-10-14T18:16:19.816477Z","steps":["trace[1333678321] 'process raft request' (duration: 309.988977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:16:19.816679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:16:19.506331Z","time spent":"310.265374ms","remote":"127.0.0.1:37966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:16:19.81744Z","caller":"traceutil/trace.go:171","msg":"trace[392471164] linearizableReadLoop","detail":"{readStateIndex:15941; appliedIndex:15939; }","duration":"133.610771ms","start":"2026-10-14T18:16:19.683801Z","end":"2026-10-14T18:16:19.817412Z","steps":["trace[392471164] 'read index received' (duration: 132.628166ms)","trace[392471164] 'applied index is now lower than readState.Index' (duration: 981.505µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:16:19.817514Z","caller":"traceutil/trace.go:171","msg":"trace[1814006614] transaction","detail":"{read_only:false; response_revision:15418; number_of_response:1; }","duration":"245.578657ms","start":"2026-10-14T18:16:19.571888Z","end":"2026-10-14T18:16:19.817467Z","steps":["trace[1814006614] 'process raft request' (duration: 245.397082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:16:19.817615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.793167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:16:19.817711Z","caller":"traceutil/trace.go:171","msg":"trace[1239739443] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:15418; }","duration":"133.91762ms","start":"2026-10-14T18:16:19.683757Z","end":"2026-10-14T18:16:19.817675Z","steps":["trace[1239739443] 'agreement among raft nodes before linearized reading' (duration: 133.790977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:16:19.817798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.54288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:16:19.817864Z","caller":"traceutil/trace.go:171","msg":"trace[178468402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15418; }","duration":"133.711636ms","start":"2026-10-14T18:16:19.68414Z","end":"2026-10-14T18:16:19.817852Z","steps":["trace[178468402] 'agreement among raft nodes before linearized reading' (duration: 133.5221ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:21:06.972031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15354} {"level":"info","ts":"2026-10-14T18:21:07.085738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15354,"took":"110.118384ms","hash":355587434,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":32542720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:21:07.08581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":355587434,"revision":15354,"compact-revision":12763} {"level":"warn","ts":"2026-10-14T18:22:05.124313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.817448ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:22:05.124399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.164069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:22:05.124471Z","caller":"traceutil/trace.go:171","msg":"trace[278033143] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:17984; }","duration":"458.019763ms","start":"2026-10-14T18:22:04.666429Z","end":"2026-10-14T18:22:05.124449Z","steps":["trace[278033143] 'range keys from in-memory index tree' (duration: 457.791537ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:05.124484Z","caller":"traceutil/trace.go:171","msg":"trace[162060309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17984; }","duration":"436.279721ms","start":"2026-10-14T18:22:04.688183Z","end":"2026-10-14T18:22:05.124463Z","steps":["trace[162060309] 'range keys from in-memory index tree' (duration: 436.053335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:05.124539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:22:04.688156Z","time spent":"436.373294ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:22:05.124377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.837572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T18:22:05.124715Z","caller":"traceutil/trace.go:171","msg":"trace[679172129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17984; }","duration":"374.248273ms","start":"2026-10-14T18:22:04.75044Z","end":"2026-10-14T18:22:05.124688Z","steps":["trace[679172129] 'range keys from in-memory index tree' (duration: 373.587235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:05.124784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:22:04.750414Z","time spent":"374.332736ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-14T18:22:05.124755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.801713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-14T18:22:05.124881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.946021ms","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-10-14T18:22:05.12491Z","caller":"traceutil/trace.go:171","msg":"trace[2024316868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17984; }","duration":"226.005918ms","start":"2026-10-14T18:22:04.898872Z","end":"2026-10-14T18:22:05.124878Z","steps":["trace[2024316868] 'range keys from in-memory index tree' (duration: 225.547095ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:05.124993Z","caller":"traceutil/trace.go:171","msg":"trace[643425149] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17984; }","duration":"147.136946ms","start":"2026-10-14T18:22:04.97783Z","end":"2026-10-14T18:22:05.124967Z","steps":["trace[643425149] 'range keys from in-memory index tree' (duration: 146.697245ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:05.127386Z","caller":"traceutil/trace.go:171","msg":"trace[777698984] transaction","detail":"{read_only:false; response_revision:17985; number_of_response:1; }","duration":"139.814112ms","start":"2026-10-14T18:22:04.987494Z","end":"2026-10-14T18:22:05.127308Z","steps":["trace[777698984] 'process raft request' (duration: 139.590026ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:12.702322Z","caller":"traceutil/trace.go:171","msg":"trace[658230672] transaction","detail":"{read_only:false; response_revision:18064; number_of_response:1; }","duration":"258.503152ms","start":"2026-10-14T18:22:12.443752Z","end":"2026-10-14T18:22:12.702255Z","steps":["trace[658230672] 'process raft request' (duration: 159.651135ms)","trace[658230672] 'compare' (duration: 98.649732ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:22:13.177321Z","caller":"traceutil/trace.go:171","msg":"trace[1040734289] transaction","detail":"{read_only:false; response_revision:18065; number_of_response:1; }","duration":"275.473696ms","start":"2026-10-14T18:22:12.901811Z","end":"2026-10-14T18:22:13.177284Z","steps":["trace[1040734289] 'process raft request' (duration: 275.190769ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:13.499088Z","caller":"traceutil/trace.go:171","msg":"trace[58830183] linearizableReadLoop","detail":"{readStateIndex:18675; appliedIndex:18674; }","duration":"302.553799ms","start":"2026-10-14T18:22:13.196483Z","end":"2026-10-14T18:22:13.499036Z","steps":["trace[58830183] 'read index received' (duration: 302.257111ms)","trace[58830183] 'applied index is now lower than readState.Index' (duration: 294.628µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:22:13.499178Z","caller":"traceutil/trace.go:171","msg":"trace[1911368039] transaction","detail":"{read_only:false; response_revision:18066; number_of_response:1; }","duration":"305.412684ms","start":"2026-10-14T18:22:13.193722Z","end":"2026-10-14T18:22:13.499135Z","steps":["trace[1911368039] 'process raft request' (duration: 305.020154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:13.499394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:22:13.193696Z","time spent":"305.587929ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:22:13.499502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.97888ms","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-10-14T18:22:13.499595Z","caller":"traceutil/trace.go:171","msg":"trace[1024424819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18066; }","duration":"303.132934ms","start":"2026-10-14T18:22:13.196437Z","end":"2026-10-14T18:22:13.49957Z","steps":["trace[1024424819] 'agreement among raft nodes before linearized reading' (duration: 302.804085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:13.49965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:22:13.196418Z","time spent":"303.221617ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-14T18:22:13.520449Z","caller":"traceutil/trace.go:171","msg":"trace[696706395] transaction","detail":"{read_only:false; response_revision:18067; number_of_response:1; }","duration":"260.014561ms","start":"2026-10-14T18:22:13.260415Z","end":"2026-10-14T18:22:13.520429Z","steps":["trace[696706395] 'process raft request' (duration: 259.802065ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:13.52063Z","caller":"traceutil/trace.go:171","msg":"trace[305672227] transaction","detail":"{read_only:false; response_revision:18068; number_of_response:1; }","duration":"255.468913ms","start":"2026-10-14T18:22:13.265136Z","end":"2026-10-14T18:22:13.520604Z","steps":["trace[305672227] 'process raft request' (duration: 255.253107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:13.520723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.654738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:22:13.520769Z","caller":"traceutil/trace.go:171","msg":"trace[1957518650] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:18068; }","duration":"303.7306ms","start":"2026-10-14T18:22:13.217024Z","end":"2026-10-14T18:22:13.520755Z","steps":["trace[1957518650] 'agreement among raft nodes before linearized reading' (duration: 303.648858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:13.520803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:22:13.217001Z","time spent":"303.793032ms","remote":"127.0.0.1:59218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-10-14T18:22:13.520837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.080579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ckngj\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-14T18:22:13.520925Z","caller":"traceutil/trace.go:171","msg":"trace[1098019928] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ckngj; range_end:; response_count:1; response_revision:18068; }","duration":"151.229174ms","start":"2026-10-14T18:22:13.369674Z","end":"2026-10-14T18:22:13.520903Z","steps":["trace[1098019928] 'agreement among raft nodes before linearized reading' (duration: 150.962357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:13.520944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.350787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T18:22:13.520984Z","caller":"traceutil/trace.go:171","msg":"trace[145069337] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18068; }","duration":"320.406138ms","start":"2026-10-14T18:22:13.200562Z","end":"2026-10-14T18:22:13.520968Z","steps":["trace[145069337] 'agreement among raft nodes before linearized reading' (duration: 320.237504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:13.521044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:22:13.200551Z","time spent":"320.485341ms","remote":"127.0.0.1:38058","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-10-14T18:22:13.521069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.398151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-14T18:22:13.521014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.756882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:22:13.52114Z","caller":"traceutil/trace.go:171","msg":"trace[482497722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18068; }","duration":"322.483482ms","start":"2026-10-14T18:22:13.198634Z","end":"2026-10-14T18:22:13.521117Z","steps":["trace[482497722] 'agreement among raft nodes before linearized reading' (duration: 322.333068ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:22:13.521172Z","caller":"traceutil/trace.go:171","msg":"trace[891330372] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:18068; }","duration":"292.958927ms","start":"2026-10-14T18:22:13.228203Z","end":"2026-10-14T18:22:13.521162Z","steps":["trace[891330372] 'agreement among raft nodes before linearized reading' (duration: 292.761102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:22:13.521179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:22:13.198623Z","time spent":"322.548135ms","remote":"127.0.0.1:38058","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-10-14T18:23:10.374382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.170701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:23:10.374469Z","caller":"traceutil/trace.go:171","msg":"trace[1842730011] linearizableReadLoop","detail":"{readStateIndex:19355; appliedIndex:19354; }","duration":"143.202603ms","start":"2026-10-14T18:23:10.231254Z","end":"2026-10-14T18:23:10.374457Z","steps":["trace[1842730011] 'read index received' (duration: 42.768574ms)","trace[1842730011] 'applied index is now lower than readState.Index' (duration: 100.433269ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:23:10.384697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.439062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T18:23:10.384784Z","caller":"traceutil/trace.go:171","msg":"trace[153089720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18727; }","duration":"153.603285ms","start":"2026-10-14T18:23:10.231158Z","end":"2026-10-14T18:23:10.384761Z","steps":["trace[153089720] 'agreement among raft nodes before linearized reading' (duration: 143.330126ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:23:10.390319Z","caller":"traceutil/trace.go:171","msg":"trace[645037651] transaction","detail":"{read_only:false; response_revision:18727; number_of_response:1; }","duration":"163.459064ms","start":"2026-10-14T18:23:10.220983Z","end":"2026-10-14T18:23:10.384442Z","steps":["trace[645037651] 'process raft request' (duration: 53.152366ms)","trace[645037651] 'compare' (duration: 100.036828ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:24:44.903785Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:24:44.911387Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T18:24:44.911527Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T18:26:06.986267Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17545} {"level":"info","ts":"2026-10-14T18:26:07.089984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17545,"took":"101.059616ms","hash":4128580384,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":35119104,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T18:26:07.090166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4128580384,"revision":17545,"compact-revision":15354} {"level":"info","ts":"2026-10-14T18:27:41.373591Z","caller":"traceutil/trace.go:171","msg":"trace[129043475] transaction","detail":"{read_only:false; response_revision:20568; number_of_response:1; }","duration":"141.627491ms","start":"2026-10-14T18:27:41.231942Z","end":"2026-10-14T18:27:41.37357Z","steps":["trace[129043475] 'process raft request' (duration: 141.491857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:41.656451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.484298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:27:41.65681Z","caller":"traceutil/trace.go:171","msg":"trace[1218803907] transaction","detail":"{read_only:false; response_revision:20569; number_of_response:1; }","duration":"362.019334ms","start":"2026-10-14T18:27:41.294738Z","end":"2026-10-14T18:27:41.656757Z","steps":["trace[1218803907] 'process raft request' (duration: 255.141386ms)","trace[1218803907] 'compare' (duration: 106.120958ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:27:41.656864Z","caller":"traceutil/trace.go:171","msg":"trace[1657415284] linearizableReadLoop","detail":"{readStateIndex:21264; appliedIndex:21263; }","duration":"267.705415ms","start":"2026-10-14T18:27:41.389113Z","end":"2026-10-14T18:27:41.656819Z","steps":["trace[1657415284] 'read index received' (duration: 160.755404ms)","trace[1657415284] 'applied index is now lower than readState.Index' (duration: 106.94486ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:27:41.657001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:27:41.2947Z","time spent":"362.202728ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:27:41.657047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.93617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T18:27:41.657107Z","caller":"traceutil/trace.go:171","msg":"trace[1339973696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20570; }","duration":"268.066264ms","start":"2026-10-14T18:27:41.389023Z","end":"2026-10-14T18:27:41.657089Z","steps":["trace[1339973696] 'agreement among raft nodes before linearized reading' (duration: 267.865118ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:27:41.657257Z","caller":"traceutil/trace.go:171","msg":"trace[1978086498] transaction","detail":"{read_only:false; response_revision:20570; number_of_response:1; }","duration":"197.071008ms","start":"2026-10-14T18:27:41.459785Z","end":"2026-10-14T18:27:41.656856Z","steps":["trace[1978086498] 'process raft request' (duration: 196.980116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:41.657638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.160168ms","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-10-14T18:27:41.657679Z","caller":"traceutil/trace.go:171","msg":"trace[1408757795] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20570; }","duration":"187.20117ms","start":"2026-10-14T18:27:41.470465Z","end":"2026-10-14T18:27:41.657666Z","steps":["trace[1408757795] 'agreement among raft nodes before linearized reading' (duration: 187.099457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:41.657677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.590885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T18:27:41.657712Z","caller":"traceutil/trace.go:171","msg":"trace[740968884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20570; }","duration":"148.651627ms","start":"2026-10-14T18:27:41.509049Z","end":"2026-10-14T18:27:41.657701Z","steps":["trace[740968884] 'agreement among raft nodes before linearized reading' (duration: 148.562165ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:27:57.655199Z","caller":"traceutil/trace.go:171","msg":"trace[2025103939] transaction","detail":"{read_only:false; response_revision:20652; number_of_response:1; }","duration":"169.543716ms","start":"2026-10-14T18:27:57.485628Z","end":"2026-10-14T18:27:57.655172Z","steps":["trace[2025103939] 'process raft request' (duration: 169.364811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:58.027814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.818225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:27:58.027913Z","caller":"traceutil/trace.go:171","msg":"trace[2097123350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20652; }","duration":"343.971539ms","start":"2026-10-14T18:27:57.683917Z","end":"2026-10-14T18:27:58.027889Z","steps":["trace[2097123350] 'range keys from in-memory index tree' (duration: 343.739694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:58.027954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:27:57.683893Z","time spent":"344.050132ms","remote":"127.0.0.1:37824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T18:27:58.028182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.958299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:27:58.028328Z","caller":"traceutil/trace.go:171","msg":"trace[1214824909] linearizableReadLoop","detail":"{readStateIndex:21351; appliedIndex:21350; }","duration":"270.088277ms","start":"2026-10-14T18:27:57.758221Z","end":"2026-10-14T18:27:58.028309Z","steps":["trace[1214824909] 'read index received' (duration: 70.81192ms)","trace[1214824909] 'applied index is now lower than readState.Index' (duration: 199.275177ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:27:58.028458Z","caller":"traceutil/trace.go:171","msg":"trace[1330944301] transaction","detail":"{read_only:false; response_revision:20653; number_of_response:1; }","duration":"289.445016ms","start":"2026-10-14T18:27:57.73898Z","end":"2026-10-14T18:27:58.028425Z","steps":["trace[1330944301] 'process raft request' (duration: 90.127308ms)","trace[1330944301] 'compare' (duration: 198.531858ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:27:58.028532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.308313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T18:27:58.028573Z","caller":"traceutil/trace.go:171","msg":"trace[803062256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20653; }","duration":"270.434337ms","start":"2026-10-14T18:27:57.758124Z","end":"2026-10-14T18:27:58.028559Z","steps":["trace[803062256] 'agreement among raft nodes before linearized reading' (duration: 270.294583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:58.028577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.455485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T18:27:58.028612Z","caller":"traceutil/trace.go:171","msg":"trace[638978492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20653; }","duration":"260.525687ms","start":"2026-10-14T18:27:57.768075Z","end":"2026-10-14T18:27:58.0286Z","steps":["trace[638978492] 'agreement among raft nodes before linearized reading' (duration: 260.356662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:58.028706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.469978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-14T18:27:58.028731Z","caller":"traceutil/trace.go:171","msg":"trace[275032178] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20653; }","duration":"235.514069ms","start":"2026-10-14T18:27:57.793209Z","end":"2026-10-14T18:27:58.028723Z","steps":["trace[275032178] 'agreement among raft nodes before linearized reading' (duration: 235.433997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:58.028919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.001287ms","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-10-14T18:27:58.028967Z","caller":"traceutil/trace.go:171","msg":"trace[30294175] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20653; }","duration":"243.114349ms","start":"2026-10-14T18:27:57.785835Z","end":"2026-10-14T18:27:58.02895Z","steps":["trace[30294175] 'agreement among raft nodes before linearized reading' (duration: 242.887052ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:27:58.21329Z","caller":"traceutil/trace.go:171","msg":"trace[1420646269] transaction","detail":"{read_only:false; response_revision:20657; number_of_response:1; }","duration":"179.086367ms","start":"2026-10-14T18:27:58.034176Z","end":"2026-10-14T18:27:58.213262Z","steps":["trace[1420646269] 'process raft request' (duration: 179.039835ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:27:58.213352Z","caller":"traceutil/trace.go:171","msg":"trace[939050514] transaction","detail":"{read_only:false; response_revision:20656; number_of_response:1; }","duration":"179.564379ms","start":"2026-10-14T18:27:58.033742Z","end":"2026-10-14T18:27:58.213307Z","steps":["trace[939050514] 'process raft request' (duration: 179.434405ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:27:58.213303Z","caller":"traceutil/trace.go:171","msg":"trace[1259195146] transaction","detail":"{read_only:false; response_revision:20655; number_of_response:1; }","duration":"179.637241ms","start":"2026-10-14T18:27:58.033641Z","end":"2026-10-14T18:27:58.213278Z","steps":["trace[1259195146] 'process raft request' (duration: 179.357294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:27:58.50403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.893458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-14T18:27:58.504119Z","caller":"traceutil/trace.go:171","msg":"trace[169006910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20657; }","duration":"145.035522ms","start":"2026-10-14T18:27:58.359061Z","end":"2026-10-14T18:27:58.504096Z","steps":["trace[169006910] 'range keys from in-memory index tree' (duration: 144.654222ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:27:58.676411Z","caller":"traceutil/trace.go:171","msg":"trace[303833880] linearizableReadLoop","detail":"{readStateIndex:21356; appliedIndex:21355; }","duration":"106.213671ms","start":"2026-10-14T18:27:58.570171Z","end":"2026-10-14T18:27:58.676385Z","steps":["trace[303833880] 'read index received' (duration: 105.907853ms)","trace[303833880] 'applied index is now lower than readState.Index' (duration: 304.788µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:27:58.676571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.391636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T18:27:58.676619Z","caller":"traceutil/trace.go:171","msg":"trace[146778238] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:20658; }","duration":"106.55084ms","start":"2026-10-14T18:27:58.570057Z","end":"2026-10-14T18:27:58.676608Z","steps":["trace[146778238] 'agreement among raft nodes before linearized reading' (duration: 106.428636ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:27:58.676642Z","caller":"traceutil/trace.go:171","msg":"trace[1815986312] transaction","detail":"{read_only:false; response_revision:20658; number_of_response:1; }","duration":"167.528013ms","start":"2026-10-14T18:27:58.509082Z","end":"2026-10-14T18:27:58.67661Z","steps":["trace[1815986312] 'process raft request' (duration: 167.107542ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:31:06.994967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20036} {"level":"info","ts":"2026-10-14T18:31:07.114581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20036,"took":"114.011207ms","hash":200060,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":30617600,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:31:07.114672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":200060,"revision":20036,"compact-revision":17545} {"level":"warn","ts":"2026-10-14T18:31:49.749736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.880896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:31:49.749825Z","caller":"traceutil/trace.go:171","msg":"trace[1753412030] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23068; }","duration":"148.028331ms","start":"2026-10-14T18:31:49.601778Z","end":"2026-10-14T18:31:49.749806Z","steps":["trace[1753412030] 'range keys from in-memory index tree' (duration: 147.782894ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:31:49.750777Z","caller":"traceutil/trace.go:171","msg":"trace[954854009] transaction","detail":"{read_only:false; response_revision:23069; number_of_response:1; }","duration":"136.742973ms","start":"2026-10-14T18:31:49.613984Z","end":"2026-10-14T18:31:49.750727Z","steps":["trace[954854009] 'process raft request' (duration: 136.072565ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:31:51.115194Z","caller":"traceutil/trace.go:171","msg":"trace[2043727258] linearizableReadLoop","detail":"{readStateIndex:23864; appliedIndex:23863; }","duration":"181.224813ms","start":"2026-10-14T18:31:50.93395Z","end":"2026-10-14T18:31:51.115175Z","steps":["trace[2043727258] 'read index received' (duration: 181.103759ms)","trace[2043727258] 'applied index is now lower than readState.Index' (duration: 120.164µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:31:51.11528Z","caller":"traceutil/trace.go:171","msg":"trace[1934692212] transaction","detail":"{read_only:false; response_revision:23091; number_of_response:1; }","duration":"199.395041ms","start":"2026-10-14T18:31:50.915879Z","end":"2026-10-14T18:31:51.115274Z","steps":["trace[1934692212] 'process raft request' (duration: 199.155544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:31:51.115525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.550942ms","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-10-14T18:31:51.115597Z","caller":"traceutil/trace.go:171","msg":"trace[945157930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23091; }","duration":"181.681505ms","start":"2026-10-14T18:31:50.933899Z","end":"2026-10-14T18:31:51.115581Z","steps":["trace[945157930] 'agreement among raft nodes before linearized reading' (duration: 181.432718ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:31:51.535351Z","caller":"traceutil/trace.go:171","msg":"trace[1149808443] transaction","detail":"{read_only:false; response_revision:23092; number_of_response:1; }","duration":"414.353789ms","start":"2026-10-14T18:31:51.120951Z","end":"2026-10-14T18:31:51.535305Z","steps":["trace[1149808443] 'process raft request' (duration: 352.944355ms)","trace[1149808443] 'compare' (duration: 61.146096ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:31:51.535592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:31:51.120928Z","time spent":"414.532174ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:31:51.539572Z","caller":"traceutil/trace.go:171","msg":"trace[1558794552] linearizableReadLoop","detail":"{readStateIndex:23866; appliedIndex:23864; }","duration":"244.902235ms","start":"2026-10-14T18:31:51.294644Z","end":"2026-10-14T18:31:51.539547Z","steps":["trace[1558794552] 'read index received' (duration: 179.216979ms)","trace[1558794552] 'applied index is now lower than readState.Index' (duration: 65.683966ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:31:51.539793Z","caller":"traceutil/trace.go:171","msg":"trace[1274930738] transaction","detail":"{read_only:false; response_revision:23093; number_of_response:1; }","duration":"270.55483ms","start":"2026-10-14T18:31:51.269039Z","end":"2026-10-14T18:31:51.539594Z","steps":["trace[1274930738] 'process raft request' (duration: 270.349305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:31:51.539892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.269405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-14T18:31:51.539991Z","caller":"traceutil/trace.go:171","msg":"trace[1097253066] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:23093; }","duration":"245.394038ms","start":"2026-10-14T18:31:51.294572Z","end":"2026-10-14T18:31:51.539966Z","steps":["trace[1097253066] 'agreement among raft nodes before linearized reading' (duration: 245.08341ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.069652Z","caller":"traceutil/trace.go:171","msg":"trace[284884990] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"186.119983ms","start":"2026-10-14T18:35:03.883505Z","end":"2026-10-14T18:35:04.069625Z","steps":["trace[284884990] 'process raft request' (duration: 185.949208ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.332777Z","caller":"traceutil/trace.go:171","msg":"trace[690968441] linearizableReadLoop","detail":"{readStateIndex:25859; appliedIndex:25858; }","duration":"150.854523ms","start":"2026-10-14T18:35:04.181894Z","end":"2026-10-14T18:35:04.332749Z","steps":["trace[690968441] 'read index received' (duration: 135.336334ms)","trace[690968441] 'applied index is now lower than readState.Index' (duration: 15.516719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:04.333126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.219372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-14T18:35:04.333169Z","caller":"traceutil/trace.go:171","msg":"trace[1529497667] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25038; }","duration":"151.318575ms","start":"2026-10-14T18:35:04.18184Z","end":"2026-10-14T18:35:04.333159Z","steps":["trace[1529497667] 'agreement among raft nodes before linearized reading' (duration: 151.078909ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.333231Z","caller":"traceutil/trace.go:171","msg":"trace[431631092] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"252.605403ms","start":"2026-10-14T18:35:04.080574Z","end":"2026-10-14T18:35:04.33318Z","steps":["trace[431631092] 'process raft request' (duration: 236.707664ms)","trace[431631092] 'compare' (duration: 15.069538ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:04.6606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.544833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:35:04.661345Z","caller":"traceutil/trace.go:171","msg":"trace[1066805948] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"301.82829ms","start":"2026-10-14T18:35:04.35949Z","end":"2026-10-14T18:35:04.661318Z","steps":["trace[1066805948] 'process raft request' (duration: 174.422515ms)","trace[1066805948] 'compare' (duration: 125.932757ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:35:04.661465Z","caller":"traceutil/trace.go:171","msg":"trace[140276155] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"227.565974ms","start":"2026-10-14T18:35:04.433874Z","end":"2026-10-14T18:35:04.66144Z","steps":["trace[140276155] 'process raft request' (duration: 227.42556ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:04.661587Z","caller":"traceutil/trace.go:171","msg":"trace[1688647625] linearizableReadLoop","detail":"{readStateIndex:25860; appliedIndex:25859; }","duration":"249.561953ms","start":"2026-10-14T18:35:04.41199Z","end":"2026-10-14T18:35:04.661552Z","steps":["trace[1688647625] 'read index received' (duration: 122.307382ms)","trace[1688647625] 'applied index is now lower than readState.Index' (duration: 127.236281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:04.661827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:35:04.359468Z","time spent":"302.064406ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:35:04.662033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.015605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:35:04.662091Z","caller":"traceutil/trace.go:171","msg":"trace[768610842] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25040; }","duration":"250.111908ms","start":"2026-10-14T18:35:04.411949Z","end":"2026-10-14T18:35:04.662061Z","steps":["trace[768610842] 'agreement among raft nodes before linearized reading' (duration: 249.924043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.665442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.774609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:35:04.665493Z","caller":"traceutil/trace.go:171","msg":"trace[1493956624] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25042; }","duration":"197.864112ms","start":"2026-10-14T18:35:04.467617Z","end":"2026-10-14T18:35:04.665481Z","steps":["trace[1493956624] 'agreement among raft nodes before linearized reading' (duration: 197.767698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.665889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.049239ms","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-10-14T18:35:04.665942Z","caller":"traceutil/trace.go:171","msg":"trace[1631651218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25042; }","duration":"162.10423ms","start":"2026-10-14T18:35:04.503824Z","end":"2026-10-14T18:35:04.665928Z","steps":["trace[1631651218] 'agreement among raft nodes before linearized reading' (duration: 161.951306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:04.666001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.704364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-14T18:35:04.666125Z","caller":"traceutil/trace.go:171","msg":"trace[1890647320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25042; }","duration":"127.868848ms","start":"2026-10-14T18:35:04.538225Z","end":"2026-10-14T18:35:04.666094Z","steps":["trace[1890647320] 'agreement among raft nodes before linearized reading' (duration: 127.52484ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:07.988793Z","caller":"traceutil/trace.go:171","msg":"trace[562478617] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"108.880528ms","start":"2026-10-14T18:35:07.879879Z","end":"2026-10-14T18:35:07.98876Z","steps":["trace[562478617] 'process raft request' (duration: 108.383475ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:06.458352Z","caller":"traceutil/trace.go:171","msg":"trace[768836595] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"147.620899ms","start":"2026-10-14T18:36:06.310711Z","end":"2026-10-14T18:36:06.458332Z","steps":["trace[768836595] 'process raft request' (duration: 147.493975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:06.849651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.411384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:36:06.84976Z","caller":"traceutil/trace.go:171","msg":"trace[464456903] linearizableReadLoop","detail":"{readStateIndex:26448; appliedIndex:26447; }","duration":"165.613682ms","start":"2026-10-14T18:36:06.684129Z","end":"2026-10-14T18:36:06.849743Z","steps":["trace[464456903] 'read index received' (duration: 12.950471ms)","trace[464456903] 'applied index is now lower than readState.Index' (duration: 152.661891ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:36:06.849792Z","caller":"traceutil/trace.go:171","msg":"trace[979376785] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"248.811154ms","start":"2026-10-14T18:36:06.600945Z","end":"2026-10-14T18:36:06.849756Z","steps":["trace[979376785] 'process raft request' (duration: 96.203275ms)","trace[979376785] 'compare' (duration: 152.24397ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:36:06.849859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.726395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:36:06.849892Z","caller":"traceutil/trace.go:171","msg":"trace[2146010863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25613; }","duration":"165.785056ms","start":"2026-10-14T18:36:06.684094Z","end":"2026-10-14T18:36:06.849879Z","steps":["trace[2146010863] 'agreement among raft nodes before linearized reading' (duration: 165.732445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.274737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.242285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:36:07.274867Z","caller":"traceutil/trace.go:171","msg":"trace[1761801267] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"370.1645ms","start":"2026-10-14T18:36:06.904674Z","end":"2026-10-14T18:36:07.274839Z","steps":["trace[1761801267] 'process raft request' (duration: 164.712379ms)","trace[1761801267] 'compare' (duration: 205.055211ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:36:07.27495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:06.904647Z","time spent":"370.258243ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:07.276305Z","caller":"traceutil/trace.go:171","msg":"trace[5408248] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"361.434121ms","start":"2026-10-14T18:36:06.914855Z","end":"2026-10-14T18:36:07.276289Z","steps":["trace[5408248] 'process raft request' (duration: 360.615169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.276458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:06.914832Z","time spent":"361.531474ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:07.27657Z","caller":"traceutil/trace.go:171","msg":"trace[2083871013] linearizableReadLoop","detail":"{readStateIndex:26452; appliedIndex:26448; }","duration":"337.200273ms","start":"2026-10-14T18:36:06.939353Z","end":"2026-10-14T18:36:07.276553Z","steps":["trace[2083871013] 'read index received' (duration: 130.114128ms)","trace[2083871013] 'applied index is now lower than readState.Index' (duration: 207.084794ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:36:07.276602Z","caller":"traceutil/trace.go:171","msg":"trace[1260039127] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"280.55137ms","start":"2026-10-14T18:36:06.996033Z","end":"2026-10-14T18:36:07.276584Z","steps":["trace[1260039127] 'process raft request' (duration: 280.480248ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:07.276661Z","caller":"traceutil/trace.go:171","msg":"trace[1692406677] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"343.779426ms","start":"2026-10-14T18:36:06.932852Z","end":"2026-10-14T18:36:07.276631Z","steps":["trace[1692406677] 'process raft request' (duration: 343.595871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.276796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:06.932831Z","time spent":"343.895938ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:36:07.276797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.419888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:36:07.276893Z","caller":"traceutil/trace.go:171","msg":"trace[2074386320] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25618; }","duration":"337.560452ms","start":"2026-10-14T18:36:06.939312Z","end":"2026-10-14T18:36:07.276873Z","steps":["trace[2074386320] 'agreement among raft nodes before linearized reading' (duration: 337.350876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.276936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:06.939299Z","time spent":"337.624083ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:36:07.276567Z","caller":"traceutil/trace.go:171","msg":"trace[1541872188] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"355.325039ms","start":"2026-10-14T18:36:06.921205Z","end":"2026-10-14T18:36:07.27653Z","steps":["trace[1541872188] 'process raft request' (duration: 355.0305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.27754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:06.921184Z","time spent":"356.12471ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:07.292465Z","caller":"traceutil/trace.go:171","msg":"trace[1867996837] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"138.018145ms","start":"2026-10-14T18:36:07.154422Z","end":"2026-10-14T18:36:07.29244Z","steps":["trace[1867996837] 'process raft request' (duration: 137.874452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.292598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.206406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:36:07.292648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.126884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-14T18:36:07.292715Z","caller":"traceutil/trace.go:171","msg":"trace[1181137386] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25619; }","duration":"273.394421ms","start":"2026-10-14T18:36:07.019292Z","end":"2026-10-14T18:36:07.292687Z","steps":["trace[1181137386] 'agreement among raft nodes before linearized reading' (duration: 273.163545ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:07.29275Z","caller":"traceutil/trace.go:171","msg":"trace[1949996688] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25619; }","duration":"169.252658ms","start":"2026-10-14T18:36:07.123482Z","end":"2026-10-14T18:36:07.292734Z","steps":["trace[1949996688] 'agreement among raft nodes before linearized reading' (duration: 169.020902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.292759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.782609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-14T18:36:07.292797Z","caller":"traceutil/trace.go:171","msg":"trace[1553348882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25619; }","duration":"104.866251ms","start":"2026-10-14T18:36:07.187919Z","end":"2026-10-14T18:36:07.292785Z","steps":["trace[1553348882] 'agreement among raft nodes before linearized reading' (duration: 104.730788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.292795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.940347ms","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-10-14T18:36:07.292889Z","caller":"traceutil/trace.go:171","msg":"trace[1822570056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25619; }","duration":"175.0596ms","start":"2026-10-14T18:36:07.117807Z","end":"2026-10-14T18:36:07.292867Z","steps":["trace[1822570056] 'agreement among raft nodes before linearized reading' (duration: 174.888506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.292888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.683776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T18:36:07.292941Z","caller":"traceutil/trace.go:171","msg":"trace[1186297140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25619; }","duration":"170.762558ms","start":"2026-10-14T18:36:07.122163Z","end":"2026-10-14T18:36:07.292925Z","steps":["trace[1186297140] 'agreement among raft nodes before linearized reading' (duration: 170.629905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.292949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.519196ms","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-10-14T18:36:07.293053Z","caller":"traceutil/trace.go:171","msg":"trace[2043402731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25619; }","duration":"166.68466ms","start":"2026-10-14T18:36:07.126349Z","end":"2026-10-14T18:36:07.293034Z","steps":["trace[2043402731] 'agreement among raft nodes before linearized reading' (duration: 166.330371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.292903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.751088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-14T18:36:07.293188Z","caller":"traceutil/trace.go:171","msg":"trace[360278921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25619; }","duration":"171.055636ms","start":"2026-10-14T18:36:07.122114Z","end":"2026-10-14T18:36:07.29317Z","steps":["trace[360278921] 'agreement among raft nodes before linearized reading' (duration: 170.438169ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:07.602829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22230} {"level":"warn","ts":"2026-10-14T18:36:07.603712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.193164ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-10-14T18:36:07.603852Z","caller":"traceutil/trace.go:171","msg":"trace[700927277] compact","detail":"{revision:22230; response_revision:25619; }","duration":"325.862613ms","start":"2026-10-14T18:36:07.277973Z","end":"2026-10-14T18:36:07.603835Z","steps":["trace[700927277] 'process raft request' (duration: 209.522099ms)","trace[700927277] 'check and update compact revision' (duration: 115.064131ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:36:07.603885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:07.277923Z","time spent":"325.958865ms","remote":"127.0.0.1:37828","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-14T18:36:07.604267Z","caller":"traceutil/trace.go:171","msg":"trace[1571154035] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"307.101909ms","start":"2026-10-14T18:36:07.297152Z","end":"2026-10-14T18:36:07.604254Z","steps":["trace[1571154035] 'process raft request' (duration: 306.665517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.604363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:07.29713Z","time spent":"307.173751ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:07.604583Z","caller":"traceutil/trace.go:171","msg":"trace[1103426598] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"307.104559ms","start":"2026-10-14T18:36:07.297453Z","end":"2026-10-14T18:36:07.604557Z","steps":["trace[1103426598] 'process raft request' (duration: 306.790391ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:07.605343Z","caller":"traceutil/trace.go:171","msg":"trace[1779467104] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"307.581452ms","start":"2026-10-14T18:36:07.297736Z","end":"2026-10-14T18:36:07.605318Z","steps":["trace[1779467104] 'process raft request' (duration: 306.804671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.605367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:07.29743Z","time spent":"307.216412ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:36:07.605463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:07.297727Z","time spent":"307.666634ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:07.605799Z","caller":"traceutil/trace.go:171","msg":"trace[287547474] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"308.011983ms","start":"2026-10-14T18:36:07.29777Z","end":"2026-10-14T18:36:07.605782Z","steps":["trace[287547474] 'process raft request' (duration: 307.524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.605956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:07.297752Z","time spent":"308.088695ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:07.60756Z","caller":"traceutil/trace.go:171","msg":"trace[1453076502] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"309.274156ms","start":"2026-10-14T18:36:07.298265Z","end":"2026-10-14T18:36:07.607539Z","steps":["trace[1453076502] 'process raft request' (duration: 307.505089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.607654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:07.298253Z","time spent":"309.358209ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:36:07.608Z","caller":"traceutil/trace.go:171","msg":"trace[1452994213] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"309.709728ms","start":"2026-10-14T18:36:07.298269Z","end":"2026-10-14T18:36:07.607979Z","steps":["trace[1452994213] 'process raft request' (duration: 309.254966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:07.608163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:36:07.298244Z","time spent":"309.841992ms","remote":"127.0.0.1:38058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:36:07.823446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.225689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:36:07.82353Z","caller":"traceutil/trace.go:171","msg":"trace[916938498] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25625; }","duration":"103.374803ms","start":"2026-10-14T18:36:07.720136Z","end":"2026-10-14T18:36:07.823511Z","steps":["trace[916938498] 'count revisions from in-memory index tree' (duration: 103.135786ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:07.987247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22230,"took":"377.529583ms","hash":2754360920,"current-db-size-bytes":55468032,"current-db-size":"56 MB","current-db-size-in-use-bytes":44875776,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-14T18:36:07.987317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2754360920,"revision":22230,"compact-revision":20036} {"level":"info","ts":"2026-10-14T18:36:37.134912Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006897.wal"} {"level":"info","ts":"2026-10-14T18:41:07.611247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25618} {"level":"info","ts":"2026-10-14T18:41:07.789194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25618,"took":"173.732648ms","hash":3115270025,"current-db-size-bytes":56827904,"current-db-size":"57 MB","current-db-size-in-use-bytes":35323904,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T18:41:07.789265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3115270025,"revision":25618,"compact-revision":22230} {"level":"info","ts":"2026-10-14T18:42:27.699436Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:42:27.706824Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T18:42:27.706976Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T18:46:07.624029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28491} {"level":"info","ts":"2026-10-14T18:46:07.773838Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28491,"took":"144.792455ms","hash":2426465389,"current-db-size-bytes":56827904,"current-db-size":"57 MB","current-db-size-in-use-bytes":30756864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:46:07.773898Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2426465389,"revision":28491,"compact-revision":25618} {"level":"info","ts":"2026-10-14T18:51:07.632657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30711} {"level":"info","ts":"2026-10-14T18:51:07.758471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30711,"took":"121.711987ms","hash":1109746734,"current-db-size-bytes":56827904,"current-db-size":"57 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:51:07.758545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1109746734,"revision":30711,"compact-revision":28491} {"level":"info","ts":"2026-10-14T18:56:07.640295Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32967} {"level":"info","ts":"2026-10-14T18:56:07.811768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32967,"took":"167.063579ms","hash":961413392,"current-db-size-bytes":57872384,"current-db-size":"58 MB","current-db-size-in-use-bytes":30945280,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T18:56:07.811854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":961413392,"revision":32967,"compact-revision":30711} {"level":"info","ts":"2026-10-14T19:01:07.649743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35263} {"level":"info","ts":"2026-10-14T19:01:07.793077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35263,"took":"139.454734ms","hash":2734580629,"current-db-size-bytes":57872384,"current-db-size":"58 MB","current-db-size-in-use-bytes":33218560,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T19:01:07.793132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2734580629,"revision":35263,"compact-revision":32967} {"level":"info","ts":"2026-10-14T19:03:51.631911Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"43ce62d0a10d51da","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T19:03:51.637552Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T19:03:51.637669Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T19:06:07.656093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37556} {"level":"info","ts":"2026-10-14T19:06:07.792523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37556,"took":"132.229195ms","hash":1724615527,"current-db-size-bytes":57872384,"current-db-size":"58 MB","current-db-size-in-use-bytes":33214464,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T19:06:07.792618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1724615527,"revision":37556,"compact-revision":35263}