{"level":"warn","ts":"2026-06-10T09:52:31.630464Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T09:52:31.630544Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-10T09:52:31.630622Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T09:52:31.630634Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-10T09:52:31.630656Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-10T09:52:31.631318Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-06-10T09:52:31.631398Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-10T09:52:31.635735Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.130688ms"} {"level":"info","ts":"2026-06-10T09:52:31.640791Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-06-10T09:52:31.640861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T09:52:31.640885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-06-10T09:52:31.640893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T09:52:31.640906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-06-10T09:52:31.64094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-06-10T09:52:31.646463Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T09:52:31.647872Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T09:52:31.649037Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-10T09:52:31.652008Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T09:52:31.652243Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T09:52:31.652285Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T09:52:31.652356Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T09:52:31.652411Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T09:52:31.653627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-06-10T09:52:31.65464Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-10T09:52:31.656733Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-10T09:52:31.656862Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-10T09:52:31.656881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-10T09:52:31.658384Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T09:52:31.658415Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T09:52:32.641765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T09:52:32.641821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T09:52:32.641843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-06-10T09:52:32.6419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-06-10T09:52:32.641921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-10T09:52:32.642054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-06-10T09:52:32.642104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-10T09:52:32.643066Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T09:52:32.64406Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T09:52:32.644135Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T09:52:32.644123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T09:52:32.64433Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T09:52:32.644418Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T09:52:32.644634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T09:52:32.644767Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T09:52:32.644829Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T09:52:32.647279Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-06-10T09:52:32.647637Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T09:52:56.310485Z","caller":"traceutil/trace.go:171","msg":"trace[164183525] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"178.158127ms","start":"2026-06-10T09:52:56.132297Z","end":"2026-06-10T09:52:56.310455Z","steps":["trace[164183525] 'process raft request' (duration: 177.964794ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:53:12.103898Z","caller":"traceutil/trace.go:171","msg":"trace[2119597765] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"263.551846ms","start":"2026-06-10T09:53:11.840315Z","end":"2026-06-10T09:53:12.103866Z","steps":["trace[2119597765] 'process raft request' (duration: 167.677719ms)","trace[2119597765] 'compare' (duration: 95.732654ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:53:12.106335Z","caller":"traceutil/trace.go:171","msg":"trace[883153250] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"254.649807ms","start":"2026-06-10T09:53:11.851654Z","end":"2026-06-10T09:53:12.106303Z","steps":["trace[883153250] 'process raft request' (duration: 254.349752ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:53:12.106344Z","caller":"traceutil/trace.go:171","msg":"trace[1144685073] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:544; }","duration":"239.004171ms","start":"2026-06-10T09:53:11.867312Z","end":"2026-06-10T09:53:12.106316Z","steps":["trace[1144685073] 'read index received' (duration: 140.691658ms)","trace[1144685073] 'applied index is now lower than readState.Index' (duration: 98.311613ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:53:12.106432Z","caller":"traceutil/trace.go:171","msg":"trace[835679309] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"202.03567ms","start":"2026-06-10T09:53:11.904349Z","end":"2026-06-10T09:53:12.106385Z","steps":["trace[835679309] 'process raft request' (duration: 201.788385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:53:12.106495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.127164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T09:53:12.106567Z","caller":"traceutil/trace.go:171","msg":"trace[2113566546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"239.292386ms","start":"2026-06-10T09:53:11.867264Z","end":"2026-06-10T09:53:12.106557Z","steps":["trace[2113566546] 'agreement among raft nodes before linearized reading' (duration: 239.118563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:53:18.442053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.875613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-10T09:53:18.442173Z","caller":"traceutil/trace.go:171","msg":"trace[1728944174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"308.054826ms","start":"2026-06-10T09:53:18.134098Z","end":"2026-06-10T09:53:18.442153Z","steps":["trace[1728944174] 'range keys from in-memory index tree' (duration: 307.69614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:53:18.442226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:53:18.134073Z","time spent":"308.138497ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T09:53:18.442396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.898923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T09:53:18.442544Z","caller":"traceutil/trace.go:171","msg":"trace[970759521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"374.097608ms","start":"2026-06-10T09:53:18.068425Z","end":"2026-06-10T09:53:18.442522Z","steps":["trace[970759521] 'range keys from in-memory index tree' (duration: 373.77811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:53:18.442618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:53:18.068401Z","time spent":"374.204079ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T09:53:18.513935Z","caller":"traceutil/trace.go:171","msg":"trace[1699841146] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"121.248558ms","start":"2026-06-10T09:53:18.392627Z","end":"2026-06-10T09:53:18.513875Z","steps":["trace[1699841146] 'process raft request' (duration: 117.204212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:53:19.015772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.344419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T09:53:19.016007Z","caller":"traceutil/trace.go:171","msg":"trace[1242692100] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"481.80503ms","start":"2026-06-10T09:53:18.534151Z","end":"2026-06-10T09:53:19.015956Z","steps":["trace[1242692100] 'process raft request' (duration: 284.833319ms)","trace[1242692100] 'compare' (duration: 196.070684ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:53:19.016087Z","caller":"traceutil/trace.go:171","msg":"trace[96876013] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"447.963264ms","start":"2026-06-10T09:53:18.568087Z","end":"2026-06-10T09:53:19.01605Z","steps":["trace[96876013] 'process raft request' (duration: 447.781901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:53:19.016291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:53:18.534121Z","time spent":"482.014204ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T09:53:19.01656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:53:18.568035Z","time spent":"448.409002ms","remote":"127.0.0.1:54848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T09:53:33.343556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.65113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T09:53:33.343681Z","caller":"traceutil/trace.go:171","msg":"trace[1895671366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"118.018717ms","start":"2026-06-10T09:53:33.225637Z","end":"2026-06-10T09:53:33.343656Z","steps":["trace[1895671366] 'range keys from in-memory index tree' (duration: 117.550699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:53:33.343849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.973254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-10T09:53:33.343998Z","caller":"traceutil/trace.go:171","msg":"trace[777742106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"251.201318ms","start":"2026-06-10T09:53:33.092761Z","end":"2026-06-10T09:53:33.343962Z","steps":["trace[777742106] 'range keys from in-memory index tree' (duration: 250.662159ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:54:01.07971Z","caller":"traceutil/trace.go:171","msg":"trace[1227420526] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"153.082106ms","start":"2026-06-10T09:54:00.926574Z","end":"2026-06-10T09:54:01.079656Z","steps":["trace[1227420526] 'process raft request' (duration: 145.371421ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:54:23.010234Z","caller":"traceutil/trace.go:171","msg":"trace[1600906264] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"105.141894ms","start":"2026-06-10T09:54:22.905056Z","end":"2026-06-10T09:54:23.010198Z","steps":["trace[1600906264] 'read index received' (duration: 104.843478ms)","trace[1600906264] 'applied index is now lower than readState.Index' (duration: 296.846µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:54:23.010336Z","caller":"traceutil/trace.go:171","msg":"trace[653732271] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"127.866052ms","start":"2026-06-10T09:54:22.882415Z","end":"2026-06-10T09:54:23.010281Z","steps":["trace[653732271] 'process raft request' (duration: 127.599177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:54:23.010623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.44132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-10T09:54:23.010665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.545192ms","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-06-10T09:54:23.010694Z","caller":"traceutil/trace.go:171","msg":"trace[2034828701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1409; }","duration":"104.603473ms","start":"2026-06-10T09:54:22.906071Z","end":"2026-06-10T09:54:23.010674Z","steps":["trace[2034828701] 'agreement among raft nodes before linearized reading' (duration: 104.354769ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:54:23.01095Z","caller":"traceutil/trace.go:171","msg":"trace[1487180790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1409; }","duration":"105.759785ms","start":"2026-06-10T09:54:22.905001Z","end":"2026-06-10T09:54:23.01076Z","steps":["trace[1487180790] 'agreement among raft nodes before linearized reading' (duration: 105.363828ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:54:47.069549Z","caller":"traceutil/trace.go:171","msg":"trace[1980113235] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"100.956976ms","start":"2026-06-10T09:54:46.96857Z","end":"2026-06-10T09:54:47.069527Z","steps":["trace[1980113235] 'process raft request' (duration: 100.811773ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:54:59.381479Z","caller":"traceutil/trace.go:171","msg":"trace[1067358182] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"118.423636ms","start":"2026-06-10T09:54:59.263032Z","end":"2026-06-10T09:54:59.381456Z","steps":["trace[1067358182] 'process raft request' (duration: 118.247942ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:54:59.456306Z","caller":"traceutil/trace.go:171","msg":"trace[191789553] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"116.138862ms","start":"2026-06-10T09:54:59.34014Z","end":"2026-06-10T09:54:59.456279Z","steps":["trace[191789553] 'process raft request' (duration: 116.04052ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:54:59.456401Z","caller":"traceutil/trace.go:171","msg":"trace[447163558] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"141.46918ms","start":"2026-06-10T09:54:59.314827Z","end":"2026-06-10T09:54:59.456296Z","steps":["trace[447163558] 'process raft request' (duration: 141.229926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:54:59.456493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.816453ms","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-06-10T09:54:59.456586Z","caller":"traceutil/trace.go:171","msg":"trace[2098328391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1930; }","duration":"135.952976ms","start":"2026-06-10T09:54:59.32061Z","end":"2026-06-10T09:54:59.456562Z","steps":["trace[2098328391] 'agreement among raft nodes before linearized reading' (duration: 135.749792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:31.749681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.138566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T09:55:31.749844Z","caller":"traceutil/trace.go:171","msg":"trace[735492095] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2196; }","duration":"170.382842ms","start":"2026-06-10T09:55:31.579446Z","end":"2026-06-10T09:55:31.749829Z","steps":["trace[735492095] 'read index received' (duration: 154.647682ms)","trace[735492095] 'applied index is now lower than readState.Index' (duration: 15.73415ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:55:31.749902Z","caller":"traceutil/trace.go:171","msg":"trace[1648261972] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"184.154003ms","start":"2026-06-10T09:55:31.56574Z","end":"2026-06-10T09:55:31.749895Z","steps":["trace[1648261972] 'process raft request' (duration: 184.045471ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:55:31.750016Z","caller":"traceutil/trace.go:171","msg":"trace[710580246] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"185.736024ms","start":"2026-06-10T09:55:31.564245Z","end":"2026-06-10T09:55:31.749981Z","steps":["trace[710580246] 'process raft request' (duration: 14.217361ms)","trace[710580246] 'compare' (duration: 171.037864ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T09:55:31.750068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.610676ms","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-06-10T09:55:31.750154Z","caller":"traceutil/trace.go:171","msg":"trace[2130912149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2120; }","duration":"170.713258ms","start":"2026-06-10T09:55:31.579402Z","end":"2026-06-10T09:55:31.750115Z","steps":["trace[2130912149] 'agreement among raft nodes before linearized reading' (duration: 170.506414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:31.76816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.543848ms","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-06-10T09:55:31.768167Z","caller":"traceutil/trace.go:171","msg":"trace[1061555047] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"117.798917ms","start":"2026-06-10T09:55:31.650336Z","end":"2026-06-10T09:55:31.768135Z","steps":["trace[1061555047] 'process raft request' (duration: 117.570813ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:55:31.768242Z","caller":"traceutil/trace.go:171","msg":"trace[1016159612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2121; }","duration":"177.656201ms","start":"2026-06-10T09:55:31.590567Z","end":"2026-06-10T09:55:31.768223Z","steps":["trace[1016159612] 'agreement among raft nodes before linearized reading' (duration: 177.457357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:31.768468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.95884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T09:55:31.76855Z","caller":"traceutil/trace.go:171","msg":"trace[1262388339] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2121; }","duration":"172.095215ms","start":"2026-06-10T09:55:31.596438Z","end":"2026-06-10T09:55:31.768533Z","steps":["trace[1262388339] 'agreement among raft nodes before linearized reading' (duration: 171.891181ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:55:37.877113Z","caller":"traceutil/trace.go:171","msg":"trace[72491570] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"110.777153ms","start":"2026-06-10T09:55:37.766309Z","end":"2026-06-10T09:55:37.877086Z","steps":["trace[72491570] 'process raft request' (duration: 110.624221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:38.205844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.213993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T09:55:38.206077Z","caller":"traceutil/trace.go:171","msg":"trace[908533743] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2229; }","duration":"395.039437ms","start":"2026-06-10T09:55:37.811014Z","end":"2026-06-10T09:55:38.206054Z","steps":["trace[908533743] 'read index received' (duration: 65.944958ms)","trace[908533743] 'applied index is now lower than readState.Index' (duration: 329.093029ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:55:38.206176Z","caller":"traceutil/trace.go:171","msg":"trace[1668710821] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"421.670015ms","start":"2026-06-10T09:55:37.784495Z","end":"2026-06-10T09:55:38.206165Z","steps":["trace[1668710821] 'process raft request' (duration: 421.452171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:38.206251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:55:37.784479Z","time spent":"421.733077ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T09:55:38.206494Z","caller":"traceutil/trace.go:171","msg":"trace[765612682] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"437.241423ms","start":"2026-06-10T09:55:37.769218Z","end":"2026-06-10T09:55:38.206459Z","steps":["trace[765612682] 'process raft request' (duration: 261.320027ms)","trace[765612682] 'compare' (duration: 174.929478ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:55:38.206523Z","caller":"traceutil/trace.go:171","msg":"trace[416137581] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"346.611964ms","start":"2026-06-10T09:55:37.859899Z","end":"2026-06-10T09:55:38.206511Z","steps":["trace[416137581] 'process raft request' (duration: 346.109924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:38.20658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.126456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T09:55:38.206606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.851437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-10T09:55:38.206613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:55:37.859869Z","time spent":"346.710346ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T09:55:38.206657Z","caller":"traceutil/trace.go:171","msg":"trace[362634724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2154; }","duration":"336.98696ms","start":"2026-06-10T09:55:37.869658Z","end":"2026-06-10T09:55:38.206645Z","steps":["trace[362634724] 'agreement among raft nodes before linearized reading' (duration: 336.860078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:38.206609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.641942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-10T09:55:38.206672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:55:37.769193Z","time spent":"437.374206ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T09:55:38.206676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.641009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-10T09:55:38.206657Z","caller":"traceutil/trace.go:171","msg":"trace[724881825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2154; }","duration":"138.211967ms","start":"2026-06-10T09:55:38.068427Z","end":"2026-06-10T09:55:38.206639Z","steps":["trace[724881825] 'agreement among raft nodes before linearized reading' (duration: 138.079725ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:55:38.206715Z","caller":"traceutil/trace.go:171","msg":"trace[1978107431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2154; }","duration":"381.750754ms","start":"2026-06-10T09:55:37.824947Z","end":"2026-06-10T09:55:38.206698Z","steps":["trace[1978107431] 'agreement among raft nodes before linearized reading' (duration: 381.53298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:38.206744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.278122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-10T09:55:38.206803Z","caller":"traceutil/trace.go:171","msg":"trace[394861619] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2154; }","duration":"395.802132ms","start":"2026-06-10T09:55:37.810951Z","end":"2026-06-10T09:55:38.206753Z","steps":["trace[394861619] 'agreement among raft nodes before linearized reading' (duration: 395.489795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:38.206697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:55:37.869646Z","time spent":"337.043121ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T09:55:38.20687Z","caller":"traceutil/trace.go:171","msg":"trace[28593118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2154; }","duration":"134.458795ms","start":"2026-06-10T09:55:38.072388Z","end":"2026-06-10T09:55:38.206847Z","steps":["trace[28593118] 'agreement among raft nodes before linearized reading' (duration: 134.123199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T09:55:38.206883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:55:37.810932Z","time spent":"395.939475ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-10T09:55:38.206882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T09:55:37.824929Z","time spent":"381.938018ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T09:55:38.477704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.234257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T09:55:38.477863Z","caller":"traceutil/trace.go:171","msg":"trace[762993774] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"266.987094ms","start":"2026-06-10T09:55:38.210847Z","end":"2026-06-10T09:55:38.477834Z","steps":["trace[762993774] 'process raft request' (duration: 106.520553ms)","trace[762993774] 'compare' (duration: 159.949332ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T09:55:38.477982Z","caller":"traceutil/trace.go:171","msg":"trace[1563697529] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"266.593567ms","start":"2026-06-10T09:55:38.211365Z","end":"2026-06-10T09:55:38.477959Z","steps":["trace[1563697529] 'process raft request' (duration: 266.543746ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T09:55:38.478035Z","caller":"traceutil/trace.go:171","msg":"trace[2123718100] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"266.940593ms","start":"2026-06-10T09:55:38.211058Z","end":"2026-06-10T09:55:38.477999Z","steps":["trace[2123718100] 'process raft request' (duration: 266.75927ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:01:01.353327Z","caller":"traceutil/trace.go:171","msg":"trace[284106358] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5170; }","duration":"173.987112ms","start":"2026-06-10T10:01:01.179288Z","end":"2026-06-10T10:01:01.353275Z","steps":["trace[284106358] 'read index received' (duration: 147.412636ms)","trace[284106358] 'applied index is now lower than readState.Index' (duration: 26.572996ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:01:01.353519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.222017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T10:01:01.353556Z","caller":"traceutil/trace.go:171","msg":"trace[552014488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4984; }","duration":"174.307678ms","start":"2026-06-10T10:01:01.179239Z","end":"2026-06-10T10:01:01.353547Z","steps":["trace[552014488] 'agreement among raft nodes before linearized reading' (duration: 174.150845ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:01:01.353348Z","caller":"traceutil/trace.go:171","msg":"trace[556201030] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"232.117208ms","start":"2026-06-10T10:01:01.121174Z","end":"2026-06-10T10:01:01.353291Z","steps":["trace[556201030] 'process raft request' (duration: 205.498442ms)","trace[556201030] 'compare' (duration: 26.419193ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:01:24.206887Z","caller":"traceutil/trace.go:171","msg":"trace[169626875] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"120.584045ms","start":"2026-06-10T10:01:24.086281Z","end":"2026-06-10T10:01:24.206865Z","steps":["trace[169626875] 'process raft request' (duration: 120.396041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:01:24.408153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.631072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T10:01:24.408283Z","caller":"traceutil/trace.go:171","msg":"trace[565533628] linearizableReadLoop","detail":"{readStateIndex:5578; appliedIndex:5577; }","duration":"180.106549ms","start":"2026-06-10T10:01:24.228158Z","end":"2026-06-10T10:01:24.408264Z","steps":["trace[565533628] 'read index received' (duration: 64.209663ms)","trace[565533628] 'applied index is now lower than readState.Index' (duration: 115.895946ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:01:24.408373Z","caller":"traceutil/trace.go:171","msg":"trace[1337969982] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"186.342407ms","start":"2026-06-10T10:01:24.221992Z","end":"2026-06-10T10:01:24.408334Z","steps":["trace[1337969982] 'process raft request' (duration: 70.431101ms)","trace[1337969982] 'compare' (duration: 115.473538ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:01:24.408473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.311832ms","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-06-10T10:01:24.408507Z","caller":"traceutil/trace.go:171","msg":"trace[1667939814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5381; }","duration":"180.397464ms","start":"2026-06-10T10:01:24.228098Z","end":"2026-06-10T10:01:24.408496Z","steps":["trace[1667939814] 'agreement among raft nodes before linearized reading' (duration: 180.264781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:01:24.408617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.993345ms","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-06-10T10:01:24.408696Z","caller":"traceutil/trace.go:171","msg":"trace[291128423] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5381; }","duration":"128.152609ms","start":"2026-06-10T10:01:24.280521Z","end":"2026-06-10T10:01:24.408674Z","steps":["trace[291128423] 'agreement among raft nodes before linearized reading' (duration: 127.906685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:01:24.408803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.506788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T10:01:24.408929Z","caller":"traceutil/trace.go:171","msg":"trace[1555826594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5381; }","duration":"125.672721ms","start":"2026-06-10T10:01:24.283225Z","end":"2026-06-10T10:01:24.408898Z","steps":["trace[1555826594] 'agreement among raft nodes before linearized reading' (duration: 125.271564ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:01:26.331033Z","caller":"traceutil/trace.go:171","msg":"trace[116648016] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"168.364484ms","start":"2026-06-10T10:01:26.162618Z","end":"2026-06-10T10:01:26.330983Z","steps":["trace[116648016] 'process raft request' (duration: 94.969718ms)","trace[116648016] 'compare' (duration: 73.141212ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:01:26.331046Z","caller":"traceutil/trace.go:171","msg":"trace[1679396751] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"121.804429ms","start":"2026-06-10T10:01:26.209197Z","end":"2026-06-10T10:01:26.331001Z","steps":["trace[1679396751] 'process raft request' (duration: 121.723037ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:01:38.520031Z","caller":"traceutil/trace.go:171","msg":"trace[731736692] linearizableReadLoop","detail":"{readStateIndex:5798; appliedIndex:5797; }","duration":"116.908276ms","start":"2026-06-10T10:01:38.403084Z","end":"2026-06-10T10:01:38.519992Z","steps":["trace[731736692] 'read index received' (duration: 51.512111ms)","trace[731736692] 'applied index is now lower than readState.Index' (duration: 65.394395ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:01:38.520223Z","caller":"traceutil/trace.go:171","msg":"trace[49180287] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"127.303543ms","start":"2026-06-10T10:01:38.392904Z","end":"2026-06-10T10:01:38.520207Z","steps":["trace[49180287] 'process raft request' (duration: 61.801376ms)","trace[49180287] 'compare' (duration: 65.11591ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:01:38.520557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.464016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b0442f20-fa0a-4f73-9535-35b50db36d0e\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-10T10:01:38.520674Z","caller":"traceutil/trace.go:171","msg":"trace[1485187472] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b0442f20-fa0a-4f73-9535-35b50db36d0e; range_end:; response_count:1; response_revision:5594; }","duration":"117.629259ms","start":"2026-06-10T10:01:38.403016Z","end":"2026-06-10T10:01:38.520645Z","steps":["trace[1485187472] 'agreement among raft nodes before linearized reading' (duration: 117.338003ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:32.680341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-06-10T10:02:32.756609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"74.217492ms","hash":620301321,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":33681408,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T10:02:32.756678Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":620301321,"revision":2969,"compact-revision":-1} {"level":"warn","ts":"2026-06-10T10:06:50.717868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.346667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-10T10:06:50.717958Z","caller":"traceutil/trace.go:171","msg":"trace[2007772056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7837; }","duration":"124.490989ms","start":"2026-06-10T10:06:50.593448Z","end":"2026-06-10T10:06:50.717939Z","steps":["trace[2007772056] 'range keys from in-memory index tree' (duration: 124.051751ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:06:50.723201Z","caller":"traceutil/trace.go:171","msg":"trace[1115924102] transaction","detail":"{read_only:false; response_revision:7838; number_of_response:1; }","duration":"108.936843ms","start":"2026-06-10T10:06:50.614238Z","end":"2026-06-10T10:06:50.723175Z","steps":["trace[1115924102] 'process raft request' (duration: 108.799191ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:07:32.710561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6040} {"level":"info","ts":"2026-06-10T10:07:32.830652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6040,"took":"118.195351ms","hash":4264375593,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":23822336,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-10T10:07:32.830749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4264375593,"revision":6040,"compact-revision":2969} {"level":"warn","ts":"2026-06-10T10:07:35.363746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.371202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T10:07:35.363907Z","caller":"traceutil/trace.go:171","msg":"trace[133516367] linearizableReadLoop","detail":"{readStateIndex:8567; appliedIndex:8566; }","duration":"420.478256ms","start":"2026-06-10T10:07:34.94341Z","end":"2026-06-10T10:07:35.363888Z","steps":["trace[133516367] 'read index received' (duration: 246.88726ms)","trace[133516367] 'applied index is now lower than readState.Index' (duration: 173.588686ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:07:35.364011Z","caller":"traceutil/trace.go:171","msg":"trace[614025138] transaction","detail":"{read_only:false; response_revision:8278; number_of_response:1; }","duration":"448.267891ms","start":"2026-06-10T10:07:34.915684Z","end":"2026-06-10T10:07:35.363952Z","steps":["trace[614025138] 'process raft request' (duration: 274.582923ms)","trace[614025138] 'compare' (duration: 173.109478ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:07:35.364132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.69978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-10T10:07:35.364171Z","caller":"traceutil/trace.go:171","msg":"trace[1759177605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8278; }","duration":"420.761702ms","start":"2026-06-10T10:07:34.943398Z","end":"2026-06-10T10:07:35.36416Z","steps":["trace[1759177605] 'agreement among raft nodes before linearized reading' (duration: 420.587759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:07:35.364217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:07:34.943383Z","time spent":"420.824603ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T10:07:35.364219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.443615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T10:07:35.364254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:07:34.915657Z","time spent":"448.455734ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:07:35.364287Z","caller":"traceutil/trace.go:171","msg":"trace[40094284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8278; }","duration":"296.553478ms","start":"2026-06-10T10:07:35.067715Z","end":"2026-06-10T10:07:35.364268Z","steps":["trace[40094284] 'agreement among raft nodes before linearized reading' (duration: 296.449386ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:08:19.252219Z","caller":"traceutil/trace.go:171","msg":"trace[1144529903] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"100.487317ms","start":"2026-06-10T10:08:19.151704Z","end":"2026-06-10T10:08:19.252191Z","steps":["trace[1144529903] 'process raft request' (duration: 100.249563ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:08:27.033201Z","caller":"traceutil/trace.go:171","msg":"trace[866279707] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"136.582847ms","start":"2026-06-10T10:08:26.896589Z","end":"2026-06-10T10:08:27.033171Z","steps":["trace[866279707] 'process raft request' (duration: 136.422014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:08:27.288006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.330477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T10:08:27.288173Z","caller":"traceutil/trace.go:171","msg":"trace[1962843293] linearizableReadLoop","detail":"{readStateIndex:8959; appliedIndex:8958; }","duration":"245.29735ms","start":"2026-06-10T10:08:27.042856Z","end":"2026-06-10T10:08:27.288153Z","steps":["trace[1962843293] 'read index received' (duration: 112.743039ms)","trace[1962843293] 'applied index is now lower than readState.Index' (duration: 132.552781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:08:27.288315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.460323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vpk2l\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-06-10T10:08:27.288354Z","caller":"traceutil/trace.go:171","msg":"trace[553689775] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vpk2l; range_end:; response_count:1; response_revision:8653; }","duration":"245.515174ms","start":"2026-06-10T10:08:27.042826Z","end":"2026-06-10T10:08:27.288342Z","steps":["trace[553689775] 'agreement among raft nodes before linearized reading' (duration: 245.384641ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:08:27.288336Z","caller":"traceutil/trace.go:171","msg":"trace[1161990627] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"246.967411ms","start":"2026-06-10T10:08:27.041273Z","end":"2026-06-10T10:08:27.28824Z","steps":["trace[1161990627] 'process raft request' (duration: 114.317948ms)","trace[1161990627] 'compare' (duration: 131.268576ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:08:52.894595Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002421.wal"} {"level":"info","ts":"2026-06-10T10:10:19.698076Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T10:10:19.705368Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-10T10:10:19.705495Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-10T10:12:32.717551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8264} {"level":"info","ts":"2026-06-10T10:12:32.802093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8264,"took":"82.360844ms","hash":3024157652,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":26308608,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-10T10:12:32.802152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3024157652,"revision":8264,"compact-revision":6040} {"level":"info","ts":"2026-06-10T10:17:32.723431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10754} {"level":"info","ts":"2026-06-10T10:17:32.803938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10754,"took":"77.87016ms","hash":3546110494,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":27111424,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-10T10:17:32.804016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3546110494,"revision":10754,"compact-revision":8264} {"level":"info","ts":"2026-06-10T10:19:40.242937Z","caller":"traceutil/trace.go:171","msg":"trace[1986623068] transaction","detail":"{read_only:false; response_revision:13769; number_of_response:1; }","duration":"169.893019ms","start":"2026-06-10T10:19:40.073014Z","end":"2026-06-10T10:19:40.242907Z","steps":["trace[1986623068] 'process raft request' (duration: 80.380418ms)","trace[1986623068] 'compare' (duration: 89.276247ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:19:47.871876Z","caller":"traceutil/trace.go:171","msg":"trace[1393011612] transaction","detail":"{read_only:false; response_revision:13805; number_of_response:1; }","duration":"132.122421ms","start":"2026-06-10T10:19:47.739722Z","end":"2026-06-10T10:19:47.871845Z","steps":["trace[1393011612] 'process raft request' (duration: 131.875507ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:19:47.871893Z","caller":"traceutil/trace.go:171","msg":"trace[1262542297] transaction","detail":"{read_only:false; response_revision:13806; number_of_response:1; }","duration":"132.01365ms","start":"2026-06-10T10:19:47.739757Z","end":"2026-06-10T10:19:47.871771Z","steps":["trace[1262542297] 'process raft request' (duration: 131.957409ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:20:06.174347Z","caller":"traceutil/trace.go:171","msg":"trace[2003070890] transaction","detail":"{read_only:false; response_revision:14036; number_of_response:1; }","duration":"195.072388ms","start":"2026-06-10T10:20:05.979235Z","end":"2026-06-10T10:20:06.174307Z","steps":["trace[2003070890] 'process raft request' (duration: 194.934725ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:20:06.361245Z","caller":"traceutil/trace.go:171","msg":"trace[2095236117] linearizableReadLoop","detail":"{readStateIndex:14514; appliedIndex:14512; }","duration":"292.777505ms","start":"2026-06-10T10:20:06.068428Z","end":"2026-06-10T10:20:06.361205Z","steps":["trace[2095236117] 'read index received' (duration: 105.821841ms)","trace[2095236117] 'applied index is now lower than readState.Index' (duration: 186.954564ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:20:06.361277Z","caller":"traceutil/trace.go:171","msg":"trace[1899099702] transaction","detail":"{read_only:false; response_revision:14037; number_of_response:1; }","duration":"369.558255ms","start":"2026-06-10T10:20:05.991684Z","end":"2026-06-10T10:20:06.361242Z","steps":["trace[1899099702] 'process raft request' (duration: 311.534201ms)","trace[1899099702] 'compare' (duration: 57.886621ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:20:06.361525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.253689ms","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-06-10T10:20:06.361544Z","caller":"traceutil/trace.go:171","msg":"trace[1485195665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14037; }","duration":"248.30849ms","start":"2026-06-10T10:20:06.11323Z","end":"2026-06-10T10:20:06.361538Z","steps":["trace[1485195665] 'agreement among raft nodes before linearized reading' (duration: 248.230179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:20:06.361586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:20:05.991657Z","time spent":"369.81862ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:20:06.36163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.184967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:20:06.361714Z","caller":"traceutil/trace.go:171","msg":"trace[610505999] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:14037; }","duration":"128.32703ms","start":"2026-06-10T10:20:06.23337Z","end":"2026-06-10T10:20:06.361697Z","steps":["trace[610505999] 'agreement among raft nodes before linearized reading' (duration: 128.184688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:20:06.361738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.630845ms","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-06-10T10:20:06.361755Z","caller":"traceutil/trace.go:171","msg":"trace[2111102567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14037; }","duration":"240.687856ms","start":"2026-06-10T10:20:06.121063Z","end":"2026-06-10T10:20:06.361751Z","steps":["trace[2111102567] 'agreement among raft nodes before linearized reading' (duration: 240.636685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:20:06.361816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.515744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-10T10:20:06.361376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:20:06.361926Z","caller":"traceutil/trace.go:171","msg":"trace[1754163339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14037; }","duration":"293.52842ms","start":"2026-06-10T10:20:06.068379Z","end":"2026-06-10T10:20:06.361907Z","steps":["trace[1754163339] 'agreement among raft nodes before linearized reading' (duration: 292.936359ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:20:06.362074Z","caller":"traceutil/trace.go:171","msg":"trace[1123999551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14037; }","duration":"248.538784ms","start":"2026-06-10T10:20:06.113284Z","end":"2026-06-10T10:20:06.361823Z","steps":["trace[1123999551] 'agreement among raft nodes before linearized reading' (duration: 248.506434ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:21:40.352786Z","caller":"traceutil/trace.go:171","msg":"trace[89239612] transaction","detail":"{read_only:false; response_revision:15092; number_of_response:1; }","duration":"140.91075ms","start":"2026-06-10T10:21:40.211853Z","end":"2026-06-10T10:21:40.352764Z","steps":["trace[89239612] 'process raft request' (duration: 140.738157ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:21:40.68689Z","caller":"traceutil/trace.go:171","msg":"trace[46887883] linearizableReadLoop","detail":"{readStateIndex:15605; appliedIndex:15604; }","duration":"302.056056ms","start":"2026-06-10T10:21:40.384801Z","end":"2026-06-10T10:21:40.686857Z","steps":["trace[46887883] 'read index received' (duration: 301.959464ms)","trace[46887883] 'applied index is now lower than readState.Index' (duration: 94.852µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:21:40.687061Z","caller":"traceutil/trace.go:171","msg":"trace[1754458140] transaction","detail":"{read_only:false; response_revision:15093; number_of_response:1; }","duration":"327.592723ms","start":"2026-06-10T10:21:40.359453Z","end":"2026-06-10T10:21:40.687046Z","steps":["trace[1754458140] 'process raft request' (duration: 327.187396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:21:40.687191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:21:40.359432Z","time spent":"327.663324ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:21:40.687196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.371361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:21:40.687529Z","caller":"traceutil/trace.go:171","msg":"trace[585456550] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15093; }","duration":"302.620586ms","start":"2026-06-10T10:21:40.384764Z","end":"2026-06-10T10:21:40.687385Z","steps":["trace[585456550] 'agreement among raft nodes before linearized reading' (duration: 302.352751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:21:40.687686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:21:40.384745Z","time spent":"302.85218ms","remote":"127.0.0.1:54848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-06-10T10:21:40.699112Z","caller":"traceutil/trace.go:171","msg":"trace[1940470905] transaction","detail":"{read_only:false; response_revision:15094; number_of_response:1; }","duration":"187.432757ms","start":"2026-06-10T10:21:40.511651Z","end":"2026-06-10T10:21:40.699084Z","steps":["trace[1940470905] 'process raft request' (duration: 187.224333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:21:40.699205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.102886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-06-10T10:21:40.699193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.765434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:21:40.699239Z","caller":"traceutil/trace.go:171","msg":"trace[546172170] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15094; }","duration":"312.150338ms","start":"2026-06-10T10:21:40.387079Z","end":"2026-06-10T10:21:40.69923Z","steps":["trace[546172170] 'agreement among raft nodes before linearized reading' (duration: 312.078606ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:21:40.699252Z","caller":"traceutil/trace.go:171","msg":"trace[838154548] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15094; }","duration":"100.879667ms","start":"2026-06-10T10:21:40.598358Z","end":"2026-06-10T10:21:40.699238Z","steps":["trace[838154548] 'agreement among raft nodes before linearized reading' (duration: 100.780624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:21:40.699268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:21:40.38707Z","time spent":"312.193189ms","remote":"127.0.0.1:54848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5471,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-06-10T10:21:40.699134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.590936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-10T10:21:40.699374Z","caller":"traceutil/trace.go:171","msg":"trace[1904708720] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15094; }","duration":"312.834081ms","start":"2026-06-10T10:21:40.386489Z","end":"2026-06-10T10:21:40.699323Z","steps":["trace[1904708720] 'agreement among raft nodes before linearized reading' (duration: 312.473054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:21:40.699612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:21:40.386477Z","time spent":"312.978635ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-06-10T10:22:32.73134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12829} {"level":"info","ts":"2026-06-10T10:22:32.814123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12829,"took":"80.073078ms","hash":464848134,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":31117312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-10T10:22:32.814184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":464848134,"revision":12829,"compact-revision":10754} {"level":"info","ts":"2026-06-10T10:27:30.669417Z","caller":"traceutil/trace.go:171","msg":"trace[1755088771] transaction","detail":"{read_only:false; response_revision:17774; number_of_response:1; }","duration":"115.446961ms","start":"2026-06-10T10:27:30.553937Z","end":"2026-06-10T10:27:30.669384Z","steps":["trace[1755088771] 'process raft request' (duration: 115.289258ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:27:31.279012Z","caller":"traceutil/trace.go:171","msg":"trace[1026110797] transaction","detail":"{read_only:false; response_revision:17775; number_of_response:1; }","duration":"420.318074ms","start":"2026-06-10T10:27:30.858675Z","end":"2026-06-10T10:27:31.278993Z","steps":["trace[1026110797] 'process raft request' (duration: 420.160611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:27:31.27916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:27:30.85865Z","time spent":"420.427796ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20888,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:27:31.279744Z","caller":"traceutil/trace.go:171","msg":"trace[1216873035] linearizableReadLoop","detail":"{readStateIndex:18374; appliedIndex:18374; }","duration":"407.48598ms","start":"2026-06-10T10:27:30.872221Z","end":"2026-06-10T10:27:31.279707Z","steps":["trace[1216873035] 'read index received' (duration: 407.468349ms)","trace[1216873035] 'applied index is now lower than readState.Index' (duration: 14.561µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:27:31.27988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.662103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-10T10:27:31.279913Z","caller":"traceutil/trace.go:171","msg":"trace[352676652] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17775; }","duration":"407.734814ms","start":"2026-06-10T10:27:30.872167Z","end":"2026-06-10T10:27:31.279902Z","steps":["trace[352676652] 'agreement among raft nodes before linearized reading' (duration: 407.626162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:27:31.279948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:27:30.872146Z","time spent":"407.786075ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-10T10:27:31.362407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.857002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:27:31.362501Z","caller":"traceutil/trace.go:171","msg":"trace[1707412408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17775; }","duration":"294.977364ms","start":"2026-06-10T10:27:31.067498Z","end":"2026-06-10T10:27:31.362476Z","steps":["trace[1707412408] 'agreement among raft nodes before linearized reading' (duration: 294.435144ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:27:32.738134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15426} {"level":"info","ts":"2026-06-10T10:27:32.842843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15426,"took":"101.273652ms","hash":3109876990,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":32714752,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-10T10:27:32.842901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3109876990,"revision":15426,"compact-revision":12829} {"level":"info","ts":"2026-06-10T10:30:41.03184Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T10:30:41.037508Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-10T10:30:41.03762Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-10T10:32:32.704334Z","caller":"traceutil/trace.go:171","msg":"trace[378501710] transaction","detail":"{read_only:false; response_revision:20126; number_of_response:1; }","duration":"140.158829ms","start":"2026-06-10T10:32:32.564127Z","end":"2026-06-10T10:32:32.704286Z","steps":["trace[378501710] 'process raft request' (duration: 139.938425ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:32:32.769053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17789} {"level":"info","ts":"2026-06-10T10:32:32.864541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17789,"took":"91.695325ms","hash":1236456624,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":34242560,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T10:32:32.864604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1236456624,"revision":17789,"compact-revision":15426} {"level":"info","ts":"2026-06-10T10:32:48.899404Z","caller":"traceutil/trace.go:171","msg":"trace[233989589] transaction","detail":"{read_only:false; response_revision:20209; number_of_response:1; }","duration":"135.177384ms","start":"2026-06-10T10:32:48.764197Z","end":"2026-06-10T10:32:48.899374Z","steps":["trace[233989589] 'process raft request' (duration: 135.006771ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:32:49.224179Z","caller":"traceutil/trace.go:171","msg":"trace[461135932] transaction","detail":"{read_only:false; response_revision:20211; number_of_response:1; }","duration":"134.180985ms","start":"2026-06-10T10:32:49.089967Z","end":"2026-06-10T10:32:49.224148Z","steps":["trace[461135932] 'process raft request' (duration: 127.04844ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:32:49.229319Z","caller":"traceutil/trace.go:171","msg":"trace[826365414] transaction","detail":"{read_only:false; response_revision:20213; number_of_response:1; }","duration":"122.352569ms","start":"2026-06-10T10:32:49.106943Z","end":"2026-06-10T10:32:49.229296Z","steps":["trace[826365414] 'process raft request' (duration: 121.443393ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:32:49.228753Z","caller":"traceutil/trace.go:171","msg":"trace[122618913] transaction","detail":"{read_only:false; response_revision:20212; number_of_response:1; }","duration":"138.320904ms","start":"2026-06-10T10:32:49.090404Z","end":"2026-06-10T10:32:49.228724Z","steps":["trace[122618913] 'process raft request' (duration: 137.726483ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:36:49.308463Z","caller":"traceutil/trace.go:171","msg":"trace[81638815] transaction","detail":"{read_only:false; response_revision:22662; number_of_response:1; }","duration":"200.009339ms","start":"2026-06-10T10:36:49.108425Z","end":"2026-06-10T10:36:49.308434Z","steps":["trace[81638815] 'process raft request' (duration: 199.768445ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:36:49.421615Z","caller":"traceutil/trace.go:171","msg":"trace[1644728129] transaction","detail":"{read_only:false; response_revision:22663; number_of_response:1; }","duration":"108.69718ms","start":"2026-06-10T10:36:49.312889Z","end":"2026-06-10T10:36:49.421586Z","steps":["trace[1644728129] 'process raft request' (duration: 98.589678ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:36:49.421901Z","caller":"traceutil/trace.go:171","msg":"trace[1161084918] transaction","detail":"{read_only:false; response_revision:22664; number_of_response:1; }","duration":"106.250473ms","start":"2026-06-10T10:36:49.315622Z","end":"2026-06-10T10:36:49.421872Z","steps":["trace[1161084918] 'process raft request' (duration: 105.924607ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:37:32.775578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20127} {"level":"info","ts":"2026-06-10T10:37:32.888196Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20127,"took":"109.425354ms","hash":4068035295,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":40620032,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-06-10T10:37:32.888274Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4068035295,"revision":20127,"compact-revision":17789} {"level":"info","ts":"2026-06-10T10:40:27.189059Z","caller":"traceutil/trace.go:171","msg":"trace[1856219022] linearizableReadLoop","detail":"{readStateIndex:25676; appliedIndex:25675; }","duration":"123.098064ms","start":"2026-06-10T10:40:27.065939Z","end":"2026-06-10T10:40:27.189038Z","steps":["trace[1856219022] 'read index received' (duration: 122.91485ms)","trace[1856219022] 'applied index is now lower than readState.Index' (duration: 182.574µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:27.189225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.223799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:27.18926Z","caller":"traceutil/trace.go:171","msg":"trace[1217527173] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"210.190995ms","start":"2026-06-10T10:40:26.97904Z","end":"2026-06-10T10:40:27.189231Z","steps":["trace[1217527173] 'process raft request' (duration: 209.875948ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:27.18926Z","caller":"traceutil/trace.go:171","msg":"trace[1450551318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24864; }","duration":"123.36753ms","start":"2026-06-10T10:40:27.065883Z","end":"2026-06-10T10:40:27.189251Z","steps":["trace[1450551318] 'agreement among raft nodes before linearized reading' (duration: 123.237427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:28.414283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.740971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:28.414363Z","caller":"traceutil/trace.go:171","msg":"trace[635614505] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:24868; }","duration":"149.851774ms","start":"2026-06-10T10:40:28.264494Z","end":"2026-06-10T10:40:28.414345Z","steps":["trace[635614505] 'count revisions from in-memory index tree' (duration: 149.63295ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:28.57711Z","caller":"traceutil/trace.go:171","msg":"trace[2090799985] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"104.722535ms","start":"2026-06-10T10:40:28.472336Z","end":"2026-06-10T10:40:28.577059Z","steps":["trace[2090799985] 'process raft request' (duration: 104.520421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:28.819692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.723374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:40:28.819892Z","caller":"traceutil/trace.go:171","msg":"trace[1539865762] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"238.561634ms","start":"2026-06-10T10:40:28.581309Z","end":"2026-06-10T10:40:28.819871Z","steps":["trace[1539865762] 'process raft request' (duration: 238.489733ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:28.820036Z","caller":"traceutil/trace.go:171","msg":"trace[647941806] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"284.457009ms","start":"2026-06-10T10:40:28.535562Z","end":"2026-06-10T10:40:28.820019Z","steps":["trace[647941806] 'process raft request' (duration: 146.240956ms)","trace[647941806] 'compare' (duration: 137.433118ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:28.820192Z","caller":"traceutil/trace.go:171","msg":"trace[2058166749] linearizableReadLoop","detail":"{readStateIndex:25682; appliedIndex:25681; }","duration":"243.104451ms","start":"2026-06-10T10:40:28.577029Z","end":"2026-06-10T10:40:28.820134Z","steps":["trace[2058166749] 'read index received' (duration: 104.768946ms)","trace[2058166749] 'applied index is now lower than readState.Index' (duration: 138.333305ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:28.820332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.591603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-10T10:40:28.820372Z","caller":"traceutil/trace.go:171","msg":"trace[617324670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24871; }","duration":"293.689945ms","start":"2026-06-10T10:40:28.526669Z","end":"2026-06-10T10:40:28.820359Z","steps":["trace[617324670] 'agreement among raft nodes before linearized reading' (duration: 293.587912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:28.820485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.755277ms","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-06-10T10:40:28.820523Z","caller":"traceutil/trace.go:171","msg":"trace[1723207579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24871; }","duration":"292.824868ms","start":"2026-06-10T10:40:28.527688Z","end":"2026-06-10T10:40:28.820513Z","steps":["trace[1723207579] 'agreement among raft nodes before linearized reading' (duration: 292.746516ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:32.375674Z","caller":"traceutil/trace.go:171","msg":"trace[1497219597] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"218.071084ms","start":"2026-06-10T10:40:32.157574Z","end":"2026-06-10T10:40:32.375646Z","steps":["trace[1497219597] 'process raft request' (duration: 217.866031ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:32.411302Z","caller":"traceutil/trace.go:171","msg":"trace[1319835598] linearizableReadLoop","detail":"{readStateIndex:25715; appliedIndex:25714; }","duration":"173.821651ms","start":"2026-06-10T10:40:32.237459Z","end":"2026-06-10T10:40:32.411281Z","steps":["trace[1319835598] 'read index received' (duration: 138.222174ms)","trace[1319835598] 'applied index is now lower than readState.Index' (duration: 35.598107ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:32.411493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.003585ms","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-06-10T10:40:32.411486Z","caller":"traceutil/trace.go:171","msg":"trace[1012414119] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"184.384532ms","start":"2026-06-10T10:40:32.227031Z","end":"2026-06-10T10:40:32.411416Z","steps":["trace[1012414119] 'process raft request' (duration: 184.122607ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:32.411543Z","caller":"traceutil/trace.go:171","msg":"trace[1283669038] 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:24902; }","duration":"174.106536ms","start":"2026-06-10T10:40:32.237422Z","end":"2026-06-10T10:40:32.411528Z","steps":["trace[1283669038] 'agreement among raft nodes before linearized reading' (duration: 173.998264ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:32.706236Z","caller":"traceutil/trace.go:171","msg":"trace[1449845031] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"116.718813ms","start":"2026-06-10T10:40:32.589463Z","end":"2026-06-10T10:40:32.706182Z","steps":["trace[1449845031] 'process raft request' (duration: 114.901838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:33.13377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.282258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:40:33.134129Z","caller":"traceutil/trace.go:171","msg":"trace[2013680603] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"164.943902ms","start":"2026-06-10T10:40:32.969164Z","end":"2026-06-10T10:40:33.134108Z","steps":["trace[2013680603] 'process raft request' (duration: 164.90069ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:33.134109Z","caller":"traceutil/trace.go:171","msg":"trace[93020032] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25717; }","duration":"246.230579ms","start":"2026-06-10T10:40:32.887845Z","end":"2026-06-10T10:40:33.134076Z","steps":["trace[93020032] 'read index received' (duration: 132.485113ms)","trace[93020032] 'applied index is now lower than readState.Index' (duration: 113.743216ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:33.134294Z","caller":"traceutil/trace.go:171","msg":"trace[1293412849] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"295.867387ms","start":"2026-06-10T10:40:32.838305Z","end":"2026-06-10T10:40:33.134172Z","steps":["trace[1293412849] 'process raft request' (duration: 182.093829ms)","trace[1293412849] 'compare' (duration: 112.981262ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:33.134224Z","caller":"traceutil/trace.go:171","msg":"trace[1890349354] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"240.586514ms","start":"2026-06-10T10:40:32.893565Z","end":"2026-06-10T10:40:33.134152Z","steps":["trace[1890349354] 'process raft request' (duration: 240.325968ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:33.134437Z","caller":"traceutil/trace.go:171","msg":"trace[1617575445] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"200.964959ms","start":"2026-06-10T10:40:32.933464Z","end":"2026-06-10T10:40:33.134429Z","steps":["trace[1617575445] 'process raft request' (duration: 200.55361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:33.13464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.583382ms","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-06-10T10:40:33.134919Z","caller":"traceutil/trace.go:171","msg":"trace[2104893569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24908; }","duration":"294.040751ms","start":"2026-06-10T10:40:32.840861Z","end":"2026-06-10T10:40:33.134902Z","steps":["trace[2104893569] 'agreement among raft nodes before linearized reading' (duration: 293.41395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:33.135235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.667593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-10T10:40:33.135355Z","caller":"traceutil/trace.go:171","msg":"trace[2037067577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24908; }","duration":"294.839615ms","start":"2026-06-10T10:40:32.8405Z","end":"2026-06-10T10:40:33.135339Z","steps":["trace[2037067577] 'agreement among raft nodes before linearized reading' (duration: 294.144673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:33.418371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.345764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:40:33.418519Z","caller":"traceutil/trace.go:171","msg":"trace[651171951] linearizableReadLoop","detail":"{readStateIndex:25722; appliedIndex:25721; }","duration":"284.231115ms","start":"2026-06-10T10:40:33.134242Z","end":"2026-06-10T10:40:33.418473Z","steps":["trace[651171951] 'read index received' (duration: 133.751868ms)","trace[651171951] 'applied index is now lower than readState.Index' (duration: 150.477197ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:33.418611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.253481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:33.418638Z","caller":"traceutil/trace.go:171","msg":"trace[164050989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24909; }","duration":"351.339233ms","start":"2026-06-10T10:40:33.06729Z","end":"2026-06-10T10:40:33.418629Z","steps":["trace[164050989] 'agreement among raft nodes before linearized reading' (duration: 351.267011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:33.41868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:33.067267Z","time spent":"351.406195ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T10:40:33.418797Z","caller":"traceutil/trace.go:171","msg":"trace[755233561] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"375.30968ms","start":"2026-06-10T10:40:33.043417Z","end":"2026-06-10T10:40:33.418727Z","steps":["trace[755233561] 'process raft request' (duration: 224.511267ms)","trace[755233561] 'compare' (duration: 150.200751ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:33.418964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:33.043386Z","time spent":"375.476113ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:40:33.682439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.977433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:40:33.682664Z","caller":"traceutil/trace.go:171","msg":"trace[319775296] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"542.980365ms","start":"2026-06-10T10:40:33.139648Z","end":"2026-06-10T10:40:33.682628Z","steps":["trace[319775296] 'process raft request' (duration: 408.716337ms)","trace[319775296] 'compare' (duration: 133.545084ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:33.682716Z","caller":"traceutil/trace.go:171","msg":"trace[304447656] linearizableReadLoop","detail":"{readStateIndex:25725; appliedIndex:25722; }","duration":"262.893388ms","start":"2026-06-10T10:40:33.419803Z","end":"2026-06-10T10:40:33.682696Z","steps":["trace[304447656] 'read index received' (duration: 128.456207ms)","trace[304447656] 'applied index is now lower than readState.Index' (duration: 134.435801ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:33.682858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:33.139603Z","time spent":"543.126136ms","remote":"127.0.0.1:54870","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-06-10T10:40:33.682863Z","caller":"traceutil/trace.go:171","msg":"trace[472839418] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"462.419209ms","start":"2026-06-10T10:40:33.220401Z","end":"2026-06-10T10:40:33.682821Z","steps":["trace[472839418] 'process raft request' (duration: 462.240666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:33.682923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.075691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:33.682963Z","caller":"traceutil/trace.go:171","msg":"trace[1472341197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24912; }","duration":"263.192553ms","start":"2026-06-10T10:40:33.419759Z","end":"2026-06-10T10:40:33.682952Z","steps":["trace[1472341197] 'agreement among raft nodes before linearized reading' (duration: 263.05127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:33.683013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:33.220369Z","time spent":"462.563142ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:40:33.683153Z","caller":"traceutil/trace.go:171","msg":"trace[1480625069] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"542.795881ms","start":"2026-06-10T10:40:33.140329Z","end":"2026-06-10T10:40:33.683125Z","steps":["trace[1480625069] 'process raft request' (duration: 542.217079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:33.683217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:33.140317Z","time spent":"542.859952ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:40:33.683213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.720355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:33.683322Z","caller":"traceutil/trace.go:171","msg":"trace[700981221] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24912; }","duration":"253.866517ms","start":"2026-06-10T10:40:33.429445Z","end":"2026-06-10T10:40:33.683311Z","steps":["trace[700981221] 'agreement among raft nodes before linearized reading' (duration: 253.728055ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:33.841866Z","caller":"traceutil/trace.go:171","msg":"trace[20953472] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"153.766369ms","start":"2026-06-10T10:40:33.688065Z","end":"2026-06-10T10:40:33.841831Z","steps":["trace[20953472] 'process raft request' (duration: 139.892985ms)","trace[20953472] 'compare' (duration: 13.367395ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:34.72166Z","caller":"traceutil/trace.go:171","msg":"trace[602109335] linearizableReadLoop","detail":"{readStateIndex:25729; appliedIndex:25728; }","duration":"162.352942ms","start":"2026-06-10T10:40:34.559281Z","end":"2026-06-10T10:40:34.721634Z","steps":["trace[602109335] 'read index received' (duration: 162.193859ms)","trace[602109335] 'applied index is now lower than readState.Index' (duration: 157.683µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:34.721757Z","caller":"traceutil/trace.go:171","msg":"trace[299086465] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"340.185021ms","start":"2026-06-10T10:40:34.381537Z","end":"2026-06-10T10:40:34.721722Z","steps":["trace[299086465] 'process raft request' (duration: 339.927966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:34.721832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.530396ms","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-06-10T10:40:34.721876Z","caller":"traceutil/trace.go:171","msg":"trace[1886849584] 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:24916; }","duration":"162.638739ms","start":"2026-06-10T10:40:34.559225Z","end":"2026-06-10T10:40:34.721863Z","steps":["trace[1886849584] 'agreement among raft nodes before linearized reading' (duration: 162.538287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:34.721987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:34.381518Z","time spent":"340.306273ms","remote":"127.0.0.1:54870","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-06-10T10:40:34.795374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.58313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:34.795445Z","caller":"traceutil/trace.go:171","msg":"trace[584048693] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24917; }","duration":"200.717343ms","start":"2026-06-10T10:40:34.59471Z","end":"2026-06-10T10:40:34.795427Z","steps":["trace[584048693] 'agreement among raft nodes before linearized reading' (duration: 200.585521ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:35.016506Z","caller":"traceutil/trace.go:171","msg":"trace[1091232077] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"123.219968ms","start":"2026-06-10T10:40:34.893222Z","end":"2026-06-10T10:40:35.016442Z","steps":["trace[1091232077] 'process raft request' (duration: 85.768084ms)","trace[1091232077] 'compare' (duration: 36.991315ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:35.275837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.866708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-10T10:40:35.275917Z","caller":"traceutil/trace.go:171","msg":"trace[1303367593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24918; }","duration":"135.991431ms","start":"2026-06-10T10:40:35.139905Z","end":"2026-06-10T10:40:35.275896Z","steps":["trace[1303367593] 'range keys from in-memory index tree' (duration: 135.692805ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:35.27591Z","caller":"traceutil/trace.go:171","msg":"trace[1174471055] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"134.705616ms","start":"2026-06-10T10:40:35.141169Z","end":"2026-06-10T10:40:35.275875Z","steps":["trace[1174471055] 'process raft request' (duration: 43.55349ms)","trace[1174471055] 'compare' (duration: 91.032924ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:35.31307Z","caller":"traceutil/trace.go:171","msg":"trace[772667177] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"168.738595ms","start":"2026-06-10T10:40:35.144317Z","end":"2026-06-10T10:40:35.313056Z","steps":["trace[772667177] 'process raft request' (duration: 168.699114ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:35.313103Z","caller":"traceutil/trace.go:171","msg":"trace[2022347582] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"171.894994ms","start":"2026-06-10T10:40:35.141173Z","end":"2026-06-10T10:40:35.313068Z","steps":["trace[2022347582] 'process raft request' (duration: 171.759032ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:35.373393Z","caller":"traceutil/trace.go:171","msg":"trace[523812817] linearizableReadLoop","detail":"{readStateIndex:25735; appliedIndex:25734; }","duration":"162.319972ms","start":"2026-06-10T10:40:35.211048Z","end":"2026-06-10T10:40:35.373368Z","steps":["trace[523812817] 'read index received' (duration: 101.987213ms)","trace[523812817] 'applied index is now lower than readState.Index' (duration: 60.331779ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:35.373519Z","caller":"traceutil/trace.go:171","msg":"trace[1417078304] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"188.217567ms","start":"2026-06-10T10:40:35.18529Z","end":"2026-06-10T10:40:35.373507Z","steps":["trace[1417078304] 'process raft request' (duration: 187.87867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:35.374017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.933004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:35.374137Z","caller":"traceutil/trace.go:171","msg":"trace[1685493578] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24923; }","duration":"163.099606ms","start":"2026-06-10T10:40:35.210994Z","end":"2026-06-10T10:40:35.374094Z","steps":["trace[1685493578] 'agreement among raft nodes before linearized reading' (duration: 162.883402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:35.812158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.312857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:35.812244Z","caller":"traceutil/trace.go:171","msg":"trace[1065755095] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24924; }","duration":"209.47597ms","start":"2026-06-10T10:40:35.602747Z","end":"2026-06-10T10:40:35.812223Z","steps":["trace[1065755095] 'count revisions from in-memory index tree' (duration: 209.197835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:35.812254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.975843ms","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-06-10T10:40:35.812327Z","caller":"traceutil/trace.go:171","msg":"trace[1944402745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24924; }","duration":"122.176777ms","start":"2026-06-10T10:40:35.690134Z","end":"2026-06-10T10:40:35.812311Z","steps":["trace[1944402745] 'range keys from in-memory index tree' (duration: 121.747989ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:35.812343Z","caller":"traceutil/trace.go:171","msg":"trace[1735226532] linearizableReadLoop","detail":"{readStateIndex:25738; appliedIndex:25737; }","duration":"121.670818ms","start":"2026-06-10T10:40:35.69065Z","end":"2026-06-10T10:40:35.812321Z","steps":["trace[1735226532] 'read index received' (duration: 43.304735ms)","trace[1735226532] 'applied index is now lower than readState.Index' (duration: 78.364543ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:35.812398Z","caller":"traceutil/trace.go:171","msg":"trace[1275846159] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"122.060315ms","start":"2026-06-10T10:40:35.690299Z","end":"2026-06-10T10:40:35.812359Z","steps":["trace[1275846159] 'process raft request' (duration: 43.740173ms)","trace[1275846159] 'compare' (duration: 77.993046ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:35.812559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.896732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-10T10:40:35.812604Z","caller":"traceutil/trace.go:171","msg":"trace[885737154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24925; }","duration":"122.002435ms","start":"2026-06-10T10:40:35.690588Z","end":"2026-06-10T10:40:35.81259Z","steps":["trace[885737154] 'agreement among raft nodes before linearized reading' (duration: 121.860801ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:36.061401Z","caller":"traceutil/trace.go:171","msg":"trace[485869515] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"243.992368ms","start":"2026-06-10T10:40:35.817367Z","end":"2026-06-10T10:40:36.061359Z","steps":["trace[485869515] 'process raft request' (duration: 149.051589ms)","trace[485869515] 'compare' (duration: 94.285677ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:36.061676Z","caller":"traceutil/trace.go:171","msg":"trace[1027503117] linearizableReadLoop","detail":"{readStateIndex:25741; appliedIndex:25738; }","duration":"215.205019ms","start":"2026-06-10T10:40:35.846434Z","end":"2026-06-10T10:40:36.061639Z","steps":["trace[1027503117] 'read index received' (duration: 34.844924ms)","trace[1027503117] 'applied index is now lower than readState.Index' (duration: 180.358025ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:36.061804Z","caller":"traceutil/trace.go:171","msg":"trace[802133030] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"244.243162ms","start":"2026-06-10T10:40:35.817513Z","end":"2026-06-10T10:40:36.061757Z","steps":["trace[802133030] 'process raft request' (duration: 243.689382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:36.062016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.644786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-06-10T10:40:36.062007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.531345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-10T10:40:36.062101Z","caller":"traceutil/trace.go:171","msg":"trace[655163655] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:24928; }","duration":"197.755538ms","start":"2026-06-10T10:40:35.864323Z","end":"2026-06-10T10:40:36.062078Z","steps":["trace[655163655] 'agreement among raft nodes before linearized reading' (duration: 197.538844ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:36.062119Z","caller":"traceutil/trace.go:171","msg":"trace[306070145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24928; }","duration":"215.718969ms","start":"2026-06-10T10:40:35.846375Z","end":"2026-06-10T10:40:36.062094Z","steps":["trace[306070145] 'agreement among raft nodes before linearized reading' (duration: 215.400382ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:37.06623Z","caller":"traceutil/trace.go:171","msg":"trace[148807934] linearizableReadLoop","detail":"{readStateIndex:25747; appliedIndex:25746; }","duration":"251.569352ms","start":"2026-06-10T10:40:36.814638Z","end":"2026-06-10T10:40:37.066207Z","steps":["trace[148807934] 'read index received' (duration: 215.385463ms)","trace[148807934] 'applied index is now lower than readState.Index' (duration: 36.182869ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:37.066256Z","caller":"traceutil/trace.go:171","msg":"trace[340255054] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"266.19492ms","start":"2026-06-10T10:40:36.800025Z","end":"2026-06-10T10:40:37.06622Z","steps":["trace[340255054] 'process raft request' (duration: 230.016571ms)","trace[340255054] 'compare' (duration: 36.064307ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:37.066496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.062053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-10T10:40:37.066622Z","caller":"traceutil/trace.go:171","msg":"trace[1212947811] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24933; }","duration":"282.217985ms","start":"2026-06-10T10:40:36.784382Z","end":"2026-06-10T10:40:37.0666Z","steps":["trace[1212947811] 'agreement among raft nodes before linearized reading' (duration: 281.92258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.094922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.256958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:37.09502Z","caller":"traceutil/trace.go:171","msg":"trace[27678590] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24934; }","duration":"270.412831ms","start":"2026-06-10T10:40:36.824584Z","end":"2026-06-10T10:40:37.094997Z","steps":["trace[27678590] 'agreement among raft nodes before linearized reading' (duration: 270.220818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.427107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.716299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:40:37.427191Z","caller":"traceutil/trace.go:171","msg":"trace[749039352] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"145.849008ms","start":"2026-06-10T10:40:37.281328Z","end":"2026-06-10T10:40:37.427177Z","steps":["trace[749039352] 'compare' (duration: 142.542035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.427699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.961372ms","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-06-10T10:40:37.42795Z","caller":"traceutil/trace.go:171","msg":"trace[834539123] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24934; }","duration":"261.873308ms","start":"2026-06-10T10:40:37.166049Z","end":"2026-06-10T10:40:37.427923Z","steps":["trace[834539123] 'range keys from in-memory index tree' (duration: 260.86958ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:37.437922Z","caller":"traceutil/trace.go:171","msg":"trace[1224455949] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"110.478365ms","start":"2026-06-10T10:40:37.327425Z","end":"2026-06-10T10:40:37.437904Z","steps":["trace[1224455949] 'process raft request' (duration: 110.275501ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:37.747126Z","caller":"traceutil/trace.go:171","msg":"trace[5344300] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"418.89339ms","start":"2026-06-10T10:40:37.328197Z","end":"2026-06-10T10:40:37.747091Z","steps":["trace[5344300] 'process raft request' (duration: 418.558663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.747356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:37.328186Z","time spent":"419.049312ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:40:37.747434Z","caller":"traceutil/trace.go:171","msg":"trace[1537842846] linearizableReadLoop","detail":"{readStateIndex:25751; appliedIndex:25749; }","duration":"405.551245ms","start":"2026-06-10T10:40:37.341852Z","end":"2026-06-10T10:40:37.747403Z","steps":["trace[1537842846] 'read index received' (duration: 95.983548ms)","trace[1537842846] 'applied index is now lower than readState.Index' (duration: 309.562857ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:37.747558Z","caller":"traceutil/trace.go:171","msg":"trace[687430991] transaction","detail":"{read_only:false; response_revision:24938; number_of_response:1; }","duration":"329.860943ms","start":"2026-06-10T10:40:37.417645Z","end":"2026-06-10T10:40:37.747506Z","steps":["trace[687430991] 'process raft request' (duration: 329.387874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.747672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.77268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:37.747768Z","caller":"traceutil/trace.go:171","msg":"trace[449961247] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24938; }","duration":"405.903153ms","start":"2026-06-10T10:40:37.341843Z","end":"2026-06-10T10:40:37.747746Z","steps":["trace[449961247] 'agreement among raft nodes before linearized reading' (duration: 405.685978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.747819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:37.341796Z","time spent":"406.012415ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-06-10T10:40:37.747983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:37.41763Z","time spent":"330.023427ms","remote":"127.0.0.1:54870","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-06-10T10:40:37.766016Z","caller":"traceutil/trace.go:171","msg":"trace[657644565] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"266.540158ms","start":"2026-06-10T10:40:37.499451Z","end":"2026-06-10T10:40:37.765992Z","steps":["trace[657644565] 'process raft request' (duration: 266.342775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.766121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.229726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-10T10:40:37.766166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.932389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-06-10T10:40:37.766199Z","caller":"traceutil/trace.go:171","msg":"trace[1917319169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24939; }","duration":"388.355049ms","start":"2026-06-10T10:40:37.377826Z","end":"2026-06-10T10:40:37.766181Z","steps":["trace[1917319169] 'agreement among raft nodes before linearized reading' (duration: 388.135325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.766247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:37.377808Z","time spent":"388.42818ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T10:40:37.766247Z","caller":"traceutil/trace.go:171","msg":"trace[998144664] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24939; }","duration":"326.00882ms","start":"2026-06-10T10:40:37.440211Z","end":"2026-06-10T10:40:37.76622Z","steps":["trace[998144664] 'agreement among raft nodes before linearized reading' (duration: 325.826937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.766238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.600064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T10:40:37.766303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:37.440198Z","time spent":"326.089421ms","remote":"127.0.0.1:54766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-06-10T10:40:37.766309Z","caller":"traceutil/trace.go:171","msg":"trace[1350820228] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24939; }","duration":"334.748837ms","start":"2026-06-10T10:40:37.431543Z","end":"2026-06-10T10:40:37.766292Z","steps":["trace[1350820228] 'agreement among raft nodes before linearized reading' (duration: 334.582124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.766364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:37.431517Z","time spent":"334.834069ms","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-10T10:40:37.911Z","caller":"traceutil/trace.go:171","msg":"trace[14336924] linearizableReadLoop","detail":"{readStateIndex:25754; appliedIndex:25753; }","duration":"139.497017ms","start":"2026-06-10T10:40:37.771477Z","end":"2026-06-10T10:40:37.910974Z","steps":["trace[14336924] 'read index received' (duration: 126.867786ms)","trace[14336924] 'applied index is now lower than readState.Index' (duration: 12.627591ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:37.910998Z","caller":"traceutil/trace.go:171","msg":"trace[207595934] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"140.810532ms","start":"2026-06-10T10:40:37.770142Z","end":"2026-06-10T10:40:37.910953Z","steps":["trace[207595934] 'process raft request' (duration: 128.004408ms)","trace[207595934] 'compare' (duration: 12.56337ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:37.911353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.834934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18746"} {"level":"info","ts":"2026-06-10T10:40:37.911389Z","caller":"traceutil/trace.go:171","msg":"trace[188128806] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24941; }","duration":"139.935326ms","start":"2026-06-10T10:40:37.771445Z","end":"2026-06-10T10:40:37.91138Z","steps":["trace[188128806] 'agreement among raft nodes before linearized reading' (duration: 139.64034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:37.911373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.974057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:37.91147Z","caller":"traceutil/trace.go:171","msg":"trace[1767268063] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:24941; }","duration":"108.11748ms","start":"2026-06-10T10:40:37.803327Z","end":"2026-06-10T10:40:37.911444Z","steps":["trace[1767268063] 'agreement among raft nodes before linearized reading' (duration: 107.935056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:38.170444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.748187ms","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-06-10T10:40:38.170556Z","caller":"traceutil/trace.go:171","msg":"trace[1397630737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24942; }","duration":"102.945202ms","start":"2026-06-10T10:40:38.067584Z","end":"2026-06-10T10:40:38.170529Z","steps":["trace[1397630737] 'range keys from in-memory index tree' (duration: 102.433571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:38.170552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.859041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-10T10:40:38.170633Z","caller":"traceutil/trace.go:171","msg":"trace[803713036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24942; }","duration":"103.016643ms","start":"2026-06-10T10:40:38.067597Z","end":"2026-06-10T10:40:38.170614Z","steps":["trace[803713036] 'range keys from in-memory index tree' (duration: 102.653237ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:38.375857Z","caller":"traceutil/trace.go:171","msg":"trace[2102066220] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"199.912689ms","start":"2026-06-10T10:40:38.175921Z","end":"2026-06-10T10:40:38.375834Z","steps":["trace[2102066220] 'process raft request' (duration: 199.469961ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:38.376065Z","caller":"traceutil/trace.go:171","msg":"trace[418150786] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"199.789785ms","start":"2026-06-10T10:40:38.176267Z","end":"2026-06-10T10:40:38.376057Z","steps":["trace[418150786] 'process raft request' (duration: 199.294086ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:45.194363Z","caller":"traceutil/trace.go:171","msg":"trace[347631549] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"292.3809ms","start":"2026-06-10T10:40:44.901953Z","end":"2026-06-10T10:40:45.194334Z","steps":["trace[347631549] 'process raft request' (duration: 292.185767ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:45.504Z","caller":"traceutil/trace.go:171","msg":"trace[562554830] linearizableReadLoop","detail":"{readStateIndex:25807; appliedIndex:25805; }","duration":"196.064355ms","start":"2026-06-10T10:40:45.307904Z","end":"2026-06-10T10:40:45.503968Z","steps":["trace[562554830] 'read index received' (duration: 195.378132ms)","trace[562554830] 'applied index is now lower than readState.Index' (duration: 684.303µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:45.504245Z","caller":"traceutil/trace.go:171","msg":"trace[1112782970] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"314.855277ms","start":"2026-06-10T10:40:45.189352Z","end":"2026-06-10T10:40:45.504207Z","steps":["trace[1112782970] 'process raft request' (duration: 313.663765ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:45.504238Z","caller":"traceutil/trace.go:171","msg":"trace[1512089729] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"313.504062ms","start":"2026-06-10T10:40:45.19068Z","end":"2026-06-10T10:40:45.504184Z","steps":["trace[1512089729] 'process raft request' (duration: 313.222036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:45.504284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.357531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T10:40:45.50435Z","caller":"traceutil/trace.go:171","msg":"trace[2146975097] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24992; }","duration":"196.499804ms","start":"2026-06-10T10:40:45.307831Z","end":"2026-06-10T10:40:45.504331Z","steps":["trace[2146975097] 'agreement among raft nodes before linearized reading' (duration: 196.349351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:45.504423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:45.189322Z","time spent":"314.998001ms","remote":"127.0.0.1:54870","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-06-10T10:40:45.504478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:45.190642Z","time spent":"313.701917ms","remote":"127.0.0.1:54870","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-06-10T10:40:45.52755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.858507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-06-10T10:40:45.527672Z","caller":"traceutil/trace.go:171","msg":"trace[1056080456] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:24994; }","duration":"148.031071ms","start":"2026-06-10T10:40:45.379616Z","end":"2026-06-10T10:40:45.527647Z","steps":["trace[1056080456] 'agreement among raft nodes before linearized reading' (duration: 147.778736ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:45.80435Z","caller":"traceutil/trace.go:171","msg":"trace[1263126522] linearizableReadLoop","detail":"{readStateIndex:25810; appliedIndex:25809; }","duration":"152.873412ms","start":"2026-06-10T10:40:45.651452Z","end":"2026-06-10T10:40:45.804326Z","steps":["trace[1263126522] 'read index received' (duration: 151.74985ms)","trace[1263126522] 'applied index is now lower than readState.Index' (duration: 1.122222ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:45.804488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.030965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:45.804513Z","caller":"traceutil/trace.go:171","msg":"trace[389471396] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24996; }","duration":"153.129537ms","start":"2026-06-10T10:40:45.651375Z","end":"2026-06-10T10:40:45.804504Z","steps":["trace[389471396] 'agreement among raft nodes before linearized reading' (duration: 153.043325ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:45.804764Z","caller":"traceutil/trace.go:171","msg":"trace[182848072] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"183.307782ms","start":"2026-06-10T10:40:45.621441Z","end":"2026-06-10T10:40:45.804749Z","steps":["trace[182848072] 'process raft request' (duration: 181.745473ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:46.120027Z","caller":"traceutil/trace.go:171","msg":"trace[173504279] linearizableReadLoop","detail":"{readStateIndex:25812; appliedIndex:25811; }","duration":"255.302813ms","start":"2026-06-10T10:40:45.86469Z","end":"2026-06-10T10:40:46.119993Z","steps":["trace[173504279] 'read index received' (duration: 254.213922ms)","trace[173504279] 'applied index is now lower than readState.Index' (duration: 1.087781ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:46.120159Z","caller":"traceutil/trace.go:171","msg":"trace[553912221] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"308.967336ms","start":"2026-06-10T10:40:45.811182Z","end":"2026-06-10T10:40:46.12015Z","steps":["trace[553912221] 'process raft request' (duration: 307.814395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:46.120276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:45.811158Z","time spent":"309.024716ms","remote":"127.0.0.1:54870","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-06-10T10:40:46.120441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.723562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-10T10:40:46.12053Z","caller":"traceutil/trace.go:171","msg":"trace[434547770] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:24997; }","duration":"255.880235ms","start":"2026-06-10T10:40:45.86463Z","end":"2026-06-10T10:40:46.12051Z","steps":["trace[434547770] 'agreement among raft nodes before linearized reading' (duration: 255.66256ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:46.45906Z","caller":"traceutil/trace.go:171","msg":"trace[701314968] linearizableReadLoop","detail":"{readStateIndex:25813; appliedIndex:25812; }","duration":"338.914686ms","start":"2026-06-10T10:40:46.120109Z","end":"2026-06-10T10:40:46.459024Z","steps":["trace[701314968] 'read index received' (duration: 328.331645ms)","trace[701314968] 'applied index is now lower than readState.Index' (duration: 10.581581ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:40:46.459143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.875146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T10:40:46.459149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.176372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T10:40:46.459194Z","caller":"traceutil/trace.go:171","msg":"trace[275884261] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"518.205502ms","start":"2026-06-10T10:40:45.940969Z","end":"2026-06-10T10:40:46.459174Z","steps":["trace[275884261] 'process raft request' (duration: 507.475718ms)","trace[275884261] 'compare' (duration: 10.055271ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:46.459218Z","caller":"traceutil/trace.go:171","msg":"trace[108071546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24998; }","duration":"391.003729ms","start":"2026-06-10T10:40:46.068193Z","end":"2026-06-10T10:40:46.459197Z","steps":["trace[108071546] 'agreement among raft nodes before linearized reading' (duration: 390.866656ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:46.459225Z","caller":"traceutil/trace.go:171","msg":"trace[2074046051] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24998; }","duration":"370.313495ms","start":"2026-06-10T10:40:46.088895Z","end":"2026-06-10T10:40:46.459208Z","steps":["trace[2074046051] 'agreement among raft nodes before linearized reading' (duration: 370.162482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:46.459252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:46.068167Z","time spent":"391.080241ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T10:40:46.459278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:46.088861Z","time spent":"370.402447ms","remote":"127.0.0.1:43132","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-06-10T10:40:46.459326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:45.940945Z","time spent":"518.281344ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:40:46.459536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.864101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-10T10:40:46.459488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.094896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-10T10:40:46.45965Z","caller":"traceutil/trace.go:171","msg":"trace[144126019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24998; }","duration":"481.000043ms","start":"2026-06-10T10:40:45.978633Z","end":"2026-06-10T10:40:46.459633Z","steps":["trace[144126019] 'agreement among raft nodes before linearized reading' (duration: 480.452082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:46.459771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:45.978624Z","time spent":"481.133767ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T10:40:46.459709Z","caller":"traceutil/trace.go:171","msg":"trace[814350185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24998; }","duration":"511.345491ms","start":"2026-06-10T10:40:45.948329Z","end":"2026-06-10T10:40:46.459675Z","steps":["trace[814350185] 'agreement among raft nodes before linearized reading' (duration: 510.832361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:46.460478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:45.948321Z","time spent":"512.090965ms","remote":"127.0.0.1:54870","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-06-10T10:40:46.462354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:46.121491Z","time spent":"340.858534ms","remote":"127.0.0.1:54632","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-10T10:40:46.462478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.516411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-10T10:40:46.462581Z","caller":"traceutil/trace.go:171","msg":"trace[1974045737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24998; }","duration":"220.661584ms","start":"2026-06-10T10:40:46.241898Z","end":"2026-06-10T10:40:46.46256Z","steps":["trace[1974045737] 'agreement among raft nodes before linearized reading' (duration: 220.321968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:52.183721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.648362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:40:52.183824Z","caller":"traceutil/trace.go:171","msg":"trace[973199216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25095; }","duration":"117.824364ms","start":"2026-06-10T10:40:52.065971Z","end":"2026-06-10T10:40:52.183796Z","steps":["trace[973199216] 'range keys from in-memory index tree' (duration: 116.54626ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:52.623581Z","caller":"traceutil/trace.go:171","msg":"trace[402183964] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"121.281409ms","start":"2026-06-10T10:40:52.502272Z","end":"2026-06-10T10:40:52.623553Z","steps":["trace[402183964] 'process raft request' (duration: 121.127586ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:52.635399Z","caller":"traceutil/trace.go:171","msg":"trace[1356129950] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"133.010584ms","start":"2026-06-10T10:40:52.502348Z","end":"2026-06-10T10:40:52.635359Z","steps":["trace[1356129950] 'process raft request' (duration: 132.805309ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:52.635405Z","caller":"traceutil/trace.go:171","msg":"trace[1691805232] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"132.517005ms","start":"2026-06-10T10:40:52.502833Z","end":"2026-06-10T10:40:52.63535Z","steps":["trace[1691805232] 'process raft request' (duration: 132.457204ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:52.635692Z","caller":"traceutil/trace.go:171","msg":"trace[1451794097] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"132.22962ms","start":"2026-06-10T10:40:52.503442Z","end":"2026-06-10T10:40:52.635671Z","steps":["trace[1451794097] 'process raft request' (duration: 131.877923ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:52.635835Z","caller":"traceutil/trace.go:171","msg":"trace[601778605] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"132.198718ms","start":"2026-06-10T10:40:52.503625Z","end":"2026-06-10T10:40:52.635824Z","steps":["trace[601778605] 'process raft request' (duration: 131.957044ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:52.988211Z","caller":"traceutil/trace.go:171","msg":"trace[1777437927] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25920; }","duration":"271.998283ms","start":"2026-06-10T10:40:52.716172Z","end":"2026-06-10T10:40:52.98817Z","steps":["trace[1777437927] 'read index received' (duration: 271.772177ms)","trace[1777437927] 'applied index is now lower than readState.Index' (duration: 224.376µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:52.988263Z","caller":"traceutil/trace.go:171","msg":"trace[69734268] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"343.617617ms","start":"2026-06-10T10:40:52.644603Z","end":"2026-06-10T10:40:52.988221Z","steps":["trace[69734268] 'process raft request' (duration: 343.29641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:52.988369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.172195ms","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-06-10T10:40:52.988402Z","caller":"traceutil/trace.go:171","msg":"trace[1703149927] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25103; }","duration":"272.277998ms","start":"2026-06-10T10:40:52.716112Z","end":"2026-06-10T10:40:52.98839Z","steps":["trace[1703149927] 'agreement among raft nodes before linearized reading' (duration: 272.176046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:52.988582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:52.644567Z","time spent":"343.787ms","remote":"127.0.0.1:54786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:40:53.007967Z","caller":"traceutil/trace.go:171","msg":"trace[607125277] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"239.288498ms","start":"2026-06-10T10:40:52.768644Z","end":"2026-06-10T10:40:53.007932Z","steps":["trace[607125277] 'process raft request' (duration: 239.100904ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:59.276286Z","caller":"traceutil/trace.go:171","msg":"trace[200513397] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"116.5326ms","start":"2026-06-10T10:40:59.159725Z","end":"2026-06-10T10:40:59.276257Z","steps":["trace[200513397] 'process raft request' (duration: 116.355447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:59.530568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.513081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:40:59.530862Z","caller":"traceutil/trace.go:171","msg":"trace[229865018] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"283.258636ms","start":"2026-06-10T10:40:59.247556Z","end":"2026-06-10T10:40:59.530815Z","steps":["trace[229865018] 'process raft request' (duration: 147.398378ms)","trace[229865018] 'compare' (duration: 135.384369ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:59.684235Z","caller":"traceutil/trace.go:171","msg":"trace[1910157503] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"124.368299ms","start":"2026-06-10T10:40:59.559824Z","end":"2026-06-10T10:40:59.684192Z","steps":["trace[1910157503] 'process raft request' (duration: 123.006373ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:40:59.900606Z","caller":"traceutil/trace.go:171","msg":"trace[2089938925] linearizableReadLoop","detail":"{readStateIndex:26002; appliedIndex:25999; }","duration":"165.602196ms","start":"2026-06-10T10:40:59.734974Z","end":"2026-06-10T10:40:59.900576Z","steps":["trace[2089938925] 'read index received' (duration: 83.96542ms)","trace[2089938925] 'applied index is now lower than readState.Index' (duration: 81.635626ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:59.900623Z","caller":"traceutil/trace.go:171","msg":"trace[1926243232] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"340.120909ms","start":"2026-06-10T10:40:59.560458Z","end":"2026-06-10T10:40:59.900579Z","steps":["trace[1926243232] 'process raft request' (duration: 258.464803ms)","trace[1926243232] 'compare' (duration: 81.341589ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:40:59.900703Z","caller":"traceutil/trace.go:171","msg":"trace[2113291535] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"328.756593ms","start":"2026-06-10T10:40:59.57194Z","end":"2026-06-10T10:40:59.900697Z","steps":["trace[2113291535] 'process raft request' (duration: 328.536479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:59.900825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:59.56043Z","time spent":"340.286112ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:40:59.900844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:59.571933Z","time spent":"328.806384ms","remote":"127.0.0.1:54870","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-06-10T10:40:59.900921Z","caller":"traceutil/trace.go:171","msg":"trace[23209270] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"323.601245ms","start":"2026-06-10T10:40:59.577296Z","end":"2026-06-10T10:40:59.900897Z","steps":["trace[23209270] 'process raft request' (duration: 323.230198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:40:59.901065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:40:59.577279Z","time spent":"323.701446ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:40:59.90105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.041143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T10:40:59.901162Z","caller":"traceutil/trace.go:171","msg":"trace[1011510014] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25183; }","duration":"166.234197ms","start":"2026-06-10T10:40:59.734899Z","end":"2026-06-10T10:40:59.901133Z","steps":["trace[1011510014] 'agreement among raft nodes before linearized reading' (duration: 166.015023ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:03.804785Z","caller":"traceutil/trace.go:171","msg":"trace[346117686] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"104.376309ms","start":"2026-06-10T10:41:03.700375Z","end":"2026-06-10T10:41:03.804751Z","steps":["trace[346117686] 'process raft request' (duration: 104.193516ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:04.073047Z","caller":"traceutil/trace.go:171","msg":"trace[1572053954] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"160.273844ms","start":"2026-06-10T10:41:03.912737Z","end":"2026-06-10T10:41:04.073011Z","steps":["trace[1572053954] 'process raft request' (duration: 159.985978ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:04.100129Z","caller":"traceutil/trace.go:171","msg":"trace[257373942] transaction","detail":"{read_only:false; response_revision:25215; number_of_response:1; }","duration":"185.241819ms","start":"2026-06-10T10:41:03.914858Z","end":"2026-06-10T10:41:04.1001Z","steps":["trace[257373942] 'process raft request' (duration: 185.151917ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:04.100158Z","caller":"traceutil/trace.go:171","msg":"trace[395883955] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"186.293659ms","start":"2026-06-10T10:41:03.913827Z","end":"2026-06-10T10:41:04.100121Z","steps":["trace[395883955] 'process raft request' (duration: 186.032444ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:04.100335Z","caller":"traceutil/trace.go:171","msg":"trace[2039605978] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"183.701309ms","start":"2026-06-10T10:41:03.916607Z","end":"2026-06-10T10:41:04.100308Z","steps":["trace[2039605978] 'process raft request' (duration: 183.455705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:41:06.483818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.704507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:41:06.484113Z","caller":"traceutil/trace.go:171","msg":"trace[130076810] linearizableReadLoop","detail":"{readStateIndex:26055; appliedIndex:26053; }","duration":"110.420243ms","start":"2026-06-10T10:41:06.373673Z","end":"2026-06-10T10:41:06.484093Z","steps":["trace[130076810] 'read index received' (duration: 47.681µs)","trace[130076810] 'applied index is now lower than readState.Index' (duration: 110.371642ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:41:06.48424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.559266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:41:06.484277Z","caller":"traceutil/trace.go:171","msg":"trace[1453929555] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25234; }","duration":"110.600947ms","start":"2026-06-10T10:41:06.373666Z","end":"2026-06-10T10:41:06.484267Z","steps":["trace[1453929555] 'agreement among raft nodes before linearized reading' (duration: 110.499475ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:06.48453Z","caller":"traceutil/trace.go:171","msg":"trace[1777329552] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"230.98813ms","start":"2026-06-10T10:41:06.253527Z","end":"2026-06-10T10:41:06.484515Z","steps":["trace[1777329552] 'process raft request' (duration: 86.470378ms)","trace[1777329552] 'compare' (duration: 143.518623ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:41:06.484787Z","caller":"traceutil/trace.go:171","msg":"trace[1386782585] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"198.181025ms","start":"2026-06-10T10:41:06.286596Z","end":"2026-06-10T10:41:06.484777Z","steps":["trace[1386782585] 'process raft request' (duration: 197.37975ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:12.308333Z","caller":"traceutil/trace.go:171","msg":"trace[753918710] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"102.122766ms","start":"2026-06-10T10:41:12.206181Z","end":"2026-06-10T10:41:12.308303Z","steps":["trace[753918710] 'process raft request' (duration: 101.963423ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:12.342264Z","caller":"traceutil/trace.go:171","msg":"trace[810905246] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"133.657687ms","start":"2026-06-10T10:41:12.208575Z","end":"2026-06-10T10:41:12.342233Z","steps":["trace[810905246] 'process raft request' (duration: 133.396121ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:12.34246Z","caller":"traceutil/trace.go:171","msg":"trace[2083491520] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"133.503673ms","start":"2026-06-10T10:41:12.208929Z","end":"2026-06-10T10:41:12.342433Z","steps":["trace[2083491520] 'process raft request' (duration: 133.236269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:41:18.561383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.629902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:41:18.561574Z","caller":"traceutil/trace.go:171","msg":"trace[341209217] transaction","detail":"{read_only:false; response_revision:25334; number_of_response:1; }","duration":"198.034513ms","start":"2026-06-10T10:41:18.36352Z","end":"2026-06-10T10:41:18.561555Z","steps":["trace[341209217] 'process raft request' (duration: 197.979982ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:18.561709Z","caller":"traceutil/trace.go:171","msg":"trace[415510525] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"198.735766ms","start":"2026-06-10T10:41:18.362944Z","end":"2026-06-10T10:41:18.56168Z","steps":["trace[415510525] 'process raft request' (duration: 71.698646ms)","trace[415510525] 'compare' (duration: 126.466589ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:41:22.009878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.568544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"warn","ts":"2026-06-10T10:41:22.009899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.370793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:41:22.00996Z","caller":"traceutil/trace.go:171","msg":"trace[2098727221] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25357; }","duration":"312.713547ms","start":"2026-06-10T10:41:21.697224Z","end":"2026-06-10T10:41:22.009937Z","steps":["trace[2098727221] 'range keys from in-memory index tree' (duration: 312.30446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:41:22.009996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:41:21.697201Z","time spent":"312.786839ms","remote":"127.0.0.1:54692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1335,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-06-10T10:41:22.010023Z","caller":"traceutil/trace.go:171","msg":"trace[1557926741] linearizableReadLoop","detail":"{readStateIndex:26182; appliedIndex:26181; }","duration":"206.521845ms","start":"2026-06-10T10:41:21.803481Z","end":"2026-06-10T10:41:22.010003Z","steps":["trace[1557926741] 'read index received' (duration: 75.961757ms)","trace[1557926741] 'applied index is now lower than readState.Index' (duration: 130.558838ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:41:22.010298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.81842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-10T10:41:22.010333Z","caller":"traceutil/trace.go:171","msg":"trace[351246459] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25358; }","duration":"206.904142ms","start":"2026-06-10T10:41:21.803416Z","end":"2026-06-10T10:41:22.010321Z","steps":["trace[351246459] 'agreement among raft nodes before linearized reading' (duration: 206.638147ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:22.01051Z","caller":"traceutil/trace.go:171","msg":"trace[1243458816] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"208.734197ms","start":"2026-06-10T10:41:21.801736Z","end":"2026-06-10T10:41:22.01047Z","steps":["trace[1243458816] 'process raft request' (duration: 77.69579ms)","trace[1243458816] 'compare' (duration: 130.235441ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:41:22.095841Z","caller":"traceutil/trace.go:171","msg":"trace[1263600292] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"211.197423ms","start":"2026-06-10T10:41:21.884604Z","end":"2026-06-10T10:41:22.095802Z","steps":["trace[1263600292] 'process raft request' (duration: 210.929548ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:38.726175Z","caller":"traceutil/trace.go:171","msg":"trace[1538603309] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"101.515743ms","start":"2026-06-10T10:41:38.624633Z","end":"2026-06-10T10:41:38.726149Z","steps":["trace[1538603309] 'process raft request' (duration: 101.34398ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:38.845054Z","caller":"traceutil/trace.go:171","msg":"trace[637353758] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"161.987685ms","start":"2026-06-10T10:41:38.683041Z","end":"2026-06-10T10:41:38.845028Z","steps":["trace[637353758] 'process raft request' (duration: 161.924214ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:38.845353Z","caller":"traceutil/trace.go:171","msg":"trace[452520166] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"215.699939ms","start":"2026-06-10T10:41:38.629617Z","end":"2026-06-10T10:41:38.845317Z","steps":["trace[452520166] 'process raft request' (duration: 215.173838ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:39.0202Z","caller":"traceutil/trace.go:171","msg":"trace[1135541662] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"100.088177ms","start":"2026-06-10T10:41:38.920083Z","end":"2026-06-10T10:41:39.020171Z","steps":["trace[1135541662] 'process raft request' (duration: 64.612031ms)","trace[1135541662] 'compare' (duration: 35.383364ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:41:39.099005Z","caller":"traceutil/trace.go:171","msg":"trace[688391280] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"176.802009ms","start":"2026-06-10T10:41:38.922164Z","end":"2026-06-10T10:41:39.098966Z","steps":["trace[688391280] 'process raft request' (duration: 176.759338ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:39.099175Z","caller":"traceutil/trace.go:171","msg":"trace[332671899] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"178.558102ms","start":"2026-06-10T10:41:38.920504Z","end":"2026-06-10T10:41:39.099063Z","steps":["trace[332671899] 'process raft request' (duration: 178.135894ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:39.099302Z","caller":"traceutil/trace.go:171","msg":"trace[1415761472] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"178.225895ms","start":"2026-06-10T10:41:38.921062Z","end":"2026-06-10T10:41:39.099288Z","steps":["trace[1415761472] 'process raft request' (duration: 177.819578ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:39.099007Z","caller":"traceutil/trace.go:171","msg":"trace[2667837] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"177.984211ms","start":"2026-06-10T10:41:38.921001Z","end":"2026-06-10T10:41:39.098985Z","steps":["trace[2667837] 'process raft request' (duration: 177.834368ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:48.958386Z","caller":"traceutil/trace.go:171","msg":"trace[263282599] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"194.609097ms","start":"2026-06-10T10:41:48.763755Z","end":"2026-06-10T10:41:48.958364Z","steps":["trace[263282599] 'process raft request' (duration: 194.451463ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:49.132278Z","caller":"traceutil/trace.go:171","msg":"trace[973996822] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"256.122909ms","start":"2026-06-10T10:41:48.87609Z","end":"2026-06-10T10:41:49.132213Z","steps":["trace[973996822] 'process raft request' (duration: 175.306719ms)","trace[973996822] 'compare' (duration: 80.33785ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:41:49.133157Z","caller":"traceutil/trace.go:171","msg":"trace[1140601079] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"255.473397ms","start":"2026-06-10T10:41:48.877597Z","end":"2026-06-10T10:41:49.13307Z","steps":["trace[1140601079] 'process raft request' (duration: 255.173331ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:49.133432Z","caller":"traceutil/trace.go:171","msg":"trace[76601286] transaction","detail":"{read_only:false; response_revision:25568; number_of_response:1; }","duration":"186.30469ms","start":"2026-06-10T10:41:48.947117Z","end":"2026-06-10T10:41:49.133422Z","steps":["trace[76601286] 'process raft request' (duration: 186.155396ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:49.133528Z","caller":"traceutil/trace.go:171","msg":"trace[910591347] linearizableReadLoop","detail":"{readStateIndex:26397; appliedIndex:26393; }","duration":"175.680008ms","start":"2026-06-10T10:41:48.957819Z","end":"2026-06-10T10:41:49.133499Z","steps":["trace[910591347] 'read index received' (duration: 465.749µs)","trace[910591347] 'applied index is now lower than readState.Index' (duration: 175.213209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:41:49.133732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.867401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T10:41:49.13379Z","caller":"traceutil/trace.go:171","msg":"trace[1284839736] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25568; }","duration":"176.001504ms","start":"2026-06-10T10:41:48.957771Z","end":"2026-06-10T10:41:49.133773Z","steps":["trace[1284839736] 'agreement among raft nodes before linearized reading' (duration: 175.841561ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:49.452796Z","caller":"traceutil/trace.go:171","msg":"trace[720308707] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"251.585403ms","start":"2026-06-10T10:41:49.201181Z","end":"2026-06-10T10:41:49.452766Z","steps":["trace[720308707] 'process raft request' (duration: 251.39815ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:49.488555Z","caller":"traceutil/trace.go:171","msg":"trace[1393786617] linearizableReadLoop","detail":"{readStateIndex:26402; appliedIndex:26398; }","duration":"272.231315ms","start":"2026-06-10T10:41:49.216256Z","end":"2026-06-10T10:41:49.488488Z","steps":["trace[1393786617] 'read index received' (duration: 236.376513ms)","trace[1393786617] 'applied index is now lower than readState.Index' (duration: 35.853652ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:41:49.488604Z","caller":"traceutil/trace.go:171","msg":"trace[805364855] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"286.813014ms","start":"2026-06-10T10:41:49.201744Z","end":"2026-06-10T10:41:49.488557Z","steps":["trace[805364855] 'process raft request' (duration: 286.477167ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:49.488636Z","caller":"traceutil/trace.go:171","msg":"trace[1902039755] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"286.810674ms","start":"2026-06-10T10:41:49.201788Z","end":"2026-06-10T10:41:49.488599Z","steps":["trace[1902039755] 'process raft request' (duration: 286.57416ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:41:49.488701Z","caller":"traceutil/trace.go:171","msg":"trace[492931255] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"286.854205ms","start":"2026-06-10T10:41:49.201815Z","end":"2026-06-10T10:41:49.488669Z","steps":["trace[492931255] 'process raft request' (duration: 286.62026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:41:49.488772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.48147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T10:41:49.488818Z","caller":"traceutil/trace.go:171","msg":"trace[1645027521] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25573; }","duration":"272.582112ms","start":"2026-06-10T10:41:49.216222Z","end":"2026-06-10T10:41:49.488804Z","steps":["trace[1645027521] 'agreement among raft nodes before linearized reading' (duration: 272.45347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:42:13.441177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.448757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T10:42:13.441394Z","caller":"traceutil/trace.go:171","msg":"trace[346518527] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"194.871971ms","start":"2026-06-10T10:42:13.246498Z","end":"2026-06-10T10:42:13.441369Z","steps":["trace[346518527] 'process raft request' (duration: 77.066197ms)","trace[346518527] 'compare' (duration: 117.143861ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:42:13.494265Z","caller":"traceutil/trace.go:171","msg":"trace[1891943127] linearizableReadLoop","detail":"{readStateIndex:26720; appliedIndex:26719; }","duration":"162.684449ms","start":"2026-06-10T10:42:13.33156Z","end":"2026-06-10T10:42:13.494245Z","steps":["trace[1891943127] 'read index received' (duration: 162.588567ms)","trace[1891943127] 'applied index is now lower than readState.Index' (duration: 94.692µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:42:13.494538Z","caller":"traceutil/trace.go:171","msg":"trace[2046813529] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"212.924246ms","start":"2026-06-10T10:42:13.281601Z","end":"2026-06-10T10:42:13.494525Z","steps":["trace[2046813529] 'process raft request' (duration: 212.552488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:42:13.495611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.033655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-10T10:42:13.495659Z","caller":"traceutil/trace.go:171","msg":"trace[1685365340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25884; }","duration":"164.123877ms","start":"2026-06-10T10:42:13.331524Z","end":"2026-06-10T10:42:13.495648Z","steps":["trace[1685365340] 'agreement among raft nodes before linearized reading' (duration: 163.161559ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:42:32.782592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23072} {"level":"info","ts":"2026-06-10T10:42:32.91963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23072,"took":"132.685757ms","hash":3405618076,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":35377152,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-10T10:42:32.919713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3405618076,"revision":23072,"compact-revision":20127} {"level":"info","ts":"2026-06-10T10:43:05.026733Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a20.wal"} {"level":"info","ts":"2026-06-10T10:47:32.788391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26061} {"level":"info","ts":"2026-06-10T10:47:32.939531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26061,"took":"146.12925ms","hash":1037237040,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":34492416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T10:47:32.939612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1037237040,"revision":26061,"compact-revision":23072} {"level":"info","ts":"2026-06-10T10:48:16.745235Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T10:48:16.752263Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-10T10:48:16.752561Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-10T10:52:32.793973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28755} {"level":"info","ts":"2026-06-10T10:52:32.937988Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28755,"took":"140.098796ms","hash":682239292,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":31047680,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-10T10:52:32.938049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":682239292,"revision":28755,"compact-revision":26061} {"level":"info","ts":"2026-06-10T10:57:32.802211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30976} {"level":"info","ts":"2026-06-10T10:57:32.91574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30976,"took":"110.629307ms","hash":3773139090,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":32280576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T10:57:32.915807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3773139090,"revision":30976,"compact-revision":28755} {"level":"info","ts":"2026-06-10T11:02:32.814999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33246} {"level":"info","ts":"2026-06-10T11:02:32.966691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33246,"took":"147.768795ms","hash":3088789047,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":32010240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T11:02:32.966768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3088789047,"revision":33246,"compact-revision":30976} {"level":"info","ts":"2026-06-10T11:07:32.82849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35606} {"level":"info","ts":"2026-06-10T11:07:32.982979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35606,"took":"149.956396ms","hash":121078069,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":32141312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T11:07:32.983056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":121078069,"revision":35606,"compact-revision":33246} {"level":"info","ts":"2026-06-10T11:09:34.447009Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T11:09:34.454689Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-10T11:09:34.45482Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-10T11:11:04.128713Z","caller":"traceutil/trace.go:171","msg":"trace[1461900672] transaction","detail":"{read_only:false; response_revision:39505; number_of_response:1; }","duration":"119.784121ms","start":"2026-06-10T11:11:04.008902Z","end":"2026-06-10T11:11:04.128686Z","steps":["trace[1461900672] 'process raft request' (duration: 115.785885ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T11:12:32.838234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37867} {"level":"info","ts":"2026-06-10T11:12:32.963862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37867,"took":"120.955315ms","hash":2546903104,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":31973376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T11:12:32.963916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2546903104,"revision":37867,"compact-revision":35606}