{"level":"warn","ts":"2026-06-16T00:34:29.041518Z","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-16T00:34:29.041621Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.21: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.19.213.21:2380","--initial-cluster=instance=https://199.19.213.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.21: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-16T00:34:29.041685Z","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-16T00:34:29.041698Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-06-16T00:34:29.041716Z","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-16T00:34:29.042357Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"]} {"level":"info","ts":"2026-06-16T00:34:29.04243Z","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.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.21: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-16T00:34:29.045806Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.169501ms"} {"level":"info","ts":"2026-06-16T00:34:29.051146Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c39f03fd9ad57fdc","cluster-id":"3c95a04d00bbaaa4"} {"level":"info","ts":"2026-06-16T00:34:29.051221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T00:34:29.051244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 0"} {"level":"info","ts":"2026-06-16T00:34:29.051261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c39f03fd9ad57fdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T00:34:29.05127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 1"} {"level":"info","ts":"2026-06-16T00:34:29.051318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"warn","ts":"2026-06-16T00:34:29.057375Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T00:34:29.05883Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T00:34:29.059939Z","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-16T00:34:29.06272Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c39f03fd9ad57fdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T00:34:29.062884Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c39f03fd9ad57fdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T00:34:29.063028Z","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-16T00:34:29.063102Z","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-16T00:34:29.063128Z","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-16T00:34:29.066741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"info","ts":"2026-06-16T00:34:29.066926Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","added-peer-id":"c39f03fd9ad57fdc","added-peer-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-06-16T00:34:29.068248Z","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-16T00:34:29.06851Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c39f03fd9ad57fdc","initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T00:34:29.068607Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T00:34:29.068557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-06-16T00:34:29.068849Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-06-16T00:34:29.852062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T00:34:29.852148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T00:34:29.852174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgPreVoteResp from c39f03fd9ad57fdc at term 1"} {"level":"info","ts":"2026-06-16T00:34:29.852194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became candidate at term 2"} {"level":"info","ts":"2026-06-16T00:34:29.852206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgVoteResp from c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-06-16T00:34:29.852224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became leader at term 2"} {"level":"info","ts":"2026-06-16T00:34:29.852249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c39f03fd9ad57fdc elected leader c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-06-16T00:34:29.853392Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c39f03fd9ad57fdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.21:2379]}","request-path":"/0/members/c39f03fd9ad57fdc/attributes","cluster-id":"3c95a04d00bbaaa4","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T00:34:29.853418Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:34:29.853406Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:34:29.853568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:34:29.853809Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T00:34:29.853867Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T00:34:29.855147Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T00:34:29.85552Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.21:2379"} {"level":"info","ts":"2026-06-16T00:34:29.859159Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:34:29.859337Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:34:29.859406Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:34:45.017166Z","caller":"traceutil/trace.go:171","msg":"trace[1444447364] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"257.866602ms","start":"2026-06-16T00:34:44.759272Z","end":"2026-06-16T00:34:45.017138Z","steps":["trace[1444447364] 'process raft request' (duration: 257.571135ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:55.525913Z","caller":"traceutil/trace.go:171","msg":"trace[32848173] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"139.095463ms","start":"2026-06-16T00:34:55.38679Z","end":"2026-06-16T00:34:55.525885Z","steps":["trace[32848173] 'process raft request' (duration: 138.884559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:35:12.04627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.783034ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:35:12.046415Z","caller":"traceutil/trace.go:171","msg":"trace[387198486] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:630; }","duration":"113.940197ms","start":"2026-06-16T00:35:11.932455Z","end":"2026-06-16T00:35:12.046396Z","steps":["trace[387198486] 'range keys from in-memory index tree' (duration: 113.769444ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:12.074267Z","caller":"traceutil/trace.go:171","msg":"trace[1364851979] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"189.466965ms","start":"2026-06-16T00:35:11.884782Z","end":"2026-06-16T00:35:12.074249Z","steps":["trace[1364851979] 'process raft request' (duration: 189.307823ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:12.312789Z","caller":"traceutil/trace.go:171","msg":"trace[616550278] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"353.123893ms","start":"2026-06-16T00:35:11.959626Z","end":"2026-06-16T00:35:12.31275Z","steps":["trace[616550278] 'read index received' (duration: 114.491358ms)","trace[616550278] 'applied index is now lower than readState.Index' (duration: 238.630425ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:35:12.312862Z","caller":"traceutil/trace.go:171","msg":"trace[2000393895] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"382.145399ms","start":"2026-06-16T00:35:11.930661Z","end":"2026-06-16T00:35:12.312806Z","steps":["trace[2000393895] 'process raft request' (duration: 336.24367ms)","trace[2000393895] 'compare' (duration: 45.537262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:35:12.313106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.44565ms","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-16T00:35:12.313171Z","caller":"traceutil/trace.go:171","msg":"trace[1506046911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"353.536981ms","start":"2026-06-16T00:35:11.959601Z","end":"2026-06-16T00:35:12.313138Z","steps":["trace[1506046911] 'agreement among raft nodes before linearized reading' (duration: 353.313727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:35:12.313384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:35:11.959589Z","time spent":"353.779645ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T00:35:12.313632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:35:11.930613Z","time spent":"382.325863ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:35:12.371928Z","caller":"traceutil/trace.go:171","msg":"trace[1242409092] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"155.284472ms","start":"2026-06-16T00:35:12.216105Z","end":"2026-06-16T00:35:12.37139Z","steps":["trace[1242409092] 'process raft request' (duration: 150.134218ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:12.521154Z","caller":"traceutil/trace.go:171","msg":"trace[335460688] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"203.401322ms","start":"2026-06-16T00:35:12.317721Z","end":"2026-06-16T00:35:12.521122Z","steps":["trace[335460688] 'process raft request' (duration: 135.781972ms)","trace[335460688] 'compare' (duration: 67.388416ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:35:28.256218Z","caller":"traceutil/trace.go:171","msg":"trace[1772990452] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"282.490516ms","start":"2026-06-16T00:35:27.973702Z","end":"2026-06-16T00:35:28.256193Z","steps":["trace[1772990452] 'process raft request' (duration: 282.292163ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:43.638072Z","caller":"traceutil/trace.go:171","msg":"trace[1806673644] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"308.697077ms","start":"2026-06-16T00:36:43.329356Z","end":"2026-06-16T00:36:43.638053Z","steps":["trace[1806673644] 'read index received' (duration: 308.588735ms)","trace[1806673644] 'applied index is now lower than readState.Index' (duration: 107.452µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:36:43.638121Z","caller":"traceutil/trace.go:171","msg":"trace[2113611553] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"326.759069ms","start":"2026-06-16T00:36:43.311328Z","end":"2026-06-16T00:36:43.638087Z","steps":["trace[2113611553] 'process raft request' (duration: 326.598266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:43.638278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:36:43.311312Z","time spent":"326.872781ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:36:43.638355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.965743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-06-16T00:36:43.638444Z","caller":"traceutil/trace.go:171","msg":"trace[1407725913] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1867; }","duration":"309.116875ms","start":"2026-06-16T00:36:43.329308Z","end":"2026-06-16T00:36:43.638425Z","steps":["trace[1407725913] 'agreement among raft nodes before linearized reading' (duration: 308.83584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:43.638502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:36:43.329288Z","time spent":"309.205917ms","remote":"127.0.0.1:51226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10690,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"info","ts":"2026-06-16T00:36:43.681056Z","caller":"traceutil/trace.go:171","msg":"trace[320804056] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"123.146129ms","start":"2026-06-16T00:36:43.557884Z","end":"2026-06-16T00:36:43.68103Z","steps":["trace[320804056] 'process raft request' (duration: 123.010927ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:44.150773Z","caller":"traceutil/trace.go:171","msg":"trace[763810360] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"214.326759ms","start":"2026-06-16T00:36:43.936411Z","end":"2026-06-16T00:36:44.150738Z","steps":["trace[763810360] 'process raft request' (duration: 213.964902ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:56.326557Z","caller":"traceutil/trace.go:171","msg":"trace[336227700] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"115.106669ms","start":"2026-06-16T00:36:56.211425Z","end":"2026-06-16T00:36:56.326532Z","steps":["trace[336227700] 'process raft request' (duration: 88.188581ms)","trace[336227700] 'compare' (duration: 26.653603ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:36:58.860048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.173208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T00:36:58.86017Z","caller":"traceutil/trace.go:171","msg":"trace[2014070023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1997; }","duration":"135.356741ms","start":"2026-06-16T00:36:58.724789Z","end":"2026-06-16T00:36:58.860146Z","steps":["trace[2014070023] 'range keys from in-memory index tree' (duration: 134.931533ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:37:32.432204Z","caller":"traceutil/trace.go:171","msg":"trace[2021935706] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"100.969944ms","start":"2026-06-16T00:37:32.331207Z","end":"2026-06-16T00:37:32.432177Z","steps":["trace[2021935706] 'process raft request' (duration: 56.979451ms)","trace[2021935706] 'compare' (duration: 43.777359ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:37:38.790995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.613669ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-16T00:37:38.791293Z","caller":"traceutil/trace.go:171","msg":"trace[614451834] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2299; }","duration":"312.31563ms","start":"2026-06-16T00:37:38.478957Z","end":"2026-06-16T00:37:38.791273Z","steps":["trace[614451834] 'read index received' (duration: 67.270035ms)","trace[614451834] 'applied index is now lower than readState.Index' (duration: 245.044295ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:37:38.79139Z","caller":"traceutil/trace.go:171","msg":"trace[1631735807] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"340.283271ms","start":"2026-06-16T00:37:38.451079Z","end":"2026-06-16T00:37:38.791362Z","steps":["trace[1631735807] 'process raft request' (duration: 340.041697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:37:38.791522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.553344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-16T00:37:38.791564Z","caller":"traceutil/trace.go:171","msg":"trace[842687347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2219; }","duration":"312.644236ms","start":"2026-06-16T00:37:38.47891Z","end":"2026-06-16T00:37:38.791554Z","steps":["trace[842687347] 'agreement among raft nodes before linearized reading' (duration: 312.446423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:37:38.791578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:37:38.451038Z","time spent":"340.442554ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:37:38.791601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:37:38.478889Z","time spent":"312.703958ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T00:37:38.792774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.283438ms","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-16T00:37:38.792835Z","caller":"traceutil/trace.go:171","msg":"trace[365164262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2220; }","duration":"256.37051ms","start":"2026-06-16T00:37:38.536447Z","end":"2026-06-16T00:37:38.792818Z","steps":["trace[365164262] 'agreement among raft nodes before linearized reading' (duration: 256.190327ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:37:38.793003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.084697ms","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-16T00:37:38.793063Z","caller":"traceutil/trace.go:171","msg":"trace[632483651] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2220; }","duration":"138.20546ms","start":"2026-06-16T00:37:38.65484Z","end":"2026-06-16T00:37:38.793046Z","steps":["trace[632483651] 'agreement among raft nodes before linearized reading' (duration: 138.030616ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:38:47.230196Z","caller":"traceutil/trace.go:171","msg":"trace[1019490522] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"129.229376ms","start":"2026-06-16T00:38:47.100917Z","end":"2026-06-16T00:38:47.230146Z","steps":["trace[1019490522] 'process raft request' (duration: 112.908937ms)","trace[1019490522] 'compare' (duration: 15.379262ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:41:13.414384Z","caller":"traceutil/trace.go:171","msg":"trace[863455802] linearizableReadLoop","detail":"{readStateIndex:3897; appliedIndex:3896; }","duration":"132.832169ms","start":"2026-06-16T00:41:13.281515Z","end":"2026-06-16T00:41:13.414347Z","steps":["trace[863455802] 'read index received' (duration: 132.486983ms)","trace[863455802] 'applied index is now lower than readState.Index' (duration: 343.386µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:41:13.414441Z","caller":"traceutil/trace.go:171","msg":"trace[1555390116] transaction","detail":"{read_only:false; response_revision:3760; number_of_response:1; }","duration":"262.225432ms","start":"2026-06-16T00:41:13.152176Z","end":"2026-06-16T00:41:13.414401Z","steps":["trace[1555390116] 'process raft request' (duration: 261.950457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:41:13.414594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.058083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:41:13.414643Z","caller":"traceutil/trace.go:171","msg":"trace[447899468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3760; }","duration":"133.159176ms","start":"2026-06-16T00:41:13.281472Z","end":"2026-06-16T00:41:13.414631Z","steps":["trace[447899468] 'agreement among raft nodes before linearized reading' (duration: 133.056944ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:34.749635Z","caller":"traceutil/trace.go:171","msg":"trace[1859318949] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"129.796475ms","start":"2026-06-16T00:42:34.619809Z","end":"2026-06-16T00:42:34.749606Z","steps":["trace[1859318949] 'process raft request' (duration: 129.552461ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:34.910225Z","caller":"traceutil/trace.go:171","msg":"trace[937535648] linearizableReadLoop","detail":"{readStateIndex:5127; appliedIndex:5126; }","duration":"112.782241ms","start":"2026-06-16T00:42:34.797415Z","end":"2026-06-16T00:42:34.910197Z","steps":["trace[937535648] 'read index received' (duration: 112.662318ms)","trace[937535648] 'applied index is now lower than readState.Index' (duration: 118.703µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:42:34.910292Z","caller":"traceutil/trace.go:171","msg":"trace[620666778] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"153.149481ms","start":"2026-06-16T00:42:34.757116Z","end":"2026-06-16T00:42:34.910265Z","steps":["trace[620666778] 'process raft request' (duration: 152.897065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:42:34.910534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.100687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a94db1ef-1cd6-4f4d-a25f-4ad8c47d5274\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-06-16T00:42:34.910581Z","caller":"traceutil/trace.go:171","msg":"trace[1009006745] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a94db1ef-1cd6-4f4d-a25f-4ad8c47d5274; range_end:; response_count:1; response_revision:4944; }","duration":"113.207949ms","start":"2026-06-16T00:42:34.797361Z","end":"2026-06-16T00:42:34.910569Z","steps":["trace[1009006745] 'agreement among raft nodes before linearized reading' (duration: 112.981785ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:34.923997Z","caller":"traceutil/trace.go:171","msg":"trace[80551970] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"112.716088ms","start":"2026-06-16T00:42:34.811234Z","end":"2026-06-16T00:42:34.92395Z","steps":["trace[80551970] 'process raft request' (duration: 112.551595ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:34.924113Z","caller":"traceutil/trace.go:171","msg":"trace[1607895501] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"110.591488ms","start":"2026-06-16T00:42:34.813502Z","end":"2026-06-16T00:42:34.924093Z","steps":["trace[1607895501] 'process raft request' (duration: 110.393054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:42:37.362663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.311525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:42:37.36292Z","caller":"traceutil/trace.go:171","msg":"trace[571938942] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"169.683455ms","start":"2026-06-16T00:42:37.193217Z","end":"2026-06-16T00:42:37.362901Z","steps":["trace[571938942] 'process raft request' (duration: 169.643474ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:37.362898Z","caller":"traceutil/trace.go:171","msg":"trace[418682622] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"170.409088ms","start":"2026-06-16T00:42:37.192429Z","end":"2026-06-16T00:42:37.362838Z","steps":["trace[418682622] 'process raft request' (duration: 40.829258ms)","trace[418682622] 'compare' (duration: 129.187393ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:42:37.362938Z","caller":"traceutil/trace.go:171","msg":"trace[937860972] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"169.903819ms","start":"2026-06-16T00:42:37.193022Z","end":"2026-06-16T00:42:37.362925Z","steps":["trace[937860972] 'process raft request' (duration: 169.744856ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:37.363025Z","caller":"traceutil/trace.go:171","msg":"trace[1126813321] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"169.94453ms","start":"2026-06-16T00:42:37.193045Z","end":"2026-06-16T00:42:37.36299Z","steps":["trace[1126813321] 'read index received' (duration: 40.221137ms)","trace[1126813321] 'applied index is now lower than readState.Index' (duration: 129.719333ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:42:37.363261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.208486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62971"} {"level":"info","ts":"2026-06-16T00:42:37.36329Z","caller":"traceutil/trace.go:171","msg":"trace[1495472516] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5014; }","duration":"170.254616ms","start":"2026-06-16T00:42:37.193029Z","end":"2026-06-16T00:42:37.363283Z","steps":["trace[1495472516] 'agreement among raft nodes before linearized reading' (duration: 170.093672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:42:37.363838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.783611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:26 size:797073"} {"level":"info","ts":"2026-06-16T00:42:37.363904Z","caller":"traceutil/trace.go:171","msg":"trace[594455423] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:26; response_revision:5014; }","duration":"132.847883ms","start":"2026-06-16T00:42:37.231028Z","end":"2026-06-16T00:42:37.363876Z","steps":["trace[594455423] 'agreement among raft nodes before linearized reading' (duration: 132.379085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:42:37.364082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.210397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:1 size:799"} {"level":"info","ts":"2026-06-16T00:42:37.364525Z","caller":"traceutil/trace.go:171","msg":"trace[132078735] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:1; response_revision:5014; }","duration":"167.663256ms","start":"2026-06-16T00:42:37.196843Z","end":"2026-06-16T00:42:37.364507Z","steps":["trace[132078735] 'agreement among raft nodes before linearized reading' (duration: 166.378982ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:37.363022Z","caller":"traceutil/trace.go:171","msg":"trace[1280185010] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"169.931279ms","start":"2026-06-16T00:42:37.193071Z","end":"2026-06-16T00:42:37.363003Z","steps":["trace[1280185010] 'process raft request' (duration: 169.751466ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:48.094703Z","caller":"traceutil/trace.go:171","msg":"trace[172530375] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"246.51369ms","start":"2026-06-16T00:42:47.84816Z","end":"2026-06-16T00:42:48.094673Z","steps":["trace[172530375] 'process raft request' (duration: 244.755527ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:48.122391Z","caller":"traceutil/trace.go:171","msg":"trace[946896609] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"251.71993ms","start":"2026-06-16T00:42:47.870641Z","end":"2026-06-16T00:42:48.122361Z","steps":["trace[946896609] 'process raft request' (duration: 251.669999ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:48.122469Z","caller":"traceutil/trace.go:171","msg":"trace[166573778] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"270.893076ms","start":"2026-06-16T00:42:47.851544Z","end":"2026-06-16T00:42:48.122437Z","steps":["trace[166573778] 'process raft request' (duration: 270.647212ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:55.562341Z","caller":"traceutil/trace.go:171","msg":"trace[1622566508] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"174.497948ms","start":"2026-06-16T00:42:55.387816Z","end":"2026-06-16T00:42:55.562314Z","steps":["trace[1622566508] 'process raft request' (duration: 174.269894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:42:55.75286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.1142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:42:55.752996Z","caller":"traceutil/trace.go:171","msg":"trace[1638466707] linearizableReadLoop","detail":"{readStateIndex:5573; appliedIndex:5572; }","duration":"190.737157ms","start":"2026-06-16T00:42:55.562239Z","end":"2026-06-16T00:42:55.752976Z","steps":["trace[1638466707] 'read index received' (duration: 26.348271ms)","trace[1638466707] 'applied index is now lower than readState.Index' (duration: 164.387446ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:42:55.753202Z","caller":"traceutil/trace.go:171","msg":"trace[531964269] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"235.98221ms","start":"2026-06-16T00:42:55.517205Z","end":"2026-06-16T00:42:55.753187Z","steps":["trace[531964269] 'process raft request' (duration: 71.430622ms)","trace[531964269] 'compare' (duration: 164.004998ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:42:55.753487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.664073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b575n\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-06-16T00:42:55.753564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.829601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5834"} {"level":"info","ts":"2026-06-16T00:42:55.753628Z","caller":"traceutil/trace.go:171","msg":"trace[1566188433] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b575n; range_end:; response_count:1; response_revision:5378; }","duration":"247.812325ms","start":"2026-06-16T00:42:55.505786Z","end":"2026-06-16T00:42:55.753599Z","steps":["trace[1566188433] 'agreement among raft nodes before linearized reading' (duration: 247.54358ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:55.753642Z","caller":"traceutil/trace.go:171","msg":"trace[101631627] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5378; }","duration":"187.999595ms","start":"2026-06-16T00:42:55.565616Z","end":"2026-06-16T00:42:55.753615Z","steps":["trace[101631627] 'agreement among raft nodes before linearized reading' (duration: 187.69362ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:42:55.898257Z","caller":"traceutil/trace.go:171","msg":"trace[117081152] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"134.471254ms","start":"2026-06-16T00:42:55.763637Z","end":"2026-06-16T00:42:55.898109Z","steps":["trace[117081152] 'process raft request' (duration: 99.236103ms)","trace[117081152] 'compare' (duration: 34.62076ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:44:23.25113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.174456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T00:44:23.25122Z","caller":"traceutil/trace.go:171","msg":"trace[1762392381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6139; }","duration":"111.330869ms","start":"2026-06-16T00:44:23.13987Z","end":"2026-06-16T00:44:23.251201Z","steps":["trace[1762392381] 'range keys from in-memory index tree' (duration: 110.91922ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:44:29.87838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3023} {"level":"info","ts":"2026-06-16T00:44:30.027625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3023,"took":"147.904494ms","hash":2419402553,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":32288768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T00:44:30.027698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2419402553,"revision":3023,"compact-revision":-1} {"level":"info","ts":"2026-06-16T00:44:31.22063Z","caller":"traceutil/trace.go:171","msg":"trace[1586620403] transaction","detail":"{read_only:false; response_revision:6181; number_of_response:1; }","duration":"148.398634ms","start":"2026-06-16T00:44:31.072205Z","end":"2026-06-16T00:44:31.220603Z","steps":["trace[1586620403] 'process raft request' (duration: 148.23262ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:44:31.531532Z","caller":"traceutil/trace.go:171","msg":"trace[1450016889] transaction","detail":"{read_only:false; response_revision:6185; number_of_response:1; }","duration":"100.764137ms","start":"2026-06-16T00:44:31.430705Z","end":"2026-06-16T00:44:31.531469Z","steps":["trace[1450016889] 'process raft request' (duration: 74.63617ms)","trace[1450016889] 'compare' (duration: 25.741749ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:48:57.596979Z","caller":"traceutil/trace.go:171","msg":"trace[135114090] transaction","detail":"{read_only:false; response_revision:8168; number_of_response:1; }","duration":"180.630135ms","start":"2026-06-16T00:48:57.416325Z","end":"2026-06-16T00:48:57.596955Z","steps":["trace[135114090] 'process raft request' (duration: 180.481602ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:48:57.761475Z","caller":"traceutil/trace.go:171","msg":"trace[791707548] linearizableReadLoop","detail":"{readStateIndex:8452; appliedIndex:8451; }","duration":"101.40228ms","start":"2026-06-16T00:48:57.660048Z","end":"2026-06-16T00:48:57.761451Z","steps":["trace[791707548] 'read index received' (duration: 85.547136ms)","trace[791707548] 'applied index is now lower than readState.Index' (duration: 15.854034ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:48:57.761571Z","caller":"traceutil/trace.go:171","msg":"trace[1218126436] transaction","detail":"{read_only:false; response_revision:8169; number_of_response:1; }","duration":"332.335877ms","start":"2026-06-16T00:48:57.429173Z","end":"2026-06-16T00:48:57.761509Z","steps":["trace[1218126436] 'process raft request' (duration: 316.501353ms)","trace[1218126436] 'compare' (duration: 15.661571ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:48:57.761689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.622445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-16T00:48:57.761724Z","caller":"traceutil/trace.go:171","msg":"trace[564628323] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8169; }","duration":"101.693606ms","start":"2026-06-16T00:48:57.66002Z","end":"2026-06-16T00:48:57.761713Z","steps":["trace[564628323] 'agreement among raft nodes before linearized reading' (duration: 101.551313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:48:57.761716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:48:57.429159Z","time spent":"332.47959ms","remote":"127.0.0.1:51314","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-16T00:49:10.993187Z","caller":"traceutil/trace.go:171","msg":"trace[756542536] transaction","detail":"{read_only:false; response_revision:8237; number_of_response:1; }","duration":"187.348414ms","start":"2026-06-16T00:49:10.805812Z","end":"2026-06-16T00:49:10.993161Z","steps":["trace[756542536] 'process raft request' (duration: 187.13693ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:49:29.884938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6173} {"level":"info","ts":"2026-06-16T00:49:29.982681Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6173,"took":"95.680301ms","hash":2518506678,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":23228416,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-16T00:49:29.982835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2518506678,"revision":6173,"compact-revision":3023} {"level":"warn","ts":"2026-06-16T00:49:38.301129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.827688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:49:38.301571Z","caller":"traceutil/trace.go:171","msg":"trace[523828416] transaction","detail":"{read_only:false; response_revision:8438; number_of_response:1; }","duration":"376.488381ms","start":"2026-06-16T00:49:37.925003Z","end":"2026-06-16T00:49:38.301491Z","steps":["trace[523828416] 'process raft request' (duration: 171.208685ms)","trace[523828416] 'compare' (duration: 204.632774ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:49:38.301591Z","caller":"traceutil/trace.go:171","msg":"trace[825921951] linearizableReadLoop","detail":"{readStateIndex:8733; appliedIndex:8732; }","duration":"185.417486ms","start":"2026-06-16T00:49:38.116111Z","end":"2026-06-16T00:49:38.301528Z","steps":["trace[825921951] 'read index received' (duration: 39.991µs)","trace[825921951] 'applied index is now lower than readState.Index' (duration: 185.373665ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:49:38.301838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:49:37.924985Z","time spent":"376.713125ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:49:38.303716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.600528ms","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-16T00:49:38.303769Z","caller":"traceutil/trace.go:171","msg":"trace[1390154812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8438; }","duration":"187.70077ms","start":"2026-06-16T00:49:38.116054Z","end":"2026-06-16T00:49:38.303755Z","steps":["trace[1390154812] 'agreement among raft nodes before linearized reading' (duration: 185.604209ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:49:38.304013Z","caller":"traceutil/trace.go:171","msg":"trace[1757050439] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"163.376525ms","start":"2026-06-16T00:49:38.140623Z","end":"2026-06-16T00:49:38.304Z","steps":["trace[1757050439] 'process raft request' (duration: 162.962208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:49:38.303896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.672392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b575n\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-16T00:49:38.304139Z","caller":"traceutil/trace.go:171","msg":"trace[1536291033] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b575n; range_end:; response_count:1; response_revision:8439; }","duration":"173.990268ms","start":"2026-06-16T00:49:38.130127Z","end":"2026-06-16T00:49:38.304117Z","steps":["trace[1536291033] 'agreement among raft nodes before linearized reading' (duration: 173.59071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:49:38.304685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.398398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:49:38.304759Z","caller":"traceutil/trace.go:171","msg":"trace[1108065042] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8439; }","duration":"136.532871ms","start":"2026-06-16T00:49:38.16821Z","end":"2026-06-16T00:49:38.304743Z","steps":["trace[1108065042] 'agreement among raft nodes before linearized reading' (duration: 136.390849ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:49:41.435805Z","caller":"traceutil/trace.go:171","msg":"trace[888513147] linearizableReadLoop","detail":"{readStateIndex:8752; appliedIndex:8750; }","duration":"281.859811ms","start":"2026-06-16T00:49:41.153925Z","end":"2026-06-16T00:49:41.435785Z","steps":["trace[888513147] 'read index received' (duration: 89.206376ms)","trace[888513147] 'applied index is now lower than readState.Index' (duration: 192.652265ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:49:41.435899Z","caller":"traceutil/trace.go:171","msg":"trace[564839750] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"291.123388ms","start":"2026-06-16T00:49:41.144768Z","end":"2026-06-16T00:49:41.435891Z","steps":["trace[564839750] 'process raft request' (duration: 197.442346ms)","trace[564839750] 'compare' (duration: 93.393837ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:49:41.43609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.153397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:49:41.436124Z","caller":"traceutil/trace.go:171","msg":"trace[31365704] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8456; }","duration":"282.199817ms","start":"2026-06-16T00:49:41.153914Z","end":"2026-06-16T00:49:41.436114Z","steps":["trace[31365704] 'agreement among raft nodes before linearized reading' (duration: 282.123176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:49:41.436155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.86508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:49:41.436244Z","caller":"traceutil/trace.go:171","msg":"trace[2102816343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8456; }","duration":"157.997742ms","start":"2026-06-16T00:49:41.278222Z","end":"2026-06-16T00:49:41.436219Z","steps":["trace[2102816343] 'agreement among raft nodes before linearized reading' (duration: 157.87104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:49:50.69312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.967109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:49:50.693256Z","caller":"traceutil/trace.go:171","msg":"trace[1895428525] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"257.739571ms","start":"2026-06-16T00:49:50.435491Z","end":"2026-06-16T00:49:50.693231Z","steps":["trace[1895428525] 'process raft request' (duration: 143.543836ms)","trace[1895428525] 'compare' (duration: 113.851177ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:49:50.702755Z","caller":"traceutil/trace.go:171","msg":"trace[1397507314] linearizableReadLoop","detail":"{readStateIndex:8848; appliedIndex:8847; }","duration":"109.538725ms","start":"2026-06-16T00:49:50.593198Z","end":"2026-06-16T00:49:50.702737Z","steps":["trace[1397507314] 'read index received' (duration: 103.689213ms)","trace[1397507314] 'applied index is now lower than readState.Index' (duration: 5.848782ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:49:50.702822Z","caller":"traceutil/trace.go:171","msg":"trace[2018522200] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"144.995653ms","start":"2026-06-16T00:49:50.5578Z","end":"2026-06-16T00:49:50.702796Z","steps":["trace[2018522200] 'process raft request' (duration: 144.83842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:49:50.702921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.712379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-16T00:49:50.702987Z","caller":"traceutil/trace.go:171","msg":"trace[1982631940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8547; }","duration":"109.82309ms","start":"2026-06-16T00:49:50.593154Z","end":"2026-06-16T00:49:50.702977Z","steps":["trace[1982631940] 'agreement among raft nodes before linearized reading' (duration: 109.673357ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:49:51.683119Z","caller":"traceutil/trace.go:171","msg":"trace[2008031522] linearizableReadLoop","detail":"{readStateIndex:8857; appliedIndex:8856; }","duration":"209.379624ms","start":"2026-06-16T00:49:51.473708Z","end":"2026-06-16T00:49:51.683088Z","steps":["trace[2008031522] 'read index received' (duration: 193.771366ms)","trace[2008031522] 'applied index is now lower than readState.Index' (duration: 15.606878ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:49:51.683138Z","caller":"traceutil/trace.go:171","msg":"trace[593005486] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"216.159784ms","start":"2026-06-16T00:49:51.46694Z","end":"2026-06-16T00:49:51.683099Z","steps":["trace[593005486] 'process raft request' (duration: 200.519485ms)","trace[593005486] 'compare' (duration: 15.491816ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:49:51.683392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.675261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-16T00:49:51.683499Z","caller":"traceutil/trace.go:171","msg":"trace[602267863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8556; }","duration":"209.831473ms","start":"2026-06-16T00:49:51.473652Z","end":"2026-06-16T00:49:51.683484Z","steps":["trace[602267863] 'agreement among raft nodes before linearized reading' (duration: 209.560268ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:50:08.535116Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000233d.wal"} {"level":"info","ts":"2026-06-16T00:50:15.008581Z","caller":"traceutil/trace.go:171","msg":"trace[199837266] linearizableReadLoop","detail":"{readStateIndex:9125; appliedIndex:9124; }","duration":"129.773457ms","start":"2026-06-16T00:50:14.878783Z","end":"2026-06-16T00:50:15.008556Z","steps":["trace[199837266] 'read index received' (duration: 128.766367ms)","trace[199837266] 'applied index is now lower than readState.Index' (duration: 1.00605ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:50:15.0087Z","caller":"traceutil/trace.go:171","msg":"trace[1282313237] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"145.069779ms","start":"2026-06-16T00:50:14.863619Z","end":"2026-06-16T00:50:15.008689Z","steps":["trace[1282313237] 'process raft request' (duration: 143.913217ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:50:15.008854Z","caller":"traceutil/trace.go:171","msg":"trace[867490490] transaction","detail":"{read_only:false; response_revision:8818; number_of_response:1; }","duration":"115.597916ms","start":"2026-06-16T00:50:14.89322Z","end":"2026-06-16T00:50:15.008818Z","steps":["trace[867490490] 'process raft request' (duration: 115.27209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:50:15.009085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.273676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-16T00:50:15.009223Z","caller":"traceutil/trace.go:171","msg":"trace[439801276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8817; }","duration":"130.46385ms","start":"2026-06-16T00:50:14.878736Z","end":"2026-06-16T00:50:15.0092Z","steps":["trace[439801276] 'agreement among raft nodes before linearized reading' (duration: 129.92715ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:08.889526Z","caller":"traceutil/trace.go:171","msg":"trace[1560741612] transaction","detail":"{read_only:false; response_revision:9307; number_of_response:1; }","duration":"197.819216ms","start":"2026-06-16T00:51:08.691686Z","end":"2026-06-16T00:51:08.889505Z","steps":["trace[1560741612] 'process raft request' (duration: 197.698984ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:51:58.976661Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c39f03fd9ad57fdc","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T00:51:58.984413Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-16T00:51:58.984575Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-16T00:54:29.891676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8343} {"level":"info","ts":"2026-06-16T00:54:29.965356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8343,"took":"71.333579ms","hash":3481067125,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":26816512,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-16T00:54:29.965417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3481067125,"revision":8343,"compact-revision":6173} {"level":"info","ts":"2026-06-16T00:54:53.015884Z","caller":"traceutil/trace.go:171","msg":"trace[524660858] linearizableReadLoop","detail":"{readStateIndex:11530; appliedIndex:11529; }","duration":"147.229006ms","start":"2026-06-16T00:54:52.868633Z","end":"2026-06-16T00:54:53.015862Z","steps":["trace[524660858] 'read index received' (duration: 147.079233ms)","trace[524660858] 'applied index is now lower than readState.Index' (duration: 148.883µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:54:53.015999Z","caller":"traceutil/trace.go:171","msg":"trace[2078712376] transaction","detail":"{read_only:false; response_revision:11156; number_of_response:1; }","duration":"155.430472ms","start":"2026-06-16T00:54:52.860516Z","end":"2026-06-16T00:54:53.015946Z","steps":["trace[2078712376] 'process raft request' (duration: 155.198968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:54:53.016204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.47991ms","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-16T00:54:53.016285Z","caller":"traceutil/trace.go:171","msg":"trace[737818661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11156; }","duration":"147.683215ms","start":"2026-06-16T00:54:52.868581Z","end":"2026-06-16T00:54:53.016264Z","steps":["trace[737818661] 'agreement among raft nodes before linearized reading' (duration: 147.42066ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:59:29.898601Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11019} {"level":"info","ts":"2026-06-16T00:59:29.978497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11019,"took":"77.412995ms","hash":399954859,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":28422144,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-16T00:59:29.978547Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":399954859,"revision":11019,"compact-revision":8343} {"level":"info","ts":"2026-06-16T01:01:16.368847Z","caller":"traceutil/trace.go:171","msg":"trace[396054469] linearizableReadLoop","detail":"{readStateIndex:14184; appliedIndex:14183; }","duration":"131.452252ms","start":"2026-06-16T01:01:16.237366Z","end":"2026-06-16T01:01:16.368818Z","steps":["trace[396054469] 'read index received' (duration: 9.368847ms)","trace[396054469] 'applied index is now lower than readState.Index' (duration: 122.082435ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:01:16.368966Z","caller":"traceutil/trace.go:171","msg":"trace[333666779] transaction","detail":"{read_only:false; response_revision:13719; number_of_response:1; }","duration":"133.883037ms","start":"2026-06-16T01:01:16.235027Z","end":"2026-06-16T01:01:16.36891Z","steps":["trace[333666779] 'process raft request' (duration: 133.628192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:01:16.369126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.735887ms","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-16T01:01:16.36925Z","caller":"traceutil/trace.go:171","msg":"trace[1060168003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13719; }","duration":"131.86374ms","start":"2026-06-16T01:01:16.237341Z","end":"2026-06-16T01:01:16.369204Z","steps":["trace[1060168003] 'agreement among raft nodes before linearized reading' (duration: 131.626956ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:24.507495Z","caller":"traceutil/trace.go:171","msg":"trace[468346349] transaction","detail":"{read_only:false; response_revision:13778; number_of_response:1; }","duration":"221.087058ms","start":"2026-06-16T01:01:24.286382Z","end":"2026-06-16T01:01:24.507469Z","steps":["trace[468346349] 'process raft request' (duration: 220.914814ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:24.507494Z","caller":"traceutil/trace.go:171","msg":"trace[2101400356] linearizableReadLoop","detail":"{readStateIndex:14246; appliedIndex:14246; }","duration":"136.617259ms","start":"2026-06-16T01:01:24.370844Z","end":"2026-06-16T01:01:24.507461Z","steps":["trace[2101400356] 'read index received' (duration: 136.605519ms)","trace[2101400356] 'applied index is now lower than readState.Index' (duration: 9.75µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:01:24.507715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.852333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-25pj7\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-06-16T01:01:24.507765Z","caller":"traceutil/trace.go:171","msg":"trace[1311195375] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-25pj7; range_end:; response_count:1; response_revision:13778; }","duration":"136.958146ms","start":"2026-06-16T01:01:24.370797Z","end":"2026-06-16T01:01:24.507755Z","steps":["trace[1311195375] 'agreement among raft nodes before linearized reading' (duration: 136.757082ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:24.546083Z","caller":"traceutil/trace.go:171","msg":"trace[991792643] transaction","detail":"{read_only:false; response_revision:13779; number_of_response:1; }","duration":"124.003682ms","start":"2026-06-16T01:01:24.422054Z","end":"2026-06-16T01:01:24.546057Z","steps":["trace[991792643] 'process raft request' (duration: 123.583704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:01:24.546143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.706699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-16T01:01:24.546214Z","caller":"traceutil/trace.go:171","msg":"trace[1317773657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13779; }","duration":"146.831281ms","start":"2026-06-16T01:01:24.399363Z","end":"2026-06-16T01:01:24.546195Z","steps":["trace[1317773657] 'agreement among raft nodes before linearized reading' (duration: 146.616396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:01:24.546361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.835225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-16T01:01:24.546484Z","caller":"traceutil/trace.go:171","msg":"trace[1584824968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13779; }","duration":"145.037338ms","start":"2026-06-16T01:01:24.401408Z","end":"2026-06-16T01:01:24.546445Z","steps":["trace[1584824968] 'agreement among raft nodes before linearized reading' (duration: 144.699962ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:24.706215Z","caller":"traceutil/trace.go:171","msg":"trace[1977816690] transaction","detail":"{read_only:false; response_revision:13780; number_of_response:1; }","duration":"185.478979ms","start":"2026-06-16T01:01:24.520711Z","end":"2026-06-16T01:01:24.70619Z","steps":["trace[1977816690] 'process raft request' (duration: 185.321386ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:24.711582Z","caller":"traceutil/trace.go:171","msg":"trace[1622697934] transaction","detail":"{read_only:false; response_revision:13781; number_of_response:1; }","duration":"161.497318ms","start":"2026-06-16T01:01:24.550066Z","end":"2026-06-16T01:01:24.711563Z","steps":["trace[1622697934] 'process raft request' (duration: 161.374415ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:01:24.711794Z","caller":"traceutil/trace.go:171","msg":"trace[1776537453] transaction","detail":"{read_only:false; response_revision:13782; number_of_response:1; }","duration":"160.415797ms","start":"2026-06-16T01:01:24.551349Z","end":"2026-06-16T01:01:24.711765Z","steps":["trace[1776537453] 'process raft request' (duration: 160.180373ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:04:29.904588Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13059} {"level":"info","ts":"2026-06-16T01:04:29.976627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13059,"took":"69.660535ms","hash":3074147152,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":31121408,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-16T01:04:29.976699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3074147152,"revision":13059,"compact-revision":11019} {"level":"info","ts":"2026-06-16T01:04:40.190397Z","caller":"traceutil/trace.go:171","msg":"trace[874036420] linearizableReadLoop","detail":"{readStateIndex:16001; appliedIndex:16000; }","duration":"128.861802ms","start":"2026-06-16T01:04:40.061514Z","end":"2026-06-16T01:04:40.190376Z","steps":["trace[874036420] 'read index received' (duration: 126.683381ms)","trace[874036420] 'applied index is now lower than readState.Index' (duration: 2.177751ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:04:40.190726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.193268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:416636"} {"level":"info","ts":"2026-06-16T01:04:40.190765Z","caller":"traceutil/trace.go:171","msg":"trace[245025104] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:15477; }","duration":"129.28575ms","start":"2026-06-16T01:04:40.061471Z","end":"2026-06-16T01:04:40.190757Z","steps":["trace[245025104] 'agreement among raft nodes before linearized reading' (duration: 129.104216ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:04:40.190816Z","caller":"traceutil/trace.go:171","msg":"trace[1613439289] transaction","detail":"{read_only:false; response_revision:15477; number_of_response:1; }","duration":"242.687698ms","start":"2026-06-16T01:04:39.948089Z","end":"2026-06-16T01:04:40.190777Z","steps":["trace[1613439289] 'process raft request' (duration: 240.061469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:09:02.888465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.609247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-06-16T01:09:02.888558Z","caller":"traceutil/trace.go:171","msg":"trace[658043368] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:17521; }","duration":"124.756579ms","start":"2026-06-16T01:09:02.763782Z","end":"2026-06-16T01:09:02.888539Z","steps":["trace[658043368] 'range keys from in-memory index tree' (duration: 124.400092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:09:03.317644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.155104ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-06-16T01:09:03.332015Z","caller":"traceutil/trace.go:171","msg":"trace[226111843] transaction","detail":"{read_only:false; response_revision:17525; number_of_response:1; }","duration":"277.298819ms","start":"2026-06-16T01:09:03.054689Z","end":"2026-06-16T01:09:03.331988Z","steps":["trace[226111843] 'process raft request' (duration: 277.054744ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:09:03.332195Z","caller":"traceutil/trace.go:171","msg":"trace[752484676] transaction","detail":"{read_only:false; response_revision:17526; number_of_response:1; }","duration":"186.464117ms","start":"2026-06-16T01:09:03.145715Z","end":"2026-06-16T01:09:03.332179Z","steps":["trace[752484676] 'process raft request' (duration: 186.17951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:09:03.64635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.010266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-16T01:09:03.646475Z","caller":"traceutil/trace.go:171","msg":"trace[1466916060] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:17527; }","duration":"220.188279ms","start":"2026-06-16T01:09:03.426255Z","end":"2026-06-16T01:09:03.646443Z","steps":["trace[1466916060] 'range keys from in-memory index tree' (duration: 219.754632ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:09:03.911626Z","caller":"traceutil/trace.go:171","msg":"trace[184538368] transaction","detail":"{read_only:false; response_revision:17528; number_of_response:1; }","duration":"260.478627ms","start":"2026-06-16T01:09:03.650457Z","end":"2026-06-16T01:09:03.910936Z","steps":["trace[184538368] 'process raft request' (duration: 260.313234ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:09:04.180532Z","caller":"traceutil/trace.go:171","msg":"trace[680742084] transaction","detail":"{read_only:false; response_revision:17529; number_of_response:1; }","duration":"529.837665ms","start":"2026-06-16T01:09:03.650669Z","end":"2026-06-16T01:09:04.180507Z","steps":["trace[680742084] 'process raft request' (duration: 529.533289ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:09:04.180572Z","caller":"traceutil/trace.go:171","msg":"trace[420989645] transaction","detail":"{read_only:false; response_revision:17530; number_of_response:1; }","duration":"528.49416ms","start":"2026-06-16T01:09:03.652043Z","end":"2026-06-16T01:09:04.180537Z","steps":["trace[420989645] 'process raft request' (duration: 528.335136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:09:04.180642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.405454ms","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-16T01:09:04.180709Z","caller":"traceutil/trace.go:171","msg":"trace[1465089084] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17530; }","duration":"350.532356ms","start":"2026-06-16T01:09:03.830159Z","end":"2026-06-16T01:09:04.180692Z","steps":["trace[1465089084] 'agreement among raft nodes before linearized reading' (duration: 350.416733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:09:04.180685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:09:03.650647Z","time spent":"529.955008ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:09:04.180735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:09:03.652031Z","time spent":"528.616532ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:09:04.180761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:09:03.830136Z","time spent":"350.608758ms","remote":"127.0.0.1:44726","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":"info","ts":"2026-06-16T01:09:04.180515Z","caller":"traceutil/trace.go:171","msg":"trace[63229619] linearizableReadLoop","detail":"{readStateIndex:18120; appliedIndex:18118; }","duration":"350.266991ms","start":"2026-06-16T01:09:03.830219Z","end":"2026-06-16T01:09:04.180486Z","steps":["trace[63229619] 'read index received' (duration: 81.466234ms)","trace[63229619] 'applied index is now lower than readState.Index' (duration: 268.799157ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:09:12.534257Z","caller":"traceutil/trace.go:171","msg":"trace[1303557927] linearizableReadLoop","detail":"{readStateIndex:18271; appliedIndex:18270; }","duration":"111.501297ms","start":"2026-06-16T01:09:12.422722Z","end":"2026-06-16T01:09:12.534224Z","steps":["trace[1303557927] 'read index received' (duration: 13.209253ms)","trace[1303557927] 'applied index is now lower than readState.Index' (duration: 98.290874ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:09:12.534494Z","caller":"traceutil/trace.go:171","msg":"trace[1982481419] transaction","detail":"{read_only:false; response_revision:17677; number_of_response:1; }","duration":"111.974575ms","start":"2026-06-16T01:09:12.422483Z","end":"2026-06-16T01:09:12.534458Z","steps":["trace[1982481419] 'process raft request' (duration: 88.480887ms)","trace[1982481419] 'compare' (duration: 23.04511ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:09:12.534739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.007226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-06-16T01:09:12.536214Z","caller":"traceutil/trace.go:171","msg":"trace[1771168216] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:17677; }","duration":"113.550345ms","start":"2026-06-16T01:09:12.422644Z","end":"2026-06-16T01:09:12.536195Z","steps":["trace[1771168216] 'agreement among raft nodes before linearized reading' (duration: 111.868323ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:09:29.910121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15422} {"level":"info","ts":"2026-06-16T01:09:30.006288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15422,"took":"92.898373ms","hash":1963799593,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":32698368,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-16T01:09:30.006364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1963799593,"revision":15422,"compact-revision":13059} {"level":"info","ts":"2026-06-16T01:12:43.940782Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c39f03fd9ad57fdc","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T01:12:43.949319Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-16T01:12:43.949594Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-16T01:14:29.915645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17829} {"level":"info","ts":"2026-06-16T01:14:30.008417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17829,"took":"89.377313ms","hash":2739992083,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":33996800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-16T01:14:30.008475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2739992083,"revision":17829,"compact-revision":15422} {"level":"info","ts":"2026-06-16T01:14:51.534266Z","caller":"traceutil/trace.go:171","msg":"trace[1960002496] transaction","detail":"{read_only:false; response_revision:20224; number_of_response:1; }","duration":"102.301017ms","start":"2026-06-16T01:14:51.431938Z","end":"2026-06-16T01:14:51.534239Z","steps":["trace[1960002496] 'process raft request' (duration: 102.128114ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:14:51.85333Z","caller":"traceutil/trace.go:171","msg":"trace[323419605] transaction","detail":"{read_only:false; response_revision:20225; number_of_response:1; }","duration":"124.440457ms","start":"2026-06-16T01:14:51.728867Z","end":"2026-06-16T01:14:51.853307Z","steps":["trace[323419605] 'process raft request' (duration: 124.305695ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:14:52.152845Z","caller":"traceutil/trace.go:171","msg":"trace[407256873] linearizableReadLoop","detail":"{readStateIndex:20906; appliedIndex:20905; }","duration":"293.678973ms","start":"2026-06-16T01:14:51.859141Z","end":"2026-06-16T01:14:52.15282Z","steps":["trace[407256873] 'read index received' (duration: 293.565539ms)","trace[407256873] 'applied index is now lower than readState.Index' (duration: 109.573µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:14:52.152872Z","caller":"traceutil/trace.go:171","msg":"trace[771954431] transaction","detail":"{read_only:false; response_revision:20226; number_of_response:1; }","duration":"294.812134ms","start":"2026-06-16T01:14:51.858027Z","end":"2026-06-16T01:14:52.152839Z","steps":["trace[771954431] 'process raft request' (duration: 294.607439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:14:52.153097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.928727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18261"} {"level":"info","ts":"2026-06-16T01:14:52.153146Z","caller":"traceutil/trace.go:171","msg":"trace[182885846] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20226; }","duration":"294.011528ms","start":"2026-06-16T01:14:51.859123Z","end":"2026-06-16T01:14:52.153135Z","steps":["trace[182885846] 'agreement among raft nodes before linearized reading' (duration: 293.816245ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:14:52.253628Z","caller":"traceutil/trace.go:171","msg":"trace[55175207] linearizableReadLoop","detail":"{readStateIndex:20907; appliedIndex:20906; }","duration":"100.692967ms","start":"2026-06-16T01:14:52.152912Z","end":"2026-06-16T01:14:52.253605Z","steps":["trace[55175207] 'read index received' (duration: 99.525295ms)","trace[55175207] 'applied index is now lower than readState.Index' (duration: 1.166522ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:14:52.253711Z","caller":"traceutil/trace.go:171","msg":"trace[1064558170] transaction","detail":"{read_only:false; response_revision:20227; number_of_response:1; }","duration":"371.414024ms","start":"2026-06-16T01:14:51.882254Z","end":"2026-06-16T01:14:52.253668Z","steps":["trace[1064558170] 'process raft request' (duration: 370.191941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:14:52.253788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.091215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:14:52.253845Z","caller":"traceutil/trace.go:171","msg":"trace[586780533] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:20227; }","duration":"383.230308ms","start":"2026-06-16T01:14:51.8706Z","end":"2026-06-16T01:14:52.25383Z","steps":["trace[586780533] 'agreement among raft nodes before linearized reading' (duration: 383.105126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:14:52.253882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:14:51.870582Z","time spent":"383.293479ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:14:52.25411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:14:51.882241Z","time spent":"371.611389ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:14:52.547768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.008461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:14:52.547868Z","caller":"traceutil/trace.go:171","msg":"trace[600878049] linearizableReadLoop","detail":"{readStateIndex:20908; appliedIndex:20907; }","duration":"185.187988ms","start":"2026-06-16T01:14:52.362665Z","end":"2026-06-16T01:14:52.547853Z","steps":["trace[600878049] 'read index received' (duration: 52.994784ms)","trace[600878049] 'applied index is now lower than readState.Index' (duration: 132.191894ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:14:52.547917Z","caller":"traceutil/trace.go:171","msg":"trace[1019073306] transaction","detail":"{read_only:false; response_revision:20228; number_of_response:1; }","duration":"223.662466ms","start":"2026-06-16T01:14:52.324222Z","end":"2026-06-16T01:14:52.547884Z","steps":["trace[1019073306] 'process raft request' (duration: 91.463292ms)","trace[1019073306] 'compare' (duration: 131.784096ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:14:52.548002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.34573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-16T01:14:52.548028Z","caller":"traceutil/trace.go:171","msg":"trace[734572899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20228; }","duration":"185.413052ms","start":"2026-06-16T01:14:52.362608Z","end":"2026-06-16T01:14:52.548021Z","steps":["trace[734572899] 'agreement among raft nodes before linearized reading' (duration: 185.281309ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:14:52.77893Z","caller":"traceutil/trace.go:171","msg":"trace[1048611810] transaction","detail":"{read_only:false; response_revision:20230; number_of_response:1; }","duration":"172.735551ms","start":"2026-06-16T01:14:52.606169Z","end":"2026-06-16T01:14:52.778905Z","steps":["trace[1048611810] 'process raft request' (duration: 171.505598ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:14:52.959604Z","caller":"traceutil/trace.go:171","msg":"trace[2107163658] transaction","detail":"{read_only:false; response_revision:20231; number_of_response:1; }","duration":"177.281548ms","start":"2026-06-16T01:14:52.78229Z","end":"2026-06-16T01:14:52.959572Z","steps":["trace[2107163658] 'process raft request' (duration: 177.127825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:14:53.224092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.498939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:14:53.22417Z","caller":"traceutil/trace.go:171","msg":"trace[789145230] linearizableReadLoop","detail":"{readStateIndex:20912; appliedIndex:20911; }","duration":"155.817872ms","start":"2026-06-16T01:14:53.068339Z","end":"2026-06-16T01:14:53.224156Z","steps":["trace[789145230] 'read index received' (duration: 22.101969ms)","trace[789145230] 'applied index is now lower than readState.Index' (duration: 133.714813ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:14:53.224288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.957124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.21\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-16T01:14:53.224364Z","caller":"traceutil/trace.go:171","msg":"trace[344644746] range","detail":"{range_begin:/registry/masterleases/199.19.213.21; range_end:; response_count:1; response_revision:20232; }","duration":"156.063696ms","start":"2026-06-16T01:14:53.068291Z","end":"2026-06-16T01:14:53.224355Z","steps":["trace[344644746] 'agreement among raft nodes before linearized reading' (duration: 155.899993ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:14:53.224361Z","caller":"traceutil/trace.go:171","msg":"trace[1477717954] transaction","detail":"{read_only:false; response_revision:20232; number_of_response:1; }","duration":"441.529913ms","start":"2026-06-16T01:14:52.782802Z","end":"2026-06-16T01:14:53.224332Z","steps":["trace[1477717954] 'process raft request' (duration: 307.713958ms)","trace[1477717954] 'compare' (duration: 133.156932ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:14:53.224697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:14:52.782777Z","time spent":"441.819678ms","remote":"127.0.0.1:51314","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-16T01:18:42.63035Z","caller":"traceutil/trace.go:171","msg":"trace[1035100640] linearizableReadLoop","detail":"{readStateIndex:23414; appliedIndex:23413; }","duration":"178.43691ms","start":"2026-06-16T01:18:42.451879Z","end":"2026-06-16T01:18:42.630316Z","steps":["trace[1035100640] 'read index received' (duration: 178.022481ms)","trace[1035100640] 'applied index is now lower than readState.Index' (duration: 413.209µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:42.630482Z","caller":"traceutil/trace.go:171","msg":"trace[1607218652] transaction","detail":"{read_only:false; response_revision:22661; number_of_response:1; }","duration":"254.393448ms","start":"2026-06-16T01:18:42.376054Z","end":"2026-06-16T01:18:42.630448Z","steps":["trace[1607218652] 'process raft request' (duration: 253.824977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:42.630656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.765846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-06-16T01:18:42.630702Z","caller":"traceutil/trace.go:171","msg":"trace[1927337631] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22661; }","duration":"178.906498ms","start":"2026-06-16T01:18:42.451783Z","end":"2026-06-16T01:18:42.630689Z","steps":["trace[1927337631] 'agreement among raft nodes before linearized reading' (duration: 178.669594ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:42.631167Z","caller":"traceutil/trace.go:171","msg":"trace[1387300070] transaction","detail":"{read_only:false; response_revision:22662; number_of_response:1; }","duration":"145.291812ms","start":"2026-06-16T01:18:42.485779Z","end":"2026-06-16T01:18:42.631071Z","steps":["trace[1387300070] 'process raft request' (duration: 145.14609ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:19:29.921329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20113} {"level":"info","ts":"2026-06-16T01:19:30.039828Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20113,"took":"113.581821ms","hash":1770016459,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":42487808,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-16T01:19:30.039914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1770016459,"revision":20113,"compact-revision":17829} {"level":"warn","ts":"2026-06-16T01:21:58.477892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.985473ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-16T01:21:58.478446Z","caller":"traceutil/trace.go:171","msg":"trace[5974787] transaction","detail":"{read_only:false; response_revision:24706; number_of_response:1; }","duration":"222.811987ms","start":"2026-06-16T01:21:58.255619Z","end":"2026-06-16T01:21:58.478431Z","steps":["trace[5974787] 'process raft request' (duration: 222.673765ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:21:58.478437Z","caller":"traceutil/trace.go:171","msg":"trace[1456208170] linearizableReadLoop","detail":"{readStateIndex:25513; appliedIndex:25512; }","duration":"293.329976ms","start":"2026-06-16T01:21:58.185077Z","end":"2026-06-16T01:21:58.478407Z","steps":["trace[1456208170] 'read index received' (duration: 52.774711ms)","trace[1456208170] 'applied index is now lower than readState.Index' (duration: 240.551925ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:21:58.478745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.662083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-16T01:21:58.47877Z","caller":"traceutil/trace.go:171","msg":"trace[914452407] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24706; }","duration":"293.751684ms","start":"2026-06-16T01:21:58.185011Z","end":"2026-06-16T01:21:58.478763Z","steps":["trace[914452407] 'agreement among raft nodes before linearized reading' (duration: 293.544921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:21:58.47875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.756825ms","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-16T01:21:58.478813Z","caller":"traceutil/trace.go:171","msg":"trace[62737350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24706; }","duration":"243.844277ms","start":"2026-06-16T01:21:58.234951Z","end":"2026-06-16T01:21:58.478795Z","steps":["trace[62737350] 'agreement among raft nodes before linearized reading' (duration: 243.661764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:21:58.479052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.285851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:21:58.479104Z","caller":"traceutil/trace.go:171","msg":"trace[1664905443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24706; }","duration":"198.371733ms","start":"2026-06-16T01:21:58.28072Z","end":"2026-06-16T01:21:58.479091Z","steps":["trace[1664905443] 'agreement among raft nodes before linearized reading' (duration: 198.291122ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:21:58.878572Z","caller":"traceutil/trace.go:171","msg":"trace[1995541864] linearizableReadLoop","detail":"{readStateIndex:25516; appliedIndex:25515; }","duration":"208.71041ms","start":"2026-06-16T01:21:58.669811Z","end":"2026-06-16T01:21:58.878521Z","steps":["trace[1995541864] 'read index received' (duration: 208.22052ms)","trace[1995541864] 'applied index is now lower than readState.Index' (duration: 488.11µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:21:58.878674Z","caller":"traceutil/trace.go:171","msg":"trace[987991385] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"373.041729ms","start":"2026-06-16T01:21:58.505567Z","end":"2026-06-16T01:21:58.878609Z","steps":["trace[987991385] 'process raft request' (duration: 372.709932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:21:58.878941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:21:58.505558Z","time spent":"373.219303ms","remote":"127.0.0.1:51314","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-16T01:21:58.878973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.125808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-16T01:21:58.879032Z","caller":"traceutil/trace.go:171","msg":"trace[1554257564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24708; }","duration":"209.277581ms","start":"2026-06-16T01:21:58.669737Z","end":"2026-06-16T01:21:58.879014Z","steps":["trace[1554257564] 'agreement among raft nodes before linearized reading' (duration: 209.022166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:21:58.879033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.360189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:21:58.879141Z","caller":"traceutil/trace.go:171","msg":"trace[1257279403] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24708; }","duration":"164.492411ms","start":"2026-06-16T01:21:58.71462Z","end":"2026-06-16T01:21:58.879113Z","steps":["trace[1257279403] 'agreement among raft nodes before linearized reading' (duration: 164.289467ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:21:59.037922Z","caller":"traceutil/trace.go:171","msg":"trace[1443477233] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"155.059831ms","start":"2026-06-16T01:21:58.88283Z","end":"2026-06-16T01:21:59.03789Z","steps":["trace[1443477233] 'process raft request' (duration: 66.371729ms)","trace[1443477233] 'compare' (duration: 88.193803ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:21:59.344867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.048062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:21:59.345008Z","caller":"traceutil/trace.go:171","msg":"trace[995807887] transaction","detail":"{read_only:false; response_revision:24711; number_of_response:1; }","duration":"270.159217ms","start":"2026-06-16T01:21:59.074809Z","end":"2026-06-16T01:21:59.344968Z","steps":["trace[995807887] 'process raft request' (duration: 133.936011ms)","trace[995807887] 'compare' (duration: 135.703245ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:21:59.506869Z","caller":"traceutil/trace.go:171","msg":"trace[1439779833] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"144.349539ms","start":"2026-06-16T01:21:59.362495Z","end":"2026-06-16T01:21:59.506845Z","steps":["trace[1439779833] 'process raft request' (duration: 144.179125ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:04.80973Z","caller":"traceutil/trace.go:171","msg":"trace[956336333] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"252.066163ms","start":"2026-06-16T01:22:04.557614Z","end":"2026-06-16T01:22:04.80968Z","steps":["trace[956336333] 'process raft request' (duration: 251.846679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:05.680836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.171056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:05.681034Z","caller":"traceutil/trace.go:171","msg":"trace[1181773243] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"311.861067ms","start":"2026-06-16T01:22:05.369122Z","end":"2026-06-16T01:22:05.680983Z","steps":["trace[1181773243] 'process raft request' (duration: 138.434386ms)","trace[1181773243] 'compare' (duration: 172.774329ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:05.681129Z","caller":"traceutil/trace.go:171","msg":"trace[1717377156] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"156.113281ms","start":"2026-06-16T01:22:05.524994Z","end":"2026-06-16T01:22:05.681107Z","steps":["trace[1717377156] 'process raft request' (duration: 155.984899ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:05.681126Z","caller":"traceutil/trace.go:171","msg":"trace[1368765842] linearizableReadLoop","detail":"{readStateIndex:25566; appliedIndex:25565; }","duration":"309.581254ms","start":"2026-06-16T01:22:05.371511Z","end":"2026-06-16T01:22:05.681092Z","steps":["trace[1368765842] 'read index received' (duration: 18.242386ms)","trace[1368765842] 'applied index is now lower than readState.Index' (duration: 291.336448ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:05.681256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:05.369084Z","time spent":"312.056631ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:22:05.681419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.89907ms","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-16T01:22:05.681466Z","caller":"traceutil/trace.go:171","msg":"trace[545095075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24758; }","duration":"310.002652ms","start":"2026-06-16T01:22:05.371453Z","end":"2026-06-16T01:22:05.681455Z","steps":["trace[545095075] 'agreement among raft nodes before linearized reading' (duration: 309.776228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:05.681513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:05.371439Z","time spent":"310.066163ms","remote":"127.0.0.1:51314","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-16T01:22:05.76316Z","caller":"traceutil/trace.go:171","msg":"trace[571138823] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"232.941771ms","start":"2026-06-16T01:22:05.530182Z","end":"2026-06-16T01:22:05.763123Z","steps":["trace[571138823] 'process raft request' (duration: 232.784378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:05.763997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.920025ms","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-16T01:22:05.764111Z","caller":"traceutil/trace.go:171","msg":"trace[1645659755] 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:24759; }","duration":"374.105548ms","start":"2026-06-16T01:22:05.389987Z","end":"2026-06-16T01:22:05.764092Z","steps":["trace[1645659755] 'agreement among raft nodes before linearized reading' (duration: 373.895474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:05.764192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:05.389963Z","time spent":"374.21431ms","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-06-16T01:22:05.764175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.542523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-16T01:22:05.764537Z","caller":"traceutil/trace.go:171","msg":"trace[1097304599] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24759; }","duration":"183.969661ms","start":"2026-06-16T01:22:05.580536Z","end":"2026-06-16T01:22:05.764505Z","steps":["trace[1097304599] 'agreement among raft nodes before linearized reading' (duration: 183.39999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:05.764219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.486261ms","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-16T01:22:05.764767Z","caller":"traceutil/trace.go:171","msg":"trace[669937582] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24759; }","duration":"331.056362ms","start":"2026-06-16T01:22:05.43367Z","end":"2026-06-16T01:22:05.764726Z","steps":["trace[669937582] 'agreement among raft nodes before linearized reading' (duration: 330.303538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:05.764907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:05.433654Z","time spent":"331.206235ms","remote":"127.0.0.1:51226","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":"info","ts":"2026-06-16T01:22:05.800634Z","caller":"traceutil/trace.go:171","msg":"trace[139941090] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"114.218036ms","start":"2026-06-16T01:22:05.686375Z","end":"2026-06-16T01:22:05.800593Z","steps":["trace[139941090] 'process raft request' (duration: 100.208691ms)","trace[139941090] 'compare' (duration: 13.68749ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:06.078496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.129321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:06.078635Z","caller":"traceutil/trace.go:171","msg":"trace[1745835657] linearizableReadLoop","detail":"{readStateIndex:25571; appliedIndex:25570; }","duration":"267.582257ms","start":"2026-06-16T01:22:05.811035Z","end":"2026-06-16T01:22:06.078617Z","steps":["trace[1745835657] 'read index received' (duration: 129.073429ms)","trace[1745835657] 'applied index is now lower than readState.Index' (duration: 138.507468ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:06.078795Z","caller":"traceutil/trace.go:171","msg":"trace[2079930055] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"267.766581ms","start":"2026-06-16T01:22:05.81099Z","end":"2026-06-16T01:22:06.078756Z","steps":["trace[2079930055] 'process raft request' (duration: 129.287003ms)","trace[2079930055] 'compare' (duration: 137.900577ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:06.078986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.969961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T01:22:06.078978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.897053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:22:06.079082Z","caller":"traceutil/trace.go:171","msg":"trace[1739306840] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24762; }","duration":"268.036665ms","start":"2026-06-16T01:22:05.811021Z","end":"2026-06-16T01:22:06.079057Z","steps":["trace[1739306840] 'agreement among raft nodes before linearized reading' (duration: 267.794481ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:06.079031Z","caller":"traceutil/trace.go:171","msg":"trace[1063569328] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24762; }","duration":"214.058911ms","start":"2026-06-16T01:22:05.864961Z","end":"2026-06-16T01:22:06.07902Z","steps":["trace[1063569328] 'agreement among raft nodes before linearized reading' (duration: 213.909329ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:06.313361Z","caller":"traceutil/trace.go:171","msg":"trace[1493091482] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"182.062444ms","start":"2026-06-16T01:22:06.131277Z","end":"2026-06-16T01:22:06.313339Z","steps":["trace[1493091482] 'process raft request' (duration: 181.995953ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:06.313645Z","caller":"traceutil/trace.go:171","msg":"trace[489449118] linearizableReadLoop","detail":"{readStateIndex:25572; appliedIndex:25571; }","duration":"226.470108ms","start":"2026-06-16T01:22:06.087162Z","end":"2026-06-16T01:22:06.313632Z","steps":["trace[489449118] 'read index received' (duration: 126.542282ms)","trace[489449118] 'applied index is now lower than readState.Index' (duration: 99.926536ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:06.313632Z","caller":"traceutil/trace.go:171","msg":"trace[1485264574] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"229.78402ms","start":"2026-06-16T01:22:06.083801Z","end":"2026-06-16T01:22:06.313585Z","steps":["trace[1485264574] 'process raft request' (duration: 129.849233ms)","trace[1485264574] 'compare' (duration: 99.463748ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:06.31384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.670091ms","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-16T01:22:06.313905Z","caller":"traceutil/trace.go:171","msg":"trace[2111169476] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24764; }","duration":"226.795003ms","start":"2026-06-16T01:22:06.087095Z","end":"2026-06-16T01:22:06.31389Z","steps":["trace[2111169476] 'agreement among raft nodes before linearized reading' (duration: 226.61832ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:06.635237Z","caller":"traceutil/trace.go:171","msg":"trace[444529743] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"129.718292ms","start":"2026-06-16T01:22:06.505487Z","end":"2026-06-16T01:22:06.635206Z","steps":["trace[444529743] 'process raft request' (duration: 129.289373ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:06.943292Z","caller":"traceutil/trace.go:171","msg":"trace[1209203069] linearizableReadLoop","detail":"{readStateIndex:25575; appliedIndex:25574; }","duration":"118.947997ms","start":"2026-06-16T01:22:06.824313Z","end":"2026-06-16T01:22:06.943261Z","steps":["trace[1209203069] 'read index received' (duration: 118.731602ms)","trace[1209203069] 'applied index is now lower than readState.Index' (duration: 215.335µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:06.943344Z","caller":"traceutil/trace.go:171","msg":"trace[348864291] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"126.020791ms","start":"2026-06-16T01:22:06.8173Z","end":"2026-06-16T01:22:06.943321Z","steps":["trace[348864291] 'process raft request' (duration: 125.796576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:06.943565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.235422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-06-16T01:22:06.943633Z","caller":"traceutil/trace.go:171","msg":"trace[1567513228] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24766; }","duration":"119.339445ms","start":"2026-06-16T01:22:06.824279Z","end":"2026-06-16T01:22:06.943618Z","steps":["trace[1567513228] 'agreement among raft nodes before linearized reading' (duration: 119.114851ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:07.135708Z","caller":"traceutil/trace.go:171","msg":"trace[773044247] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"161.488763ms","start":"2026-06-16T01:22:06.974193Z","end":"2026-06-16T01:22:07.135682Z","steps":["trace[773044247] 'process raft request' (duration: 161.265909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:07.512212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.092529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:07.512331Z","caller":"traceutil/trace.go:171","msg":"trace[1525461130] linearizableReadLoop","detail":"{readStateIndex:25577; appliedIndex:25575; }","duration":"391.474608ms","start":"2026-06-16T01:22:07.120838Z","end":"2026-06-16T01:22:07.512313Z","steps":["trace[1525461130] 'read index received' (duration: 14.606207ms)","trace[1525461130] 'applied index is now lower than readState.Index' (duration: 376.867561ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:07.512437Z","caller":"traceutil/trace.go:171","msg":"trace[1285354388] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"499.070199ms","start":"2026-06-16T01:22:07.013361Z","end":"2026-06-16T01:22:07.512431Z","steps":["trace[1285354388] 'process raft request' (duration: 242.698035ms)","trace[1285354388] 'compare' (duration: 255.939476ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:07.51247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:07.013346Z","time spent":"499.10791ms","remote":"127.0.0.1:51314","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-16T01:22:07.512486Z","caller":"traceutil/trace.go:171","msg":"trace[577213002] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"391.07503ms","start":"2026-06-16T01:22:07.121395Z","end":"2026-06-16T01:22:07.51247Z","steps":["trace[577213002] 'process raft request' (duration: 390.886417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:07.512574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:07.121385Z","time spent":"391.145331ms","remote":"127.0.0.1:51314","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-16T01:22:07.512701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.862535ms","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-16T01:22:07.512719Z","caller":"traceutil/trace.go:171","msg":"trace[1448899719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24769; }","duration":"391.909667ms","start":"2026-06-16T01:22:07.120805Z","end":"2026-06-16T01:22:07.512714Z","steps":["trace[1448899719] 'agreement among raft nodes before linearized reading' (duration: 391.788534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:07.512734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:07.12079Z","time spent":"391.940677ms","remote":"127.0.0.1:51314","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-16T01:22:07.516802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.851564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:22:07.516814Z","caller":"traceutil/trace.go:171","msg":"trace[1591225766] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"121.343612ms","start":"2026-06-16T01:22:07.395438Z","end":"2026-06-16T01:22:07.516782Z","steps":["trace[1591225766] 'process raft request' (duration: 121.23311ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:07.516854Z","caller":"traceutil/trace.go:171","msg":"trace[1216874267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24770; }","duration":"236.939496ms","start":"2026-06-16T01:22:07.279902Z","end":"2026-06-16T01:22:07.516842Z","steps":["trace[1216874267] 'agreement among raft nodes before linearized reading' (duration: 236.850114ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:07.885378Z","caller":"traceutil/trace.go:171","msg":"trace[968934211] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"197.397605ms","start":"2026-06-16T01:22:07.687955Z","end":"2026-06-16T01:22:07.885352Z","steps":["trace[968934211] 'process raft request' (duration: 197.192781ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:08.132376Z","caller":"traceutil/trace.go:171","msg":"trace[142563131] linearizableReadLoop","detail":"{readStateIndex:25583; appliedIndex:25580; }","duration":"327.023205ms","start":"2026-06-16T01:22:07.805332Z","end":"2026-06-16T01:22:08.132355Z","steps":["trace[142563131] 'read index received' (duration: 79.854205ms)","trace[142563131] 'applied index is now lower than readState.Index' (duration: 247.16808ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:08.132416Z","caller":"traceutil/trace.go:171","msg":"trace[148507993] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"418.919029ms","start":"2026-06-16T01:22:07.713464Z","end":"2026-06-16T01:22:08.132383Z","steps":["trace[148507993] 'process raft request' (duration: 387.753618ms)","trace[148507993] 'compare' (duration: 30.706513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:08.1327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:07.713436Z","time spent":"419.185043ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:22:08.132718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.385563ms","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-16T01:22:08.132501Z","caller":"traceutil/trace.go:171","msg":"trace[89728772] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"362.660512ms","start":"2026-06-16T01:22:07.769833Z","end":"2026-06-16T01:22:08.132493Z","steps":["trace[89728772] 'process raft request' (duration: 362.438038ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:08.132784Z","caller":"traceutil/trace.go:171","msg":"trace[2119423110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24774; }","duration":"327.538785ms","start":"2026-06-16T01:22:07.805236Z","end":"2026-06-16T01:22:08.132775Z","steps":["trace[2119423110] 'agreement among raft nodes before linearized reading' (duration: 327.299931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:08.132847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:07.805217Z","time spent":"327.616696ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T01:22:08.132906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:07.769801Z","time spent":"363.003278ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:22:08.461649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.840456ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-16T01:22:08.461945Z","caller":"traceutil/trace.go:171","msg":"trace[1015193194] linearizableReadLoop","detail":"{readStateIndex:25586; appliedIndex:25585; }","duration":"179.479665ms","start":"2026-06-16T01:22:08.282441Z","end":"2026-06-16T01:22:08.461921Z","steps":["trace[1015193194] 'read index received' (duration: 12.302503ms)","trace[1015193194] 'applied index is now lower than readState.Index' (duration: 167.175802ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:08.462074Z","caller":"traceutil/trace.go:171","msg":"trace[315360659] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"141.685549ms","start":"2026-06-16T01:22:08.320357Z","end":"2026-06-16T01:22:08.462043Z","steps":["trace[315360659] 'process raft request' (duration: 141.418994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:08.464665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.699154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-16T01:22:08.464751Z","caller":"traceutil/trace.go:171","msg":"trace[836571794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24777; }","duration":"146.854487ms","start":"2026-06-16T01:22:08.317872Z","end":"2026-06-16T01:22:08.464727Z","steps":["trace[836571794] 'agreement among raft nodes before linearized reading' (duration: 144.171106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:08.464981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.545433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:22:08.465018Z","caller":"traceutil/trace.go:171","msg":"trace[1567304891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24777; }","duration":"182.581784ms","start":"2026-06-16T01:22:08.282426Z","end":"2026-06-16T01:22:08.465008Z","steps":["trace[1567304891] 'agreement among raft nodes before linearized reading' (duration: 179.595347ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:08.662109Z","caller":"traceutil/trace.go:171","msg":"trace[1681431773] linearizableReadLoop","detail":"{readStateIndex:25588; appliedIndex:25587; }","duration":"161.550234ms","start":"2026-06-16T01:22:08.500535Z","end":"2026-06-16T01:22:08.662085Z","steps":["trace[1681431773] 'read index received' (duration: 161.412852ms)","trace[1681431773] 'applied index is now lower than readState.Index' (duration: 136.202µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:08.662261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.706918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:22:08.662298Z","caller":"traceutil/trace.go:171","msg":"trace[1740734178] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24778; }","duration":"161.812849ms","start":"2026-06-16T01:22:08.500473Z","end":"2026-06-16T01:22:08.662286Z","steps":["trace[1740734178] 'agreement among raft nodes before linearized reading' (duration: 161.708527ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:08.66256Z","caller":"traceutil/trace.go:171","msg":"trace[1915428059] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"189.078339ms","start":"2026-06-16T01:22:08.473451Z","end":"2026-06-16T01:22:08.66253Z","steps":["trace[1915428059] 'process raft request' (duration: 188.427436ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:09.872914Z","caller":"traceutil/trace.go:171","msg":"trace[491060301] linearizableReadLoop","detail":"{readStateIndex:25593; appliedIndex:25591; }","duration":"338.757808ms","start":"2026-06-16T01:22:09.534131Z","end":"2026-06-16T01:22:09.872889Z","steps":["trace[491060301] 'read index received' (duration: 67.804577ms)","trace[491060301] 'applied index is now lower than readState.Index' (duration: 270.952251ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:09.873217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.072564ms","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-16T01:22:09.873262Z","caller":"traceutil/trace.go:171","msg":"trace[958532280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24783; }","duration":"339.158845ms","start":"2026-06-16T01:22:09.534089Z","end":"2026-06-16T01:22:09.873248Z","steps":["trace[958532280] 'agreement among raft nodes before linearized reading' (duration: 338.89022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:09.873295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:09.534079Z","time spent":"339.208605ms","remote":"127.0.0.1:51314","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":"info","ts":"2026-06-16T01:22:09.873284Z","caller":"traceutil/trace.go:171","msg":"trace[1810723103] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"352.267974ms","start":"2026-06-16T01:22:09.520979Z","end":"2026-06-16T01:22:09.873247Z","steps":["trace[1810723103] 'process raft request' (duration: 336.127008ms)","trace[1810723103] 'compare' (duration: 15.648727ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:09.873462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:09.520952Z","time spent":"352.403306ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:22:10.441512Z","caller":"traceutil/trace.go:171","msg":"trace[1495661957] linearizableReadLoop","detail":"{readStateIndex:25597; appliedIndex:25596; }","duration":"292.413328ms","start":"2026-06-16T01:22:10.149077Z","end":"2026-06-16T01:22:10.44149Z","steps":["trace[1495661957] 'read index received' (duration: 40.005629ms)","trace[1495661957] 'applied index is now lower than readState.Index' (duration: 252.406869ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:10.441714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.616352ms","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-16T01:22:10.441743Z","caller":"traceutil/trace.go:171","msg":"trace[1812502693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24789; }","duration":"292.679764ms","start":"2026-06-16T01:22:10.149055Z","end":"2026-06-16T01:22:10.441735Z","steps":["trace[1812502693] 'agreement among raft nodes before linearized reading' (duration: 292.537061ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:10.442134Z","caller":"traceutil/trace.go:171","msg":"trace[1003305008] transaction","detail":"{read_only:false; response_revision:24787; number_of_response:1; }","duration":"294.329996ms","start":"2026-06-16T01:22:10.147795Z","end":"2026-06-16T01:22:10.442125Z","steps":["trace[1003305008] 'process raft request' (duration: 293.51008ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:10.44233Z","caller":"traceutil/trace.go:171","msg":"trace[67549335] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"261.100145ms","start":"2026-06-16T01:22:10.181222Z","end":"2026-06-16T01:22:10.442322Z","steps":["trace[67549335] 'process raft request' (duration: 260.209038ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:10.44242Z","caller":"traceutil/trace.go:171","msg":"trace[1010352878] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"254.125092ms","start":"2026-06-16T01:22:10.188289Z","end":"2026-06-16T01:22:10.442414Z","steps":["trace[1010352878] 'process raft request' (duration: 253.172234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:10.745572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.013017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:10.74585Z","caller":"traceutil/trace.go:171","msg":"trace[265049671] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"278.84298ms","start":"2026-06-16T01:22:10.466984Z","end":"2026-06-16T01:22:10.745827Z","steps":["trace[265049671] 'process raft request' (duration: 278.708288ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:10.745872Z","caller":"traceutil/trace.go:171","msg":"trace[766667937] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"299.279418ms","start":"2026-06-16T01:22:10.446554Z","end":"2026-06-16T01:22:10.745834Z","steps":["trace[766667937] 'process raft request' (duration: 31.856063ms)","trace[766667937] 'compare' (duration: 266.899725ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:11.226061Z","caller":"traceutil/trace.go:171","msg":"trace[1142469125] linearizableReadLoop","detail":"{readStateIndex:25604; appliedIndex:25603; }","duration":"182.533263ms","start":"2026-06-16T01:22:11.043496Z","end":"2026-06-16T01:22:11.226029Z","steps":["trace[1142469125] 'read index received' (duration: 182.243948ms)","trace[1142469125] 'applied index is now lower than readState.Index' (duration: 287.665µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:11.226242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.717987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:22:11.226301Z","caller":"traceutil/trace.go:171","msg":"trace[70310141] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:24794; }","duration":"182.84316ms","start":"2026-06-16T01:22:11.043442Z","end":"2026-06-16T01:22:11.226285Z","steps":["trace[70310141] 'agreement among raft nodes before linearized reading' (duration: 182.705937ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:11.226301Z","caller":"traceutil/trace.go:171","msg":"trace[1685025827] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"231.481453ms","start":"2026-06-16T01:22:10.994793Z","end":"2026-06-16T01:22:11.226274Z","steps":["trace[1685025827] 'process raft request' (duration: 231.024333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:11.532127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.864859ms","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-16T01:22:11.532189Z","caller":"traceutil/trace.go:171","msg":"trace[130460012] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24795; }","duration":"114.979002ms","start":"2026-06-16T01:22:11.417196Z","end":"2026-06-16T01:22:11.532175Z","steps":["trace[130460012] 'range keys from in-memory index tree' (duration: 114.766728ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:14.679936Z","caller":"traceutil/trace.go:171","msg":"trace[1439669256] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"219.819861ms","start":"2026-06-16T01:22:14.460089Z","end":"2026-06-16T01:22:14.679909Z","steps":["trace[1439669256] 'process raft request' (duration: 219.673738ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:14.702602Z","caller":"traceutil/trace.go:171","msg":"trace[634983949] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"241.599194ms","start":"2026-06-16T01:22:14.460981Z","end":"2026-06-16T01:22:14.702581Z","steps":["trace[634983949] 'process raft request' (duration: 241.503652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:15.140855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.519169ms","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-16T01:22:15.140936Z","caller":"traceutil/trace.go:171","msg":"trace[442895973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24819; }","duration":"371.643673ms","start":"2026-06-16T01:22:14.769271Z","end":"2026-06-16T01:22:15.140915Z","steps":["trace[442895973] 'range keys from in-memory index tree' (duration: 371.354866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:15.140976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:14.769251Z","time spent":"371.717514ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T01:22:15.141869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.004554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:15.141942Z","caller":"traceutil/trace.go:171","msg":"trace[1910858357] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"371.425637ms","start":"2026-06-16T01:22:14.770503Z","end":"2026-06-16T01:22:15.141929Z","steps":["trace[1910858357] 'process raft request' (duration: 228.294711ms)","trace[1910858357] 'compare' (duration: 142.466314ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:15.141999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:14.77047Z","time spent":"371.502299ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:22:15.482381Z","caller":"traceutil/trace.go:171","msg":"trace[1734598047] linearizableReadLoop","detail":"{readStateIndex:25632; appliedIndex:25630; }","duration":"707.385093ms","start":"2026-06-16T01:22:14.77497Z","end":"2026-06-16T01:22:15.482355Z","steps":["trace[1734598047] 'read index received' (duration: 223.875998ms)","trace[1734598047] 'applied index is now lower than readState.Index' (duration: 483.507964ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:15.482476Z","caller":"traceutil/trace.go:171","msg":"trace[1656064275] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"711.102202ms","start":"2026-06-16T01:22:14.771327Z","end":"2026-06-16T01:22:15.482429Z","steps":["trace[1656064275] 'process raft request' (duration: 699.137746ms)","trace[1656064275] 'compare' (duration: 11.770442ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:15.482583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.600327ms","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-16T01:22:15.482631Z","caller":"traceutil/trace.go:171","msg":"trace[897805308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24821; }","duration":"707.690448ms","start":"2026-06-16T01:22:14.774924Z","end":"2026-06-16T01:22:15.482614Z","steps":["trace[897805308] 'agreement among raft nodes before linearized reading' (duration: 707.537394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:15.482661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:14.774906Z","time spent":"707.74832ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T01:22:15.482671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:14.771312Z","time spent":"711.246575ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:22:15.508735Z","caller":"traceutil/trace.go:171","msg":"trace[439636014] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"361.597341ms","start":"2026-06-16T01:22:15.147109Z","end":"2026-06-16T01:22:15.508706Z","steps":["trace[439636014] 'process raft request' (duration: 361.413488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:15.508862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:15.147085Z","time spent":"361.711704ms","remote":"127.0.0.1:51314","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-16T01:22:15.509283Z","caller":"traceutil/trace.go:171","msg":"trace[628147406] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"248.18927ms","start":"2026-06-16T01:22:15.261077Z","end":"2026-06-16T01:22:15.509266Z","steps":["trace[628147406] 'process raft request' (duration: 247.556908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:15.509577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.091898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:22:15.509634Z","caller":"traceutil/trace.go:171","msg":"trace[1118857385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24823; }","duration":"229.22334ms","start":"2026-06-16T01:22:15.280396Z","end":"2026-06-16T01:22:15.509619Z","steps":["trace[1118857385] 'agreement among raft nodes before linearized reading' (duration: 229.097087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:15.509992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.359119ms","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-16T01:22:15.510052Z","caller":"traceutil/trace.go:171","msg":"trace[536199694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24824; }","duration":"271.444471ms","start":"2026-06-16T01:22:15.238593Z","end":"2026-06-16T01:22:15.510038Z","steps":["trace[536199694] 'agreement among raft nodes before linearized reading' (duration: 271.255768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:15.70592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.47035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:15.706034Z","caller":"traceutil/trace.go:171","msg":"trace[1380106423] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"192.589884ms","start":"2026-06-16T01:22:15.513423Z","end":"2026-06-16T01:22:15.706012Z","steps":["trace[1380106423] 'process raft request' (duration: 85.90679ms)","trace[1380106423] 'compare' (duration: 106.182545ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:22.580036Z","caller":"traceutil/trace.go:171","msg":"trace[804372179] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"152.787499ms","start":"2026-06-16T01:22:22.427223Z","end":"2026-06-16T01:22:22.58001Z","steps":["trace[804372179] 'process raft request' (duration: 152.620336ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:22.917685Z","caller":"traceutil/trace.go:171","msg":"trace[1889336003] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"488.51573ms","start":"2026-06-16T01:22:22.429144Z","end":"2026-06-16T01:22:22.917659Z","steps":["trace[1889336003] 'process raft request' (duration: 488.204784ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:22.917834Z","caller":"traceutil/trace.go:171","msg":"trace[1404584012] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"465.441163ms","start":"2026-06-16T01:22:22.452382Z","end":"2026-06-16T01:22:22.917823Z","steps":["trace[1404584012] 'process raft request' (duration: 465.207198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:22.917845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:22.42913Z","time spent":"488.634452ms","remote":"127.0.0.1:51336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":865,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:22:22.917891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:22.452358Z","time spent":"465.505863ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:22:22.917969Z","caller":"traceutil/trace.go:171","msg":"trace[95743601] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"388.210236ms","start":"2026-06-16T01:22:22.529733Z","end":"2026-06-16T01:22:22.917944Z","steps":["trace[95743601] 'process raft request' (duration: 388.045513ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:22.917964Z","caller":"traceutil/trace.go:171","msg":"trace[1754662257] linearizableReadLoop","detail":"{readStateIndex:25689; appliedIndex:25687; }","duration":"473.464674ms","start":"2026-06-16T01:22:22.444465Z","end":"2026-06-16T01:22:22.91793Z","steps":["trace[1754662257] 'read index received' (duration: 135.46185ms)","trace[1754662257] 'applied index is now lower than readState.Index' (duration: 338.000294ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:22.918104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:22.52971Z","time spent":"388.315148ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:22:22.91823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.731078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T01:22:22.918328Z","caller":"traceutil/trace.go:171","msg":"trace[1589274292] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:24879; }","duration":"473.873382ms","start":"2026-06-16T01:22:22.444434Z","end":"2026-06-16T01:22:22.918308Z","steps":["trace[1589274292] 'agreement among raft nodes before linearized reading' (duration: 473.597446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:22.918386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:22.444427Z","time spent":"473.948753ms","remote":"127.0.0.1:51318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":92,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:22:23.154317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.902109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:23.154451Z","caller":"traceutil/trace.go:171","msg":"trace[1958788344] linearizableReadLoop","detail":"{readStateIndex:25692; appliedIndex:25691; }","duration":"236.399016ms","start":"2026-06-16T01:22:22.918032Z","end":"2026-06-16T01:22:23.154431Z","steps":["trace[1958788344] 'read index received' (duration: 100.295994ms)","trace[1958788344] 'applied index is now lower than readState.Index' (duration: 136.101262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:23.154664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.336841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18b96b1f13598e49\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-06-16T01:22:23.154706Z","caller":"traceutil/trace.go:171","msg":"trace[197557148] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18b96b1f13598e49; range_end:; response_count:1; response_revision:24880; }","duration":"571.408422ms","start":"2026-06-16T01:22:22.583282Z","end":"2026-06-16T01:22:23.154691Z","steps":["trace[197557148] 'agreement among raft nodes before linearized reading' (duration: 571.250219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:23.154744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:22.583265Z","time spent":"571.469854ms","remote":"127.0.0.1:51118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":745,"request content":"key:\"/registry/events/openstack/container-infra-registry.18b96b1f13598e49\" "} {"level":"warn","ts":"2026-06-16T01:22:23.154758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.565418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T01:22:23.154876Z","caller":"traceutil/trace.go:171","msg":"trace[1661234512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24880; }","duration":"422.743612ms","start":"2026-06-16T01:22:22.732107Z","end":"2026-06-16T01:22:23.154851Z","steps":["trace[1661234512] 'agreement among raft nodes before linearized reading' (duration: 422.372595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:23.154934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:22.732086Z","time spent":"422.837863ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T01:22:23.155022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.071892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:22:23.15506Z","caller":"traceutil/trace.go:171","msg":"trace[1213602803] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:24880; }","duration":"594.140134ms","start":"2026-06-16T01:22:22.560907Z","end":"2026-06-16T01:22:23.155048Z","steps":["trace[1213602803] 'agreement among raft nodes before linearized reading' (duration: 594.078552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:23.155091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:22.560893Z","time spent":"594.189724ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:22:23.155196Z","caller":"traceutil/trace.go:171","msg":"trace[114915261] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"449.016509ms","start":"2026-06-16T01:22:22.70589Z","end":"2026-06-16T01:22:23.154906Z","steps":["trace[114915261] 'process raft request' (duration: 312.424678ms)","trace[114915261] 'compare' (duration: 135.745996ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:23.155437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:22.705869Z","time spent":"449.462439ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:22:23.155288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.483546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:22:23.156528Z","caller":"traceutil/trace.go:171","msg":"trace[1571394034] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24880; }","duration":"200.713558ms","start":"2026-06-16T01:22:22.955794Z","end":"2026-06-16T01:22:23.156508Z","steps":["trace[1571394034] 'agreement among raft nodes before linearized reading' (duration: 199.461134ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:23.350587Z","caller":"traceutil/trace.go:171","msg":"trace[1760668950] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"181.621917ms","start":"2026-06-16T01:22:23.168942Z","end":"2026-06-16T01:22:23.350564Z","steps":["trace[1760668950] 'process raft request' (duration: 181.585906ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:23.350931Z","caller":"traceutil/trace.go:171","msg":"trace[1363026237] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"191.451012ms","start":"2026-06-16T01:22:23.159472Z","end":"2026-06-16T01:22:23.350923Z","steps":["trace[1363026237] 'process raft request' (duration: 191.009224ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:23.35104Z","caller":"traceutil/trace.go:171","msg":"trace[1945877261] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"192.744707ms","start":"2026-06-16T01:22:23.158259Z","end":"2026-06-16T01:22:23.351004Z","steps":["trace[1945877261] 'process raft request' (duration: 192.033814ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:23.352368Z","caller":"traceutil/trace.go:171","msg":"trace[1038512980] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"154.665335ms","start":"2026-06-16T01:22:23.197692Z","end":"2026-06-16T01:22:23.352357Z","steps":["trace[1038512980] 'process raft request' (duration: 154.444471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:28.341749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.761637ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-16T01:22:28.545188Z","caller":"traceutil/trace.go:171","msg":"trace[1306323127] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"168.028059ms","start":"2026-06-16T01:22:28.37714Z","end":"2026-06-16T01:22:28.545168Z","steps":["trace[1306323127] 'process raft request' (duration: 167.876686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:34.106565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.441145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:34.106762Z","caller":"traceutil/trace.go:171","msg":"trace[2079736493] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"118.845046ms","start":"2026-06-16T01:22:33.987896Z","end":"2026-06-16T01:22:34.106741Z","steps":["trace[2079736493] 'process raft request' (duration: 118.781914ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:34.107001Z","caller":"traceutil/trace.go:171","msg":"trace[55688792] linearizableReadLoop","detail":"{readStateIndex:25848; appliedIndex:25847; }","duration":"120.873823ms","start":"2026-06-16T01:22:33.986117Z","end":"2026-06-16T01:22:34.106991Z","steps":["trace[55688792] 'read index received' (duration: 50.161µs)","trace[55688792] 'applied index is now lower than readState.Index' (duration: 120.822652ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:34.107131Z","caller":"traceutil/trace.go:171","msg":"trace[1605498947] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"147.778434ms","start":"2026-06-16T01:22:33.959301Z","end":"2026-06-16T01:22:34.10708Z","steps":["trace[1605498947] 'process raft request' (duration: 25.714638ms)","trace[1605498947] 'compare' (duration: 121.324192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:34.107179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.067078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-16T01:22:34.10722Z","caller":"traceutil/trace.go:171","msg":"trace[1444657497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25034; }","duration":"121.155569ms","start":"2026-06-16T01:22:33.986055Z","end":"2026-06-16T01:22:34.107211Z","steps":["trace[1444657497] 'agreement among raft nodes before linearized reading' (duration: 120.979345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:36.685775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.519941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T01:22:36.685864Z","caller":"traceutil/trace.go:171","msg":"trace[392431767] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25060; }","duration":"164.669364ms","start":"2026-06-16T01:22:36.521176Z","end":"2026-06-16T01:22:36.685845Z","steps":["trace[392431767] 'count revisions from in-memory index tree' (duration: 164.402629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:36.685926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.016243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-16T01:22:36.686017Z","caller":"traceutil/trace.go:171","msg":"trace[319538992] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25060; }","duration":"124.166025ms","start":"2026-06-16T01:22:36.561829Z","end":"2026-06-16T01:22:36.685995Z","steps":["trace[319538992] 'range keys from in-memory index tree' (duration: 123.826248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:36.686066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.2486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-06-16T01:22:36.686151Z","caller":"traceutil/trace.go:171","msg":"trace[1307536341] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25060; }","duration":"140.395913ms","start":"2026-06-16T01:22:36.545736Z","end":"2026-06-16T01:22:36.686132Z","steps":["trace[1307536341] 'range keys from in-memory index tree' (duration: 140.036247ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:36.875562Z","caller":"traceutil/trace.go:171","msg":"trace[1943458882] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"180.142739ms","start":"2026-06-16T01:22:36.695393Z","end":"2026-06-16T01:22:36.875536Z","steps":["trace[1943458882] 'process raft request' (duration: 179.963056ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:55.735755Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065bd.wal"} {"level":"warn","ts":"2026-06-16T01:22:59.886111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.060116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:22:59.88622Z","caller":"traceutil/trace.go:171","msg":"trace[1765906953] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"177.244373ms","start":"2026-06-16T01:22:59.708956Z","end":"2026-06-16T01:22:59.8862Z","steps":["trace[1765906953] 'process raft request' (duration: 65.016604ms)","trace[1765906953] 'compare' (duration: 111.75248ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:59.892455Z","caller":"traceutil/trace.go:171","msg":"trace[1514962938] linearizableReadLoop","detail":"{readStateIndex:26075; appliedIndex:26073; }","duration":"129.776333ms","start":"2026-06-16T01:22:59.762663Z","end":"2026-06-16T01:22:59.892439Z","steps":["trace[1514962938] 'read index received' (duration: 11.323225ms)","trace[1514962938] 'applied index is now lower than readState.Index' (duration: 118.452288ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:22:59.89258Z","caller":"traceutil/trace.go:171","msg":"trace[1741878096] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"182.793129ms","start":"2026-06-16T01:22:59.709759Z","end":"2026-06-16T01:22:59.892552Z","steps":["trace[1741878096] 'process raft request' (duration: 182.569685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:59.892691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.012487ms","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-16T01:22:59.892714Z","caller":"traceutil/trace.go:171","msg":"trace[46072001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25254; }","duration":"130.082728ms","start":"2026-06-16T01:22:59.762625Z","end":"2026-06-16T01:22:59.892708Z","steps":["trace[46072001] 'agreement among raft nodes before linearized reading' (duration: 129.907635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:59.892721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.999731ms","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-16T01:22:59.892777Z","caller":"traceutil/trace.go:171","msg":"trace[567411655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25254; }","duration":"126.110563ms","start":"2026-06-16T01:22:59.766654Z","end":"2026-06-16T01:22:59.892764Z","steps":["trace[567411655] 'agreement among raft nodes before linearized reading' (duration: 125.903429ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:00.096284Z","caller":"traceutil/trace.go:171","msg":"trace[493795319] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"199.615407ms","start":"2026-06-16T01:22:59.89665Z","end":"2026-06-16T01:23:00.096265Z","steps":["trace[493795319] 'process raft request' (duration: 199.490355ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:00.096692Z","caller":"traceutil/trace.go:171","msg":"trace[1846379111] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"196.927707ms","start":"2026-06-16T01:22:59.899743Z","end":"2026-06-16T01:23:00.09667Z","steps":["trace[1846379111] 'process raft request' (duration: 196.494618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:00.352554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.702573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:23:00.352774Z","caller":"traceutil/trace.go:171","msg":"trace[243636363] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"159.63794ms","start":"2026-06-16T01:23:00.19312Z","end":"2026-06-16T01:23:00.352758Z","steps":["trace[243636363] 'process raft request' (duration: 159.590649ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:00.352779Z","caller":"traceutil/trace.go:171","msg":"trace[1142001297] linearizableReadLoop","detail":"{readStateIndex:26079; appliedIndex:26078; }","duration":"221.952302ms","start":"2026-06-16T01:23:00.130803Z","end":"2026-06-16T01:23:00.352755Z","steps":["trace[1142001297] 'read index received' (duration: 93.975043ms)","trace[1142001297] 'applied index is now lower than readState.Index' (duration: 127.975089ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:00.352842Z","caller":"traceutil/trace.go:171","msg":"trace[1627560147] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"162.266299ms","start":"2026-06-16T01:23:00.190553Z","end":"2026-06-16T01:23:00.35282Z","steps":["trace[1627560147] 'process raft request' (duration: 162.101856ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:00.352836Z","caller":"traceutil/trace.go:171","msg":"trace[655837271] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"252.167955ms","start":"2026-06-16T01:23:00.100633Z","end":"2026-06-16T01:23:00.352801Z","steps":["trace[655837271] 'process raft request' (duration: 124.137005ms)","trace[655837271] 'compare' (duration: 127.416737ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:00.353004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.675252ms","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-16T01:23:00.353048Z","caller":"traceutil/trace.go:171","msg":"trace[1030056425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25260; }","duration":"127.753804ms","start":"2026-06-16T01:23:00.225284Z","end":"2026-06-16T01:23:00.353038Z","steps":["trace[1030056425] 'agreement among raft nodes before linearized reading' (duration: 127.584041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:00.353038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.191717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T01:23:00.353136Z","caller":"traceutil/trace.go:171","msg":"trace[1300697604] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25260; }","duration":"222.343269ms","start":"2026-06-16T01:23:00.130774Z","end":"2026-06-16T01:23:00.353117Z","steps":["trace[1300697604] 'agreement among raft nodes before linearized reading' (duration: 222.071574ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:00.617909Z","caller":"traceutil/trace.go:171","msg":"trace[946882088] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"260.380251ms","start":"2026-06-16T01:23:00.357499Z","end":"2026-06-16T01:23:00.617879Z","steps":["trace[946882088] 'process raft request' (duration: 184.542962ms)","trace[946882088] 'compare' (duration: 75.690336ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:06.208863Z","caller":"traceutil/trace.go:171","msg":"trace[1067157999] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"142.057205ms","start":"2026-06-16T01:23:06.066785Z","end":"2026-06-16T01:23:06.208842Z","steps":["trace[1067157999] 'process raft request' (duration: 141.890222ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:06.403854Z","caller":"traceutil/trace.go:171","msg":"trace[673566783] linearizableReadLoop","detail":"{readStateIndex:26131; appliedIndex:26130; }","duration":"286.962075ms","start":"2026-06-16T01:23:06.116862Z","end":"2026-06-16T01:23:06.403824Z","steps":["trace[673566783] 'read index received' (duration: 91.960285ms)","trace[673566783] 'applied index is now lower than readState.Index' (duration: 194.99993ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:06.403887Z","caller":"traceutil/trace.go:171","msg":"trace[146849123] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"290.226916ms","start":"2026-06-16T01:23:06.113628Z","end":"2026-06-16T01:23:06.403854Z","steps":["trace[146849123] 'process raft request' (duration: 219.049927ms)","trace[146849123] 'compare' (duration: 70.998666ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:06.404227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.337623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-16T01:23:06.404293Z","caller":"traceutil/trace.go:171","msg":"trace[130643082] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25309; }","duration":"287.449525ms","start":"2026-06-16T01:23:06.116825Z","end":"2026-06-16T01:23:06.404275Z","steps":["trace[130643082] 'agreement among raft nodes before linearized reading' (duration: 287.147359ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:06.473648Z","caller":"traceutil/trace.go:171","msg":"trace[824926041] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"260.680156ms","start":"2026-06-16T01:23:06.212918Z","end":"2026-06-16T01:23:06.473599Z","steps":["trace[824926041] 'process raft request' (duration: 260.421421ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:06.473806Z","caller":"traceutil/trace.go:171","msg":"trace[5035533] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"260.479863ms","start":"2026-06-16T01:23:06.21327Z","end":"2026-06-16T01:23:06.47375Z","steps":["trace[5035533] 'process raft request' (duration: 260.205788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:06.479289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.412004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:23:06.479347Z","caller":"traceutil/trace.go:171","msg":"trace[133308129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25314; }","duration":"198.523476ms","start":"2026-06-16T01:23:06.280809Z","end":"2026-06-16T01:23:06.479332Z","steps":["trace[133308129] 'agreement among raft nodes before linearized reading' (duration: 198.398624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:06.47939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.296474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-16T01:23:06.479424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.531641ms","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-16T01:23:06.479474Z","caller":"traceutil/trace.go:171","msg":"trace[1422644665] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25314; }","duration":"109.448957ms","start":"2026-06-16T01:23:06.370006Z","end":"2026-06-16T01:23:06.479455Z","steps":["trace[1422644665] 'agreement among raft nodes before linearized reading' (duration: 109.308674ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:06.479801Z","caller":"traceutil/trace.go:171","msg":"trace[780859915] 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:25314; }","duration":"261.656914ms","start":"2026-06-16T01:23:06.217831Z","end":"2026-06-16T01:23:06.479488Z","steps":["trace[780859915] 'agreement among raft nodes before linearized reading' (duration: 261.517642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:06.479215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.534377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-06-16T01:23:06.479926Z","caller":"traceutil/trace.go:171","msg":"trace[1049697983] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:25314; }","duration":"267.275112ms","start":"2026-06-16T01:23:06.212631Z","end":"2026-06-16T01:23:06.479906Z","steps":["trace[1049697983] 'agreement among raft nodes before linearized reading' (duration: 266.489017ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:06.648136Z","caller":"traceutil/trace.go:171","msg":"trace[764311069] linearizableReadLoop","detail":"{readStateIndex:26137; appliedIndex:26136; }","duration":"162.635926ms","start":"2026-06-16T01:23:06.485476Z","end":"2026-06-16T01:23:06.648112Z","steps":["trace[764311069] 'read index received' (duration: 153.431381ms)","trace[764311069] 'applied index is now lower than readState.Index' (duration: 9.203645ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:06.648405Z","caller":"traceutil/trace.go:171","msg":"trace[1126860177] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"163.297438ms","start":"2026-06-16T01:23:06.485073Z","end":"2026-06-16T01:23:06.648371Z","steps":["trace[1126860177] 'process raft request' (duration: 153.732787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:06.648517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.011332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:23:06.648608Z","caller":"traceutil/trace.go:171","msg":"trace[82035336] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25315; }","duration":"163.131974ms","start":"2026-06-16T01:23:06.485454Z","end":"2026-06-16T01:23:06.648586Z","steps":["trace[82035336] 'agreement among raft nodes before linearized reading' (duration: 162.937931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:06.867116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.625503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:23:06.867475Z","caller":"traceutil/trace.go:171","msg":"trace[191847861] linearizableReadLoop","detail":"{readStateIndex:26139; appliedIndex:26137; }","duration":"219.21697ms","start":"2026-06-16T01:23:06.648234Z","end":"2026-06-16T01:23:06.867451Z","steps":["trace[191847861] 'read index received' (duration: 111.078597ms)","trace[191847861] 'applied index is now lower than readState.Index' (duration: 108.136743ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:06.867514Z","caller":"traceutil/trace.go:171","msg":"trace[238903640] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"230.712767ms","start":"2026-06-16T01:23:06.636773Z","end":"2026-06-16T01:23:06.867486Z","steps":["trace[238903640] 'process raft request' (duration: 230.441572ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:06.867587Z","caller":"traceutil/trace.go:171","msg":"trace[1233318992] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"375.488426ms","start":"2026-06-16T01:23:06.492061Z","end":"2026-06-16T01:23:06.86755Z","steps":["trace[1233318992] 'process raft request' (duration: 267.330873ms)","trace[1233318992] 'compare' (duration: 107.427208ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:06.867723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.067067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-16T01:23:06.867778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:23:06.492041Z","time spent":"375.636558ms","remote":"127.0.0.1:51528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:23:06.867767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.977441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:23:06.867801Z","caller":"traceutil/trace.go:171","msg":"trace[1834858115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25317; }","duration":"228.19784ms","start":"2026-06-16T01:23:06.639582Z","end":"2026-06-16T01:23:06.86778Z","steps":["trace[1834858115] 'agreement among raft nodes before linearized reading' (duration: 227.992836ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:06.867887Z","caller":"traceutil/trace.go:171","msg":"trace[567245997] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25317; }","duration":"213.098093ms","start":"2026-06-16T01:23:06.654744Z","end":"2026-06-16T01:23:06.867843Z","steps":["trace[567245997] 'agreement among raft nodes before linearized reading' (duration: 212.886889ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:07.091472Z","caller":"traceutil/trace.go:171","msg":"trace[32774999] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"146.674862ms","start":"2026-06-16T01:23:06.944758Z","end":"2026-06-16T01:23:07.091432Z","steps":["trace[32774999] 'process raft request' (duration: 130.933513ms)","trace[32774999] 'compare' (duration: 15.28011ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:07.848719Z","caller":"traceutil/trace.go:171","msg":"trace[1213793821] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"114.290408ms","start":"2026-06-16T01:23:07.734393Z","end":"2026-06-16T01:23:07.848684Z","steps":["trace[1213793821] 'process raft request' (duration: 38.455969ms)","trace[1213793821] 'compare' (duration: 75.470632ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:08.062385Z","caller":"traceutil/trace.go:171","msg":"trace[1547875712] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"143.297329ms","start":"2026-06-16T01:23:07.919055Z","end":"2026-06-16T01:23:08.062353Z","steps":["trace[1547875712] 'process raft request' (duration: 78.06886ms)","trace[1547875712] 'compare' (duration: 65.051145ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:08.28854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.522321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:23:08.288622Z","caller":"traceutil/trace.go:171","msg":"trace[1632622935] linearizableReadLoop","detail":"{readStateIndex:26147; appliedIndex:26146; }","duration":"255.867556ms","start":"2026-06-16T01:23:08.032741Z","end":"2026-06-16T01:23:08.288609Z","steps":["trace[1632622935] 'read index received' (duration: 140.16966ms)","trace[1632622935] 'applied index is now lower than readState.Index' (duration: 115.695096ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:08.28869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.952247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:23:08.288708Z","caller":"traceutil/trace.go:171","msg":"trace[875312724] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25325; }","duration":"256.004418ms","start":"2026-06-16T01:23:08.032696Z","end":"2026-06-16T01:23:08.288701Z","steps":["trace[875312724] 'agreement among raft nodes before linearized reading' (duration: 255.964287ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:08.288735Z","caller":"traceutil/trace.go:171","msg":"trace[1395086076] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"316.407444ms","start":"2026-06-16T01:23:07.97227Z","end":"2026-06-16T01:23:08.288677Z","steps":["trace[1395086076] 'process raft request' (duration: 200.681978ms)","trace[1395086076] 'compare' (duration: 115.43551ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:08.288959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:23:07.97224Z","time spent":"316.595097ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:23:08.296973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.825423ms","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-16T01:23:08.297019Z","caller":"traceutil/trace.go:171","msg":"trace[1075746755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25325; }","duration":"246.904905ms","start":"2026-06-16T01:23:08.050103Z","end":"2026-06-16T01:23:08.297008Z","steps":["trace[1075746755] 'agreement among raft nodes before linearized reading' (duration: 246.773072ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:08.75464Z","caller":"traceutil/trace.go:171","msg":"trace[61833175] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"268.422702ms","start":"2026-06-16T01:23:08.486183Z","end":"2026-06-16T01:23:08.754605Z","steps":["trace[61833175] 'process raft request' (duration: 171.143247ms)","trace[61833175] 'compare' (duration: 97.041831ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:08.754819Z","caller":"traceutil/trace.go:171","msg":"trace[1681266398] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"265.141941ms","start":"2026-06-16T01:23:08.489653Z","end":"2026-06-16T01:23:08.754795Z","steps":["trace[1681266398] 'process raft request' (duration: 264.874546ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:08.754924Z","caller":"traceutil/trace.go:171","msg":"trace[462150714] linearizableReadLoop","detail":"{readStateIndex:26153; appliedIndex:26151; }","duration":"160.841301ms","start":"2026-06-16T01:23:08.594054Z","end":"2026-06-16T01:23:08.754895Z","steps":["trace[462150714] 'read index received' (duration: 63.186318ms)","trace[462150714] 'applied index is now lower than readState.Index' (duration: 97.651363ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:08.755145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.471225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T01:23:08.755175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.110617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-16T01:23:08.755211Z","caller":"traceutil/trace.go:171","msg":"trace[1917543196] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25330; }","duration":"161.186788ms","start":"2026-06-16T01:23:08.594014Z","end":"2026-06-16T01:23:08.7552Z","steps":["trace[1917543196] 'agreement among raft nodes before linearized reading' (duration: 160.996894ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:08.755212Z","caller":"traceutil/trace.go:171","msg":"trace[264998146] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25330; }","duration":"121.575887ms","start":"2026-06-16T01:23:08.633617Z","end":"2026-06-16T01:23:08.755192Z","steps":["trace[264998146] 'agreement among raft nodes before linearized reading' (duration: 121.451415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:08.755228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.76318ms","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-16T01:23:08.755291Z","caller":"traceutil/trace.go:171","msg":"trace[1565206626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25330; }","duration":"102.880632ms","start":"2026-06-16T01:23:08.652394Z","end":"2026-06-16T01:23:08.755275Z","steps":["trace[1565206626] 'agreement among raft nodes before linearized reading' (duration: 102.725589ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:10.116743Z","caller":"traceutil/trace.go:171","msg":"trace[351391956] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"184.958059ms","start":"2026-06-16T01:23:09.931756Z","end":"2026-06-16T01:23:10.116714Z","steps":["trace[351391956] 'process raft request' (duration: 99.598829ms)","trace[351391956] 'compare' (duration: 85.154126ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:31.232237Z","caller":"traceutil/trace.go:171","msg":"trace[745900943] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"141.78656ms","start":"2026-06-16T01:23:31.090391Z","end":"2026-06-16T01:23:31.232178Z","steps":["trace[745900943] 'process raft request' (duration: 131.397763ms)","trace[745900943] 'compare' (duration: 10.174853ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:31.511288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.320327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:23:31.5114Z","caller":"traceutil/trace.go:171","msg":"trace[1208309195] linearizableReadLoop","detail":"{readStateIndex:26320; appliedIndex:26319; }","duration":"229.931664ms","start":"2026-06-16T01:23:31.281451Z","end":"2026-06-16T01:23:31.511383Z","steps":["trace[1208309195] 'read index received' (duration: 82.385404ms)","trace[1208309195] 'applied index is now lower than readState.Index' (duration: 147.54502ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:31.511473Z","caller":"traceutil/trace.go:171","msg":"trace[831401749] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"276.968976ms","start":"2026-06-16T01:23:31.234493Z","end":"2026-06-16T01:23:31.511462Z","steps":["trace[831401749] 'process raft request' (duration: 129.379625ms)","trace[831401749] 'compare' (duration: 147.183172ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:23:31.511809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.334382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:23:31.511898Z","caller":"traceutil/trace.go:171","msg":"trace[663445770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25493; }","duration":"230.486024ms","start":"2026-06-16T01:23:31.28139Z","end":"2026-06-16T01:23:31.511876Z","steps":["trace[663445770] 'agreement among raft nodes before linearized reading' (duration: 230.25512ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:31.709664Z","caller":"traceutil/trace.go:171","msg":"trace[583929585] linearizableReadLoop","detail":"{readStateIndex:26321; appliedIndex:26320; }","duration":"198.20806ms","start":"2026-06-16T01:23:31.511427Z","end":"2026-06-16T01:23:31.709635Z","steps":["trace[583929585] 'read index received' (duration: 195.748284ms)","trace[583929585] 'applied index is now lower than readState.Index' (duration: 2.458146ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:23:31.709702Z","caller":"traceutil/trace.go:171","msg":"trace[1211136620] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"224.796695ms","start":"2026-06-16T01:23:31.484868Z","end":"2026-06-16T01:23:31.709664Z","steps":["trace[1211136620] 'process raft request' (duration: 222.37365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:31.709859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.28672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:23:31.709948Z","caller":"traceutil/trace.go:171","msg":"trace[750113277] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25494; }","duration":"404.451283ms","start":"2026-06-16T01:23:31.305475Z","end":"2026-06-16T01:23:31.709926Z","steps":["trace[750113277] 'agreement among raft nodes before linearized reading' (duration: 404.28402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:23:31.709997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:23:31.305443Z","time spent":"404.542335ms","remote":"127.0.0.1:60756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:23:31.709975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.951487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:23:31.71018Z","caller":"traceutil/trace.go:171","msg":"trace[2076594629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25494; }","duration":"197.139091ms","start":"2026-06-16T01:23:31.512926Z","end":"2026-06-16T01:23:31.710065Z","steps":["trace[2076594629] 'agreement among raft nodes before linearized reading' (duration: 196.914516ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:23:35.937185Z","caller":"traceutil/trace.go:171","msg":"trace[1672008947] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"159.411425ms","start":"2026-06-16T01:23:35.777685Z","end":"2026-06-16T01:23:35.937097Z","steps":["trace[1672008947] 'process raft request' (duration: 159.088908ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:24:29.927887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23162} {"level":"info","ts":"2026-06-16T01:24:30.06031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23162,"took":"128.376985ms","hash":4086363018,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":35364864,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-16T01:24:30.060448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4086363018,"revision":23162,"compact-revision":20113} {"level":"warn","ts":"2026-06-16T01:25:08.900689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.048454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T01:25:08.900795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.345148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:08.900813Z","caller":"traceutil/trace.go:171","msg":"trace[1493159412] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26512; }","duration":"231.211897ms","start":"2026-06-16T01:25:08.669572Z","end":"2026-06-16T01:25:08.900784Z","steps":["trace[1493159412] 'count revisions from in-memory index tree' (duration: 230.971783ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:08.90085Z","caller":"traceutil/trace.go:171","msg":"trace[1931131479] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26512; }","duration":"105.42986ms","start":"2026-06-16T01:25:08.795405Z","end":"2026-06-16T01:25:08.900835Z","steps":["trace[1931131479] 'range keys from in-memory index tree' (duration: 105.280777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:08.900854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.72824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21430"} {"level":"info","ts":"2026-06-16T01:25:08.900957Z","caller":"traceutil/trace.go:171","msg":"trace[1061719308] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26512; }","duration":"101.853213ms","start":"2026-06-16T01:25:08.799086Z","end":"2026-06-16T01:25:08.900939Z","steps":["trace[1061719308] 'range keys from in-memory index tree' (duration: 101.674169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:08.901102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.429993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:08.901186Z","caller":"traceutil/trace.go:171","msg":"trace[402336134] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26512; }","duration":"103.542084ms","start":"2026-06-16T01:25:08.797627Z","end":"2026-06-16T01:25:08.901169Z","steps":["trace[402336134] 'range keys from in-memory index tree' (duration: 103.371292ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:09.084492Z","caller":"traceutil/trace.go:171","msg":"trace[1493838730] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"146.589912ms","start":"2026-06-16T01:25:08.937869Z","end":"2026-06-16T01:25:09.084459Z","steps":["trace[1493838730] 'process raft request' (duration: 146.383428ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:09.29574Z","caller":"traceutil/trace.go:171","msg":"trace[2024777629] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"109.703611ms","start":"2026-06-16T01:25:09.186017Z","end":"2026-06-16T01:25:09.295721Z","steps":["trace[2024777629] 'process raft request' (duration: 109.6515ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:09.295874Z","caller":"traceutil/trace.go:171","msg":"trace[1926555260] transaction","detail":"{read_only:false; response_revision:26514; number_of_response:1; }","duration":"357.196467ms","start":"2026-06-16T01:25:08.93864Z","end":"2026-06-16T01:25:09.295837Z","steps":["trace[1926555260] 'process raft request' (duration: 282.646903ms)","trace[1926555260] 'compare' (duration: 73.382882ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:25:09.29597Z","caller":"traceutil/trace.go:171","msg":"trace[1819638733] linearizableReadLoop","detail":"{readStateIndex:27365; appliedIndex:27363; }","duration":"337.511975ms","start":"2026-06-16T01:25:08.958445Z","end":"2026-06-16T01:25:09.295957Z","steps":["trace[1819638733] 'read index received' (duration: 125.892989ms)","trace[1819638733] 'applied index is now lower than readState.Index' (duration: 211.617596ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:25:09.296016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:08.938621Z","time spent":"357.32298ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:25:09.296264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.80983ms","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-16T01:25:09.296319Z","caller":"traceutil/trace.go:171","msg":"trace[1705033053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26515; }","duration":"337.939482ms","start":"2026-06-16T01:25:08.958367Z","end":"2026-06-16T01:25:09.296306Z","steps":["trace[1705033053] 'agreement among raft nodes before linearized reading' (duration: 337.743208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:09.296497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:08.958338Z","time spent":"338.140996ms","remote":"127.0.0.1:51314","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-16T01:25:09.532892Z","caller":"traceutil/trace.go:171","msg":"trace[86310005] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"138.055769ms","start":"2026-06-16T01:25:09.394785Z","end":"2026-06-16T01:25:09.532841Z","steps":["trace[86310005] 'process raft request' (duration: 137.993068ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:09.533492Z","caller":"traceutil/trace.go:171","msg":"trace[763289665] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"231.927221ms","start":"2026-06-16T01:25:09.301529Z","end":"2026-06-16T01:25:09.533456Z","steps":["trace[763289665] 'process raft request' (duration: 187.570099ms)","trace[763289665] 'compare' (duration: 43.346743ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:25:09.53363Z","caller":"traceutil/trace.go:171","msg":"trace[1600466449] linearizableReadLoop","detail":"{readStateIndex:27367; appliedIndex:27366; }","duration":"162.958412ms","start":"2026-06-16T01:25:09.370654Z","end":"2026-06-16T01:25:09.533612Z","steps":["trace[1600466449] 'read index received' (duration: 118.366716ms)","trace[1600466449] 'applied index is now lower than readState.Index' (duration: 44.589586ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:25:09.533784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.111476ms","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-16T01:25:09.53417Z","caller":"traceutil/trace.go:171","msg":"trace[1487272808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26517; }","duration":"163.529843ms","start":"2026-06-16T01:25:09.370609Z","end":"2026-06-16T01:25:09.534139Z","steps":["trace[1487272808] 'agreement among raft nodes before linearized reading' (duration: 163.052144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:09.534399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.878108ms","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-16T01:25:09.534513Z","caller":"traceutil/trace.go:171","msg":"trace[93121162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26517; }","duration":"107.0303ms","start":"2026-06-16T01:25:09.427464Z","end":"2026-06-16T01:25:09.534495Z","steps":["trace[93121162] 'agreement among raft nodes before linearized reading' (duration: 106.777076ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:09.882854Z","caller":"traceutil/trace.go:171","msg":"trace[1389469115] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"343.503427ms","start":"2026-06-16T01:25:09.539334Z","end":"2026-06-16T01:25:09.882838Z","steps":["trace[1389469115] 'process raft request' (duration: 343.400895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:09.882964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:09.539315Z","time spent":"343.591428ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:25:10.59153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.605145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:25:10.59169Z","caller":"traceutil/trace.go:171","msg":"trace[583748211] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"522.089757ms","start":"2026-06-16T01:25:10.069585Z","end":"2026-06-16T01:25:10.591674Z","steps":["trace[583748211] 'process raft request' (duration: 522.033216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.591753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.069568Z","time spent":"522.155938ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:25:10.591768Z","caller":"traceutil/trace.go:171","msg":"trace[1829505170] linearizableReadLoop","detail":"{readStateIndex:27370; appliedIndex:27368; }","duration":"847.42374ms","start":"2026-06-16T01:25:09.744313Z","end":"2026-06-16T01:25:10.591736Z","steps":["trace[1829505170] 'read index received' (duration: 138.375525ms)","trace[1829505170] 'applied index is now lower than readState.Index' (duration: 709.046495ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:25:10.591851Z","caller":"traceutil/trace.go:171","msg":"trace[1028383548] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"1.051698837s","start":"2026-06-16T01:25:09.540076Z","end":"2026-06-16T01:25:10.591775Z","steps":["trace[1028383548] 'process raft request' (duration: 615.232485ms)","trace[1028383548] 'compare' (duration: 435.231528ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:25:10.591928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.613674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-16T01:25:10.592067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:09.540058Z","time spent":"1.051937971s","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:25:10.591961Z","caller":"traceutil/trace.go:171","msg":"trace[231465564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26520; }","duration":"847.696815ms","start":"2026-06-16T01:25:09.744257Z","end":"2026-06-16T01:25:10.591954Z","steps":["trace[231465564] 'agreement among raft nodes before linearized reading' (duration: 847.547792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.592178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:09.744236Z","time spent":"847.93336ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T01:25:10.859485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.106072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:25:10.859638Z","caller":"traceutil/trace.go:171","msg":"trace[202703256] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"596.896266ms","start":"2026-06-16T01:25:10.262715Z","end":"2026-06-16T01:25:10.859611Z","steps":["trace[202703256] 'process raft request' (duration: 430.548899ms)","trace[202703256] 'compare' (duration: 165.920829ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:25:10.859734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.26269Z","time spent":"596.999307ms","remote":"127.0.0.1:51314","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-16T01:25:10.874596Z","caller":"traceutil/trace.go:171","msg":"trace[523238502] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"334.613239ms","start":"2026-06-16T01:25:10.539954Z","end":"2026-06-16T01:25:10.874567Z","steps":["trace[523238502] 'process raft request' (duration: 334.378014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.874774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.539934Z","time spent":"334.739331ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:25:10.874808Z","caller":"traceutil/trace.go:171","msg":"trace[871071302] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"232.125185ms","start":"2026-06-16T01:25:10.642661Z","end":"2026-06-16T01:25:10.874786Z","steps":["trace[871071302] 'process raft request' (duration: 231.87928ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:10.874985Z","caller":"traceutil/trace.go:171","msg":"trace[1371369608] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"279.927962ms","start":"2026-06-16T01:25:10.595023Z","end":"2026-06-16T01:25:10.874951Z","steps":["trace[1371369608] 'process raft request' (duration: 279.483393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.875048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040967422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1115"} {"level":"warn","ts":"2026-06-16T01:25:10.875044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.865179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T01:25:10.875091Z","caller":"traceutil/trace.go:171","msg":"trace[1493988387] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:26525; }","duration":"1.041065964s","start":"2026-06-16T01:25:09.834011Z","end":"2026-06-16T01:25:10.875077Z","steps":["trace[1493988387] 'agreement among raft nodes before linearized reading' (duration: 1.040973792s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.875109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.417287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:10.875113Z","caller":"traceutil/trace.go:171","msg":"trace[1338553623] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26525; }","duration":"904.978001ms","start":"2026-06-16T01:25:09.970114Z","end":"2026-06-16T01:25:10.875092Z","steps":["trace[1338553623] 'agreement among raft nodes before linearized reading' (duration: 904.869939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.87514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:09.833989Z","time spent":"1.041142046s","remote":"127.0.0.1:51244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1138,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" "} {"level":"info","ts":"2026-06-16T01:25:10.875147Z","caller":"traceutil/trace.go:171","msg":"trace[1412125553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26525; }","duration":"596.467848ms","start":"2026-06-16T01:25:10.278667Z","end":"2026-06-16T01:25:10.875135Z","steps":["trace[1412125553] 'agreement among raft nodes before linearized reading' (duration: 596.420297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.875169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.698183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-06-16T01:25:10.875192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.278653Z","time spent":"596.532749ms","remote":"127.0.0.1:51042","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-16T01:25:10.875228Z","caller":"traceutil/trace.go:171","msg":"trace[1915873157] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:26525; }","duration":"839.839286ms","start":"2026-06-16T01:25:10.035376Z","end":"2026-06-16T01:25:10.875215Z","steps":["trace[1915873157] 'agreement among raft nodes before linearized reading' (duration: 839.568251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.875275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.596706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:10.875325Z","caller":"traceutil/trace.go:171","msg":"trace[128327079] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26525; }","duration":"824.685648ms","start":"2026-06-16T01:25:10.050624Z","end":"2026-06-16T01:25:10.87531Z","steps":["trace[128327079] 'agreement among raft nodes before linearized reading' (duration: 824.591216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.874814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079787278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-06-16T01:25:10.875371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.05061Z","time spent":"824.751149ms","remote":"127.0.0.1:59280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:25:10.875401Z","caller":"traceutil/trace.go:171","msg":"trace[843168831] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26525; }","duration":"1.08042893s","start":"2026-06-16T01:25:09.794955Z","end":"2026-06-16T01:25:10.875384Z","steps":["trace[843168831] 'agreement among raft nodes before linearized reading' (duration: 1.079701097s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.875471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:09.794932Z","time spent":"1.080516602s","remote":"127.0.0.1:51226","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-16T01:25:10.875068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.399016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T01:25:10.875588Z","caller":"traceutil/trace.go:171","msg":"trace[1723897320] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:26525; }","duration":"332.954287ms","start":"2026-06-16T01:25:10.542622Z","end":"2026-06-16T01:25:10.875576Z","steps":["trace[1723897320] 'agreement among raft nodes before linearized reading' (duration: 332.357006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.875633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.542602Z","time spent":"333.019898ms","remote":"127.0.0.1:51568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":60,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:25:10.874865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.443008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21430"} {"level":"info","ts":"2026-06-16T01:25:10.875743Z","caller":"traceutil/trace.go:171","msg":"trace[1875524882] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26525; }","duration":"714.352275ms","start":"2026-06-16T01:25:10.161375Z","end":"2026-06-16T01:25:10.875728Z","steps":["trace[1875524882] 'agreement among raft nodes before linearized reading' (duration: 713.325246ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.874917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.359859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T01:25:10.875827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.161359Z","time spent":"714.451086ms","remote":"127.0.0.1:51288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":21453,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-06-16T01:25:10.875863Z","caller":"traceutil/trace.go:171","msg":"trace[1747744991] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26525; }","duration":"721.333048ms","start":"2026-06-16T01:25:10.154513Z","end":"2026-06-16T01:25:10.875846Z","steps":["trace[1747744991] 'agreement among raft nodes before linearized reading' (duration: 720.338349ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:10.87486Z","caller":"traceutil/trace.go:171","msg":"trace[1394753080] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"329.954861ms","start":"2026-06-16T01:25:10.544861Z","end":"2026-06-16T01:25:10.874816Z","steps":["trace[1394753080] 'process raft request' (duration: 329.601745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.876037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.544847Z","time spent":"331.118853ms","remote":"127.0.0.1:51314","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-16T01:25:10.876044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.035354Z","time spent":"840.52957ms","remote":"127.0.0.1:51244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":252,"response size":32,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:25:10.874957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.872707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T01:25:10.87619Z","caller":"traceutil/trace.go:171","msg":"trace[1635873758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26525; }","duration":"294.120391ms","start":"2026-06-16T01:25:10.582051Z","end":"2026-06-16T01:25:10.876172Z","steps":["trace[1635873758] 'agreement among raft nodes before linearized reading' (duration: 292.848947ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:10.874605Z","caller":"traceutil/trace.go:171","msg":"trace[1307442] linearizableReadLoop","detail":"{readStateIndex:27374; appliedIndex:27371; }","duration":"282.704354ms","start":"2026-06-16T01:25:10.591876Z","end":"2026-06-16T01:25:10.87458Z","steps":["trace[1307442] 'read index received' (duration: 101.385913ms)","trace[1307442] 'applied index is now lower than readState.Index' (duration: 181.317481ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:25:10.874967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.578074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:10.876416Z","caller":"traceutil/trace.go:171","msg":"trace[293257013] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26525; }","duration":"761.017601ms","start":"2026-06-16T01:25:10.115375Z","end":"2026-06-16T01:25:10.876392Z","steps":["trace[293257013] 'agreement among raft nodes before linearized reading' (duration: 759.563263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.875178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:09.97009Z","time spent":"905.070273ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:25:10.876507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.115305Z","time spent":"761.177234ms","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:25:10.876435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.047552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T01:25:10.876791Z","caller":"traceutil/trace.go:171","msg":"trace[200118364] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:26525; }","duration":"166.391918ms","start":"2026-06-16T01:25:10.710371Z","end":"2026-06-16T01:25:10.876763Z","steps":["trace[200118364] 'agreement among raft nodes before linearized reading' (duration: 166.016391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:10.87597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:25:10.154497Z","time spent":"721.45587ms","remote":"127.0.0.1:51288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-06-16T01:25:11.180488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.519961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:25:11.180681Z","caller":"traceutil/trace.go:171","msg":"trace[1064333819] linearizableReadLoop","detail":"{readStateIndex:27377; appliedIndex:27376; }","duration":"274.986097ms","start":"2026-06-16T01:25:10.905673Z","end":"2026-06-16T01:25:11.180659Z","steps":["trace[1064333819] 'read index received' (duration: 61.143189ms)","trace[1064333819] 'applied index is now lower than readState.Index' (duration: 213.841608ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:25:11.180908Z","caller":"traceutil/trace.go:171","msg":"trace[1671208152] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"298.943012ms","start":"2026-06-16T01:25:10.881818Z","end":"2026-06-16T01:25:11.180761Z","steps":["trace[1671208152] 'process raft request' (duration: 85.040764ms)","trace[1671208152] 'compare' (duration: 213.387189ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:25:11.18135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.673101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:11.181422Z","caller":"traceutil/trace.go:171","msg":"trace[2074640711] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26527; }","duration":"275.778733ms","start":"2026-06-16T01:25:10.905625Z","end":"2026-06-16T01:25:11.181403Z","steps":["trace[2074640711] 'agreement among raft nodes before linearized reading' (duration: 275.266912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:11.181477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.734353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21430"} {"level":"warn","ts":"2026-06-16T01:25:11.181498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.931897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:11.181515Z","caller":"traceutil/trace.go:171","msg":"trace[959656785] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26527; }","duration":"274.795424ms","start":"2026-06-16T01:25:10.906707Z","end":"2026-06-16T01:25:11.181502Z","steps":["trace[959656785] 'agreement among raft nodes before linearized reading' (duration: 274.679181ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:11.181591Z","caller":"traceutil/trace.go:171","msg":"trace[242551682] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26527; }","duration":"167.015739ms","start":"2026-06-16T01:25:11.014517Z","end":"2026-06-16T01:25:11.181532Z","steps":["trace[242551682] 'agreement among raft nodes before linearized reading' (duration: 166.937438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:11.181349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.917497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T01:25:11.181659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.182582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:11.181745Z","caller":"traceutil/trace.go:171","msg":"trace[2082969937] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26527; }","duration":"275.291274ms","start":"2026-06-16T01:25:10.906447Z","end":"2026-06-16T01:25:11.181738Z","steps":["trace[2082969937] 'agreement among raft nodes before linearized reading' (duration: 275.197812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:11.181752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.194333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21430"} {"level":"info","ts":"2026-06-16T01:25:11.18181Z","caller":"traceutil/trace.go:171","msg":"trace[711191348] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26527; }","duration":"274.306615ms","start":"2026-06-16T01:25:10.907488Z","end":"2026-06-16T01:25:11.181794Z","steps":["trace[711191348] 'agreement among raft nodes before linearized reading' (duration: 273.875637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:11.181857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.108039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T01:25:11.181881Z","caller":"traceutil/trace.go:171","msg":"trace[310387530] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26527; }","duration":"198.16953ms","start":"2026-06-16T01:25:10.983703Z","end":"2026-06-16T01:25:11.181872Z","steps":["trace[310387530] 'agreement among raft nodes before linearized reading' (duration: 198.12756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:11.181404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.324968ms","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-16T01:25:11.182009Z","caller":"traceutil/trace.go:171","msg":"trace[126578122] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26527; }","duration":"242.960739ms","start":"2026-06-16T01:25:10.939022Z","end":"2026-06-16T01:25:11.181983Z","steps":["trace[126578122] 'agreement among raft nodes before linearized reading' (duration: 242.315228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:11.182033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.330706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21430"} {"level":"info","ts":"2026-06-16T01:25:11.182171Z","caller":"traceutil/trace.go:171","msg":"trace[1991682214] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26527; }","duration":"274.482258ms","start":"2026-06-16T01:25:10.907668Z","end":"2026-06-16T01:25:11.18215Z","steps":["trace[1991682214] 'agreement among raft nodes before linearized reading' (duration: 274.218194ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:25:11.182461Z","caller":"traceutil/trace.go:171","msg":"trace[1433914314] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26527; }","duration":"275.273014ms","start":"2026-06-16T01:25:10.906376Z","end":"2026-06-16T01:25:11.181649Z","steps":["trace[1433914314] 'agreement among raft nodes before linearized reading' (duration: 274.873867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:11.554112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.247014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:25:11.55428Z","caller":"traceutil/trace.go:171","msg":"trace[1766610088] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"254.02786ms","start":"2026-06-16T01:25:11.300227Z","end":"2026-06-16T01:25:11.554255Z","steps":["trace[1766610088] 'process raft request' (duration: 76.547802ms)","trace[1766610088] 'compare' (duration: 176.947219ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:25:11.590176Z","caller":"traceutil/trace.go:171","msg":"trace[1822759141] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"288.78389ms","start":"2026-06-16T01:25:11.301334Z","end":"2026-06-16T01:25:11.590118Z","steps":["trace[1822759141] 'process raft request' (duration: 288.523954ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:25:11.799161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.767875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T01:25:11.7994Z","caller":"traceutil/trace.go:171","msg":"trace[1552940032] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"204.771756ms","start":"2026-06-16T01:25:11.5946Z","end":"2026-06-16T01:25:11.799372Z","steps":["trace[1552940032] 'process raft request' (duration: 86.643724ms)","trace[1552940032] 'compare' (duration: 117.51624ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:26:36.501263Z","caller":"traceutil/trace.go:171","msg":"trace[1077981710] transaction","detail":"{read_only:false; response_revision:27394; number_of_response:1; }","duration":"105.793488ms","start":"2026-06-16T01:26:36.395439Z","end":"2026-06-16T01:26:36.501233Z","steps":["trace[1077981710] 'process raft request' (duration: 105.504212ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:26:43.803093Z","caller":"traceutil/trace.go:171","msg":"trace[1050302973] transaction","detail":"{read_only:false; response_revision:27455; number_of_response:1; }","duration":"113.732798ms","start":"2026-06-16T01:26:43.689325Z","end":"2026-06-16T01:26:43.803058Z","steps":["trace[1050302973] 'process raft request' (duration: 113.614316ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:29:29.934239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26102} {"level":"info","ts":"2026-06-16T01:29:30.085186Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26102,"took":"146.483628ms","hash":3784777602,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":34623488,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-16T01:29:30.08526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3784777602,"revision":26102,"compact-revision":23162} {"level":"info","ts":"2026-06-16T01:30:05.528218Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c39f03fd9ad57fdc","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T01:30:05.533992Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-16T01:30:05.534124Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-16T01:34:29.941191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28798} {"level":"info","ts":"2026-06-16T01:34:30.080167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28798,"took":"134.313628ms","hash":709237678,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":30388224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-16T01:34:30.080242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":709237678,"revision":28798,"compact-revision":26102} {"level":"info","ts":"2026-06-16T01:39:29.950624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31022} {"level":"info","ts":"2026-06-16T01:39:30.070182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31022,"took":"115.98739ms","hash":1894482262,"current-db-size-bytes":59121664,"current-db-size":"59 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-16T01:39:30.070234Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1894482262,"revision":31022,"compact-revision":28798} {"level":"info","ts":"2026-06-16T01:44:29.95803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33285} {"level":"info","ts":"2026-06-16T01:44:30.102972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33285,"took":"140.912844ms","hash":752440840,"current-db-size-bytes":60162048,"current-db-size":"60 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T01:44:30.103051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":752440840,"revision":33285,"compact-revision":31022} {"level":"info","ts":"2026-06-16T01:49:29.96523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35583} {"level":"info","ts":"2026-06-16T01:49:30.114066Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35583,"took":"143.045404ms","hash":1463627632,"current-db-size-bytes":62746624,"current-db-size":"63 MB","current-db-size-in-use-bytes":31567872,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T01:49:30.114138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1463627632,"revision":35583,"compact-revision":33285} {"level":"info","ts":"2026-06-16T01:51:32.070128Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c39f03fd9ad57fdc","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T01:51:32.076525Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-16T01:51:32.076725Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-16T01:54:29.972814Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37812} {"level":"info","ts":"2026-06-16T01:54:30.087687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37812,"took":"111.632648ms","hash":4086679503,"current-db-size-bytes":62746624,"current-db-size":"63 MB","current-db-size-in-use-bytes":31039488,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-16T01:54:30.087744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4086679503,"revision":37812,"compact-revision":35583}