{"level":"warn","ts":"2026-06-02T17:27:58.982799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T17:27:58.98288Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-02T17:27:58.982942Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T17:27:58.982951Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-06-02T17:27:58.98297Z","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-06-02T17:27:58.983572Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-06-02T17:27:58.983642Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-02T17:27:58.987467Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.622ms"} {"level":"info","ts":"2026-06-02T17:27:58.992934Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-06-02T17:27:58.993004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T17:27:58.993027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-06-02T17:27:58.993041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T17:27:58.993057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-06-02T17:27:58.993092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-06-02T17:27:58.998774Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T17:27:59.002278Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T17:27:59.003684Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-02T17:27:59.006439Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T17:27:59.006702Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T17:27:59.00678Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T17:27:59.008173Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T17:27:59.0082Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-02T17:27:59.009047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-06-02T17:27:59.009277Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-06-02T17:27:59.01086Z","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-06-02T17:27:59.011033Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-06-02T17:27:59.011079Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-06-02T17:27:59.012175Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T17:27:59.012215Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T17:27:59.693739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T17:27:59.693805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T17:27:59.69384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-06-02T17:27:59.69386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-06-02T17:27:59.693871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-06-02T17:27:59.693887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-06-02T17:27:59.693903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-06-02T17:27:59.695053Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T17:27:59.695097Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T17:27:59.695189Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:27:59.695185Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T17:27:59.695406Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T17:27:59.69544Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T17:27:59.696313Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:27:59.696455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:27:59.69649Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T17:27:59.698284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-06-02T17:27:59.698913Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T17:28:19.450931Z","caller":"traceutil/trace.go:171","msg":"trace[876840310] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"227.271395ms","start":"2026-06-02T17:28:19.223631Z","end":"2026-06-02T17:28:19.450903Z","steps":["trace[876840310] 'process raft request' (duration: 192.982851ms)","trace[876840310] 'compare' (duration: 34.15824ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:28:42.850479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.358846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:28:42.850947Z","caller":"traceutil/trace.go:171","msg":"trace[1784085531] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"151.002837ms","start":"2026-06-02T17:28:42.699808Z","end":"2026-06-02T17:28:42.850811Z","steps":["trace[1784085531] 'process raft request' (duration: 11.461222ms)","trace[1784085531] 'compare' (duration: 138.068759ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:28:42.858459Z","caller":"traceutil/trace.go:171","msg":"trace[1998985281] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"157.717902ms","start":"2026-06-02T17:28:42.700704Z","end":"2026-06-02T17:28:42.858422Z","steps":["trace[1998985281] 'process raft request' (duration: 157.255731ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:43.816169Z","caller":"traceutil/trace.go:171","msg":"trace[902064202] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"421.804442ms","start":"2026-06-02T17:28:43.394339Z","end":"2026-06-02T17:28:43.816144Z","steps":["trace[902064202] 'read index received' (duration: 421.548956ms)","trace[902064202] 'applied index is now lower than readState.Index' (duration: 254.316µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:28:43.816518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.693183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-02T17:28:43.816535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.660689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T17:28:43.816564Z","caller":"traceutil/trace.go:171","msg":"trace[246327637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"242.815016ms","start":"2026-06-02T17:28:43.573737Z","end":"2026-06-02T17:28:43.816552Z","steps":["trace[246327637] 'agreement among raft nodes before linearized reading' (duration: 242.585181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:43.816547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.39667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-86s2q\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-02T17:28:43.816609Z","caller":"traceutil/trace.go:171","msg":"trace[995269027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"150.736021ms","start":"2026-06-02T17:28:43.665853Z","end":"2026-06-02T17:28:43.816589Z","steps":["trace[995269027] 'agreement among raft nodes before linearized reading' (duration: 150.492515ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:43.816623Z","caller":"traceutil/trace.go:171","msg":"trace[2075359866] range","detail":"{range_begin:/registry/pods/kube-system/cilium-86s2q; range_end:; response_count:1; response_revision:598; }","duration":"169.501052ms","start":"2026-06-02T17:28:43.647104Z","end":"2026-06-02T17:28:43.816605Z","steps":["trace[2075359866] 'agreement among raft nodes before linearized reading' (duration: 169.251276ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:28:43.816245Z","caller":"traceutil/trace.go:171","msg":"trace[922714562] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"433.163882ms","start":"2026-06-02T17:28:43.383039Z","end":"2026-06-02T17:28:43.816203Z","steps":["trace[922714562] 'process raft request' (duration: 432.965137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:43.816536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.175891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T17:28:43.816922Z","caller":"traceutil/trace.go:171","msg":"trace[421919451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"422.600361ms","start":"2026-06-02T17:28:43.394283Z","end":"2026-06-02T17:28:43.816884Z","steps":["trace[421919451] 'agreement among raft nodes before linearized reading' (duration: 422.027647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:28:43.816833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:28:43.383017Z","time spent":"433.744425ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T17:28:43.817006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:28:43.394264Z","time spent":"422.718784ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T17:29:51.213971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.581806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T17:29:51.214068Z","caller":"traceutil/trace.go:171","msg":"trace[459094219] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1420; }","duration":"109.73942ms","start":"2026-06-02T17:29:51.10431Z","end":"2026-06-02T17:29:51.21405Z","steps":["trace[459094219] 'count revisions from in-memory index tree' (duration: 109.489504ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:30:23.943975Z","caller":"traceutil/trace.go:171","msg":"trace[1643126545] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"140.934513ms","start":"2026-06-02T17:30:23.803013Z","end":"2026-06-02T17:30:23.943947Z","steps":["trace[1643126545] 'process raft request' (duration: 140.777759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:30:28.807269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.815061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-02T17:30:28.807502Z","caller":"traceutil/trace.go:171","msg":"trace[113924756] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1936; }","duration":"141.093268ms","start":"2026-06-02T17:30:28.666387Z","end":"2026-06-02T17:30:28.80748Z","steps":["trace[113924756] 'range keys from in-memory index tree' (duration: 140.40676ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:31:12.006056Z","caller":"traceutil/trace.go:171","msg":"trace[1782469192] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"149.653743ms","start":"2026-06-02T17:31:11.856374Z","end":"2026-06-02T17:31:12.006028Z","steps":["trace[1782469192] 'process raft request' (duration: 149.452008ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:32:30.640537Z","caller":"traceutil/trace.go:171","msg":"trace[1143352914] linearizableReadLoop","detail":"{readStateIndex:2908; appliedIndex:2906; }","duration":"177.301701ms","start":"2026-06-02T17:32:30.463206Z","end":"2026-06-02T17:32:30.640508Z","steps":["trace[1143352914] 'read index received' (duration: 55.899679ms)","trace[1143352914] 'applied index is now lower than readState.Index' (duration: 121.400242ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:32:30.640701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.481665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:32:30.640739Z","caller":"traceutil/trace.go:171","msg":"trace[988077849] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2802; }","duration":"177.578568ms","start":"2026-06-02T17:32:30.46315Z","end":"2026-06-02T17:32:30.640729Z","steps":["trace[988077849] 'agreement among raft nodes before linearized reading' (duration: 177.462125ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:32:30.640824Z","caller":"traceutil/trace.go:171","msg":"trace[384453147] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"192.468341ms","start":"2026-06-02T17:32:30.448327Z","end":"2026-06-02T17:32:30.640795Z","steps":["trace[384453147] 'process raft request' (duration: 114.087355ms)","trace[384453147] 'compare' (duration: 77.72724ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:34:50.067297Z","caller":"traceutil/trace.go:171","msg":"trace[22335091] linearizableReadLoop","detail":"{readStateIndex:3881; appliedIndex:3880; }","duration":"115.534889ms","start":"2026-06-02T17:34:49.951737Z","end":"2026-06-02T17:34:50.067272Z","steps":["trace[22335091] 'read index received' (duration: 115.398996ms)","trace[22335091] 'applied index is now lower than readState.Index' (duration: 134.813µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:34:50.067435Z","caller":"traceutil/trace.go:171","msg":"trace[1724470170] transaction","detail":"{read_only:false; response_revision:3740; number_of_response:1; }","duration":"117.031697ms","start":"2026-06-02T17:34:49.950393Z","end":"2026-06-02T17:34:50.067425Z","steps":["trace[1724470170] 'process raft request' (duration: 116.716309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:34:50.067614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.962754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T17:34:50.067692Z","caller":"traceutil/trace.go:171","msg":"trace[673093168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3740; }","duration":"110.074727ms","start":"2026-06-02T17:34:49.957599Z","end":"2026-06-02T17:34:50.067674Z","steps":["trace[673093168] 'agreement among raft nodes before linearized reading' (duration: 109.899092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:34:50.067892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.148904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T17:34:50.067927Z","caller":"traceutil/trace.go:171","msg":"trace[340187373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3740; }","duration":"116.204485ms","start":"2026-06-02T17:34:49.951714Z","end":"2026-06-02T17:34:50.067919Z","steps":["trace[340187373] 'agreement among raft nodes before linearized reading' (duration: 116.113053ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:34:55.430837Z","caller":"traceutil/trace.go:171","msg":"trace[1852099714] transaction","detail":"{read_only:false; response_revision:3771; number_of_response:1; }","duration":"108.17804ms","start":"2026-06-02T17:34:55.322633Z","end":"2026-06-02T17:34:55.430811Z","steps":["trace[1852099714] 'process raft request' (duration: 108.021747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:12.766269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.095913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f01539e-9b37-4dfa-9e99-cf84c79f076d\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-06-02T17:36:12.766468Z","caller":"traceutil/trace.go:171","msg":"trace[710020597] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f01539e-9b37-4dfa-9e99-cf84c79f076d; range_end:; response_count:1; response_revision:4723; }","duration":"118.340769ms","start":"2026-06-02T17:36:12.648098Z","end":"2026-06-02T17:36:12.766438Z","steps":["trace[710020597] 'range keys from in-memory index tree' (duration: 117.883848ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:18.993215Z","caller":"traceutil/trace.go:171","msg":"trace[1338291351] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5121; }","duration":"175.595006ms","start":"2026-06-02T17:36:18.817597Z","end":"2026-06-02T17:36:18.993192Z","steps":["trace[1338291351] 'read index received' (duration: 175.518724ms)","trace[1338291351] 'applied index is now lower than readState.Index' (duration: 75.352µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:18.993285Z","caller":"traceutil/trace.go:171","msg":"trace[576317468] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4936; }","duration":"239.564126ms","start":"2026-06-02T17:36:18.753658Z","end":"2026-06-02T17:36:18.993223Z","steps":["trace[576317468] 'process raft request' (duration: 239.467464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:18.993471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.863094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9f2a4c14-42a0-4608-906e-58ffe70954cd\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-02T17:36:18.993521Z","caller":"traceutil/trace.go:171","msg":"trace[118461246] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9f2a4c14-42a0-4608-906e-58ffe70954cd; range_end:; response_count:1; response_revision:4936; }","duration":"175.924815ms","start":"2026-06-02T17:36:18.817582Z","end":"2026-06-02T17:36:18.993507Z","steps":["trace[118461246] 'agreement among raft nodes before linearized reading' (duration: 175.76652ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:19.38481Z","caller":"traceutil/trace.go:171","msg":"trace[1436593307] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"191.813007ms","start":"2026-06-02T17:36:19.192966Z","end":"2026-06-02T17:36:19.384779Z","steps":["trace[1436593307] 'process raft request' (duration: 130.247315ms)","trace[1436593307] 'compare' (duration: 61.423239ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:19.458537Z","caller":"traceutil/trace.go:171","msg":"trace[1760026786] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"265.243415ms","start":"2026-06-02T17:36:19.193261Z","end":"2026-06-02T17:36:19.458505Z","steps":["trace[1760026786] 'process raft request' (duration: 265.07134ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:19.458686Z","caller":"traceutil/trace.go:171","msg":"trace[622416586] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"166.138869ms","start":"2026-06-02T17:36:19.292507Z","end":"2026-06-02T17:36:19.458646Z","steps":["trace[622416586] 'process raft request' (duration: 165.957085ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:19.458728Z","caller":"traceutil/trace.go:171","msg":"trace[71614757] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"263.347149ms","start":"2026-06-02T17:36:19.19535Z","end":"2026-06-02T17:36:19.458697Z","steps":["trace[71614757] 'process raft request' (duration: 263.080532ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:26.277447Z","caller":"traceutil/trace.go:171","msg":"trace[696724402] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"100.682028ms","start":"2026-06-02T17:36:26.176735Z","end":"2026-06-02T17:36:26.277417Z","steps":["trace[696724402] 'process raft request' (duration: 96.780573ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:26.277508Z","caller":"traceutil/trace.go:171","msg":"trace[1170404285] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"100.067303ms","start":"2026-06-02T17:36:26.177414Z","end":"2026-06-02T17:36:26.277482Z","steps":["trace[1170404285] 'process raft request' (duration: 99.905139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:31.835331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.189483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:36:31.835464Z","caller":"traceutil/trace.go:171","msg":"trace[143747746] linearizableReadLoop","detail":"{readStateIndex:5394; appliedIndex:5393; }","duration":"317.938776ms","start":"2026-06-02T17:36:31.517505Z","end":"2026-06-02T17:36:31.835444Z","steps":["trace[143747746] 'read index received' (duration: 91.265892ms)","trace[143747746] 'applied index is now lower than readState.Index' (duration: 226.671244ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:31.835597Z","caller":"traceutil/trace.go:171","msg":"trace[1726539394] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"337.628439ms","start":"2026-06-02T17:36:31.497936Z","end":"2026-06-02T17:36:31.835564Z","steps":["trace[1726539394] 'process raft request' (duration: 110.922974ms)","trace[1726539394] 'compare' (duration: 226.05682ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:36:31.835692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:36:31.497908Z","time spent":"337.736092ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T17:36:31.83572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.200672ms","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-06-02T17:36:31.835807Z","caller":"traceutil/trace.go:171","msg":"trace[1060862200] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5200; }","duration":"318.320345ms","start":"2026-06-02T17:36:31.517463Z","end":"2026-06-02T17:36:31.835783Z","steps":["trace[1060862200] 'agreement among raft nodes before linearized reading' (duration: 318.139571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:31.835865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:36:31.517445Z","time spent":"318.405177ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-02T17:36:31.835728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.618989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:36:31.835988Z","caller":"traceutil/trace.go:171","msg":"trace[1788881498] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5200; }","duration":"240.940867ms","start":"2026-06-02T17:36:31.595031Z","end":"2026-06-02T17:36:31.835972Z","steps":["trace[1788881498] 'agreement among raft nodes before linearized reading' (duration: 240.598709ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:39.323488Z","caller":"traceutil/trace.go:171","msg":"trace[217438366] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"116.140069ms","start":"2026-06-02T17:36:39.207321Z","end":"2026-06-02T17:36:39.323461Z","steps":["trace[217438366] 'process raft request' (duration: 47.842798ms)","trace[217438366] 'compare' (duration: 68.173418ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:39.392865Z","caller":"traceutil/trace.go:171","msg":"trace[1099124625] linearizableReadLoop","detail":"{readStateIndex:5593; appliedIndex:5591; }","duration":"168.100967ms","start":"2026-06-02T17:36:39.224737Z","end":"2026-06-02T17:36:39.392838Z","steps":["trace[1099124625] 'read index received' (duration: 30.41212ms)","trace[1099124625] 'applied index is now lower than readState.Index' (duration: 137.687827ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:39.393036Z","caller":"traceutil/trace.go:171","msg":"trace[402898248] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"178.684891ms","start":"2026-06-02T17:36:39.214312Z","end":"2026-06-02T17:36:39.392997Z","steps":["trace[402898248] 'process raft request' (duration: 178.374003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:39.399649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.832529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224963"} {"level":"info","ts":"2026-06-02T17:36:39.40064Z","caller":"traceutil/trace.go:171","msg":"trace[1110777625] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5394; }","duration":"175.444724ms","start":"2026-06-02T17:36:39.22468Z","end":"2026-06-02T17:36:39.400125Z","steps":["trace[1110777625] 'agreement among raft nodes before linearized reading' (duration: 168.294712ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:39.627988Z","caller":"traceutil/trace.go:171","msg":"trace[833326109] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5593; }","duration":"227.022872ms","start":"2026-06-02T17:36:39.400934Z","end":"2026-06-02T17:36:39.627956Z","steps":["trace[833326109] 'read index received' (duration: 178.045326ms)","trace[833326109] 'applied index is now lower than readState.Index' (duration: 48.976506ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:39.628089Z","caller":"traceutil/trace.go:171","msg":"trace[476207547] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"227.164775ms","start":"2026-06-02T17:36:39.400871Z","end":"2026-06-02T17:36:39.628036Z","steps":["trace[476207547] 'process raft request' (duration: 178.170099ms)","trace[476207547] 'compare' (duration: 48.788041ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:36:39.628246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.219887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-ffhhq\" ","response":"range_response_count:1 size:4040"} {"level":"warn","ts":"2026-06-02T17:36:39.628261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.297169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb4393e2-6a9d-497d-b0bb-af28711712a4\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-06-02T17:36:39.628296Z","caller":"traceutil/trace.go:171","msg":"trace[717929761] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-ffhhq; range_end:; response_count:1; response_revision:5395; }","duration":"227.307529ms","start":"2026-06-02T17:36:39.400973Z","end":"2026-06-02T17:36:39.628281Z","steps":["trace[717929761] 'agreement among raft nodes before linearized reading' (duration: 227.148915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:39.628315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.243683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-06-02T17:36:39.628334Z","caller":"traceutil/trace.go:171","msg":"trace[1219897134] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bb4393e2-6a9d-497d-b0bb-af28711712a4; range_end:; response_count:1; response_revision:5395; }","duration":"227.406732ms","start":"2026-06-02T17:36:39.400907Z","end":"2026-06-02T17:36:39.628314Z","steps":["trace[1219897134] 'agreement among raft nodes before linearized reading' (duration: 227.144365ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:36:39.628384Z","caller":"traceutil/trace.go:171","msg":"trace[561662381] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5395; }","duration":"201.347035ms","start":"2026-06-02T17:36:39.42702Z","end":"2026-06-02T17:36:39.628367Z","steps":["trace[561662381] 'agreement among raft nodes before linearized reading' (duration: 201.152719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:39.896551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.183215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:36:39.896757Z","caller":"traceutil/trace.go:171","msg":"trace[1621299520] linearizableReadLoop","detail":"{readStateIndex:5597; appliedIndex:5595; }","duration":"169.440308ms","start":"2026-06-02T17:36:39.727299Z","end":"2026-06-02T17:36:39.896739Z","steps":["trace[1621299520] 'read index received' (duration: 11.980807ms)","trace[1621299520] 'applied index is now lower than readState.Index' (duration: 157.458251ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:39.896741Z","caller":"traceutil/trace.go:171","msg":"trace[170334826] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"171.805236ms","start":"2026-06-02T17:36:39.724911Z","end":"2026-06-02T17:36:39.896717Z","steps":["trace[170334826] 'process raft request' (duration: 14.358645ms)","trace[170334826] 'compare' (duration: 157.077132ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:36:39.896882Z","caller":"traceutil/trace.go:171","msg":"trace[1753680096] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"171.919879ms","start":"2026-06-02T17:36:39.724921Z","end":"2026-06-02T17:36:39.89684Z","steps":["trace[1753680096] 'process raft request' (duration: 171.731424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:36:39.896945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.631033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-06-02T17:36:39.897007Z","caller":"traceutil/trace.go:171","msg":"trace[278425272] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5398; }","duration":"169.691934ms","start":"2026-06-02T17:36:39.727294Z","end":"2026-06-02T17:36:39.896986Z","steps":["trace[278425272] 'agreement among raft nodes before linearized reading' (duration: 169.51417ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:37:59.732381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2962} {"level":"info","ts":"2026-06-02T17:37:59.799561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2962,"took":"65.524336ms","hash":1913530258,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":34095104,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T17:37:59.799686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1913530258,"revision":2962,"compact-revision":-1} {"level":"warn","ts":"2026-06-02T17:42:38.912818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.728102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T17:42:38.913067Z","caller":"traceutil/trace.go:171","msg":"trace[101277244] linearizableReadLoop","detail":"{readStateIndex:8441; appliedIndex:8438; }","duration":"432.084986ms","start":"2026-06-02T17:42:38.480952Z","end":"2026-06-02T17:42:38.913037Z","steps":["trace[101277244] 'read index received' (duration: 233.926µs)","trace[101277244] 'applied index is now lower than readState.Index' (duration: 431.85019ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:42:38.91316Z","caller":"traceutil/trace.go:171","msg":"trace[305437719] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"442.34722ms","start":"2026-06-02T17:42:38.470803Z","end":"2026-06-02T17:42:38.91315Z","steps":["trace[305437719] 'process raft request' (duration: 442.132505ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:42:38.913199Z","caller":"traceutil/trace.go:171","msg":"trace[1215233720] transaction","detail":"{read_only:false; response_revision:8154; number_of_response:1; }","duration":"442.405892ms","start":"2026-06-02T17:42:38.470764Z","end":"2026-06-02T17:42:38.91317Z","steps":["trace[1215233720] 'process raft request' (duration: 318.231259ms)","trace[1215233720] 'compare' (duration: 123.403255ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:42:38.913232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:42:38.470775Z","time spent":"442.423712ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T17:42:38.913348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:42:38.470744Z","time spent":"442.521615ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T17:42:38.913598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.035968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dngf8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-02T17:42:38.913685Z","caller":"traceutil/trace.go:171","msg":"trace[746680753] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dngf8; range_end:; response_count:1; response_revision:8155; }","duration":"263.168571ms","start":"2026-06-02T17:42:38.650496Z","end":"2026-06-02T17:42:38.913664Z","steps":["trace[746680753] 'agreement among raft nodes before linearized reading' (duration: 262.942486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:42:38.913735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.774352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-85b568d88f-wtwtp.18b55482ce65b208\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-06-02T17:42:38.913808Z","caller":"traceutil/trace.go:171","msg":"trace[1259616852] range","detail":"{range_begin:/registry/events/openstack/barbican-api-85b568d88f-wtwtp.18b55482ce65b208; range_end:; response_count:1; response_revision:8155; }","duration":"432.880345ms","start":"2026-06-02T17:42:38.480907Z","end":"2026-06-02T17:42:38.913787Z","steps":["trace[1259616852] 'agreement among raft nodes before linearized reading' (duration: 432.6403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:42:38.913851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:42:38.48089Z","time spent":"432.950956ms","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1158,"request content":"key:\"/registry/events/openstack/barbican-api-85b568d88f-wtwtp.18b55482ce65b208\" "} {"level":"info","ts":"2026-06-02T17:42:50.210519Z","caller":"traceutil/trace.go:171","msg":"trace[78812938] linearizableReadLoop","detail":"{readStateIndex:8505; appliedIndex:8504; }","duration":"215.976771ms","start":"2026-06-02T17:42:49.994517Z","end":"2026-06-02T17:42:50.210494Z","steps":["trace[78812938] 'read index received' (duration: 215.818108ms)","trace[78812938] 'applied index is now lower than readState.Index' (duration: 157.223µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:42:50.210717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.178366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:42:50.210758Z","caller":"traceutil/trace.go:171","msg":"trace[1896227027] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8216; }","duration":"216.238157ms","start":"2026-06-02T17:42:49.994509Z","end":"2026-06-02T17:42:50.210747Z","steps":["trace[1896227027] 'agreement among raft nodes before linearized reading' (duration: 216.149815ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:42:50.210837Z","caller":"traceutil/trace.go:171","msg":"trace[474227404] transaction","detail":"{read_only:false; response_revision:8216; number_of_response:1; }","duration":"328.074326ms","start":"2026-06-02T17:42:49.882734Z","end":"2026-06-02T17:42:50.210808Z","steps":["trace[474227404] 'process raft request' (duration: 327.610875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:42:50.210985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:42:49.882701Z","time spent":"328.208778ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T17:42:50.324902Z","caller":"traceutil/trace.go:171","msg":"trace[2119217243] transaction","detail":"{read_only:false; response_revision:8217; number_of_response:1; }","duration":"324.296545ms","start":"2026-06-02T17:42:50.000567Z","end":"2026-06-02T17:42:50.324864Z","steps":["trace[2119217243] 'process raft request' (duration: 302.339059ms)","trace[2119217243] 'compare' (duration: 21.835623ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:42:50.325254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:42:50.000547Z","time spent":"324.452369ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T17:42:51.144521Z","caller":"traceutil/trace.go:171","msg":"trace[412262796] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"176.215299ms","start":"2026-06-02T17:42:50.968279Z","end":"2026-06-02T17:42:51.144494Z","steps":["trace[412262796] 'process raft request' (duration: 176.012714ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:42:51.212361Z","caller":"traceutil/trace.go:171","msg":"trace[1257835620] linearizableReadLoop","detail":"{readStateIndex:8509; appliedIndex:8507; }","duration":"238.796157ms","start":"2026-06-02T17:42:50.973545Z","end":"2026-06-02T17:42:51.212342Z","steps":["trace[1257835620] 'read index received' (duration: 170.82493ms)","trace[1257835620] 'applied index is now lower than readState.Index' (duration: 67.969817ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:42:51.212615Z","caller":"traceutil/trace.go:171","msg":"trace[664268711] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"241.583664ms","start":"2026-06-02T17:42:50.970974Z","end":"2026-06-02T17:42:51.212557Z","steps":["trace[664268711] 'process raft request' (duration: 191.733651ms)","trace[664268711] 'compare' (duration: 49.545366ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:42:51.212691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.114415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dngf8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-02T17:42:51.212986Z","caller":"traceutil/trace.go:171","msg":"trace[563600185] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dngf8; range_end:; response_count:1; response_revision:8220; }","duration":"239.450553ms","start":"2026-06-02T17:42:50.973511Z","end":"2026-06-02T17:42:51.212962Z","steps":["trace[563600185] 'agreement among raft nodes before linearized reading' (duration: 238.921841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:42:51.217432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.949202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:42:51.217536Z","caller":"traceutil/trace.go:171","msg":"trace[1179352935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8221; }","duration":"211.097835ms","start":"2026-06-02T17:42:51.006413Z","end":"2026-06-02T17:42:51.217511Z","steps":["trace[1179352935] 'agreement among raft nodes before linearized reading' (duration: 210.88857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:42:51.217606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.081308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T17:42:51.217683Z","caller":"traceutil/trace.go:171","msg":"trace[1376159054] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8221; }","duration":"194.201521ms","start":"2026-06-02T17:42:51.023465Z","end":"2026-06-02T17:42:51.217667Z","steps":["trace[1376159054] 'agreement among raft nodes before linearized reading' (duration: 193.994486ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:42:59.738929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6072} {"level":"info","ts":"2026-06-02T17:42:59.831097Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6072,"took":"90.290472ms","hash":934184,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":23678976,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-02T17:42:59.831172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":934184,"revision":6072,"compact-revision":2962} {"level":"warn","ts":"2026-06-02T17:43:19.359589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.092814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:43:19.3597Z","caller":"traceutil/trace.go:171","msg":"trace[1534422699] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:8426; }","duration":"208.283347ms","start":"2026-06-02T17:43:19.151397Z","end":"2026-06-02T17:43:19.35968Z","steps":["trace[1534422699] 'count revisions from in-memory index tree' (duration: 208.016531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:43:19.359721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.2612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:43:19.359965Z","caller":"traceutil/trace.go:171","msg":"trace[1403662178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8426; }","duration":"353.536815ms","start":"2026-06-02T17:43:19.006393Z","end":"2026-06-02T17:43:19.35993Z","steps":["trace[1403662178] 'range keys from in-memory index tree' (duration: 353.192988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:43:19.360045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:43:19.006376Z","time spent":"353.651439ms","remote":"127.0.0.1:58052","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-06-02T17:43:21.797437Z","caller":"traceutil/trace.go:171","msg":"trace[319864533] linearizableReadLoop","detail":"{readStateIndex:8738; appliedIndex:8737; }","duration":"166.982748ms","start":"2026-06-02T17:43:21.630427Z","end":"2026-06-02T17:43:21.79741Z","steps":["trace[319864533] 'read index received' (duration: 83.1208ms)","trace[319864533] 'applied index is now lower than readState.Index' (duration: 83.860538ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:43:21.797515Z","caller":"traceutil/trace.go:171","msg":"trace[1773032209] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"188.058203ms","start":"2026-06-02T17:43:21.609415Z","end":"2026-06-02T17:43:21.797473Z","steps":["trace[1773032209] 'process raft request' (duration: 104.181374ms)","trace[1773032209] 'compare' (duration: 83.697025ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T17:43:21.797641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.172953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T17:43:21.79774Z","caller":"traceutil/trace.go:171","msg":"trace[180922139] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8440; }","duration":"167.305786ms","start":"2026-06-02T17:43:21.630413Z","end":"2026-06-02T17:43:21.797719Z","steps":["trace[180922139] 'agreement among raft nodes before linearized reading' (duration: 167.129421ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:43:31.432261Z","caller":"traceutil/trace.go:171","msg":"trace[390748737] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8529; }","duration":"111.012059ms","start":"2026-06-02T17:43:31.321217Z","end":"2026-06-02T17:43:31.432229Z","steps":["trace[390748737] 'process raft request' (duration: 103.401736ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:43:31.444567Z","caller":"traceutil/trace.go:171","msg":"trace[1242510119] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"120.841713ms","start":"2026-06-02T17:43:31.323706Z","end":"2026-06-02T17:43:31.444548Z","steps":["trace[1242510119] 'process raft request' (duration: 120.760971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:43:32.097742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.733233ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:43:32.09787Z","caller":"traceutil/trace.go:171","msg":"trace[209807574] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8536; }","duration":"103.882248ms","start":"2026-06-02T17:43:31.99397Z","end":"2026-06-02T17:43:32.097852Z","steps":["trace[209807574] 'range keys from in-memory index tree' (duration: 103.715013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:43:32.097849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.76469ms","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-06-02T17:43:32.09794Z","caller":"traceutil/trace.go:171","msg":"trace[474814459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8536; }","duration":"102.900224ms","start":"2026-06-02T17:43:31.99502Z","end":"2026-06-02T17:43:32.09792Z","steps":["trace[474814459] 'range keys from in-memory index tree' (duration: 102.593336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:43:32.098335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.517838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T17:43:32.098482Z","caller":"traceutil/trace.go:171","msg":"trace[425967523] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8536; }","duration":"103.739834ms","start":"2026-06-02T17:43:31.994711Z","end":"2026-06-02T17:43:32.098451Z","steps":["trace[425967523] 'range keys from in-memory index tree' (duration: 102.926605ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:43:32.144141Z","caller":"traceutil/trace.go:171","msg":"trace[1501852812] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"103.776095ms","start":"2026-06-02T17:43:32.040339Z","end":"2026-06-02T17:43:32.144115Z","steps":["trace[1501852812] 'process raft request' (duration: 103.629471ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:43:47.45835Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002319.wal"} {"level":"info","ts":"2026-06-02T17:45:39.779014Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T17:45:39.794634Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-02T17:45:39.794788Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-02T17:47:05.052436Z","caller":"traceutil/trace.go:171","msg":"trace[200991124] transaction","detail":"{read_only:false; response_revision:10329; number_of_response:1; }","duration":"101.161315ms","start":"2026-06-02T17:47:04.951241Z","end":"2026-06-02T17:47:05.052402Z","steps":["trace[200991124] 'process raft request' (duration: 78.471549ms)","trace[200991124] 'compare' (duration: 22.279125ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:47:59.746345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8272} {"level":"info","ts":"2026-06-02T17:47:59.822528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8272,"took":"73.94487ms","hash":1067326590,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":26460160,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-02T17:47:59.822623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1067326590,"revision":8272,"compact-revision":6072} {"level":"info","ts":"2026-06-02T17:48:29.278513Z","caller":"traceutil/trace.go:171","msg":"trace[1193370427] linearizableReadLoop","detail":"{readStateIndex:11496; appliedIndex:11495; }","duration":"115.237303ms","start":"2026-06-02T17:48:29.163225Z","end":"2026-06-02T17:48:29.278462Z","steps":["trace[1193370427] 'read index received' (duration: 115.011558ms)","trace[1193370427] 'applied index is now lower than readState.Index' (duration: 224.745µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:48:29.278625Z","caller":"traceutil/trace.go:171","msg":"trace[1397403897] transaction","detail":"{read_only:false; response_revision:11120; number_of_response:1; }","duration":"137.344357ms","start":"2026-06-02T17:48:29.141225Z","end":"2026-06-02T17:48:29.278569Z","steps":["trace[1397403897] 'process raft request' (duration: 137.09767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:48:29.278945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.707405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T17:48:29.279074Z","caller":"traceutil/trace.go:171","msg":"trace[576918836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11120; }","duration":"115.884549ms","start":"2026-06-02T17:48:29.163169Z","end":"2026-06-02T17:48:29.279053Z","steps":["trace[576918836] 'agreement among raft nodes before linearized reading' (duration: 115.583322ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:48:29.418365Z","caller":"traceutil/trace.go:171","msg":"trace[2033855401] transaction","detail":"{read_only:false; response_revision:11121; number_of_response:1; }","duration":"135.183953ms","start":"2026-06-02T17:48:29.283129Z","end":"2026-06-02T17:48:29.418313Z","steps":["trace[2033855401] 'process raft request' (duration: 73.386346ms)","trace[2033855401] 'compare' (duration: 61.414708ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:48:29.455554Z","caller":"traceutil/trace.go:171","msg":"trace[1553640285] transaction","detail":"{read_only:false; response_revision:11122; number_of_response:1; }","duration":"112.544969ms","start":"2026-06-02T17:48:29.342975Z","end":"2026-06-02T17:48:29.45552Z","steps":["trace[1553640285] 'process raft request' (duration: 112.374785ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:52:59.753688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10930} {"level":"info","ts":"2026-06-02T17:52:59.839831Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10930,"took":"83.50421ms","hash":3033961413,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":28106752,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T17:52:59.839903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3033961413,"revision":10930,"compact-revision":8272} {"level":"info","ts":"2026-06-02T17:54:36.98431Z","caller":"traceutil/trace.go:171","msg":"trace[2056085616] transaction","detail":"{read_only:false; response_revision:13608; number_of_response:1; }","duration":"110.299274ms","start":"2026-06-02T17:54:36.873979Z","end":"2026-06-02T17:54:36.984278Z","steps":["trace[2056085616] 'process raft request' (duration: 110.074339ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:54:44.857056Z","caller":"traceutil/trace.go:171","msg":"trace[1273876212] linearizableReadLoop","detail":"{readStateIndex:14109; appliedIndex:14108; }","duration":"108.759528ms","start":"2026-06-02T17:54:44.748264Z","end":"2026-06-02T17:54:44.857023Z","steps":["trace[1273876212] 'read index received' (duration: 108.580243ms)","trace[1273876212] 'applied index is now lower than readState.Index' (duration: 177.405µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:54:44.857163Z","caller":"traceutil/trace.go:171","msg":"trace[1077674236] transaction","detail":"{read_only:false; response_revision:13646; number_of_response:1; }","duration":"116.098205ms","start":"2026-06-02T17:54:44.741018Z","end":"2026-06-02T17:54:44.857116Z","steps":["trace[1077674236] 'process raft request' (duration: 115.846909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:54:44.857259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.968183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T17:54:44.857342Z","caller":"traceutil/trace.go:171","msg":"trace[152842785] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:13646; }","duration":"109.114187ms","start":"2026-06-02T17:54:44.748209Z","end":"2026-06-02T17:54:44.857323Z","steps":["trace[152842785] 'agreement among raft nodes before linearized reading' (duration: 108.947223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:54:53.353142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.88331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T17:54:53.353249Z","caller":"traceutil/trace.go:171","msg":"trace[1691365239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13707; }","duration":"175.047445ms","start":"2026-06-02T17:54:53.178172Z","end":"2026-06-02T17:54:53.35322Z","steps":["trace[1691365239] 'range keys from in-memory index tree' (duration: 174.713546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:54:53.353623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.761547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T17:54:53.353728Z","caller":"traceutil/trace.go:171","msg":"trace[550362220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13707; }","duration":"206.935311ms","start":"2026-06-02T17:54:53.146769Z","end":"2026-06-02T17:54:53.353705Z","steps":["trace[550362220] 'range keys from in-memory index tree' (duration: 206.398278ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:54:53.462998Z","caller":"traceutil/trace.go:171","msg":"trace[997980676] transaction","detail":"{read_only:false; response_revision:13708; number_of_response:1; }","duration":"105.946161ms","start":"2026-06-02T17:54:53.357024Z","end":"2026-06-02T17:54:53.46297Z","steps":["trace[997980676] 'process raft request' (duration: 105.796717ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:54:53.495364Z","caller":"traceutil/trace.go:171","msg":"trace[180320988] transaction","detail":"{read_only:false; response_revision:13710; number_of_response:1; }","duration":"136.586527ms","start":"2026-06-02T17:54:53.358758Z","end":"2026-06-02T17:54:53.495344Z","steps":["trace[180320988] 'process raft request' (duration: 136.551196ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:54:53.495466Z","caller":"traceutil/trace.go:171","msg":"trace[37980565] transaction","detail":"{read_only:false; response_revision:13709; number_of_response:1; }","duration":"138.106384ms","start":"2026-06-02T17:54:53.357312Z","end":"2026-06-02T17:54:53.495419Z","steps":["trace[37980565] 'process raft request' (duration: 137.851798ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:55:02.514219Z","caller":"traceutil/trace.go:171","msg":"trace[548712006] linearizableReadLoop","detail":"{readStateIndex:14342; appliedIndex:14341; }","duration":"190.941886ms","start":"2026-06-02T17:55:02.323252Z","end":"2026-06-02T17:55:02.514194Z","steps":["trace[548712006] 'read index received' (duration: 190.734731ms)","trace[548712006] 'applied index is now lower than readState.Index' (duration: 206.285µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:55:02.514262Z","caller":"traceutil/trace.go:171","msg":"trace[711733800] transaction","detail":"{read_only:false; response_revision:13874; number_of_response:1; }","duration":"334.637686ms","start":"2026-06-02T17:55:02.179599Z","end":"2026-06-02T17:55:02.514236Z","steps":["trace[711733800] 'process raft request' (duration: 334.446901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:55:02.514369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.10308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T17:55:02.514409Z","caller":"traceutil/trace.go:171","msg":"trace[828815418] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:13874; }","duration":"191.191172ms","start":"2026-06-02T17:55:02.323203Z","end":"2026-06-02T17:55:02.514395Z","steps":["trace[828815418] 'agreement among raft nodes before linearized reading' (duration: 191.116961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T17:55:02.514731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T17:55:02.179585Z","time spent":"334.751319ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7255,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T17:55:02.672955Z","caller":"traceutil/trace.go:171","msg":"trace[1318335737] transaction","detail":"{read_only:false; response_revision:13875; number_of_response:1; }","duration":"142.805157ms","start":"2026-06-02T17:55:02.530088Z","end":"2026-06-02T17:55:02.672893Z","steps":["trace[1318335737] 'process raft request' (duration: 142.618083ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T17:56:41.451285Z","caller":"traceutil/trace.go:171","msg":"trace[119117085] transaction","detail":"{read_only:false; response_revision:14961; number_of_response:1; }","duration":"125.42993ms","start":"2026-06-02T17:56:41.325824Z","end":"2026-06-02T17:56:41.451254Z","steps":["trace[119117085] 'process raft request' (duration: 84.33388ms)","trace[119117085] 'compare' (duration: 40.977547ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T17:57:59.765066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12974} {"level":"info","ts":"2026-06-02T17:57:59.836497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12974,"took":"68.816961ms","hash":3289209539,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T17:57:59.836595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3289209539,"revision":12974,"compact-revision":10930} {"level":"warn","ts":"2026-06-02T18:02:01.715999Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.12329119s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T18:02:01.717608Z","caller":"traceutil/trace.go:171","msg":"trace[1743489967] transaction","detail":"{read_only:false; response_revision:17429; number_of_response:1; }","duration":"1.125001292s","start":"2026-06-02T18:02:00.592578Z","end":"2026-06-02T18:02:01.71758Z","steps":["trace[1743489967] 'process raft request' (duration: 1.124771826s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.717782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:00.59256Z","time spent":"1.125129165s","remote":"127.0.0.1:58324","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-06-02T18:02:01.399173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114765544017775,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T18:02:01.720122Z","caller":"traceutil/trace.go:171","msg":"trace[1582815009] linearizableReadLoop","detail":"{readStateIndex:18009; appliedIndex:18009; }","duration":"1.084043945s","start":"2026-06-02T18:02:00.635989Z","end":"2026-06-02T18:02:01.720033Z","steps":["trace[1582815009] 'read index received' (duration: 1.084039545s)","trace[1582815009] 'applied index is now lower than readState.Index' (duration: 3.04µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:02:01.720212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084213498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:02:01.72023Z","caller":"traceutil/trace.go:171","msg":"trace[482343828] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:17429; }","duration":"1.08428541s","start":"2026-06-02T18:02:00.635938Z","end":"2026-06-02T18:02:01.720224Z","steps":["trace[482343828] 'agreement among raft nodes before linearized reading' (duration: 1.084213389s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.720253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:00.635923Z","time spent":"1.084326241s","remote":"127.0.0.1:58272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-02T18:02:01.791202Z","caller":"traceutil/trace.go:171","msg":"trace[746034897] transaction","detail":"{read_only:false; response_revision:17430; number_of_response:1; }","duration":"1.12161973s","start":"2026-06-02T18:02:00.669563Z","end":"2026-06-02T18:02:01.791183Z","steps":["trace[746034897] 'process raft request' (duration: 1.121269012s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.791307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:00.669541Z","time spent":"1.121709832s","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:02:01.791647Z","caller":"traceutil/trace.go:171","msg":"trace[994521542] transaction","detail":"{read_only:false; response_revision:17431; number_of_response:1; }","duration":"386.462953ms","start":"2026-06-02T18:02:01.405177Z","end":"2026-06-02T18:02:01.79164Z","steps":["trace[994521542] 'process raft request' (duration: 385.767956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.791689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:01.405159Z","time spent":"386.506494ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9136,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:02:01.791899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.763573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:23 size:211824"} {"level":"info","ts":"2026-06-02T18:02:01.791976Z","caller":"traceutil/trace.go:171","msg":"trace[111613210] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:23; response_revision:17431; }","duration":"899.885766ms","start":"2026-06-02T18:02:00.892071Z","end":"2026-06-02T18:02:01.791957Z","steps":["trace[111613210] 'agreement among raft nodes before linearized reading' (duration: 899.445336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:01.792112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:00.892051Z","time spent":"899.956958ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":23,"response size":211847,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-06-02T18:02:30.415988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.042374ms","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-06-02T18:02:30.416097Z","caller":"traceutil/trace.go:171","msg":"trace[195511632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17651; }","duration":"135.183968ms","start":"2026-06-02T18:02:30.280885Z","end":"2026-06-02T18:02:30.416069Z","steps":["trace[195511632] 'range keys from in-memory index tree' (duration: 134.86992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:30.416043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.491182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6g5mb\" ","response":"range_response_count:1 size:20865"} {"level":"info","ts":"2026-06-02T18:02:30.416236Z","caller":"traceutil/trace.go:171","msg":"trace[461836084] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6g5mb; range_end:; response_count:1; response_revision:17651; }","duration":"210.722158ms","start":"2026-06-02T18:02:30.205492Z","end":"2026-06-02T18:02:30.416214Z","steps":["trace[461836084] 'range keys from in-memory index tree' (duration: 210.344119ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:02:30.913567Z","caller":"traceutil/trace.go:171","msg":"trace[1683395129] linearizableReadLoop","detail":"{readStateIndex:18239; appliedIndex:18238; }","duration":"447.907124ms","start":"2026-06-02T18:02:30.465639Z","end":"2026-06-02T18:02:30.913546Z","steps":["trace[1683395129] 'read index received' (duration: 37.146325ms)","trace[1683395129] 'applied index is now lower than readState.Index' (duration: 410.760029ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:02:30.913773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.181346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-8n4xw\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-06-02T18:02:30.913798Z","caller":"traceutil/trace.go:171","msg":"trace[2090462956] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-8n4xw; range_end:; response_count:1; response_revision:17653; }","duration":"349.261798ms","start":"2026-06-02T18:02:30.564529Z","end":"2026-06-02T18:02:30.91379Z","steps":["trace[2090462956] 'agreement among raft nodes before linearized reading' (duration: 349.137515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:30.913824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:30.564507Z","time spent":"349.311278ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-8n4xw\" "} {"level":"warn","ts":"2026-06-02T18:02:30.913846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.175841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:02:30.913935Z","caller":"traceutil/trace.go:171","msg":"trace[1100697082] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:17653; }","duration":"448.360955ms","start":"2026-06-02T18:02:30.465555Z","end":"2026-06-02T18:02:30.913916Z","steps":["trace[1100697082] 'agreement among raft nodes before linearized reading' (duration: 448.106989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:30.913972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:30.465526Z","time spent":"448.435807ms","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":78,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-06-02T18:02:30.914339Z","caller":"traceutil/trace.go:171","msg":"trace[1948241724] transaction","detail":"{read_only:false; response_revision:17653; number_of_response:1; }","duration":"482.133948ms","start":"2026-06-02T18:02:30.431485Z","end":"2026-06-02T18:02:30.913619Z","steps":["trace[1948241724] 'process raft request' (duration: 481.919223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:02:30.914444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:02:30.431457Z","time spent":"482.924388ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20980,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:02:59.771502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15483} {"level":"info","ts":"2026-06-02T18:02:59.863246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15483,"took":"88.983475ms","hash":3928639794,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":33792000,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T18:02:59.863334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3928639794,"revision":15483,"compact-revision":12974} {"level":"info","ts":"2026-06-02T18:03:22.405719Z","caller":"traceutil/trace.go:171","msg":"trace[1018287018] transaction","detail":"{read_only:false; response_revision:18264; number_of_response:1; }","duration":"144.706297ms","start":"2026-06-02T18:03:22.260987Z","end":"2026-06-02T18:03:22.405694Z","steps":["trace[1018287018] 'process raft request' (duration: 144.645796ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:03:22.405984Z","caller":"traceutil/trace.go:171","msg":"trace[1807525526] transaction","detail":"{read_only:false; response_revision:18263; number_of_response:1; }","duration":"149.016931ms","start":"2026-06-02T18:03:22.256795Z","end":"2026-06-02T18:03:22.405812Z","steps":["trace[1807525526] 'process raft request' (duration: 68.339876ms)","trace[1807525526] 'compare' (duration: 80.335477ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:03:22.423987Z","caller":"traceutil/trace.go:171","msg":"trace[84651110] transaction","detail":"{read_only:false; response_revision:18265; number_of_response:1; }","duration":"113.74892ms","start":"2026-06-02T18:03:22.310202Z","end":"2026-06-02T18:03:22.423951Z","steps":["trace[84651110] 'process raft request' (duration: 113.559536ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:05:52.005196Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":20006,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T18:05:52.009704Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20006} {"level":"info","ts":"2026-06-02T18:05:52.009824Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15006} {"level":"info","ts":"2026-06-02T18:07:17.956441Z","caller":"traceutil/trace.go:171","msg":"trace[1020515108] linearizableReadLoop","detail":"{readStateIndex:20634; appliedIndex:20633; }","duration":"114.859287ms","start":"2026-06-02T18:07:17.841556Z","end":"2026-06-02T18:07:17.956415Z","steps":["trace[1020515108] 'read index received' (duration: 114.632272ms)","trace[1020515108] 'applied index is now lower than readState.Index' (duration: 225.175µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:07:17.956594Z","caller":"traceutil/trace.go:171","msg":"trace[973802594] transaction","detail":"{read_only:false; response_revision:19975; number_of_response:1; }","duration":"122.482951ms","start":"2026-06-02T18:07:17.834045Z","end":"2026-06-02T18:07:17.956528Z","steps":["trace[973802594] 'process raft request' (duration: 122.164503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:07:17.956803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.126553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:07:17.956991Z","caller":"traceutil/trace.go:171","msg":"trace[609582939] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:19975; }","duration":"115.43312ms","start":"2026-06-02T18:07:17.841506Z","end":"2026-06-02T18:07:17.956939Z","steps":["trace[609582939] 'agreement among raft nodes before linearized reading' (duration: 115.01064ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:07:45.474201Z","caller":"traceutil/trace.go:171","msg":"trace[926047805] linearizableReadLoop","detail":"{readStateIndex:20780; appliedIndex:20779; }","duration":"254.287235ms","start":"2026-06-02T18:07:45.219888Z","end":"2026-06-02T18:07:45.474176Z","steps":["trace[926047805] 'read index received' (duration: 254.050949ms)","trace[926047805] 'applied index is now lower than readState.Index' (duration: 235.366µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:07:45.474343Z","caller":"traceutil/trace.go:171","msg":"trace[1271152578] transaction","detail":"{read_only:false; response_revision:20114; number_of_response:1; }","duration":"338.399272ms","start":"2026-06-02T18:07:45.135879Z","end":"2026-06-02T18:07:45.474278Z","steps":["trace[1271152578] 'process raft request' (duration: 338.114015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:07:45.474377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.465169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:07:45.474424Z","caller":"traceutil/trace.go:171","msg":"trace[229804588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20114; }","duration":"254.564522ms","start":"2026-06-02T18:07:45.219848Z","end":"2026-06-02T18:07:45.474413Z","steps":["trace[229804588] 'agreement among raft nodes before linearized reading' (duration: 254.430829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:07:45.47454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:07:45.135841Z","time spent":"338.582856ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:07:59.778115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18086} {"level":"info","ts":"2026-06-02T18:07:59.889905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18086,"took":"108.160656ms","hash":557315425,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":29642752,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T18:07:59.889972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":557315425,"revision":18086,"compact-revision":15483} {"level":"info","ts":"2026-06-02T18:11:30.017848Z","caller":"traceutil/trace.go:171","msg":"trace[875118181] linearizableReadLoop","detail":"{readStateIndex:23217; appliedIndex:23216; }","duration":"130.957752ms","start":"2026-06-02T18:11:29.886869Z","end":"2026-06-02T18:11:30.017827Z","steps":["trace[875118181] 'read index received' (duration: 107.400515ms)","trace[875118181] 'applied index is now lower than readState.Index' (duration: 23.556627ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:11:30.017938Z","caller":"traceutil/trace.go:171","msg":"trace[298154406] transaction","detail":"{read_only:false; response_revision:22482; number_of_response:1; }","duration":"131.538797ms","start":"2026-06-02T18:11:29.88639Z","end":"2026-06-02T18:11:30.017929Z","steps":["trace[298154406] 'process raft request' (duration: 107.862588ms)","trace[298154406] 'compare' (duration: 23.459484ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:11:30.018277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.389673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5182"} {"level":"info","ts":"2026-06-02T18:11:30.018404Z","caller":"traceutil/trace.go:171","msg":"trace[1940922254] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:22482; }","duration":"131.535176ms","start":"2026-06-02T18:11:29.886817Z","end":"2026-06-02T18:11:30.018352Z","steps":["trace[1940922254] 'agreement among raft nodes before linearized reading' (duration: 131.206198ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:11:30.373349Z","caller":"traceutil/trace.go:171","msg":"trace[421088365] linearizableReadLoop","detail":"{readStateIndex:23221; appliedIndex:23218; }","duration":"344.879843ms","start":"2026-06-02T18:11:30.028435Z","end":"2026-06-02T18:11:30.373314Z","steps":["trace[421088365] 'read index received' (duration: 289.227323ms)","trace[421088365] 'applied index is now lower than readState.Index' (duration: 55.65113ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:11:30.373548Z","caller":"traceutil/trace.go:171","msg":"trace[1409161585] transaction","detail":"{read_only:false; response_revision:22484; number_of_response:1; }","duration":"350.988341ms","start":"2026-06-02T18:11:30.022515Z","end":"2026-06-02T18:11:30.373503Z","steps":["trace[1409161585] 'process raft request' (duration: 295.134996ms)","trace[1409161585] 'compare' (duration: 55.325492ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:11:30.373788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.333745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:11:30.373518Z","caller":"traceutil/trace.go:171","msg":"trace[2144954785] transaction","detail":"{read_only:false; response_revision:22485; number_of_response:1; }","duration":"350.52947ms","start":"2026-06-02T18:11:30.022949Z","end":"2026-06-02T18:11:30.373479Z","steps":["trace[2144954785] 'process raft request' (duration: 350.209053ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:11:30.373781Z","caller":"traceutil/trace.go:171","msg":"trace[549100797] transaction","detail":"{read_only:false; response_revision:22486; number_of_response:1; }","duration":"349.007613ms","start":"2026-06-02T18:11:30.024713Z","end":"2026-06-02T18:11:30.37372Z","steps":["trace[549100797] 'process raft request' (duration: 348.523811ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:11:30.373853Z","caller":"traceutil/trace.go:171","msg":"trace[1608275659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22486; }","duration":"345.438457ms","start":"2026-06-02T18:11:30.028397Z","end":"2026-06-02T18:11:30.373835Z","steps":["trace[1608275659] 'agreement among raft nodes before linearized reading' (duration: 345.321044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:11:30.373864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:11:30.022483Z","time spent":"351.297088ms","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T18:11:30.373892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:11:30.028383Z","time spent":"345.501189ms","remote":"127.0.0.1:58056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T18:11:30.373931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:11:30.022931Z","time spent":"350.922019ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4303,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T18:11:30.373961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:11:30.024694Z","time spent":"349.188117ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5145,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:11:30.374865Z","caller":"traceutil/trace.go:171","msg":"trace[82469527] transaction","detail":"{read_only:false; response_revision:22487; number_of_response:1; }","duration":"346.224687ms","start":"2026-06-02T18:11:30.028619Z","end":"2026-06-02T18:11:30.374844Z","steps":["trace[82469527] 'process raft request' (duration: 345.887859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:11:30.374957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:11:30.028603Z","time spent":"346.303768ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:11:30.375013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.06574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-06-02T18:11:30.375097Z","caller":"traceutil/trace.go:171","msg":"trace[1289220131] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22488; }","duration":"115.183553ms","start":"2026-06-02T18:11:30.259893Z","end":"2026-06-02T18:11:30.375076Z","steps":["trace[1289220131] 'agreement among raft nodes before linearized reading' (duration: 114.963018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:11:30.375491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.678588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:11:30.375557Z","caller":"traceutil/trace.go:171","msg":"trace[1972636188] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:22488; }","duration":"160.786281ms","start":"2026-06-02T18:11:30.214754Z","end":"2026-06-02T18:11:30.375541Z","steps":["trace[1972636188] 'agreement among raft nodes before linearized reading' (duration: 160.042794ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:12:59.78332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20188} {"level":"info","ts":"2026-06-02T18:12:59.888656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20188,"took":"100.679065ms","hash":2298359716,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":42020864,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-02T18:12:59.888825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2298359716,"revision":20188,"compact-revision":18086} {"level":"info","ts":"2026-06-02T18:14:33.936524Z","caller":"traceutil/trace.go:171","msg":"trace[206041959] transaction","detail":"{read_only:false; response_revision:24398; number_of_response:1; }","duration":"147.609715ms","start":"2026-06-02T18:14:33.788894Z","end":"2026-06-02T18:14:33.936503Z","steps":["trace[206041959] 'process raft request' (duration: 147.487022ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:33.95425Z","caller":"traceutil/trace.go:171","msg":"trace[1426237617] transaction","detail":"{read_only:false; response_revision:24399; number_of_response:1; }","duration":"158.660022ms","start":"2026-06-02T18:14:33.79555Z","end":"2026-06-02T18:14:33.95421Z","steps":["trace[1426237617] 'process raft request' (duration: 158.452817ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:33.95425Z","caller":"traceutil/trace.go:171","msg":"trace[1881868938] linearizableReadLoop","detail":"{readStateIndex:25184; appliedIndex:25182; }","duration":"158.511699ms","start":"2026-06-02T18:14:33.795651Z","end":"2026-06-02T18:14:33.954163Z","steps":["trace[1881868938] 'read index received' (duration: 140.712569ms)","trace[1881868938] 'applied index is now lower than readState.Index' (duration: 17.79759ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:33.954435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.757654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:14:33.954509Z","caller":"traceutil/trace.go:171","msg":"trace[329131754] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:24399; }","duration":"158.868276ms","start":"2026-06-02T18:14:33.795623Z","end":"2026-06-02T18:14:33.954491Z","steps":["trace[329131754] 'agreement among raft nodes before linearized reading' (duration: 158.672212ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:38.83775Z","caller":"traceutil/trace.go:171","msg":"trace[29359846] linearizableReadLoop","detail":"{readStateIndex:25223; appliedIndex:25222; }","duration":"132.508781ms","start":"2026-06-02T18:14:38.705226Z","end":"2026-06-02T18:14:38.837734Z","steps":["trace[29359846] 'read index received' (duration: 132.323267ms)","trace[29359846] 'applied index is now lower than readState.Index' (duration: 184.944µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:38.837856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.616314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:38.837887Z","caller":"traceutil/trace.go:171","msg":"trace[377263524] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24437; }","duration":"132.663575ms","start":"2026-06-02T18:14:38.705215Z","end":"2026-06-02T18:14:38.837879Z","steps":["trace[377263524] 'agreement among raft nodes before linearized reading' (duration: 132.577813ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:38.838074Z","caller":"traceutil/trace.go:171","msg":"trace[366614513] transaction","detail":"{read_only:false; response_revision:24437; number_of_response:1; }","duration":"184.182016ms","start":"2026-06-02T18:14:38.653852Z","end":"2026-06-02T18:14:38.838034Z","steps":["trace[366614513] 'process raft request' (duration: 183.786827ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:39.319361Z","caller":"traceutil/trace.go:171","msg":"trace[1377681402] transaction","detail":"{read_only:false; response_revision:24440; number_of_response:1; }","duration":"101.088665ms","start":"2026-06-02T18:14:39.21825Z","end":"2026-06-02T18:14:39.319339Z","steps":["trace[1377681402] 'process raft request' (duration: 100.945272ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:39.934781Z","caller":"traceutil/trace.go:171","msg":"trace[1047971911] linearizableReadLoop","detail":"{readStateIndex:25228; appliedIndex:25227; }","duration":"261.632691ms","start":"2026-06-02T18:14:39.673125Z","end":"2026-06-02T18:14:39.934758Z","steps":["trace[1047971911] 'read index received' (duration: 261.19473ms)","trace[1047971911] 'applied index is now lower than readState.Index' (duration: 436.801µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:39.934928Z","caller":"traceutil/trace.go:171","msg":"trace[122229647] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"264.102431ms","start":"2026-06-02T18:14:39.67081Z","end":"2026-06-02T18:14:39.934912Z","steps":["trace[122229647] 'process raft request' (duration: 263.576758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:39.935146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.093739ms","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-06-02T18:14:39.935213Z","caller":"traceutil/trace.go:171","msg":"trace[354362513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24442; }","duration":"261.207122ms","start":"2026-06-02T18:14:39.673988Z","end":"2026-06-02T18:14:39.935195Z","steps":["trace[354362513] 'agreement among raft nodes before linearized reading' (duration: 261.030368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:39.935423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.536621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-02T18:14:39.935451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.746853ms","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-06-02T18:14:39.93546Z","caller":"traceutil/trace.go:171","msg":"trace[155409306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24442; }","duration":"259.600113ms","start":"2026-06-02T18:14:39.675848Z","end":"2026-06-02T18:14:39.935448Z","steps":["trace[155409306] 'agreement among raft nodes before linearized reading' (duration: 259.507521ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:39.935496Z","caller":"traceutil/trace.go:171","msg":"trace[1167350494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24442; }","duration":"148.832625ms","start":"2026-06-02T18:14:39.78665Z","end":"2026-06-02T18:14:39.935483Z","steps":["trace[1167350494] 'agreement among raft nodes before linearized reading' (duration: 148.655401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:39.935483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.320498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T18:14:39.935527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.706819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T18:14:39.935606Z","caller":"traceutil/trace.go:171","msg":"trace[667059490] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:24442; }","duration":"262.497932ms","start":"2026-06-02T18:14:39.673082Z","end":"2026-06-02T18:14:39.935579Z","steps":["trace[667059490] 'agreement among raft nodes before linearized reading' (duration: 262.261857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:39.93566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.498679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:14:39.935662Z","caller":"traceutil/trace.go:171","msg":"trace[223933352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24442; }","duration":"255.891414ms","start":"2026-06-02T18:14:39.67975Z","end":"2026-06-02T18:14:39.935642Z","steps":["trace[223933352] 'agreement among raft nodes before linearized reading' (duration: 255.590507ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:39.935698Z","caller":"traceutil/trace.go:171","msg":"trace[575755482] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:24442; }","duration":"102.560251ms","start":"2026-06-02T18:14:39.833123Z","end":"2026-06-02T18:14:39.935683Z","steps":["trace[575755482] 'agreement among raft nodes before linearized reading' (duration: 102.494819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:40.241322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.233577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:14:40.241727Z","caller":"traceutil/trace.go:171","msg":"trace[1752929310] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"301.317208ms","start":"2026-06-02T18:14:39.940393Z","end":"2026-06-02T18:14:40.241711Z","steps":["trace[1752929310] 'process raft request' (duration: 143.581579ms)","trace[1752929310] 'compare' (duration: 156.893619ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:40.241785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:39.94038Z","time spent":"301.377259ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:14:40.242119Z","caller":"traceutil/trace.go:171","msg":"trace[1782858827] linearizableReadLoop","detail":"{readStateIndex:25233; appliedIndex:25229; }","duration":"266.278613ms","start":"2026-06-02T18:14:39.975829Z","end":"2026-06-02T18:14:40.242107Z","steps":["trace[1782858827] 'read index received' (duration: 108.117344ms)","trace[1782858827] 'applied index is now lower than readState.Index' (duration: 158.160179ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:40.242322Z","caller":"traceutil/trace.go:171","msg":"trace[1324581299] transaction","detail":"{read_only:false; response_revision:24445; number_of_response:1; }","duration":"301.822519ms","start":"2026-06-02T18:14:39.94049Z","end":"2026-06-02T18:14:40.242312Z","steps":["trace[1324581299] 'process raft request' (duration: 301.162833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:40.242376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:39.940466Z","time spent":"301.8788ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:14:40.242492Z","caller":"traceutil/trace.go:171","msg":"trace[711431188] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"301.239465ms","start":"2026-06-02T18:14:39.941245Z","end":"2026-06-02T18:14:40.242485Z","steps":["trace[711431188] 'process raft request' (duration: 300.773564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:40.242529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:39.941237Z","time spent":"301.271085ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:14:40.242714Z","caller":"traceutil/trace.go:171","msg":"trace[1206697680] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"285.032065ms","start":"2026-06-02T18:14:39.957672Z","end":"2026-06-02T18:14:40.242704Z","steps":["trace[1206697680] 'process raft request' (duration: 284.38987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:40.24273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.882548ms","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-06-02T18:14:40.242786Z","caller":"traceutil/trace.go:171","msg":"trace[1807452986] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24447; }","duration":"266.99449ms","start":"2026-06-02T18:14:39.975775Z","end":"2026-06-02T18:14:40.24277Z","steps":["trace[1807452986] 'agreement among raft nodes before linearized reading' (duration: 266.790616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:40.591789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.854815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:14:40.592046Z","caller":"traceutil/trace.go:171","msg":"trace[616236342] transaction","detail":"{read_only:false; response_revision:24450; number_of_response:1; }","duration":"209.845975ms","start":"2026-06-02T18:14:40.382167Z","end":"2026-06-02T18:14:40.592013Z","steps":["trace[616236342] 'process raft request' (duration: 209.802114ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:40.592074Z","caller":"traceutil/trace.go:171","msg":"trace[247326950] linearizableReadLoop","detail":"{readStateIndex:25234; appliedIndex:25233; }","duration":"349.895747ms","start":"2026-06-02T18:14:40.242146Z","end":"2026-06-02T18:14:40.592042Z","steps":["trace[247326950] 'read index received' (duration: 112.710274ms)","trace[247326950] 'applied index is now lower than readState.Index' (duration: 237.176973ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:40.592178Z","caller":"traceutil/trace.go:171","msg":"trace[1877862942] transaction","detail":"{read_only:false; response_revision:24449; number_of_response:1; }","duration":"344.778554ms","start":"2026-06-02T18:14:40.247387Z","end":"2026-06-02T18:14:40.592166Z","steps":["trace[1877862942] 'process raft request' (duration: 344.514988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:40.59226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:40.24737Z","time spent":"344.846545ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:14:40.592456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.358743ms","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-06-02T18:14:40.592496Z","caller":"traceutil/trace.go:171","msg":"trace[1112646692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24450; }","duration":"302.398294ms","start":"2026-06-02T18:14:40.290088Z","end":"2026-06-02T18:14:40.592486Z","steps":["trace[1112646692] 'agreement among raft nodes before linearized reading' (duration: 302.272911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:40.592526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:40.290032Z","time spent":"302.486686ms","remote":"127.0.0.1:58324","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-06-02T18:14:40.592599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.474441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:40.592668Z","caller":"traceutil/trace.go:171","msg":"trace[1484281416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24450; }","duration":"585.569894ms","start":"2026-06-02T18:14:40.007078Z","end":"2026-06-02T18:14:40.592648Z","steps":["trace[1484281416] 'agreement among raft nodes before linearized reading' (duration: 585.464251ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:40.592691Z","caller":"traceutil/trace.go:171","msg":"trace[551514992] transaction","detail":"{read_only:false; response_revision:24448; number_of_response:1; }","duration":"404.654826ms","start":"2026-06-02T18:14:40.188008Z","end":"2026-06-02T18:14:40.592663Z","steps":["trace[551514992] 'process raft request' (duration: 166.837328ms)","trace[551514992] 'compare' (duration: 236.706472ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:40.592702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:40.007061Z","time spent":"585.634156ms","remote":"127.0.0.1:58052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T18:14:40.59283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:40.187988Z","time spent":"404.750878ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:14:41.114292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.544791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:14:41.114865Z","caller":"traceutil/trace.go:171","msg":"trace[1541215088] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24452; }","duration":"108.147875ms","start":"2026-06-02T18:14:41.006665Z","end":"2026-06-02T18:14:41.114813Z","steps":["trace[1541215088] 'count revisions from in-memory index tree' (duration: 107.444978ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:41.320622Z","caller":"traceutil/trace.go:171","msg":"trace[1071217758] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"271.895169ms","start":"2026-06-02T18:14:41.048696Z","end":"2026-06-02T18:14:41.320591Z","steps":["trace[1071217758] 'process raft request' (duration: 271.729835ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:41.584997Z","caller":"traceutil/trace.go:171","msg":"trace[43661066] linearizableReadLoop","detail":"{readStateIndex:25240; appliedIndex:25238; }","duration":"495.518116ms","start":"2026-06-02T18:14:41.089458Z","end":"2026-06-02T18:14:41.584977Z","steps":["trace[43661066] 'read index received' (duration: 230.970503ms)","trace[43661066] 'applied index is now lower than readState.Index' (duration: 264.546833ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:41.58509Z","caller":"traceutil/trace.go:171","msg":"trace[590042361] transaction","detail":"{read_only:false; response_revision:24454; number_of_response:1; }","duration":"498.491317ms","start":"2026-06-02T18:14:41.08659Z","end":"2026-06-02T18:14:41.585081Z","steps":["trace[590042361] 'process raft request' (duration: 479.784646ms)","trace[590042361] 'compare' (duration: 18.510256ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:41.585217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:41.086525Z","time spent":"498.583189ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:14:41.58554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.080269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:41.585573Z","caller":"traceutil/trace.go:171","msg":"trace[1399942440] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:24454; }","duration":"496.15116ms","start":"2026-06-02T18:14:41.089412Z","end":"2026-06-02T18:14:41.585564Z","steps":["trace[1399942440] 'agreement among raft nodes before linearized reading' (duration: 496.097419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:41.585596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:41.089395Z","time spent":"496.195371ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-02T18:14:41.5856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.521825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:41.585657Z","caller":"traceutil/trace.go:171","msg":"trace[397950] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24454; }","duration":"167.606837ms","start":"2026-06-02T18:14:41.418033Z","end":"2026-06-02T18:14:41.58564Z","steps":["trace[397950] 'agreement among raft nodes before linearized reading' (duration: 167.524355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:41.585236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.285613ms","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-06-02T18:14:41.586011Z","caller":"traceutil/trace.go:171","msg":"trace[954193895] 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:24454; }","duration":"416.096542ms","start":"2026-06-02T18:14:41.169879Z","end":"2026-06-02T18:14:41.585976Z","steps":["trace[954193895] 'agreement among raft nodes before linearized reading' (duration: 415.289863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:41.586341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:41.169854Z","time spent":"416.445261ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-06-02T18:14:41.869822Z","caller":"traceutil/trace.go:171","msg":"trace[1987534217] linearizableReadLoop","detail":"{readStateIndex:25241; appliedIndex:25240; }","duration":"264.196023ms","start":"2026-06-02T18:14:41.605604Z","end":"2026-06-02T18:14:41.8698Z","steps":["trace[1987534217] 'read index received' (duration: 174.407081ms)","trace[1987534217] 'applied index is now lower than readState.Index' (duration: 89.787992ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:41.870065Z","caller":"traceutil/trace.go:171","msg":"trace[766441191] transaction","detail":"{read_only:false; response_revision:24455; number_of_response:1; }","duration":"275.049035ms","start":"2026-06-02T18:14:41.595003Z","end":"2026-06-02T18:14:41.870052Z","steps":["trace[766441191] 'process raft request' (duration: 185.001136ms)","trace[766441191] 'compare' (duration: 89.600048ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:41.870222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.091372ms","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":"warn","ts":"2026-06-02T18:14:41.870283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.662824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:14:41.870315Z","caller":"traceutil/trace.go:171","msg":"trace[447129487] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24455; }","duration":"186.226115ms","start":"2026-06-02T18:14:41.684061Z","end":"2026-06-02T18:14:41.870287Z","steps":["trace[447129487] 'agreement among raft nodes before linearized reading' (duration: 186.068161ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:41.870343Z","caller":"traceutil/trace.go:171","msg":"trace[704116393] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24455; }","duration":"264.754877ms","start":"2026-06-02T18:14:41.605575Z","end":"2026-06-02T18:14:41.87033Z","steps":["trace[704116393] 'agreement among raft nodes before linearized reading' (duration: 264.577602ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:42.067574Z","caller":"traceutil/trace.go:171","msg":"trace[485871804] transaction","detail":"{read_only:false; response_revision:24456; number_of_response:1; }","duration":"125.335529ms","start":"2026-06-02T18:14:41.942207Z","end":"2026-06-02T18:14:42.067542Z","steps":["trace[485871804] 'process raft request' (duration: 73.642214ms)","trace[485871804] 'compare' (duration: 51.203534ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:42.332481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.793175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:14:42.332602Z","caller":"traceutil/trace.go:171","msg":"trace[659470975] linearizableReadLoop","detail":"{readStateIndex:25243; appliedIndex:25242; }","duration":"259.53401ms","start":"2026-06-02T18:14:42.073033Z","end":"2026-06-02T18:14:42.332567Z","steps":["trace[659470975] 'read index received' (duration: 144.480449ms)","trace[659470975] 'applied index is now lower than readState.Index' (duration: 115.052441ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:42.332859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.814868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:42.332829Z","caller":"traceutil/trace.go:171","msg":"trace[1038659661] transaction","detail":"{read_only:false; response_revision:24457; number_of_response:1; }","duration":"262.005ms","start":"2026-06-02T18:14:42.070784Z","end":"2026-06-02T18:14:42.332789Z","steps":["trace[1038659661] 'process raft request' (duration: 146.826426ms)","trace[1038659661] 'compare' (duration: 114.692182ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:42.33291Z","caller":"traceutil/trace.go:171","msg":"trace[30482349] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24457; }","duration":"259.89947ms","start":"2026-06-02T18:14:42.072998Z","end":"2026-06-02T18:14:42.332897Z","steps":["trace[30482349] 'agreement among raft nodes before linearized reading' (duration: 259.813636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.646285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.623261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:14:42.646373Z","caller":"traceutil/trace.go:171","msg":"trace[573699975] linearizableReadLoop","detail":"{readStateIndex:25244; appliedIndex:25243; }","duration":"313.730957ms","start":"2026-06-02T18:14:42.332628Z","end":"2026-06-02T18:14:42.646359Z","steps":["trace[573699975] 'read index received' (duration: 182.006344ms)","trace[573699975] 'applied index is now lower than readState.Index' (duration: 131.723473ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:42.646431Z","caller":"traceutil/trace.go:171","msg":"trace[188613442] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"397.955615ms","start":"2026-06-02T18:14:42.248467Z","end":"2026-06-02T18:14:42.646422Z","steps":["trace[188613442] 'process raft request' (duration: 266.10444ms)","trace[188613442] 'compare' (duration: 131.257971ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:42.646473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.248452Z","time spent":"397.997926ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:14:42.646561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.491922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T18:14:42.646647Z","caller":"traceutil/trace.go:171","msg":"trace[556782959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24458; }","duration":"399.602975ms","start":"2026-06-02T18:14:42.247028Z","end":"2026-06-02T18:14:42.64663Z","steps":["trace[556782959] 'agreement among raft nodes before linearized reading' (duration: 399.41821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.646685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.247019Z","time spent":"399.657536ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T18:14:42.646825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.112284ms","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-06-02T18:14:42.64685Z","caller":"traceutil/trace.go:171","msg":"trace[502023329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24458; }","duration":"399.174675ms","start":"2026-06-02T18:14:42.247666Z","end":"2026-06-02T18:14:42.646841Z","steps":["trace[502023329] 'agreement among raft nodes before linearized reading' (duration: 399.102674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.646887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.247644Z","time spent":"399.237756ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T18:14:42.646899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.321082ms","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-06-02T18:14:42.647147Z","caller":"traceutil/trace.go:171","msg":"trace[988987054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24458; }","duration":"400.616289ms","start":"2026-06-02T18:14:42.246508Z","end":"2026-06-02T18:14:42.647124Z","steps":["trace[988987054] 'agreement among raft nodes before linearized reading' (duration: 400.189109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.647207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.246487Z","time spent":"400.707532ms","remote":"127.0.0.1:58324","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-06-02T18:14:42.684376Z","caller":"traceutil/trace.go:171","msg":"trace[485801744] transaction","detail":"{read_only:false; response_revision:24459; number_of_response:1; }","duration":"344.325663ms","start":"2026-06-02T18:14:42.340031Z","end":"2026-06-02T18:14:42.684356Z","steps":["trace[485801744] 'process raft request' (duration: 344.212291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.684601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.340017Z","time spent":"344.422206ms","remote":"127.0.0.1:58324","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-06-02T18:14:42.905888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.893551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:14:42.90609Z","caller":"traceutil/trace.go:171","msg":"trace[1591929243] linearizableReadLoop","detail":"{readStateIndex:25246; appliedIndex:25245; }","duration":"259.687114ms","start":"2026-06-02T18:14:42.646391Z","end":"2026-06-02T18:14:42.906078Z","steps":["trace[1591929243] 'read index received' (duration: 38.722002ms)","trace[1591929243] 'applied index is now lower than readState.Index' (duration: 220.964292ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:42.906318Z","caller":"traceutil/trace.go:171","msg":"trace[1686737690] transaction","detail":"{read_only:false; response_revision:24460; number_of_response:1; }","duration":"307.231179ms","start":"2026-06-02T18:14:42.599076Z","end":"2026-06-02T18:14:42.906307Z","steps":["trace[1686737690] 'process raft request' (duration: 190.839796ms)","trace[1686737690] 'compare' (duration: 115.42063ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:42.906373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.599053Z","time spent":"307.295061ms","remote":"127.0.0.1:58324","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-06-02T18:14:42.906495Z","caller":"traceutil/trace.go:171","msg":"trace[147702211] transaction","detail":"{read_only:false; response_revision:24461; number_of_response:1; }","duration":"255.289468ms","start":"2026-06-02T18:14:42.651199Z","end":"2026-06-02T18:14:42.906489Z","steps":["trace[147702211] 'process raft request' (duration: 254.765026ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:42.906645Z","caller":"traceutil/trace.go:171","msg":"trace[907530497] transaction","detail":"{read_only:false; response_revision:24462; number_of_response:1; }","duration":"254.561222ms","start":"2026-06-02T18:14:42.652072Z","end":"2026-06-02T18:14:42.906634Z","steps":["trace[907530497] 'process raft request' (duration: 253.953657ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:42.906776Z","caller":"traceutil/trace.go:171","msg":"trace[482541913] transaction","detail":"{read_only:false; response_revision:24463; number_of_response:1; }","duration":"254.429467ms","start":"2026-06-02T18:14:42.652337Z","end":"2026-06-02T18:14:42.906767Z","steps":["trace[482541913] 'process raft request' (duration: 253.71402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.906933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.34291ms","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-06-02T18:14:42.906962Z","caller":"traceutil/trace.go:171","msg":"trace[486415961] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24463; }","duration":"309.410232ms","start":"2026-06-02T18:14:42.597544Z","end":"2026-06-02T18:14:42.906954Z","steps":["trace[486415961] 'agreement among raft nodes before linearized reading' (duration: 309.3128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.906982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.597524Z","time spent":"309.453533ms","remote":"127.0.0.1:58324","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-06-02T18:14:42.907083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.906142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:14:42.907105Z","caller":"traceutil/trace.go:171","msg":"trace[806860731] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:24463; }","duration":"458.977444ms","start":"2026-06-02T18:14:42.448122Z","end":"2026-06-02T18:14:42.9071Z","steps":["trace[806860731] 'agreement among raft nodes before linearized reading' (duration: 458.938263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.907131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.448101Z","time spent":"459.021826ms","remote":"127.0.0.1:52312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-02T18:14:42.907244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.848457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:42.907267Z","caller":"traceutil/trace.go:171","msg":"trace[139203528] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24463; }","duration":"567.899798ms","start":"2026-06-02T18:14:42.339361Z","end":"2026-06-02T18:14:42.90726Z","steps":["trace[139203528] 'agreement among raft nodes before linearized reading' (duration: 567.863118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.90729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:42.339344Z","time spent":"567.94162ms","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-02T18:14:42.907419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.561527ms","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-06-02T18:14:42.907442Z","caller":"traceutil/trace.go:171","msg":"trace[710399112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24463; }","duration":"231.614069ms","start":"2026-06-02T18:14:42.675822Z","end":"2026-06-02T18:14:42.907436Z","steps":["trace[710399112] 'agreement among raft nodes before linearized reading' (duration: 231.551107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.907827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.701578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:42.90786Z","caller":"traceutil/trace.go:171","msg":"trace[932189416] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24463; }","duration":"103.738678ms","start":"2026-06-02T18:14:42.804115Z","end":"2026-06-02T18:14:42.907853Z","steps":["trace[932189416] 'agreement among raft nodes before linearized reading' (duration: 103.689267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:42.907969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.638537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:42.91046Z","caller":"traceutil/trace.go:171","msg":"trace[11997190] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24463; }","duration":"231.150428ms","start":"2026-06-02T18:14:42.679297Z","end":"2026-06-02T18:14:42.910447Z","steps":["trace[11997190] 'agreement among raft nodes before linearized reading' (duration: 228.653608ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:53.217451Z","caller":"traceutil/trace.go:171","msg":"trace[1100502648] linearizableReadLoop","detail":"{readStateIndex:25329; appliedIndex:25328; }","duration":"210.9145ms","start":"2026-06-02T18:14:53.006495Z","end":"2026-06-02T18:14:53.21741Z","steps":["trace[1100502648] 'read index received' (duration: 137.778108ms)","trace[1100502648] 'applied index is now lower than readState.Index' (duration: 73.135312ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:53.217613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.098004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:53.217664Z","caller":"traceutil/trace.go:171","msg":"trace[116140977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24540; }","duration":"211.186206ms","start":"2026-06-02T18:14:53.006459Z","end":"2026-06-02T18:14:53.217645Z","steps":["trace[116140977] 'agreement among raft nodes before linearized reading' (duration: 211.089554ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:53.217673Z","caller":"traceutil/trace.go:171","msg":"trace[1690594956] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"230.068401ms","start":"2026-06-02T18:14:52.987562Z","end":"2026-06-02T18:14:53.217631Z","steps":["trace[1690594956] 'process raft request' (duration: 156.731315ms)","trace[1690594956] 'compare' (duration: 73.009508ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:58.819203Z","caller":"traceutil/trace.go:171","msg":"trace[88956085] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"113.424502ms","start":"2026-06-02T18:14:58.705753Z","end":"2026-06-02T18:14:58.819178Z","steps":["trace[88956085] 'process raft request' (duration: 113.258088ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:59.134691Z","caller":"traceutil/trace.go:171","msg":"trace[1828548509] linearizableReadLoop","detail":"{readStateIndex:25370; appliedIndex:25369; }","duration":"156.718354ms","start":"2026-06-02T18:14:58.977953Z","end":"2026-06-02T18:14:59.134672Z","steps":["trace[1828548509] 'read index received' (duration: 140.399011ms)","trace[1828548509] 'applied index is now lower than readState.Index' (duration: 16.318563ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:59.134769Z","caller":"traceutil/trace.go:171","msg":"trace[1614171705] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"168.096069ms","start":"2026-06-02T18:14:58.966637Z","end":"2026-06-02T18:14:59.134733Z","steps":["trace[1614171705] 'process raft request' (duration: 151.736494ms)","trace[1614171705] 'compare' (duration: 16.191121ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:59.134957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.977231ms","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-06-02T18:14:59.135025Z","caller":"traceutil/trace.go:171","msg":"trace[45876739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24580; }","duration":"157.093563ms","start":"2026-06-02T18:14:58.977913Z","end":"2026-06-02T18:14:59.135007Z","steps":["trace[45876739] 'agreement among raft nodes before linearized reading' (duration: 156.827847ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:59.396023Z","caller":"traceutil/trace.go:171","msg":"trace[2163315] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"406.954001ms","start":"2026-06-02T18:14:58.989048Z","end":"2026-06-02T18:14:59.396002Z","steps":["trace[2163315] 'process raft request' (duration: 406.809258ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:59.396105Z","caller":"traceutil/trace.go:171","msg":"trace[1785996961] linearizableReadLoop","detail":"{readStateIndex:25371; appliedIndex:25371; }","duration":"261.330835ms","start":"2026-06-02T18:14:59.134743Z","end":"2026-06-02T18:14:59.396074Z","steps":["trace[1785996961] 'read index received' (duration: 261.315835ms)","trace[1785996961] 'applied index is now lower than readState.Index' (duration: 12.53µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:14:59.396175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:58.989028Z","time spent":"407.075935ms","remote":"127.0.0.1:58324","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-06-02T18:14:59.396312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.052993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:59.396364Z","caller":"traceutil/trace.go:171","msg":"trace[1387025938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24581; }","duration":"392.132684ms","start":"2026-06-02T18:14:59.004216Z","end":"2026-06-02T18:14:59.396349Z","steps":["trace[1387025938] 'agreement among raft nodes before linearized reading' (duration: 391.990801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:59.396401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:59.004213Z","time spent":"392.180665ms","remote":"127.0.0.1:58052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T18:14:59.396692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.665327ms","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-06-02T18:14:59.396689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.899694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T18:14:59.396928Z","caller":"traceutil/trace.go:171","msg":"trace[264841513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24581; }","duration":"393.170961ms","start":"2026-06-02T18:14:59.003735Z","end":"2026-06-02T18:14:59.396906Z","steps":["trace[264841513] 'agreement among raft nodes before linearized reading' (duration: 392.745841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:59.396691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.527265ms","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":"warn","ts":"2026-06-02T18:14:59.396987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:59.00373Z","time spent":"393.242912ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T18:14:59.397033Z","caller":"traceutil/trace.go:171","msg":"trace[1163677537] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24581; }","duration":"397.898483ms","start":"2026-06-02T18:14:58.999105Z","end":"2026-06-02T18:14:59.397003Z","steps":["trace[1163677537] 'agreement among raft nodes before linearized reading' (duration: 397.401071ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:59.396783Z","caller":"traceutil/trace.go:171","msg":"trace[1039507339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24581; }","duration":"392.78517ms","start":"2026-06-02T18:14:59.003975Z","end":"2026-06-02T18:14:59.396761Z","steps":["trace[1039507339] 'agreement among raft nodes before linearized reading' (duration: 392.548085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:59.397113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:58.99909Z","time spent":"398.000096ms","remote":"127.0.0.1:58324","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-06-02T18:14:59.397121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:59.003959Z","time spent":"393.144638ms","remote":"127.0.0.1:58324","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-06-02T18:14:59.621293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.011283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:14:59.621612Z","caller":"traceutil/trace.go:171","msg":"trace[704874205] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"220.794148ms","start":"2026-06-02T18:14:59.4008Z","end":"2026-06-02T18:14:59.621594Z","steps":["trace[704874205] 'process raft request' (duration: 220.712856ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:59.621636Z","caller":"traceutil/trace.go:171","msg":"trace[2094358757] linearizableReadLoop","detail":"{readStateIndex:25373; appliedIndex:25371; }","duration":"225.433029ms","start":"2026-06-02T18:14:59.396175Z","end":"2026-06-02T18:14:59.621608Z","steps":["trace[2094358757] 'read index received' (duration: 80.037057ms)","trace[2094358757] 'applied index is now lower than readState.Index' (duration: 145.393492ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:59.621663Z","caller":"traceutil/trace.go:171","msg":"trace[1465739825] transaction","detail":"{read_only:false; response_revision:24582; number_of_response:1; }","duration":"482.247515ms","start":"2026-06-02T18:14:59.139393Z","end":"2026-06-02T18:14:59.621641Z","steps":["trace[1465739825] 'process raft request' (duration: 336.797602ms)","trace[1465739825] 'compare' (duration: 144.803658ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:14:59.621672Z","caller":"traceutil/trace.go:171","msg":"trace[1500860186] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"218.086902ms","start":"2026-06-02T18:14:59.403561Z","end":"2026-06-02T18:14:59.621647Z","steps":["trace[1500860186] 'process raft request' (duration: 217.99608ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:59.621759Z","caller":"traceutil/trace.go:171","msg":"trace[1335625892] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"231.324541ms","start":"2026-06-02T18:14:59.390422Z","end":"2026-06-02T18:14:59.621747Z","steps":["trace[1335625892] 'process raft request' (duration: 230.962822ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:14:59.621743Z","caller":"traceutil/trace.go:171","msg":"trace[1679042600] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"221.087285ms","start":"2026-06-02T18:14:59.400613Z","end":"2026-06-02T18:14:59.621701Z","steps":["trace[1679042600] 'process raft request' (duration: 220.863929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:59.621791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:59.139379Z","time spent":"482.338277ms","remote":"127.0.0.1:58324","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-06-02T18:14:59.621885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.227157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:14:59.62193Z","caller":"traceutil/trace.go:171","msg":"trace[1567152316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24586; }","duration":"223.35034ms","start":"2026-06-02T18:14:59.398568Z","end":"2026-06-02T18:14:59.621919Z","steps":["trace[1567152316] 'agreement among raft nodes before linearized reading' (duration: 223.262818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:59.621991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.39363ms","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-06-02T18:14:59.622031Z","caller":"traceutil/trace.go:171","msg":"trace[1146304811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24586; }","duration":"365.473462ms","start":"2026-06-02T18:14:59.256548Z","end":"2026-06-02T18:14:59.622021Z","steps":["trace[1146304811] 'agreement among raft nodes before linearized reading' (duration: 365.341319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:14:59.622066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:59.256527Z","time spent":"365.531413ms","remote":"127.0.0.1:58324","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":"info","ts":"2026-06-02T18:14:59.785429Z","caller":"traceutil/trace.go:171","msg":"trace[1168946436] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"119.366125ms","start":"2026-06-02T18:14:59.666005Z","end":"2026-06-02T18:14:59.785371Z","steps":["trace[1168946436] 'process raft request' (duration: 106.69065ms)","trace[1168946436] 'compare' (duration: 12.513971ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:00.15226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.492551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:15:00.152544Z","caller":"traceutil/trace.go:171","msg":"trace[1743311555] linearizableReadLoop","detail":"{readStateIndex:25380; appliedIndex:25378; }","duration":"146.165142ms","start":"2026-06-02T18:15:00.006357Z","end":"2026-06-02T18:15:00.152523Z","steps":["trace[1743311555] 'read index received' (duration: 3.28392ms)","trace[1743311555] 'applied index is now lower than readState.Index' (duration: 142.879902ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:00.152674Z","caller":"traceutil/trace.go:171","msg":"trace[1609943028] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"174.130005ms","start":"2026-06-02T18:14:59.978529Z","end":"2026-06-02T18:15:00.152659Z","steps":["trace[1609943028] 'process raft request' (duration: 173.90139ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:00.152811Z","caller":"traceutil/trace.go:171","msg":"trace[1700551382] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"332.036648ms","start":"2026-06-02T18:14:59.82075Z","end":"2026-06-02T18:15:00.152786Z","steps":["trace[1700551382] 'process raft request' (duration: 188.913431ms)","trace[1700551382] 'compare' (duration: 142.234125ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:00.152941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.580002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:00.153001Z","caller":"traceutil/trace.go:171","msg":"trace[224440983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24590; }","duration":"146.667183ms","start":"2026-06-02T18:15:00.00632Z","end":"2026-06-02T18:15:00.152987Z","steps":["trace[224440983] 'agreement among raft nodes before linearized reading' (duration: 146.573521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:00.153035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:14:59.820727Z","time spent":"332.181602ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:15:00.362996Z","caller":"traceutil/trace.go:171","msg":"trace[1094658591] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"206.649218ms","start":"2026-06-02T18:15:00.156323Z","end":"2026-06-02T18:15:00.362973Z","steps":["trace[1094658591] 'process raft request' (duration: 206.506594ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:00.363401Z","caller":"traceutil/trace.go:171","msg":"trace[321707091] linearizableReadLoop","detail":"{readStateIndex:25382; appliedIndex:25380; }","duration":"125.500393ms","start":"2026-06-02T18:15:00.237877Z","end":"2026-06-02T18:15:00.363377Z","steps":["trace[321707091] 'read index received' (duration: 124.880299ms)","trace[321707091] 'applied index is now lower than readState.Index' (duration: 619.164µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:00.363616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.682337ms","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-06-02T18:15:00.363658Z","caller":"traceutil/trace.go:171","msg":"trace[577719227] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"188.581982ms","start":"2026-06-02T18:15:00.175063Z","end":"2026-06-02T18:15:00.363645Z","steps":["trace[577719227] 'process raft request' (duration: 188.182612ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:00.363667Z","caller":"traceutil/trace.go:171","msg":"trace[922230566] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24592; }","duration":"125.80141ms","start":"2026-06-02T18:15:00.23785Z","end":"2026-06-02T18:15:00.363652Z","steps":["trace[922230566] 'agreement among raft nodes before linearized reading' (duration: 125.619765ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:00.689504Z","caller":"traceutil/trace.go:171","msg":"trace[903631992] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"303.359067ms","start":"2026-06-02T18:15:00.386106Z","end":"2026-06-02T18:15:00.689465Z","steps":["trace[903631992] 'process raft request' (duration: 283.946009ms)","trace[903631992] 'compare' (duration: 19.183192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:00.68965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:00.386089Z","time spent":"303.483709ms","remote":"127.0.0.1:58298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:15:00.907963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.566859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:15:00.908351Z","caller":"traceutil/trace.go:171","msg":"trace[613730360] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"494.025759ms","start":"2026-06-02T18:15:00.414291Z","end":"2026-06-02T18:15:00.908316Z","steps":["trace[613730360] 'process raft request' (duration: 379.021579ms)","trace[613730360] 'compare' (duration: 114.401405ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:00.90841Z","caller":"traceutil/trace.go:171","msg":"trace[1614066944] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"277.410112ms","start":"2026-06-02T18:15:00.630965Z","end":"2026-06-02T18:15:00.908375Z","steps":["trace[1614066944] 'process raft request' (duration: 277.148296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:00.908487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:00.414257Z","time spent":"494.162182ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5614,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T18:15:00.908477Z","caller":"traceutil/trace.go:171","msg":"trace[1102624487] linearizableReadLoop","detail":"{readStateIndex:25385; appliedIndex:25383; }","duration":"371.503537ms","start":"2026-06-02T18:15:00.536948Z","end":"2026-06-02T18:15:00.908452Z","steps":["trace[1102624487] 'read index received' (duration: 133.176067ms)","trace[1102624487] 'applied index is now lower than readState.Index' (duration: 238.32509ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:00.908661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.674591ms","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-06-02T18:15:00.908727Z","caller":"traceutil/trace.go:171","msg":"trace[631724987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24597; }","duration":"371.788914ms","start":"2026-06-02T18:15:00.536904Z","end":"2026-06-02T18:15:00.908693Z","steps":["trace[631724987] 'agreement among raft nodes before linearized reading' (duration: 371.607409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:00.908759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:00.536884Z","time spent":"371.867817ms","remote":"127.0.0.1:58324","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-06-02T18:15:01.226892Z","caller":"traceutil/trace.go:171","msg":"trace[694583624] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"314.114105ms","start":"2026-06-02T18:15:00.912743Z","end":"2026-06-02T18:15:01.226857Z","steps":["trace[694583624] 'process raft request' (duration: 239.091578ms)","trace[694583624] 'compare' (duration: 74.888024ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:01.227093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:00.912719Z","time spent":"314.262099ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:15:01.420224Z","caller":"traceutil/trace.go:171","msg":"trace[1920461865] transaction","detail":"{read_only:false; response_revision:24600; number_of_response:1; }","duration":"507.065423ms","start":"2026-06-02T18:15:00.91313Z","end":"2026-06-02T18:15:01.420196Z","steps":["trace[1920461865] 'process raft request' (duration: 506.92829ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:01.420218Z","caller":"traceutil/trace.go:171","msg":"trace[2016640247] transaction","detail":"{read_only:false; response_revision:24599; number_of_response:1; }","duration":"507.234687ms","start":"2026-06-02T18:15:00.912935Z","end":"2026-06-02T18:15:01.42017Z","steps":["trace[2016640247] 'process raft request' (duration: 507.006472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.420397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:00.913114Z","time spent":"507.178636ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5660,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:15:01.420401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:00.912911Z","time spent":"507.407911ms","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T18:15:01.420426Z","caller":"traceutil/trace.go:171","msg":"trace[448405541] transaction","detail":"{read_only:false; response_revision:24602; number_of_response:1; }","duration":"280.062796ms","start":"2026-06-02T18:15:01.140337Z","end":"2026-06-02T18:15:01.420399Z","steps":["trace[448405541] 'process raft request' (duration: 279.82801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.420328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.272113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:15:01.420225Z","caller":"traceutil/trace.go:171","msg":"trace[347777846] linearizableReadLoop","detail":"{readStateIndex:25391; appliedIndex:25387; }","duration":"479.166301ms","start":"2026-06-02T18:15:00.941037Z","end":"2026-06-02T18:15:01.420203Z","steps":["trace[347777846] 'read index received' (duration: 210.883569ms)","trace[347777846] 'applied index is now lower than readState.Index' (duration: 268.281952ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:01.420589Z","caller":"traceutil/trace.go:171","msg":"trace[700396340] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24602; }","duration":"479.584391ms","start":"2026-06-02T18:15:00.940987Z","end":"2026-06-02T18:15:01.420571Z","steps":["trace[700396340] 'agreement among raft nodes before linearized reading' (duration: 479.289784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.420636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:00.940963Z","time spent":"479.658912ms","remote":"127.0.0.1:39980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-06-02T18:15:01.420411Z","caller":"traceutil/trace.go:171","msg":"trace[715952199] transaction","detail":"{read_only:false; response_revision:24601; number_of_response:1; }","duration":"504.735616ms","start":"2026-06-02T18:15:00.915651Z","end":"2026-06-02T18:15:01.420387Z","steps":["trace[715952199] 'process raft request' (duration: 504.46633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.420823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:00.91564Z","time spent":"505.109586ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:15:01.443592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.809598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:01.443675Z","caller":"traceutil/trace.go:171","msg":"trace[1226385427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24603; }","duration":"438.935461ms","start":"2026-06-02T18:15:01.00472Z","end":"2026-06-02T18:15:01.443656Z","steps":["trace[1226385427] 'agreement among raft nodes before linearized reading' (duration: 438.811479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.443714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:01.004694Z","time spent":"439.011864ms","remote":"127.0.0.1:58056","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-06-02T18:15:01.734411Z","caller":"traceutil/trace.go:171","msg":"trace[1144061168] linearizableReadLoop","detail":"{readStateIndex:25395; appliedIndex:25394; }","duration":"230.717236ms","start":"2026-06-02T18:15:01.503677Z","end":"2026-06-02T18:15:01.734394Z","steps":["trace[1144061168] 'read index received' (duration: 131.688242ms)","trace[1144061168] 'applied index is now lower than readState.Index' (duration: 99.028464ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:01.734658Z","caller":"traceutil/trace.go:171","msg":"trace[1526703302] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"283.670232ms","start":"2026-06-02T18:15:01.45095Z","end":"2026-06-02T18:15:01.73462Z","steps":["trace[1526703302] 'process raft request' (duration: 184.420262ms)","trace[1526703302] 'compare' (duration: 98.795369ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:01.73487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.988862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:01.734925Z","caller":"traceutil/trace.go:171","msg":"trace[790455491] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:24605; }","duration":"231.275379ms","start":"2026-06-02T18:15:01.503635Z","end":"2026-06-02T18:15:01.73491Z","steps":["trace[790455491] 'agreement among raft nodes before linearized reading' (duration: 230.948042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.773805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.844516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:01.773891Z","caller":"traceutil/trace.go:171","msg":"trace[396227431] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24606; }","duration":"229.993059ms","start":"2026-06-02T18:15:01.543878Z","end":"2026-06-02T18:15:01.773871Z","steps":["trace[396227431] 'agreement among raft nodes before linearized reading' (duration: 229.834546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.773988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.014574ms","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-06-02T18:15:01.774026Z","caller":"traceutil/trace.go:171","msg":"trace[1495397804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24606; }","duration":"148.112277ms","start":"2026-06-02T18:15:01.625905Z","end":"2026-06-02T18:15:01.774017Z","steps":["trace[1495397804] 'agreement among raft nodes before linearized reading' (duration: 147.962833ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:01.773826Z","caller":"traceutil/trace.go:171","msg":"trace[1925082565] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"145.939535ms","start":"2026-06-02T18:15:01.62786Z","end":"2026-06-02T18:15:01.773799Z","steps":["trace[1925082565] 'process raft request' (duration: 145.714449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.774243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.340723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-02T18:15:01.774238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.118783ms","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-06-02T18:15:01.774298Z","caller":"traceutil/trace.go:171","msg":"trace[1927737881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24606; }","duration":"148.421965ms","start":"2026-06-02T18:15:01.625862Z","end":"2026-06-02T18:15:01.774284Z","steps":["trace[1927737881] 'agreement among raft nodes before linearized reading' (duration: 148.257021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.774336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.497427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T18:15:01.774323Z","caller":"traceutil/trace.go:171","msg":"trace[1307001140] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24606; }","duration":"147.229605ms","start":"2026-06-02T18:15:01.627068Z","end":"2026-06-02T18:15:01.774298Z","steps":["trace[1307001140] 'agreement among raft nodes before linearized reading' (duration: 147.02239ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:01.774353Z","caller":"traceutil/trace.go:171","msg":"trace[531551473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24606; }","duration":"148.550758ms","start":"2026-06-02T18:15:01.625797Z","end":"2026-06-02T18:15:01.774348Z","steps":["trace[531551473] 'agreement among raft nodes before linearized reading' (duration: 148.506197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.774431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.611172ms","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-06-02T18:15:01.774493Z","caller":"traceutil/trace.go:171","msg":"trace[672671669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24606; }","duration":"124.698345ms","start":"2026-06-02T18:15:01.649778Z","end":"2026-06-02T18:15:01.774477Z","steps":["trace[672671669] 'agreement among raft nodes before linearized reading' (duration: 124.52768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:01.77483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.802811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-06-02T18:15:01.774868Z","caller":"traceutil/trace.go:171","msg":"trace[836796235] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:24606; }","duration":"125.839592ms","start":"2026-06-02T18:15:01.649018Z","end":"2026-06-02T18:15:01.774857Z","steps":["trace[836796235] 'agreement among raft nodes before linearized reading' (duration: 124.760145ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:02.02361Z","caller":"traceutil/trace.go:171","msg":"trace[708645019] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"245.498302ms","start":"2026-06-02T18:15:01.778079Z","end":"2026-06-02T18:15:02.023577Z","steps":["trace[708645019] 'process raft request' (duration: 148.027086ms)","trace[708645019] 'compare' (duration: 97.124518ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:02.346513Z","caller":"traceutil/trace.go:171","msg":"trace[1418657498] transaction","detail":"{read_only:false; response_revision:24608; number_of_response:1; }","duration":"567.725794ms","start":"2026-06-02T18:15:01.778735Z","end":"2026-06-02T18:15:02.34646Z","steps":["trace[1418657498] 'process raft request' (duration: 552.464866ms)","trace[1418657498] 'compare' (duration: 15.121515ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:02.346687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:01.778721Z","time spent":"567.861918ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:15:02.346923Z","caller":"traceutil/trace.go:171","msg":"trace[898049358] transaction","detail":"{read_only:false; response_revision:24609; number_of_response:1; }","duration":"567.813257ms","start":"2026-06-02T18:15:01.779089Z","end":"2026-06-02T18:15:02.346903Z","steps":["trace[898049358] 'process raft request' (duration: 567.329405ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:02.346958Z","caller":"traceutil/trace.go:171","msg":"trace[785390689] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"567.440088ms","start":"2026-06-02T18:15:01.779485Z","end":"2026-06-02T18:15:02.346925Z","steps":["trace[785390689] 'process raft request' (duration: 567.258044ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:02.346986Z","caller":"traceutil/trace.go:171","msg":"trace[386920812] linearizableReadLoop","detail":"{readStateIndex:25402; appliedIndex:25397; }","duration":"340.239676ms","start":"2026-06-02T18:15:02.006722Z","end":"2026-06-02T18:15:02.346962Z","steps":["trace[386920812] 'read index received' (duration: 324.404894ms)","trace[386920812] 'applied index is now lower than readState.Index' (duration: 15.833902ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:02.347029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:01.779063Z","time spent":"567.905619ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:15:02.347088Z","caller":"traceutil/trace.go:171","msg":"trace[349325715] transaction","detail":"{read_only:false; response_revision:24611; number_of_response:1; }","duration":"566.961406ms","start":"2026-06-02T18:15:01.780113Z","end":"2026-06-02T18:15:02.347075Z","steps":["trace[349325715] 'process raft request' (duration: 566.74043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:02.347109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:01.779474Z","time spent":"567.552571ms","remote":"127.0.0.1:58324","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-06-02T18:15:02.347183Z","caller":"traceutil/trace.go:171","msg":"trace[2022444675] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"555.896969ms","start":"2026-06-02T18:15:01.791273Z","end":"2026-06-02T18:15:02.34717Z","steps":["trace[2022444675] 'process raft request' (duration: 555.630163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:02.347193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:01.780105Z","time spent":"567.008987ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:15:02.347252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:01.791251Z","time spent":"555.96754ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:15:02.347272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.556923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:02.347298Z","caller":"traceutil/trace.go:171","msg":"trace[617475178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24612; }","duration":"340.609885ms","start":"2026-06-02T18:15:02.006681Z","end":"2026-06-02T18:15:02.347291Z","steps":["trace[617475178] 'agreement among raft nodes before linearized reading' (duration: 340.567244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:02.347316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:02.006659Z","time spent":"340.650985ms","remote":"127.0.0.1:58052","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-06-02T18:15:02.368974Z","caller":"traceutil/trace.go:171","msg":"trace[646738121] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"209.514327ms","start":"2026-06-02T18:15:02.159434Z","end":"2026-06-02T18:15:02.368949Z","steps":["trace[646738121] 'process raft request' (duration: 209.318402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:02.369115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.878363ms","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-06-02T18:15:02.369206Z","caller":"traceutil/trace.go:171","msg":"trace[848034589] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24613; }","duration":"224.020345ms","start":"2026-06-02T18:15:02.145166Z","end":"2026-06-02T18:15:02.369187Z","steps":["trace[848034589] 'agreement among raft nodes before linearized reading' (duration: 223.840971ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:02.58778Z","caller":"traceutil/trace.go:171","msg":"trace[1041507923] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"217.048349ms","start":"2026-06-02T18:15:02.370706Z","end":"2026-06-02T18:15:02.587755Z","steps":["trace[1041507923] 'process raft request' (duration: 216.780932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:02.588556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.061819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:02.588641Z","caller":"traceutil/trace.go:171","msg":"trace[226004445] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:24614; }","duration":"134.206183ms","start":"2026-06-02T18:15:02.454407Z","end":"2026-06-02T18:15:02.588613Z","steps":["trace[226004445] 'agreement among raft nodes before linearized reading' (duration: 134.06574ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:02.58835Z","caller":"traceutil/trace.go:171","msg":"trace[465301145] linearizableReadLoop","detail":"{readStateIndex:25404; appliedIndex:25404; }","duration":"133.883695ms","start":"2026-06-02T18:15:02.454451Z","end":"2026-06-02T18:15:02.588334Z","steps":["trace[465301145] 'read index received' (duration: 133.876915ms)","trace[465301145] 'applied index is now lower than readState.Index' (duration: 5.23µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:05.790876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.581256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:05.790988Z","caller":"traceutil/trace.go:171","msg":"trace[637846423] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24642; }","duration":"145.713719ms","start":"2026-06-02T18:15:05.645247Z","end":"2026-06-02T18:15:05.790961Z","steps":["trace[637846423] 'count revisions from in-memory index tree' (duration: 145.492555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:05.791155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.152798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-06-02T18:15:05.791228Z","caller":"traceutil/trace.go:171","msg":"trace[1574022203] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24642; }","duration":"219.293421ms","start":"2026-06-02T18:15:05.571917Z","end":"2026-06-02T18:15:05.79121Z","steps":["trace[1574022203] 'range keys from in-memory index tree' (duration: 218.982504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:06.056061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.761757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:15:06.05619Z","caller":"traceutil/trace.go:171","msg":"trace[1553896334] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"141.402506ms","start":"2026-06-02T18:15:05.914765Z","end":"2026-06-02T18:15:06.056167Z","steps":["trace[1553896334] 'process raft request' (duration: 23.451985ms)","trace[1553896334] 'compare' (duration: 117.664455ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:09.438764Z","caller":"traceutil/trace.go:171","msg":"trace[679605631] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"163.867117ms","start":"2026-06-02T18:15:09.274877Z","end":"2026-06-02T18:15:09.438745Z","steps":["trace[679605631] 'process raft request' (duration: 163.718193ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:09.690904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.35156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:15:09.691019Z","caller":"traceutil/trace.go:171","msg":"trace[1261797886] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"243.644217ms","start":"2026-06-02T18:15:09.447362Z","end":"2026-06-02T18:15:09.691007Z","steps":["trace[1261797886] 'process raft request' (duration: 115.129153ms)","trace[1261797886] 'compare' (duration: 128.208317ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:09.691096Z","caller":"traceutil/trace.go:171","msg":"trace[73201193] linearizableReadLoop","detail":"{readStateIndex:25525; appliedIndex:25523; }","duration":"162.220197ms","start":"2026-06-02T18:15:09.528868Z","end":"2026-06-02T18:15:09.691088Z","steps":["trace[73201193] 'read index received' (duration: 33.749063ms)","trace[73201193] 'applied index is now lower than readState.Index' (duration: 128.470574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:09.691213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.34612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-06-02T18:15:09.691245Z","caller":"traceutil/trace.go:171","msg":"trace[1867603061] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24733; }","duration":"162.407962ms","start":"2026-06-02T18:15:09.52883Z","end":"2026-06-02T18:15:09.691238Z","steps":["trace[1867603061] 'agreement among raft nodes before linearized reading' (duration: 162.281629ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:09.691378Z","caller":"traceutil/trace.go:171","msg":"trace[931231055] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"206.872003ms","start":"2026-06-02T18:15:09.48449Z","end":"2026-06-02T18:15:09.691362Z","steps":["trace[931231055] 'process raft request' (duration: 206.485833ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:09.92088Z","caller":"traceutil/trace.go:171","msg":"trace[438271127] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"109.797505ms","start":"2026-06-02T18:15:09.811046Z","end":"2026-06-02T18:15:09.920843Z","steps":["trace[438271127] 'process raft request' (duration: 53.509138ms)","trace[438271127] 'compare' (duration: 55.384874ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:29.701399Z","caller":"traceutil/trace.go:171","msg":"trace[429417768] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"114.561409ms","start":"2026-06-02T18:15:29.586808Z","end":"2026-06-02T18:15:29.70137Z","steps":["trace[429417768] 'process raft request' (duration: 114.400274ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:29.946116Z","caller":"traceutil/trace.go:171","msg":"trace[1402385574] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"187.022095ms","start":"2026-06-02T18:15:29.759067Z","end":"2026-06-02T18:15:29.946089Z","steps":["trace[1402385574] 'process raft request' (duration: 146.95842ms)","trace[1402385574] 'compare' (duration: 39.960942ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:30.110418Z","caller":"traceutil/trace.go:171","msg":"trace[441324124] linearizableReadLoop","detail":"{readStateIndex:25721; appliedIndex:25721; }","duration":"183.794947ms","start":"2026-06-02T18:15:29.92657Z","end":"2026-06-02T18:15:30.110364Z","steps":["trace[441324124] 'read index received' (duration: 183.768366ms)","trace[441324124] 'applied index is now lower than readState.Index' (duration: 22.721µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:30.11059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.986601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:30.110691Z","caller":"traceutil/trace.go:171","msg":"trace[813720546] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24923; }","duration":"184.147635ms","start":"2026-06-02T18:15:29.926523Z","end":"2026-06-02T18:15:30.110671Z","steps":["trace[813720546] 'agreement among raft nodes before linearized reading' (duration: 183.962931ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:30.110753Z","caller":"traceutil/trace.go:171","msg":"trace[943115670] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"348.551954ms","start":"2026-06-02T18:15:29.761462Z","end":"2026-06-02T18:15:30.110014Z","steps":["trace[943115670] 'process raft request' (duration: 348.337749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:30.110977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:15:29.761427Z","time spent":"349.447287ms","remote":"127.0.0.1:58324","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-06-02T18:15:30.121964Z","caller":"traceutil/trace.go:171","msg":"trace[1736573378] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"129.219401ms","start":"2026-06-02T18:15:29.992727Z","end":"2026-06-02T18:15:30.121947Z","steps":["trace[1736573378] 'process raft request' (duration: 129.076308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:30.404754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.704193ms","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-06-02T18:15:30.404847Z","caller":"traceutil/trace.go:171","msg":"trace[1115459646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24924; }","duration":"109.847667ms","start":"2026-06-02T18:15:30.294976Z","end":"2026-06-02T18:15:30.404824Z","steps":["trace[1115459646] 'range keys from in-memory index tree' (duration: 109.531448ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:30.63519Z","caller":"traceutil/trace.go:171","msg":"trace[1914453859] linearizableReadLoop","detail":"{readStateIndex:25725; appliedIndex:25723; }","duration":"163.842007ms","start":"2026-06-02T18:15:30.471317Z","end":"2026-06-02T18:15:30.635159Z","steps":["trace[1914453859] 'read index received' (duration: 77.797034ms)","trace[1914453859] 'applied index is now lower than readState.Index' (duration: 86.043453ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:30.635361Z","caller":"traceutil/trace.go:171","msg":"trace[2105031834] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"168.057728ms","start":"2026-06-02T18:15:30.467287Z","end":"2026-06-02T18:15:30.635345Z","steps":["trace[2105031834] 'process raft request' (duration: 167.802102ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:30.635357Z","caller":"traceutil/trace.go:171","msg":"trace[97533908] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"169.261037ms","start":"2026-06-02T18:15:30.466056Z","end":"2026-06-02T18:15:30.635317Z","steps":["trace[97533908] 'process raft request' (duration: 83.061651ms)","trace[97533908] 'compare' (duration: 85.815917ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:15:30.635624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.098718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:30.635675Z","caller":"traceutil/trace.go:171","msg":"trace[1947837672] 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:24927; }","duration":"170.217011ms","start":"2026-06-02T18:15:30.465445Z","end":"2026-06-02T18:15:30.635662Z","steps":["trace[1947837672] 'agreement among raft nodes before linearized reading' (duration: 170.091918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:30.635732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.352039ms","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-06-02T18:15:30.635822Z","caller":"traceutil/trace.go:171","msg":"trace[1127497021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24927; }","duration":"159.480532ms","start":"2026-06-02T18:15:30.47632Z","end":"2026-06-02T18:15:30.635801Z","steps":["trace[1127497021] 'agreement among raft nodes before linearized reading' (duration: 159.247566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:30.635819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.006638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-02T18:15:30.635822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.246324ms","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":"warn","ts":"2026-06-02T18:15:30.635865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.090341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T18:15:30.635892Z","caller":"traceutil/trace.go:171","msg":"trace[432429218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24927; }","duration":"129.12797ms","start":"2026-06-02T18:15:30.506746Z","end":"2026-06-02T18:15:30.635874Z","steps":["trace[432429218] 'agreement among raft nodes before linearized reading' (duration: 128.876654ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:30.635902Z","caller":"traceutil/trace.go:171","msg":"trace[203445939] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24927; }","duration":"156.342446ms","start":"2026-06-02T18:15:30.47954Z","end":"2026-06-02T18:15:30.635882Z","steps":["trace[203445939] 'agreement among raft nodes before linearized reading' (duration: 156.08742ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:30.635973Z","caller":"traceutil/trace.go:171","msg":"trace[1268242239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24927; }","duration":"127.296775ms","start":"2026-06-02T18:15:30.508645Z","end":"2026-06-02T18:15:30.635942Z","steps":["trace[1268242239] 'agreement among raft nodes before linearized reading' (duration: 126.964517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:32.125983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.616633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:32.12606Z","caller":"traceutil/trace.go:171","msg":"trace[1024423295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24937; }","duration":"117.714505ms","start":"2026-06-02T18:15:32.008328Z","end":"2026-06-02T18:15:32.126043Z","steps":["trace[1024423295] 'range keys from in-memory index tree' (duration: 117.523201ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:32.328875Z","caller":"traceutil/trace.go:171","msg":"trace[1610673056] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"201.694088ms","start":"2026-06-02T18:15:32.127158Z","end":"2026-06-02T18:15:32.328852Z","steps":["trace[1610673056] 'process raft request' (duration: 201.546644ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:32.845154Z","caller":"traceutil/trace.go:171","msg":"trace[1833614259] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"122.837099ms","start":"2026-06-02T18:15:32.722296Z","end":"2026-06-02T18:15:32.845133Z","steps":["trace[1833614259] 'process raft request' (duration: 122.775168ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:32.845166Z","caller":"traceutil/trace.go:171","msg":"trace[931342123] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"123.497995ms","start":"2026-06-02T18:15:32.721627Z","end":"2026-06-02T18:15:32.845125Z","steps":["trace[931342123] 'process raft request' (duration: 123.324121ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:32.845574Z","caller":"traceutil/trace.go:171","msg":"trace[572684347] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"122.412479ms","start":"2026-06-02T18:15:32.723129Z","end":"2026-06-02T18:15:32.845542Z","steps":["trace[572684347] 'process raft request' (duration: 121.968528ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:32.845635Z","caller":"traceutil/trace.go:171","msg":"trace[820025163] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"122.44493ms","start":"2026-06-02T18:15:32.723164Z","end":"2026-06-02T18:15:32.845609Z","steps":["trace[820025163] 'process raft request' (duration: 122.313036ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:34.992736Z","caller":"traceutil/trace.go:171","msg":"trace[1242714395] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"138.832733ms","start":"2026-06-02T18:15:34.853871Z","end":"2026-06-02T18:15:34.992704Z","steps":["trace[1242714395] 'process raft request' (duration: 109.410655ms)","trace[1242714395] 'compare' (duration: 29.221604ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:34.995458Z","caller":"traceutil/trace.go:171","msg":"trace[40990527] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"133.964337ms","start":"2026-06-02T18:15:34.861339Z","end":"2026-06-02T18:15:34.995304Z","steps":["trace[40990527] 'process raft request' (duration: 133.901956ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:34.995792Z","caller":"traceutil/trace.go:171","msg":"trace[516583369] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"141.375835ms","start":"2026-06-02T18:15:34.85437Z","end":"2026-06-02T18:15:34.995746Z","steps":["trace[516583369] 'process raft request' (duration: 140.375361ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:34.995818Z","caller":"traceutil/trace.go:171","msg":"trace[73430969] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"140.75243ms","start":"2026-06-02T18:15:34.85501Z","end":"2026-06-02T18:15:34.995762Z","steps":["trace[73430969] 'process raft request' (duration: 140.171796ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:15:38.476881Z","caller":"traceutil/trace.go:171","msg":"trace[1679855928] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"130.335139ms","start":"2026-06-02T18:15:38.346516Z","end":"2026-06-02T18:15:38.476852Z","steps":["trace[1679855928] 'process raft request' (duration: 98.922962ms)","trace[1679855928] 'compare' (duration: 31.038848ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:38.661862Z","caller":"traceutil/trace.go:171","msg":"trace[1509927767] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"133.029143ms","start":"2026-06-02T18:15:38.528795Z","end":"2026-06-02T18:15:38.661825Z","steps":["trace[1509927767] 'process raft request' (duration: 108.352199ms)","trace[1509927767] 'compare' (duration: 24.382327ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:38.905173Z","caller":"traceutil/trace.go:171","msg":"trace[352624144] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"220.200554ms","start":"2026-06-02T18:15:38.684944Z","end":"2026-06-02T18:15:38.905145Z","steps":["trace[352624144] 'process raft request' (duration: 123.453933ms)","trace[352624144] 'compare' (duration: 96.521006ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:46.050264Z","caller":"traceutil/trace.go:171","msg":"trace[1475261351] linearizableReadLoop","detail":"{readStateIndex:25853; appliedIndex:25852; }","duration":"156.996883ms","start":"2026-06-02T18:15:45.893244Z","end":"2026-06-02T18:15:46.050241Z","steps":["trace[1475261351] 'read index received' (duration: 156.776876ms)","trace[1475261351] 'applied index is now lower than readState.Index' (duration: 219.117µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:15:46.050309Z","caller":"traceutil/trace.go:171","msg":"trace[256253190] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"254.845309ms","start":"2026-06-02T18:15:45.795432Z","end":"2026-06-02T18:15:46.050278Z","steps":["trace[256253190] 'process raft request' (duration: 254.658233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:15:46.051289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.196957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:15:46.051415Z","caller":"traceutil/trace.go:171","msg":"trace[1362702875] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25052; }","duration":"158.179231ms","start":"2026-06-02T18:15:45.893203Z","end":"2026-06-02T18:15:46.051382Z","steps":["trace[1362702875] 'agreement among raft nodes before linearized reading' (duration: 157.168537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:02.482685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.548332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:02.483113Z","caller":"traceutil/trace.go:171","msg":"trace[65483430] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"313.950013ms","start":"2026-06-02T18:16:02.169134Z","end":"2026-06-02T18:16:02.483084Z","steps":["trace[65483430] 'process raft request' (duration: 163.860237ms)","trace[65483430] 'compare' (duration: 149.442869ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:02.483218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:02.169111Z","time spent":"314.064725ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:16:02.491302Z","caller":"traceutil/trace.go:171","msg":"trace[1829481097] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"109.261881ms","start":"2026-06-02T18:16:02.38202Z","end":"2026-06-02T18:16:02.491282Z","steps":["trace[1829481097] 'process raft request' (duration: 109.153009ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:02.840496Z","caller":"traceutil/trace.go:171","msg":"trace[210266433] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"276.552222ms","start":"2026-06-02T18:16:02.563895Z","end":"2026-06-02T18:16:02.840447Z","steps":["trace[210266433] 'process raft request' (duration: 276.338016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:03.156845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.876935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:03.157025Z","caller":"traceutil/trace.go:171","msg":"trace[27199827] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"245.404931ms","start":"2026-06-02T18:16:02.911604Z","end":"2026-06-02T18:16:03.157009Z","steps":["trace[27199827] 'process raft request' (duration: 245.34566ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:03.157061Z","caller":"traceutil/trace.go:171","msg":"trace[1671723322] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"312.115618ms","start":"2026-06-02T18:16:02.844909Z","end":"2026-06-02T18:16:03.157025Z","steps":["trace[1671723322] 'process raft request' (duration: 79.977626ms)","trace[1671723322] 'compare' (duration: 231.762413ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:03.157295Z","caller":"traceutil/trace.go:171","msg":"trace[383100576] linearizableReadLoop","detail":"{readStateIndex:25988; appliedIndex:25987; }","duration":"257.749618ms","start":"2026-06-02T18:16:02.899508Z","end":"2026-06-02T18:16:03.157258Z","steps":["trace[383100576] 'read index received' (duration: 25.439723ms)","trace[383100576] 'applied index is now lower than readState.Index' (duration: 232.307355ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:03.1574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:02.844886Z","time spent":"312.378333ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:03.157572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.966014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:16:03.157662Z","caller":"traceutil/trace.go:171","msg":"trace[517391189] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25185; }","duration":"258.24126ms","start":"2026-06-02T18:16:02.8994Z","end":"2026-06-02T18:16:03.157642Z","steps":["trace[517391189] 'agreement among raft nodes before linearized reading' (duration: 257.962104ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:03.19299Z","caller":"traceutil/trace.go:171","msg":"trace[1091911093] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"190.477628ms","start":"2026-06-02T18:16:03.002473Z","end":"2026-06-02T18:16:03.192951Z","steps":["trace[1091911093] 'process raft request' (duration: 190.403636ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:03.192995Z","caller":"traceutil/trace.go:171","msg":"trace[241422570] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"192.394894ms","start":"2026-06-02T18:16:03.000526Z","end":"2026-06-02T18:16:03.192921Z","steps":["trace[241422570] 'process raft request' (duration: 192.187149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:03.207414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.367459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-02T18:16:03.207491Z","caller":"traceutil/trace.go:171","msg":"trace[1446410241] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:25188; }","duration":"230.480881ms","start":"2026-06-02T18:16:02.976992Z","end":"2026-06-02T18:16:03.207472Z","steps":["trace[1446410241] 'agreement among raft nodes before linearized reading' (duration: 230.278516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:03.207682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.423106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:03.207758Z","caller":"traceutil/trace.go:171","msg":"trace[1469292717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25188; }","duration":"200.518959ms","start":"2026-06-02T18:16:03.007217Z","end":"2026-06-02T18:16:03.207736Z","steps":["trace[1469292717] 'agreement among raft nodes before linearized reading' (duration: 200.424737ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:03.618204Z","caller":"traceutil/trace.go:171","msg":"trace[1116231490] linearizableReadLoop","detail":"{readStateIndex:25993; appliedIndex:25992; }","duration":"409.650797ms","start":"2026-06-02T18:16:03.208526Z","end":"2026-06-02T18:16:03.618177Z","steps":["trace[1116231490] 'read index received' (duration: 409.538524ms)","trace[1116231490] 'applied index is now lower than readState.Index' (duration: 111.063µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:03.618242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:03.208303Z","time spent":"409.926254ms","remote":"127.0.0.1:58082","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-02T18:16:03.618409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.817752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:03.618533Z","caller":"traceutil/trace.go:171","msg":"trace[486041384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25188; }","duration":"410.018696ms","start":"2026-06-02T18:16:03.208492Z","end":"2026-06-02T18:16:03.618511Z","steps":["trace[486041384] 'agreement among raft nodes before linearized reading' (duration: 409.7735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:03.618594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:03.208478Z","time spent":"410.102989ms","remote":"127.0.0.1:58056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T18:16:03.875937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.520275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:16:03.876271Z","caller":"traceutil/trace.go:171","msg":"trace[1527998362] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"256.955889ms","start":"2026-06-02T18:16:03.619299Z","end":"2026-06-02T18:16:03.876255Z","steps":["trace[1527998362] 'process raft request' (duration: 256.904738ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:03.876468Z","caller":"traceutil/trace.go:171","msg":"trace[206884777] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"664.867833ms","start":"2026-06-02T18:16:03.21157Z","end":"2026-06-02T18:16:03.876438Z","steps":["trace[206884777] 'process raft request' (duration: 664.451063ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:03.876539Z","caller":"traceutil/trace.go:171","msg":"trace[391724206] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"664.369511ms","start":"2026-06-02T18:16:03.212153Z","end":"2026-06-02T18:16:03.876522Z","steps":["trace[391724206] 'process raft request' (duration: 663.961212ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:03.876599Z","caller":"traceutil/trace.go:171","msg":"trace[396169677] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"665.340006ms","start":"2026-06-02T18:16:03.211216Z","end":"2026-06-02T18:16:03.876556Z","steps":["trace[396169677] 'process raft request' (duration: 475.110984ms)","trace[396169677] 'compare' (duration: 189.326461ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:03.876642Z","caller":"traceutil/trace.go:171","msg":"trace[2002417945] linearizableReadLoop","detail":"{readStateIndex:25997; appliedIndex:25993; }","duration":"258.315431ms","start":"2026-06-02T18:16:03.618279Z","end":"2026-06-02T18:16:03.876594Z","steps":["trace[2002417945] 'read index received' (duration: 68.040668ms)","trace[2002417945] 'applied index is now lower than readState.Index' (duration: 190.272543ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:03.876645Z","caller":"traceutil/trace.go:171","msg":"trace[199352749] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"664.311371ms","start":"2026-06-02T18:16:03.212314Z","end":"2026-06-02T18:16:03.876626Z","steps":["trace[199352749] 'process raft request' (duration: 663.838689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:03.876788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.88352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T18:16:03.876797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:03.212306Z","time spent":"664.415973ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:03.876803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:03.211192Z","time spent":"665.499259ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T18:16:03.876823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:03.211549Z","time spent":"665.018917ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:16:03.876848Z","caller":"traceutil/trace.go:171","msg":"trace[2076278605] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25193; }","duration":"663.949441ms","start":"2026-06-02T18:16:03.212872Z","end":"2026-06-02T18:16:03.876821Z","steps":["trace[2076278605] 'agreement among raft nodes before linearized reading' (duration: 663.868369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:03.876893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:03.212864Z","time spent":"664.018154ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-06-02T18:16:03.876878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.255995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-06-02T18:16:03.876906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.377753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T18:16:03.876957Z","caller":"traceutil/trace.go:171","msg":"trace[1593422377] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25193; }","duration":"340.380328ms","start":"2026-06-02T18:16:03.536556Z","end":"2026-06-02T18:16:03.876936Z","steps":["trace[1593422377] 'agreement among raft nodes before linearized reading' (duration: 340.147823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:03.877016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:03.536532Z","time spent":"340.47251ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-02T18:16:03.876629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T18:16:03.212144Z","time spent":"664.434544ms","remote":"127.0.0.1:58324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T18:16:03.876968Z","caller":"traceutil/trace.go:171","msg":"trace[201250498] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25193; }","duration":"231.475935ms","start":"2026-06-02T18:16:03.645474Z","end":"2026-06-02T18:16:03.87695Z","steps":["trace[201250498] 'agreement among raft nodes before linearized reading' (duration: 231.391363ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:04.119775Z","caller":"traceutil/trace.go:171","msg":"trace[1376337416] linearizableReadLoop","detail":"{readStateIndex:25999; appliedIndex:25998; }","duration":"236.18217ms","start":"2026-06-02T18:16:03.883556Z","end":"2026-06-02T18:16:04.119739Z","steps":["trace[1376337416] 'read index received' (duration: 184.024353ms)","trace[1376337416] 'applied index is now lower than readState.Index' (duration: 52.156757ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:04.120062Z","caller":"traceutil/trace.go:171","msg":"trace[260373899] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"238.277549ms","start":"2026-06-02T18:16:03.881764Z","end":"2026-06-02T18:16:04.120041Z","steps":["trace[260373899] 'process raft request' (duration: 185.714852ms)","trace[260373899] 'compare' (duration: 52.124476ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:16:04.12016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.578089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:16:04.120236Z","caller":"traceutil/trace.go:171","msg":"trace[388262518] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25194; }","duration":"236.685071ms","start":"2026-06-02T18:16:03.883533Z","end":"2026-06-02T18:16:04.120218Z","steps":["trace[388262518] 'agreement among raft nodes before linearized reading' (duration: 236.525307ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:04.306949Z","caller":"traceutil/trace.go:171","msg":"trace[1188029170] linearizableReadLoop","detail":"{readStateIndex:26000; appliedIndex:25999; }","duration":"186.989574ms","start":"2026-06-02T18:16:04.119844Z","end":"2026-06-02T18:16:04.306834Z","steps":["trace[1188029170] 'read index received' (duration: 184.195037ms)","trace[1188029170] 'applied index is now lower than readState.Index' (duration: 2.792967ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:16:04.306994Z","caller":"traceutil/trace.go:171","msg":"trace[1490911727] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"267.390121ms","start":"2026-06-02T18:16:04.039574Z","end":"2026-06-02T18:16:04.306964Z","steps":["trace[1490911727] 'process raft request' (duration: 264.505841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:16:04.307192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.064202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T18:16:04.307221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.528596ms","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-06-02T18:16:04.307253Z","caller":"traceutil/trace.go:171","msg":"trace[146876582] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25195; }","duration":"196.182724ms","start":"2026-06-02T18:16:04.111054Z","end":"2026-06-02T18:16:04.307237Z","steps":["trace[146876582] 'agreement among raft nodes before linearized reading' (duration: 196.019691ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:04.30728Z","caller":"traceutil/trace.go:171","msg":"trace[326459840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25195; }","duration":"118.628188ms","start":"2026-06-02T18:16:04.188635Z","end":"2026-06-02T18:16:04.307263Z","steps":["trace[326459840] 'agreement among raft nodes before linearized reading' (duration: 118.455504ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:16:32.616175Z","caller":"traceutil/trace.go:171","msg":"trace[1837472308] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"122.46603ms","start":"2026-06-02T18:16:32.49368Z","end":"2026-06-02T18:16:32.616146Z","steps":["trace[1837472308] 'process raft request' (duration: 66.254216ms)","trace[1837472308] 'compare' (duration: 55.869616ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:27.165228Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068df.wal"} {"level":"info","ts":"2026-06-02T18:17:56.726281Z","caller":"traceutil/trace.go:171","msg":"trace[1364003825] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"220.03509ms","start":"2026-06-02T18:17:56.506156Z","end":"2026-06-02T18:17:56.726191Z","steps":["trace[1364003825] 'process raft request' (duration: 219.859046ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:56.795336Z","caller":"traceutil/trace.go:171","msg":"trace[330771131] linearizableReadLoop","detail":"{readStateIndex:27122; appliedIndex:27120; }","duration":"218.309458ms","start":"2026-06-02T18:17:56.577001Z","end":"2026-06-02T18:17:56.79531Z","steps":["trace[330771131] 'read index received' (duration: 149.03595ms)","trace[330771131] 'applied index is now lower than readState.Index' (duration: 69.270508ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:56.795591Z","caller":"traceutil/trace.go:171","msg":"trace[2113677929] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"289.085863ms","start":"2026-06-02T18:17:56.506493Z","end":"2026-06-02T18:17:56.795579Z","steps":["trace[2113677929] 'process raft request' (duration: 288.695113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:56.795809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.785039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:17:56.795869Z","caller":"traceutil/trace.go:171","msg":"trace[304879229] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26292; }","duration":"218.897962ms","start":"2026-06-02T18:17:56.576959Z","end":"2026-06-02T18:17:56.795857Z","steps":["trace[304879229] 'agreement among raft nodes before linearized reading' (duration: 218.79952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:56.796398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.701468ms","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-06-02T18:17:56.796444Z","caller":"traceutil/trace.go:171","msg":"trace[349756339] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26292; }","duration":"147.78077ms","start":"2026-06-02T18:17:56.648649Z","end":"2026-06-02T18:17:56.79643Z","steps":["trace[349756339] 'agreement among raft nodes before linearized reading' (duration: 147.647207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:56.796841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.478408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T18:17:56.796976Z","caller":"traceutil/trace.go:171","msg":"trace[675316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26292; }","duration":"217.96545ms","start":"2026-06-02T18:17:56.57899Z","end":"2026-06-02T18:17:56.796955Z","steps":["trace[675316] 'agreement among raft nodes before linearized reading' (duration: 217.10605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:56.797265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.482509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1021"} {"level":"info","ts":"2026-06-02T18:17:56.79731Z","caller":"traceutil/trace.go:171","msg":"trace[2125489322] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:26292; }","duration":"166.53717ms","start":"2026-06-02T18:17:56.630758Z","end":"2026-06-02T18:17:56.797295Z","steps":["trace[2125489322] 'agreement among raft nodes before linearized reading' (duration: 166.453818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:56.79934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.003405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:987"} {"level":"info","ts":"2026-06-02T18:17:56.799403Z","caller":"traceutil/trace.go:171","msg":"trace[522171057] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:26292; }","duration":"175.095468ms","start":"2026-06-02T18:17:56.624291Z","end":"2026-06-02T18:17:56.799387Z","steps":["trace[522171057] 'agreement among raft nodes before linearized reading' (duration: 174.933974ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:56.993954Z","caller":"traceutil/trace.go:171","msg":"trace[1419331671] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"187.744642ms","start":"2026-06-02T18:17:56.80619Z","end":"2026-06-02T18:17:56.993934Z","steps":["trace[1419331671] 'process raft request' (duration: 187.616189ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.033886Z","caller":"traceutil/trace.go:171","msg":"trace[660939714] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"227.594671ms","start":"2026-06-02T18:17:56.806255Z","end":"2026-06-02T18:17:57.03385Z","steps":["trace[660939714] 'process raft request' (duration: 227.322305ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.034024Z","caller":"traceutil/trace.go:171","msg":"trace[1424182645] linearizableReadLoop","detail":"{readStateIndex:27124; appliedIndex:27123; }","duration":"194.251578ms","start":"2026-06-02T18:17:56.839744Z","end":"2026-06-02T18:17:57.033996Z","steps":["trace[1424182645] 'read index received' (duration: 155.380613ms)","trace[1424182645] 'applied index is now lower than readState.Index' (duration: 38.869215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:57.034703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.931545ms","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-06-02T18:17:57.034774Z","caller":"traceutil/trace.go:171","msg":"trace[900036726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26296; }","duration":"195.048608ms","start":"2026-06-02T18:17:56.839708Z","end":"2026-06-02T18:17:57.034756Z","steps":["trace[900036726] 'agreement among raft nodes before linearized reading' (duration: 194.379061ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.219956Z","caller":"traceutil/trace.go:171","msg":"trace[1961013442] linearizableReadLoop","detail":"{readStateIndex:27127; appliedIndex:27126; }","duration":"115.359259ms","start":"2026-06-02T18:17:57.104565Z","end":"2026-06-02T18:17:57.219924Z","steps":["trace[1961013442] 'read index received' (duration: 84.227639ms)","trace[1961013442] 'applied index is now lower than readState.Index' (duration: 31.13012ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:57.220137Z","caller":"traceutil/trace.go:171","msg":"trace[654790285] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"180.775004ms","start":"2026-06-02T18:17:57.039342Z","end":"2026-06-02T18:17:57.220117Z","steps":["trace[654790285] 'process raft request' (duration: 149.45021ms)","trace[654790285] 'compare' (duration: 30.996057ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:57.220866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.27639ms","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-06-02T18:17:57.221154Z","caller":"traceutil/trace.go:171","msg":"trace[1473585606] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26297; }","duration":"116.409164ms","start":"2026-06-02T18:17:57.104521Z","end":"2026-06-02T18:17:57.220931Z","steps":["trace[1473585606] 'agreement among raft nodes before linearized reading' (duration: 115.539183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:57.499641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.072309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T18:17:57.499744Z","caller":"traceutil/trace.go:171","msg":"trace[1463275540] linearizableReadLoop","detail":"{readStateIndex:27128; appliedIndex:27127; }","duration":"176.86828ms","start":"2026-06-02T18:17:57.32286Z","end":"2026-06-02T18:17:57.499729Z","steps":["trace[1463275540] 'read index received' (duration: 13.655828ms)","trace[1463275540] 'applied index is now lower than readState.Index' (duration: 163.211302ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:57.499826Z","caller":"traceutil/trace.go:171","msg":"trace[705231608] transaction","detail":"{read_only:false; response_revision:26298; number_of_response:1; }","duration":"183.50518ms","start":"2026-06-02T18:17:57.316309Z","end":"2026-06-02T18:17:57.499814Z","steps":["trace[705231608] 'process raft request' (duration: 20.170955ms)","trace[705231608] 'compare' (duration: 162.939256ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T18:17:57.499964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.524786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T18:17:57.500051Z","caller":"traceutil/trace.go:171","msg":"trace[1920050036] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:26298; }","duration":"143.639869ms","start":"2026-06-02T18:17:57.35638Z","end":"2026-06-02T18:17:57.50002Z","steps":["trace[1920050036] 'agreement among raft nodes before linearized reading' (duration: 143.529057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T18:17:57.50021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.343191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-06-02T18:17:57.500256Z","caller":"traceutil/trace.go:171","msg":"trace[204289790] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26298; }","duration":"177.415694ms","start":"2026-06-02T18:17:57.322829Z","end":"2026-06-02T18:17:57.500244Z","steps":["trace[204289790] 'agreement among raft nodes before linearized reading' (duration: 177.334192ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.794783Z","caller":"traceutil/trace.go:171","msg":"trace[1717621602] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"148.576559ms","start":"2026-06-02T18:17:57.646185Z","end":"2026-06-02T18:17:57.794762Z","steps":["trace[1717621602] 'process raft request' (duration: 130.829172ms)","trace[1717621602] 'compare' (duration: 17.549442ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T18:17:57.795226Z","caller":"traceutil/trace.go:171","msg":"trace[674792303] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"130.014311ms","start":"2026-06-02T18:17:57.665174Z","end":"2026-06-02T18:17:57.795188Z","steps":["trace[674792303] 'process raft request' (duration: 129.54213ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:57.795334Z","caller":"traceutil/trace.go:171","msg":"trace[1081302046] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"123.423883ms","start":"2026-06-02T18:17:57.671892Z","end":"2026-06-02T18:17:57.795316Z","steps":["trace[1081302046] 'process raft request' (duration: 123.011493ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T18:17:59.788852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23479} {"level":"info","ts":"2026-06-02T18:17:59.92532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23479,"took":"132.527633ms","hash":2554375726,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":36376576,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-02T18:17:59.925371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2554375726,"revision":23479,"compact-revision":20188} {"level":"info","ts":"2026-06-02T18:22:59.794861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26314} {"level":"info","ts":"2026-06-02T18:22:59.934237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26314,"took":"134.884038ms","hash":415458856,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":33529856,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T18:22:59.934296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":415458856,"revision":26314,"compact-revision":23479} {"level":"info","ts":"2026-06-02T18:23:24.586965Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":30007,"local-member-snapshot-index":20006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T18:23:24.590963Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30007} {"level":"info","ts":"2026-06-02T18:23:24.591027Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25007} {"level":"info","ts":"2026-06-02T18:27:59.801057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28914} {"level":"info","ts":"2026-06-02T18:27:59.937485Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28914,"took":"132.428537ms","hash":2705120628,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":30208000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T18:27:59.937552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2705120628,"revision":28914,"compact-revision":26314} {"level":"info","ts":"2026-06-02T18:32:59.808582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31137} {"level":"info","ts":"2026-06-02T18:32:59.934209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31137,"took":"120.934681ms","hash":707414580,"current-db-size-bytes":57757696,"current-db-size":"58 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T18:32:59.934275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":707414580,"revision":31137,"compact-revision":28914} {"level":"info","ts":"2026-06-02T18:37:59.815059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33403} {"level":"info","ts":"2026-06-02T18:37:59.966636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33403,"took":"145.500921ms","hash":1395313366,"current-db-size-bytes":59023360,"current-db-size":"59 MB","current-db-size-in-use-bytes":32571392,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-02T18:37:59.966703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1395313366,"revision":33403,"compact-revision":31137} {"level":"info","ts":"2026-06-02T18:42:59.822001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35705} {"level":"info","ts":"2026-06-02T18:42:59.960168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35705,"took":"134.138518ms","hash":489575212,"current-db-size-bytes":61190144,"current-db-size":"61 MB","current-db-size-in-use-bytes":31473664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T18:42:59.960266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":489575212,"revision":35705,"compact-revision":33403} {"level":"info","ts":"2026-06-02T18:44:51.866448Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":40008,"local-member-snapshot-index":30007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T18:44:51.894757Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40008} {"level":"info","ts":"2026-06-02T18:44:51.894886Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35008}