{"level":"warn","ts":"2026-09-23T10:35:40.123026Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-23T10:35:40.123124Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.127: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.127:2380","--initial-cluster=instance=https://162.253.55.127:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.127:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.127:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-23T10:35:40.123239Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-23T10:35:40.123269Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.127:2380"]} {"level":"info","ts":"2026-09-23T10:35:40.123321Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-23T10:35:40.124168Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"]} {"level":"info","ts":"2026-09-23T10:35:40.124289Z","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.127:2380"],"listen-peer-urls":["https://162.253.55.127:2380"],"advertise-client-urls":["https://162.253.55.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.127:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-23T10:35:40.129705Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.089674ms"} {"level":"info","ts":"2026-09-23T10:35:40.144228Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9e5e76b85fbfd435","cluster-id":"7d97e4b11cbd31c2"} {"level":"info","ts":"2026-09-23T10:35:40.144351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=()"} {"level":"info","ts":"2026-09-23T10:35:40.144378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became follower at term 0"} {"level":"info","ts":"2026-09-23T10:35:40.144394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9e5e76b85fbfd435 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-23T10:35:40.144409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became follower at term 1"} {"level":"info","ts":"2026-09-23T10:35:40.144498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=(11411689040055882805)"} {"level":"warn","ts":"2026-09-23T10:35:40.156045Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-23T10:35:40.158461Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-23T10:35:40.161438Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-23T10:35:40.165334Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9e5e76b85fbfd435","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-23T10:35:40.165605Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9e5e76b85fbfd435","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-23T10:35:40.16569Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-23T10:35:40.165796Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-23T10:35:40.165837Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-23T10:35:40.167212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=(11411689040055882805)"} {"level":"info","ts":"2026-09-23T10:35:40.167475Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7d97e4b11cbd31c2","local-member-id":"9e5e76b85fbfd435","added-peer-id":"9e5e76b85fbfd435","added-peer-peer-urls":["https://162.253.55.127:2380"]} {"level":"info","ts":"2026-09-23T10:35:40.170736Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-23T10:35:40.170843Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.127:2380"} {"level":"info","ts":"2026-09-23T10:35:40.170937Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.127:2380"} {"level":"info","ts":"2026-09-23T10:35:40.171081Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9e5e76b85fbfd435","initial-advertise-peer-urls":["https://162.253.55.127:2380"],"listen-peer-urls":["https://162.253.55.127:2380"],"advertise-client-urls":["https://162.253.55.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-23T10:35:40.171161Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-23T10:35:40.345189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 is starting a new election at term 1"} {"level":"info","ts":"2026-09-23T10:35:40.345279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-23T10:35:40.345332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 received MsgPreVoteResp from 9e5e76b85fbfd435 at term 1"} {"level":"info","ts":"2026-09-23T10:35:40.345352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became candidate at term 2"} {"level":"info","ts":"2026-09-23T10:35:40.345363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 received MsgVoteResp from 9e5e76b85fbfd435 at term 2"} {"level":"info","ts":"2026-09-23T10:35:40.345377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became leader at term 2"} {"level":"info","ts":"2026-09-23T10:35:40.345394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9e5e76b85fbfd435 elected leader 9e5e76b85fbfd435 at term 2"} {"level":"info","ts":"2026-09-23T10:35:40.346952Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9e5e76b85fbfd435","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.127:2379]}","request-path":"/0/members/9e5e76b85fbfd435/attributes","cluster-id":"7d97e4b11cbd31c2","publish-timeout":"7s"} {"level":"info","ts":"2026-09-23T10:35:40.346997Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T10:35:40.347115Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:35:40.347025Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T10:35:40.347551Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-23T10:35:40.347577Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-23T10:35:40.348957Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7d97e4b11cbd31c2","local-member-id":"9e5e76b85fbfd435","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:35:40.349066Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:35:40.349096Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:35:40.350778Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.127:2379"} {"level":"info","ts":"2026-09-23T10:35:40.350804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-23T10:35:49.955336Z","caller":"traceutil/trace.go:171","msg":"trace[1345379738] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"126.775683ms","start":"2026-09-23T10:35:49.828491Z","end":"2026-09-23T10:35:49.955266Z","steps":["trace[1345379738] 'process raft request' (duration: 58.146893ms)","trace[1345379738] 'compare' (duration: 68.101696ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:35:49.955525Z","caller":"traceutil/trace.go:171","msg":"trace[126956348] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"126.699882ms","start":"2026-09-23T10:35:49.828769Z","end":"2026-09-23T10:35:49.955469Z","steps":["trace[126956348] 'process raft request' (duration: 126.26022ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:36:28.820501Z","caller":"traceutil/trace.go:171","msg":"trace[1780120592] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"151.311182ms","start":"2026-09-23T10:36:28.669151Z","end":"2026-09-23T10:36:28.820462Z","steps":["trace[1780120592] 'process raft request' (duration: 150.636685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:36:29.477932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.360095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:36:29.47837Z","caller":"traceutil/trace.go:171","msg":"trace[817864323] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"638.049992ms","start":"2026-09-23T10:36:28.840277Z","end":"2026-09-23T10:36:29.478327Z","steps":["trace[817864323] 'process raft request' (duration: 227.277704ms)","trace[817864323] 'compare' (duration: 409.168441ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:36:29.478492Z","caller":"traceutil/trace.go:171","msg":"trace[164318865] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"584.168749ms","start":"2026-09-23T10:36:28.894263Z","end":"2026-09-23T10:36:29.478432Z","steps":["trace[164318865] 'read index received' (duration: 173.337342ms)","trace[164318865] 'applied index is now lower than readState.Index' (duration: 410.826007ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:36:29.478509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:36:28.840235Z","time spent":"638.216846ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:36:29.478609Z","caller":"traceutil/trace.go:171","msg":"trace[1383065979] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"580.20272ms","start":"2026-09-23T10:36:28.898376Z","end":"2026-09-23T10:36:29.478579Z","steps":["trace[1383065979] 'process raft request' (duration: 579.938368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:36:29.478821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:36:28.898356Z","time spent":"580.391384ms","remote":"127.0.0.1:43678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-23T10:36:29.557633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.344963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-23T10:36:29.557653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.910298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:36:29.557716Z","caller":"traceutil/trace.go:171","msg":"trace[568523369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"663.544376ms","start":"2026-09-23T10:36:28.894151Z","end":"2026-09-23T10:36:29.557695Z","steps":["trace[568523369] 'agreement among raft nodes before linearized reading' (duration: 584.381811ms)","trace[568523369] 'range keys from in-memory index tree' (duration: 78.903164ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:36:29.557749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:36:28.894123Z","time spent":"663.616404ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-23T10:36:29.557748Z","caller":"traceutil/trace.go:171","msg":"trace[2000685953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"530.063043ms","start":"2026-09-23T10:36:29.027658Z","end":"2026-09-23T10:36:29.557721Z","steps":["trace[2000685953] 'agreement among raft nodes before linearized reading' (duration: 529.87618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:36:29.557746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.950215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-23T10:36:29.557796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:36:29.027629Z","time spent":"530.15958ms","remote":"127.0.0.1:43356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T10:36:29.557811Z","caller":"traceutil/trace.go:171","msg":"trace[274100586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"521.054622ms","start":"2026-09-23T10:36:29.036739Z","end":"2026-09-23T10:36:29.557793Z","steps":["trace[274100586] 'agreement among raft nodes before linearized reading' (duration: 520.82209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:36:29.557849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:36:29.036717Z","time spent":"521.122799ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T10:36:31.041218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.791029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-23T10:36:31.04137Z","caller":"traceutil/trace.go:171","msg":"trace[1270109399] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:628; }","duration":"108.016275ms","start":"2026-09-23T10:36:30.933319Z","end":"2026-09-23T10:36:31.041336Z","steps":["trace[1270109399] 'range keys from in-memory index tree' (duration: 107.602524ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:39.383372Z","caller":"traceutil/trace.go:171","msg":"trace[770572206] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"115.707349ms","start":"2026-09-23T10:37:39.267634Z","end":"2026-09-23T10:37:39.383342Z","steps":["trace[770572206] 'process raft request' (duration: 114.744042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:37:55.581712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.060422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:37:55.582036Z","caller":"traceutil/trace.go:171","msg":"trace[1658398771] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"162.702712ms","start":"2026-09-23T10:37:55.419287Z","end":"2026-09-23T10:37:55.58199Z","steps":["trace[1658398771] 'process raft request' (duration: 56.231739ms)","trace[1658398771] 'compare' (duration: 105.669781ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:37:55.582074Z","caller":"traceutil/trace.go:171","msg":"trace[1100984208] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"162.768154ms","start":"2026-09-23T10:37:55.419287Z","end":"2026-09-23T10:37:55.582055Z","steps":["trace[1100984208] 'process raft request' (duration: 162.600279ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:55.588226Z","caller":"traceutil/trace.go:171","msg":"trace[91173731] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"167.466044ms","start":"2026-09-23T10:37:55.420691Z","end":"2026-09-23T10:37:55.588157Z","steps":["trace[91173731] 'process raft request' (duration: 167.185446ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:37:55.5883Z","caller":"traceutil/trace.go:171","msg":"trace[358165024] linearizableReadLoop","detail":"{readStateIndex:1779; appliedIndex:1775; }","duration":"125.244213ms","start":"2026-09-23T10:37:55.463038Z","end":"2026-09-23T10:37:55.588282Z","steps":["trace[358165024] 'read index received' (duration: 12.556908ms)","trace[358165024] 'applied index is now lower than readState.Index' (duration: 112.686285ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:37:55.588323Z","caller":"traceutil/trace.go:171","msg":"trace[1839222321] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"131.942779ms","start":"2026-09-23T10:37:55.456362Z","end":"2026-09-23T10:37:55.588305Z","steps":["trace[1839222321] 'process raft request' (duration: 131.702352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:37:55.588646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.579192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-23T10:37:55.588717Z","caller":"traceutil/trace.go:171","msg":"trace[1875827419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1707; }","duration":"125.697606ms","start":"2026-09-23T10:37:55.463002Z","end":"2026-09-23T10:37:55.588699Z","steps":["trace[1875827419] 'agreement among raft nodes before linearized reading' (duration: 125.332736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:38:06.171583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.68147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:38:06.171676Z","caller":"traceutil/trace.go:171","msg":"trace[1443799780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"145.834585ms","start":"2026-09-23T10:38:06.025825Z","end":"2026-09-23T10:38:06.171659Z","steps":["trace[1443799780] 'range keys from in-memory index tree' (duration: 145.613228ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:06.56842Z","caller":"traceutil/trace.go:171","msg":"trace[463471493] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"139.833258ms","start":"2026-09-23T10:38:06.428547Z","end":"2026-09-23T10:38:06.56838Z","steps":["trace[463471493] 'process raft request' (duration: 95.17675ms)","trace[463471493] 'compare' (duration: 44.512264ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:38:13.003922Z","caller":"traceutil/trace.go:171","msg":"trace[1497522866] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"178.074459ms","start":"2026-09-23T10:38:12.825818Z","end":"2026-09-23T10:38:13.003893Z","steps":["trace[1497522866] 'process raft request' (duration: 177.842672ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:13.042644Z","caller":"traceutil/trace.go:171","msg":"trace[584296662] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"182.245513ms","start":"2026-09-23T10:38:12.860373Z","end":"2026-09-23T10:38:13.042619Z","steps":["trace[584296662] 'process raft request' (duration: 182.170151ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:13.042701Z","caller":"traceutil/trace.go:171","msg":"trace[1258345395] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"215.186138ms","start":"2026-09-23T10:38:12.827479Z","end":"2026-09-23T10:38:13.042665Z","steps":["trace[1258345395] 'process raft request' (duration: 214.91819ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:13.042896Z","caller":"traceutil/trace.go:171","msg":"trace[1394915259] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"123.891435ms","start":"2026-09-23T10:38:12.918976Z","end":"2026-09-23T10:38:13.042868Z","steps":["trace[1394915259] 'process raft request' (duration: 123.610047ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:50.137233Z","caller":"traceutil/trace.go:171","msg":"trace[697812796] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2225; }","duration":"109.887688ms","start":"2026-09-23T10:38:50.027322Z","end":"2026-09-23T10:38:50.13721Z","steps":["trace[697812796] 'read index received' (duration: 21.077785ms)","trace[697812796] 'applied index is now lower than readState.Index' (duration: 88.809023ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:38:50.13738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.047562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:38:50.137402Z","caller":"traceutil/trace.go:171","msg":"trace[1233166111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2142; }","duration":"110.139925ms","start":"2026-09-23T10:38:50.027255Z","end":"2026-09-23T10:38:50.137395Z","steps":["trace[1233166111] 'agreement among raft nodes before linearized reading' (duration: 110.074563ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:50.13739Z","caller":"traceutil/trace.go:171","msg":"trace[654176148] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"181.786571ms","start":"2026-09-23T10:38:49.955529Z","end":"2026-09-23T10:38:50.137316Z","steps":["trace[654176148] 'process raft request' (duration: 92.937407ms)","trace[654176148] 'compare' (duration: 88.418452ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:38:50.823162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.138588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:38:50.823312Z","caller":"traceutil/trace.go:171","msg":"trace[887035799] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"250.322372ms","start":"2026-09-23T10:38:50.572974Z","end":"2026-09-23T10:38:50.823297Z","steps":["trace[887035799] 'read index received' (duration: 93.0004ms)","trace[887035799] 'applied index is now lower than readState.Index' (duration: 157.320432ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:38:50.823449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.469706ms","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":"info","ts":"2026-09-23T10:38:50.823427Z","caller":"traceutil/trace.go:171","msg":"trace[896542512] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"309.254577ms","start":"2026-09-23T10:38:50.514092Z","end":"2026-09-23T10:38:50.823346Z","steps":["trace[896542512] 'process raft request' (duration: 151.811521ms)","trace[896542512] 'compare' (duration: 156.899761ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:38:50.823485Z","caller":"traceutil/trace.go:171","msg":"trace[1424067750] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"250.539898ms","start":"2026-09-23T10:38:50.572933Z","end":"2026-09-23T10:38:50.823472Z","steps":["trace[1424067750] 'agreement among raft nodes before linearized reading' (duration: 250.405244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:38:50.823606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:38:50.514063Z","time spent":"309.454282ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:38:50.935605Z","caller":"traceutil/trace.go:171","msg":"trace[1194477291] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"145.64956ms","start":"2026-09-23T10:38:50.789927Z","end":"2026-09-23T10:38:50.935577Z","steps":["trace[1194477291] 'process raft request' (duration: 145.584058ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:38:50.935637Z","caller":"traceutil/trace.go:171","msg":"trace[994375972] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"352.782964ms","start":"2026-09-23T10:38:50.582802Z","end":"2026-09-23T10:38:50.935585Z","steps":["trace[994375972] 'process raft request' (duration: 346.825858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:38:50.936079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:38:50.582774Z","time spent":"352.926068ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:40:19.703926Z","caller":"traceutil/trace.go:171","msg":"trace[1689268155] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"117.686294ms","start":"2026-09-23T10:40:19.58619Z","end":"2026-09-23T10:40:19.703876Z","steps":["trace[1689268155] 'process raft request' (duration: 117.458087ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:40:19.963796Z","caller":"traceutil/trace.go:171","msg":"trace[707000438] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"241.344781ms","start":"2026-09-23T10:40:19.722409Z","end":"2026-09-23T10:40:19.963754Z","steps":["trace[707000438] 'process raft request' (duration: 241.067033ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:17.657766Z","caller":"traceutil/trace.go:171","msg":"trace[645548854] linearizableReadLoop","detail":"{readStateIndex:5488; appliedIndex:5487; }","duration":"107.850315ms","start":"2026-09-23T10:45:17.549881Z","end":"2026-09-23T10:45:17.657731Z","steps":["trace[645548854] 'read index received' (duration: 66.431616ms)","trace[645548854] 'applied index is now lower than readState.Index' (duration: 41.417279ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:45:17.657839Z","caller":"traceutil/trace.go:171","msg":"trace[169421256] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"171.077892ms","start":"2026-09-23T10:45:17.486731Z","end":"2026-09-23T10:45:17.657809Z","steps":["trace[169421256] 'process raft request' (duration: 129.671954ms)","trace[169421256] 'compare' (duration: 41.083799ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:45:17.658103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.203114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b8d2f50d-eda8-4af1-9cd6-f1de41a68b9a\" ","response":"range_response_count:1 size:4068"} {"level":"info","ts":"2026-09-23T10:45:17.658138Z","caller":"traceutil/trace.go:171","msg":"trace[1290128367] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b8d2f50d-eda8-4af1-9cd6-f1de41a68b9a; range_end:; response_count:1; response_revision:5285; }","duration":"108.285226ms","start":"2026-09-23T10:45:17.54984Z","end":"2026-09-23T10:45:17.658125Z","steps":["trace[1290128367] 'agreement among raft nodes before linearized reading' (duration: 108.06167ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:19.123647Z","caller":"traceutil/trace.go:171","msg":"trace[1348143978] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"120.435371ms","start":"2026-09-23T10:45:19.003177Z","end":"2026-09-23T10:45:19.123612Z","steps":["trace[1348143978] 'process raft request' (duration: 120.353399ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:19.12376Z","caller":"traceutil/trace.go:171","msg":"trace[581867482] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"122.713113ms","start":"2026-09-23T10:45:19.000999Z","end":"2026-09-23T10:45:19.123713Z","steps":["trace[581867482] 'process raft request' (duration: 72.271836ms)","trace[581867482] 'compare' (duration: 50.116528ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:45:19.124644Z","caller":"traceutil/trace.go:171","msg":"trace[1058121051] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"108.680728ms","start":"2026-09-23T10:45:19.01594Z","end":"2026-09-23T10:45:19.124621Z","steps":["trace[1058121051] 'process raft request' (duration: 108.486682ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:33.977663Z","caller":"traceutil/trace.go:171","msg":"trace[1342200648] linearizableReadLoop","detail":"{readStateIndex:5786; appliedIndex:5785; }","duration":"181.660953ms","start":"2026-09-23T10:45:33.795976Z","end":"2026-09-23T10:45:33.977637Z","steps":["trace[1342200648] 'read index received' (duration: 181.252542ms)","trace[1342200648] 'applied index is now lower than readState.Index' (duration: 407.551µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:45:33.977716Z","caller":"traceutil/trace.go:171","msg":"trace[1776454819] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"309.467396ms","start":"2026-09-23T10:45:33.668212Z","end":"2026-09-23T10:45:33.977679Z","steps":["trace[1776454819] 'process raft request' (duration: 309.181298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:45:33.97789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:45:33.66818Z","time spent":"309.61838ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":130947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T10:45:33.977921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.929341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-23T10:45:33.977963Z","caller":"traceutil/trace.go:171","msg":"trace[203889276] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5573; }","duration":"182.033393ms","start":"2026-09-23T10:45:33.795916Z","end":"2026-09-23T10:45:33.977949Z","steps":["trace[203889276] 'agreement among raft nodes before linearized reading' (duration: 181.851688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:45:33.978145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.816966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-23T10:45:33.978198Z","caller":"traceutil/trace.go:171","msg":"trace[424108926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5573; }","duration":"114.907509ms","start":"2026-09-23T10:45:33.863273Z","end":"2026-09-23T10:45:33.978181Z","steps":["trace[424108926] 'agreement among raft nodes before linearized reading' (duration: 114.764385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:45:33.978279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.377104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T10:45:33.978424Z","caller":"traceutil/trace.go:171","msg":"trace[1526437261] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5573; }","duration":"102.58159ms","start":"2026-09-23T10:45:33.875822Z","end":"2026-09-23T10:45:33.978404Z","steps":["trace[1526437261] 'agreement among raft nodes before linearized reading' (duration: 102.256331ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:34.114538Z","caller":"traceutil/trace.go:171","msg":"trace[755986568] transaction","detail":"{read_only:false; response_revision:5575; number_of_response:1; }","duration":"131.742101ms","start":"2026-09-23T10:45:33.982756Z","end":"2026-09-23T10:45:34.114498Z","steps":["trace[755986568] 'process raft request' (duration: 126.849347ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:34.1149Z","caller":"traceutil/trace.go:171","msg":"trace[940910792] linearizableReadLoop","detail":"{readStateIndex:5789; appliedIndex:5786; }","duration":"127.457253ms","start":"2026-09-23T10:45:33.987433Z","end":"2026-09-23T10:45:34.11489Z","steps":["trace[940910792] 'read index received' (duration: 5.747248ms)","trace[940910792] 'applied index is now lower than readState.Index' (duration: 121.706155ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:45:34.114994Z","caller":"traceutil/trace.go:171","msg":"trace[944875426] transaction","detail":"{read_only:false; response_revision:5576; number_of_response:1; }","duration":"131.204356ms","start":"2026-09-23T10:45:33.98378Z","end":"2026-09-23T10:45:34.114984Z","steps":["trace[944875426] 'process raft request' (duration: 130.571268ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:34.115269Z","caller":"traceutil/trace.go:171","msg":"trace[1228802860] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"117.992873ms","start":"2026-09-23T10:45:33.997164Z","end":"2026-09-23T10:45:34.115157Z","steps":["trace[1228802860] 'process raft request' (duration: 117.287823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:45:34.116302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.831561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-xzklp\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-09-23T10:45:34.116429Z","caller":"traceutil/trace.go:171","msg":"trace[487791087] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-xzklp; range_end:; response_count:1; response_revision:5577; }","duration":"129.009016ms","start":"2026-09-23T10:45:33.987396Z","end":"2026-09-23T10:45:34.116405Z","steps":["trace[487791087] 'agreement among raft nodes before linearized reading' (duration: 127.562496ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:34.117056Z","caller":"traceutil/trace.go:171","msg":"trace[1443064992] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"119.770482ms","start":"2026-09-23T10:45:33.997263Z","end":"2026-09-23T10:45:34.117034Z","steps":["trace[1443064992] 'process raft request' (duration: 117.327455ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:45:40.843051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2937} {"level":"info","ts":"2026-09-23T10:45:40.931928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2937,"took":"87.657709ms","hash":1358652187,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":31698944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T10:45:40.931996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1358652187,"revision":2937,"compact-revision":-1} {"level":"warn","ts":"2026-09-23T10:46:39.732992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.066843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-09-23T10:46:39.733206Z","caller":"traceutil/trace.go:171","msg":"trace[733559967] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:6287; }","duration":"103.211287ms","start":"2026-09-23T10:46:39.629865Z","end":"2026-09-23T10:46:39.733077Z","steps":["trace[733559967] 'range keys from in-memory index tree' (duration: 102.852707ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:50:40.853378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5677} {"level":"info","ts":"2026-09-23T10:50:40.944109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5677,"took":"89.104199ms","hash":1839249836,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":25952256,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-23T10:50:40.944187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1839249836,"revision":5677,"compact-revision":2937} {"level":"info","ts":"2026-09-23T10:52:09.639846Z","caller":"traceutil/trace.go:171","msg":"trace[188518374] linearizableReadLoop","detail":"{readStateIndex:9003; appliedIndex:9002; }","duration":"323.756004ms","start":"2026-09-23T10:52:09.316066Z","end":"2026-09-23T10:52:09.639822Z","steps":["trace[188518374] 'read index received' (duration: 323.58322ms)","trace[188518374] 'applied index is now lower than readState.Index' (duration: 171.894µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:52:09.639891Z","caller":"traceutil/trace.go:171","msg":"trace[1761293185] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"329.567244ms","start":"2026-09-23T10:52:09.310301Z","end":"2026-09-23T10:52:09.639868Z","steps":["trace[1761293185] 'process raft request' (duration: 329.377388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:52:09.640037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.96107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:52:09.640091Z","caller":"traceutil/trace.go:171","msg":"trace[132338336] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8693; }","duration":"324.050002ms","start":"2026-09-23T10:52:09.316024Z","end":"2026-09-23T10:52:09.640074Z","steps":["trace[132338336] 'agreement among raft nodes before linearized reading' (duration: 323.95426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:52:09.640137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:52:09.31601Z","time spent":"324.115564ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-23T10:52:09.640157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:52:09.310274Z","time spent":"329.755039ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:52:10.323792Z","caller":"traceutil/trace.go:171","msg":"trace[1439298739] linearizableReadLoop","detail":"{readStateIndex:9006; appliedIndex:9005; }","duration":"142.573574ms","start":"2026-09-23T10:52:10.181194Z","end":"2026-09-23T10:52:10.323767Z","steps":["trace[1439298739] 'read index received' (duration: 142.346608ms)","trace[1439298739] 'applied index is now lower than readState.Index' (duration: 226.146µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:52:10.323864Z","caller":"traceutil/trace.go:171","msg":"trace[167038653] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"219.242553ms","start":"2026-09-23T10:52:10.104587Z","end":"2026-09-23T10:52:10.32383Z","steps":["trace[167038653] 'process raft request' (duration: 219.009406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:52:10.324041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.830611ms","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-09-23T10:52:10.324092Z","caller":"traceutil/trace.go:171","msg":"trace[1885090644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8695; }","duration":"142.925183ms","start":"2026-09-23T10:52:10.181141Z","end":"2026-09-23T10:52:10.324066Z","steps":["trace[1885090644] 'agreement among raft nodes before linearized reading' (duration: 142.747728ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:52:10.820533Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002334.wal"} {"level":"info","ts":"2026-09-23T10:52:10.820873Z","caller":"traceutil/trace.go:171","msg":"trace[1198165755] linearizableReadLoop","detail":"{readStateIndex:9011; appliedIndex:9010; }","duration":"224.921349ms","start":"2026-09-23T10:52:10.595929Z","end":"2026-09-23T10:52:10.820851Z","steps":["trace[1198165755] 'read index received' (duration: 224.757554ms)","trace[1198165755] 'applied index is now lower than readState.Index' (duration: 162.965µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:52:10.820941Z","caller":"traceutil/trace.go:171","msg":"trace[2091713422] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"244.797652ms","start":"2026-09-23T10:52:10.576108Z","end":"2026-09-23T10:52:10.820906Z","steps":["trace[2091713422] 'process raft request' (duration: 244.599577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:52:10.821049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.098613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-23T10:52:10.821091Z","caller":"traceutil/trace.go:171","msg":"trace[599380084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8700; }","duration":"225.157755ms","start":"2026-09-23T10:52:10.595923Z","end":"2026-09-23T10:52:10.821081Z","steps":["trace[599380084] 'agreement among raft nodes before linearized reading' (duration: 225.009271ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:52:53.517845Z","caller":"traceutil/trace.go:171","msg":"trace[660333985] transaction","detail":"{read_only:false; response_revision:9018; number_of_response:1; }","duration":"122.601387ms","start":"2026-09-23T10:52:53.39522Z","end":"2026-09-23T10:52:53.517821Z","steps":["trace[660333985] 'process raft request' (duration: 122.435462ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:53:54.953852Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9e5e76b85fbfd435","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T10:53:54.96148Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-23T10:53:54.961604Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-23T10:55:40.861813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7991} {"level":"info","ts":"2026-09-23T10:55:40.936343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7991,"took":"72.379466ms","hash":1875180344,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":28622848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-23T10:55:40.936416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1875180344,"revision":7991,"compact-revision":5677} {"level":"info","ts":"2026-09-23T11:00:40.868382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10395} {"level":"info","ts":"2026-09-23T11:00:40.957728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10395,"took":"86.567907ms","hash":2138711872,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":27639808,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T11:00:40.957793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2138711872,"revision":10395,"compact-revision":7991} {"level":"info","ts":"2026-09-23T11:04:31.566558Z","caller":"traceutil/trace.go:171","msg":"trace[1673756356] transaction","detail":"{read_only:false; response_revision:14263; number_of_response:1; }","duration":"116.083764ms","start":"2026-09-23T11:04:31.450428Z","end":"2026-09-23T11:04:31.566512Z","steps":["trace[1673756356] 'process raft request' (duration: 115.881978ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:04:31.592007Z","caller":"traceutil/trace.go:171","msg":"trace[82059949] transaction","detail":"{read_only:false; response_revision:14264; number_of_response:1; }","duration":"137.02594ms","start":"2026-09-23T11:04:31.454944Z","end":"2026-09-23T11:04:31.59197Z","steps":["trace[82059949] 'process raft request' (duration: 136.28842ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:04:55.855718Z","caller":"traceutil/trace.go:171","msg":"trace[1738153625] linearizableReadLoop","detail":"{readStateIndex:14885; appliedIndex:14884; }","duration":"192.048633ms","start":"2026-09-23T11:04:55.663639Z","end":"2026-09-23T11:04:55.855687Z","steps":["trace[1738153625] 'read index received' (duration: 191.841847ms)","trace[1738153625] 'applied index is now lower than readState.Index' (duration: 205.356µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:04:55.855915Z","caller":"traceutil/trace.go:171","msg":"trace[1490689790] transaction","detail":"{read_only:false; response_revision:14387; number_of_response:1; }","duration":"202.695271ms","start":"2026-09-23T11:04:55.653182Z","end":"2026-09-23T11:04:55.855877Z","steps":["trace[1490689790] 'process raft request' (duration: 202.328891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:04:55.856348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.686371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T11:04:55.856419Z","caller":"traceutil/trace.go:171","msg":"trace[1374269811] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:14387; }","duration":"192.813964ms","start":"2026-09-23T11:04:55.663585Z","end":"2026-09-23T11:04:55.856399Z","steps":["trace[1374269811] 'agreement among raft nodes before linearized reading' (duration: 192.283639ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:04:55.87564Z","caller":"traceutil/trace.go:171","msg":"trace[2132343435] transaction","detail":"{read_only:false; response_revision:14388; number_of_response:1; }","duration":"162.985639ms","start":"2026-09-23T11:04:55.712618Z","end":"2026-09-23T11:04:55.875604Z","steps":["trace[2132343435] 'process raft request' (duration: 162.742972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:04:55.875823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.844483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-23T11:04:55.875896Z","caller":"traceutil/trace.go:171","msg":"trace[555065556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14389; }","duration":"102.967846ms","start":"2026-09-23T11:04:55.772911Z","end":"2026-09-23T11:04:55.875879Z","steps":["trace[555065556] 'agreement among raft nodes before linearized reading' (duration: 102.645507ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:05:03.320362Z","caller":"traceutil/trace.go:171","msg":"trace[1217405273] transaction","detail":"{read_only:false; response_revision:14440; number_of_response:1; }","duration":"113.276595ms","start":"2026-09-23T11:05:03.207058Z","end":"2026-09-23T11:05:03.320334Z","steps":["trace[1217405273] 'process raft request' (duration: 113.10246ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:05:40.87798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12723} {"level":"info","ts":"2026-09-23T11:05:40.963058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12723,"took":"81.998458ms","hash":3456626479,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":28962816,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-23T11:05:40.963163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3456626479,"revision":12723,"compact-revision":10395} {"level":"info","ts":"2026-09-23T11:07:02.268682Z","caller":"traceutil/trace.go:171","msg":"trace[1486411909] transaction","detail":"{read_only:false; response_revision:15647; number_of_response:1; }","duration":"208.569184ms","start":"2026-09-23T11:07:02.060086Z","end":"2026-09-23T11:07:02.268655Z","steps":["trace[1486411909] 'process raft request' (duration: 198.419265ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:07:02.268827Z","caller":"traceutil/trace.go:171","msg":"trace[1532017721] linearizableReadLoop","detail":"{readStateIndex:16190; appliedIndex:16188; }","duration":"148.838882ms","start":"2026-09-23T11:07:02.119966Z","end":"2026-09-23T11:07:02.268805Z","steps":["trace[1532017721] 'read index received' (duration: 18.157679ms)","trace[1532017721] 'applied index is now lower than readState.Index' (duration: 130.680113ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:07:02.269051Z","caller":"traceutil/trace.go:171","msg":"trace[1561232507] transaction","detail":"{read_only:false; response_revision:15648; number_of_response:1; }","duration":"156.340209ms","start":"2026-09-23T11:07:02.112699Z","end":"2026-09-23T11:07:02.269039Z","steps":["trace[1561232507] 'process raft request' (duration: 155.907017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:07:02.26961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.198125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-dg2h6\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-09-23T11:07:02.269684Z","caller":"traceutil/trace.go:171","msg":"trace[494531871] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-dg2h6; range_end:; response_count:1; response_revision:15648; }","duration":"104.315868ms","start":"2026-09-23T11:07:02.16535Z","end":"2026-09-23T11:07:02.269666Z","steps":["trace[494531871] 'agreement among raft nodes before linearized reading' (duration: 104.00473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:07:02.270066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.073556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-dg2h6\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-09-23T11:07:02.270161Z","caller":"traceutil/trace.go:171","msg":"trace[869555032] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-dg2h6; range_end:; response_count:1; response_revision:15648; }","duration":"150.257021ms","start":"2026-09-23T11:07:02.119881Z","end":"2026-09-23T11:07:02.270138Z","steps":["trace[869555032] 'agreement among raft nodes before linearized reading' (duration: 149.906522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:07:02.487846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.873586ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T11:07:02.487993Z","caller":"traceutil/trace.go:171","msg":"trace[535685133] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15648; }","duration":"112.041471ms","start":"2026-09-23T11:07:02.375932Z","end":"2026-09-23T11:07:02.487974Z","steps":["trace[535685133] 'range keys from in-memory index tree' (duration: 111.851826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:07:02.488375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.508867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-23T11:07:02.488421Z","caller":"traceutil/trace.go:171","msg":"trace[615985098] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15648; }","duration":"111.592209ms","start":"2026-09-23T11:07:02.376817Z","end":"2026-09-23T11:07:02.488409Z","steps":["trace[615985098] 'range keys from in-memory index tree' (duration: 111.337891ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:07:02.77391Z","caller":"traceutil/trace.go:171","msg":"trace[2032550446] transaction","detail":"{read_only:false; response_revision:15652; number_of_response:1; }","duration":"108.884744ms","start":"2026-09-23T11:07:02.664964Z","end":"2026-09-23T11:07:02.773849Z","steps":["trace[2032550446] 'process raft request' (duration: 93.037418ms)","trace[2032550446] 'compare' (duration: 15.671851ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:10:40.885305Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14869} {"level":"info","ts":"2026-09-23T11:10:40.993971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14869,"took":"105.804939ms","hash":2159913879,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":30375936,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T11:10:40.994046Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2159913879,"revision":14869,"compact-revision":12723} {"level":"info","ts":"2026-09-23T11:12:48.767554Z","caller":"traceutil/trace.go:171","msg":"trace[195196597] transaction","detail":"{read_only:false; response_revision:18233; number_of_response:1; }","duration":"159.078254ms","start":"2026-09-23T11:12:48.608449Z","end":"2026-09-23T11:12:48.767527Z","steps":["trace[195196597] 'process raft request' (duration: 158.93631ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:12:49.091571Z","caller":"traceutil/trace.go:171","msg":"trace[1756125586] linearizableReadLoop","detail":"{readStateIndex:18864; appliedIndex:18863; }","duration":"122.158519ms","start":"2026-09-23T11:12:48.969371Z","end":"2026-09-23T11:12:49.091529Z","steps":["trace[1756125586] 'read index received' (duration: 43.826155ms)","trace[1756125586] 'applied index is now lower than readState.Index' (duration: 78.330974ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:12:49.091655Z","caller":"traceutil/trace.go:171","msg":"trace[403613799] transaction","detail":"{read_only:false; response_revision:18236; number_of_response:1; }","duration":"130.780397ms","start":"2026-09-23T11:12:48.960827Z","end":"2026-09-23T11:12:49.091607Z","steps":["trace[403613799] 'process raft request' (duration: 52.36582ms)","trace[403613799] 'compare' (duration: 78.041926ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:12:49.091849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.467097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-23T11:12:49.091927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.570413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-dg2h6\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-09-23T11:12:49.09197Z","caller":"traceutil/trace.go:171","msg":"trace[1827769073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18236; }","duration":"122.56604ms","start":"2026-09-23T11:12:48.96935Z","end":"2026-09-23T11:12:49.091916Z","steps":["trace[1827769073] 'agreement among raft nodes before linearized reading' (duration: 122.319103ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:12:49.091985Z","caller":"traceutil/trace.go:171","msg":"trace[719827377] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-dg2h6; range_end:; response_count:1; response_revision:18236; }","duration":"121.717187ms","start":"2026-09-23T11:12:48.970256Z","end":"2026-09-23T11:12:49.091973Z","steps":["trace[719827377] 'agreement among raft nodes before linearized reading' (duration: 121.457209ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:14:40.992499Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9e5e76b85fbfd435","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:14:41.000376Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-23T11:14:41.000556Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-23T11:15:40.892443Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17343} {"level":"info","ts":"2026-09-23T11:15:41.00024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17343,"took":"102.608701ms","hash":2411787965,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":34787328,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-23T11:15:41.000317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2411787965,"revision":17343,"compact-revision":14869} {"level":"info","ts":"2026-09-23T11:18:29.494692Z","caller":"traceutil/trace.go:171","msg":"trace[1837836674] transaction","detail":"{read_only:false; response_revision:20885; number_of_response:1; }","duration":"151.746912ms","start":"2026-09-23T11:18:29.342916Z","end":"2026-09-23T11:18:29.494663Z","steps":["trace[1837836674] 'process raft request' (duration: 151.505296ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:18:29.495052Z","caller":"traceutil/trace.go:171","msg":"trace[1603069682] transaction","detail":"{read_only:false; response_revision:20886; number_of_response:1; }","duration":"148.874084ms","start":"2026-09-23T11:18:29.346135Z","end":"2026-09-23T11:18:29.495009Z","steps":["trace[1603069682] 'process raft request' (duration: 148.475043ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:18:29.495077Z","caller":"traceutil/trace.go:171","msg":"trace[1854095883] transaction","detail":"{read_only:false; response_revision:20887; number_of_response:1; }","duration":"146.770956ms","start":"2026-09-23T11:18:29.348264Z","end":"2026-09-23T11:18:29.495035Z","steps":["trace[1854095883] 'process raft request' (duration: 146.55671ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:20:40.9034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19744} {"level":"info","ts":"2026-09-23T11:20:41.029785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19744,"took":"123.203237ms","hash":221910949,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":30572544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T11:20:41.029849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":221910949,"revision":19744,"compact-revision":17343} {"level":"info","ts":"2026-09-23T11:22:23.145733Z","caller":"traceutil/trace.go:171","msg":"trace[750958172] linearizableReadLoop","detail":"{readStateIndex:24139; appliedIndex:24138; }","duration":"119.357162ms","start":"2026-09-23T11:22:23.026339Z","end":"2026-09-23T11:22:23.145696Z","steps":["trace[750958172] 'read index received' (duration: 106.313733ms)","trace[750958172] 'applied index is now lower than readState.Index' (duration: 13.042379ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:22:23.145858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.513357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:22:23.145893Z","caller":"traceutil/trace.go:171","msg":"trace[1841214468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23348; }","duration":"119.607849ms","start":"2026-09-23T11:22:23.026276Z","end":"2026-09-23T11:22:23.145884Z","steps":["trace[1841214468] 'agreement among raft nodes before linearized reading' (duration: 119.530897ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:22:23.145984Z","caller":"traceutil/trace.go:171","msg":"trace[430106739] transaction","detail":"{read_only:false; response_revision:23348; number_of_response:1; }","duration":"264.0045ms","start":"2026-09-23T11:22:22.881942Z","end":"2026-09-23T11:22:23.145947Z","steps":["trace[430106739] 'process raft request' (duration: 250.779806ms)","trace[430106739] 'compare' (duration: 12.482363ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:25:21.162673Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000653e.wal"} {"level":"info","ts":"2026-09-23T11:25:40.910768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21979} {"level":"info","ts":"2026-09-23T11:25:41.027607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21979,"took":"112.593326ms","hash":508110509,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":44724224,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-23T11:25:41.027669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":508110509,"revision":21979,"compact-revision":19744} {"level":"info","ts":"2026-09-23T11:26:00.841045Z","caller":"traceutil/trace.go:171","msg":"trace[1132082763] linearizableReadLoop","detail":"{readStateIndex:26368; appliedIndex:26367; }","duration":"169.431879ms","start":"2026-09-23T11:26:00.67159Z","end":"2026-09-23T11:26:00.841022Z","steps":["trace[1132082763] 'read index received' (duration: 169.271215ms)","trace[1132082763] 'applied index is now lower than readState.Index' (duration: 159.504µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:00.841225Z","caller":"traceutil/trace.go:171","msg":"trace[1267756560] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"193.240623ms","start":"2026-09-23T11:26:00.647974Z","end":"2026-09-23T11:26:00.841215Z","steps":["trace[1267756560] 'process raft request' (duration: 192.901264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:00.841454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.86065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-09-23T11:26:00.841493Z","caller":"traceutil/trace.go:171","msg":"trace[705430378] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25519; }","duration":"169.910502ms","start":"2026-09-23T11:26:00.671571Z","end":"2026-09-23T11:26:00.841482Z","steps":["trace[705430378] 'agreement among raft nodes before linearized reading' (duration: 169.767828ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:01.34752Z","caller":"traceutil/trace.go:171","msg":"trace[1480295833] linearizableReadLoop","detail":"{readStateIndex:26369; appliedIndex:26368; }","duration":"394.485928ms","start":"2026-09-23T11:26:00.953004Z","end":"2026-09-23T11:26:01.347489Z","steps":["trace[1480295833] 'read index received' (duration: 394.324133ms)","trace[1480295833] 'applied index is now lower than readState.Index' (duration: 160.114µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:01.347887Z","caller":"traceutil/trace.go:171","msg":"trace[1610810342] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"411.844875ms","start":"2026-09-23T11:26:00.936029Z","end":"2026-09-23T11:26:01.347874Z","steps":["trace[1610810342] 'process raft request' (duration: 411.261939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.348033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:00.936005Z","time spent":"411.926537ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:01.348302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.297219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:01.348349Z","caller":"traceutil/trace.go:171","msg":"trace[821983478] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25520; }","duration":"395.376222ms","start":"2026-09-23T11:26:00.952959Z","end":"2026-09-23T11:26:01.348335Z","steps":["trace[821983478] 'agreement among raft nodes before linearized reading' (duration: 395.294699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.34838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:00.952941Z","time spent":"395.430814ms","remote":"127.0.0.1:54604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true "} {"level":"info","ts":"2026-09-23T11:26:01.440117Z","caller":"traceutil/trace.go:171","msg":"trace[1660858027] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"109.154272ms","start":"2026-09-23T11:26:01.330908Z","end":"2026-09-23T11:26:01.440062Z","steps":["trace[1660858027] 'process raft request' (duration: 109.08735ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:01.440679Z","caller":"traceutil/trace.go:171","msg":"trace[1773593216] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"342.100487ms","start":"2026-09-23T11:26:01.098562Z","end":"2026-09-23T11:26:01.440663Z","steps":["trace[1773593216] 'process raft request' (duration: 341.205123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.440829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.09853Z","time spent":"342.194539ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:01.441028Z","caller":"traceutil/trace.go:171","msg":"trace[1786410449] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"120.202735ms","start":"2026-09-23T11:26:01.320811Z","end":"2026-09-23T11:26:01.441013Z","steps":["trace[1786410449] 'process raft request' (duration: 119.130966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.441934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.64331ms","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-09-23T11:26:01.441983Z","caller":"traceutil/trace.go:171","msg":"trace[1471698428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25523; }","duration":"103.755293ms","start":"2026-09-23T11:26:01.338213Z","end":"2026-09-23T11:26:01.441968Z","steps":["trace[1471698428] 'agreement among raft nodes before linearized reading' (duration: 103.581319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.442137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.553795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-23T11:26:01.442239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.045347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:01.442255Z","caller":"traceutil/trace.go:171","msg":"trace[949914598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25523; }","duration":"276.74348ms","start":"2026-09-23T11:26:01.165486Z","end":"2026-09-23T11:26:01.44223Z","steps":["trace[949914598] 'agreement among raft nodes before linearized reading' (duration: 276.360449ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:01.442287Z","caller":"traceutil/trace.go:171","msg":"trace[196650140] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25523; }","duration":"417.142941ms","start":"2026-09-23T11:26:01.02513Z","end":"2026-09-23T11:26:01.442273Z","steps":["trace[196650140] 'agreement among raft nodes before linearized reading' (duration: 417.056228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.442325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.025103Z","time spent":"417.211132ms","remote":"127.0.0.1:39180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:26:01.442594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.213965ms","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-09-23T11:26:01.442636Z","caller":"traceutil/trace.go:171","msg":"trace[1253172880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25523; }","duration":"276.274036ms","start":"2026-09-23T11:26:01.166349Z","end":"2026-09-23T11:26:01.442623Z","steps":["trace[1253172880] 'agreement among raft nodes before linearized reading' (duration: 276.163863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.442599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.807061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-09-23T11:26:01.442944Z","caller":"traceutil/trace.go:171","msg":"trace[183724769] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25523; }","duration":"489.14077ms","start":"2026-09-23T11:26:00.953762Z","end":"2026-09-23T11:26:01.442903Z","steps":["trace[183724769] 'agreement among raft nodes before linearized reading' (duration: 488.742189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.443004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:00.953749Z","time spent":"489.235512ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-09-23T11:26:01.63868Z","caller":"traceutil/trace.go:171","msg":"trace[42351232] linearizableReadLoop","detail":"{readStateIndex:26373; appliedIndex:26372; }","duration":"196.867382ms","start":"2026-09-23T11:26:01.441762Z","end":"2026-09-23T11:26:01.63863Z","steps":["trace[42351232] 'read index received' (duration: 113.931501ms)","trace[42351232] 'applied index is now lower than readState.Index' (duration: 82.934061ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:01.638843Z","caller":"traceutil/trace.go:171","msg":"trace[1666600137] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"275.302558ms","start":"2026-09-23T11:26:01.363501Z","end":"2026-09-23T11:26:01.638804Z","steps":["trace[1666600137] 'process raft request' (duration: 192.226574ms)","trace[1666600137] 'compare' (duration: 82.669773ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:01.638933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.233654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:01.63904Z","caller":"traceutil/trace.go:171","msg":"trace[1828997997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25524; }","duration":"260.4398ms","start":"2026-09-23T11:26:01.378578Z","end":"2026-09-23T11:26:01.639018Z","steps":["trace[1828997997] 'agreement among raft nodes before linearized reading' (duration: 260.235605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.972362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.345603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:01.972545Z","caller":"traceutil/trace.go:171","msg":"trace[1896942508] linearizableReadLoop","detail":"{readStateIndex:26377; appliedIndex:26373; }","duration":"333.742577ms","start":"2026-09-23T11:26:01.638789Z","end":"2026-09-23T11:26:01.972532Z","steps":["trace[1896942508] 'read index received' (duration: 127.174777ms)","trace[1896942508] 'applied index is now lower than readState.Index' (duration: 206.56684ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:01.972732Z","caller":"traceutil/trace.go:171","msg":"trace[1502373255] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"524.997295ms","start":"2026-09-23T11:26:01.447726Z","end":"2026-09-23T11:26:01.972723Z","steps":["trace[1502373255] 'process raft request' (duration: 318.21973ms)","trace[1502373255] 'compare' (duration: 205.709936ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:01.972783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.447688Z","time spent":"525.071217ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:01.972919Z","caller":"traceutil/trace.go:171","msg":"trace[1624997613] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"523.591307ms","start":"2026-09-23T11:26:01.449321Z","end":"2026-09-23T11:26:01.972912Z","steps":["trace[1624997613] 'process raft request' (duration: 523.112963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.972955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.449281Z","time spent":"523.657188ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:01.973041Z","caller":"traceutil/trace.go:171","msg":"trace[713733135] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"523.261587ms","start":"2026-09-23T11:26:01.449773Z","end":"2026-09-23T11:26:01.973035Z","steps":["trace[713733135] 'process raft request' (duration: 522.697031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.973094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.449758Z","time spent":"523.304019ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:01.973169Z","caller":"traceutil/trace.go:171","msg":"trace[2139529395] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"392.673057ms","start":"2026-09-23T11:26:01.580491Z","end":"2026-09-23T11:26:01.973164Z","steps":["trace[2139529395] 'process raft request' (duration: 392.013469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.973205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.58046Z","time spent":"392.726949ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:01.973359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.245936ms","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-09-23T11:26:01.973393Z","caller":"traceutil/trace.go:171","msg":"trace[1163168430] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25528; }","duration":"420.367169ms","start":"2026-09-23T11:26:01.553016Z","end":"2026-09-23T11:26:01.973383Z","steps":["trace[1163168430] 'agreement among raft nodes before linearized reading' (duration: 420.260666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.973412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.552956Z","time spent":"420.451601ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-23T11:26:01.973559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.302593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:26:01.973585Z","caller":"traceutil/trace.go:171","msg":"trace[617662097] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:25528; }","duration":"485.394716ms","start":"2026-09-23T11:26:01.488183Z","end":"2026-09-23T11:26:01.973578Z","steps":["trace[617662097] 'agreement among raft nodes before linearized reading' (duration: 485.329874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.973606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.488123Z","time spent":"485.474958ms","remote":"127.0.0.1:54172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":9,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:26:01.973762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.701946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-23T11:26:01.973784Z","caller":"traceutil/trace.go:171","msg":"trace[1217060318] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25528; }","duration":"301.772947ms","start":"2026-09-23T11:26:01.672006Z","end":"2026-09-23T11:26:01.973779Z","steps":["trace[1217060318] 'agreement among raft nodes before linearized reading' (duration: 301.706956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:01.973802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:01.671976Z","time spent":"301.821269ms","remote":"127.0.0.1:43494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"warn","ts":"2026-09-23T11:26:01.974132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.989026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:26:01.974158Z","caller":"traceutil/trace.go:171","msg":"trace[392332750] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25528; }","duration":"245.09869ms","start":"2026-09-23T11:26:01.729054Z","end":"2026-09-23T11:26:01.974153Z","steps":["trace[392332750] 'agreement among raft nodes before linearized reading' (duration: 244.977136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:02.257163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.446742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:02.257595Z","caller":"traceutil/trace.go:171","msg":"trace[866093810] transaction","detail":"{read_only:false; response_revision:25530; number_of_response:1; }","duration":"219.410174ms","start":"2026-09-23T11:26:02.038153Z","end":"2026-09-23T11:26:02.257563Z","steps":["trace[866093810] 'process raft request' (duration: 110.448508ms)","trace[866093810] 'compare' (duration: 104.075411ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:02.257977Z","caller":"traceutil/trace.go:171","msg":"trace[1673128275] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"152.444612ms","start":"2026-09-23T11:26:02.105522Z","end":"2026-09-23T11:26:02.257966Z","steps":["trace[1673128275] 'process raft request' (duration: 151.756803ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:07.500231Z","caller":"traceutil/trace.go:171","msg":"trace[631873239] transaction","detail":"{read_only:false; response_revision:25564; number_of_response:1; }","duration":"131.889587ms","start":"2026-09-23T11:26:07.368318Z","end":"2026-09-23T11:26:07.500207Z","steps":["trace[631873239] 'process raft request' (duration: 131.716172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:07.694838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.042849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:07.695267Z","caller":"traceutil/trace.go:171","msg":"trace[1549255483] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"230.014095ms","start":"2026-09-23T11:26:07.465223Z","end":"2026-09-23T11:26:07.695237Z","steps":["trace[1549255483] 'process raft request' (duration: 229.757668ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:07.695281Z","caller":"traceutil/trace.go:171","msg":"trace[1573981367] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"233.153491ms","start":"2026-09-23T11:26:07.462087Z","end":"2026-09-23T11:26:07.69524Z","steps":["trace[1573981367] 'process raft request' (duration: 111.572288ms)","trace[1573981367] 'compare' (duration: 120.738259ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:07.695356Z","caller":"traceutil/trace.go:171","msg":"trace[1222621201] linearizableReadLoop","detail":"{readStateIndex:26417; appliedIndex:26413; }","duration":"215.179197ms","start":"2026-09-23T11:26:07.480136Z","end":"2026-09-23T11:26:07.695315Z","steps":["trace[1222621201] 'read index received' (duration: 19.945828ms)","trace[1222621201] 'applied index is now lower than readState.Index' (duration: 195.227539ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:07.695379Z","caller":"traceutil/trace.go:171","msg":"trace[1956769924] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"226.065946ms","start":"2026-09-23T11:26:07.469251Z","end":"2026-09-23T11:26:07.695317Z","steps":["trace[1956769924] 'process raft request' (duration: 225.791468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:07.695544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.380962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:07.695683Z","caller":"traceutil/trace.go:171","msg":"trace[84082318] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25568; }","duration":"215.592378ms","start":"2026-09-23T11:26:07.480056Z","end":"2026-09-23T11:26:07.695649Z","steps":["trace[84082318] 'agreement among raft nodes before linearized reading' (duration: 215.374742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:07.69561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.324243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:07.695871Z","caller":"traceutil/trace.go:171","msg":"trace[1211253893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25568; }","duration":"146.665322ms","start":"2026-09-23T11:26:07.549187Z","end":"2026-09-23T11:26:07.695852Z","steps":["trace[1211253893] 'agreement among raft nodes before linearized reading' (duration: 146.356784ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:08.250944Z","caller":"traceutil/trace.go:171","msg":"trace[488027280] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"245.138861ms","start":"2026-09-23T11:26:08.005772Z","end":"2026-09-23T11:26:08.250911Z","steps":["trace[488027280] 'process raft request' (duration: 244.941176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:08.547627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.037583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:08.547775Z","caller":"traceutil/trace.go:171","msg":"trace[15158619] linearizableReadLoop","detail":"{readStateIndex:26420; appliedIndex:26418; }","duration":"529.799368ms","start":"2026-09-23T11:26:08.017922Z","end":"2026-09-23T11:26:08.547721Z","steps":["trace[15158619] 'read index received' (duration: 232.760141ms)","trace[15158619] 'applied index is now lower than readState.Index' (duration: 297.037687ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:08.547851Z","caller":"traceutil/trace.go:171","msg":"trace[66173061] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"532.125362ms","start":"2026-09-23T11:26:08.015713Z","end":"2026-09-23T11:26:08.547838Z","steps":["trace[66173061] 'process raft request' (duration: 336.77943ms)","trace[66173061] 'compare' (duration: 194.752395ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:08.547918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:08.015687Z","time spent":"532.196234ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:08.54811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.147358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:26:08.548601Z","caller":"traceutil/trace.go:171","msg":"trace[45033915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25570; }","duration":"530.685402ms","start":"2026-09-23T11:26:08.017891Z","end":"2026-09-23T11:26:08.548576Z","steps":["trace[45033915] 'agreement among raft nodes before linearized reading' (duration: 529.963662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:08.548646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:08.017872Z","time spent":"530.760944ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-23T11:26:08.548866Z","caller":"traceutil/trace.go:171","msg":"trace[390919464] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"268.663618ms","start":"2026-09-23T11:26:08.280171Z","end":"2026-09-23T11:26:08.548834Z","steps":["trace[390919464] 'process raft request' (duration: 268.37067ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:08.549046Z","caller":"traceutil/trace.go:171","msg":"trace[781143987] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"220.0255ms","start":"2026-09-23T11:26:08.329Z","end":"2026-09-23T11:26:08.549025Z","steps":["trace[781143987] 'process raft request' (duration: 219.764873ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:08.789258Z","caller":"traceutil/trace.go:171","msg":"trace[689533698] linearizableReadLoop","detail":"{readStateIndex:26423; appliedIndex:26420; }","duration":"241.430417ms","start":"2026-09-23T11:26:08.547801Z","end":"2026-09-23T11:26:08.789232Z","steps":["trace[689533698] 'read index received' (duration: 805.702µs)","trace[689533698] 'applied index is now lower than readState.Index' (duration: 240.623095ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:08.789473Z","caller":"traceutil/trace.go:171","msg":"trace[2061895610] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"415.576246ms","start":"2026-09-23T11:26:08.373872Z","end":"2026-09-23T11:26:08.789448Z","steps":["trace[2061895610] 'process raft request' (duration: 415.056982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:08.78956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.346185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:26:08.789613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:08.37384Z","time spent":"415.680979ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:08.78968Z","caller":"traceutil/trace.go:171","msg":"trace[900585539] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25573; }","duration":"465.52392ms","start":"2026-09-23T11:26:08.324124Z","end":"2026-09-23T11:26:08.789648Z","steps":["trace[900585539] 'agreement among raft nodes before linearized reading' (duration: 465.258462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:08.789732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:08.324044Z","time spent":"465.676604ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-09-23T11:26:08.78971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.672931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:26:08.78992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.154239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-23T11:26:08.789987Z","caller":"traceutil/trace.go:171","msg":"trace[766654669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25573; }","duration":"762.952448ms","start":"2026-09-23T11:26:08.027011Z","end":"2026-09-23T11:26:08.789964Z","steps":["trace[766654669] 'agreement among raft nodes before linearized reading' (duration: 762.63406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:08.79004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:08.026997Z","time spent":"763.0331ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T11:26:08.790212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.094738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-23T11:26:08.79021Z","caller":"traceutil/trace.go:171","msg":"trace[185169441] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"236.358599ms","start":"2026-09-23T11:26:08.5538Z","end":"2026-09-23T11:26:08.790158Z","steps":["trace[185169441] 'process raft request' (duration: 236.105642ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:08.790264Z","caller":"traceutil/trace.go:171","msg":"trace[1350008070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25573; }","duration":"501.219161ms","start":"2026-09-23T11:26:08.289028Z","end":"2026-09-23T11:26:08.790247Z","steps":["trace[1350008070] 'agreement among raft nodes before linearized reading' (duration: 500.342487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:08.790301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:08.288995Z","time spent":"501.299213ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-23T11:26:08.790058Z","caller":"traceutil/trace.go:171","msg":"trace[374412463] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25573; }","duration":"733.507599ms","start":"2026-09-23T11:26:08.0565Z","end":"2026-09-23T11:26:08.790008Z","steps":["trace[374412463] 'agreement among raft nodes before linearized reading' (duration: 732.995294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:08.790345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:08.056457Z","time spent":"733.874348ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-23T11:26:09.098795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.174745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:09.099158Z","caller":"traceutil/trace.go:171","msg":"trace[1057190035] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"212.808382ms","start":"2026-09-23T11:26:08.886198Z","end":"2026-09-23T11:26:09.099006Z","steps":["trace[1057190035] 'process raft request' (duration: 112.265338ms)","trace[1057190035] 'compare' (duration: 99.962788ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:09.573862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.537352ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-23T11:26:09.850724Z","caller":"traceutil/trace.go:171","msg":"trace[102053790] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"368.356679ms","start":"2026-09-23T11:26:09.482342Z","end":"2026-09-23T11:26:09.850699Z","steps":["trace[102053790] 'process raft request' (duration: 368.171754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:09.850875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:09.482317Z","time spent":"368.478992ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:10.061868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.535229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:10.062104Z","caller":"traceutil/trace.go:171","msg":"trace[77418363] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"555.904536ms","start":"2026-09-23T11:26:09.506149Z","end":"2026-09-23T11:26:10.062054Z","steps":["trace[77418363] 'process raft request' (duration: 421.074859ms)","trace[77418363] 'compare' (duration: 134.19612ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:10.062243Z","caller":"traceutil/trace.go:171","msg":"trace[1238106338] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"359.67468ms","start":"2026-09-23T11:26:09.702549Z","end":"2026-09-23T11:26:10.062224Z","steps":["trace[1238106338] 'process raft request' (duration: 359.625778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:10.062257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:09.50612Z","time spent":"556.04507ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:10.062257Z","caller":"traceutil/trace.go:171","msg":"trace[337684083] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"361.017077ms","start":"2026-09-23T11:26:09.701208Z","end":"2026-09-23T11:26:10.062225Z","steps":["trace[337684083] 'process raft request' (duration: 360.76397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:10.06232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:09.702533Z","time spent":"359.750192ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:10.062404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:09.70118Z","time spent":"361.13692ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:10.062438Z","caller":"traceutil/trace.go:171","msg":"trace[1340270482] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"360.702778ms","start":"2026-09-23T11:26:09.701725Z","end":"2026-09-23T11:26:10.062428Z","steps":["trace[1340270482] 'process raft request' (duration: 360.375239ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:10.06245Z","caller":"traceutil/trace.go:171","msg":"trace[400902450] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"360.014959ms","start":"2026-09-23T11:26:09.702423Z","end":"2026-09-23T11:26:10.062438Z","steps":["trace[400902450] 'process raft request' (duration: 359.718951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:10.062507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:09.702386Z","time spent":"360.091092ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:10.06251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:09.7017Z","time spent":"360.762ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:10.12188Z","caller":"traceutil/trace.go:171","msg":"trace[1789688140] transaction","detail":"{read_only:false; response_revision:25584; number_of_response:1; }","duration":"269.48413ms","start":"2026-09-23T11:26:09.852371Z","end":"2026-09-23T11:26:10.121855Z","steps":["trace[1789688140] 'process raft request' (duration: 269.315656ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:10.510845Z","caller":"traceutil/trace.go:171","msg":"trace[305964118] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"152.481032ms","start":"2026-09-23T11:26:10.358331Z","end":"2026-09-23T11:26:10.510812Z","steps":["trace[305964118] 'process raft request' (duration: 152.264946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:10.688834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.17654ms","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-09-23T11:26:10.688968Z","caller":"traceutil/trace.go:171","msg":"trace[2104934207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25586; }","duration":"134.405045ms","start":"2026-09-23T11:26:10.554535Z","end":"2026-09-23T11:26:10.68894Z","steps":["trace[2104934207] 'range keys from in-memory index tree' (duration: 133.84071ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:10.688848Z","caller":"traceutil/trace.go:171","msg":"trace[6530869] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"133.157941ms","start":"2026-09-23T11:26:10.555663Z","end":"2026-09-23T11:26:10.688821Z","steps":["trace[6530869] 'process raft request' (duration: 125.376507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:11.05396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.478587ms","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-09-23T11:26:11.054094Z","caller":"traceutil/trace.go:171","msg":"trace[723508450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25588; }","duration":"258.637642ms","start":"2026-09-23T11:26:10.795406Z","end":"2026-09-23T11:26:11.054044Z","steps":["trace[723508450] 'range keys from in-memory index tree' (duration: 258.277682ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:11.085269Z","caller":"traceutil/trace.go:171","msg":"trace[2130231967] linearizableReadLoop","detail":"{readStateIndex:26440; appliedIndex:26439; }","duration":"258.502598ms","start":"2026-09-23T11:26:10.82673Z","end":"2026-09-23T11:26:11.085232Z","steps":["trace[2130231967] 'read index received' (duration: 258.161209ms)","trace[2130231967] 'applied index is now lower than readState.Index' (duration: 339.789µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:11.085408Z","caller":"traceutil/trace.go:171","msg":"trace[1433459165] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"288.703489ms","start":"2026-09-23T11:26:10.796669Z","end":"2026-09-23T11:26:11.085372Z","steps":["trace[1433459165] 'process raft request' (duration: 288.271407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:11.085667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.900389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-23T11:26:11.085754Z","caller":"traceutil/trace.go:171","msg":"trace[798960822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25589; }","duration":"259.015642ms","start":"2026-09-23T11:26:10.82672Z","end":"2026-09-23T11:26:11.085736Z","steps":["trace[798960822] 'agreement among raft nodes before linearized reading' (duration: 258.710444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:11.43167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.918282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:11.431939Z","caller":"traceutil/trace.go:171","msg":"trace[1041761663] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"338.58317ms","start":"2026-09-23T11:26:11.093336Z","end":"2026-09-23T11:26:11.43192Z","steps":["trace[1041761663] 'process raft request' (duration: 338.470777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:11.432013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:11.093304Z","time spent":"338.677643ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:11.432262Z","caller":"traceutil/trace.go:171","msg":"trace[2078568339] transaction","detail":"{read_only:false; response_revision:25590; number_of_response:1; }","duration":"372.678117ms","start":"2026-09-23T11:26:11.05957Z","end":"2026-09-23T11:26:11.432248Z","steps":["trace[2078568339] 'process raft request' (duration: 102.073756ms)","trace[2078568339] 'compare' (duration: 269.613394ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:11.432317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:11.059512Z","time spent":"372.77649ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:11.432449Z","caller":"traceutil/trace.go:171","msg":"trace[1805646142] linearizableReadLoop","detail":"{readStateIndex:26441; appliedIndex:26440; }","duration":"347.041312ms","start":"2026-09-23T11:26:11.085396Z","end":"2026-09-23T11:26:11.432437Z","steps":["trace[1805646142] 'read index received' (duration: 76.268107ms)","trace[1805646142] 'applied index is now lower than readState.Index' (duration: 270.771985ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:11.432733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.406237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:11.432775Z","caller":"traceutil/trace.go:171","msg":"trace[851870767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25591; }","duration":"405.483239ms","start":"2026-09-23T11:26:11.027277Z","end":"2026-09-23T11:26:11.43276Z","steps":["trace[851870767] 'agreement among raft nodes before linearized reading' (duration: 405.419628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:11.432802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:11.027251Z","time spent":"405.545251ms","remote":"127.0.0.1:43356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T11:26:11.433195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.179398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-23T11:26:11.433288Z","caller":"traceutil/trace.go:171","msg":"trace[1377543993] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25591; }","duration":"602.308782ms","start":"2026-09-23T11:26:10.830951Z","end":"2026-09-23T11:26:11.43326Z","steps":["trace[1377543993] 'agreement among raft nodes before linearized reading' (duration: 601.828499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:11.433276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.663254ms","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-09-23T11:26:11.433339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:10.830939Z","time spent":"602.391594ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-23T11:26:11.433359Z","caller":"traceutil/trace.go:171","msg":"trace[1053313414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25591; }","duration":"329.802289ms","start":"2026-09-23T11:26:11.103531Z","end":"2026-09-23T11:26:11.433333Z","steps":["trace[1053313414] 'agreement among raft nodes before linearized reading' (duration: 329.337876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:11.433408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:11.103506Z","time spent":"329.892981ms","remote":"127.0.0.1:43602","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":"info","ts":"2026-09-23T11:26:11.615241Z","caller":"traceutil/trace.go:171","msg":"trace[791995757] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"175.931958ms","start":"2026-09-23T11:26:11.439274Z","end":"2026-09-23T11:26:11.615206Z","steps":["trace[791995757] 'process raft request' (duration: 145.621195ms)","trace[791995757] 'compare' (duration: 30.096787ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:11.667714Z","caller":"traceutil/trace.go:171","msg":"trace[2053525009] transaction","detail":"{read_only:false; response_revision:25594; number_of_response:1; }","duration":"227.934697ms","start":"2026-09-23T11:26:11.43976Z","end":"2026-09-23T11:26:11.667695Z","steps":["trace[2053525009] 'process raft request' (duration: 227.903016ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:11.667793Z","caller":"traceutil/trace.go:171","msg":"trace[981712592] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"228.04307ms","start":"2026-09-23T11:26:11.439722Z","end":"2026-09-23T11:26:11.667765Z","steps":["trace[981712592] 'process raft request' (duration: 227.809454ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:24.430781Z","caller":"traceutil/trace.go:171","msg":"trace[230153608] linearizableReadLoop","detail":"{readStateIndex:26594; appliedIndex:26593; }","duration":"212.719679ms","start":"2026-09-23T11:26:24.218036Z","end":"2026-09-23T11:26:24.430756Z","steps":["trace[230153608] 'read index received' (duration: 212.146424ms)","trace[230153608] 'applied index is now lower than readState.Index' (duration: 572.125µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:24.430859Z","caller":"traceutil/trace.go:171","msg":"trace[1749727390] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"221.334706ms","start":"2026-09-23T11:26:24.209493Z","end":"2026-09-23T11:26:24.430828Z","steps":["trace[1749727390] 'process raft request' (duration: 220.685529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:24.431028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.954506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.127\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-23T11:26:24.431111Z","caller":"traceutil/trace.go:171","msg":"trace[458718763] range","detail":"{range_begin:/registry/masterleases/162.253.55.127; range_end:; response_count:1; response_revision:25740; }","duration":"213.09331ms","start":"2026-09-23T11:26:24.218002Z","end":"2026-09-23T11:26:24.431096Z","steps":["trace[458718763] 'agreement among raft nodes before linearized reading' (duration: 212.866794ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:24.579107Z","caller":"traceutil/trace.go:171","msg":"trace[559232247] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"211.671011ms","start":"2026-09-23T11:26:24.367392Z","end":"2026-09-23T11:26:24.579063Z","steps":["trace[559232247] 'process raft request' (duration: 209.527302ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:24.579305Z","caller":"traceutil/trace.go:171","msg":"trace[1949495257] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26594; }","duration":"148.451852ms","start":"2026-09-23T11:26:24.430843Z","end":"2026-09-23T11:26:24.579295Z","steps":["trace[1949495257] 'read index received' (duration: 145.994894ms)","trace[1949495257] 'applied index is now lower than readState.Index' (duration: 2.455848ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:24.579388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.053326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:24.579424Z","caller":"traceutil/trace.go:171","msg":"trace[581925331] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25741; }","duration":"174.152529ms","start":"2026-09-23T11:26:24.40526Z","end":"2026-09-23T11:26:24.579413Z","steps":["trace[581925331] 'agreement among raft nodes before linearized reading' (duration: 174.075217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:25.023224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.921792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:25.023354Z","caller":"traceutil/trace.go:171","msg":"trace[1248538389] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"431.644719ms","start":"2026-09-23T11:26:24.591689Z","end":"2026-09-23T11:26:25.023334Z","steps":["trace[1248538389] 'process raft request' (duration: 297.50412ms)","trace[1248538389] 'compare' (duration: 133.655975ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:25.023419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:24.591668Z","time spent":"431.716331ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:25.089948Z","caller":"traceutil/trace.go:171","msg":"trace[307646531] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"340.569294ms","start":"2026-09-23T11:26:24.749348Z","end":"2026-09-23T11:26:25.089917Z","steps":["trace[307646531] 'process raft request' (duration: 340.380679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:25.090148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:24.749328Z","time spent":"340.699758ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:25.090179Z","caller":"traceutil/trace.go:171","msg":"trace[505230130] linearizableReadLoop","detail":"{readStateIndex:26599; appliedIndex:26597; }","duration":"284.912975ms","start":"2026-09-23T11:26:24.805231Z","end":"2026-09-23T11:26:25.090144Z","steps":["trace[505230130] 'read index received' (duration: 83.935218ms)","trace[505230130] 'applied index is now lower than readState.Index' (duration: 200.975806ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:25.090323Z","caller":"traceutil/trace.go:171","msg":"trace[353388460] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"225.494211ms","start":"2026-09-23T11:26:24.864799Z","end":"2026-09-23T11:26:25.090293Z","steps":["trace[353388460] 'process raft request' (duration: 225.077929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:25.090465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.218363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-09-23T11:26:25.09051Z","caller":"traceutil/trace.go:171","msg":"trace[1355069149] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350; range_end:; response_count:1; response_revision:25745; }","duration":"285.282234ms","start":"2026-09-23T11:26:24.805215Z","end":"2026-09-23T11:26:25.090497Z","steps":["trace[1355069149] 'agreement among raft nodes before linearized reading' (duration: 285.093739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:25.090584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.443924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-23T11:26:25.090645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.915559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:26:25.090681Z","caller":"traceutil/trace.go:171","msg":"trace[924623919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25745; }","duration":"259.615889ms","start":"2026-09-23T11:26:24.831039Z","end":"2026-09-23T11:26:25.090655Z","steps":["trace[924623919] 'agreement among raft nodes before linearized reading' (duration: 259.32057ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:25.090765Z","caller":"traceutil/trace.go:171","msg":"trace[1009005109] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25745; }","duration":"259.070483ms","start":"2026-09-23T11:26:24.831645Z","end":"2026-09-23T11:26:25.090715Z","steps":["trace[1009005109] 'agreement among raft nodes before linearized reading' (duration: 258.789235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:25.424291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.533737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:25.424427Z","caller":"traceutil/trace.go:171","msg":"trace[170337890] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"329.109809ms","start":"2026-09-23T11:26:25.095289Z","end":"2026-09-23T11:26:25.424398Z","steps":["trace[170337890] 'process raft request' (duration: 189.370997ms)","trace[170337890] 'compare' (duration: 139.007362ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:25.424511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.095271Z","time spent":"329.196771ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:25.808061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.03671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:25.808255Z","caller":"traceutil/trace.go:171","msg":"trace[452926242] linearizableReadLoop","detail":"{readStateIndex:26604; appliedIndex:26600; }","duration":"636.938524ms","start":"2026-09-23T11:26:25.171302Z","end":"2026-09-23T11:26:25.80824Z","steps":["trace[452926242] 'read index received' (duration: 113.376198ms)","trace[452926242] 'applied index is now lower than readState.Index' (duration: 523.561206ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:25.80845Z","caller":"traceutil/trace.go:171","msg":"trace[1260799424] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"664.552713ms","start":"2026-09-23T11:26:25.143859Z","end":"2026-09-23T11:26:25.808411Z","steps":["trace[1260799424] 'process raft request' (duration: 664.346487ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:25.808484Z","caller":"traceutil/trace.go:171","msg":"trace[175342063] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"710.333912ms","start":"2026-09-23T11:26:25.098113Z","end":"2026-09-23T11:26:25.808446Z","steps":["trace[175342063] 'process raft request' (duration: 477.842169ms)","trace[175342063] 'compare' (duration: 231.6659ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:25.808623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.143852Z","time spent":"664.686497ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:25.80862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.853728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-23T11:26:25.808647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.342115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350\" ","response":"range_response_count:1 size:3761"} {"level":"warn","ts":"2026-09-23T11:26:25.808653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.098049Z","time spent":"710.507897ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:25.808691Z","caller":"traceutil/trace.go:171","msg":"trace[873233680] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350; range_end:; response_count:1; response_revision:25749; }","duration":"637.420217ms","start":"2026-09-23T11:26:25.17126Z","end":"2026-09-23T11:26:25.80868Z","steps":["trace[873233680] 'agreement among raft nodes before linearized reading' (duration: 637.353455ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:25.80869Z","caller":"traceutil/trace.go:171","msg":"trace[105447022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25749; }","duration":"306.972471ms","start":"2026-09-23T11:26:25.501697Z","end":"2026-09-23T11:26:25.808669Z","steps":["trace[105447022] 'agreement among raft nodes before linearized reading' (duration: 306.772455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:25.80872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.171234Z","time spent":"637.479749ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350\" "} {"level":"warn","ts":"2026-09-23T11:26:25.808731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.501677Z","time spent":"307.045692ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-23T11:26:25.808465Z","caller":"traceutil/trace.go:171","msg":"trace[497380330] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"666.334643ms","start":"2026-09-23T11:26:25.142101Z","end":"2026-09-23T11:26:25.808435Z","steps":["trace[497380330] 'process raft request' (duration: 666.050795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:25.808824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.14208Z","time spent":"666.703382ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:25.80901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.38505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-09-23T11:26:25.809031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.38902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:26:25.809067Z","caller":"traceutil/trace.go:171","msg":"trace[1477344453] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25749; }","duration":"272.464702ms","start":"2026-09-23T11:26:25.536588Z","end":"2026-09-23T11:26:25.809053Z","steps":["trace[1477344453] 'agreement among raft nodes before linearized reading' (duration: 272.333409ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:25.809101Z","caller":"traceutil/trace.go:171","msg":"trace[795651323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25749; }","duration":"296.451112ms","start":"2026-09-23T11:26:25.512606Z","end":"2026-09-23T11:26:25.809057Z","steps":["trace[795651323] 'agreement among raft nodes before linearized reading' (duration: 296.302428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:26.16275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.165584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:26.162995Z","caller":"traceutil/trace.go:171","msg":"trace[591379984] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"347.908396ms","start":"2026-09-23T11:26:25.815062Z","end":"2026-09-23T11:26:26.162971Z","steps":["trace[591379984] 'process raft request' (duration: 231.432253ms)","trace[591379984] 'compare' (duration: 115.879347ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:26.163045Z","caller":"traceutil/trace.go:171","msg":"trace[585131573] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"346.58825ms","start":"2026-09-23T11:26:25.81644Z","end":"2026-09-23T11:26:26.163029Z","steps":["trace[585131573] 'process raft request' (duration: 346.501557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:26.163103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.815032Z","time spent":"348.001089ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:26.163135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.816425Z","time spent":"346.674173ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:26.163276Z","caller":"traceutil/trace.go:171","msg":"trace[492845574] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"348.201774ms","start":"2026-09-23T11:26:25.815064Z","end":"2026-09-23T11:26:26.163266Z","steps":["trace[492845574] 'process raft request' (duration: 347.785373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:26.163324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.815033Z","time spent":"348.265236ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:26.163374Z","caller":"traceutil/trace.go:171","msg":"trace[206836787] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"346.905159ms","start":"2026-09-23T11:26:25.816448Z","end":"2026-09-23T11:26:26.163353Z","steps":["trace[206836787] 'process raft request' (duration: 346.538889ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:26.163367Z","caller":"traceutil/trace.go:171","msg":"trace[756462515] linearizableReadLoop","detail":"{readStateIndex:26609; appliedIndex:26605; }","duration":"338.832497ms","start":"2026-09-23T11:26:25.824511Z","end":"2026-09-23T11:26:26.163344Z","steps":["trace[756462515] 'read index received' (duration: 222.000205ms)","trace[756462515] 'applied index is now lower than readState.Index' (duration: 116.830442ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:26.163471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.816431Z","time spent":"346.984271ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:26.164019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.482005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-09-23T11:26:26.164121Z","caller":"traceutil/trace.go:171","msg":"trace[1310959546] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350; range_end:; response_count:1; response_revision:25754; }","duration":"339.630749ms","start":"2026-09-23T11:26:25.824469Z","end":"2026-09-23T11:26:26.1641Z","steps":["trace[1310959546] 'agreement among raft nodes before linearized reading' (duration: 338.977811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:26.164164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:25.824455Z","time spent":"339.700581ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350\" "} {"level":"info","ts":"2026-09-23T11:26:26.410607Z","caller":"traceutil/trace.go:171","msg":"trace[1912417687] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"289.565422ms","start":"2026-09-23T11:26:26.121006Z","end":"2026-09-23T11:26:26.410571Z","steps":["trace[1912417687] 'process raft request' (duration: 194.273052ms)","trace[1912417687] 'compare' (duration: 95.135416ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:26.41112Z","caller":"traceutil/trace.go:171","msg":"trace[2089597181] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"288.545605ms","start":"2026-09-23T11:26:26.122549Z","end":"2026-09-23T11:26:26.411094Z","steps":["trace[2089597181] 'process raft request' (duration: 288.337479ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:26.411307Z","caller":"traceutil/trace.go:171","msg":"trace[75921076] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"287.128435ms","start":"2026-09-23T11:26:26.124164Z","end":"2026-09-23T11:26:26.411293Z","steps":["trace[75921076] 'process raft request' (duration: 286.869008ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:26.411626Z","caller":"traceutil/trace.go:171","msg":"trace[370600902] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"285.4875ms","start":"2026-09-23T11:26:26.126124Z","end":"2026-09-23T11:26:26.411611Z","steps":["trace[370600902] 'process raft request' (duration: 285.11932ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:26.411745Z","caller":"traceutil/trace.go:171","msg":"trace[524897693] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"282.532038ms","start":"2026-09-23T11:26:26.129202Z","end":"2026-09-23T11:26:26.411734Z","steps":["trace[524897693] 'process raft request' (duration: 282.340593ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:26.411761Z","caller":"traceutil/trace.go:171","msg":"trace[1965886280] linearizableReadLoop","detail":"{readStateIndex:26614; appliedIndex:26609; }","duration":"248.306547ms","start":"2026-09-23T11:26:26.163424Z","end":"2026-09-23T11:26:26.41173Z","steps":["trace[1965886280] 'read index received' (duration: 151.809174ms)","trace[1965886280] 'applied index is now lower than readState.Index' (duration: 96.450522ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:26.411939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.141063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:26.412013Z","caller":"traceutil/trace.go:171","msg":"trace[843352504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25759; }","duration":"384.261246ms","start":"2026-09-23T11:26:26.027729Z","end":"2026-09-23T11:26:26.411991Z","steps":["trace[843352504] 'agreement among raft nodes before linearized reading' (duration: 384.129083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:26.412096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:26.027708Z","time spent":"384.346328ms","remote":"127.0.0.1:43356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T11:26:26.624721Z","caller":"traceutil/trace.go:171","msg":"trace[1848533205] linearizableReadLoop","detail":"{readStateIndex:26615; appliedIndex:26614; }","duration":"111.917336ms","start":"2026-09-23T11:26:26.512778Z","end":"2026-09-23T11:26:26.624695Z","steps":["trace[1848533205] 'read index received' (duration: 79.720652ms)","trace[1848533205] 'applied index is now lower than readState.Index' (duration: 32.195224ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:26.624853Z","caller":"traceutil/trace.go:171","msg":"trace[496694199] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"188.006239ms","start":"2026-09-23T11:26:26.436833Z","end":"2026-09-23T11:26:26.624839Z","steps":["trace[496694199] 'process raft request' (duration: 155.113146ms)","trace[496694199] 'compare' (duration: 32.06002ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:26.625093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.262026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:26:26.625177Z","caller":"traceutil/trace.go:171","msg":"trace[1877603755] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25760; }","duration":"112.39541ms","start":"2026-09-23T11:26:26.512762Z","end":"2026-09-23T11:26:26.625158Z","steps":["trace[1877603755] 'agreement among raft nodes before linearized reading' (duration: 112.174034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:26.939359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.952831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:26.939489Z","caller":"traceutil/trace.go:171","msg":"trace[472141989] linearizableReadLoop","detail":"{readStateIndex:26616; appliedIndex:26615; }","duration":"295.192767ms","start":"2026-09-23T11:26:26.644271Z","end":"2026-09-23T11:26:26.939464Z","steps":["trace[472141989] 'read index received' (duration: 136.048441ms)","trace[472141989] 'applied index is now lower than readState.Index' (duration: 159.142426ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:26.939582Z","caller":"traceutil/trace.go:171","msg":"trace[779136138] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"308.711899ms","start":"2026-09-23T11:26:26.630843Z","end":"2026-09-23T11:26:26.939555Z","steps":["trace[779136138] 'process raft request' (duration: 149.46635ms)","trace[779136138] 'compare' (duration: 158.447827ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:26.9397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:26.630818Z","time spent":"308.835833ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:26.93973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.460204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:26:26.939766Z","caller":"traceutil/trace.go:171","msg":"trace[889638963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25761; }","duration":"295.530046ms","start":"2026-09-23T11:26:26.644223Z","end":"2026-09-23T11:26:26.939753Z","steps":["trace[889638963] 'agreement among raft nodes before linearized reading' (duration: 295.360692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:26.939867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.660945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-09-23T11:26:26.939964Z","caller":"traceutil/trace.go:171","msg":"trace[1473102207] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fe630cbc-4be5-4ff8-9508-9c86a51be350; range_end:; response_count:1; response_revision:25761; }","duration":"125.811269ms","start":"2026-09-23T11:26:26.814128Z","end":"2026-09-23T11:26:26.939939Z","steps":["trace[1473102207] 'agreement among raft nodes before linearized reading' (duration: 125.537062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:27.271501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.812246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:27.271628Z","caller":"traceutil/trace.go:171","msg":"trace[1713105496] linearizableReadLoop","detail":"{readStateIndex:26617; appliedIndex:26616; }","duration":"288.127573ms","start":"2026-09-23T11:26:26.983468Z","end":"2026-09-23T11:26:27.271596Z","steps":["trace[1713105496] 'read index received' (duration: 100.979497ms)","trace[1713105496] 'applied index is now lower than readState.Index' (duration: 187.146476ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:27.271715Z","caller":"traceutil/trace.go:171","msg":"trace[2015198781] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"327.211668ms","start":"2026-09-23T11:26:26.944485Z","end":"2026-09-23T11:26:27.271697Z","steps":["trace[2015198781] 'process raft request' (duration: 140.104333ms)","trace[2015198781] 'compare' (duration: 186.500108ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:27.271801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:26.944459Z","time spent":"327.283809ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:27.271889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.41908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:26:27.271942Z","caller":"traceutil/trace.go:171","msg":"trace[825145913] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25762; }","duration":"288.517363ms","start":"2026-09-23T11:26:26.983407Z","end":"2026-09-23T11:26:27.271925Z","steps":["trace[825145913] 'agreement among raft nodes before linearized reading' (duration: 288.41284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:27.289387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.540006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:27.289482Z","caller":"traceutil/trace.go:171","msg":"trace[1016958886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25763; }","duration":"260.656977ms","start":"2026-09-23T11:26:27.0288Z","end":"2026-09-23T11:26:27.289457Z","steps":["trace[1016958886] 'agreement among raft nodes before linearized reading' (duration: 260.485572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:27.289774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.226688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:27.289816Z","caller":"traceutil/trace.go:171","msg":"trace[5727383] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25763; }","duration":"151.3095ms","start":"2026-09-23T11:26:27.138492Z","end":"2026-09-23T11:26:27.289802Z","steps":["trace[5727383] 'agreement among raft nodes before linearized reading' (duration: 151.242089ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:27.28987Z","caller":"traceutil/trace.go:171","msg":"trace[157396523] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"193.445179ms","start":"2026-09-23T11:26:27.096377Z","end":"2026-09-23T11:26:27.289822Z","steps":["trace[157396523] 'process raft request' (duration: 192.682708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:27.624661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.962119ms","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-09-23T11:26:27.624744Z","caller":"traceutil/trace.go:171","msg":"trace[442010998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25763; }","duration":"196.079892ms","start":"2026-09-23T11:26:27.428645Z","end":"2026-09-23T11:26:27.624725Z","steps":["trace[442010998] 'range keys from in-memory index tree' (duration: 195.791134ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:27.791603Z","caller":"traceutil/trace.go:171","msg":"trace[266417095] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"160.078022ms","start":"2026-09-23T11:26:27.631499Z","end":"2026-09-23T11:26:27.791577Z","steps":["trace[266417095] 'process raft request' (duration: 159.881547ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:27.945205Z","caller":"traceutil/trace.go:171","msg":"trace[2045624740] linearizableReadLoop","detail":"{readStateIndex:26620; appliedIndex:26619; }","duration":"118.078806ms","start":"2026-09-23T11:26:27.827101Z","end":"2026-09-23T11:26:27.94518Z","steps":["trace[2045624740] 'read index received' (duration: 70.452037ms)","trace[2045624740] 'applied index is now lower than readState.Index' (duration: 47.622529ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:27.945232Z","caller":"traceutil/trace.go:171","msg":"trace[1724351571] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"128.308298ms","start":"2026-09-23T11:26:27.816891Z","end":"2026-09-23T11:26:27.945199Z","steps":["trace[1724351571] 'process raft request' (duration: 80.73387ms)","trace[1724351571] 'compare' (duration: 47.407913ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:27.945409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.292722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T11:26:27.945471Z","caller":"traceutil/trace.go:171","msg":"trace[1247274167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25765; }","duration":"118.415956ms","start":"2026-09-23T11:26:27.827041Z","end":"2026-09-23T11:26:27.945457Z","steps":["trace[1247274167] 'agreement among raft nodes before linearized reading' (duration: 118.263962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.495116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.318463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:28.495246Z","caller":"traceutil/trace.go:171","msg":"trace[1824038190] linearizableReadLoop","detail":"{readStateIndex:26621; appliedIndex:26620; }","duration":"466.24029ms","start":"2026-09-23T11:26:28.028987Z","end":"2026-09-23T11:26:28.495227Z","steps":["trace[1824038190] 'read index received' (duration: 351.620228ms)","trace[1824038190] 'applied index is now lower than readState.Index' (duration: 114.618852ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:28.495311Z","caller":"traceutil/trace.go:171","msg":"trace[1505903728] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"545.789217ms","start":"2026-09-23T11:26:27.949511Z","end":"2026-09-23T11:26:28.4953Z","steps":["trace[1505903728] 'process raft request' (duration: 431.186126ms)","trace[1505903728] 'compare' (duration: 114.20263ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:28.495362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:27.94949Z","time spent":"545.843369ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:28.495509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.500787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:28.495588Z","caller":"traceutil/trace.go:171","msg":"trace[2060802168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25766; }","duration":"466.620261ms","start":"2026-09-23T11:26:28.028944Z","end":"2026-09-23T11:26:28.495564Z","steps":["trace[2060802168] 'agreement among raft nodes before linearized reading' (duration: 466.498437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.495629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.02892Z","time spent":"466.701023ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T11:26:28.7259Z","caller":"traceutil/trace.go:171","msg":"trace[1223606658] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"309.305305ms","start":"2026-09-23T11:26:28.416559Z","end":"2026-09-23T11:26:28.725864Z","steps":["trace[1223606658] 'process raft request' (duration: 258.707274ms)","trace[1223606658] 'compare' (duration: 50.455527ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:28.726108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.416534Z","time spent":"309.451709ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:28.748151Z","caller":"traceutil/trace.go:171","msg":"trace[1451252000] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"329.178031ms","start":"2026-09-23T11:26:28.418948Z","end":"2026-09-23T11:26:28.748126Z","steps":["trace[1451252000] 'process raft request' (duration: 328.995296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.748297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.418919Z","time spent":"329.297235ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:28.748506Z","caller":"traceutil/trace.go:171","msg":"trace[1038193841] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"328.695269ms","start":"2026-09-23T11:26:28.419773Z","end":"2026-09-23T11:26:28.748468Z","steps":["trace[1038193841] 'process raft request' (duration: 328.289007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.748687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.41976Z","time spent":"328.831072ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:28.748677Z","caller":"traceutil/trace.go:171","msg":"trace[532174735] transaction","detail":"{read_only:false; response_revision:25770; number_of_response:1; }","duration":"328.546284ms","start":"2026-09-23T11:26:28.420085Z","end":"2026-09-23T11:26:28.748632Z","steps":["trace[532174735] 'process raft request' (duration: 328.246966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.748857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.692011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-23T11:26:28.748873Z","caller":"traceutil/trace.go:171","msg":"trace[1909009038] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"118.092547ms","start":"2026-09-23T11:26:28.630755Z","end":"2026-09-23T11:26:28.748847Z","steps":["trace[1909009038] 'process raft request' (duration: 117.741737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.748878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.835811ms","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-09-23T11:26:28.74893Z","caller":"traceutil/trace.go:171","msg":"trace[1562330708] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25771; }","duration":"387.816464ms","start":"2026-09-23T11:26:28.361095Z","end":"2026-09-23T11:26:28.748912Z","steps":["trace[1562330708] 'agreement among raft nodes before linearized reading' (duration: 387.604938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.748976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.361047Z","time spent":"387.917296ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-23T11:26:28.748952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.42006Z","time spent":"328.776661ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:28.748967Z","caller":"traceutil/trace.go:171","msg":"trace[2015782516] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25771; }","duration":"580.937044ms","start":"2026-09-23T11:26:28.168004Z","end":"2026-09-23T11:26:28.748941Z","steps":["trace[2015782516] 'agreement among raft nodes before linearized reading' (duration: 580.724878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.749053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.167997Z","time spent":"581.043587ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-23T11:26:28.749125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.411437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-23T11:26:28.749166Z","caller":"traceutil/trace.go:171","msg":"trace[646519715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25771; }","duration":"581.448968ms","start":"2026-09-23T11:26:28.167702Z","end":"2026-09-23T11:26:28.749151Z","steps":["trace[646519715] 'agreement among raft nodes before linearized reading' (duration: 581.315925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.749204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.167652Z","time spent":"581.544671ms","remote":"127.0.0.1:43602","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-09-23T11:26:28.749448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.476826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-23T11:26:28.749514Z","caller":"traceutil/trace.go:171","msg":"trace[41605830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25771; }","duration":"582.566169ms","start":"2026-09-23T11:26:28.166931Z","end":"2026-09-23T11:26:28.749497Z","steps":["trace[41605830] 'agreement among raft nodes before linearized reading' (duration: 582.415575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:28.749561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:28.166914Z","time spent":"582.63354ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-23T11:26:28.749304Z","caller":"traceutil/trace.go:171","msg":"trace[1168915422] linearizableReadLoop","detail":"{readStateIndex:26625; appliedIndex:26621; }","duration":"253.295314ms","start":"2026-09-23T11:26:28.495269Z","end":"2026-09-23T11:26:28.748565Z","steps":["trace[1168915422] 'read index received' (duration: 180.011829ms)","trace[1168915422] 'applied index is now lower than readState.Index' (duration: 73.282125ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:30.196231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.314552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:30.196326Z","caller":"traceutil/trace.go:171","msg":"trace[1355342325] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25779; }","duration":"102.464646ms","start":"2026-09-23T11:26:30.093835Z","end":"2026-09-23T11:26:30.1963Z","steps":["trace[1355342325] 'count revisions from in-memory index tree' (duration: 102.22722ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:32.703953Z","caller":"traceutil/trace.go:171","msg":"trace[56787888] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"189.130451ms","start":"2026-09-23T11:26:32.514793Z","end":"2026-09-23T11:26:32.703923Z","steps":["trace[56787888] 'process raft request' (duration: 188.953656ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:32.797813Z","caller":"traceutil/trace.go:171","msg":"trace[1448675702] linearizableReadLoop","detail":"{readStateIndex:26669; appliedIndex:26667; }","duration":"123.760573ms","start":"2026-09-23T11:26:32.674032Z","end":"2026-09-23T11:26:32.797792Z","steps":["trace[1448675702] 'read index received' (duration: 29.762488ms)","trace[1448675702] 'applied index is now lower than readState.Index' (duration: 93.997245ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:32.798021Z","caller":"traceutil/trace.go:171","msg":"trace[943505123] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"202.256352ms","start":"2026-09-23T11:26:32.595751Z","end":"2026-09-23T11:26:32.798007Z","steps":["trace[943505123] 'process raft request' (duration: 200.033261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:32.798022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.968798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:32.798201Z","caller":"traceutil/trace.go:171","msg":"trace[1349807336] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25813; }","duration":"124.173814ms","start":"2026-09-23T11:26:32.673991Z","end":"2026-09-23T11:26:32.798165Z","steps":["trace[1349807336] 'agreement among raft nodes before linearized reading' (duration: 123.973619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:52.356768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.616266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:52.356845Z","caller":"traceutil/trace.go:171","msg":"trace[1447520629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25981; }","duration":"332.731389ms","start":"2026-09-23T11:26:52.024095Z","end":"2026-09-23T11:26:52.356826Z","steps":["trace[1447520629] 'range keys from in-memory index tree' (duration: 332.554444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:52.356875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.024059Z","time spent":"332.809351ms","remote":"127.0.0.1:43356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T11:26:52.356906Z","caller":"traceutil/trace.go:171","msg":"trace[1175819707] linearizableReadLoop","detail":"{readStateIndex:26842; appliedIndex:26841; }","duration":"275.716232ms","start":"2026-09-23T11:26:52.081167Z","end":"2026-09-23T11:26:52.356883Z","steps":["trace[1175819707] 'read index received' (duration: 223.689031ms)","trace[1175819707] 'applied index is now lower than readState.Index' (duration: 52.026341ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:52.357027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.840095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:52.35706Z","caller":"traceutil/trace.go:171","msg":"trace[1407300003] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25982; }","duration":"275.910557ms","start":"2026-09-23T11:26:52.081137Z","end":"2026-09-23T11:26:52.357047Z","steps":["trace[1407300003] 'agreement among raft nodes before linearized reading' (duration: 275.821365ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:52.357219Z","caller":"traceutil/trace.go:171","msg":"trace[923088054] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"332.402099ms","start":"2026-09-23T11:26:52.024804Z","end":"2026-09-23T11:26:52.357206Z","steps":["trace[923088054] 'process raft request' (duration: 279.997819ms)","trace[923088054] 'compare' (duration: 51.540677ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:52.357294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.024753Z","time spent":"332.491813ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:52.855679Z","caller":"traceutil/trace.go:171","msg":"trace[1382969122] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"284.104063ms","start":"2026-09-23T11:26:52.571552Z","end":"2026-09-23T11:26:52.855656Z","steps":["trace[1382969122] 'process raft request' (duration: 283.28979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.060551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.942488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:53.060657Z","caller":"traceutil/trace.go:171","msg":"trace[474180109] transaction","detail":"{read_only:false; response_revision:25984; number_of_response:1; }","duration":"197.810789ms","start":"2026-09-23T11:26:52.862827Z","end":"2026-09-23T11:26:53.060638Z","steps":["trace[474180109] 'process raft request' (duration: 57.695236ms)","trace[474180109] 'compare' (duration: 139.813735ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:53.24495Z","caller":"traceutil/trace.go:171","msg":"trace[1116842137] transaction","detail":"{read_only:false; response_revision:25985; number_of_response:1; }","duration":"375.334131ms","start":"2026-09-23T11:26:52.869582Z","end":"2026-09-23T11:26:53.244916Z","steps":["trace[1116842137] 'process raft request' (duration: 346.310213ms)","trace[1116842137] 'compare' (duration: 28.807692ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:53.245145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.86957Z","time spent":"375.466024ms","remote":"127.0.0.1:54172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3217,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:53.245399Z","caller":"traceutil/trace.go:171","msg":"trace[23498783] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"368.212615ms","start":"2026-09-23T11:26:52.877146Z","end":"2026-09-23T11:26:53.245359Z","steps":["trace[23498783] 'process raft request' (duration: 367.701441ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:53.245469Z","caller":"traceutil/trace.go:171","msg":"trace[1315154463] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"365.631804ms","start":"2026-09-23T11:26:52.879827Z","end":"2026-09-23T11:26:53.245459Z","steps":["trace[1315154463] 'process raft request' (duration: 365.338296ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:53.245504Z","caller":"traceutil/trace.go:171","msg":"trace[1544256873] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"363.102454ms","start":"2026-09-23T11:26:52.882388Z","end":"2026-09-23T11:26:53.24549Z","steps":["trace[1544256873] 'process raft request' (duration: 362.877188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.245584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.879816Z","time spent":"365.690455ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:53.245593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.882378Z","time spent":"363.172026ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:53.245668Z","caller":"traceutil/trace.go:171","msg":"trace[1474120843] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"353.620813ms","start":"2026-09-23T11:26:52.892039Z","end":"2026-09-23T11:26:53.24566Z","steps":["trace[1474120843] 'process raft request' (duration: 353.302665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.245677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.877132Z","time spent":"368.437071ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:26:53.245437Z","caller":"traceutil/trace.go:171","msg":"trace[1853526106] linearizableReadLoop","detail":"{readStateIndex:26850; appliedIndex:26843; }","duration":"335.895036ms","start":"2026-09-23T11:26:52.90952Z","end":"2026-09-23T11:26:53.245415Z","steps":["trace[1853526106] 'read index received' (duration: 11.012052ms)","trace[1853526106] 'applied index is now lower than readState.Index' (duration: 324.882184ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:53.245749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.892028Z","time spent":"353.671604ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:53.245603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.066891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-23T11:26:53.245799Z","caller":"traceutil/trace.go:171","msg":"trace[2137586167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25990; }","duration":"336.321888ms","start":"2026-09-23T11:26:52.909461Z","end":"2026-09-23T11:26:53.245783Z","steps":["trace[2137586167] 'agreement among raft nodes before linearized reading' (duration: 336.03153ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:53.245406Z","caller":"traceutil/trace.go:171","msg":"trace[1370246136] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"358.626112ms","start":"2026-09-23T11:26:52.886753Z","end":"2026-09-23T11:26:53.245379Z","steps":["trace[1370246136] 'process raft request' (duration: 358.55494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.245843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.909439Z","time spent":"336.39224ms","remote":"127.0.0.1:43602","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-09-23T11:26:53.24587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.633706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-23T11:26:53.245895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.886739Z","time spent":"359.106264ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:53.245966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.649205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:26:53.245974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.577165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-09-23T11:26:53.246003Z","caller":"traceutil/trace.go:171","msg":"trace[450325195] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25990; }","duration":"252.624567ms","start":"2026-09-23T11:26:52.993369Z","end":"2026-09-23T11:26:53.245993Z","steps":["trace[450325195] 'agreement among raft nodes before linearized reading' (duration: 252.577035ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:53.245996Z","caller":"traceutil/trace.go:171","msg":"trace[1772660506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25990; }","duration":"221.710576ms","start":"2026-09-23T11:26:53.024279Z","end":"2026-09-23T11:26:53.245989Z","steps":["trace[1772660506] 'agreement among raft nodes before linearized reading' (duration: 221.665235ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:53.245913Z","caller":"traceutil/trace.go:171","msg":"trace[143913086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25990; }","duration":"328.697258ms","start":"2026-09-23T11:26:52.917203Z","end":"2026-09-23T11:26:53.2459Z","steps":["trace[143913086] 'agreement among raft nodes before linearized reading' (duration: 328.573425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.24618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.917186Z","time spent":"328.982696ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-23T11:26:53.246265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.430319ms","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-09-23T11:26:53.246307Z","caller":"traceutil/trace.go:171","msg":"trace[2081983392] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25990; }","duration":"309.505421ms","start":"2026-09-23T11:26:52.936788Z","end":"2026-09-23T11:26:53.246294Z","steps":["trace[2081983392] 'agreement among raft nodes before linearized reading' (duration: 309.408398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.246344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:52.936768Z","time spent":"309.567702ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-23T11:26:53.485505Z","caller":"traceutil/trace.go:171","msg":"trace[173124511] linearizableReadLoop","detail":"{readStateIndex:26853; appliedIndex:26850; }","duration":"232.086202ms","start":"2026-09-23T11:26:53.2534Z","end":"2026-09-23T11:26:53.485487Z","steps":["trace[173124511] 'read index received' (duration: 137.124031ms)","trace[173124511] 'applied index is now lower than readState.Index' (duration: 94.961501ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:53.485902Z","caller":"traceutil/trace.go:171","msg":"trace[366931673] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"233.84084ms","start":"2026-09-23T11:26:53.25202Z","end":"2026-09-23T11:26:53.48586Z","steps":["trace[366931673] 'process raft request' (duration: 233.361697ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:53.486005Z","caller":"traceutil/trace.go:171","msg":"trace[1796542886] transaction","detail":"{read_only:false; response_revision:25991; number_of_response:1; }","duration":"234.788476ms","start":"2026-09-23T11:26:53.251175Z","end":"2026-09-23T11:26:53.485963Z","steps":["trace[1796542886] 'process raft request' (duration: 139.341941ms)","trace[1796542886] 'compare' (duration: 94.626102ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:53.486033Z","caller":"traceutil/trace.go:171","msg":"trace[1685866587] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"233.729967ms","start":"2026-09-23T11:26:53.252269Z","end":"2026-09-23T11:26:53.485999Z","steps":["trace[1685866587] 'process raft request' (duration: 233.192732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.486121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.667748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-09-23T11:26:53.486199Z","caller":"traceutil/trace.go:171","msg":"trace[376840985] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25993; }","duration":"232.822502ms","start":"2026-09-23T11:26:53.253356Z","end":"2026-09-23T11:26:53.486178Z","steps":["trace[376840985] 'agreement among raft nodes before linearized reading' (duration: 232.541344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.825363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.737762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:53.825509Z","caller":"traceutil/trace.go:171","msg":"trace[901805627] linearizableReadLoop","detail":"{readStateIndex:26854; appliedIndex:26853; }","duration":"339.745392ms","start":"2026-09-23T11:26:53.485741Z","end":"2026-09-23T11:26:53.825487Z","steps":["trace[901805627] 'read index received' (duration: 215.711681ms)","trace[901805627] 'applied index is now lower than readState.Index' (duration: 124.031731ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:53.825539Z","caller":"traceutil/trace.go:171","msg":"trace[1024768673] transaction","detail":"{read_only:false; response_revision:25994; number_of_response:1; }","duration":"429.230022ms","start":"2026-09-23T11:26:53.396278Z","end":"2026-09-23T11:26:53.825508Z","steps":["trace[1024768673] 'process raft request' (duration: 305.229303ms)","trace[1024768673] 'compare' (duration: 123.29545ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:53.825683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:53.396252Z","time spent":"429.349146ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:26:53.825765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.788315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:26:53.825815Z","caller":"traceutil/trace.go:171","msg":"trace[1886660689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25994; }","duration":"410.865417ms","start":"2026-09-23T11:26:53.414936Z","end":"2026-09-23T11:26:53.825802Z","steps":["trace[1886660689] 'agreement among raft nodes before linearized reading' (duration: 410.661262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.825865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:53.414919Z","time spent":"410.93602ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T11:26:53.826121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.052218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-09-23T11:26:53.826169Z","caller":"traceutil/trace.go:171","msg":"trace[933537146] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25994; }","duration":"333.12247ms","start":"2026-09-23T11:26:53.493033Z","end":"2026-09-23T11:26:53.826156Z","steps":["trace[933537146] 'agreement among raft nodes before linearized reading' (duration: 332.971006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.826197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:53.493014Z","time spent":"333.176021ms","remote":"127.0.0.1:43440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1178,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-09-23T11:26:53.826269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.898533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T11:26:53.82632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.983112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1114"} {"level":"info","ts":"2026-09-23T11:26:53.826378Z","caller":"traceutil/trace.go:171","msg":"trace[261508019] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:25994; }","duration":"171.120836ms","start":"2026-09-23T11:26:53.655238Z","end":"2026-09-23T11:26:53.826358Z","steps":["trace[261508019] 'agreement among raft nodes before linearized reading' (duration: 170.876979ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:53.826381Z","caller":"traceutil/trace.go:171","msg":"trace[941757347] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25994; }","duration":"561.058638ms","start":"2026-09-23T11:26:53.265292Z","end":"2026-09-23T11:26:53.826351Z","steps":["trace[941757347] 'agreement among raft nodes before linearized reading' (duration: 560.852742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.826298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.984217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-23T11:26:53.826512Z","caller":"traceutil/trace.go:171","msg":"trace[1888415779] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25994; }","duration":"173.222253ms","start":"2026-09-23T11:26:53.653262Z","end":"2026-09-23T11:26:53.826484Z","steps":["trace[1888415779] 'agreement among raft nodes before linearized reading' (duration: 172.934325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:53.82652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:26:53.26527Z","time spent":"561.212061ms","remote":"127.0.0.1:54682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-23T11:26:54.128383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.411396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:26:54.128482Z","caller":"traceutil/trace.go:171","msg":"trace[198676067] linearizableReadLoop","detail":"{readStateIndex:26856; appliedIndex:26855; }","duration":"260.815992ms","start":"2026-09-23T11:26:53.86765Z","end":"2026-09-23T11:26:54.128466Z","steps":["trace[198676067] 'read index received' (duration: 58.17435ms)","trace[198676067] 'applied index is now lower than readState.Index' (duration: 202.640482ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:54.128592Z","caller":"traceutil/trace.go:171","msg":"trace[1108185031] transaction","detail":"{read_only:false; response_revision:25996; number_of_response:1; }","duration":"264.117042ms","start":"2026-09-23T11:26:53.864443Z","end":"2026-09-23T11:26:54.12856Z","steps":["trace[1108185031] 'process raft request' (duration: 61.44415ms)","trace[1108185031] 'compare' (duration: 202.155288ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:54.128955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.304035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-09-23T11:26:54.129Z","caller":"traceutil/trace.go:171","msg":"trace[832152934] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25996; }","duration":"261.385427ms","start":"2026-09-23T11:26:53.867604Z","end":"2026-09-23T11:26:54.128989Z","steps":["trace[832152934] 'agreement among raft nodes before linearized reading' (duration: 261.231343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:54.129028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.46912ms","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-09-23T11:26:54.129102Z","caller":"traceutil/trace.go:171","msg":"trace[1626866513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25996; }","duration":"221.566322ms","start":"2026-09-23T11:26:53.907494Z","end":"2026-09-23T11:26:54.129061Z","steps":["trace[1626866513] 'agreement among raft nodes before linearized reading' (duration: 221.414948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:54.129282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.428734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:26:54.129315Z","caller":"traceutil/trace.go:171","msg":"trace[1419005147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25996; }","duration":"103.460995ms","start":"2026-09-23T11:26:54.025844Z","end":"2026-09-23T11:26:54.129305Z","steps":["trace[1419005147] 'agreement among raft nodes before linearized reading' (duration: 103.411094ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:54.429471Z","caller":"traceutil/trace.go:171","msg":"trace[743930910] linearizableReadLoop","detail":"{readStateIndex:26859; appliedIndex:26858; }","duration":"224.979576ms","start":"2026-09-23T11:26:54.20447Z","end":"2026-09-23T11:26:54.429449Z","steps":["trace[743930910] 'read index received' (duration: 224.888954ms)","trace[743930910] 'applied index is now lower than readState.Index' (duration: 89.642µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:54.42953Z","caller":"traceutil/trace.go:171","msg":"trace[873024994] transaction","detail":"{read_only:false; response_revision:25999; number_of_response:1; }","duration":"232.006969ms","start":"2026-09-23T11:26:54.197492Z","end":"2026-09-23T11:26:54.429499Z","steps":["trace[873024994] 'process raft request' (duration: 231.790503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:54.429683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.191162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-09-23T11:26:54.429844Z","caller":"traceutil/trace.go:171","msg":"trace[379011151] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25999; }","duration":"225.336316ms","start":"2026-09-23T11:26:54.204444Z","end":"2026-09-23T11:26:54.42978Z","steps":["trace[379011151] 'agreement among raft nodes before linearized reading' (duration: 225.11276ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:54.47412Z","caller":"traceutil/trace.go:171","msg":"trace[598705982] transaction","detail":"{read_only:false; response_revision:26000; number_of_response:1; }","duration":"112.36767ms","start":"2026-09-23T11:26:54.361703Z","end":"2026-09-23T11:26:54.474071Z","steps":["trace[598705982] 'process raft request' (duration: 112.046251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:54.474192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.819217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.127\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-23T11:26:54.474261Z","caller":"traceutil/trace.go:171","msg":"trace[621176320] range","detail":"{range_begin:/registry/masterleases/162.253.55.127; range_end:; response_count:1; response_revision:26000; }","duration":"254.93829ms","start":"2026-09-23T11:26:54.219305Z","end":"2026-09-23T11:26:54.474243Z","steps":["trace[621176320] 'agreement among raft nodes before linearized reading' (duration: 254.700873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:54.474543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.491955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-23T11:26:54.474644Z","caller":"traceutil/trace.go:171","msg":"trace[1562258436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26000; }","duration":"123.631209ms","start":"2026-09-23T11:26:54.350979Z","end":"2026-09-23T11:26:54.47461Z","steps":["trace[1562258436] 'agreement among raft nodes before linearized reading' (duration: 123.351761ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:54.745498Z","caller":"traceutil/trace.go:171","msg":"trace[1933095503] linearizableReadLoop","detail":"{readStateIndex:26861; appliedIndex:26860; }","duration":"267.254669ms","start":"2026-09-23T11:26:54.47821Z","end":"2026-09-23T11:26:54.745465Z","steps":["trace[1933095503] 'read index received' (duration: 198.849678ms)","trace[1933095503] 'applied index is now lower than readState.Index' (duration: 68.403451ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:26:54.745693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.463544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-23T11:26:54.745756Z","caller":"traceutil/trace.go:171","msg":"trace[288107677] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26000; }","duration":"267.555937ms","start":"2026-09-23T11:26:54.478185Z","end":"2026-09-23T11:26:54.745741Z","steps":["trace[288107677] 'agreement among raft nodes before linearized reading' (duration: 267.367062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:54.745775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.543262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-09-23T11:26:54.745811Z","caller":"traceutil/trace.go:171","msg":"trace[2023646797] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26000; }","duration":"265.590683ms","start":"2026-09-23T11:26:54.480206Z","end":"2026-09-23T11:26:54.745797Z","steps":["trace[2023646797] 'agreement among raft nodes before linearized reading' (duration: 265.427398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:54.978204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.74112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:26:54.978288Z","caller":"traceutil/trace.go:171","msg":"trace[1641587928] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26001; }","duration":"108.873704ms","start":"2026-09-23T11:26:54.869394Z","end":"2026-09-23T11:26:54.978268Z","steps":["trace[1641587928] 'count revisions from in-memory index tree' (duration: 108.621576ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:55.720467Z","caller":"traceutil/trace.go:171","msg":"trace[1386514250] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"216.229875ms","start":"2026-09-23T11:26:55.504199Z","end":"2026-09-23T11:26:55.720429Z","steps":["trace[1386514250] 'process raft request' (duration: 132.175674ms)","trace[1386514250] 'compare' (duration: 83.533147ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:55.720993Z","caller":"traceutil/trace.go:171","msg":"trace[1252444085] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"216.72008ms","start":"2026-09-23T11:26:55.504229Z","end":"2026-09-23T11:26:55.720949Z","steps":["trace[1252444085] 'process raft request' (duration: 216.117713ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:56.012917Z","caller":"traceutil/trace.go:171","msg":"trace[87841789] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"181.569723ms","start":"2026-09-23T11:26:55.831298Z","end":"2026-09-23T11:26:56.012867Z","steps":["trace[87841789] 'process raft request' (duration: 100.423311ms)","trace[87841789] 'compare' (duration: 80.878444ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:56.013261Z","caller":"traceutil/trace.go:171","msg":"trace[471085843] linearizableReadLoop","detail":"{readStateIndex:26874; appliedIndex:26873; }","duration":"154.021055ms","start":"2026-09-23T11:26:55.85921Z","end":"2026-09-23T11:26:56.013231Z","steps":["trace[471085843] 'read index received' (duration: 72.486213ms)","trace[471085843] 'applied index is now lower than readState.Index' (duration: 81.532422ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:56.013433Z","caller":"traceutil/trace.go:171","msg":"trace[74005883] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"146.159909ms","start":"2026-09-23T11:26:55.867251Z","end":"2026-09-23T11:26:56.013411Z","steps":["trace[74005883] 'process raft request' (duration: 146.011945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:26:56.01351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.314293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T11:26:56.013603Z","caller":"traceutil/trace.go:171","msg":"trace[1247134925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26013; }","duration":"154.454977ms","start":"2026-09-23T11:26:55.859125Z","end":"2026-09-23T11:26:56.01358Z","steps":["trace[1247134925] 'agreement among raft nodes before linearized reading' (duration: 154.20566ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:57.575992Z","caller":"traceutil/trace.go:171","msg":"trace[1520130725] transaction","detail":"{read_only:false; response_revision:26023; number_of_response:1; }","duration":"245.593493ms","start":"2026-09-23T11:26:57.330365Z","end":"2026-09-23T11:26:57.575959Z","steps":["trace[1520130725] 'process raft request' (duration: 201.809049ms)","trace[1520130725] 'compare' (duration: 43.610659ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:26:57.576433Z","caller":"traceutil/trace.go:171","msg":"trace[1469458970] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"245.966864ms","start":"2026-09-23T11:26:57.330422Z","end":"2026-09-23T11:26:57.576389Z","steps":["trace[1469458970] 'process raft request' (duration: 245.496861ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:57.57684Z","caller":"traceutil/trace.go:171","msg":"trace[1190804341] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"245.312665ms","start":"2026-09-23T11:26:57.331498Z","end":"2026-09-23T11:26:57.57681Z","steps":["trace[1190804341] 'process raft request' (duration: 244.865013ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:26:57.57661Z","caller":"traceutil/trace.go:171","msg":"trace[1099218230] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"245.465049ms","start":"2026-09-23T11:26:57.331128Z","end":"2026-09-23T11:26:57.576593Z","steps":["trace[1099218230] 'process raft request' (duration: 245.072768ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:00.222255Z","caller":"traceutil/trace.go:171","msg":"trace[102728031] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"103.67517ms","start":"2026-09-23T11:27:00.118552Z","end":"2026-09-23T11:27:00.222227Z","steps":["trace[102728031] 'process raft request' (duration: 91.119775ms)","trace[102728031] 'compare' (duration: 11.944618ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:27:01.795755Z","caller":"traceutil/trace.go:171","msg":"trace[1084135389] linearizableReadLoop","detail":"{readStateIndex:26915; appliedIndex:26912; }","duration":"198.283112ms","start":"2026-09-23T11:27:01.597452Z","end":"2026-09-23T11:27:01.795735Z","steps":["trace[1084135389] 'read index received' (duration: 113.196652ms)","trace[1084135389] 'applied index is now lower than readState.Index' (duration: 85.08564ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:27:01.795804Z","caller":"traceutil/trace.go:171","msg":"trace[488176116] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"203.66162ms","start":"2026-09-23T11:27:01.592104Z","end":"2026-09-23T11:27:01.795766Z","steps":["trace[488176116] 'process raft request' (duration: 203.560867ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:01.795968Z","caller":"traceutil/trace.go:171","msg":"trace[1885218156] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"204.106892ms","start":"2026-09-23T11:27:01.591853Z","end":"2026-09-23T11:27:01.79596Z","steps":["trace[1885218156] 'process raft request' (duration: 118.865508ms)","trace[1885218156] 'compare' (duration: 84.698699ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:27:01.795974Z","caller":"traceutil/trace.go:171","msg":"trace[564584453] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"203.241048ms","start":"2026-09-23T11:27:01.592686Z","end":"2026-09-23T11:27:01.795927Z","steps":["trace[564584453] 'process raft request' (duration: 203.020552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:27:01.796211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.539789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:27:01.796289Z","caller":"traceutil/trace.go:171","msg":"trace[1330365699] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26053; }","duration":"178.672443ms","start":"2026-09-23T11:27:01.617595Z","end":"2026-09-23T11:27:01.796268Z","steps":["trace[1330365699] 'agreement among raft nodes before linearized reading' (duration: 178.533169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:27:01.796385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.92757ms","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-09-23T11:27:01.796376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.238914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:27:01.796425Z","caller":"traceutil/trace.go:171","msg":"trace[770705601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26053; }","duration":"198.986992ms","start":"2026-09-23T11:27:01.597427Z","end":"2026-09-23T11:27:01.796414Z","steps":["trace[770705601] 'agreement among raft nodes before linearized reading' (duration: 198.881539ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:01.796455Z","caller":"traceutil/trace.go:171","msg":"trace[620667678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26053; }","duration":"149.382928ms","start":"2026-09-23T11:27:01.647043Z","end":"2026-09-23T11:27:01.796426Z","steps":["trace[620667678] 'agreement among raft nodes before linearized reading' (duration: 149.252905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:27:02.099024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.469562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:27:02.099371Z","caller":"traceutil/trace.go:171","msg":"trace[231718411] linearizableReadLoop","detail":"{readStateIndex:26918; appliedIndex:26915; }","duration":"263.402773ms","start":"2026-09-23T11:27:01.835944Z","end":"2026-09-23T11:27:02.099347Z","steps":["trace[231718411] 'read index received' (duration: 42.992342ms)","trace[231718411] 'applied index is now lower than readState.Index' (duration: 220.409221ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:27:02.09943Z","caller":"traceutil/trace.go:171","msg":"trace[1901452978] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"299.3076ms","start":"2026-09-23T11:27:01.800096Z","end":"2026-09-23T11:27:02.099404Z","steps":["trace[1901452978] 'process raft request' (duration: 162.345564ms)","trace[1901452978] 'compare' (duration: 136.214366ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:27:02.099428Z","caller":"traceutil/trace.go:171","msg":"trace[2104946138] transaction","detail":"{read_only:false; response_revision:26056; number_of_response:1; }","duration":"297.761527ms","start":"2026-09-23T11:27:01.801616Z","end":"2026-09-23T11:27:02.099378Z","steps":["trace[2104946138] 'process raft request' (duration: 297.550502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:27:02.099706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.751953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T11:27:02.099771Z","caller":"traceutil/trace.go:171","msg":"trace[781396274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26057; }","duration":"263.859296ms","start":"2026-09-23T11:27:01.835893Z","end":"2026-09-23T11:27:02.099753Z","steps":["trace[781396274] 'agreement among raft nodes before linearized reading' (duration: 263.669201ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:03.74576Z","caller":"traceutil/trace.go:171","msg":"trace[1504285459] transaction","detail":"{read_only:false; response_revision:26064; number_of_response:1; }","duration":"143.530127ms","start":"2026-09-23T11:27:03.60218Z","end":"2026-09-23T11:27:03.74571Z","steps":["trace[1504285459] 'process raft request' (duration: 143.30073ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:03.979356Z","caller":"traceutil/trace.go:171","msg":"trace[1252855656] transaction","detail":"{read_only:false; response_revision:26066; number_of_response:1; }","duration":"176.02221ms","start":"2026-09-23T11:27:03.803298Z","end":"2026-09-23T11:27:03.97932Z","steps":["trace[1252855656] 'process raft request' (duration: 86.484558ms)","trace[1252855656] 'compare' (duration: 89.275985ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:27:03.979552Z","caller":"traceutil/trace.go:171","msg":"trace[1248462876] transaction","detail":"{read_only:false; response_revision:26067; number_of_response:1; }","duration":"175.212298ms","start":"2026-09-23T11:27:03.804318Z","end":"2026-09-23T11:27:03.979531Z","steps":["trace[1248462876] 'process raft request' (duration: 174.951031ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:03.979537Z","caller":"traceutil/trace.go:171","msg":"trace[1403102574] linearizableReadLoop","detail":"{readStateIndex:26929; appliedIndex:26927; }","duration":"106.868698ms","start":"2026-09-23T11:27:03.872632Z","end":"2026-09-23T11:27:03.979501Z","steps":["trace[1403102574] 'read index received' (duration: 17.134701ms)","trace[1403102574] 'applied index is now lower than readState.Index' (duration: 89.731517ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:27:03.979675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.010153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:27:03.979734Z","caller":"traceutil/trace.go:171","msg":"trace[341571278] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:26067; }","duration":"107.123315ms","start":"2026-09-23T11:27:03.872596Z","end":"2026-09-23T11:27:03.979719Z","steps":["trace[341571278] 'agreement among raft nodes before linearized reading' (duration: 106.999702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:27:03.979846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.729675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:27:03.9799Z","caller":"traceutil/trace.go:171","msg":"trace[1571740372] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:26067; }","duration":"102.849659ms","start":"2026-09-23T11:27:03.877033Z","end":"2026-09-23T11:27:03.979883Z","steps":["trace[1571740372] 'agreement among raft nodes before linearized reading' (duration: 102.756626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:27:06.818857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.108293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:27:06.818969Z","caller":"traceutil/trace.go:171","msg":"trace[96074723] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:26090; }","duration":"116.255996ms","start":"2026-09-23T11:27:06.702679Z","end":"2026-09-23T11:27:06.818935Z","steps":["trace[96074723] 'count revisions from in-memory index tree' (duration: 116.02758ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:24.567511Z","caller":"traceutil/trace.go:171","msg":"trace[794048475] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"123.150196ms","start":"2026-09-23T11:27:24.44433Z","end":"2026-09-23T11:27:24.56748Z","steps":["trace[794048475] 'process raft request' (duration: 122.977891ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:24.577342Z","caller":"traceutil/trace.go:171","msg":"trace[1280851002] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"129.621734ms","start":"2026-09-23T11:27:24.447689Z","end":"2026-09-23T11:27:24.577311Z","steps":["trace[1280851002] 'process raft request' (duration: 129.485581ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:44.050512Z","caller":"traceutil/trace.go:171","msg":"trace[431983052] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"130.687583ms","start":"2026-09-23T11:27:43.919797Z","end":"2026-09-23T11:27:44.050485Z","steps":["trace[431983052] 'process raft request' (duration: 130.437567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:27:44.050685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.628097ms","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":"info","ts":"2026-09-23T11:27:44.050781Z","caller":"traceutil/trace.go:171","msg":"trace[1935069693] 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:26510; }","duration":"116.799282ms","start":"2026-09-23T11:27:43.93396Z","end":"2026-09-23T11:27:44.050759Z","steps":["trace[1935069693] 'agreement among raft nodes before linearized reading' (duration: 116.608957ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:44.050488Z","caller":"traceutil/trace.go:171","msg":"trace[1487605895] linearizableReadLoop","detail":"{readStateIndex:27382; appliedIndex:27381; }","duration":"116.418771ms","start":"2026-09-23T11:27:43.934032Z","end":"2026-09-23T11:27:44.050451Z","steps":["trace[1487605895] 'read index received' (duration: 116.189285ms)","trace[1487605895] 'applied index is now lower than readState.Index' (duration: 228.256µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:27:44.128835Z","caller":"traceutil/trace.go:171","msg":"trace[187836538] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"112.331149ms","start":"2026-09-23T11:27:44.016464Z","end":"2026-09-23T11:27:44.128795Z","steps":["trace[187836538] 'process raft request' (duration: 112.073222ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:44.238707Z","caller":"traceutil/trace.go:171","msg":"trace[1265889872] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"107.992909ms","start":"2026-09-23T11:27:44.130676Z","end":"2026-09-23T11:27:44.238669Z","steps":["trace[1265889872] 'process raft request' (duration: 17.056738ms)","trace[1265889872] 'compare' (duration: 90.648683ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:27:44.512237Z","caller":"traceutil/trace.go:171","msg":"trace[1511301129] transaction","detail":"{read_only:false; response_revision:26514; number_of_response:1; }","duration":"268.224005ms","start":"2026-09-23T11:27:44.243981Z","end":"2026-09-23T11:27:44.512205Z","steps":["trace[1511301129] 'process raft request' (duration: 267.899116ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:27:44.512424Z","caller":"traceutil/trace.go:171","msg":"trace[1504731141] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"145.797839ms","start":"2026-09-23T11:27:44.366598Z","end":"2026-09-23T11:27:44.512396Z","steps":["trace[1504731141] 'process raft request' (duration: 145.546782ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:28:59.161911Z","caller":"traceutil/trace.go:171","msg":"trace[284836862] transaction","detail":"{read_only:false; response_revision:27156; number_of_response:1; }","duration":"112.552997ms","start":"2026-09-23T11:28:59.049322Z","end":"2026-09-23T11:28:59.161875Z","steps":["trace[284836862] 'process raft request' (duration: 105.472141ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:16.920138Z","caller":"traceutil/trace.go:171","msg":"trace[203022980] linearizableReadLoop","detail":"{readStateIndex:28217; appliedIndex:28216; }","duration":"214.86648ms","start":"2026-09-23T11:29:16.705177Z","end":"2026-09-23T11:29:16.920043Z","steps":["trace[203022980] 'read index received' (duration: 210.87353ms)","trace[203022980] 'applied index is now lower than readState.Index' (duration: 3.99191ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T11:29:16.920238Z","caller":"traceutil/trace.go:171","msg":"trace[1371868537] transaction","detail":"{read_only:false; response_revision:27325; number_of_response:1; }","duration":"220.99805ms","start":"2026-09-23T11:29:16.699171Z","end":"2026-09-23T11:29:16.920169Z","steps":["trace[1371868537] 'process raft request' (duration: 216.719001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:16.920571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.382795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-23T11:29:16.921216Z","caller":"traceutil/trace.go:171","msg":"trace[767363940] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:27325; }","duration":"216.006242ms","start":"2026-09-23T11:29:16.705125Z","end":"2026-09-23T11:29:16.921131Z","steps":["trace[767363940] 'agreement among raft nodes before linearized reading' (duration: 215.183639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.182899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.039715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:29:17.183016Z","caller":"traceutil/trace.go:171","msg":"trace[800604586] linearizableReadLoop","detail":"{readStateIndex:28218; appliedIndex:28217; }","duration":"155.412654ms","start":"2026-09-23T11:29:17.027584Z","end":"2026-09-23T11:29:17.182997Z","steps":["trace[800604586] 'read index received' (duration: 31.04985ms)","trace[800604586] 'applied index is now lower than readState.Index' (duration: 124.361404ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:17.183266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.682201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:17.183323Z","caller":"traceutil/trace.go:171","msg":"trace[167804295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27326; }","duration":"155.775664ms","start":"2026-09-23T11:29:17.027533Z","end":"2026-09-23T11:29:17.183308Z","steps":["trace[167804295] 'agreement among raft nodes before linearized reading' (duration: 155.690041ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:17.183352Z","caller":"traceutil/trace.go:171","msg":"trace[137189310] transaction","detail":"{read_only:false; response_revision:27326; number_of_response:1; }","duration":"250.256191ms","start":"2026-09-23T11:29:16.933055Z","end":"2026-09-23T11:29:17.183311Z","steps":["trace[137189310] 'process raft request' (duration: 125.710832ms)","trace[137189310] 'compare' (duration: 123.893251ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:17.183619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.622602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:17.183796Z","caller":"traceutil/trace.go:171","msg":"trace[982530856] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27326; }","duration":"106.780847ms","start":"2026-09-23T11:29:17.076928Z","end":"2026-09-23T11:29:17.183708Z","steps":["trace[982530856] 'agreement among raft nodes before linearized reading' (duration: 106.568661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.754656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.748427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T11:29:17.754927Z","caller":"traceutil/trace.go:171","msg":"trace[1159647336] transaction","detail":"{read_only:false; response_revision:27328; number_of_response:1; }","duration":"566.542139ms","start":"2026-09-23T11:29:17.188349Z","end":"2026-09-23T11:29:17.754892Z","steps":["trace[1159647336] 'process raft request' (duration: 117.439912ms)","trace[1159647336] 'compare' (duration: 448.603514ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:17.755136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.188324Z","time spent":"566.690914ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:17.755096Z","caller":"traceutil/trace.go:171","msg":"trace[716345982] transaction","detail":"{read_only:false; response_revision:27330; number_of_response:1; }","duration":"445.24231ms","start":"2026-09-23T11:29:17.30983Z","end":"2026-09-23T11:29:17.755073Z","steps":["trace[716345982] 'process raft request' (duration: 445.150048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.755454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.309795Z","time spent":"445.611661ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T11:29:17.755457Z","caller":"traceutil/trace.go:171","msg":"trace[601999115] transaction","detail":"{read_only:false; response_revision:27329; number_of_response:1; }","duration":"566.776526ms","start":"2026-09-23T11:29:17.188641Z","end":"2026-09-23T11:29:17.755417Z","steps":["trace[601999115] 'process raft request' (duration: 566.130608ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:29:17.755811Z","caller":"traceutil/trace.go:171","msg":"trace[1667807924] linearizableReadLoop","detail":"{readStateIndex:28221; appliedIndex:28219; }","duration":"496.270684ms","start":"2026-09-23T11:29:17.25951Z","end":"2026-09-23T11:29:17.75578Z","steps":["trace[1667807924] 'read index received' (duration: 46.250491ms)","trace[1667807924] 'applied index is now lower than readState.Index' (duration: 450.011493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T11:29:17.756001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.481439ms","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-09-23T11:29:17.756338Z","caller":"traceutil/trace.go:171","msg":"trace[776655068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27330; }","duration":"496.84054ms","start":"2026-09-23T11:29:17.25945Z","end":"2026-09-23T11:29:17.75629Z","steps":["trace[776655068] 'agreement among raft nodes before linearized reading' (duration: 496.392708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.756461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.259424Z","time spent":"497.002364ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-23T11:29:17.756428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.188625Z","time spent":"567.475576ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T11:29:17.756665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.710707ms","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-09-23T11:29:17.756752Z","caller":"traceutil/trace.go:171","msg":"trace[963413446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27330; }","duration":"422.83865ms","start":"2026-09-23T11:29:17.333888Z","end":"2026-09-23T11:29:17.756727Z","steps":["trace[963413446] 'agreement among raft nodes before linearized reading' (duration: 422.583433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.756805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T11:29:17.333858Z","time spent":"422.933323ms","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-23T11:29:17.757021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.853937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21431"} {"level":"info","ts":"2026-09-23T11:29:17.757072Z","caller":"traceutil/trace.go:171","msg":"trace[982571020] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:27330; }","duration":"120.998371ms","start":"2026-09-23T11:29:17.636061Z","end":"2026-09-23T11:29:17.75706Z","steps":["trace[982571020] 'agreement among raft nodes before linearized reading' (duration: 120.847037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T11:29:17.757084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.827771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T11:29:17.757251Z","caller":"traceutil/trace.go:171","msg":"trace[934026296] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27330; }","duration":"144.969594ms","start":"2026-09-23T11:29:17.612206Z","end":"2026-09-23T11:29:17.757176Z","steps":["trace[934026296] 'agreement among raft nodes before linearized reading' (duration: 144.848731ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T11:30:40.923258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25250} {"level":"info","ts":"2026-09-23T11:30:41.081845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25250,"took":"153.350827ms","hash":2242454770,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":38727680,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-09-23T11:30:41.081933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2242454770,"revision":25250,"compact-revision":21979} {"level":"info","ts":"2026-09-23T11:32:19.94651Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9e5e76b85fbfd435","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:32:19.952485Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-23T11:32:19.952648Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-23T11:35:40.931125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28210} {"level":"info","ts":"2026-09-23T11:35:41.081644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28210,"took":"146.380024ms","hash":3064955742,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":32505856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T11:35:41.081713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3064955742,"revision":28210,"compact-revision":25250} {"level":"info","ts":"2026-09-23T11:40:40.93942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30568} {"level":"info","ts":"2026-09-23T11:40:41.085336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30568,"took":"140.605807ms","hash":3699918429,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31342592,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T11:40:41.085409Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3699918429,"revision":30568,"compact-revision":28210} {"level":"info","ts":"2026-09-23T11:45:40.945431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32823} {"level":"info","ts":"2026-09-23T11:45:41.096636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32823,"took":"145.902756ms","hash":1251561542,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31539200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T11:45:41.096703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1251561542,"revision":32823,"compact-revision":30568} {"level":"info","ts":"2026-09-23T11:50:40.954327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35055} {"level":"info","ts":"2026-09-23T11:50:41.103661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35055,"took":"145.572656ms","hash":273549773,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":33026048,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-23T11:50:41.103735Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":273549773,"revision":35055,"compact-revision":32823} {"level":"info","ts":"2026-09-23T11:53:51.099453Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9e5e76b85fbfd435","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T11:53:51.109072Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-23T11:53:51.109186Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-23T11:55:40.963644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37350} {"level":"info","ts":"2026-09-23T11:55:41.203477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37350,"took":"234.364875ms","hash":3696466484,"current-db-size-bytes":59338752,"current-db-size":"59 MB","current-db-size-in-use-bytes":33210368,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-23T11:55:41.203537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3696466484,"revision":37350,"compact-revision":35055} {"level":"info","ts":"2026-09-23T12:00:40.970825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39628} {"level":"info","ts":"2026-09-23T12:00:41.122219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39628,"took":"147.351302ms","hash":2146551046,"current-db-size-bytes":59338752,"current-db-size":"59 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T12:00:41.122311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2146551046,"revision":39628,"compact-revision":37350}