{"level":"warn","ts":"2026-09-02T16:28:03.994105Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-02T16:28:03.994225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.31:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.31:2380","--initial-cluster=instance=https://162.253.55.31:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.31:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.31:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-02T16:28:03.994321Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-02T16:28:03.994344Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-09-02T16:28:03.994377Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-02T16:28:03.995217Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"]} {"level":"info","ts":"2026-09-02T16:28:03.995332Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.31:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-02T16:28:04.00006Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.443934ms"} {"level":"info","ts":"2026-09-02T16:28:04.005964Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de05d1e64231de42","cluster-id":"4f2999739a68ff64"} {"level":"info","ts":"2026-09-02T16:28:04.006054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=()"} {"level":"info","ts":"2026-09-02T16:28:04.006074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 0"} {"level":"info","ts":"2026-09-02T16:28:04.006088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de05d1e64231de42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-02T16:28:04.006101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 1"} {"level":"info","ts":"2026-09-02T16:28:04.006153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"warn","ts":"2026-09-02T16:28:04.013834Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-02T16:28:04.016353Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-02T16:28:04.019683Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-02T16:28:04.02346Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de05d1e64231de42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-02T16:28:04.023652Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de05d1e64231de42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-02T16:28:04.023817Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-02T16:28:04.023876Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-02T16:28:04.023888Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-02T16:28:04.024781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"info","ts":"2026-09-02T16:28:04.024928Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","added-peer-id":"de05d1e64231de42","added-peer-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-09-02T16:28:04.028956Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-02T16:28:04.029065Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-09-02T16:28:04.029124Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-09-02T16:28:04.029258Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de05d1e64231de42","initial-advertise-peer-urls":["https://162.253.55.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-02T16:28:04.029312Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-02T16:28:04.106743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 is starting a new election at term 1"} {"level":"info","ts":"2026-09-02T16:28:04.106807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-02T16:28:04.106828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgPreVoteResp from de05d1e64231de42 at term 1"} {"level":"info","ts":"2026-09-02T16:28:04.106846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became candidate at term 2"} {"level":"info","ts":"2026-09-02T16:28:04.106856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgVoteResp from de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-09-02T16:28:04.106871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became leader at term 2"} {"level":"info","ts":"2026-09-02T16:28:04.106883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de05d1e64231de42 elected leader de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-09-02T16:28:04.108017Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T16:28:04.126562Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T16:28:04.126608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de05d1e64231de42","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.31:2379]}","request-path":"/0/members/de05d1e64231de42/attributes","cluster-id":"4f2999739a68ff64","publish-timeout":"7s"} {"level":"info","ts":"2026-09-02T16:28:04.126638Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T16:28:04.12663Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-02T16:28:04.126657Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T16:28:04.12666Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-02T16:28:04.127008Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-02T16:28:04.127058Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-02T16:28:04.129776Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.31:2379"} {"level":"info","ts":"2026-09-02T16:28:04.12997Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-02T16:28:22.660674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.357044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-02T16:28:22.660823Z","caller":"traceutil/trace.go:171","msg":"trace[1761152863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"203.539838ms","start":"2026-09-02T16:28:22.45722Z","end":"2026-09-02T16:28:22.66076Z","steps":["trace[1761152863] 'range keys from in-memory index tree' (duration: 203.261352ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:28:22.777624Z","caller":"traceutil/trace.go:171","msg":"trace[349394285] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"112.503967ms","start":"2026-09-02T16:28:22.665093Z","end":"2026-09-02T16:28:22.777597Z","steps":["trace[349394285] 'process raft request' (duration: 112.327353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:28:23.025456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.07499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T16:28:23.025648Z","caller":"traceutil/trace.go:171","msg":"trace[2085082775] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"359.129307ms","start":"2026-09-02T16:28:22.666493Z","end":"2026-09-02T16:28:23.025623Z","steps":["trace[2085082775] 'process raft request' (duration: 195.131388ms)","trace[2085082775] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 162.921346ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:28:23.025722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T16:28:22.666475Z","time spent":"359.213639ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T16:28:23.025719Z","caller":"traceutil/trace.go:171","msg":"trace[34698419] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:426; }","duration":"293.520627ms","start":"2026-09-02T16:28:22.732153Z","end":"2026-09-02T16:28:23.025674Z","steps":["trace[34698419] 'read index received' (duration: 45.25398ms)","trace[34698419] 'applied index is now lower than readState.Index' (duration: 248.260036ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:28:23.02589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.729981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T16:28:23.025943Z","caller":"traceutil/trace.go:171","msg":"trace[1428976952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"293.819073ms","start":"2026-09-02T16:28:22.73211Z","end":"2026-09-02T16:28:23.025929Z","steps":["trace[1428976952] 'agreement among raft nodes before linearized reading' (duration: 293.715471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:28:23.026003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.328047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-02T16:28:23.026069Z","caller":"traceutil/trace.go:171","msg":"trace[905990012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"273.453559ms","start":"2026-09-02T16:28:22.752598Z","end":"2026-09-02T16:28:23.026052Z","steps":["trace[905990012] 'agreement among raft nodes before linearized reading' (duration: 273.288466ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:28:23.202364Z","caller":"traceutil/trace.go:171","msg":"trace[798708505] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"172.09002ms","start":"2026-09-02T16:28:23.030249Z","end":"2026-09-02T16:28:23.202339Z","steps":["trace[798708505] 'process raft request' (duration: 171.940996ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:28:49.160932Z","caller":"traceutil/trace.go:171","msg":"trace[1100558846] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"245.134519ms","start":"2026-09-02T16:28:48.915772Z","end":"2026-09-02T16:28:49.160906Z","steps":["trace[1100558846] 'read index received' (duration: 243.240419ms)","trace[1100558846] 'applied index is now lower than readState.Index' (duration: 1.89324ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:28:49.161062Z","caller":"traceutil/trace.go:171","msg":"trace[1971326767] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"261.362805ms","start":"2026-09-02T16:28:48.899653Z","end":"2026-09-02T16:28:49.161016Z","steps":["trace[1971326767] 'process raft request' (duration: 259.622357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:28:49.162113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.310375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-02T16:28:49.162263Z","caller":"traceutil/trace.go:171","msg":"trace[1849530585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"246.470897ms","start":"2026-09-02T16:28:48.915725Z","end":"2026-09-02T16:28:49.162196Z","steps":["trace[1849530585] 'agreement among raft nodes before linearized reading' (duration: 245.314153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:28:49.166544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.419268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bls4v\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-02T16:28:49.166698Z","caller":"traceutil/trace.go:171","msg":"trace[963310286] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bls4v; range_end:; response_count:1; response_revision:632; }","duration":"107.581902ms","start":"2026-09-02T16:28:49.059057Z","end":"2026-09-02T16:28:49.166639Z","steps":["trace[963310286] 'agreement among raft nodes before linearized reading' (duration: 102.022624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:28:49.654712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.628667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T16:28:49.654877Z","caller":"traceutil/trace.go:171","msg":"trace[618521637] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"413.806997ms","start":"2026-09-02T16:28:49.241052Z","end":"2026-09-02T16:28:49.654859Z","steps":["trace[618521637] 'process raft request' (duration: 413.755766ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:28:49.654883Z","caller":"traceutil/trace.go:171","msg":"trace[925236621] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"466.454634ms","start":"2026-09-02T16:28:49.188395Z","end":"2026-09-02T16:28:49.65485Z","steps":["trace[925236621] 'process raft request' (duration: 272.610853ms)","trace[925236621] 'compare' (duration: 193.471893ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:28:49.654936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T16:28:49.241024Z","time spent":"413.889879ms","remote":"127.0.0.1:54004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-02T16:28:49.654982Z","caller":"traceutil/trace.go:171","msg":"trace[931445933] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"460.390055ms","start":"2026-09-02T16:28:49.194555Z","end":"2026-09-02T16:28:49.654945Z","steps":["trace[931445933] 'read index received' (duration: 2.402691ms)","trace[931445933] 'applied index is now lower than readState.Index' (duration: 457.982434ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:28:49.655049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T16:28:49.188358Z","time spent":"466.602257ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T16:28:49.655375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.579181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-02T16:28:49.655426Z","caller":"traceutil/trace.go:171","msg":"trace[759740865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"289.712085ms","start":"2026-09-02T16:28:49.3657Z","end":"2026-09-02T16:28:49.655412Z","steps":["trace[759740865] 'agreement among raft nodes before linearized reading' (duration: 289.50224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:28:49.655902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.342655ms","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-09-02T16:28:49.655975Z","caller":"traceutil/trace.go:171","msg":"trace[106049673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"461.544389ms","start":"2026-09-02T16:28:49.194415Z","end":"2026-09-02T16:28:49.655959Z","steps":["trace[106049673] 'agreement among raft nodes before linearized reading' (duration: 460.696971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:28:49.656006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T16:28:49.194397Z","time spent":"461.600941ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-02T16:29:06.731066Z","caller":"traceutil/trace.go:171","msg":"trace[1006427474] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"117.793888ms","start":"2026-09-02T16:29:06.613237Z","end":"2026-09-02T16:29:06.731031Z","steps":["trace[1006427474] 'process raft request' (duration: 117.647545ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:30:23.781765Z","caller":"traceutil/trace.go:171","msg":"trace[505325894] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"276.479455ms","start":"2026-09-02T16:30:23.505239Z","end":"2026-09-02T16:30:23.781718Z","steps":["trace[505325894] 'process raft request' (duration: 242.358011ms)","trace[505325894] 'compare' (duration: 33.959531ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:30:23.781881Z","caller":"traceutil/trace.go:171","msg":"trace[1794029167] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"215.41234ms","start":"2026-09-02T16:30:23.56643Z","end":"2026-09-02T16:30:23.781843Z","steps":["trace[1794029167] 'read index received' (duration: 181.221144ms)","trace[1794029167] 'applied index is now lower than readState.Index' (duration: 34.188776ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:30:23.782329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.868229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-050711a5-36b1-4fde-8ff3-45b9a2c01d24\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-02T16:30:23.782578Z","caller":"traceutil/trace.go:171","msg":"trace[627470690] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-050711a5-36b1-4fde-8ff3-45b9a2c01d24; range_end:; response_count:1; response_revision:1871; }","duration":"216.156675ms","start":"2026-09-02T16:30:23.566368Z","end":"2026-09-02T16:30:23.782525Z","steps":["trace[627470690] 'agreement among raft nodes before linearized reading' (duration: 215.709686ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:30:36.628713Z","caller":"traceutil/trace.go:171","msg":"trace[1849881150] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"101.352069ms","start":"2026-09-02T16:30:36.527328Z","end":"2026-09-02T16:30:36.62868Z","steps":["trace[1849881150] 'process raft request' (duration: 100.982022ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:30:36.83104Z","caller":"traceutil/trace.go:171","msg":"trace[1700820164] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"141.012911ms","start":"2026-09-02T16:30:36.689968Z","end":"2026-09-02T16:30:36.830981Z","steps":["trace[1700820164] 'process raft request' (duration: 111.21808ms)","trace[1700820164] 'compare' (duration: 29.633408ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:30:40.376992Z","caller":"traceutil/trace.go:171","msg":"trace[1983919973] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"119.457344ms","start":"2026-09-02T16:30:40.257517Z","end":"2026-09-02T16:30:40.376974Z","steps":["trace[1983919973] 'read index received' (duration: 119.333071ms)","trace[1983919973] 'applied index is now lower than readState.Index' (duration: 123.133µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:30:40.377041Z","caller":"traceutil/trace.go:171","msg":"trace[1145763074] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"123.184203ms","start":"2026-09-02T16:30:40.253832Z","end":"2026-09-02T16:30:40.377016Z","steps":["trace[1145763074] 'process raft request' (duration: 123.007089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:30:40.377202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.672549ms","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-09-02T16:30:40.377249Z","caller":"traceutil/trace.go:171","msg":"trace[1896060650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2006; }","duration":"119.794111ms","start":"2026-09-02T16:30:40.257443Z","end":"2026-09-02T16:30:40.377237Z","steps":["trace[1896060650] 'agreement among raft nodes before linearized reading' (duration: 119.646438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:30:40.377326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.025877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-02T16:30:40.377397Z","caller":"traceutil/trace.go:171","msg":"trace[596544932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2006; }","duration":"113.132979ms","start":"2026-09-02T16:30:40.264246Z","end":"2026-09-02T16:30:40.377379Z","steps":["trace[596544932] 'agreement among raft nodes before linearized reading' (duration: 112.879674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:31:19.004559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.528853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T16:31:19.004663Z","caller":"traceutil/trace.go:171","msg":"trace[126503525] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2219; }","duration":"183.676417ms","start":"2026-09-02T16:31:18.820955Z","end":"2026-09-02T16:31:19.004631Z","steps":["trace[126503525] 'count revisions from in-memory index tree' (duration: 183.470282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:32:36.890211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.577566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T16:32:36.890353Z","caller":"traceutil/trace.go:171","msg":"trace[671527743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2833; }","duration":"156.77598ms","start":"2026-09-02T16:32:36.733551Z","end":"2026-09-02T16:32:36.890327Z","steps":["trace[671527743] 'range keys from in-memory index tree' (duration: 156.448012ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:37:16.2168Z","caller":"traceutil/trace.go:171","msg":"trace[351712377] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5114; }","duration":"152.056807ms","start":"2026-09-02T16:37:16.064721Z","end":"2026-09-02T16:37:16.216778Z","steps":["trace[351712377] 'process raft request' (duration: 56.097869ms)","trace[351712377] 'compare' (duration: 95.884727ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:37:16.216786Z","caller":"traceutil/trace.go:171","msg":"trace[816106112] linearizableReadLoop","detail":"{readStateIndex:5310; appliedIndex:5309; }","duration":"142.075112ms","start":"2026-09-02T16:37:16.074684Z","end":"2026-09-02T16:37:16.216759Z","steps":["trace[816106112] 'read index received' (duration: 46.034032ms)","trace[816106112] 'applied index is now lower than readState.Index' (duration: 96.04006ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:37:16.217005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.303486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-02T16:37:16.21708Z","caller":"traceutil/trace.go:171","msg":"trace[1631821374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5114; }","duration":"142.424989ms","start":"2026-09-02T16:37:16.074637Z","end":"2026-09-02T16:37:16.217062Z","steps":["trace[1631821374] 'agreement among raft nodes before linearized reading' (duration: 142.203185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:37:16.217144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.448965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-02T16:37:16.217202Z","caller":"traceutil/trace.go:171","msg":"trace[243375497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5114; }","duration":"139.533358ms","start":"2026-09-02T16:37:16.077652Z","end":"2026-09-02T16:37:16.217185Z","steps":["trace[243375497] 'agreement among raft nodes before linearized reading' (duration: 139.346493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:37:19.379222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.232739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-02T16:37:19.379334Z","caller":"traceutil/trace.go:171","msg":"trace[588993415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5258; }","duration":"132.392853ms","start":"2026-09-02T16:37:19.246915Z","end":"2026-09-02T16:37:19.379308Z","steps":["trace[588993415] 'range keys from in-memory index tree' (duration: 131.964605ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:37:33.685618Z","caller":"traceutil/trace.go:171","msg":"trace[1747632081] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"187.642085ms","start":"2026-09-02T16:37:33.497952Z","end":"2026-09-02T16:37:33.685594Z","steps":["trace[1747632081] 'process raft request' (duration: 187.458271ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:37:41.545554Z","caller":"traceutil/trace.go:171","msg":"trace[1636312617] transaction","detail":"{read_only:false; response_revision:5666; number_of_response:1; }","duration":"180.742767ms","start":"2026-09-02T16:37:41.364773Z","end":"2026-09-02T16:37:41.545516Z","steps":["trace[1636312617] 'process raft request' (duration: 180.539622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:37:41.740726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.262785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T16:37:41.740828Z","caller":"traceutil/trace.go:171","msg":"trace[393834217] linearizableReadLoop","detail":"{readStateIndex:5878; appliedIndex:5877; }","duration":"122.670925ms","start":"2026-09-02T16:37:41.618136Z","end":"2026-09-02T16:37:41.740807Z","steps":["trace[393834217] 'read index received' (duration: 13.265716ms)","trace[393834217] 'applied index is now lower than readState.Index' (duration: 109.403899ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:37:41.740848Z","caller":"traceutil/trace.go:171","msg":"trace[1181204390] transaction","detail":"{read_only:false; response_revision:5667; number_of_response:1; }","duration":"126.405295ms","start":"2026-09-02T16:37:41.614413Z","end":"2026-09-02T16:37:41.740819Z","steps":["trace[1181204390] 'process raft request' (duration: 16.965926ms)","trace[1181204390] 'compare' (duration: 109.083801ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:37:41.740974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.829488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-02T16:37:41.741023Z","caller":"traceutil/trace.go:171","msg":"trace[367455807] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5667; }","duration":"122.90272ms","start":"2026-09-02T16:37:41.618105Z","end":"2026-09-02T16:37:41.741008Z","steps":["trace[367455807] 'agreement among raft nodes before linearized reading' (duration: 122.754407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:37:41.740663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.84209ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T16:37:41.741254Z","caller":"traceutil/trace.go:171","msg":"trace[1166422230] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5666; }","duration":"105.488354ms","start":"2026-09-02T16:37:41.635748Z","end":"2026-09-02T16:37:41.741236Z","steps":["trace[1166422230] 'range keys from in-memory index tree' (duration: 104.784469ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:37:52.229255Z","caller":"traceutil/trace.go:171","msg":"trace[1737069017] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"124.548096ms","start":"2026-09-02T16:37:52.104683Z","end":"2026-09-02T16:37:52.229231Z","steps":["trace[1737069017] 'process raft request' (duration: 124.376112ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:37:52.229302Z","caller":"traceutil/trace.go:171","msg":"trace[229800459] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"124.657927ms","start":"2026-09-02T16:37:52.104606Z","end":"2026-09-02T16:37:52.229264Z","steps":["trace[229800459] 'process raft request' (duration: 87.590358ms)","trace[229800459] 'compare' (duration: 36.726872ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:37:52.229505Z","caller":"traceutil/trace.go:171","msg":"trace[1941498687] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"124.7482ms","start":"2026-09-02T16:37:52.104725Z","end":"2026-09-02T16:37:52.229474Z","steps":["trace[1941498687] 'process raft request' (duration: 124.409843ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:38:04.903944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-09-02T16:38:04.980874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"75.422525ms","hash":707199473,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":32358400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-02T16:38:04.980939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":707199473,"revision":2988,"compact-revision":-1} {"level":"info","ts":"2026-09-02T16:38:35.067766Z","caller":"traceutil/trace.go:171","msg":"trace[1935800498] transaction","detail":"{read_only:false; response_revision:6195; number_of_response:1; }","duration":"112.015905ms","start":"2026-09-02T16:38:34.955714Z","end":"2026-09-02T16:38:35.06773Z","steps":["trace[1935800498] 'process raft request' (duration: 76.256074ms)","trace[1935800498] 'compare' (duration: 35.592218ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:43:04.910764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5916} {"level":"info","ts":"2026-09-02T16:43:05.007918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5916,"took":"95.285134ms","hash":1511144044,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":26677248,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-02T16:43:05.007998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1511144044,"revision":5916,"compact-revision":2988} {"level":"info","ts":"2026-09-02T16:44:01.726727Z","caller":"traceutil/trace.go:171","msg":"trace[1366140387] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"169.613097ms","start":"2026-09-02T16:44:01.557078Z","end":"2026-09-02T16:44:01.726691Z","steps":["trace[1366140387] 'process raft request' (duration: 169.100625ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:44:01.726726Z","caller":"traceutil/trace.go:171","msg":"trace[581498164] linearizableReadLoop","detail":"{readStateIndex:8885; appliedIndex:8884; }","duration":"121.214074ms","start":"2026-09-02T16:44:01.605435Z","end":"2026-09-02T16:44:01.726649Z","steps":["trace[581498164] 'read index received' (duration: 120.658771ms)","trace[581498164] 'applied index is now lower than readState.Index' (duration: 552.643µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:44:01.726942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.48806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-02T16:44:01.726992Z","caller":"traceutil/trace.go:171","msg":"trace[1939122898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8582; }","duration":"121.596982ms","start":"2026-09-02T16:44:01.605384Z","end":"2026-09-02T16:44:01.726981Z","steps":["trace[1939122898] 'agreement among raft nodes before linearized reading' (duration: 121.408858ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:44:17.946345Z","caller":"traceutil/trace.go:171","msg":"trace[825247610] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"112.508765ms","start":"2026-09-02T16:44:17.833801Z","end":"2026-09-02T16:44:17.94631Z","steps":["trace[825247610] 'process raft request' (duration: 98.463302ms)","trace[825247610] 'compare' (duration: 13.701245ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:44:18.210414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.89578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.31\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-02T16:44:18.210565Z","caller":"traceutil/trace.go:171","msg":"trace[209568341] range","detail":"{range_begin:/registry/masterleases/162.253.55.31; range_end:; response_count:1; response_revision:8674; }","duration":"110.106054ms","start":"2026-09-02T16:44:18.100434Z","end":"2026-09-02T16:44:18.21054Z","steps":["trace[209568341] 'range keys from in-memory index tree' (duration: 109.690265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:44:41.019505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.913677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T16:44:41.019731Z","caller":"traceutil/trace.go:171","msg":"trace[959058509] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"205.630562ms","start":"2026-09-02T16:44:40.814083Z","end":"2026-09-02T16:44:41.019713Z","steps":["trace[959058509] 'process raft request' (duration: 205.571121ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:44:41.019905Z","caller":"traceutil/trace.go:171","msg":"trace[1017211860] transaction","detail":"{read_only:false; response_revision:8844; number_of_response:1; }","duration":"247.77377ms","start":"2026-09-02T16:44:40.772097Z","end":"2026-09-02T16:44:41.019871Z","steps":["trace[1017211860] 'process raft request' (duration: 87.403244ms)","trace[1017211860] 'compare' (duration: 159.784644ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:44:41.0201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.686299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-02T16:44:41.020136Z","caller":"traceutil/trace.go:171","msg":"trace[1557617355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8845; }","duration":"215.74828ms","start":"2026-09-02T16:44:40.804379Z","end":"2026-09-02T16:44:41.020127Z","steps":["trace[1557617355] 'agreement among raft nodes before linearized reading' (duration: 215.600857ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:44:41.019956Z","caller":"traceutil/trace.go:171","msg":"trace[182461714] linearizableReadLoop","detail":"{readStateIndex:9157; appliedIndex:9156; }","duration":"215.526855ms","start":"2026-09-02T16:44:40.80442Z","end":"2026-09-02T16:44:41.019946Z","steps":["trace[182461714] 'read index received' (duration: 55.369933ms)","trace[182461714] 'applied index is now lower than readState.Index' (duration: 160.155672ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:44:41.178678Z","caller":"traceutil/trace.go:171","msg":"trace[2074144193] transaction","detail":"{read_only:false; response_revision:8846; number_of_response:1; }","duration":"277.961723ms","start":"2026-09-02T16:44:40.900685Z","end":"2026-09-02T16:44:41.178647Z","steps":["trace[2074144193] 'process raft request' (duration: 229.267282ms)","trace[2074144193] 'compare' (duration: 48.458446ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:44:41.178842Z","caller":"traceutil/trace.go:171","msg":"trace[2146774752] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"154.043341ms","start":"2026-09-02T16:44:41.02477Z","end":"2026-09-02T16:44:41.178813Z","steps":["trace[2146774752] 'process raft request' (duration: 153.843747ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:44:41.178852Z","caller":"traceutil/trace.go:171","msg":"trace[1981595426] linearizableReadLoop","detail":"{readStateIndex:9159; appliedIndex:9158; }","duration":"158.426156ms","start":"2026-09-02T16:44:41.020221Z","end":"2026-09-02T16:44:41.178648Z","steps":["trace[1981595426] 'read index received' (duration: 109.706815ms)","trace[1981595426] 'applied index is now lower than readState.Index' (duration: 48.717941ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T16:44:41.178967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.333947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-02T16:44:41.178875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.785635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T16:44:41.178995Z","caller":"traceutil/trace.go:171","msg":"trace[1106186649] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8846; }","duration":"257.414239ms","start":"2026-09-02T16:44:40.92157Z","end":"2026-09-02T16:44:41.178984Z","steps":["trace[1106186649] 'agreement among raft nodes before linearized reading' (duration: 257.105812ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:44:41.179015Z","caller":"traceutil/trace.go:171","msg":"trace[1932342280] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8846; }","duration":"217.986959ms","start":"2026-09-02T16:44:40.961008Z","end":"2026-09-02T16:44:41.178995Z","steps":["trace[1932342280] 'agreement among raft nodes before linearized reading' (duration: 217.665972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:44:41.179034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.417309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hw967\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-02T16:44:41.1791Z","caller":"traceutil/trace.go:171","msg":"trace[1115374152] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hw967; range_end:; response_count:1; response_revision:8846; }","duration":"186.586413ms","start":"2026-09-02T16:44:40.992494Z","end":"2026-09-02T16:44:41.179081Z","steps":["trace[1115374152] 'agreement among raft nodes before linearized reading' (duration: 186.200105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:44:41.179202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.656299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-02T16:44:41.179342Z","caller":"traceutil/trace.go:171","msg":"trace[438521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8846; }","duration":"369.872893ms","start":"2026-09-02T16:44:40.809439Z","end":"2026-09-02T16:44:41.179312Z","steps":["trace[438521] 'agreement among raft nodes before linearized reading' (duration: 369.214709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:44:41.179455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T16:44:40.809427Z","time spent":"370.008255ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-02T16:44:45.533776Z","caller":"traceutil/trace.go:171","msg":"trace[1956863294] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"108.268773ms","start":"2026-09-02T16:44:45.425476Z","end":"2026-09-02T16:44:45.533745Z","steps":["trace[1956863294] 'process raft request' (duration: 108.093249ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:45:08.916955Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024da.wal"} {"level":"info","ts":"2026-09-02T16:46:03.285679Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T16:46:03.292619Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-02T16:46:03.292731Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-02T16:48:04.91817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8082} {"level":"info","ts":"2026-09-02T16:48:04.992728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8082,"took":"72.215172ms","hash":2527766723,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-02T16:48:04.992805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2527766723,"revision":8082,"compact-revision":5916} {"level":"warn","ts":"2026-09-02T16:51:50.520099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.214744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hw967\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-02T16:51:50.520181Z","caller":"traceutil/trace.go:171","msg":"trace[1439229809] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hw967; range_end:; response_count:1; response_revision:12146; }","duration":"118.344237ms","start":"2026-09-02T16:51:50.401818Z","end":"2026-09-02T16:51:50.520163Z","steps":["trace[1439229809] 'range keys from in-memory index tree' (duration: 118.04894ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:53:04.924931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10516} {"level":"info","ts":"2026-09-02T16:53:05.010366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10516,"took":"83.0467ms","hash":2966705141,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":26042368,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-02T16:53:05.010486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2966705141,"revision":10516,"compact-revision":8082} {"level":"info","ts":"2026-09-02T16:58:04.931313Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12644} {"level":"info","ts":"2026-09-02T16:58:05.009036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12644,"took":"75.308506ms","hash":2129435873,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":26886144,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-02T16:58:05.009115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2129435873,"revision":12644,"compact-revision":10516} {"level":"info","ts":"2026-09-02T16:58:16.782987Z","caller":"traceutil/trace.go:171","msg":"trace[1725399335] transaction","detail":"{read_only:false; response_revision:14745; number_of_response:1; }","duration":"132.968339ms","start":"2026-09-02T16:58:16.649964Z","end":"2026-09-02T16:58:16.782932Z","steps":["trace[1725399335] 'process raft request' (duration: 132.814875ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:58:40.595794Z","caller":"traceutil/trace.go:171","msg":"trace[1020166381] linearizableReadLoop","detail":"{readStateIndex:15586; appliedIndex:15585; }","duration":"222.383144ms","start":"2026-09-02T16:58:40.373385Z","end":"2026-09-02T16:58:40.595768Z","steps":["trace[1020166381] 'read index received' (duration: 222.252231ms)","trace[1020166381] 'applied index is now lower than readState.Index' (duration: 129.933µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T16:58:40.595854Z","caller":"traceutil/trace.go:171","msg":"trace[82118188] transaction","detail":"{read_only:false; response_revision:15060; number_of_response:1; }","duration":"280.641659ms","start":"2026-09-02T16:58:40.315184Z","end":"2026-09-02T16:58:40.595826Z","steps":["trace[82118188] 'process raft request' (duration: 280.439515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T16:58:40.596016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.618059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-02T16:58:40.596056Z","caller":"traceutil/trace.go:171","msg":"trace[2026945470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15060; }","duration":"222.703181ms","start":"2026-09-02T16:58:40.373342Z","end":"2026-09-02T16:58:40.596045Z","steps":["trace[2026945470] 'agreement among raft nodes before linearized reading' (duration: 222.553618ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:58:40.656795Z","caller":"traceutil/trace.go:171","msg":"trace[958910820] transaction","detail":"{read_only:false; response_revision:15061; number_of_response:1; }","duration":"200.603163ms","start":"2026-09-02T16:58:40.456145Z","end":"2026-09-02T16:58:40.656748Z","steps":["trace[958910820] 'process raft request' (duration: 200.338758ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T16:58:40.730787Z","caller":"traceutil/trace.go:171","msg":"trace[1050284687] transaction","detail":"{read_only:false; response_revision:15062; number_of_response:1; }","duration":"129.624608ms","start":"2026-09-02T16:58:40.601135Z","end":"2026-09-02T16:58:40.730759Z","steps":["trace[1050284687] 'process raft request' (duration: 83.50483ms)","trace[1050284687] 'compare' (duration: 45.982235ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:01:19.099183Z","caller":"traceutil/trace.go:171","msg":"trace[1802163446] transaction","detail":"{read_only:false; response_revision:16372; number_of_response:1; }","duration":"123.924768ms","start":"2026-09-02T17:01:18.975238Z","end":"2026-09-02T17:01:19.099162Z","steps":["trace[1802163446] 'process raft request' (duration: 123.774735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:01:24.684104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.866225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:01:24.684288Z","caller":"traceutil/trace.go:171","msg":"trace[1164983616] linearizableReadLoop","detail":"{readStateIndex:16966; appliedIndex:16965; }","duration":"158.106951ms","start":"2026-09-02T17:01:24.526152Z","end":"2026-09-02T17:01:24.684259Z","steps":["trace[1164983616] 'read index received' (duration: 17.993171ms)","trace[1164983616] 'applied index is now lower than readState.Index' (duration: 140.11244ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:01:24.684319Z","caller":"traceutil/trace.go:171","msg":"trace[1425725687] transaction","detail":"{read_only:false; response_revision:16402; number_of_response:1; }","duration":"203.79906ms","start":"2026-09-02T17:01:24.480488Z","end":"2026-09-02T17:01:24.684287Z","steps":["trace[1425725687] 'process raft request' (duration: 63.652599ms)","trace[1425725687] 'compare' (duration: 139.720412ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:01:24.684515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.566987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-02T17:01:24.684556Z","caller":"traceutil/trace.go:171","msg":"trace[1522184933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16402; }","duration":"129.660288ms","start":"2026-09-02T17:01:24.554881Z","end":"2026-09-02T17:01:24.684541Z","steps":["trace[1522184933] 'agreement among raft nodes before linearized reading' (duration: 129.501395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:01:24.684613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.435078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-02T17:01:24.684711Z","caller":"traceutil/trace.go:171","msg":"trace[612235949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16402; }","duration":"158.544671ms","start":"2026-09-02T17:01:24.526139Z","end":"2026-09-02T17:01:24.684684Z","steps":["trace[612235949] 'agreement among raft nodes before linearized reading' (duration: 158.250014ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:01:24.915181Z","caller":"traceutil/trace.go:171","msg":"trace[1780369683] transaction","detail":"{read_only:false; response_revision:16405; number_of_response:1; }","duration":"104.427683ms","start":"2026-09-02T17:01:24.810702Z","end":"2026-09-02T17:01:24.91513Z","steps":["trace[1780369683] 'process raft request' (duration: 72.273552ms)","trace[1780369683] 'compare' (duration: 31.983858ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:01:25.112846Z","caller":"traceutil/trace.go:171","msg":"trace[2077190950] transaction","detail":"{read_only:false; response_revision:16406; number_of_response:1; }","duration":"191.367347ms","start":"2026-09-02T17:01:24.921446Z","end":"2026-09-02T17:01:25.112814Z","steps":["trace[2077190950] 'process raft request' (duration: 111.157877ms)","trace[2077190950] 'compare' (duration: 80.070137ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:01:25.551514Z","caller":"traceutil/trace.go:171","msg":"trace[1196194287] transaction","detail":"{read_only:false; response_revision:16407; number_of_response:1; }","duration":"201.135125ms","start":"2026-09-02T17:01:25.350288Z","end":"2026-09-02T17:01:25.551423Z","steps":["trace[1196194287] 'process raft request' (duration: 200.676825ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:03:04.93652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14668} {"level":"info","ts":"2026-09-02T17:03:05.01822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14668,"took":"78.589326ms","hash":139896117,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":34410496,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-02T17:03:05.018318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":139896117,"revision":14668,"compact-revision":12644} {"level":"info","ts":"2026-09-02T17:06:18.661125Z","caller":"traceutil/trace.go:171","msg":"trace[1299433119] transaction","detail":"{read_only:false; response_revision:18659; number_of_response:1; }","duration":"278.481094ms","start":"2026-09-02T17:06:18.382586Z","end":"2026-09-02T17:06:18.661067Z","steps":["trace[1299433119] 'process raft request' (duration: 230.873955ms)","trace[1299433119] 'compare' (duration: 47.236132ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:06:19.161768Z","caller":"traceutil/trace.go:171","msg":"trace[361883065] linearizableReadLoop","detail":"{readStateIndex:19297; appliedIndex:19296; }","duration":"332.359426ms","start":"2026-09-02T17:06:18.829371Z","end":"2026-09-02T17:06:19.16173Z","steps":["trace[361883065] 'read index received' (duration: 332.224032ms)","trace[361883065] 'applied index is now lower than readState.Index' (duration: 134.044µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:06:19.162251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.869507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-02T17:06:19.162314Z","caller":"traceutil/trace.go:171","msg":"trace[1806520537] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18661; }","duration":"332.98619ms","start":"2026-09-02T17:06:18.829309Z","end":"2026-09-02T17:06:19.162295Z","steps":["trace[1806520537] 'agreement among raft nodes before linearized reading' (duration: 332.558831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:06:19.162359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:06:18.829295Z","time spent":"333.051551ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-02T17:06:19.162411Z","caller":"traceutil/trace.go:171","msg":"trace[1154360551] transaction","detail":"{read_only:false; response_revision:18661; number_of_response:1; }","duration":"415.52051ms","start":"2026-09-02T17:06:18.746838Z","end":"2026-09-02T17:06:19.162358Z","steps":["trace[1154360551] 'process raft request' (duration: 414.660941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:06:19.162644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:06:18.746809Z","time spent":"415.736194ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:06:19.172074Z","caller":"traceutil/trace.go:171","msg":"trace[1313933711] transaction","detail":"{read_only:false; response_revision:18663; number_of_response:1; }","duration":"303.847041ms","start":"2026-09-02T17:06:18.868192Z","end":"2026-09-02T17:06:19.172039Z","steps":["trace[1313933711] 'process raft request' (duration: 303.78675ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:06:19.17218Z","caller":"traceutil/trace.go:171","msg":"trace[1378464880] transaction","detail":"{read_only:false; response_revision:18662; number_of_response:1; }","duration":"314.518137ms","start":"2026-09-02T17:06:18.857571Z","end":"2026-09-02T17:06:19.172089Z","steps":["trace[1378464880] 'process raft request' (duration: 314.270932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:06:19.172259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:06:18.868167Z","time spent":"304.010904ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:06:19.172397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:06:18.857551Z","time spent":"314.745632ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:07:18.916827Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T17:07:18.924881Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-02T17:07:18.924995Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-02T17:08:04.944056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17384} {"level":"info","ts":"2026-09-02T17:08:05.056287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17384,"took":"107.614282ms","hash":127014844,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":34496512,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-02T17:08:05.056358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":127014844,"revision":17384,"compact-revision":14668} {"level":"warn","ts":"2026-09-02T17:10:33.203937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.963337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:10:33.2041Z","caller":"traceutil/trace.go:171","msg":"trace[710322857] transaction","detail":"{read_only:false; response_revision:20828; number_of_response:1; }","duration":"227.710918ms","start":"2026-09-02T17:10:32.976364Z","end":"2026-09-02T17:10:33.204075Z","steps":["trace[710322857] 'process raft request' (duration: 105.525368ms)","trace[710322857] 'compare' (duration: 121.837412ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:12:01.653521Z","caller":"traceutil/trace.go:171","msg":"trace[1318930326] linearizableReadLoop","detail":"{readStateIndex:22061; appliedIndex:22060; }","duration":"132.832014ms","start":"2026-09-02T17:12:01.520661Z","end":"2026-09-02T17:12:01.653493Z","steps":["trace[1318930326] 'read index received' (duration: 132.555138ms)","trace[1318930326] 'applied index is now lower than readState.Index' (duration: 275.656µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:12:01.653759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.069249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:12:01.653768Z","caller":"traceutil/trace.go:171","msg":"trace[40942352] transaction","detail":"{read_only:false; response_revision:21339; number_of_response:1; }","duration":"341.794338ms","start":"2026-09-02T17:12:01.311923Z","end":"2026-09-02T17:12:01.653717Z","steps":["trace[40942352] 'process raft request' (duration: 341.37177ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:12:01.653811Z","caller":"traceutil/trace.go:171","msg":"trace[1692964074] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:21339; }","duration":"133.177371ms","start":"2026-09-02T17:12:01.520615Z","end":"2026-09-02T17:12:01.653792Z","steps":["trace[1692964074] 'agreement among raft nodes before linearized reading' (duration: 133.007317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:12:01.654112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:12:01.311902Z","time spent":"341.961663ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:12:02.440329Z","caller":"traceutil/trace.go:171","msg":"trace[1266635161] transaction","detail":"{read_only:false; response_revision:21341; number_of_response:1; }","duration":"141.54974ms","start":"2026-09-02T17:12:02.298752Z","end":"2026-09-02T17:12:02.440302Z","steps":["trace[1266635161] 'process raft request' (duration: 141.339765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:12:02.722685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.552194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:12:02.722995Z","caller":"traceutil/trace.go:171","msg":"trace[1183670525] transaction","detail":"{read_only:false; response_revision:21344; number_of_response:1; }","duration":"146.080956ms","start":"2026-09-02T17:12:02.576895Z","end":"2026-09-02T17:12:02.722976Z","steps":["trace[1183670525] 'process raft request' (duration: 146.015185ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:12:02.72299Z","caller":"traceutil/trace.go:171","msg":"trace[2013038107] transaction","detail":"{read_only:false; response_revision:21342; number_of_response:1; }","duration":"423.548357ms","start":"2026-09-02T17:12:02.29941Z","end":"2026-09-02T17:12:02.722958Z","steps":["trace[2013038107] 'process raft request' (duration: 199.581584ms)","trace[2013038107] 'compare' (duration: 223.294199ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:12:02.723064Z","caller":"traceutil/trace.go:171","msg":"trace[1467526657] transaction","detail":"{read_only:false; response_revision:21343; number_of_response:1; }","duration":"423.551497ms","start":"2026-09-02T17:12:02.299498Z","end":"2026-09-02T17:12:02.723049Z","steps":["trace[1467526657] 'process raft request' (duration: 423.322662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:12:02.723166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:12:02.299394Z","time spent":"423.68128ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:12:02.723214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:12:02.29949Z","time spent":"423.604667ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:12:03.03987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.440358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:12:03.040136Z","caller":"traceutil/trace.go:171","msg":"trace[6228551] transaction","detail":"{read_only:false; response_revision:21345; number_of_response:1; }","duration":"445.202448ms","start":"2026-09-02T17:12:02.594894Z","end":"2026-09-02T17:12:03.040096Z","steps":["trace[6228551] 'process raft request' (duration: 239.348791ms)","trace[6228551] 'compare' (duration: 204.853226ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:12:03.040333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:12:02.594861Z","time spent":"445.362411ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:12:03.040165Z","caller":"traceutil/trace.go:171","msg":"trace[1634342785] linearizableReadLoop","detail":"{readStateIndex:22067; appliedIndex:22063; }","duration":"418.855277ms","start":"2026-09-02T17:12:02.621255Z","end":"2026-09-02T17:12:03.040111Z","steps":["trace[1634342785] 'read index received' (duration: 17.477122ms)","trace[1634342785] 'applied index is now lower than readState.Index' (duration: 401.376535ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:12:03.040461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.197804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-02T17:12:03.040535Z","caller":"traceutil/trace.go:171","msg":"trace[73548331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21345; }","duration":"419.314737ms","start":"2026-09-02T17:12:02.621206Z","end":"2026-09-02T17:12:03.040521Z","steps":["trace[73548331] 'agreement among raft nodes before linearized reading' (duration: 418.98798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:12:03.04057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.645671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:12:03.040639Z","caller":"traceutil/trace.go:171","msg":"trace[411484776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21345; }","duration":"307.756714ms","start":"2026-09-02T17:12:02.732864Z","end":"2026-09-02T17:12:03.040621Z","steps":["trace[411484776] 'agreement among raft nodes before linearized reading' (duration: 307.624801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:12:03.040636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:12:02.621182Z","time spent":"419.432969ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-02T17:12:03.040691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:12:02.732847Z","time spent":"307.831545ms","remote":"127.0.0.1:53708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-02T17:12:03.283392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.740785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:12:03.283507Z","caller":"traceutil/trace.go:171","msg":"trace[1354364604] linearizableReadLoop","detail":"{readStateIndex:22068; appliedIndex:22067; }","duration":"146.036755ms","start":"2026-09-02T17:12:03.13745Z","end":"2026-09-02T17:12:03.283487Z","steps":["trace[1354364604] 'read index received' (duration: 43.133347ms)","trace[1354364604] 'applied index is now lower than readState.Index' (duration: 102.901888ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:12:03.284516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.061117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-02T17:12:03.28451Z","caller":"traceutil/trace.go:171","msg":"trace[122965110] transaction","detail":"{read_only:false; response_revision:21346; number_of_response:1; }","duration":"238.335919ms","start":"2026-09-02T17:12:03.045236Z","end":"2026-09-02T17:12:03.283572Z","steps":["trace[122965110] 'process raft request' (duration: 135.315018ms)","trace[122965110] 'compare' (duration: 102.604852ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:12:03.28462Z","caller":"traceutil/trace.go:171","msg":"trace[1583711655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21346; }","duration":"147.190351ms","start":"2026-09-02T17:12:03.137389Z","end":"2026-09-02T17:12:03.284579Z","steps":["trace[1583711655] 'agreement among raft nodes before linearized reading' (duration: 146.270401ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:12:03.398081Z","caller":"traceutil/trace.go:171","msg":"trace[896295130] linearizableReadLoop","detail":"{readStateIndex:22069; appliedIndex:22068; }","duration":"114.509156ms","start":"2026-09-02T17:12:03.283538Z","end":"2026-09-02T17:12:03.398047Z","steps":["trace[896295130] 'read index received' (duration: 59.559547ms)","trace[896295130] 'applied index is now lower than readState.Index' (duration: 54.946379ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:12:03.398324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.121418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-02T17:12:03.398379Z","caller":"traceutil/trace.go:171","msg":"trace[237043115] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21346; }","duration":"241.22931ms","start":"2026-09-02T17:12:03.157134Z","end":"2026-09-02T17:12:03.398364Z","steps":["trace[237043115] 'agreement among raft nodes before linearized reading' (duration: 241.021186ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:12:03.398553Z","caller":"traceutil/trace.go:171","msg":"trace[1638304970] transaction","detail":"{read_only:false; response_revision:21347; number_of_response:1; }","duration":"109.11148ms","start":"2026-09-02T17:12:03.289384Z","end":"2026-09-02T17:12:03.398495Z","steps":["trace[1638304970] 'process raft request' (duration: 108.559758ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:13:04.953119Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19707} {"level":"info","ts":"2026-09-02T17:13:05.084507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19707,"took":"128.129425ms","hash":135869636,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":30076928,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-02T17:13:05.084571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":135869636,"revision":19707,"compact-revision":17384} {"level":"info","ts":"2026-09-02T17:16:03.193914Z","caller":"traceutil/trace.go:171","msg":"trace[741171018] transaction","detail":"{read_only:false; response_revision:23850; number_of_response:1; }","duration":"102.640203ms","start":"2026-09-02T17:16:03.091254Z","end":"2026-09-02T17:16:03.193894Z","steps":["trace[741171018] 'process raft request' (duration: 102.468348ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:16:03.495275Z","caller":"traceutil/trace.go:171","msg":"trace[1958225649] linearizableReadLoop","detail":"{readStateIndex:24649; appliedIndex:24647; }","duration":"145.933603ms","start":"2026-09-02T17:16:03.349319Z","end":"2026-09-02T17:16:03.495252Z","steps":["trace[1958225649] 'read index received' (duration: 82.084485ms)","trace[1958225649] 'applied index is now lower than readState.Index' (duration: 63.848008ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:16:03.49531Z","caller":"traceutil/trace.go:171","msg":"trace[1449922879] transaction","detail":"{read_only:false; response_revision:23852; number_of_response:1; }","duration":"328.326752ms","start":"2026-09-02T17:16:03.166955Z","end":"2026-09-02T17:16:03.495282Z","steps":["trace[1449922879] 'process raft request' (duration: 328.24555ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:16:03.495342Z","caller":"traceutil/trace.go:171","msg":"trace[383427893] transaction","detail":"{read_only:false; response_revision:23851; number_of_response:1; }","duration":"394.050401ms","start":"2026-09-02T17:16:03.101234Z","end":"2026-09-02T17:16:03.495284Z","steps":["trace[383427893] 'process raft request' (duration: 330.164452ms)","trace[383427893] 'compare' (duration: 63.676374ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:16:03.495487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.164738ms","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:625"} {"level":"warn","ts":"2026-09-02T17:16:03.495522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:16:03.166924Z","time spent":"328.487265ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:16:03.495536Z","caller":"traceutil/trace.go:171","msg":"trace[315345841] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23852; }","duration":"146.266021ms","start":"2026-09-02T17:16:03.349255Z","end":"2026-09-02T17:16:03.495521Z","steps":["trace[315345841] 'agreement among raft nodes before linearized reading' (duration: 146.103427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:16:03.495564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:16:03.101191Z","time spent":"394.238434ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:18:04.961541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21934} {"level":"info","ts":"2026-09-02T17:18:05.073799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21934,"took":"108.735499ms","hash":698356335,"current-db-size-bytes":56221696,"current-db-size":"56 MB","current-db-size-in-use-bytes":44359680,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-02T17:18:05.073867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":698356335,"revision":21934,"compact-revision":19707} {"level":"info","ts":"2026-09-02T17:19:20.542975Z","caller":"traceutil/trace.go:171","msg":"trace[710818904] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"106.607584ms","start":"2026-09-02T17:19:20.43633Z","end":"2026-09-02T17:19:20.542937Z","steps":["trace[710818904] 'process raft request' (duration: 106.42025ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:20.708802Z","caller":"traceutil/trace.go:171","msg":"trace[1565362870] linearizableReadLoop","detail":"{readStateIndex:26679; appliedIndex:26678; }","duration":"157.224094ms","start":"2026-09-02T17:19:20.551555Z","end":"2026-09-02T17:19:20.708779Z","steps":["trace[1565362870] 'read index received' (duration: 117.882925ms)","trace[1565362870] 'applied index is now lower than readState.Index' (duration: 39.339978ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:20.709013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.446648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-09-02T17:19:20.70905Z","caller":"traceutil/trace.go:171","msg":"trace[1524539037] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25828; }","duration":"157.603281ms","start":"2026-09-02T17:19:20.551436Z","end":"2026-09-02T17:19:20.70904Z","steps":["trace[1524539037] 'agreement among raft nodes before linearized reading' (duration: 157.421777ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:20.709298Z","caller":"traceutil/trace.go:171","msg":"trace[1816718219] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"174.274667ms","start":"2026-09-02T17:19:20.534992Z","end":"2026-09-02T17:19:20.709267Z","steps":["trace[1816718219] 'process raft request' (duration: 134.434577ms)","trace[1816718219] 'compare' (duration: 38.983382ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:20.951343Z","caller":"traceutil/trace.go:171","msg":"trace[1127740370] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"339.308677ms","start":"2026-09-02T17:19:20.612005Z","end":"2026-09-02T17:19:20.951314Z","steps":["trace[1127740370] 'process raft request' (duration: 260.067396ms)","trace[1127740370] 'compare' (duration: 78.726329ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:20.951478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:20.611985Z","time spent":"339.423879ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:20.99553Z","caller":"traceutil/trace.go:171","msg":"trace[1799302072] linearizableReadLoop","detail":"{readStateIndex:26681; appliedIndex:26679; }","duration":"197.865469ms","start":"2026-09-02T17:19:20.797636Z","end":"2026-09-02T17:19:20.995501Z","steps":["trace[1799302072] 'read index received' (duration: 74.534939ms)","trace[1799302072] 'applied index is now lower than readState.Index' (duration: 123.32946ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:20.995846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.149106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-02T17:19:20.995893Z","caller":"traceutil/trace.go:171","msg":"trace[2132613017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25830; }","duration":"198.271369ms","start":"2026-09-02T17:19:20.797608Z","end":"2026-09-02T17:19:20.995879Z","steps":["trace[2132613017] 'agreement among raft nodes before linearized reading' (duration: 198.003753ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:20.996077Z","caller":"traceutil/trace.go:171","msg":"trace[1625251627] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"269.485278ms","start":"2026-09-02T17:19:20.726501Z","end":"2026-09-02T17:19:20.995987Z","steps":["trace[1625251627] 'process raft request' (duration: 268.841674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:21.228277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.848694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:21.228369Z","caller":"traceutil/trace.go:171","msg":"trace[1862508351] transaction","detail":"{read_only:false; response_revision:25831; number_of_response:1; }","duration":"227.687387ms","start":"2026-09-02T17:19:21.000667Z","end":"2026-09-02T17:19:21.228354Z","steps":["trace[1862508351] 'process raft request' (duration: 71.683709ms)","trace[1862508351] 'compare' (duration: 155.509566ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:21.236658Z","caller":"traceutil/trace.go:171","msg":"trace[1143483269] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"145.278819ms","start":"2026-09-02T17:19:21.091352Z","end":"2026-09-02T17:19:21.236631Z","steps":["trace[1143483269] 'process raft request' (duration: 144.726096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:21.829664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16015539571726174138,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-02T17:19:21.852134Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000683d.wal"} {"level":"info","ts":"2026-09-02T17:19:21.852684Z","caller":"traceutil/trace.go:171","msg":"trace[172019049] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"609.405837ms","start":"2026-09-02T17:19:21.243227Z","end":"2026-09-02T17:19:21.852632Z","steps":["trace[172019049] 'process raft request' (duration: 609.150032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:21.852861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:21.243203Z","time spent":"609.56985ms","remote":"127.0.0.1:53922","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-09-02T17:19:21.881163Z","caller":"traceutil/trace.go:171","msg":"trace[64890559] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"469.575126ms","start":"2026-09-02T17:19:21.411554Z","end":"2026-09-02T17:19:21.881129Z","steps":["trace[64890559] 'process raft request' (duration: 469.33571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:21.881349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:21.411528Z","time spent":"469.721658ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:21.88137Z","caller":"traceutil/trace.go:171","msg":"trace[757952797] linearizableReadLoop","detail":"{readStateIndex:26686; appliedIndex:26683; }","duration":"552.672638ms","start":"2026-09-02T17:19:21.328652Z","end":"2026-09-02T17:19:21.881324Z","steps":["trace[757952797] 'read index received' (duration: 523.75346ms)","trace[757952797] 'applied index is now lower than readState.Index' (duration: 28.915308ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:21.881423Z","caller":"traceutil/trace.go:171","msg":"trace[1416786464] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"322.13641ms","start":"2026-09-02T17:19:21.559258Z","end":"2026-09-02T17:19:21.881394Z","steps":["trace[1416786464] 'process raft request' (duration: 321.821914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:21.881573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:21.55923Z","time spent":"322.262433ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:19:21.881609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.050396ms","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":"warn","ts":"2026-09-02T17:19:21.881642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.975684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-02T17:19:21.881639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.633143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:21.881685Z","caller":"traceutil/trace.go:171","msg":"trace[962105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25835; }","duration":"553.067666ms","start":"2026-09-02T17:19:21.328606Z","end":"2026-09-02T17:19:21.881674Z","steps":["trace[962105] 'agreement among raft nodes before linearized reading' (duration: 552.868502ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:21.881694Z","caller":"traceutil/trace.go:171","msg":"trace[1105855029] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25835; }","duration":"478.189109ms","start":"2026-09-02T17:19:21.403483Z","end":"2026-09-02T17:19:21.881672Z","steps":["trace[1105855029] 'agreement among raft nodes before linearized reading' (duration: 478.041246ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:21.881717Z","caller":"traceutil/trace.go:171","msg":"trace[1809682276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25835; }","duration":"150.798626ms","start":"2026-09-02T17:19:21.7309Z","end":"2026-09-02T17:19:21.881699Z","steps":["trace[1809682276] 'agreement among raft nodes before linearized reading' (duration: 150.633533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:21.881718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:21.328589Z","time spent":"553.121257ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-02T17:19:21.881754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:21.403447Z","time spent":"478.289961ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-02T17:19:21.881943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.141095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-02T17:19:21.882351Z","caller":"traceutil/trace.go:171","msg":"trace[1522455635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25835; }","duration":"212.312288ms","start":"2026-09-02T17:19:21.669717Z","end":"2026-09-02T17:19:21.882029Z","steps":["trace[1522455635] 'agreement among raft nodes before linearized reading' (duration: 211.951731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:22.176722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.374865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:22.176841Z","caller":"traceutil/trace.go:171","msg":"trace[761949375] linearizableReadLoop","detail":"{readStateIndex:26688; appliedIndex:26686; }","duration":"228.418871ms","start":"2026-09-02T17:19:21.94841Z","end":"2026-09-02T17:19:22.176829Z","steps":["trace[761949375] 'read index received' (duration: 78.892002ms)","trace[761949375] 'applied index is now lower than readState.Index' (duration: 149.525959ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:22.177038Z","caller":"traceutil/trace.go:171","msg":"trace[230541330] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"289.494194ms","start":"2026-09-02T17:19:21.887454Z","end":"2026-09-02T17:19:22.176948Z","steps":["trace[230541330] 'process raft request' (duration: 139.828313ms)","trace[230541330] 'compare' (duration: 149.222222ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:22.177178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.766339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-09-02T17:19:22.177217Z","caller":"traceutil/trace.go:171","msg":"trace[206126162] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25837; }","duration":"228.852771ms","start":"2026-09-02T17:19:21.948355Z","end":"2026-09-02T17:19:22.177207Z","steps":["trace[206126162] 'agreement among raft nodes before linearized reading' (duration: 228.708238ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:22.177084Z","caller":"traceutil/trace.go:171","msg":"trace[553396503] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"288.558595ms","start":"2026-09-02T17:19:21.888503Z","end":"2026-09-02T17:19:22.177061Z","steps":["trace[553396503] 'process raft request' (duration: 288.292059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:22.177496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.490164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-02T17:19:22.177519Z","caller":"traceutil/trace.go:171","msg":"trace[1095420289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25837; }","duration":"176.574846ms","start":"2026-09-02T17:19:22.000938Z","end":"2026-09-02T17:19:22.177513Z","steps":["trace[1095420289] 'agreement among raft nodes before linearized reading' (duration: 176.500104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:22.17759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.73174ms","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-09-02T17:19:22.17764Z","caller":"traceutil/trace.go:171","msg":"trace[1639563425] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25837; }","duration":"124.794791ms","start":"2026-09-02T17:19:22.052834Z","end":"2026-09-02T17:19:22.177629Z","steps":["trace[1639563425] 'agreement among raft nodes before linearized reading' (duration: 124.70621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:22.446347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.072064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:22.446554Z","caller":"traceutil/trace.go:171","msg":"trace[1305860796] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"264.273946ms","start":"2026-09-02T17:19:22.182205Z","end":"2026-09-02T17:19:22.446479Z","steps":["trace[1305860796] 'process raft request' (duration: 110.979898ms)","trace[1305860796] 'compare' (duration: 152.87342ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:22.483483Z","caller":"traceutil/trace.go:171","msg":"trace[924338631] linearizableReadLoop","detail":"{readStateIndex:26690; appliedIndex:26688; }","duration":"208.275313ms","start":"2026-09-02T17:19:22.275182Z","end":"2026-09-02T17:19:22.483457Z","steps":["trace[924338631] 'read index received' (duration: 17.992924ms)","trace[924338631] 'applied index is now lower than readState.Index' (duration: 190.281329ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:22.483572Z","caller":"traceutil/trace.go:171","msg":"trace[1190712798] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"246.135579ms","start":"2026-09-02T17:19:22.237401Z","end":"2026-09-02T17:19:22.483536Z","steps":["trace[1190712798] 'process raft request' (duration: 245.931615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:22.483719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.517368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-02T17:19:22.483778Z","caller":"traceutil/trace.go:171","msg":"trace[1202583717] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25839; }","duration":"208.585669ms","start":"2026-09-02T17:19:22.275175Z","end":"2026-09-02T17:19:22.48376Z","steps":["trace[1202583717] 'agreement among raft nodes before linearized reading' (duration: 208.382035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:22.722723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.829402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:22.72314Z","caller":"traceutil/trace.go:171","msg":"trace[495284230] linearizableReadLoop","detail":"{readStateIndex:26692; appliedIndex:26690; }","duration":"116.971505ms","start":"2026-09-02T17:19:22.605981Z","end":"2026-09-02T17:19:22.722952Z","steps":["trace[495284230] 'read index received' (duration: 6.830407ms)","trace[495284230] 'applied index is now lower than readState.Index' (duration: 110.139848ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:22.723134Z","caller":"traceutil/trace.go:171","msg":"trace[1212223365] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"230.545427ms","start":"2026-09-02T17:19:22.492547Z","end":"2026-09-02T17:19:22.723092Z","steps":["trace[1212223365] 'process raft request' (duration: 120.238055ms)","trace[1212223365] 'compare' (duration: 109.539636ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:22.723149Z","caller":"traceutil/trace.go:171","msg":"trace[418476168] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"172.852717ms","start":"2026-09-02T17:19:22.550235Z","end":"2026-09-02T17:19:22.723088Z","steps":["trace[418476168] 'process raft request' (duration: 172.624232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:22.723242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.26139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:22.723333Z","caller":"traceutil/trace.go:171","msg":"trace[27106758] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25841; }","duration":"117.360993ms","start":"2026-09-02T17:19:22.605944Z","end":"2026-09-02T17:19:22.723305Z","steps":["trace[27106758] 'agreement among raft nodes before linearized reading' (duration: 117.22038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:23.042844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.154304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:23.042979Z","caller":"traceutil/trace.go:171","msg":"trace[772287991] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25842; }","duration":"114.335499ms","start":"2026-09-02T17:19:22.928597Z","end":"2026-09-02T17:19:23.042932Z","steps":["trace[772287991] 'count revisions from in-memory index tree' (duration: 114.037911ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:23.332562Z","caller":"traceutil/trace.go:171","msg":"trace[1169120370] linearizableReadLoop","detail":"{readStateIndex:26695; appliedIndex:26694; }","duration":"100.224628ms","start":"2026-09-02T17:19:23.232308Z","end":"2026-09-02T17:19:23.332532Z","steps":["trace[1169120370] 'read index received' (duration: 46.975932ms)","trace[1169120370] 'applied index is now lower than readState.Index' (duration: 53.246956ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:23.332598Z","caller":"traceutil/trace.go:171","msg":"trace[1550978572] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"329.325374ms","start":"2026-09-02T17:19:23.003241Z","end":"2026-09-02T17:19:23.332566Z","steps":["trace[1550978572] 'process raft request' (duration: 275.955276ms)","trace[1550978572] 'compare' (duration: 53.110953ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:23.332734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:23.003224Z","time spent":"329.436786ms","remote":"127.0.0.1:53922","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-09-02T17:19:23.332788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.453012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-02T17:19:23.332826Z","caller":"traceutil/trace.go:171","msg":"trace[193980225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25844; }","duration":"100.547885ms","start":"2026-09-02T17:19:23.232268Z","end":"2026-09-02T17:19:23.332816Z","steps":["trace[193980225] 'agreement among raft nodes before linearized reading' (duration: 100.365151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:23.492989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.05872ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-02T17:19:23.493575Z","caller":"traceutil/trace.go:171","msg":"trace[2063687404] linearizableReadLoop","detail":"{readStateIndex:26697; appliedIndex:26695; }","duration":"141.517597ms","start":"2026-09-02T17:19:23.352025Z","end":"2026-09-02T17:19:23.493543Z","steps":["trace[2063687404] 'read index received' (duration: 6.267283ms)","trace[2063687404] 'applied index is now lower than readState.Index' (duration: 135.247324ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:23.493694Z","caller":"traceutil/trace.go:171","msg":"trace[1244017389] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"155.908925ms","start":"2026-09-02T17:19:23.337769Z","end":"2026-09-02T17:19:23.493678Z","steps":["trace[1244017389] 'process raft request' (duration: 155.333603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:23.650399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.350082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T17:19:23.65048Z","caller":"traceutil/trace.go:171","msg":"trace[1595980806] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25845; }","duration":"298.441125ms","start":"2026-09-02T17:19:23.352021Z","end":"2026-09-02T17:19:23.650462Z","steps":["trace[1595980806] 'agreement among raft nodes before linearized reading' (duration: 141.62306ms)","trace[1595980806] 'count revisions from in-memory index tree' (duration: 156.700252ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:23.650886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.800279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:23.651054Z","caller":"traceutil/trace.go:171","msg":"trace[1095570793] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"282.870653ms","start":"2026-09-02T17:19:23.368171Z","end":"2026-09-02T17:19:23.651041Z","steps":["trace[1095570793] 'process raft request' (duration: 136.834919ms)","trace[1095570793] 'compare' (duration: 145.466132ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:23.651211Z","caller":"traceutil/trace.go:171","msg":"trace[1903377700] linearizableReadLoop","detail":"{readStateIndex:26699; appliedIndex:26697; }","duration":"117.23812ms","start":"2026-09-02T17:19:23.533942Z","end":"2026-09-02T17:19:23.65118Z","steps":["trace[1903377700] 'read index received' (duration: 116.128917ms)","trace[1903377700] 'applied index is now lower than readState.Index' (duration: 1.107993ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:23.651312Z","caller":"traceutil/trace.go:171","msg":"trace[2030831672] transaction","detail":"{read_only:false; response_revision:25847; number_of_response:1; }","duration":"274.109145ms","start":"2026-09-02T17:19:23.37717Z","end":"2026-09-02T17:19:23.65128Z","steps":["trace[2030831672] 'process raft request' (duration: 273.824689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:23.651354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.398773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:23.651393Z","caller":"traceutil/trace.go:171","msg":"trace[1566064758] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25847; }","duration":"117.478365ms","start":"2026-09-02T17:19:23.533901Z","end":"2026-09-02T17:19:23.651379Z","steps":["trace[1566064758] 'agreement among raft nodes before linearized reading' (duration: 117.405793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:26.138629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.787034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:26.138733Z","caller":"traceutil/trace.go:171","msg":"trace[1390311409] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:25864; }","duration":"126.942497ms","start":"2026-09-02T17:19:26.011763Z","end":"2026-09-02T17:19:26.138706Z","steps":["trace[1390311409] 'count revisions from in-memory index tree' (duration: 126.63955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:26.557835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.682409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:26.558074Z","caller":"traceutil/trace.go:171","msg":"trace[1621390771] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"311.81152ms","start":"2026-09-02T17:19:26.246245Z","end":"2026-09-02T17:19:26.558057Z","steps":["trace[1621390771] 'process raft request' (duration: 311.673377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:26.558149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:26.246217Z","time spent":"311.895472ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:26.55816Z","caller":"traceutil/trace.go:171","msg":"trace[1172583195] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"359.896986ms","start":"2026-09-02T17:19:26.198182Z","end":"2026-09-02T17:19:26.558079Z","steps":["trace[1172583195] 'process raft request' (duration: 249.88303ms)","trace[1172583195] 'compare' (duration: 109.431064ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:26.558483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:26.198162Z","time spent":"360.12047ms","remote":"127.0.0.1:53922","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-09-02T17:19:26.565334Z","caller":"traceutil/trace.go:171","msg":"trace[1098595114] linearizableReadLoop","detail":"{readStateIndex:26720; appliedIndex:26719; }","duration":"104.51194ms","start":"2026-09-02T17:19:26.460799Z","end":"2026-09-02T17:19:26.565311Z","steps":["trace[1098595114] 'read index received' (duration: 104.428448ms)","trace[1098595114] 'applied index is now lower than readState.Index' (duration: 82.562µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:26.56546Z","caller":"traceutil/trace.go:171","msg":"trace[479920024] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"171.897837ms","start":"2026-09-02T17:19:26.393541Z","end":"2026-09-02T17:19:26.565439Z","steps":["trace[479920024] 'process raft request' (duration: 171.660832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:26.565534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.723814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-02T17:19:26.565558Z","caller":"traceutil/trace.go:171","msg":"trace[987371153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25868; }","duration":"104.805866ms","start":"2026-09-02T17:19:26.460745Z","end":"2026-09-02T17:19:26.565551Z","steps":["trace[987371153] 'agreement among raft nodes before linearized reading' (duration: 104.694253ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:26.646704Z","caller":"traceutil/trace.go:171","msg":"trace[422151735] transaction","detail":"{read_only:false; response_revision:25869; number_of_response:1; }","duration":"144.678276ms","start":"2026-09-02T17:19:26.501998Z","end":"2026-09-02T17:19:26.646676Z","steps":["trace[422151735] 'process raft request' (duration: 144.497012ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:26.887759Z","caller":"traceutil/trace.go:171","msg":"trace[1015000296] linearizableReadLoop","detail":"{readStateIndex:26722; appliedIndex:26721; }","duration":"155.437736ms","start":"2026-09-02T17:19:26.732301Z","end":"2026-09-02T17:19:26.887739Z","steps":["trace[1015000296] 'read index received' (duration: 124.327473ms)","trace[1015000296] 'applied index is now lower than readState.Index' (duration: 31.109033ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:26.887829Z","caller":"traceutil/trace.go:171","msg":"trace[134585817] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"316.683343ms","start":"2026-09-02T17:19:26.571082Z","end":"2026-09-02T17:19:26.887765Z","steps":["trace[134585817] 'process raft request' (duration: 285.529859ms)","trace[134585817] 'compare' (duration: 30.685285ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:26.887903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.595429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:26.888137Z","caller":"traceutil/trace.go:171","msg":"trace[519689369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25870; }","duration":"155.909076ms","start":"2026-09-02T17:19:26.732203Z","end":"2026-09-02T17:19:26.888112Z","steps":["trace[519689369] 'agreement among raft nodes before linearized reading' (duration: 155.6215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:26.88803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:26.571056Z","time spent":"316.857618ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:26.888687Z","caller":"traceutil/trace.go:171","msg":"trace[702296713] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"139.790842ms","start":"2026-09-02T17:19:26.748884Z","end":"2026-09-02T17:19:26.888675Z","steps":["trace[702296713] 'process raft request' (duration: 139.533836ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:26.888696Z","caller":"traceutil/trace.go:171","msg":"trace[1623936890] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"138.619836ms","start":"2026-09-02T17:19:26.750066Z","end":"2026-09-02T17:19:26.888686Z","steps":["trace[1623936890] 'process raft request' (duration: 138.511343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:26.888854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.685419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-02T17:19:26.888914Z","caller":"traceutil/trace.go:171","msg":"trace[84327495] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25872; }","duration":"139.784871ms","start":"2026-09-02T17:19:26.749114Z","end":"2026-09-02T17:19:26.888899Z","steps":["trace[84327495] 'agreement among raft nodes before linearized reading' (duration: 139.536626ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:36.101897Z","caller":"traceutil/trace.go:171","msg":"trace[2024453852] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"169.841592ms","start":"2026-09-02T17:19:35.932033Z","end":"2026-09-02T17:19:36.101875Z","steps":["trace[2024453852] 'process raft request' (duration: 169.628537ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:36.104236Z","caller":"traceutil/trace.go:171","msg":"trace[192228777] linearizableReadLoop","detail":"{readStateIndex:26791; appliedIndex:26789; }","duration":"165.494638ms","start":"2026-09-02T17:19:35.938708Z","end":"2026-09-02T17:19:36.104203Z","steps":["trace[192228777] 'read index received' (duration: 162.977005ms)","trace[192228777] 'applied index is now lower than readState.Index' (duration: 2.516753ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:36.104431Z","caller":"traceutil/trace.go:171","msg":"trace[2121425471] transaction","detail":"{read_only:false; response_revision:25937; number_of_response:1; }","duration":"171.707761ms","start":"2026-09-02T17:19:35.932702Z","end":"2026-09-02T17:19:36.10441Z","steps":["trace[2121425471] 'process raft request' (duration: 171.379604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:36.104453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.723673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-02T17:19:36.10449Z","caller":"traceutil/trace.go:171","msg":"trace[334052518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25937; }","duration":"165.814235ms","start":"2026-09-02T17:19:35.938665Z","end":"2026-09-02T17:19:36.10448Z","steps":["trace[334052518] 'agreement among raft nodes before linearized reading' (duration: 165.636312ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:40.748833Z","caller":"traceutil/trace.go:171","msg":"trace[1790776065] linearizableReadLoop","detail":"{readStateIndex:26881; appliedIndex:26880; }","duration":"122.057154ms","start":"2026-09-02T17:19:40.626757Z","end":"2026-09-02T17:19:40.748814Z","steps":["trace[1790776065] 'read index received' (duration: 121.907581ms)","trace[1790776065] 'applied index is now lower than readState.Index' (duration: 148.743µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:40.748873Z","caller":"traceutil/trace.go:171","msg":"trace[1761785241] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"138.213089ms","start":"2026-09-02T17:19:40.610636Z","end":"2026-09-02T17:19:40.748849Z","steps":["trace[1761785241] 'process raft request' (duration: 138.050916ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:40.749031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.256158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-02T17:19:40.74907Z","caller":"traceutil/trace.go:171","msg":"trace[345307125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26026; }","duration":"122.334919ms","start":"2026-09-02T17:19:40.626722Z","end":"2026-09-02T17:19:40.749057Z","steps":["trace[345307125] 'agreement among raft nodes before linearized reading' (duration: 122.196907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:41.101674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.423626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:41.101885Z","caller":"traceutil/trace.go:171","msg":"trace[517273980] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"348.447132ms","start":"2026-09-02T17:19:40.75342Z","end":"2026-09-02T17:19:41.101867Z","steps":["trace[517273980] 'process raft request' (duration: 348.35683ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:41.101899Z","caller":"traceutil/trace.go:171","msg":"trace[1367193228] linearizableReadLoop","detail":"{readStateIndex:26882; appliedIndex:26881; }","duration":"352.975818ms","start":"2026-09-02T17:19:40.748896Z","end":"2026-09-02T17:19:41.101872Z","steps":["trace[1367193228] 'read index received' (duration: 98.258696ms)","trace[1367193228] 'applied index is now lower than readState.Index' (duration: 254.715562ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:41.102001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:40.7534Z","time spent":"348.542704ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:41.102025Z","caller":"traceutil/trace.go:171","msg":"trace[1694723989] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"405.124601ms","start":"2026-09-02T17:19:40.696887Z","end":"2026-09-02T17:19:41.102012Z","steps":["trace[1694723989] 'process raft request' (duration: 150.271296ms)","trace[1694723989] 'compare' (duration: 254.243352ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:41.102127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:40.696864Z","time spent":"405.185642ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:19:41.102295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.252319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:41.102333Z","caller":"traceutil/trace.go:171","msg":"trace[752627940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26028; }","duration":"371.325099ms","start":"2026-09-02T17:19:40.730999Z","end":"2026-09-02T17:19:41.102324Z","steps":["trace[752627940] 'agreement among raft nodes before linearized reading' (duration: 371.260248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:41.102359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:40.730974Z","time spent":"371.379741ms","remote":"127.0.0.1:53708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-02T17:19:41.124062Z","caller":"traceutil/trace.go:171","msg":"trace[570093851] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"189.009722ms","start":"2026-09-02T17:19:40.935025Z","end":"2026-09-02T17:19:41.124034Z","steps":["trace[570093851] 'process raft request' (duration: 188.781977ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:41.124279Z","caller":"traceutil/trace.go:171","msg":"trace[313752148] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"186.004478ms","start":"2026-09-02T17:19:40.938218Z","end":"2026-09-02T17:19:41.124223Z","steps":["trace[313752148] 'process raft request' (duration: 185.770343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:41.12437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.085691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-02T17:19:41.12444Z","caller":"traceutil/trace.go:171","msg":"trace[1872176391] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26030; }","duration":"165.184743ms","start":"2026-09-02T17:19:40.959238Z","end":"2026-09-02T17:19:41.124422Z","steps":["trace[1872176391] 'agreement among raft nodes before linearized reading' (duration: 165.018229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:41.124559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.528458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-02T17:19:41.124622Z","caller":"traceutil/trace.go:171","msg":"trace[428677007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26030; }","duration":"171.659851ms","start":"2026-09-02T17:19:40.95295Z","end":"2026-09-02T17:19:41.12461Z","steps":["trace[428677007] 'agreement among raft nodes before linearized reading' (duration: 171.497737ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:41.253365Z","caller":"traceutil/trace.go:171","msg":"trace[1704814805] linearizableReadLoop","detail":"{readStateIndex:26886; appliedIndex:26885; }","duration":"129.146614ms","start":"2026-09-02T17:19:41.124199Z","end":"2026-09-02T17:19:41.253346Z","steps":["trace[1704814805] 'read index received' (duration: 120.634203ms)","trace[1704814805] 'applied index is now lower than readState.Index' (duration: 8.511741ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:41.253371Z","caller":"traceutil/trace.go:171","msg":"trace[598295970] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"135.054141ms","start":"2026-09-02T17:19:41.118298Z","end":"2026-09-02T17:19:41.253352Z","steps":["trace[598295970] 'process raft request' (duration: 126.518549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:41.253634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.911128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:41.253666Z","caller":"traceutil/trace.go:171","msg":"trace[2045832941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26032; }","duration":"149.990239ms","start":"2026-09-02T17:19:41.103667Z","end":"2026-09-02T17:19:41.253658Z","steps":["trace[2045832941] 'agreement among raft nodes before linearized reading' (duration: 149.773944ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:41.253766Z","caller":"traceutil/trace.go:171","msg":"trace[1164617876] transaction","detail":"{read_only:false; response_revision:26033; number_of_response:1; }","duration":"124.956426ms","start":"2026-09-02T17:19:41.128778Z","end":"2026-09-02T17:19:41.253734Z","steps":["trace[1164617876] 'process raft request' (duration: 124.845003ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:41.25387Z","caller":"traceutil/trace.go:171","msg":"trace[619022072] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"125.150279ms","start":"2026-09-02T17:19:41.128711Z","end":"2026-09-02T17:19:41.253862Z","steps":["trace[619022072] 'process raft request' (duration: 124.606958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:41.253864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.675951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2d5a4d11-18e4-44ec-ab06-2d25a9d44ea0\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-09-02T17:19:41.253986Z","caller":"traceutil/trace.go:171","msg":"trace[442812893] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2d5a4d11-18e4-44ec-ab06-2d25a9d44ea0; range_end:; response_count:1; response_revision:26033; }","duration":"126.812675ms","start":"2026-09-02T17:19:41.127162Z","end":"2026-09-02T17:19:41.253975Z","steps":["trace[442812893] 'agreement among raft nodes before linearized reading' (duration: 126.520788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:41.594115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.711593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T17:19:41.59417Z","caller":"traceutil/trace.go:171","msg":"trace[1721100042] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:26033; }","duration":"210.810426ms","start":"2026-09-02T17:19:41.383347Z","end":"2026-09-02T17:19:41.594157Z","steps":["trace[1721100042] 'count revisions from in-memory index tree' (duration: 210.633682ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:41.594206Z","caller":"traceutil/trace.go:171","msg":"trace[1953151637] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"184.376562ms","start":"2026-09-02T17:19:41.409805Z","end":"2026-09-02T17:19:41.594182Z","steps":["trace[1953151637] 'process raft request' (duration: 123.627346ms)","trace[1953151637] 'compare' (duration: 60.481319ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:41.989769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.531359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:41.989849Z","caller":"traceutil/trace.go:171","msg":"trace[1981187551] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"413.300325ms","start":"2026-09-02T17:19:41.576534Z","end":"2026-09-02T17:19:41.989834Z","steps":["trace[1981187551] 'process raft request' (duration: 128.629583ms)","trace[1981187551] 'compare' (duration: 283.871934ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:41.989893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:41.576518Z","time spent":"413.354076ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:42.1982Z","caller":"traceutil/trace.go:171","msg":"trace[527167933] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"615.961767ms","start":"2026-09-02T17:19:41.582201Z","end":"2026-09-02T17:19:42.198162Z","steps":["trace[527167933] 'process raft request' (duration: 580.852369ms)","trace[527167933] 'compare' (duration: 34.75174ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:42.19838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:41.582182Z","time spent":"616.096959ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:42.198167Z","caller":"traceutil/trace.go:171","msg":"trace[38139794] linearizableReadLoop","detail":"{readStateIndex:26891; appliedIndex:26889; }","duration":"603.993281ms","start":"2026-09-02T17:19:41.594146Z","end":"2026-09-02T17:19:42.198139Z","steps":["trace[38139794] 'read index received' (duration: 110.988007ms)","trace[38139794] 'applied index is now lower than readState.Index' (duration: 493.004204ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:42.198635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.170722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:42.19869Z","caller":"traceutil/trace.go:171","msg":"trace[491462191] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:26038; }","duration":"467.239374ms","start":"2026-09-02T17:19:41.731434Z","end":"2026-09-02T17:19:42.198673Z","steps":["trace[491462191] 'agreement among raft nodes before linearized reading' (duration: 467.124381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:42.198723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:41.731383Z","time spent":"467.330907ms","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true "} {"level":"warn","ts":"2026-09-02T17:19:42.198512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.270375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-02T17:19:42.198772Z","caller":"traceutil/trace.go:171","msg":"trace[630288723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26037; }","duration":"618.557881ms","start":"2026-09-02T17:19:41.580202Z","end":"2026-09-02T17:19:42.198759Z","steps":["trace[630288723] 'agreement among raft nodes before linearized reading' (duration: 618.046971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:42.198835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:41.580191Z","time spent":"618.631663ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-02T17:19:42.198894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.686798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:42.198915Z","caller":"traceutil/trace.go:171","msg":"trace[436898825] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:26038; }","duration":"463.707459ms","start":"2026-09-02T17:19:41.735201Z","end":"2026-09-02T17:19:42.198908Z","steps":["trace[436898825] 'agreement among raft nodes before linearized reading' (duration: 463.663128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:42.198933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:41.73518Z","time spent":"463.74738ms","remote":"127.0.0.1:37664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-09-02T17:19:42.199126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.371956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:42.199181Z","caller":"traceutil/trace.go:171","msg":"trace[1686638685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26038; }","duration":"466.450278ms","start":"2026-09-02T17:19:41.732708Z","end":"2026-09-02T17:19:42.199158Z","steps":["trace[1686638685] 'agreement among raft nodes before linearized reading' (duration: 466.366836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:42.19888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.108288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:42.199305Z","caller":"traceutil/trace.go:171","msg":"trace[326075457] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26038; }","duration":"276.59726ms","start":"2026-09-02T17:19:41.922688Z","end":"2026-09-02T17:19:42.199285Z","steps":["trace[326075457] 'agreement among raft nodes before linearized reading' (duration: 276.098878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:42.199212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:41.732693Z","time spent":"466.513079ms","remote":"127.0.0.1:53708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-02T17:19:42.387164Z","caller":"traceutil/trace.go:171","msg":"trace[345128268] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"184.72721ms","start":"2026-09-02T17:19:42.202403Z","end":"2026-09-02T17:19:42.38713Z","steps":["trace[345128268] 'process raft request' (duration: 101.612037ms)","trace[345128268] 'compare' (duration: 82.805166ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:42.599893Z","caller":"traceutil/trace.go:171","msg":"trace[1112172514] linearizableReadLoop","detail":"{readStateIndex:26896; appliedIndex:26895; }","duration":"112.670684ms","start":"2026-09-02T17:19:42.487205Z","end":"2026-09-02T17:19:42.599876Z","steps":["trace[1112172514] 'read index received' (duration: 112.49666ms)","trace[1112172514] 'applied index is now lower than readState.Index' (duration: 173.394µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:42.600041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.815277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:42.600071Z","caller":"traceutil/trace.go:171","msg":"trace[811247463] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26041; }","duration":"112.921059ms","start":"2026-09-02T17:19:42.487143Z","end":"2026-09-02T17:19:42.600064Z","steps":["trace[811247463] 'agreement among raft nodes before linearized reading' (duration: 112.837637ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:42.600047Z","caller":"traceutil/trace.go:171","msg":"trace[1308477489] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"208.481067ms","start":"2026-09-02T17:19:42.391521Z","end":"2026-09-02T17:19:42.600002Z","steps":["trace[1308477489] 'process raft request' (duration: 208.235941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:42.816172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.475387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:42.816279Z","caller":"traceutil/trace.go:171","msg":"trace[587418054] linearizableReadLoop","detail":"{readStateIndex:26897; appliedIndex:26896; }","duration":"163.730653ms","start":"2026-09-02T17:19:42.65253Z","end":"2026-09-02T17:19:42.816261Z","steps":["trace[587418054] 'read index received' (duration: 53.022551ms)","trace[587418054] 'applied index is now lower than readState.Index' (duration: 110.706922ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:42.816389Z","caller":"traceutil/trace.go:171","msg":"trace[1596183912] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"178.971987ms","start":"2026-09-02T17:19:42.637379Z","end":"2026-09-02T17:19:42.816351Z","steps":["trace[1596183912] 'process raft request' (duration: 68.223584ms)","trace[1596183912] 'compare' (duration: 110.315794ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:42.816415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.884706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:42.816596Z","caller":"traceutil/trace.go:171","msg":"trace[1782641011] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:26042; }","duration":"164.106221ms","start":"2026-09-02T17:19:42.652474Z","end":"2026-09-02T17:19:42.81658Z","steps":["trace[1782641011] 'agreement among raft nodes before linearized reading' (duration: 163.893206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.395746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.250989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:43.395849Z","caller":"traceutil/trace.go:171","msg":"trace[1499583108] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"280.123224ms","start":"2026-09-02T17:19:43.115704Z","end":"2026-09-02T17:19:43.395827Z","steps":["trace[1499583108] 'process raft request' (duration: 148.709951ms)","trace[1499583108] 'compare' (duration: 131.039095ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:43.781342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.622467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:43.781729Z","caller":"traceutil/trace.go:171","msg":"trace[463231224] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"652.379733ms","start":"2026-09-02T17:19:43.129308Z","end":"2026-09-02T17:19:43.781688Z","steps":["trace[463231224] 'process raft request' (duration: 422.274815ms)","trace[463231224] 'compare' (duration: 228.813181ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:43.781905Z","caller":"traceutil/trace.go:171","msg":"trace[2080382932] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"652.474835ms","start":"2026-09-02T17:19:43.129405Z","end":"2026-09-02T17:19:43.78188Z","steps":["trace[2080382932] 'process raft request' (duration: 652.193119ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:43.782009Z","caller":"traceutil/trace.go:171","msg":"trace[1482759442] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"521.005492ms","start":"2026-09-02T17:19:43.260934Z","end":"2026-09-02T17:19:43.78194Z","steps":["trace[1482759442] 'process raft request' (duration: 520.835978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.782069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.129291Z","time spent":"652.509096ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:43.782102Z","caller":"traceutil/trace.go:171","msg":"trace[1355784099] linearizableReadLoop","detail":"{readStateIndex:26901; appliedIndex:26898; }","duration":"533.473068ms","start":"2026-09-02T17:19:43.2486Z","end":"2026-09-02T17:19:43.782073Z","steps":["trace[1355784099] 'read index received' (duration: 15.760426ms)","trace[1355784099] 'applied index is now lower than readState.Index' (duration: 517.709082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:43.782068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.129384Z","time spent":"652.616459ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:19:43.782227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.260918Z","time spent":"521.190137ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:19:43.782233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.635912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T17:19:43.782319Z","caller":"traceutil/trace.go:171","msg":"trace[1071087016] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26047; }","duration":"533.758365ms","start":"2026-09-02T17:19:43.24855Z","end":"2026-09-02T17:19:43.782308Z","steps":["trace[1071087016] 'agreement among raft nodes before linearized reading' (duration: 533.629102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.782352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.248532Z","time spent":"533.811045ms","remote":"127.0.0.1:44154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-09-02T17:19:43.797988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.481927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-02T17:19:43.798088Z","caller":"traceutil/trace.go:171","msg":"trace[1738933991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26048; }","duration":"540.659961ms","start":"2026-09-02T17:19:43.257403Z","end":"2026-09-02T17:19:43.798063Z","steps":["trace[1738933991] 'agreement among raft nodes before linearized reading' (duration: 540.361755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.798144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.257384Z","time spent":"540.747943ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-02T17:19:43.798234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.064909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:43.798358Z","caller":"traceutil/trace.go:171","msg":"trace[1647997202] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26048; }","duration":"315.226223ms","start":"2026-09-02T17:19:43.483113Z","end":"2026-09-02T17:19:43.798339Z","steps":["trace[1647997202] 'agreement among raft nodes before linearized reading' (duration: 315.048739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.798415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.483101Z","time spent":"315.285324ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-02T17:19:43.798578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.509998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-02T17:19:43.798635Z","caller":"traceutil/trace.go:171","msg":"trace[706933886] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26048; }","duration":"540.60106ms","start":"2026-09-02T17:19:43.258019Z","end":"2026-09-02T17:19:43.79862Z","steps":["trace[706933886] 'agreement among raft nodes before linearized reading' (duration: 540.447837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.798673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.258001Z","time spent":"540.662331ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-02T17:19:43.798633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.191641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8dd465e4-2834-416a-b09f-70aa1c835f6b\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-09-02T17:19:43.798773Z","caller":"traceutil/trace.go:171","msg":"trace[641965443] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"199.155378ms","start":"2026-09-02T17:19:43.599598Z","end":"2026-09-02T17:19:43.798754Z","steps":["trace[641965443] 'process raft request' (duration: 197.938522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.798871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.507397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-09-02T17:19:43.798912Z","caller":"traceutil/trace.go:171","msg":"trace[1954605444] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26048; }","duration":"109.593789ms","start":"2026-09-02T17:19:43.689308Z","end":"2026-09-02T17:19:43.798902Z","steps":["trace[1954605444] 'agreement among raft nodes before linearized reading' (duration: 109.458225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.798724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.128791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T17:19:43.800052Z","caller":"traceutil/trace.go:171","msg":"trace[96321533] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26048; }","duration":"331.872518ms","start":"2026-09-02T17:19:43.46815Z","end":"2026-09-02T17:19:43.800023Z","steps":["trace[96321533] 'agreement among raft nodes before linearized reading' (duration: 330.07117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:43.800196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.46812Z","time spent":"332.055273ms","remote":"127.0.0.1:54084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-09-02T17:19:43.799204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.422662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:43.800387Z","caller":"traceutil/trace.go:171","msg":"trace[2076616711] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:26048; }","duration":"251.632927ms","start":"2026-09-02T17:19:43.548725Z","end":"2026-09-02T17:19:43.800358Z","steps":["trace[2076616711] 'agreement among raft nodes before linearized reading' (duration: 250.393541ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:43.79946Z","caller":"traceutil/trace.go:171","msg":"trace[1424740913] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8dd465e4-2834-416a-b09f-70aa1c835f6b; range_end:; response_count:1; response_revision:26048; }","duration":"194.416426ms","start":"2026-09-02T17:19:43.604353Z","end":"2026-09-02T17:19:43.798769Z","steps":["trace[1424740913] 'agreement among raft nodes before linearized reading' (duration: 193.710141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:44.18917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.615264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:19:44.189276Z","caller":"traceutil/trace.go:171","msg":"trace[1291416785] transaction","detail":"{read_only:false; response_revision:26049; number_of_response:1; }","duration":"385.708427ms","start":"2026-09-02T17:19:43.803547Z","end":"2026-09-02T17:19:44.189256Z","steps":["trace[1291416785] 'process raft request' (duration: 271.92506ms)","trace[1291416785] 'compare' (duration: 113.367788ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:19:44.189337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.803521Z","time spent":"385.783118ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:44.44068Z","caller":"traceutil/trace.go:171","msg":"trace[104686347] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"636.259721ms","start":"2026-09-02T17:19:43.804392Z","end":"2026-09-02T17:19:44.440652Z","steps":["trace[104686347] 'process raft request' (duration: 631.204083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:44.440901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.804375Z","time spent":"636.425114ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:44.441742Z","caller":"traceutil/trace.go:171","msg":"trace[1292739395] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"637.111297ms","start":"2026-09-02T17:19:43.804619Z","end":"2026-09-02T17:19:44.441731Z","steps":["trace[1292739395] 'process raft request' (duration: 635.978154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:44.441797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:43.804605Z","time spent":"637.166168ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:44.442108Z","caller":"traceutil/trace.go:171","msg":"trace[969938680] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"386.511933ms","start":"2026-09-02T17:19:44.055587Z","end":"2026-09-02T17:19:44.442099Z","steps":["trace[969938680] 'process raft request' (duration: 386.100765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:44.442151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:44.055563Z","time spent":"386.566875ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:19:44.442243Z","caller":"traceutil/trace.go:171","msg":"trace[187470962] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"238.683091ms","start":"2026-09-02T17:19:44.203537Z","end":"2026-09-02T17:19:44.44222Z","steps":["trace[187470962] 'process raft request' (duration: 238.64158ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:44.442363Z","caller":"traceutil/trace.go:171","msg":"trace[655301530] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"287.40796ms","start":"2026-09-02T17:19:44.154945Z","end":"2026-09-02T17:19:44.442353Z","steps":["trace[655301530] 'process raft request' (duration: 287.064652ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:44.442401Z","caller":"traceutil/trace.go:171","msg":"trace[116518247] linearizableReadLoop","detail":"{readStateIndex:26908; appliedIndex:26905; }","duration":"361.673153ms","start":"2026-09-02T17:19:44.08072Z","end":"2026-09-02T17:19:44.442393Z","steps":["trace[116518247] 'read index received' (duration: 354.976371ms)","trace[116518247] 'applied index is now lower than readState.Index' (duration: 6.696032ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:44.442492Z","caller":"traceutil/trace.go:171","msg":"trace[2048256001] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"239.008217ms","start":"2026-09-02T17:19:44.203472Z","end":"2026-09-02T17:19:44.44248Z","steps":["trace[2048256001] 'process raft request' (duration: 238.627869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:44.442544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.820366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T17:19:44.44258Z","caller":"traceutil/trace.go:171","msg":"trace[1667079690] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26055; }","duration":"361.883658ms","start":"2026-09-02T17:19:44.08069Z","end":"2026-09-02T17:19:44.442573Z","steps":["trace[1667079690] 'agreement among raft nodes before linearized reading' (duration: 361.781566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:44.442601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:44.080673Z","time spent":"361.921639ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-09-02T17:19:44.442796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.036159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-09-02T17:19:44.442857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.293482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-02T17:19:44.442881Z","caller":"traceutil/trace.go:171","msg":"trace[1730828440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26055; }","duration":"201.346443ms","start":"2026-09-02T17:19:44.241527Z","end":"2026-09-02T17:19:44.442874Z","steps":["trace[1730828440] 'agreement among raft nodes before linearized reading' (duration: 201.253831ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:44.442896Z","caller":"traceutil/trace.go:171","msg":"trace[1416753679] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26055; }","duration":"293.183432ms","start":"2026-09-02T17:19:44.149689Z","end":"2026-09-02T17:19:44.442872Z","steps":["trace[1416753679] 'agreement among raft nodes before linearized reading' (duration: 292.948257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:44.443104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.492015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-09-02T17:19:44.443195Z","caller":"traceutil/trace.go:171","msg":"trace[1793781665] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26055; }","duration":"101.834192ms","start":"2026-09-02T17:19:44.341319Z","end":"2026-09-02T17:19:44.443154Z","steps":["trace[1793781665] 'agreement among raft nodes before linearized reading' (duration: 101.425053ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:45.90834Z","caller":"traceutil/trace.go:171","msg":"trace[1157167905] transaction","detail":"{read_only:false; response_revision:26063; number_of_response:1; }","duration":"120.724846ms","start":"2026-09-02T17:19:45.787587Z","end":"2026-09-02T17:19:45.908312Z","steps":["trace[1157167905] 'process raft request' (duration: 120.52258ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:45.931674Z","caller":"traceutil/trace.go:171","msg":"trace[348389594] linearizableReadLoop","detail":"{readStateIndex:26922; appliedIndex:26918; }","duration":"120.588412ms","start":"2026-09-02T17:19:45.811061Z","end":"2026-09-02T17:19:45.931649Z","steps":["trace[348389594] 'read index received' (duration: 97.052909ms)","trace[348389594] 'applied index is now lower than readState.Index' (duration: 23.534263ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:45.93173Z","caller":"traceutil/trace.go:171","msg":"trace[537151736] transaction","detail":"{read_only:false; response_revision:26065; number_of_response:1; }","duration":"142.265664ms","start":"2026-09-02T17:19:45.789435Z","end":"2026-09-02T17:19:45.9317Z","steps":["trace[537151736] 'process raft request' (duration: 142.142962ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:45.931773Z","caller":"traceutil/trace.go:171","msg":"trace[553825751] transaction","detail":"{read_only:false; response_revision:26064; number_of_response:1; }","duration":"143.933999ms","start":"2026-09-02T17:19:45.787803Z","end":"2026-09-02T17:19:45.931737Z","steps":["trace[553825751] 'process raft request' (duration: 143.609102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:45.931842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.766376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8dd465e4-2834-416a-b09f-70aa1c835f6b\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-09-02T17:19:45.931873Z","caller":"traceutil/trace.go:171","msg":"trace[607489228] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8dd465e4-2834-416a-b09f-70aa1c835f6b; range_end:; response_count:1; response_revision:26066; }","duration":"120.835367ms","start":"2026-09-02T17:19:45.811027Z","end":"2026-09-02T17:19:45.931862Z","steps":["trace[607489228] 'agreement among raft nodes before linearized reading' (duration: 120.711984ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:45.93193Z","caller":"traceutil/trace.go:171","msg":"trace[872984885] transaction","detail":"{read_only:false; response_revision:26066; number_of_response:1; }","duration":"127.341827ms","start":"2026-09-02T17:19:45.804576Z","end":"2026-09-02T17:19:45.931918Z","steps":["trace[872984885] 'process raft request' (duration: 127.036911ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:46.276887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.179996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-02T17:19:46.277024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.782419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:46.277078Z","caller":"traceutil/trace.go:171","msg":"trace[198948485] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:26066; }","duration":"190.882061ms","start":"2026-09-02T17:19:46.086181Z","end":"2026-09-02T17:19:46.277063Z","steps":["trace[198948485] 'count revisions from in-memory index tree' (duration: 190.701367ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:46.277028Z","caller":"traceutil/trace.go:171","msg":"trace[97656296] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26066; }","duration":"145.34569ms","start":"2026-09-02T17:19:46.131656Z","end":"2026-09-02T17:19:46.277001Z","steps":["trace[97656296] 'count revisions from in-memory index tree' (duration: 145.047604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:49.735613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.311155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-02T17:19:49.735704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.866229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:19:49.735719Z","caller":"traceutil/trace.go:171","msg":"trace[1674032090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26103; }","duration":"315.463819ms","start":"2026-09-02T17:19:49.420227Z","end":"2026-09-02T17:19:49.735691Z","steps":["trace[1674032090] 'range keys from in-memory index tree' (duration: 315.06364ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:49.735751Z","caller":"traceutil/trace.go:171","msg":"trace[558843047] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26103; }","duration":"467.95442ms","start":"2026-09-02T17:19:49.267781Z","end":"2026-09-02T17:19:49.735736Z","steps":["trace[558843047] 'count revisions from in-memory index tree' (duration: 467.792137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:49.735784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:49.26776Z","time spent":"468.013132ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-02T17:19:49.735777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:19:49.420209Z","time spent":"315.55258ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-02T17:19:49.735794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.770191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-09-02T17:19:49.735834Z","caller":"traceutil/trace.go:171","msg":"trace[1037591204] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26103; }","duration":"282.841963ms","start":"2026-09-02T17:19:49.45298Z","end":"2026-09-02T17:19:49.735822Z","steps":["trace[1037591204] 'range keys from in-memory index tree' (duration: 282.654029ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:49.736254Z","caller":"traceutil/trace.go:171","msg":"trace[1264299088] linearizableReadLoop","detail":"{readStateIndex:26961; appliedIndex:26960; }","duration":"180.048239ms","start":"2026-09-02T17:19:49.556194Z","end":"2026-09-02T17:19:49.736242Z","steps":["trace[1264299088] 'read index received' (duration: 179.813784ms)","trace[1264299088] 'applied index is now lower than readState.Index' (duration: 233.485µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:49.736319Z","caller":"traceutil/trace.go:171","msg":"trace[1636120861] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"241.019841ms","start":"2026-09-02T17:19:49.495287Z","end":"2026-09-02T17:19:49.736306Z","steps":["trace[1636120861] 'process raft request' (duration: 240.852387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:49.736493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.289455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-02T17:19:49.736611Z","caller":"traceutil/trace.go:171","msg":"trace[1551415193] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26104; }","duration":"180.465549ms","start":"2026-09-02T17:19:49.556123Z","end":"2026-09-02T17:19:49.736589Z","steps":["trace[1551415193] 'agreement among raft nodes before linearized reading' (duration: 180.201343ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:19:49.857294Z","caller":"traceutil/trace.go:171","msg":"trace[1706191136] linearizableReadLoop","detail":"{readStateIndex:26962; appliedIndex:26961; }","duration":"115.844871ms","start":"2026-09-02T17:19:49.741431Z","end":"2026-09-02T17:19:49.857276Z","steps":["trace[1706191136] 'read index received' (duration: 115.664547ms)","trace[1706191136] 'applied index is now lower than readState.Index' (duration: 179.634µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:19:49.857402Z","caller":"traceutil/trace.go:171","msg":"trace[1631700088] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"117.156809ms","start":"2026-09-02T17:19:49.740233Z","end":"2026-09-02T17:19:49.85739Z","steps":["trace[1631700088] 'process raft request' (duration: 116.922314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:19:49.857463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.015144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-02T17:19:49.857495Z","caller":"traceutil/trace.go:171","msg":"trace[959974617] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26105; }","duration":"116.094976ms","start":"2026-09-02T17:19:49.741388Z","end":"2026-09-02T17:19:49.857483Z","steps":["trace[959974617] 'agreement among raft nodes before linearized reading' (duration: 115.961193ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:03.441782Z","caller":"traceutil/trace.go:171","msg":"trace[746652057] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"245.366492ms","start":"2026-09-02T17:20:03.196389Z","end":"2026-09-02T17:20:03.441755Z","steps":["trace[746652057] 'process raft request' (duration: 245.169468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:03.827269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.361642ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-02T17:20:03.827531Z","caller":"traceutil/trace.go:171","msg":"trace[1606402510] linearizableReadLoop","detail":"{readStateIndex:27099; appliedIndex:27098; }","duration":"190.302669ms","start":"2026-09-02T17:20:03.637215Z","end":"2026-09-02T17:20:03.827518Z","steps":["trace[1606402510] 'read index received' (duration: 106.592µs)","trace[1606402510] 'applied index is now lower than readState.Index' (duration: 190.195017ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:03.827722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.489263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:20:03.827754Z","caller":"traceutil/trace.go:171","msg":"trace[2005407694] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26239; }","duration":"190.573895ms","start":"2026-09-02T17:20:03.63717Z","end":"2026-09-02T17:20:03.827744Z","steps":["trace[2005407694] 'agreement among raft nodes before linearized reading' (duration: 190.494043ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.129333Z","caller":"traceutil/trace.go:171","msg":"trace[1637676793] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"148.582248ms","start":"2026-09-02T17:20:03.980719Z","end":"2026-09-02T17:20:04.129301Z","steps":["trace[1637676793] 'process raft request' (duration: 51.705802ms)","trace[1637676793] 'compare' (duration: 96.608531ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:04.193688Z","caller":"traceutil/trace.go:171","msg":"trace[1802607201] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"195.51369ms","start":"2026-09-02T17:20:03.998155Z","end":"2026-09-02T17:20:04.193669Z","steps":["trace[1802607201] 'process raft request' (duration: 195.455749ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.193706Z","caller":"traceutil/trace.go:171","msg":"trace[1038811850] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"195.99142ms","start":"2026-09-02T17:20:03.997681Z","end":"2026-09-02T17:20:04.193672Z","steps":["trace[1038811850] 'process raft request' (duration: 195.820997ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.19384Z","caller":"traceutil/trace.go:171","msg":"trace[601893255] linearizableReadLoop","detail":"{readStateIndex:27104; appliedIndex:27101; }","duration":"158.063392ms","start":"2026-09-02T17:20:04.035748Z","end":"2026-09-02T17:20:04.193811Z","steps":["trace[601893255] 'read index received' (duration: 157.772906ms)","trace[601893255] 'applied index is now lower than readState.Index' (duration: 289.226µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:04.194024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.257146ms","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:6"} {"level":"info","ts":"2026-09-02T17:20:04.194062Z","caller":"traceutil/trace.go:171","msg":"trace[1416955565] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"184.464624ms","start":"2026-09-02T17:20:04.009586Z","end":"2026-09-02T17:20:04.19405Z","steps":["trace[1416955565] 'process raft request' (duration: 184.058256ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.194079Z","caller":"traceutil/trace.go:171","msg":"trace[409039298] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26244; }","duration":"158.382299ms","start":"2026-09-02T17:20:04.035683Z","end":"2026-09-02T17:20:04.194066Z","steps":["trace[409039298] 'agreement among raft nodes before linearized reading' (duration: 158.227155ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.693244Z","caller":"traceutil/trace.go:171","msg":"trace[284857303] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"106.561113ms","start":"2026-09-02T17:20:04.586654Z","end":"2026-09-02T17:20:04.693215Z","steps":["trace[284857303] 'process raft request' (duration: 93.2804ms)","trace[284857303] 'compare' (duration: 12.677131ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:04.703572Z","caller":"traceutil/trace.go:171","msg":"trace[1337191488] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"104.329625ms","start":"2026-09-02T17:20:04.599219Z","end":"2026-09-02T17:20:04.703549Z","steps":["trace[1337191488] 'process raft request' (duration: 104.133941ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.836022Z","caller":"traceutil/trace.go:171","msg":"trace[68904583] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"127.751503ms","start":"2026-09-02T17:20:04.708244Z","end":"2026-09-02T17:20:04.835995Z","steps":["trace[68904583] 'process raft request' (duration: 124.556605ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.895535Z","caller":"traceutil/trace.go:171","msg":"trace[557783297] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"187.257923ms","start":"2026-09-02T17:20:04.708253Z","end":"2026-09-02T17:20:04.895511Z","steps":["trace[557783297] 'process raft request' (duration: 187.031298ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.895776Z","caller":"traceutil/trace.go:171","msg":"trace[1276351168] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"187.310064ms","start":"2026-09-02T17:20:04.708456Z","end":"2026-09-02T17:20:04.895766Z","steps":["trace[1276351168] 'process raft request' (duration: 187.006998ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.896061Z","caller":"traceutil/trace.go:171","msg":"trace[475540465] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"187.466948ms","start":"2026-09-02T17:20:04.708561Z","end":"2026-09-02T17:20:04.896028Z","steps":["trace[475540465] 'process raft request' (duration: 187.165661ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.896153Z","caller":"traceutil/trace.go:171","msg":"trace[810881873] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"158.017521ms","start":"2026-09-02T17:20:04.738122Z","end":"2026-09-02T17:20:04.89614Z","steps":["trace[810881873] 'process raft request' (duration: 157.796066ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.896337Z","caller":"traceutil/trace.go:171","msg":"trace[691115229] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"147.036597ms","start":"2026-09-02T17:20:04.749284Z","end":"2026-09-02T17:20:04.896321Z","steps":["trace[691115229] 'process raft request' (duration: 146.802652ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:04.896375Z","caller":"traceutil/trace.go:171","msg":"trace[1821812730] linearizableReadLoop","detail":"{readStateIndex:27114; appliedIndex:27107; }","duration":"131.809091ms","start":"2026-09-02T17:20:04.764539Z","end":"2026-09-02T17:20:04.896348Z","steps":["trace[1821812730] 'read index received' (duration: 68.357557ms)","trace[1821812730] 'applied index is now lower than readState.Index' (duration: 63.450774ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:04.896392Z","caller":"traceutil/trace.go:171","msg":"trace[2020542794] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"138.836072ms","start":"2026-09-02T17:20:04.757534Z","end":"2026-09-02T17:20:04.89637Z","steps":["trace[2020542794] 'process raft request' (duration: 138.706429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:04.896617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.067946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-09-02T17:20:04.89666Z","caller":"traceutil/trace.go:171","msg":"trace[488991120] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26254; }","duration":"132.165078ms","start":"2026-09-02T17:20:04.764483Z","end":"2026-09-02T17:20:04.896648Z","steps":["trace[488991120] 'agreement among raft nodes before linearized reading' (duration: 131.949094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:05.143285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.042942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-09-02T17:20:05.143581Z","caller":"traceutil/trace.go:171","msg":"trace[453410226] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26254; }","duration":"113.342868ms","start":"2026-09-02T17:20:05.030171Z","end":"2026-09-02T17:20:05.143514Z","steps":["trace[453410226] 'range keys from in-memory index tree' (duration: 112.597382ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:08.235178Z","caller":"traceutil/trace.go:171","msg":"trace[2053739000] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"231.408766ms","start":"2026-09-02T17:20:08.003756Z","end":"2026-09-02T17:20:08.235165Z","steps":["trace[2053739000] 'process raft request' (duration: 231.313593ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:08.282534Z","caller":"traceutil/trace.go:171","msg":"trace[102448719] transaction","detail":"{read_only:false; response_revision:26276; number_of_response:1; }","duration":"139.901745ms","start":"2026-09-02T17:20:08.142602Z","end":"2026-09-02T17:20:08.282503Z","steps":["trace[102448719] 'process raft request' (duration: 139.632449ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:09.011259Z","caller":"traceutil/trace.go:171","msg":"trace[1794579837] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"112.869908ms","start":"2026-09-02T17:20:08.898366Z","end":"2026-09-02T17:20:09.011236Z","steps":["trace[1794579837] 'process raft request' (duration: 112.727425ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:09.068405Z","caller":"traceutil/trace.go:171","msg":"trace[1391010432] linearizableReadLoop","detail":"{readStateIndex:27148; appliedIndex:27146; }","duration":"154.845952ms","start":"2026-09-02T17:20:08.913537Z","end":"2026-09-02T17:20:09.068383Z","steps":["trace[1391010432] 'read index received' (duration: 97.57036ms)","trace[1391010432] 'applied index is now lower than readState.Index' (duration: 57.274562ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:09.068523Z","caller":"traceutil/trace.go:171","msg":"trace[505343636] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"161.478914ms","start":"2026-09-02T17:20:08.907003Z","end":"2026-09-02T17:20:09.068482Z","steps":["trace[505343636] 'process raft request' (duration: 161.252949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:09.06862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.402162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-02T17:20:09.069513Z","caller":"traceutil/trace.go:171","msg":"trace[1306052736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26286; }","duration":"154.305731ms","start":"2026-09-02T17:20:08.915173Z","end":"2026-09-02T17:20:09.069479Z","steps":["trace[1306052736] 'agreement among raft nodes before linearized reading' (duration: 153.334871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:09.06865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.117728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-02T17:20:09.069702Z","caller":"traceutil/trace.go:171","msg":"trace[1044948290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26286; }","duration":"156.206971ms","start":"2026-09-02T17:20:08.913468Z","end":"2026-09-02T17:20:09.069675Z","steps":["trace[1044948290] 'agreement among raft nodes before linearized reading' (duration: 155.038547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:09.0687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.194332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-02T17:20:09.069797Z","caller":"traceutil/trace.go:171","msg":"trace[1932496267] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26286; }","duration":"120.309106ms","start":"2026-09-02T17:20:08.949475Z","end":"2026-09-02T17:20:09.069784Z","steps":["trace[1932496267] 'agreement among raft nodes before linearized reading' (duration: 119.167792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:09.068746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.288158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-02T17:20:09.069978Z","caller":"traceutil/trace.go:171","msg":"trace[560354735] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26286; }","duration":"153.536894ms","start":"2026-09-02T17:20:08.916403Z","end":"2026-09-02T17:20:09.06994Z","steps":["trace[560354735] 'agreement among raft nodes before linearized reading' (duration: 152.297488ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:25.361794Z","caller":"traceutil/trace.go:171","msg":"trace[2116397843] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"168.439523ms","start":"2026-09-02T17:20:25.193336Z","end":"2026-09-02T17:20:25.361775Z","steps":["trace[2116397843] 'process raft request' (duration: 168.358871ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:25.361796Z","caller":"traceutil/trace.go:171","msg":"trace[1025031446] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"170.691801ms","start":"2026-09-02T17:20:25.191074Z","end":"2026-09-02T17:20:25.361766Z","steps":["trace[1025031446] 'process raft request' (duration: 157.005539ms)","trace[1025031446] 'compare' (duration: 13.057989ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:25.637032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.740052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:20:25.637315Z","caller":"traceutil/trace.go:171","msg":"trace[1200120406] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"149.948208ms","start":"2026-09-02T17:20:25.487259Z","end":"2026-09-02T17:20:25.637207Z","steps":["trace[1200120406] 'process raft request' (duration: 149.868206ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:25.637681Z","caller":"traceutil/trace.go:171","msg":"trace[1184591915] linearizableReadLoop","detail":"{readStateIndex:27294; appliedIndex:27293; }","duration":"152.194487ms","start":"2026-09-02T17:20:25.485457Z","end":"2026-09-02T17:20:25.637652Z","steps":["trace[1184591915] 'read index received' (duration: 25.73098ms)","trace[1184591915] 'applied index is now lower than readState.Index' (duration: 126.461057ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:25.637694Z","caller":"traceutil/trace.go:171","msg":"trace[1213199654] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"157.244953ms","start":"2026-09-02T17:20:25.480422Z","end":"2026-09-02T17:20:25.637667Z","steps":["trace[1213199654] 'process raft request' (duration: 30.755365ms)","trace[1213199654] 'compare' (duration: 125.16839ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:25.63784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.36223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:20:25.637886Z","caller":"traceutil/trace.go:171","msg":"trace[2063220869] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:26430; }","duration":"152.456142ms","start":"2026-09-02T17:20:25.485418Z","end":"2026-09-02T17:20:25.637874Z","steps":["trace[2063220869] 'agreement among raft nodes before linearized reading' (duration: 152.338929ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:30.677742Z","caller":"traceutil/trace.go:171","msg":"trace[747594206] linearizableReadLoop","detail":"{readStateIndex:27333; appliedIndex:27329; }","duration":"271.559002ms","start":"2026-09-02T17:20:30.406155Z","end":"2026-09-02T17:20:30.677714Z","steps":["trace[747594206] 'read index received' (duration: 42.392784ms)","trace[747594206] 'applied index is now lower than readState.Index' (duration: 229.165218ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:30.677795Z","caller":"traceutil/trace.go:171","msg":"trace[26204004] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"272.725637ms","start":"2026-09-02T17:20:30.405043Z","end":"2026-09-02T17:20:30.677768Z","steps":["trace[26204004] 'process raft request' (duration: 272.493882ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:30.677886Z","caller":"traceutil/trace.go:171","msg":"trace[1592943197] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"272.635314ms","start":"2026-09-02T17:20:30.405236Z","end":"2026-09-02T17:20:30.677871Z","steps":["trace[1592943197] 'process raft request' (duration: 272.42132ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:30.678029Z","caller":"traceutil/trace.go:171","msg":"trace[1295581568] transaction","detail":"{read_only:false; response_revision:26465; number_of_response:1; }","duration":"272.967562ms","start":"2026-09-02T17:20:30.405029Z","end":"2026-09-02T17:20:30.677997Z","steps":["trace[1295581568] 'process raft request' (duration: 211.796007ms)","trace[1295581568] 'compare' (duration: 60.329077ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:30.678205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.041352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-02T17:20:30.678255Z","caller":"traceutil/trace.go:171","msg":"trace[561185701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26467; }","duration":"272.101213ms","start":"2026-09-02T17:20:30.406137Z","end":"2026-09-02T17:20:30.678239Z","steps":["trace[561185701] 'agreement among raft nodes before linearized reading' (duration: 271.909809ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:35.985861Z","caller":"traceutil/trace.go:171","msg":"trace[838167676] linearizableReadLoop","detail":"{readStateIndex:27373; appliedIndex:27372; }","duration":"105.303886ms","start":"2026-09-02T17:20:35.880534Z","end":"2026-09-02T17:20:35.985838Z","steps":["trace[838167676] 'read index received' (duration: 105.040181ms)","trace[838167676] 'applied index is now lower than readState.Index' (duration: 260.335µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:35.986024Z","caller":"traceutil/trace.go:171","msg":"trace[644471014] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"139.069707ms","start":"2026-09-02T17:20:35.846927Z","end":"2026-09-02T17:20:35.985996Z","steps":["trace[644471014] 'process raft request' (duration: 138.75313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:35.986055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.50223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:20:35.986093Z","caller":"traceutil/trace.go:171","msg":"trace[150367212] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26506; }","duration":"105.587553ms","start":"2026-09-02T17:20:35.880494Z","end":"2026-09-02T17:20:35.986081Z","steps":["trace[150367212] 'agreement among raft nodes before linearized reading' (duration: 105.497141ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:36.607373Z","caller":"traceutil/trace.go:171","msg":"trace[706240019] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"138.139296ms","start":"2026-09-02T17:20:36.469209Z","end":"2026-09-02T17:20:36.607348Z","steps":["trace[706240019] 'process raft request' (duration: 137.966393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:36.924743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.731812ms","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-09-02T17:20:36.924822Z","caller":"traceutil/trace.go:171","msg":"trace[1254296190] 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:26507; }","duration":"253.866495ms","start":"2026-09-02T17:20:36.670935Z","end":"2026-09-02T17:20:36.924801Z","steps":["trace[1254296190] 'count revisions from in-memory index tree' (duration: 253.62514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:36.924926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.975688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:20:36.925085Z","caller":"traceutil/trace.go:171","msg":"trace[1225543688] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"215.318152ms","start":"2026-09-02T17:20:36.709743Z","end":"2026-09-02T17:20:36.925061Z","steps":["trace[1225543688] 'process raft request' (duration: 80.095958ms)","trace[1225543688] 'compare' (duration: 134.778914ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:36.959026Z","caller":"traceutil/trace.go:171","msg":"trace[1653470237] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"247.822055ms","start":"2026-09-02T17:20:36.711178Z","end":"2026-09-02T17:20:36.959Z","steps":["trace[1653470237] 'process raft request' (duration: 247.61143ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:36.959217Z","caller":"traceutil/trace.go:171","msg":"trace[1358761746] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"247.698832ms","start":"2026-09-02T17:20:36.711509Z","end":"2026-09-02T17:20:36.959207Z","steps":["trace[1358761746] 'process raft request' (duration: 247.431186ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:36.959394Z","caller":"traceutil/trace.go:171","msg":"trace[1261234473] linearizableReadLoop","detail":"{readStateIndex:27377; appliedIndex:27374; }","duration":"245.038875ms","start":"2026-09-02T17:20:36.714329Z","end":"2026-09-02T17:20:36.959367Z","steps":["trace[1261234473] 'read index received' (duration: 75.52874ms)","trace[1261234473] 'applied index is now lower than readState.Index' (duration: 169.508305ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:36.959552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.200919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-02T17:20:36.959632Z","caller":"traceutil/trace.go:171","msg":"trace[1337560075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26510; }","duration":"245.313671ms","start":"2026-09-02T17:20:36.714297Z","end":"2026-09-02T17:20:36.95961Z","steps":["trace[1337560075] 'agreement among raft nodes before linearized reading' (duration: 245.064616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:37.149462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.75363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:20:37.14972Z","caller":"traceutil/trace.go:171","msg":"trace[1241181878] linearizableReadLoop","detail":"{readStateIndex:27379; appliedIndex:27377; }","duration":"190.239068ms","start":"2026-09-02T17:20:36.959467Z","end":"2026-09-02T17:20:37.149706Z","steps":["trace[1241181878] 'read index received' (duration: 66.272674ms)","trace[1241181878] 'applied index is now lower than readState.Index' (duration: 123.965664ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:37.149727Z","caller":"traceutil/trace.go:171","msg":"trace[211736761] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"331.150983ms","start":"2026-09-02T17:20:36.818561Z","end":"2026-09-02T17:20:37.149712Z","steps":["trace[211736761] 'process raft request' (duration: 331.00305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:37.149793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:36.818541Z","time spent":"331.219054ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:20:37.149798Z","caller":"traceutil/trace.go:171","msg":"trace[1486636995] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"185.741652ms","start":"2026-09-02T17:20:36.964048Z","end":"2026-09-02T17:20:37.14979Z","steps":["trace[1486636995] 'process raft request' (duration: 185.623339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:37.149941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.223091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-02T17:20:37.149985Z","caller":"traceutil/trace.go:171","msg":"trace[743550207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26513; }","duration":"434.263562ms","start":"2026-09-02T17:20:36.715711Z","end":"2026-09-02T17:20:37.149975Z","steps":["trace[743550207] 'agreement among raft nodes before linearized reading' (duration: 434.037317ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:37.149925Z","caller":"traceutil/trace.go:171","msg":"trace[119945777] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"355.973032ms","start":"2026-09-02T17:20:36.793914Z","end":"2026-09-02T17:20:37.149887Z","steps":["trace[119945777] 'process raft request' (duration: 231.696081ms)","trace[119945777] 'compare' (duration: 123.513355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:37.15Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.516745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-02T17:20:37.150006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:36.715694Z","time spent":"434.307003ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-02T17:20:37.15003Z","caller":"traceutil/trace.go:171","msg":"trace[506950731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26513; }","duration":"417.574176ms","start":"2026-09-02T17:20:36.732446Z","end":"2026-09-02T17:20:37.15002Z","steps":["trace[506950731] 'agreement among raft nodes before linearized reading' (duration: 417.317131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:37.150056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:36.73243Z","time spent":"417.619597ms","remote":"127.0.0.1:53708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-02T17:20:37.150096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:36.793889Z","time spent":"356.124905ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:20:37.307157Z","caller":"traceutil/trace.go:171","msg":"trace[851200013] transaction","detail":"{read_only:false; response_revision:26514; number_of_response:1; }","duration":"152.992882ms","start":"2026-09-02T17:20:37.15414Z","end":"2026-09-02T17:20:37.307133Z","steps":["trace[851200013] 'process raft request' (duration: 152.148385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:37.545324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.86631ms","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-09-02T17:20:37.545316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.597829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-02T17:20:37.545399Z","caller":"traceutil/trace.go:171","msg":"trace[256899614] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26517; }","duration":"107.978002ms","start":"2026-09-02T17:20:37.437398Z","end":"2026-09-02T17:20:37.545376Z","steps":["trace[256899614] 'range keys from in-memory index tree' (duration: 107.720457ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:37.545414Z","caller":"traceutil/trace.go:171","msg":"trace[1202847405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26517; }","duration":"133.760613ms","start":"2026-09-02T17:20:37.411635Z","end":"2026-09-02T17:20:37.545395Z","steps":["trace[1202847405] 'range keys from in-memory index tree' (duration: 133.379664ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:37.678931Z","caller":"traceutil/trace.go:171","msg":"trace[1322180716] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"128.796187ms","start":"2026-09-02T17:20:37.550115Z","end":"2026-09-02T17:20:37.678911Z","steps":["trace[1322180716] 'process raft request' (duration: 128.659754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:54.11803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.115659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:20:54.118126Z","caller":"traceutil/trace.go:171","msg":"trace[978701511] linearizableReadLoop","detail":"{readStateIndex:27518; appliedIndex:27517; }","duration":"236.383661ms","start":"2026-09-02T17:20:53.881726Z","end":"2026-09-02T17:20:54.11811Z","steps":["trace[978701511] 'read index received' (duration: 95.091718ms)","trace[978701511] 'applied index is now lower than readState.Index' (duration: 141.290903ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:54.118192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.469743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T17:20:54.118244Z","caller":"traceutil/trace.go:171","msg":"trace[1496595103] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:26647; }","duration":"236.554625ms","start":"2026-09-02T17:20:53.881676Z","end":"2026-09-02T17:20:54.118231Z","steps":["trace[1496595103] 'agreement among raft nodes before linearized reading' (duration: 236.475993ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:54.118406Z","caller":"traceutil/trace.go:171","msg":"trace[371027268] transaction","detail":"{read_only:false; response_revision:26647; number_of_response:1; }","duration":"280.140924ms","start":"2026-09-02T17:20:53.83822Z","end":"2026-09-02T17:20:54.118361Z","steps":["trace[371027268] 'process raft request' (duration: 138.586236ms)","trace[371027268] 'compare' (duration: 140.778502ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:54.868649Z","caller":"traceutil/trace.go:171","msg":"trace[334106358] linearizableReadLoop","detail":"{readStateIndex:27519; appliedIndex:27518; }","duration":"135.814106ms","start":"2026-09-02T17:20:54.732818Z","end":"2026-09-02T17:20:54.868632Z","steps":["trace[334106358] 'read index received' (duration: 135.728924ms)","trace[334106358] 'applied index is now lower than readState.Index' (duration: 84.542µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:54.868684Z","caller":"traceutil/trace.go:171","msg":"trace[779073160] transaction","detail":"{read_only:false; response_revision:26648; number_of_response:1; }","duration":"195.025169ms","start":"2026-09-02T17:20:54.673644Z","end":"2026-09-02T17:20:54.868669Z","steps":["trace[779073160] 'process raft request' (duration: 194.881386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:54.86873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.900638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:20:54.868747Z","caller":"traceutil/trace.go:171","msg":"trace[134594872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26648; }","duration":"135.96254ms","start":"2026-09-02T17:20:54.732778Z","end":"2026-09-02T17:20:54.868741Z","steps":["trace[134594872] 'agreement among raft nodes before linearized reading' (duration: 135.917279ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:55.237586Z","caller":"traceutil/trace.go:171","msg":"trace[1526345101] transaction","detail":"{read_only:false; response_revision:26650; number_of_response:1; }","duration":"206.141237ms","start":"2026-09-02T17:20:55.031408Z","end":"2026-09-02T17:20:55.23755Z","steps":["trace[1526345101] 'process raft request' (duration: 129.183625ms)","trace[1526345101] 'compare' (duration: 76.808678ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:55.23761Z","caller":"traceutil/trace.go:171","msg":"trace[1007203542] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27520; }","duration":"187.257834ms","start":"2026-09-02T17:20:55.050309Z","end":"2026-09-02T17:20:55.237566Z","steps":["trace[1007203542] 'read index received' (duration: 110.295752ms)","trace[1007203542] 'applied index is now lower than readState.Index' (duration: 76.961142ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:55.237889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.55076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:20:55.237978Z","caller":"traceutil/trace.go:171","msg":"trace[1539320494] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26651; }","duration":"187.669353ms","start":"2026-09-02T17:20:55.050265Z","end":"2026-09-02T17:20:55.237934Z","steps":["trace[1539320494] 'agreement among raft nodes before linearized reading' (duration: 187.432298ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:55.237933Z","caller":"traceutil/trace.go:171","msg":"trace[1455862286] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"187.479839ms","start":"2026-09-02T17:20:55.050416Z","end":"2026-09-02T17:20:55.237895Z","steps":["trace[1455862286] 'process raft request' (duration: 187.114671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:55.504644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.248477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:20:55.504807Z","caller":"traceutil/trace.go:171","msg":"trace[1399391929] linearizableReadLoop","detail":"{readStateIndex:27524; appliedIndex:27522; }","duration":"267.114507ms","start":"2026-09-02T17:20:55.237676Z","end":"2026-09-02T17:20:55.504791Z","steps":["trace[1399391929] 'read index received' (duration: 107.614285ms)","trace[1399391929] 'applied index is now lower than readState.Index' (duration: 159.499112ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:55.504887Z","caller":"traceutil/trace.go:171","msg":"trace[2011885987] transaction","detail":"{read_only:false; response_revision:26653; number_of_response:1; }","duration":"281.32135ms","start":"2026-09-02T17:20:55.223556Z","end":"2026-09-02T17:20:55.504877Z","steps":["trace[2011885987] 'process raft request' (duration: 281.182307ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:55.504946Z","caller":"traceutil/trace.go:171","msg":"trace[2141853286] transaction","detail":"{read_only:false; response_revision:26652; number_of_response:1; }","duration":"282.854093ms","start":"2026-09-02T17:20:55.222066Z","end":"2026-09-02T17:20:55.50492Z","steps":["trace[2141853286] 'process raft request' (duration: 123.229048ms)","trace[2141853286] 'compare' (duration: 159.143814ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:55.505015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.630441ms","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-09-02T17:20:55.505069Z","caller":"traceutil/trace.go:171","msg":"trace[1567326129] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26653; }","duration":"262.706402ms","start":"2026-09-02T17:20:55.242344Z","end":"2026-09-02T17:20:55.505051Z","steps":["trace[1567326129] 'agreement among raft nodes before linearized reading' (duration: 262.57824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:55.505069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.295622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-02T17:20:55.505137Z","caller":"traceutil/trace.go:171","msg":"trace[603525694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26653; }","duration":"276.404734ms","start":"2026-09-02T17:20:55.228714Z","end":"2026-09-02T17:20:55.505118Z","steps":["trace[603525694] 'agreement among raft nodes before linearized reading' (duration: 276.20538ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:55.85187Z","caller":"traceutil/trace.go:171","msg":"trace[1593009030] transaction","detail":"{read_only:false; response_revision:26655; number_of_response:1; }","duration":"341.652587ms","start":"2026-09-02T17:20:55.510192Z","end":"2026-09-02T17:20:55.851844Z","steps":["trace[1593009030] 'process raft request' (duration: 253.853395ms)","trace[1593009030] 'compare' (duration: 87.544047ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:55.852044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:55.510167Z","time spent":"341.774259ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:20:55.852088Z","caller":"traceutil/trace.go:171","msg":"trace[1760894724] linearizableReadLoop","detail":"{readStateIndex:27528; appliedIndex:27524; }","duration":"341.174867ms","start":"2026-09-02T17:20:55.510896Z","end":"2026-09-02T17:20:55.852071Z","steps":["trace[1760894724] 'read index received' (duration: 8.121993ms)","trace[1760894724] 'applied index is now lower than readState.Index' (duration: 333.052124ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:55.852183Z","caller":"traceutil/trace.go:171","msg":"trace[648799674] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"341.699987ms","start":"2026-09-02T17:20:55.510457Z","end":"2026-09-02T17:20:55.852157Z","steps":["trace[648799674] 'process raft request' (duration: 341.563885ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:20:55.852186Z","caller":"traceutil/trace.go:171","msg":"trace[363659037] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"341.822491ms","start":"2026-09-02T17:20:55.510339Z","end":"2026-09-02T17:20:55.852162Z","steps":["trace[363659037] 'process raft request' (duration: 341.453303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:55.852273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:55.510324Z","time spent":"341.900872ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:20:55.852285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:55.510413Z","time spent":"341.8001ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:20:55.852334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.432542ms","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-09-02T17:20:55.852377Z","caller":"traceutil/trace.go:171","msg":"trace[79941506] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26657; }","duration":"341.505824ms","start":"2026-09-02T17:20:55.510856Z","end":"2026-09-02T17:20:55.852362Z","steps":["trace[79941506] 'agreement among raft nodes before linearized reading' (duration: 341.407952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:55.852439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:55.510844Z","time spent":"341.554064ms","remote":"127.0.0.1:53930","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-09-02T17:20:55.924747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.238148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:20:55.924814Z","caller":"traceutil/trace.go:171","msg":"trace[687641567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26658; }","duration":"191.368731ms","start":"2026-09-02T17:20:55.73343Z","end":"2026-09-02T17:20:55.924799Z","steps":["trace[687641567] 'agreement among raft nodes before linearized reading' (duration: 191.269799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:55.925066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.928939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-02T17:20:55.925099Z","caller":"traceutil/trace.go:171","msg":"trace[1440415197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26658; }","duration":"233.99761ms","start":"2026-09-02T17:20:55.691093Z","end":"2026-09-02T17:20:55.92509Z","steps":["trace[1440415197] 'agreement among raft nodes before linearized reading' (duration: 233.853237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:56.208713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.108014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:20:56.208947Z","caller":"traceutil/trace.go:171","msg":"trace[845103605] transaction","detail":"{read_only:false; response_revision:26659; number_of_response:1; }","duration":"279.359648ms","start":"2026-09-02T17:20:55.929562Z","end":"2026-09-02T17:20:56.208921Z","steps":["trace[845103605] 'process raft request' (duration: 104.969428ms)","trace[845103605] 'compare' (duration: 173.655024ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:56.209245Z","caller":"traceutil/trace.go:171","msg":"trace[828210250] transaction","detail":"{read_only:false; response_revision:26660; number_of_response:1; }","duration":"257.742447ms","start":"2026-09-02T17:20:55.951495Z","end":"2026-09-02T17:20:56.209237Z","steps":["trace[828210250] 'process raft request' (duration: 257.313828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:56.676974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.119408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T17:20:56.677117Z","caller":"traceutil/trace.go:171","msg":"trace[1214497895] linearizableReadLoop","detail":"{readStateIndex:27533; appliedIndex:27531; }","duration":"358.477905ms","start":"2026-09-02T17:20:56.318627Z","end":"2026-09-02T17:20:56.677105Z","steps":["trace[1214497895] 'read index received' (duration: 211.211094ms)","trace[1214497895] 'applied index is now lower than readState.Index' (duration: 147.265901ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:20:56.677206Z","caller":"traceutil/trace.go:171","msg":"trace[322768558] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"462.387551ms","start":"2026-09-02T17:20:56.21481Z","end":"2026-09-02T17:20:56.677197Z","steps":["trace[322768558] 'process raft request' (duration: 314.962976ms)","trace[322768558] 'compare' (duration: 147.032427ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:20:56.677256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:56.214785Z","time spent":"462.444423ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T17:20:56.677398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.848152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:20:56.677484Z","caller":"traceutil/trace.go:171","msg":"trace[155511356] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:26662; }","duration":"214.972805ms","start":"2026-09-02T17:20:56.46249Z","end":"2026-09-02T17:20:56.677462Z","steps":["trace[155511356] 'agreement among raft nodes before linearized reading' (duration: 214.806431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:56.677405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.765412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-02T17:20:56.677525Z","caller":"traceutil/trace.go:171","msg":"trace[2118697299] transaction","detail":"{read_only:false; response_revision:26662; number_of_response:1; }","duration":"389.029267ms","start":"2026-09-02T17:20:56.288472Z","end":"2026-09-02T17:20:56.677501Z","steps":["trace[2118697299] 'process raft request' (duration: 388.583918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:56.677633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:56.28845Z","time spent":"389.124879ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T17:20:56.677533Z","caller":"traceutil/trace.go:171","msg":"trace[560469500] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26662; }","duration":"358.998576ms","start":"2026-09-02T17:20:56.318525Z","end":"2026-09-02T17:20:56.677524Z","steps":["trace[560469500] 'agreement among raft nodes before linearized reading' (duration: 358.677019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:20:56.677694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T17:20:56.318503Z","time spent":"359.18004ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-02T17:20:57.692193Z","caller":"traceutil/trace.go:171","msg":"trace[1286409101] transaction","detail":"{read_only:false; response_revision:26673; number_of_response:1; }","duration":"115.80834ms","start":"2026-09-02T17:20:57.57636Z","end":"2026-09-02T17:20:57.692168Z","steps":["trace[1286409101] 'process raft request' (duration: 115.038834ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:22:36.034099Z","caller":"traceutil/trace.go:171","msg":"trace[366212401] transaction","detail":"{read_only:false; response_revision:27666; number_of_response:1; }","duration":"108.978965ms","start":"2026-09-02T17:22:35.925087Z","end":"2026-09-02T17:22:36.034066Z","steps":["trace[366212401] 'process raft request' (duration: 108.684129ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:22:36.074123Z","caller":"traceutil/trace.go:171","msg":"trace[1432634605] linearizableReadLoop","detail":"{readStateIndex:28561; appliedIndex:28559; }","duration":"121.998051ms","start":"2026-09-02T17:22:35.952057Z","end":"2026-09-02T17:22:36.074055Z","steps":["trace[1432634605] 'read index received' (duration: 81.769564ms)","trace[1432634605] 'applied index is now lower than readState.Index' (duration: 40.161136ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:22:36.074336Z","caller":"traceutil/trace.go:171","msg":"trace[1614646176] transaction","detail":"{read_only:false; response_revision:27667; number_of_response:1; }","duration":"124.194619ms","start":"2026-09-02T17:22:35.950112Z","end":"2026-09-02T17:22:36.074306Z","steps":["trace[1614646176] 'process raft request' (duration: 123.730799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:22:36.074388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.300888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-02T17:22:36.074425Z","caller":"traceutil/trace.go:171","msg":"trace[95701721] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:27667; }","duration":"122.385849ms","start":"2026-09-02T17:22:35.952028Z","end":"2026-09-02T17:22:36.074414Z","steps":["trace[95701721] 'agreement among raft nodes before linearized reading' (duration: 122.179915ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:22:36.254834Z","caller":"traceutil/trace.go:171","msg":"trace[591426789] linearizableReadLoop","detail":"{readStateIndex:28562; appliedIndex:28561; }","duration":"111.732833ms","start":"2026-09-02T17:22:36.143079Z","end":"2026-09-02T17:22:36.254812Z","steps":["trace[591426789] 'read index received' (duration: 111.540359ms)","trace[591426789] 'applied index is now lower than readState.Index' (duration: 191.584µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:22:36.254962Z","caller":"traceutil/trace.go:171","msg":"trace[611577491] transaction","detail":"{read_only:false; response_revision:27668; number_of_response:1; }","duration":"117.033007ms","start":"2026-09-02T17:22:36.137897Z","end":"2026-09-02T17:22:36.25493Z","steps":["trace[611577491] 'process raft request' (duration: 116.782292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:22:36.25683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.723696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-02T17:22:36.25691Z","caller":"traceutil/trace.go:171","msg":"trace[1114842355] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:27668; }","duration":"113.843239ms","start":"2026-09-02T17:22:36.14305Z","end":"2026-09-02T17:22:36.256893Z","steps":["trace[1114842355] 'agreement among raft nodes before linearized reading' (duration: 112.256775ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:22:36.694891Z","caller":"traceutil/trace.go:171","msg":"trace[1353673175] transaction","detail":"{read_only:false; response_revision:27670; number_of_response:1; }","duration":"164.137781ms","start":"2026-09-02T17:22:36.530728Z","end":"2026-09-02T17:22:36.694866Z","steps":["trace[1353673175] 'process raft request' (duration: 70.204388ms)","trace[1353673175] 'compare' (duration: 93.583396ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:22:36.694884Z","caller":"traceutil/trace.go:171","msg":"trace[1818082899] linearizableReadLoop","detail":"{readStateIndex:28564; appliedIndex:28563; }","duration":"154.971475ms","start":"2026-09-02T17:22:36.539843Z","end":"2026-09-02T17:22:36.694814Z","steps":["trace[1818082899] 'read index received' (duration: 61.010691ms)","trace[1818082899] 'applied index is now lower than readState.Index' (duration: 93.958874ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:22:36.695094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.238861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-02T17:22:36.695166Z","caller":"traceutil/trace.go:171","msg":"trace[328704687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27670; }","duration":"155.352543ms","start":"2026-09-02T17:22:36.539795Z","end":"2026-09-02T17:22:36.695148Z","steps":["trace[328704687] 'agreement among raft nodes before linearized reading' (duration: 155.122979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:22:36.695325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.563087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-02T17:22:36.69539Z","caller":"traceutil/trace.go:171","msg":"trace[524550266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27670; }","duration":"139.70008ms","start":"2026-09-02T17:22:36.555667Z","end":"2026-09-02T17:22:36.695367Z","steps":["trace[524550266] 'agreement among raft nodes before linearized reading' (duration: 139.438665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:22:36.695447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.439321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:22:36.695501Z","caller":"traceutil/trace.go:171","msg":"trace[151589020] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:27670; }","duration":"130.519123ms","start":"2026-09-02T17:22:36.564967Z","end":"2026-09-02T17:22:36.695486Z","steps":["trace[151589020] 'agreement among raft nodes before linearized reading' (duration: 130.425502ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:22:36.896351Z","caller":"traceutil/trace.go:171","msg":"trace[1260996336] linearizableReadLoop","detail":"{readStateIndex:28566; appliedIndex:28565; }","duration":"130.727309ms","start":"2026-09-02T17:22:36.765588Z","end":"2026-09-02T17:22:36.896316Z","steps":["trace[1260996336] 'read index received' (duration: 49.012266ms)","trace[1260996336] 'applied index is now lower than readState.Index' (duration: 81.713493ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T17:22:36.896393Z","caller":"traceutil/trace.go:171","msg":"trace[1650814311] transaction","detail":"{read_only:false; response_revision:27672; number_of_response:1; }","duration":"196.789797ms","start":"2026-09-02T17:22:36.699564Z","end":"2026-09-02T17:22:36.896354Z","steps":["trace[1650814311] 'process raft request' (duration: 115.144475ms)","trace[1650814311] 'compare' (duration: 81.381076ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T17:22:36.89668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.140921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21430"} {"level":"info","ts":"2026-09-02T17:22:36.896737Z","caller":"traceutil/trace.go:171","msg":"trace[1611306267] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:27672; }","duration":"112.244144ms","start":"2026-09-02T17:22:36.784474Z","end":"2026-09-02T17:22:36.896718Z","steps":["trace[1611306267] 'agreement among raft nodes before linearized reading' (duration: 112.09869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:22:36.896511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.901513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:22:36.897341Z","caller":"traceutil/trace.go:171","msg":"trace[634215783] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27672; }","duration":"131.755881ms","start":"2026-09-02T17:22:36.765545Z","end":"2026-09-02T17:22:36.897301Z","steps":["trace[634215783] 'agreement among raft nodes before linearized reading' (duration: 130.907433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T17:22:37.270349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.784571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T17:22:37.270477Z","caller":"traceutil/trace.go:171","msg":"trace[1986459947] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:27673; }","duration":"245.949085ms","start":"2026-09-02T17:22:37.024474Z","end":"2026-09-02T17:22:37.270423Z","steps":["trace[1986459947] 'count revisions from in-memory index tree' (duration: 245.69595ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:23:04.98739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25098} {"level":"info","ts":"2026-09-02T17:23:05.451729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25098,"took":"460.096792ms","hash":780178728,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":36974592,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-02T17:23:05.451922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":780178728,"revision":25098,"compact-revision":21934} {"level":"warn","ts":"2026-09-02T17:23:51.911846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.944458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T17:23:51.911956Z","caller":"traceutil/trace.go:171","msg":"trace[1803369618] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:28430; }","duration":"120.098871ms","start":"2026-09-02T17:23:51.79182Z","end":"2026-09-02T17:23:51.911919Z","steps":["trace[1803369618] 'count revisions from in-memory index tree' (duration: 119.802225ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T17:24:59.02633Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T17:24:59.032287Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-02T17:24:59.032367Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-02T17:28:04.993643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27907} {"level":"info","ts":"2026-09-02T17:28:05.137185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27907,"took":"139.345972ms","hash":2082249959,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":33419264,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-02T17:28:05.13725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2082249959,"revision":27907,"compact-revision":25098} {"level":"info","ts":"2026-09-02T17:33:05.030889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30479} {"level":"info","ts":"2026-09-02T17:33:05.203216Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30479,"took":"167.598874ms","hash":2719888041,"current-db-size-bytes":56643584,"current-db-size":"57 MB","current-db-size-in-use-bytes":30605312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-02T17:33:05.203309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2719888041,"revision":30479,"compact-revision":27907} {"level":"info","ts":"2026-09-02T17:38:05.055188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32733} {"level":"info","ts":"2026-09-02T17:38:05.221015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32733,"took":"160.905838ms","hash":2791317288,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":30265344,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-02T17:38:05.221087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2791317288,"revision":32733,"compact-revision":30479} {"level":"info","ts":"2026-09-02T17:43:05.06253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34958} {"level":"info","ts":"2026-09-02T17:43:05.213153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34958,"took":"145.808695ms","hash":4027926987,"current-db-size-bytes":57683968,"current-db-size":"58 MB","current-db-size-in-use-bytes":31641600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-02T17:43:05.213442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4027926987,"revision":34958,"compact-revision":32733} {"level":"info","ts":"2026-09-02T17:46:24.98978Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de05d1e64231de42","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T17:46:24.995109Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-02T17:46:24.995248Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-02T17:48:05.069151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37255} {"level":"info","ts":"2026-09-02T17:48:05.20849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37255,"took":"135.090196ms","hash":4075655714,"current-db-size-bytes":59383808,"current-db-size":"59 MB","current-db-size-in-use-bytes":32677888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-02T17:48:05.208555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4075655714,"revision":37255,"compact-revision":34958} {"level":"info","ts":"2026-09-02T17:53:05.075288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39533} {"level":"info","ts":"2026-09-02T17:53:05.207544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39533,"took":"128.967995ms","hash":739159157,"current-db-size-bytes":59805696,"current-db-size":"60 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-02T17:53:05.207599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":739159157,"revision":39533,"compact-revision":37255}