{"level":"warn","ts":"2026-06-10T01:18:51.250844Z","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-10T01:18:51.250957Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-10T01:18:51.251029Z","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-10T01:18:51.251043Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-10T01:18:51.251061Z","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-10T01:18:51.251669Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-06-10T01:18:51.251737Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-10T01:18:51.256883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.932653ms"} {"level":"info","ts":"2026-06-10T01:18:51.264599Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-06-10T01:18:51.264683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T01:18:51.264704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-06-10T01:18:51.264723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T01:18:51.264735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-06-10T01:18:51.26478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-06-10T01:18:51.271886Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T01:18:51.273371Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T01:18:51.274607Z","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-10T01:18:51.278095Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T01:18:51.278606Z","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-10T01:18:51.278708Z","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-10T01:18:51.27872Z","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-10T01:18:51.278674Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T01:18:51.280538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-06-10T01:18:51.280827Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-10T01:18:51.283188Z","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-10T01:18:51.283512Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T01:18:51.283572Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T01:18:51.283569Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-10T01:18:51.283756Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-10T01:18:51.965472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T01:18:51.965576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T01:18:51.965603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-06-10T01:18:51.965626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-06-10T01:18:51.965643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-10T01:18:51.965665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-06-10T01:18:51.965686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-10T01:18:51.967921Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T01:18:51.972254Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T01:18:51.972328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T01:18:51.972526Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T01:18:51.972591Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T01:18:51.972692Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T01:18:51.973735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T01:18:51.973928Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T01:18:51.974019Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T01:18:51.976024Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T01:18:51.976339Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-06-10T01:19:35.187152Z","caller":"traceutil/trace.go:171","msg":"trace[1200629906] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"149.896141ms","start":"2026-06-10T01:19:35.03673Z","end":"2026-06-10T01:19:35.186626Z","steps":["trace[1200629906] 'process raft request' (duration: 127.251215ms)","trace[1200629906] 'compare' (duration: 22.448262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:19:35.975966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.396738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T01:19:35.976147Z","caller":"traceutil/trace.go:171","msg":"trace[920479586] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"289.62908ms","start":"2026-06-10T01:19:35.686497Z","end":"2026-06-10T01:19:35.976126Z","steps":["trace[920479586] 'read index received' (duration: 128.210903ms)","trace[920479586] 'applied index is now lower than readState.Index' (duration: 161.416487ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:19:35.976275Z","caller":"traceutil/trace.go:171","msg":"trace[769928953] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"415.535769ms","start":"2026-06-10T01:19:35.560727Z","end":"2026-06-10T01:19:35.976263Z","steps":["trace[769928953] 'process raft request' (duration: 254.070861ms)","trace[769928953] 'compare' (duration: 160.022881ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:19:35.976339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:19:35.560703Z","time spent":"415.608201ms","remote":"127.0.0.1:41280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T01:19:35.976468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.949316ms","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-10T01:19:35.976575Z","caller":"traceutil/trace.go:171","msg":"trace[366256158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"290.107979ms","start":"2026-06-10T01:19:35.686444Z","end":"2026-06-10T01:19:35.976552Z","steps":["trace[366256158] 'agreement among raft nodes before linearized reading' (duration: 289.860084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:19:35.976901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.826634ms","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-10T01:19:35.977048Z","caller":"traceutil/trace.go:171","msg":"trace[1736016691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"154.022468ms","start":"2026-06-10T01:19:35.822991Z","end":"2026-06-10T01:19:35.977014Z","steps":["trace[1736016691] 'agreement among raft nodes before linearized reading' (duration: 153.730103ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:19:50.277241Z","caller":"traceutil/trace.go:171","msg":"trace[1492231733] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"136.104371ms","start":"2026-06-10T01:19:50.141116Z","end":"2026-06-10T01:19:50.27722Z","steps":["trace[1492231733] 'process raft request' (duration: 136.05046ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:19:50.277295Z","caller":"traceutil/trace.go:171","msg":"trace[2057694035] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"140.524444ms","start":"2026-06-10T01:19:50.136747Z","end":"2026-06-10T01:19:50.277271Z","steps":["trace[2057694035] 'process raft request' (duration: 140.31688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:21:31.072305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.054626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/pxc-webhook-ssl\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:21:31.072427Z","caller":"traceutil/trace.go:171","msg":"trace[513382105] range","detail":"{range_begin:/registry/secrets/openstack/pxc-webhook-ssl; range_end:; response_count:0; response_revision:1903; }","duration":"111.201198ms","start":"2026-06-10T01:21:30.961207Z","end":"2026-06-10T01:21:31.072408Z","steps":["trace[513382105] 'range keys from in-memory index tree' (duration: 110.955603ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:21:34.933168Z","caller":"traceutil/trace.go:171","msg":"trace[717504927] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"205.527936ms","start":"2026-06-10T01:21:34.727607Z","end":"2026-06-10T01:21:34.933135Z","steps":["trace[717504927] 'process raft request' (duration: 177.086907ms)","trace[717504927] 'compare' (duration: 28.293736ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:21:34.957831Z","caller":"traceutil/trace.go:171","msg":"trace[143165537] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"227.997212ms","start":"2026-06-10T01:21:34.729805Z","end":"2026-06-10T01:21:34.957803Z","steps":["trace[143165537] 'process raft request' (duration: 227.769667ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:21:34.958305Z","caller":"traceutil/trace.go:171","msg":"trace[541487565] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"227.617085ms","start":"2026-06-10T01:21:34.730665Z","end":"2026-06-10T01:21:34.958282Z","steps":["trace[541487565] 'process raft request' (duration: 227.065635ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:21:34.958467Z","caller":"traceutil/trace.go:171","msg":"trace[1562657620] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"227.693227ms","start":"2026-06-10T01:21:34.730757Z","end":"2026-06-10T01:21:34.95845Z","steps":["trace[1562657620] 'process raft request' (duration: 227.528024ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:21:34.95837Z","caller":"traceutil/trace.go:171","msg":"trace[2058399225] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"227.619345ms","start":"2026-06-10T01:21:34.730715Z","end":"2026-06-10T01:21:34.958334Z","steps":["trace[2058399225] 'process raft request' (duration: 227.422391ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:21:34.95846Z","caller":"traceutil/trace.go:171","msg":"trace[76746634] linearizableReadLoop","detail":"{readStateIndex:2067; appliedIndex:2062; }","duration":"225.79472ms","start":"2026-06-10T01:21:34.73265Z","end":"2026-06-10T01:21:34.958445Z","steps":["trace[76746634] 'read index received' (duration: 172.059141ms)","trace[76746634] 'applied index is now lower than readState.Index' (duration: 53.734088ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:21:34.95859Z","caller":"traceutil/trace.go:171","msg":"trace[786543935] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"225.358242ms","start":"2026-06-10T01:21:34.733199Z","end":"2026-06-10T01:21:34.958557Z","steps":["trace[786543935] 'process raft request' (duration: 225.181618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:21:34.958652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.974903ms","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-10T01:21:34.958694Z","caller":"traceutil/trace.go:171","msg":"trace[2025323616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1993; }","duration":"226.051364ms","start":"2026-06-10T01:21:34.732633Z","end":"2026-06-10T01:21:34.958684Z","steps":["trace[2025323616] 'agreement among raft nodes before linearized reading' (duration: 225.85679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:21:34.958904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.897225ms","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-10T01:21:34.959084Z","caller":"traceutil/trace.go:171","msg":"trace[1742934789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1993; }","duration":"194.040708ms","start":"2026-06-10T01:21:34.764941Z","end":"2026-06-10T01:21:34.958982Z","steps":["trace[1742934789] 'agreement among raft nodes before linearized reading' (duration: 193.758792ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:21:46.490266Z","caller":"traceutil/trace.go:171","msg":"trace[545447823] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"269.449702ms","start":"2026-06-10T01:21:46.220776Z","end":"2026-06-10T01:21:46.490225Z","steps":["trace[545447823] 'process raft request' (duration: 269.186227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:22:25.526832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.206951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T01:22:25.526958Z","caller":"traceutil/trace.go:171","msg":"trace[1696145068] linearizableReadLoop","detail":"{readStateIndex:2395; appliedIndex:2394; }","duration":"221.444679ms","start":"2026-06-10T01:22:25.305496Z","end":"2026-06-10T01:22:25.526941Z","steps":["trace[1696145068] 'read index received' (duration: 95.8066ms)","trace[1696145068] 'applied index is now lower than readState.Index' (duration: 125.636889ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:22:25.527054Z","caller":"traceutil/trace.go:171","msg":"trace[1271230328] transaction","detail":"{read_only:false; response_revision:2308; number_of_response:1; }","duration":"226.71038ms","start":"2026-06-10T01:22:25.300326Z","end":"2026-06-10T01:22:25.527036Z","steps":["trace[1271230328] 'process raft request' (duration: 101.01809ms)","trace[1271230328] 'compare' (duration: 125.067718ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:22:25.527264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.746555ms","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-10T01:22:25.527348Z","caller":"traceutil/trace.go:171","msg":"trace[245762911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2308; }","duration":"221.862787ms","start":"2026-06-10T01:22:25.305469Z","end":"2026-06-10T01:22:25.527332Z","steps":["trace[245762911] 'agreement among raft nodes before linearized reading' (duration: 221.639693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:22:25.527348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.171285ms","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-10T01:22:25.52746Z","caller":"traceutil/trace.go:171","msg":"trace[2120108940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2308; }","duration":"220.316108ms","start":"2026-06-10T01:22:25.307118Z","end":"2026-06-10T01:22:25.527434Z","steps":["trace[2120108940] 'agreement among raft nodes before linearized reading' (duration: 220.011022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:22:25.527372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.99699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-10T01:22:25.527607Z","caller":"traceutil/trace.go:171","msg":"trace[1878213600] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2308; }","duration":"111.219724ms","start":"2026-06-10T01:22:25.416333Z","end":"2026-06-10T01:22:25.527553Z","steps":["trace[1878213600] 'agreement among raft nodes before linearized reading' (duration: 110.843067ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:22:25.715344Z","caller":"traceutil/trace.go:171","msg":"trace[1403277654] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"183.387667ms","start":"2026-06-10T01:22:25.53193Z","end":"2026-06-10T01:22:25.715318Z","steps":["trace[1403277654] 'process raft request' (duration: 183.194822ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:22:25.715537Z","caller":"traceutil/trace.go:171","msg":"trace[1671859155] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"183.293415ms","start":"2026-06-10T01:22:25.532211Z","end":"2026-06-10T01:22:25.715505Z","steps":["trace[1671859155] 'process raft request' (duration: 183.068171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:23:14.383746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.159718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-06-10T01:23:14.383858Z","caller":"traceutil/trace.go:171","msg":"trace[1782660123] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2748; }","duration":"195.324881ms","start":"2026-06-10T01:23:14.188511Z","end":"2026-06-10T01:23:14.383836Z","steps":["trace[1782660123] 'range keys from in-memory index tree' (duration: 194.953474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:23:48.206558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.696407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:23:48.20664Z","caller":"traceutil/trace.go:171","msg":"trace[1293780902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2950; }","duration":"125.829029ms","start":"2026-06-10T01:23:48.080796Z","end":"2026-06-10T01:23:48.206625Z","steps":["trace[1293780902] 'range keys from in-memory index tree' (duration: 125.622584ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:23:48.404786Z","caller":"traceutil/trace.go:171","msg":"trace[1383444321] linearizableReadLoop","detail":"{readStateIndex:3064; appliedIndex:3063; }","duration":"103.717269ms","start":"2026-06-10T01:23:48.301047Z","end":"2026-06-10T01:23:48.404765Z","steps":["trace[1383444321] 'read index received' (duration: 103.513126ms)","trace[1383444321] 'applied index is now lower than readState.Index' (duration: 202.903µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:23:48.404877Z","caller":"traceutil/trace.go:171","msg":"trace[1616046644] transaction","detail":"{read_only:false; response_revision:2952; number_of_response:1; }","duration":"133.480094ms","start":"2026-06-10T01:23:48.271374Z","end":"2026-06-10T01:23:48.404854Z","steps":["trace[1616046644] 'process raft request' (duration: 133.26303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:23:48.404985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.926293ms","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-10T01:23:48.405527Z","caller":"traceutil/trace.go:171","msg":"trace[279278633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2952; }","duration":"104.518184ms","start":"2026-06-10T01:23:48.300994Z","end":"2026-06-10T01:23:48.405512Z","steps":["trace[279278633] 'agreement among raft nodes before linearized reading' (duration: 103.878982ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:27:39.698417Z","caller":"traceutil/trace.go:171","msg":"trace[1877103223] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"103.064626ms","start":"2026-06-10T01:27:39.595316Z","end":"2026-06-10T01:27:39.69838Z","steps":["trace[1877103223] 'process raft request' (duration: 49.49786ms)","trace[1877103223] 'compare' (duration: 53.24163ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:27:45.103983Z","caller":"traceutil/trace.go:171","msg":"trace[145552127] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"177.612592ms","start":"2026-06-10T01:27:44.926344Z","end":"2026-06-10T01:27:45.103957Z","steps":["trace[145552127] 'process raft request' (duration: 177.402697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:27:49.151398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.08983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-06-10T01:27:49.151691Z","caller":"traceutil/trace.go:171","msg":"trace[1277751020] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5143; }","duration":"266.816296ms","start":"2026-06-10T01:27:48.884845Z","end":"2026-06-10T01:27:49.151662Z","steps":["trace[1277751020] 'process raft request' (duration: 136.220776ms)","trace[1277751020] 'compare' (duration: 130.039018ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:27:49.155141Z","caller":"traceutil/trace.go:171","msg":"trace[488941791] linearizableReadLoop","detail":"{readStateIndex:5338; appliedIndex:5336; }","duration":"243.850779ms","start":"2026-06-10T01:27:48.911259Z","end":"2026-06-10T01:27:49.155109Z","steps":["trace[488941791] 'read index received' (duration: 109.825444ms)","trace[488941791] 'applied index is now lower than readState.Index' (duration: 133.999294ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:27:49.155313Z","caller":"traceutil/trace.go:171","msg":"trace[253803122] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"260.175989ms","start":"2026-06-10T01:27:48.895119Z","end":"2026-06-10T01:27:49.155295Z","steps":["trace[253803122] 'process raft request' (duration: 259.67482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:27:49.155649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.333787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-06-10T01:27:49.155702Z","caller":"traceutil/trace.go:171","msg":"trace[1276476991] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5144; }","duration":"244.447769ms","start":"2026-06-10T01:27:48.911241Z","end":"2026-06-10T01:27:49.155688Z","steps":["trace[1276476991] 'agreement among raft nodes before linearized reading' (duration: 244.085402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:27:49.155745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.001236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ae621d7c-a28c-4675-9934-4458a886d2f4\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-06-10T01:27:49.155831Z","caller":"traceutil/trace.go:171","msg":"trace[857040587] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ae621d7c-a28c-4675-9934-4458a886d2f4; range_end:; response_count:1; response_revision:5144; }","duration":"161.14965ms","start":"2026-06-10T01:27:48.994664Z","end":"2026-06-10T01:27:49.155813Z","steps":["trace[857040587] 'agreement among raft nodes before linearized reading' (duration: 160.836964ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:27:49.448096Z","caller":"traceutil/trace.go:171","msg":"trace[709974256] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"111.746751ms","start":"2026-06-10T01:27:49.33632Z","end":"2026-06-10T01:27:49.448066Z","steps":["trace[709974256] 'process raft request' (duration: 26.956192ms)","trace[709974256] 'compare' (duration: 84.402392ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:27:49.460682Z","caller":"traceutil/trace.go:171","msg":"trace[593484319] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"124.085765ms","start":"2026-06-10T01:27:49.336552Z","end":"2026-06-10T01:27:49.460638Z","steps":["trace[593484319] 'process raft request' (duration: 123.894471ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:27:49.460641Z","caller":"traceutil/trace.go:171","msg":"trace[1514364539] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"123.987083ms","start":"2026-06-10T01:27:49.336629Z","end":"2026-06-10T01:27:49.460616Z","steps":["trace[1514364539] 'process raft request' (duration: 123.945712ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:27:49.613323Z","caller":"traceutil/trace.go:171","msg":"trace[921526667] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"107.858908ms","start":"2026-06-10T01:27:49.505434Z","end":"2026-06-10T01:27:49.613292Z","steps":["trace[921526667] 'process raft request' (duration: 107.739175ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:27:49.613378Z","caller":"traceutil/trace.go:171","msg":"trace[262449820] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"114.725527ms","start":"2026-06-10T01:27:49.498613Z","end":"2026-06-10T01:27:49.613338Z","steps":["trace[262449820] 'process raft request' (duration: 114.356281ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:28:11.11599Z","caller":"traceutil/trace.go:171","msg":"trace[144093474] linearizableReadLoop","detail":"{readStateIndex:5824; appliedIndex:5823; }","duration":"203.316744ms","start":"2026-06-10T01:28:10.912649Z","end":"2026-06-10T01:28:11.115965Z","steps":["trace[144093474] 'read index received' (duration: 203.157032ms)","trace[144093474] 'applied index is now lower than readState.Index' (duration: 158.182µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:28:11.116117Z","caller":"traceutil/trace.go:171","msg":"trace[1449630660] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"207.006745ms","start":"2026-06-10T01:28:10.909073Z","end":"2026-06-10T01:28:11.11608Z","steps":["trace[1449630660] 'process raft request' (duration: 206.716129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:28:11.116297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.582609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ae621d7c-a28c-4675-9934-4458a886d2f4\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-06-10T01:28:11.116346Z","caller":"traceutil/trace.go:171","msg":"trace[1482158487] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ae621d7c-a28c-4675-9934-4458a886d2f4; range_end:; response_count:1; response_revision:5618; }","duration":"203.699712ms","start":"2026-06-10T01:28:10.912634Z","end":"2026-06-10T01:28:11.116333Z","steps":["trace[1482158487] 'agreement among raft nodes before linearized reading' (duration: 203.480688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:28:11.116409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.323642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-10T01:28:11.116589Z","caller":"traceutil/trace.go:171","msg":"trace[1280252791] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5618; }","duration":"110.570156ms","start":"2026-06-10T01:28:11.005993Z","end":"2026-06-10T01:28:11.116563Z","steps":["trace[1280252791] 'agreement among raft nodes before linearized reading' (duration: 110.184839ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:28:11.238233Z","caller":"traceutil/trace.go:171","msg":"trace[1936041493] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"116.786374ms","start":"2026-06-10T01:28:11.121411Z","end":"2026-06-10T01:28:11.238198Z","steps":["trace[1936041493] 'process raft request' (duration: 60.532518ms)","trace[1936041493] 'compare' (duration: 56.091953ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:28:11.264132Z","caller":"traceutil/trace.go:171","msg":"trace[465949169] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"137.09433ms","start":"2026-06-10T01:28:11.127004Z","end":"2026-06-10T01:28:11.264098Z","steps":["trace[465949169] 'process raft request' (duration: 136.884365ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:28:11.440302Z","caller":"traceutil/trace.go:171","msg":"trace[990796131] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"163.957389ms","start":"2026-06-10T01:28:11.276306Z","end":"2026-06-10T01:28:11.440263Z","steps":["trace[990796131] 'process raft request' (duration: 163.616123ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:28:21.170794Z","caller":"traceutil/trace.go:171","msg":"trace[1904960731] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"125.425917ms","start":"2026-06-10T01:28:21.045337Z","end":"2026-06-10T01:28:21.170763Z","steps":["trace[1904960731] 'process raft request' (duration: 85.892788ms)","trace[1904960731] 'compare' (duration: 39.312665ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:28:21.171133Z","caller":"traceutil/trace.go:171","msg":"trace[1932946188] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"125.58293ms","start":"2026-06-10T01:28:21.045498Z","end":"2026-06-10T01:28:21.171081Z","steps":["trace[1932946188] 'process raft request' (duration: 125.217933ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:28:21.17128Z","caller":"traceutil/trace.go:171","msg":"trace[1877915835] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"125.647792ms","start":"2026-06-10T01:28:21.045614Z","end":"2026-06-10T01:28:21.171262Z","steps":["trace[1877915835] 'process raft request' (duration: 125.354226ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:28:21.171434Z","caller":"traceutil/trace.go:171","msg":"trace[1906009239] transaction","detail":"{read_only:false; response_revision:5730; number_of_response:1; }","duration":"125.639513ms","start":"2026-06-10T01:28:21.04575Z","end":"2026-06-10T01:28:21.171389Z","steps":["trace[1906009239] 'process raft request' (duration: 125.442458ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:28:51.997743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-06-10T01:28:52.059652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"60.470218ms","hash":2708783328,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":33550336,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T01:28:52.05971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2708783328,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-06-10T01:33:52.003268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6013} {"level":"info","ts":"2026-06-10T01:33:52.101064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6013,"took":"95.145439ms","hash":3164672260,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":21991424,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-10T01:33:52.101173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3164672260,"revision":6013,"compact-revision":2974} {"level":"info","ts":"2026-06-10T01:34:19.026446Z","caller":"traceutil/trace.go:171","msg":"trace[974462004] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"135.996276ms","start":"2026-06-10T01:34:18.890414Z","end":"2026-06-10T01:34:19.026411Z","steps":["trace[974462004] 'process raft request' (duration: 125.2139ms)","trace[974462004] 'compare' (duration: 10.637883ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:34:19.705718Z","caller":"traceutil/trace.go:171","msg":"trace[60884644] linearizableReadLoop","detail":"{readStateIndex:8718; appliedIndex:8717; }","duration":"218.987082ms","start":"2026-06-10T01:34:19.486707Z","end":"2026-06-10T01:34:19.705695Z","steps":["trace[60884644] 'read index received' (duration: 218.88027ms)","trace[60884644] 'applied index is now lower than readState.Index' (duration: 105.662µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:34:19.705755Z","caller":"traceutil/trace.go:171","msg":"trace[1399419432] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"222.991169ms","start":"2026-06-10T01:34:19.482735Z","end":"2026-06-10T01:34:19.705727Z","steps":["trace[1399419432] 'process raft request' (duration: 222.793655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:34:19.705916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.185225ms","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-10T01:34:19.705999Z","caller":"traceutil/trace.go:171","msg":"trace[1292085823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8422; }","duration":"219.323348ms","start":"2026-06-10T01:34:19.48666Z","end":"2026-06-10T01:34:19.705983Z","steps":["trace[1292085823] 'agreement among raft nodes before linearized reading' (duration: 219.136425ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:34:19.716689Z","caller":"traceutil/trace.go:171","msg":"trace[1134204167] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"173.938055ms","start":"2026-06-10T01:34:19.5427Z","end":"2026-06-10T01:34:19.716638Z","steps":["trace[1134204167] 'process raft request' (duration: 173.729431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:34:19.716884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.72306ms","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-10T01:34:19.716934Z","caller":"traceutil/trace.go:171","msg":"trace[1432441704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8424; }","duration":"114.802541ms","start":"2026-06-10T01:34:19.602117Z","end":"2026-06-10T01:34:19.716919Z","steps":["trace[1432441704] 'agreement among raft nodes before linearized reading' (duration: 114.656738ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:34:19.716729Z","caller":"traceutil/trace.go:171","msg":"trace[1478276497] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"152.919166ms","start":"2026-06-10T01:34:19.563787Z","end":"2026-06-10T01:34:19.716706Z","steps":["trace[1478276497] 'process raft request' (duration: 152.758383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:34:19.716741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.947773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-10T01:34:19.717095Z","caller":"traceutil/trace.go:171","msg":"trace[648024419] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8424; }","duration":"228.33645ms","start":"2026-06-10T01:34:19.488747Z","end":"2026-06-10T01:34:19.717083Z","steps":["trace[648024419] 'agreement among raft nodes before linearized reading' (duration: 227.845601ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:35:01.618774Z","caller":"traceutil/trace.go:171","msg":"trace[2110678953] linearizableReadLoop","detail":"{readStateIndex:9049; appliedIndex:9048; }","duration":"142.485987ms","start":"2026-06-10T01:35:01.476261Z","end":"2026-06-10T01:35:01.618747Z","steps":["trace[2110678953] 'read index received' (duration: 142.398945ms)","trace[2110678953] 'applied index is now lower than readState.Index' (duration: 86.042µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:35:01.619003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.719091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tvrnh\" ","response":"range_response_count:1 size:4646"} {"level":"info","ts":"2026-06-10T01:35:01.619036Z","caller":"traceutil/trace.go:171","msg":"trace[1726686284] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tvrnh; range_end:; response_count:1; response_revision:8740; }","duration":"142.810513ms","start":"2026-06-10T01:35:01.476215Z","end":"2026-06-10T01:35:01.619026Z","steps":["trace[1726686284] 'agreement among raft nodes before linearized reading' (duration: 142.6476ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:35:01.619071Z","caller":"traceutil/trace.go:171","msg":"trace[1382784263] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"276.434352ms","start":"2026-06-10T01:35:01.342573Z","end":"2026-06-10T01:35:01.619007Z","steps":["trace[1382784263] 'process raft request' (duration: 276.046635ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:35:01.752389Z","caller":"traceutil/trace.go:171","msg":"trace[261477324] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"274.928524ms","start":"2026-06-10T01:35:01.477439Z","end":"2026-06-10T01:35:01.752367Z","steps":["trace[261477324] 'process raft request' (duration: 210.575371ms)","trace[261477324] 'compare' (duration: 64.194361ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:35:01.756682Z","caller":"traceutil/trace.go:171","msg":"trace[1220120463] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"125.983764ms","start":"2026-06-10T01:35:01.630667Z","end":"2026-06-10T01:35:01.756651Z","steps":["trace[1220120463] 'process raft request' (duration: 125.80749ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:35:18.977165Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002424.wal"} {"level":"info","ts":"2026-06-10T01:36:18.541118Z","caller":"traceutil/trace.go:171","msg":"trace[180877897] transaction","detail":"{read_only:false; response_revision:9493; number_of_response:1; }","duration":"123.423805ms","start":"2026-06-10T01:36:18.417661Z","end":"2026-06-10T01:36:18.541085Z","steps":["trace[180877897] 'process raft request' (duration: 101.233344ms)","trace[180877897] 'compare' (duration: 22.003147ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:36:18.541337Z","caller":"traceutil/trace.go:171","msg":"trace[889051272] transaction","detail":"{read_only:false; response_revision:9494; number_of_response:1; }","duration":"123.189021ms","start":"2026-06-10T01:36:18.418113Z","end":"2026-06-10T01:36:18.541302Z","steps":["trace[889051272] 'process raft request' (duration: 122.929445ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:36:18.541323Z","caller":"traceutil/trace.go:171","msg":"trace[911430307] linearizableReadLoop","detail":"{readStateIndex:9827; appliedIndex:9825; }","duration":"114.916553ms","start":"2026-06-10T01:36:18.426362Z","end":"2026-06-10T01:36:18.541279Z","steps":["trace[911430307] 'read index received' (duration: 92.523958ms)","trace[911430307] 'applied index is now lower than readState.Index' (duration: 22.389235ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:36:18.542044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.626746ms","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-10T01:36:18.542215Z","caller":"traceutil/trace.go:171","msg":"trace[1422423558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9494; }","duration":"115.848021ms","start":"2026-06-10T01:36:18.426321Z","end":"2026-06-10T01:36:18.542169Z","steps":["trace[1422423558] 'agreement among raft nodes before linearized reading' (duration: 115.366862ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:36:40.665655Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T01:36:40.672896Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-06-10T01:36:40.673002Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-06-10T01:38:52.011651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8136} {"level":"info","ts":"2026-06-10T01:38:52.078701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8136,"took":"63.905711ms","hash":3573188337,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":29356032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-10T01:38:52.07942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3573188337,"revision":8136,"compact-revision":6013} {"level":"warn","ts":"2026-06-10T01:40:03.941813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.7145ms","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-10T01:40:03.941912Z","caller":"traceutil/trace.go:171","msg":"trace[969204555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11352; }","duration":"109.852662ms","start":"2026-06-10T01:40:03.832042Z","end":"2026-06-10T01:40:03.941895Z","steps":["trace[969204555] 'range keys from in-memory index tree' (duration: 109.526086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:40:03.942271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.356048ms","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-10T01:40:03.942416Z","caller":"traceutil/trace.go:171","msg":"trace[869275612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11352; }","duration":"104.599342ms","start":"2026-06-10T01:40:03.837788Z","end":"2026-06-10T01:40:03.942387Z","steps":["trace[869275612] 'range keys from in-memory index tree' (duration: 103.972101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:40:03.942437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.692431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-06-10T01:40:03.942456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.373732ms","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-10T01:40:03.942495Z","caller":"traceutil/trace.go:171","msg":"trace[1683649458] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11352; }","duration":"107.749542ms","start":"2026-06-10T01:40:03.834729Z","end":"2026-06-10T01:40:03.942478Z","steps":["trace[1683649458] 'range keys from in-memory index tree' (duration: 107.558158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:40:03.942493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.84137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T01:40:03.942535Z","caller":"traceutil/trace.go:171","msg":"trace[823974048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11352; }","duration":"110.463843ms","start":"2026-06-10T01:40:03.832053Z","end":"2026-06-10T01:40:03.942516Z","steps":["trace[823974048] 'range keys from in-memory index tree' (duration: 110.133458ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:40:03.942568Z","caller":"traceutil/trace.go:171","msg":"trace[450051121] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:11352; }","duration":"110.934542ms","start":"2026-06-10T01:40:03.831616Z","end":"2026-06-10T01:40:03.94255Z","steps":["trace[450051121] 'count revisions from in-memory index tree' (duration: 110.738808ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:43:52.018729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10657} {"level":"info","ts":"2026-06-10T01:43:52.102551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10657,"took":"80.023826ms","hash":1864572375,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":27914240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-10T01:43:52.102627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1864572375,"revision":10657,"compact-revision":8136} {"level":"info","ts":"2026-06-10T01:47:30.126975Z","caller":"traceutil/trace.go:171","msg":"trace[608262651] transaction","detail":"{read_only:false; response_revision:14250; number_of_response:1; }","duration":"138.274543ms","start":"2026-06-10T01:47:29.98865Z","end":"2026-06-10T01:47:30.126925Z","steps":["trace[608262651] 'process raft request' (duration: 138.072109ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:47:57.327663Z","caller":"traceutil/trace.go:171","msg":"trace[1972605630] transaction","detail":"{read_only:false; response_revision:14392; number_of_response:1; }","duration":"107.646481ms","start":"2026-06-10T01:47:57.219997Z","end":"2026-06-10T01:47:57.327643Z","steps":["trace[1972605630] 'process raft request' (duration: 107.502458ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:48:04.805623Z","caller":"traceutil/trace.go:171","msg":"trace[424014020] transaction","detail":"{read_only:false; response_revision:14445; number_of_response:1; }","duration":"100.342453ms","start":"2026-06-10T01:48:04.705234Z","end":"2026-06-10T01:48:04.805576Z","steps":["trace[424014020] 'process raft request' (duration: 100.1609ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:48:05.108367Z","caller":"traceutil/trace.go:171","msg":"trace[140043607] transaction","detail":"{read_only:false; response_revision:14446; number_of_response:1; }","duration":"175.623039ms","start":"2026-06-10T01:48:04.932668Z","end":"2026-06-10T01:48:05.108291Z","steps":["trace[140043607] 'process raft request' (duration: 175.192332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:48:05.413278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:48:05.109647Z","time spent":"303.623948ms","remote":"127.0.0.1:41126","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-10T01:48:14.675071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.23014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T01:48:14.675292Z","caller":"traceutil/trace.go:171","msg":"trace[1444881775] transaction","detail":"{read_only:false; response_revision:14608; number_of_response:1; }","duration":"388.458396ms","start":"2026-06-10T01:48:14.286789Z","end":"2026-06-10T01:48:14.675247Z","steps":["trace[1444881775] 'process raft request' (duration: 152.890889ms)","trace[1444881775] 'compare' (duration: 235.008756ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:48:14.675407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:48:14.286777Z","time spent":"388.579678ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T01:48:14.683064Z","caller":"traceutil/trace.go:171","msg":"trace[919353138] linearizableReadLoop","detail":"{readStateIndex:15113; appliedIndex:15111; }","duration":"349.445227ms","start":"2026-06-10T01:48:14.333592Z","end":"2026-06-10T01:48:14.683038Z","steps":["trace[919353138] 'read index received' (duration: 106.050372ms)","trace[919353138] 'applied index is now lower than readState.Index' (duration: 243.393355ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:48:14.683199Z","caller":"traceutil/trace.go:171","msg":"trace[249759586] transaction","detail":"{read_only:false; response_revision:14609; number_of_response:1; }","duration":"391.370561ms","start":"2026-06-10T01:48:14.291816Z","end":"2026-06-10T01:48:14.683186Z","steps":["trace[249759586] 'process raft request' (duration: 391.060456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:48:14.683339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:48:14.29179Z","time spent":"391.437892ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T01:48:14.683399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.917936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T01:48:14.6835Z","caller":"traceutil/trace.go:171","msg":"trace[1094117012] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:14609; }","duration":"282.098729ms","start":"2026-06-10T01:48:14.40138Z","end":"2026-06-10T01:48:14.683479Z","steps":["trace[1094117012] 'agreement among raft nodes before linearized reading' (duration: 281.909385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:48:14.683565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.340891ms","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-10T01:48:14.683652Z","caller":"traceutil/trace.go:171","msg":"trace[2125981849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14609; }","duration":"139.454814ms","start":"2026-06-10T01:48:14.544174Z","end":"2026-06-10T01:48:14.683629Z","steps":["trace[2125981849] 'agreement among raft nodes before linearized reading' (duration: 139.180128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:48:14.683655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.034037ms","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-10T01:48:14.683733Z","caller":"traceutil/trace.go:171","msg":"trace[101582527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14609; }","duration":"350.19501ms","start":"2026-06-10T01:48:14.333522Z","end":"2026-06-10T01:48:14.683717Z","steps":["trace[101582527] 'agreement among raft nodes before linearized reading' (duration: 349.934245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:48:14.683786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:48:14.333498Z","time spent":"350.275781ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T01:48:14.683767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.680155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-10T01:48:14.683881Z","caller":"traceutil/trace.go:171","msg":"trace[1098726425] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14609; }","duration":"123.804937ms","start":"2026-06-10T01:48:14.560048Z","end":"2026-06-10T01:48:14.683853Z","steps":["trace[1098726425] 'agreement among raft nodes before linearized reading' (duration: 123.500451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:48:14.683896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.989384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-10T01:48:14.683905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.554344ms","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-10T01:48:14.683933Z","caller":"traceutil/trace.go:171","msg":"trace[217756695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14609; }","duration":"140.069956ms","start":"2026-06-10T01:48:14.543853Z","end":"2026-06-10T01:48:14.683923Z","steps":["trace[217756695] 'agreement among raft nodes before linearized reading' (duration: 139.906303ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:48:14.68397Z","caller":"traceutil/trace.go:171","msg":"trace[401637595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14609; }","duration":"140.666656ms","start":"2026-06-10T01:48:14.543286Z","end":"2026-06-10T01:48:14.683953Z","steps":["trace[401637595] 'agreement among raft nodes before linearized reading' (duration: 140.482603ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:48:52.027095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12831} {"level":"info","ts":"2026-06-10T01:48:52.109587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12831,"took":"78.54999ms","hash":19307550,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":28291072,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-10T01:48:52.109661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":19307550,"revision":12831,"compact-revision":10657} {"level":"warn","ts":"2026-06-10T01:49:55.522487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.373948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T01:49:55.522605Z","caller":"traceutil/trace.go:171","msg":"trace[1818865914] linearizableReadLoop","detail":"{readStateIndex:16243; appliedIndex:16242; }","duration":"150.783528ms","start":"2026-06-10T01:49:55.3718Z","end":"2026-06-10T01:49:55.522584Z","steps":["trace[1818865914] 'read index received' (duration: 27.155715ms)","trace[1818865914] 'applied index is now lower than readState.Index' (duration: 123.626413ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:49:55.522692Z","caller":"traceutil/trace.go:171","msg":"trace[1249978253] transaction","detail":"{read_only:false; response_revision:15704; number_of_response:1; }","duration":"183.750643ms","start":"2026-06-10T01:49:55.338909Z","end":"2026-06-10T01:49:55.522659Z","steps":["trace[1249978253] 'process raft request' (duration: 60.083549ms)","trace[1249978253] 'compare' (duration: 123.269376ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:49:55.52279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.002172ms","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-10T01:49:55.522822Z","caller":"traceutil/trace.go:171","msg":"trace[1745139858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15704; }","duration":"151.083623ms","start":"2026-06-10T01:49:55.371727Z","end":"2026-06-10T01:49:55.522811Z","steps":["trace[1745139858] 'agreement among raft nodes before linearized reading' (duration: 150.958031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:49:55.522973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.523435ms","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-10T01:49:55.522997Z","caller":"traceutil/trace.go:171","msg":"trace[170855805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15704; }","duration":"148.602907ms","start":"2026-06-10T01:49:55.374386Z","end":"2026-06-10T01:49:55.522989Z","steps":["trace[170855805] 'agreement among raft nodes before linearized reading' (duration: 148.534305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:49:55.52464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.288826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:49:55.524714Z","caller":"traceutil/trace.go:171","msg":"trace[915193580] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15704; }","duration":"105.400668ms","start":"2026-06-10T01:49:55.419301Z","end":"2026-06-10T01:49:55.524702Z","steps":["trace[915193580] 'agreement among raft nodes before linearized reading' (duration: 105.292176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:49:55.52489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.899405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-10T01:49:55.524926Z","caller":"traceutil/trace.go:171","msg":"trace[744047837] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15704; }","duration":"126.948157ms","start":"2026-06-10T01:49:55.397968Z","end":"2026-06-10T01:49:55.524916Z","steps":["trace[744047837] 'agreement among raft nodes before linearized reading' (duration: 126.858595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:49:55.524994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.145434ms","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-10T01:49:55.525049Z","caller":"traceutil/trace.go:171","msg":"trace[1349738919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15704; }","duration":"132.240747ms","start":"2026-06-10T01:49:55.392793Z","end":"2026-06-10T01:49:55.525034Z","steps":["trace[1349738919] 'agreement among raft nodes before linearized reading' (duration: 132.135745ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:49:55.685724Z","caller":"traceutil/trace.go:171","msg":"trace[1822987130] transaction","detail":"{read_only:false; response_revision:15705; number_of_response:1; }","duration":"157.613877ms","start":"2026-06-10T01:49:55.528084Z","end":"2026-06-10T01:49:55.685698Z","steps":["trace[1822987130] 'process raft request' (duration: 157.457094ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:49:55.687325Z","caller":"traceutil/trace.go:171","msg":"trace[628456213] linearizableReadLoop","detail":"{readStateIndex:16248; appliedIndex:16243; }","duration":"107.999836ms","start":"2026-06-10T01:49:55.579272Z","end":"2026-06-10T01:49:55.687272Z","steps":["trace[628456213] 'read index received' (duration: 106.308295ms)","trace[628456213] 'applied index is now lower than readState.Index' (duration: 1.690021ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:49:55.687412Z","caller":"traceutil/trace.go:171","msg":"trace[1875095331] transaction","detail":"{read_only:false; response_revision:15707; number_of_response:1; }","duration":"155.199381ms","start":"2026-06-10T01:49:55.532199Z","end":"2026-06-10T01:49:55.687398Z","steps":["trace[1875095331] 'process raft request' (duration: 154.918946ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:49:55.687505Z","caller":"traceutil/trace.go:171","msg":"trace[1090977623] transaction","detail":"{read_only:false; response_revision:15706; number_of_response:1; }","duration":"155.438476ms","start":"2026-06-10T01:49:55.532059Z","end":"2026-06-10T01:49:55.687498Z","steps":["trace[1090977623] 'process raft request' (duration: 154.840475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:49:55.687772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.494386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-z4zw4\" ","response":"range_response_count:1 size:20755"} {"level":"info","ts":"2026-06-10T01:49:55.687799Z","caller":"traceutil/trace.go:171","msg":"trace[1920594777] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-z4zw4; range_end:; response_count:1; response_revision:15709; }","duration":"108.578157ms","start":"2026-06-10T01:49:55.579214Z","end":"2026-06-10T01:49:55.687792Z","steps":["trace[1920594777] 'agreement among raft nodes before linearized reading' (duration: 108.462925ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:49:55.687906Z","caller":"traceutil/trace.go:171","msg":"trace[1456922337] transaction","detail":"{read_only:false; response_revision:15709; number_of_response:1; }","duration":"152.915728ms","start":"2026-06-10T01:49:55.534984Z","end":"2026-06-10T01:49:55.6879Z","steps":["trace[1456922337] 'process raft request' (duration: 152.235876ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:49:55.68995Z","caller":"traceutil/trace.go:171","msg":"trace[1228192096] transaction","detail":"{read_only:false; response_revision:15708; number_of_response:1; }","duration":"156.497676ms","start":"2026-06-10T01:49:55.533424Z","end":"2026-06-10T01:49:55.689921Z","steps":["trace[1228192096] 'process raft request' (duration: 153.744214ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:49:56.136861Z","caller":"traceutil/trace.go:171","msg":"trace[608109923] linearizableReadLoop","detail":"{readStateIndex:16249; appliedIndex:16248; }","duration":"177.800159ms","start":"2026-06-10T01:49:55.959029Z","end":"2026-06-10T01:49:56.136829Z","steps":["trace[608109923] 'read index received' (duration: 177.535024ms)","trace[608109923] 'applied index is now lower than readState.Index' (duration: 263.005µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:49:56.137061Z","caller":"traceutil/trace.go:171","msg":"trace[989770249] transaction","detail":"{read_only:false; response_revision:15710; number_of_response:1; }","duration":"218.373339ms","start":"2026-06-10T01:49:55.918557Z","end":"2026-06-10T01:49:56.136931Z","steps":["trace[989770249] 'process raft request' (duration: 218.034043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:49:56.13721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.027538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-06-10T01:49:56.137341Z","caller":"traceutil/trace.go:171","msg":"trace[1218156467] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:15710; }","duration":"173.229652ms","start":"2026-06-10T01:49:55.964078Z","end":"2026-06-10T01:49:56.137308Z","steps":["trace[1218156467] 'agreement among raft nodes before linearized reading' (duration: 172.970127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:49:56.138323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.292468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mxtrq\" ","response":"range_response_count:1 size:6514"} {"level":"info","ts":"2026-06-10T01:49:56.138414Z","caller":"traceutil/trace.go:171","msg":"trace[1529842847] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mxtrq; range_end:; response_count:1; response_revision:15710; }","duration":"179.41356ms","start":"2026-06-10T01:49:55.958987Z","end":"2026-06-10T01:49:56.1384Z","steps":["trace[1529842847] 'agreement among raft nodes before linearized reading' (duration: 178.003683ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:53:52.033415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15045} {"level":"info","ts":"2026-06-10T01:53:52.130163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15045,"took":"92.450122ms","hash":3442254955,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":31322112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-10T01:53:52.130245Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3442254955,"revision":15045,"compact-revision":12831} {"level":"info","ts":"2026-06-10T01:55:29.540618Z","caller":"traceutil/trace.go:171","msg":"trace[208714277] transaction","detail":"{read_only:false; response_revision:18218; number_of_response:1; }","duration":"138.206279ms","start":"2026-06-10T01:55:29.402379Z","end":"2026-06-10T01:55:29.540585Z","steps":["trace[208714277] 'process raft request' (duration: 137.981805ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:55:29.557903Z","caller":"traceutil/trace.go:171","msg":"trace[1382871492] transaction","detail":"{read_only:false; response_revision:18219; number_of_response:1; }","duration":"102.95358ms","start":"2026-06-10T01:55:29.454934Z","end":"2026-06-10T01:55:29.557888Z","steps":["trace[1382871492] 'process raft request' (duration: 102.470311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:55:37.420426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.945468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T01:55:37.420524Z","caller":"traceutil/trace.go:171","msg":"trace[1144961149] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:18290; }","duration":"171.089972ms","start":"2026-06-10T01:55:37.249414Z","end":"2026-06-10T01:55:37.420504Z","steps":["trace[1144961149] 'count revisions from in-memory index tree' (duration: 170.871537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:55:37.420623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.42292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T01:55:37.420715Z","caller":"traceutil/trace.go:171","msg":"trace[869862227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18290; }","duration":"340.565252ms","start":"2026-06-10T01:55:37.080129Z","end":"2026-06-10T01:55:37.420695Z","steps":["trace[869862227] 'range keys from in-memory index tree' (duration: 340.279387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:55:37.420756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:55:37.080066Z","time spent":"340.681995ms","remote":"127.0.0.1:41104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T01:55:37.421216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.543508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-z4zw4\" ","response":"range_response_count:1 size:20858"} {"level":"info","ts":"2026-06-10T01:55:37.421261Z","caller":"traceutil/trace.go:171","msg":"trace[2084539899] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-z4zw4; range_end:; response_count:1; response_revision:18290; }","duration":"250.630458ms","start":"2026-06-10T01:55:37.17062Z","end":"2026-06-10T01:55:37.421251Z","steps":["trace[2084539899] 'range keys from in-memory index tree' (duration: 250.363144ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:55:37.602013Z","caller":"traceutil/trace.go:171","msg":"trace[1853613475] transaction","detail":"{read_only:false; response_revision:18291; number_of_response:1; }","duration":"175.394233ms","start":"2026-06-10T01:55:37.426592Z","end":"2026-06-10T01:55:37.601986Z","steps":["trace[1853613475] 'process raft request' (duration: 175.150588ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:55:37.675597Z","caller":"traceutil/trace.go:171","msg":"trace[2064533927] transaction","detail":"{read_only:false; response_revision:18292; number_of_response:1; }","duration":"237.712044ms","start":"2026-06-10T01:55:37.437844Z","end":"2026-06-10T01:55:37.675556Z","steps":["trace[2064533927] 'process raft request' (duration: 200.293195ms)","trace[2064533927] 'compare' (duration: 36.850998ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:56:58.410125Z","caller":"traceutil/trace.go:171","msg":"trace[1151542596] transaction","detail":"{read_only:false; response_revision:19120; number_of_response:1; }","duration":"105.252404ms","start":"2026-06-10T01:56:58.304847Z","end":"2026-06-10T01:56:58.4101Z","steps":["trace[1151542596] 'process raft request' (duration: 105.095181ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:56:58.413248Z","caller":"traceutil/trace.go:171","msg":"trace[1752666826] transaction","detail":"{read_only:false; response_revision:19121; number_of_response:1; }","duration":"107.908354ms","start":"2026-06-10T01:56:58.305324Z","end":"2026-06-10T01:56:58.413232Z","steps":["trace[1752666826] 'process raft request' (duration: 107.831713ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:57:36.614451Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T01:57:36.62045Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-06-10T01:57:36.620552Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-06-10T01:58:52.040698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17486} {"level":"info","ts":"2026-06-10T01:58:52.137507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17486,"took":"93.77992ms","hash":1721403089,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":34664448,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-10T01:58:52.137568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1721403089,"revision":17486,"compact-revision":15045} {"level":"info","ts":"2026-06-10T02:00:54.107441Z","caller":"traceutil/trace.go:171","msg":"trace[1693494077] transaction","detail":"{read_only:false; response_revision:20795; number_of_response:1; }","duration":"191.34805ms","start":"2026-06-10T02:00:53.916063Z","end":"2026-06-10T02:00:54.107411Z","steps":["trace[1693494077] 'process raft request' (duration: 191.125806ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:00:54.914675Z","caller":"traceutil/trace.go:171","msg":"trace[1680949724] transaction","detail":"{read_only:false; response_revision:20796; number_of_response:1; }","duration":"141.602286ms","start":"2026-06-10T02:00:54.773044Z","end":"2026-06-10T02:00:54.914646Z","steps":["trace[1680949724] 'process raft request' (duration: 141.418243ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:00:55.199281Z","caller":"traceutil/trace.go:171","msg":"trace[1193255869] transaction","detail":"{read_only:false; response_revision:20797; number_of_response:1; }","duration":"227.328362ms","start":"2026-06-10T02:00:54.971916Z","end":"2026-06-10T02:00:55.199245Z","steps":["trace[1193255869] 'process raft request' (duration: 224.796264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:00:55.19943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.996957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:00:55.199265Z","caller":"traceutil/trace.go:171","msg":"trace[263276069] linearizableReadLoop","detail":"{readStateIndex:21503; appliedIndex:21502; }","duration":"119.804883ms","start":"2026-06-10T02:00:55.079425Z","end":"2026-06-10T02:00:55.199229Z","steps":["trace[263276069] 'read index received' (duration: 117.253384ms)","trace[263276069] 'applied index is now lower than readState.Index' (duration: 2.549809ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:00:55.199504Z","caller":"traceutil/trace.go:171","msg":"trace[402263090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20797; }","duration":"120.14231ms","start":"2026-06-10T02:00:55.079346Z","end":"2026-06-10T02:00:55.199489Z","steps":["trace[402263090] 'agreement among raft nodes before linearized reading' (duration: 120.002287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:00:55.445311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.351288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T02:00:55.445476Z","caller":"traceutil/trace.go:171","msg":"trace[2117188279] linearizableReadLoop","detail":"{readStateIndex:21505; appliedIndex:21504; }","duration":"159.948774ms","start":"2026-06-10T02:00:55.285501Z","end":"2026-06-10T02:00:55.445449Z","steps":["trace[2117188279] 'read index received' (duration: 33.214229ms)","trace[2117188279] 'applied index is now lower than readState.Index' (duration: 126.732145ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:00:55.44551Z","caller":"traceutil/trace.go:171","msg":"trace[2122265028] transaction","detail":"{read_only:false; response_revision:20798; number_of_response:1; }","duration":"230.259609ms","start":"2026-06-10T02:00:55.215214Z","end":"2026-06-10T02:00:55.445474Z","steps":["trace[2122265028] 'process raft request' (duration: 103.587865ms)","trace[2122265028] 'compare' (duration: 126.151913ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:00:55.44566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.141058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-10T02:00:55.44572Z","caller":"traceutil/trace.go:171","msg":"trace[2095610657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20798; }","duration":"160.27515ms","start":"2026-06-10T02:00:55.285428Z","end":"2026-06-10T02:00:55.445703Z","steps":["trace[2095610657] 'agreement among raft nodes before linearized reading' (duration: 160.092377ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:00:55.732285Z","caller":"traceutil/trace.go:171","msg":"trace[1732455122] linearizableReadLoop","detail":"{readStateIndex:21507; appliedIndex:21506; }","duration":"131.325172ms","start":"2026-06-10T02:00:55.600885Z","end":"2026-06-10T02:00:55.73221Z","steps":["trace[1732455122] 'read index received' (duration: 34.208729ms)","trace[1732455122] 'applied index is now lower than readState.Index' (duration: 97.114973ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:00:55.73235Z","caller":"traceutil/trace.go:171","msg":"trace[1448293355] transaction","detail":"{read_only:false; response_revision:20800; number_of_response:1; }","duration":"159.764681ms","start":"2026-06-10T02:00:55.572523Z","end":"2026-06-10T02:00:55.732288Z","steps":["trace[1448293355] 'process raft request' (duration: 62.633938ms)","trace[1448293355] 'compare' (duration: 96.802526ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:00:55.732925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.218812ms","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-10T02:00:55.733057Z","caller":"traceutil/trace.go:171","msg":"trace[1474380994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20800; }","duration":"119.403215ms","start":"2026-06-10T02:00:55.613627Z","end":"2026-06-10T02:00:55.73303Z","steps":["trace[1474380994] 'agreement among raft nodes before linearized reading' (duration: 119.111439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:00:55.733294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.376271ms","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-10T02:00:55.733363Z","caller":"traceutil/trace.go:171","msg":"trace[1787582556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20800; }","duration":"132.497923ms","start":"2026-06-10T02:00:55.600852Z","end":"2026-06-10T02:00:55.73335Z","steps":["trace[1787582556] 'agreement among raft nodes before linearized reading' (duration: 131.586726ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:00:55.940275Z","caller":"traceutil/trace.go:171","msg":"trace[1291513741] transaction","detail":"{read_only:false; response_revision:20801; number_of_response:1; }","duration":"201.822428ms","start":"2026-06-10T02:00:55.738399Z","end":"2026-06-10T02:00:55.940221Z","steps":["trace[1291513741] 'process raft request' (duration: 189.581876ms)","trace[1291513741] 'compare' (duration: 11.522829ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:00:55.941039Z","caller":"traceutil/trace.go:171","msg":"trace[597540733] linearizableReadLoop","detail":"{readStateIndex:21510; appliedIndex:21507; }","duration":"182.214277ms","start":"2026-06-10T02:00:55.758803Z","end":"2026-06-10T02:00:55.941017Z","steps":["trace[597540733] 'read index received' (duration: 169.123598ms)","trace[597540733] 'applied index is now lower than readState.Index' (duration: 13.089639ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:00:55.941078Z","caller":"traceutil/trace.go:171","msg":"trace[897966474] transaction","detail":"{read_only:false; response_revision:20802; number_of_response:1; }","duration":"202.550463ms","start":"2026-06-10T02:00:55.738513Z","end":"2026-06-10T02:00:55.941064Z","steps":["trace[897966474] 'process raft request' (duration: 202.351959ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:00:55.941199Z","caller":"traceutil/trace.go:171","msg":"trace[1857163671] transaction","detail":"{read_only:false; response_revision:20803; number_of_response:1; }","duration":"202.579414ms","start":"2026-06-10T02:00:55.738587Z","end":"2026-06-10T02:00:55.941166Z","steps":["trace[1857163671] 'process raft request' (duration: 202.39048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:00:55.941262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.40772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-10T02:00:55.941462Z","caller":"traceutil/trace.go:171","msg":"trace[1627293395] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20803; }","duration":"182.566204ms","start":"2026-06-10T02:00:55.758751Z","end":"2026-06-10T02:00:55.941317Z","steps":["trace[1627293395] 'agreement among raft nodes before linearized reading' (duration: 182.34998ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:03:52.047957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19873} {"level":"info","ts":"2026-06-10T02:03:52.167236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19873,"took":"114.416181ms","hash":130593201,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-10T02:03:52.1673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":130593201,"revision":19873,"compact-revision":17486} {"level":"info","ts":"2026-06-10T02:04:53.525888Z","caller":"traceutil/trace.go:171","msg":"trace[1641187385] linearizableReadLoop","detail":"{readStateIndex:24008; appliedIndex:24007; }","duration":"217.098479ms","start":"2026-06-10T02:04:53.308763Z","end":"2026-06-10T02:04:53.525862Z","steps":["trace[1641187385] 'read index received' (duration: 216.844664ms)","trace[1641187385] 'applied index is now lower than readState.Index' (duration: 252.735µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:04:53.526044Z","caller":"traceutil/trace.go:171","msg":"trace[2139803070] transaction","detail":"{read_only:false; response_revision:23227; number_of_response:1; }","duration":"359.545261ms","start":"2026-06-10T02:04:53.166437Z","end":"2026-06-10T02:04:53.525982Z","steps":["trace[2139803070] 'process raft request' (duration: 359.245546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:04:53.5266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.909143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-10T02:04:53.526702Z","caller":"traceutil/trace.go:171","msg":"trace[1217720985] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23227; }","duration":"180.005725ms","start":"2026-06-10T02:04:53.346681Z","end":"2026-06-10T02:04:53.526687Z","steps":["trace[1217720985] 'agreement among raft nodes before linearized reading' (duration: 179.833452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:04:53.526676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.012199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-10T02:04:53.526695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.983081ms","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-10T02:04:53.526865Z","caller":"traceutil/trace.go:171","msg":"trace[829428633] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:23227; }","duration":"128.219063ms","start":"2026-06-10T02:04:53.398614Z","end":"2026-06-10T02:04:53.526833Z","steps":["trace[829428633] 'agreement among raft nodes before linearized reading' (duration: 127.932368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:04:53.526297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.512036ms","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-10T02:04:53.526902Z","caller":"traceutil/trace.go:171","msg":"trace[657738718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23227; }","duration":"184.247816ms","start":"2026-06-10T02:04:53.342632Z","end":"2026-06-10T02:04:53.52688Z","steps":["trace[657738718] 'agreement among raft nodes before linearized reading' (duration: 183.875379ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:04:53.526982Z","caller":"traceutil/trace.go:171","msg":"trace[2085980895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23227; }","duration":"218.245761ms","start":"2026-06-10T02:04:53.308717Z","end":"2026-06-10T02:04:53.526962Z","steps":["trace[2085980895] 'agreement among raft nodes before linearized reading' (duration: 217.413735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:04:53.526793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.184916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-10T02:04:53.52709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:04:53.166417Z","time spent":"360.02878ms","remote":"127.0.0.1:41344","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-10T02:04:53.527168Z","caller":"traceutil/trace.go:171","msg":"trace[484865740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23227; }","duration":"182.529512ms","start":"2026-06-10T02:04:53.34455Z","end":"2026-06-10T02:04:53.52708Z","steps":["trace[484865740] 'agreement among raft nodes before linearized reading' (duration: 182.039273ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:04:53.636443Z","caller":"traceutil/trace.go:171","msg":"trace[1194757444] transaction","detail":"{read_only:false; response_revision:23230; number_of_response:1; }","duration":"103.724327ms","start":"2026-06-10T02:04:53.532696Z","end":"2026-06-10T02:04:53.63642Z","steps":["trace[1194757444] 'process raft request' (duration: 103.649266ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:04:53.636442Z","caller":"traceutil/trace.go:171","msg":"trace[834316001] transaction","detail":"{read_only:false; response_revision:23229; number_of_response:1; }","duration":"103.796309ms","start":"2026-06-10T02:04:53.532612Z","end":"2026-06-10T02:04:53.636408Z","steps":["trace[834316001] 'process raft request' (duration: 103.683227ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:04:53.636446Z","caller":"traceutil/trace.go:171","msg":"trace[1328528191] transaction","detail":"{read_only:false; response_revision:23228; number_of_response:1; }","duration":"103.795439ms","start":"2026-06-10T02:04:53.532612Z","end":"2026-06-10T02:04:53.636407Z","steps":["trace[1328528191] 'process raft request' (duration: 88.266675ms)","trace[1328528191] 'compare' (duration: 15.29189ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:04:53.636782Z","caller":"traceutil/trace.go:171","msg":"trace[540806534] transaction","detail":"{read_only:false; response_revision:23231; number_of_response:1; }","duration":"103.713447ms","start":"2026-06-10T02:04:53.533017Z","end":"2026-06-10T02:04:53.63673Z","steps":["trace[540806534] 'process raft request' (duration: 103.364591ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:30.052988Z","caller":"traceutil/trace.go:171","msg":"trace[1011403842] linearizableReadLoop","detail":"{readStateIndex:26262; appliedIndex:26261; }","duration":"119.711921ms","start":"2026-06-10T02:08:29.933245Z","end":"2026-06-10T02:08:30.052957Z","steps":["trace[1011403842] 'read index received' (duration: 118.61901ms)","trace[1011403842] 'applied index is now lower than readState.Index' (duration: 1.091641ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:30.053118Z","caller":"traceutil/trace.go:171","msg":"trace[1499479327] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"141.29918ms","start":"2026-06-10T02:08:29.911789Z","end":"2026-06-10T02:08:30.053088Z","steps":["trace[1499479327] 'process raft request' (duration: 140.164298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:30.053173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.908164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:30.053215Z","caller":"traceutil/trace.go:171","msg":"trace[321215793] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25427; }","duration":"120.019787ms","start":"2026-06-10T02:08:29.933182Z","end":"2026-06-10T02:08:30.053202Z","steps":["trace[321215793] 'agreement among raft nodes before linearized reading' (duration: 119.914995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:34.225744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.07ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:34.225852Z","caller":"traceutil/trace.go:171","msg":"trace[1315736680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25454; }","duration":"147.433136ms","start":"2026-06-10T02:08:34.078397Z","end":"2026-06-10T02:08:34.22583Z","steps":["trace[1315736680] 'range keys from in-memory index tree' (duration: 146.963787ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:34.490889Z","caller":"traceutil/trace.go:171","msg":"trace[566428808] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"111.26966ms","start":"2026-06-10T02:08:34.379598Z","end":"2026-06-10T02:08:34.490868Z","steps":["trace[566428808] 'process raft request' (duration: 111.113737ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:34.629972Z","caller":"traceutil/trace.go:171","msg":"trace[475233501] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"249.030524ms","start":"2026-06-10T02:08:34.380904Z","end":"2026-06-10T02:08:34.629935Z","steps":["trace[475233501] 'process raft request' (duration: 248.733057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:35.220658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.534146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T02:08:35.220842Z","caller":"traceutil/trace.go:171","msg":"trace[740075088] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"274.224782ms","start":"2026-06-10T02:08:34.946598Z","end":"2026-06-10T02:08:35.220822Z","steps":["trace[740075088] 'process raft request' (duration: 274.16063ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:35.221063Z","caller":"traceutil/trace.go:171","msg":"trace[1599659163] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"274.408155ms","start":"2026-06-10T02:08:34.946592Z","end":"2026-06-10T02:08:35.221Z","steps":["trace[1599659163] 'process raft request' (duration: 133.447441ms)","trace[1599659163] 'compare' (duration: 140.25188ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:35.319354Z","caller":"traceutil/trace.go:171","msg":"trace[1644297686] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"372.660219ms","start":"2026-06-10T02:08:34.946648Z","end":"2026-06-10T02:08:35.319308Z","steps":["trace[1644297686] 'process raft request' (duration: 372.378554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:35.319498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.714471ms","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":"info","ts":"2026-06-10T02:08:35.319497Z","caller":"traceutil/trace.go:171","msg":"trace[1648384175] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"352.934144ms","start":"2026-06-10T02:08:34.966518Z","end":"2026-06-10T02:08:35.319452Z","steps":["trace[1648384175] 'process raft request' (duration: 352.70833ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:35.31956Z","caller":"traceutil/trace.go:171","msg":"trace[92112908] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25464; }","duration":"332.829553ms","start":"2026-06-10T02:08:34.986716Z","end":"2026-06-10T02:08:35.319545Z","steps":["trace[92112908] 'agreement among raft nodes before linearized reading' (duration: 332.709381ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:35.319549Z","caller":"traceutil/trace.go:171","msg":"trace[737432352] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"263.796384ms","start":"2026-06-10T02:08:35.055719Z","end":"2026-06-10T02:08:35.319515Z","steps":["trace[737432352] 'process raft request' (duration: 263.554289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:35.319581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:34.946631Z","time spent":"372.839552ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T02:08:35.319607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:34.986706Z","time spent":"332.890044ms","remote":"127.0.0.1:57920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-06-10T02:08:35.319618Z","caller":"traceutil/trace.go:171","msg":"trace[1567951500] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"372.869473ms","start":"2026-06-10T02:08:34.946697Z","end":"2026-06-10T02:08:35.319567Z","steps":["trace[1567951500] 'process raft request' (duration: 372.479945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:35.319682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:34.966505Z","time spent":"353.067397ms","remote":"127.0.0.1:41344","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-10T02:08:35.319357Z","caller":"traceutil/trace.go:171","msg":"trace[279788461] linearizableReadLoop","detail":"{readStateIndex:26299; appliedIndex:26294; }","duration":"332.577618ms","start":"2026-06-10T02:08:34.986753Z","end":"2026-06-10T02:08:35.31933Z","steps":["trace[279788461] 'read index received' (duration: 93.32184ms)","trace[279788461] 'applied index is now lower than readState.Index' (duration: 239.254348ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:35.319813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:34.946675Z","time spent":"373.040355ms","remote":"127.0.0.1:41344","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-10T02:08:35.319888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.031641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:35.319932Z","caller":"traceutil/trace.go:171","msg":"trace[1055867109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25464; }","duration":"241.086073ms","start":"2026-06-10T02:08:35.078835Z","end":"2026-06-10T02:08:35.319921Z","steps":["trace[1055867109] 'agreement among raft nodes before linearized reading' (duration: 240.974921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:35.31996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.924542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T02:08:35.320004Z","caller":"traceutil/trace.go:171","msg":"trace[2102870315] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25464; }","duration":"103.004733ms","start":"2026-06-10T02:08:35.21698Z","end":"2026-06-10T02:08:35.319985Z","steps":["trace[2102870315] 'agreement among raft nodes before linearized reading' (duration: 102.910731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:35.320029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.639097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T02:08:35.320076Z","caller":"traceutil/trace.go:171","msg":"trace[1993687679] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:25464; }","duration":"180.694868ms","start":"2026-06-10T02:08:35.139369Z","end":"2026-06-10T02:08:35.320064Z","steps":["trace[1993687679] 'agreement among raft nodes before linearized reading' (duration: 180.522414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:35.482921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.894881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:35.483001Z","caller":"traceutil/trace.go:171","msg":"trace[1349184371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25464; }","duration":"162.057334ms","start":"2026-06-10T02:08:35.320926Z","end":"2026-06-10T02:08:35.482984Z","steps":["trace[1349184371] 'range keys from in-memory index tree' (duration: 161.813779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:35.48327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.865863ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-06-10T02:08:35.483546Z","caller":"traceutil/trace.go:171","msg":"trace[154929890] linearizableReadLoop","detail":"{readStateIndex:26301; appliedIndex:26300; }","duration":"114.643245ms","start":"2026-06-10T02:08:35.368694Z","end":"2026-06-10T02:08:35.483337Z","steps":["trace[154929890] 'read index received' (duration: 40.761µs)","trace[154929890] 'applied index is now lower than readState.Index' (duration: 114.601064ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:35.483856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.150624ms","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-10T02:08:35.483939Z","caller":"traceutil/trace.go:171","msg":"trace[2065979411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25464; }","duration":"115.281817ms","start":"2026-06-10T02:08:35.368639Z","end":"2026-06-10T02:08:35.483921Z","steps":["trace[2065979411] 'agreement among raft nodes before linearized reading' (duration: 114.93638ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:35.683233Z","caller":"traceutil/trace.go:171","msg":"trace[1489305303] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"194.825856ms","start":"2026-06-10T02:08:35.488357Z","end":"2026-06-10T02:08:35.683183Z","steps":["trace[1489305303] 'process raft request' (duration: 194.613812ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:35.68333Z","caller":"traceutil/trace.go:171","msg":"trace[775728365] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"189.181248ms","start":"2026-06-10T02:08:35.494132Z","end":"2026-06-10T02:08:35.683314Z","steps":["trace[775728365] 'process raft request' (duration: 188.893253ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:35.68336Z","caller":"traceutil/trace.go:171","msg":"trace[1214403019] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"198.889572ms","start":"2026-06-10T02:08:35.48444Z","end":"2026-06-10T02:08:35.68333Z","steps":["trace[1214403019] 'process raft request' (duration: 131.730828ms)","trace[1214403019] 'compare' (duration: 66.674516ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:36.18175Z","caller":"traceutil/trace.go:171","msg":"trace[879717014] linearizableReadLoop","detail":"{readStateIndex:26305; appliedIndex:26304; }","duration":"102.641817ms","start":"2026-06-10T02:08:36.079087Z","end":"2026-06-10T02:08:36.181729Z","steps":["trace[879717014] 'read index received' (duration: 102.459503ms)","trace[879717014] 'applied index is now lower than readState.Index' (duration: 181.264µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:36.181784Z","caller":"traceutil/trace.go:171","msg":"trace[1971356684] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"198.169879ms","start":"2026-06-10T02:08:35.983584Z","end":"2026-06-10T02:08:36.181754Z","steps":["trace[1971356684] 'process raft request' (duration: 198.015996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:36.181918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.81347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:36.181963Z","caller":"traceutil/trace.go:171","msg":"trace[1551266600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25468; }","duration":"102.900453ms","start":"2026-06-10T02:08:36.079042Z","end":"2026-06-10T02:08:36.181942Z","steps":["trace[1551266600] 'agreement among raft nodes before linearized reading' (duration: 102.831511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:36.368883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.982442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T02:08:36.36896Z","caller":"traceutil/trace.go:171","msg":"trace[577060660] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25468; }","duration":"104.232368ms","start":"2026-06-10T02:08:36.264705Z","end":"2026-06-10T02:08:36.368938Z","steps":["trace[577060660] 'count revisions from in-memory index tree' (duration: 103.840249ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:37.078399Z","caller":"traceutil/trace.go:171","msg":"trace[1511190991] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"129.75582ms","start":"2026-06-10T02:08:36.948586Z","end":"2026-06-10T02:08:37.078342Z","steps":["trace[1511190991] 'process raft request' (duration: 129.531096ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:37.405261Z","caller":"traceutil/trace.go:171","msg":"trace[1095613287] linearizableReadLoop","detail":"{readStateIndex:26310; appliedIndex:26309; }","duration":"322.547349ms","start":"2026-06-10T02:08:37.082687Z","end":"2026-06-10T02:08:37.405234Z","steps":["trace[1095613287] 'read index received' (duration: 322.413966ms)","trace[1095613287] 'applied index is now lower than readState.Index' (duration: 131.643µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:37.405315Z","caller":"traceutil/trace.go:171","msg":"trace[1021897468] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"332.844624ms","start":"2026-06-10T02:08:37.072433Z","end":"2026-06-10T02:08:37.405277Z","steps":["trace[1021897468] 'process raft request' (duration: 332.62255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:37.405515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:37.0724Z","time spent":"333.008536ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T02:08:37.405499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.784843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:37.405718Z","caller":"traceutil/trace.go:171","msg":"trace[1834805136] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25473; }","duration":"323.042617ms","start":"2026-06-10T02:08:37.082652Z","end":"2026-06-10T02:08:37.405694Z","steps":["trace[1834805136] 'agreement among raft nodes before linearized reading' (duration: 322.734892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:37.405775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:37.082639Z","time spent":"323.12499ms","remote":"127.0.0.1:41278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-10T02:08:37.406739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.606775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:37.406843Z","caller":"traceutil/trace.go:171","msg":"trace[1716010801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25476; }","duration":"298.738847ms","start":"2026-06-10T02:08:37.108086Z","end":"2026-06-10T02:08:37.406825Z","steps":["trace[1716010801] 'agreement among raft nodes before linearized reading' (duration: 298.585104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:37.407437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.31619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-10T02:08:37.407485Z","caller":"traceutil/trace.go:171","msg":"trace[422090666] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25476; }","duration":"180.401312ms","start":"2026-06-10T02:08:37.227068Z","end":"2026-06-10T02:08:37.40747Z","steps":["trace[422090666] 'agreement among raft nodes before linearized reading' (duration: 180.30411ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:37.407584Z","caller":"traceutil/trace.go:171","msg":"trace[1254608375] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"145.193513ms","start":"2026-06-10T02:08:37.262332Z","end":"2026-06-10T02:08:37.407526Z","steps":["trace[1254608375] 'process raft request' (duration: 144.140653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:37.407715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.505953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:37.407767Z","caller":"traceutil/trace.go:171","msg":"trace[1346100010] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25476; }","duration":"239.608175ms","start":"2026-06-10T02:08:37.168148Z","end":"2026-06-10T02:08:37.407756Z","steps":["trace[1346100010] 'agreement among raft nodes before linearized reading' (duration: 239.531013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:37.407695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.196886ms","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-10T02:08:37.407837Z","caller":"traceutil/trace.go:171","msg":"trace[760056031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25476; }","duration":"182.37935ms","start":"2026-06-10T02:08:37.225438Z","end":"2026-06-10T02:08:37.407817Z","steps":["trace[760056031] 'agreement among raft nodes before linearized reading' (duration: 182.098904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:47.822258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.075701ms","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-10T02:08:47.822354Z","caller":"traceutil/trace.go:171","msg":"trace[600303423] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25554; }","duration":"146.219714ms","start":"2026-06-10T02:08:47.676113Z","end":"2026-06-10T02:08:47.822332Z","steps":["trace[600303423] 'range keys from in-memory index tree' (duration: 145.99592ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:47.833705Z","caller":"traceutil/trace.go:171","msg":"trace[1718975175] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"109.066028ms","start":"2026-06-10T02:08:47.724622Z","end":"2026-06-10T02:08:47.833688Z","steps":["trace[1718975175] 'process raft request' (duration: 108.778263ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:50.904757Z","caller":"traceutil/trace.go:171","msg":"trace[1046213086] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"132.079135ms","start":"2026-06-10T02:08:50.772649Z","end":"2026-06-10T02:08:50.904728Z","steps":["trace[1046213086] 'process raft request' (duration: 131.862531ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:50.959247Z","caller":"traceutil/trace.go:171","msg":"trace[1688552780] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"186.377366ms","start":"2026-06-10T02:08:50.772838Z","end":"2026-06-10T02:08:50.959216Z","steps":["trace[1688552780] 'process raft request' (duration: 186.130531ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:52.058129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22165} {"level":"info","ts":"2026-06-10T02:08:52.17217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22165,"took":"108.816533ms","hash":4139534481,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":44056576,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-10T02:08:52.172232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4139534481,"revision":22165,"compact-revision":19873} {"level":"info","ts":"2026-06-10T02:08:52.820149Z","caller":"traceutil/trace.go:171","msg":"trace[8124744] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"248.023164ms","start":"2026-06-10T02:08:52.572103Z","end":"2026-06-10T02:08:52.820126Z","steps":["trace[8124744] 'process raft request' (duration: 247.865391ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:53.123825Z","caller":"traceutil/trace.go:171","msg":"trace[1759457018] linearizableReadLoop","detail":"{readStateIndex:26431; appliedIndex:26430; }","duration":"178.739081ms","start":"2026-06-10T02:08:52.945054Z","end":"2026-06-10T02:08:53.123793Z","steps":["trace[1759457018] 'read index received' (duration: 178.545207ms)","trace[1759457018] 'applied index is now lower than readState.Index' (duration: 192.404µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:53.123862Z","caller":"traceutil/trace.go:171","msg":"trace[637985050] transaction","detail":"{read_only:false; response_revision:25590; number_of_response:1; }","duration":"211.175796ms","start":"2026-06-10T02:08:52.912662Z","end":"2026-06-10T02:08:53.123838Z","steps":["trace[637985050] 'process raft request' (duration: 210.950522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:53.124101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.009825ms","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-10T02:08:53.124271Z","caller":"traceutil/trace.go:171","msg":"trace[477477515] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25590; }","duration":"179.189919ms","start":"2026-06-10T02:08:52.944998Z","end":"2026-06-10T02:08:53.124187Z","steps":["trace[477477515] 'agreement among raft nodes before linearized reading' (duration: 178.897603ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:53.137311Z","caller":"traceutil/trace.go:171","msg":"trace[1050176332] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"173.181605ms","start":"2026-06-10T02:08:52.964108Z","end":"2026-06-10T02:08:53.13729Z","steps":["trace[1050176332] 'process raft request' (duration: 172.710415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:53.137331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.807523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-06-10T02:08:53.137411Z","caller":"traceutil/trace.go:171","msg":"trace[179008556] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25591; }","duration":"138.922285ms","start":"2026-06-10T02:08:52.998468Z","end":"2026-06-10T02:08:53.13739Z","steps":["trace[179008556] 'agreement among raft nodes before linearized reading' (duration: 138.716211ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:53.582588Z","caller":"traceutil/trace.go:171","msg":"trace[35001337] linearizableReadLoop","detail":"{readStateIndex:26434; appliedIndex:26433; }","duration":"257.761109ms","start":"2026-06-10T02:08:53.324805Z","end":"2026-06-10T02:08:53.582566Z","steps":["trace[35001337] 'read index received' (duration: 257.655707ms)","trace[35001337] 'applied index is now lower than readState.Index' (duration: 104.172µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:53.582661Z","caller":"traceutil/trace.go:171","msg":"trace[1996455422] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"373.175389ms","start":"2026-06-10T02:08:53.209448Z","end":"2026-06-10T02:08:53.582623Z","steps":["trace[1996455422] 'process raft request' (duration: 372.942684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:53.582776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.939322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:53.582847Z","caller":"traceutil/trace.go:171","msg":"trace[560260817] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25593; }","duration":"258.064895ms","start":"2026-06-10T02:08:53.324768Z","end":"2026-06-10T02:08:53.582833Z","steps":["trace[560260817] 'agreement among raft nodes before linearized reading' (duration: 257.910362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:53.582856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:53.209428Z","time spent":"373.313092ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:08:53.619741Z","caller":"traceutil/trace.go:171","msg":"trace[865251481] transaction","detail":"{read_only:false; response_revision:25594; number_of_response:1; }","duration":"149.571477ms","start":"2026-06-10T02:08:53.470153Z","end":"2026-06-10T02:08:53.619724Z","steps":["trace[865251481] 'process raft request' (duration: 149.328972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:53.619862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.848926ms","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-10T02:08:53.619912Z","caller":"traceutil/trace.go:171","msg":"trace[1671948235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25595; }","duration":"116.913937ms","start":"2026-06-10T02:08:53.502984Z","end":"2026-06-10T02:08:53.619898Z","steps":["trace[1671948235] 'agreement among raft nodes before linearized reading' (duration: 116.743104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:53.619927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.013645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-10T02:08:53.619954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.642201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-10T02:08:53.619989Z","caller":"traceutil/trace.go:171","msg":"trace[1223629097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25595; }","duration":"130.110328ms","start":"2026-06-10T02:08:53.489864Z","end":"2026-06-10T02:08:53.619974Z","steps":["trace[1223629097] 'agreement among raft nodes before linearized reading' (duration: 129.914474ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:53.620026Z","caller":"traceutil/trace.go:171","msg":"trace[626542969] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25595; }","duration":"108.747103ms","start":"2026-06-10T02:08:53.511264Z","end":"2026-06-10T02:08:53.620011Z","steps":["trace[626542969] 'agreement among raft nodes before linearized reading' (duration: 108.600919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:53.620064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.584817ms","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-10T02:08:53.620102Z","caller":"traceutil/trace.go:171","msg":"trace[168542399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25595; }","duration":"110.622688ms","start":"2026-06-10T02:08:53.50947Z","end":"2026-06-10T02:08:53.620093Z","steps":["trace[168542399] 'agreement among raft nodes before linearized reading' (duration: 110.529326ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:53.620187Z","caller":"traceutil/trace.go:171","msg":"trace[1161675234] transaction","detail":"{read_only:false; response_revision:25595; number_of_response:1; }","duration":"146.908647ms","start":"2026-06-10T02:08:53.473267Z","end":"2026-06-10T02:08:53.620175Z","steps":["trace[1161675234] 'process raft request' (duration: 146.363656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:53.621485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.138079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-10T02:08:53.621547Z","caller":"traceutil/trace.go:171","msg":"trace[90179816] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25595; }","duration":"285.220091ms","start":"2026-06-10T02:08:53.33631Z","end":"2026-06-10T02:08:53.62153Z","steps":["trace[90179816] 'agreement among raft nodes before linearized reading' (duration: 283.441856ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:53.812475Z","caller":"traceutil/trace.go:171","msg":"trace[1099574607] transaction","detail":"{read_only:false; response_revision:25596; number_of_response:1; }","duration":"187.856074ms","start":"2026-06-10T02:08:53.624583Z","end":"2026-06-10T02:08:53.812439Z","steps":["trace[1099574607] 'process raft request' (duration: 162.441182ms)","trace[1099574607] 'compare' (duration: 24.838531ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:54.151993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.126418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T02:08:54.152656Z","caller":"traceutil/trace.go:171","msg":"trace[1626207003] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"527.597917ms","start":"2026-06-10T02:08:53.625036Z","end":"2026-06-10T02:08:54.152634Z","steps":["trace[1626207003] 'process raft request' (duration: 527.491165ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:54.152657Z","caller":"traceutil/trace.go:171","msg":"trace[986732561] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"527.594717ms","start":"2026-06-10T02:08:53.625024Z","end":"2026-06-10T02:08:54.152619Z","steps":["trace[986732561] 'process raft request' (duration: 339.743454ms)","trace[986732561] 'compare' (duration: 186.990356ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:54.152743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:53.625013Z","time spent":"527.691619ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T02:08:54.152837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:53.625011Z","time spent":"527.72619ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:08:54.153077Z","caller":"traceutil/trace.go:171","msg":"trace[1951404571] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"526.424435ms","start":"2026-06-10T02:08:53.626635Z","end":"2026-06-10T02:08:54.153059Z","steps":["trace[1951404571] 'process raft request' (duration: 525.967146ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:54.153165Z","caller":"traceutil/trace.go:171","msg":"trace[1069959622] linearizableReadLoop","detail":"{readStateIndex:26441; appliedIndex:26437; }","duration":"280.684214ms","start":"2026-06-10T02:08:53.87247Z","end":"2026-06-10T02:08:54.153154Z","steps":["trace[1069959622] 'read index received' (duration: 92.313602ms)","trace[1069959622] 'applied index is now lower than readState.Index' (duration: 188.369542ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:54.153242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:53.626618Z","time spent":"526.525826ms","remote":"127.0.0.1:41344","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-10T02:08:54.153283Z","caller":"traceutil/trace.go:171","msg":"trace[2126348223] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"299.618793ms","start":"2026-06-10T02:08:53.853627Z","end":"2026-06-10T02:08:54.153246Z","steps":["trace[2126348223] 'process raft request' (duration: 299.323068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:54.153393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.921178ms","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-10T02:08:54.15343Z","caller":"traceutil/trace.go:171","msg":"trace[2097255847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25600; }","duration":"280.988799ms","start":"2026-06-10T02:08:53.872431Z","end":"2026-06-10T02:08:54.15342Z","steps":["trace[2097255847] 'agreement among raft nodes before linearized reading' (duration: 280.801485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:54.153527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.958607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:54.153581Z","caller":"traceutil/trace.go:171","msg":"trace[1763677147] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25600; }","duration":"138.059619ms","start":"2026-06-10T02:08:54.015507Z","end":"2026-06-10T02:08:54.153567Z","steps":["trace[1763677147] 'agreement among raft nodes before linearized reading' (duration: 137.979458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:54.153641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.325427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1042"} {"level":"info","ts":"2026-06-10T02:08:54.153698Z","caller":"traceutil/trace.go:171","msg":"trace[1929959264] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:25600; }","duration":"125.402908ms","start":"2026-06-10T02:08:54.028284Z","end":"2026-06-10T02:08:54.153687Z","steps":["trace[1929959264] 'agreement among raft nodes before linearized reading' (duration: 125.235455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:54.473592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.667114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-10T02:08:54.473687Z","caller":"traceutil/trace.go:171","msg":"trace[1449487352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25601; }","duration":"211.825757ms","start":"2026-06-10T02:08:54.261841Z","end":"2026-06-10T02:08:54.473667Z","steps":["trace[1449487352] 'range keys from in-memory index tree' (duration: 211.230207ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:54.599948Z","caller":"traceutil/trace.go:171","msg":"trace[339850056] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"121.145838ms","start":"2026-06-10T02:08:54.478769Z","end":"2026-06-10T02:08:54.599915Z","steps":["trace[339850056] 'process raft request' (duration: 120.941704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:54.965643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.2252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:54.965755Z","caller":"traceutil/trace.go:171","msg":"trace[1008671431] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25602; }","duration":"285.433794ms","start":"2026-06-10T02:08:54.680298Z","end":"2026-06-10T02:08:54.965732Z","steps":["trace[1008671431] 'count revisions from in-memory index tree' (duration: 285.086207ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:54.966359Z","caller":"traceutil/trace.go:171","msg":"trace[212937953] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"139.765051ms","start":"2026-06-10T02:08:54.826515Z","end":"2026-06-10T02:08:54.96628Z","steps":["trace[212937953] 'process raft request' (duration: 139.416054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:55.331502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.162068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:55.331603Z","caller":"traceutil/trace.go:171","msg":"trace[1872624959] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25603; }","duration":"219.353541ms","start":"2026-06-10T02:08:55.112226Z","end":"2026-06-10T02:08:55.33158Z","steps":["trace[1872624959] 'count revisions from in-memory index tree' (duration: 219.061885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:55.331606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.92677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:55.331731Z","caller":"traceutil/trace.go:171","msg":"trace[1110483652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25603; }","duration":"251.131143ms","start":"2026-06-10T02:08:55.080571Z","end":"2026-06-10T02:08:55.331703Z","steps":["trace[1110483652] 'range keys from in-memory index tree' (duration: 250.686555ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:55.333047Z","caller":"traceutil/trace.go:171","msg":"trace[1412377134] linearizableReadLoop","detail":"{readStateIndex:26445; appliedIndex:26444; }","duration":"193.284686ms","start":"2026-06-10T02:08:55.139741Z","end":"2026-06-10T02:08:55.333026Z","steps":["trace[1412377134] 'read index received' (duration: 193.025102ms)","trace[1412377134] 'applied index is now lower than readState.Index' (duration: 257.774µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:55.333252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.50556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T02:08:55.333287Z","caller":"traceutil/trace.go:171","msg":"trace[973340340] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:25604; }","duration":"193.575081ms","start":"2026-06-10T02:08:55.139701Z","end":"2026-06-10T02:08:55.333276Z","steps":["trace[973340340] 'agreement among raft nodes before linearized reading' (duration: 193.402908ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:55.333436Z","caller":"traceutil/trace.go:171","msg":"trace[1533340731] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"202.769606ms","start":"2026-06-10T02:08:55.130575Z","end":"2026-06-10T02:08:55.333344Z","steps":["trace[1533340731] 'process raft request' (duration: 202.235417ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:55.3555Z","caller":"traceutil/trace.go:171","msg":"trace[136693773] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"212.261556ms","start":"2026-06-10T02:08:55.14321Z","end":"2026-06-10T02:08:55.355471Z","steps":["trace[136693773] 'process raft request' (duration: 212.021161ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:55.356209Z","caller":"traceutil/trace.go:171","msg":"trace[607439135] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"179.709268ms","start":"2026-06-10T02:08:55.176254Z","end":"2026-06-10T02:08:55.355963Z","steps":["trace[607439135] 'process raft request' (duration: 179.162158ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:55.94612Z","caller":"traceutil/trace.go:171","msg":"trace[553071729] linearizableReadLoop","detail":"{readStateIndex:26451; appliedIndex:26450; }","duration":"129.345844ms","start":"2026-06-10T02:08:55.816739Z","end":"2026-06-10T02:08:55.946085Z","steps":["trace[553071729] 'read index received' (duration: 103.633256ms)","trace[553071729] 'applied index is now lower than readState.Index' (duration: 25.711768ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:55.94628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.520837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-10T02:08:55.946317Z","caller":"traceutil/trace.go:171","msg":"trace[1895667738] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25609; }","duration":"129.594599ms","start":"2026-06-10T02:08:55.81671Z","end":"2026-06-10T02:08:55.946304Z","steps":["trace[1895667738] 'agreement among raft nodes before linearized reading' (duration: 129.470956ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:55.946496Z","caller":"traceutil/trace.go:171","msg":"trace[1708140184] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"319.391027ms","start":"2026-06-10T02:08:55.626735Z","end":"2026-06-10T02:08:55.946126Z","steps":["trace[1708140184] 'process raft request' (duration: 293.216871ms)","trace[1708140184] 'compare' (duration: 25.698598ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:55.946825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:55.626695Z","time spent":"319.966648ms","remote":"127.0.0.1:41344","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-10T02:08:56.123948Z","caller":"traceutil/trace.go:171","msg":"trace[797354810] linearizableReadLoop","detail":"{readStateIndex:26452; appliedIndex:26451; }","duration":"160.433104ms","start":"2026-06-10T02:08:55.963487Z","end":"2026-06-10T02:08:56.12392Z","steps":["trace[797354810] 'read index received' (duration: 132.159718ms)","trace[797354810] 'applied index is now lower than readState.Index' (duration: 28.271806ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:56.124287Z","caller":"traceutil/trace.go:171","msg":"trace[131607657] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"171.799809ms","start":"2026-06-10T02:08:55.952469Z","end":"2026-06-10T02:08:56.124269Z","steps":["trace[131607657] 'process raft request' (duration: 143.132466ms)","trace[131607657] 'compare' (duration: 23.23489ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:56.124321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.410116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:1 size:969"} {"level":"info","ts":"2026-06-10T02:08:56.124419Z","caller":"traceutil/trace.go:171","msg":"trace[980468078] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:1; response_revision:25610; }","duration":"146.576209ms","start":"2026-06-10T02:08:55.97782Z","end":"2026-06-10T02:08:56.124396Z","steps":["trace[980468078] 'agreement among raft nodes before linearized reading' (duration: 146.275984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.124407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.900852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-06-10T02:08:56.124673Z","caller":"traceutil/trace.go:171","msg":"trace[422877695] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25610; }","duration":"161.217008ms","start":"2026-06-10T02:08:55.963432Z","end":"2026-06-10T02:08:56.124649Z","steps":["trace[422877695] 'agreement among raft nodes before linearized reading' (duration: 160.880942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.124735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.218943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"info","ts":"2026-06-10T02:08:56.124783Z","caller":"traceutil/trace.go:171","msg":"trace[1915649682] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:25610; }","duration":"146.295694ms","start":"2026-06-10T02:08:55.978475Z","end":"2026-06-10T02:08:56.124771Z","steps":["trace[1915649682] 'agreement among raft nodes before linearized reading' (duration: 146.183312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.422406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.768646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T02:08:56.423841Z","caller":"traceutil/trace.go:171","msg":"trace[1821471704] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"285.341952ms","start":"2026-06-10T02:08:56.13845Z","end":"2026-06-10T02:08:56.423792Z","steps":["trace[1821471704] 'process raft request' (duration: 149.064188ms)","trace[1821471704] 'compare' (duration: 133.826058ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:56.424148Z","caller":"traceutil/trace.go:171","msg":"trace[440492636] linearizableReadLoop","detail":"{readStateIndex:26454; appliedIndex:26452; }","duration":"266.462064ms","start":"2026-06-10T02:08:56.15767Z","end":"2026-06-10T02:08:56.424132Z","steps":["trace[440492636] 'read index received' (duration: 129.829173ms)","trace[440492636] 'applied index is now lower than readState.Index' (duration: 136.631831ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:08:56.424206Z","caller":"traceutil/trace.go:171","msg":"trace[674358637] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"265.798521ms","start":"2026-06-10T02:08:56.158387Z","end":"2026-06-10T02:08:56.424185Z","steps":["trace[674358637] 'process raft request' (duration: 265.686528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.424508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.834741ms","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-10T02:08:56.424489Z","caller":"traceutil/trace.go:171","msg":"trace[1226404103] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"285.71086ms","start":"2026-06-10T02:08:56.138744Z","end":"2026-06-10T02:08:56.424455Z","steps":["trace[1226404103] 'process raft request' (duration: 285.16601ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:56.424555Z","caller":"traceutil/trace.go:171","msg":"trace[2100177516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25613; }","duration":"266.903273ms","start":"2026-06-10T02:08:56.157642Z","end":"2026-06-10T02:08:56.424546Z","steps":["trace[2100177516] 'agreement among raft nodes before linearized reading' (duration: 266.651258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.424884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.551386ms","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-10T02:08:56.424921Z","caller":"traceutil/trace.go:171","msg":"trace[1727128430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25613; }","duration":"266.623758ms","start":"2026-06-10T02:08:56.15829Z","end":"2026-06-10T02:08:56.424913Z","steps":["trace[1727128430] 'agreement among raft nodes before linearized reading' (duration: 266.298232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.424913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.135756ms","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-10T02:08:56.42496Z","caller":"traceutil/trace.go:171","msg":"trace[1507305099] 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:25613; }","duration":"181.221127ms","start":"2026-06-10T02:08:56.243724Z","end":"2026-06-10T02:08:56.424945Z","steps":["trace[1507305099] 'agreement among raft nodes before linearized reading' (duration: 181.110425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.425354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.869301ms","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-10T02:08:56.425426Z","caller":"traceutil/trace.go:171","msg":"trace[1600356649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25613; }","duration":"266.967593ms","start":"2026-06-10T02:08:56.158439Z","end":"2026-06-10T02:08:56.425406Z","steps":["trace[1600356649] 'agreement among raft nodes before linearized reading' (duration: 266.766679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.425588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.583957ms","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-10T02:08:56.425644Z","caller":"traceutil/trace.go:171","msg":"trace[1185525219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25613; }","duration":"246.642718ms","start":"2026-06-10T02:08:56.17899Z","end":"2026-06-10T02:08:56.425633Z","steps":["trace[1185525219] 'agreement among raft nodes before linearized reading' (duration: 246.417844ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:56.909569Z","caller":"traceutil/trace.go:171","msg":"trace[3143101] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"480.535254ms","start":"2026-06-10T02:08:56.429014Z","end":"2026-06-10T02:08:56.909549Z","steps":["trace[3143101] 'process raft request' (duration: 480.393642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.909712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.429002Z","time spent":"480.630907ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:08:56.942832Z","caller":"traceutil/trace.go:171","msg":"trace[1093093534] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"512.734495ms","start":"2026-06-10T02:08:56.430081Z","end":"2026-06-10T02:08:56.942816Z","steps":["trace[1093093534] 'process raft request' (duration: 512.579692ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:56.94312Z","caller":"traceutil/trace.go:171","msg":"trace[693491319] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"512.784776ms","start":"2026-06-10T02:08:56.430304Z","end":"2026-06-10T02:08:56.943088Z","steps":["trace[693491319] 'process raft request' (duration: 512.44961ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:56.943208Z","caller":"traceutil/trace.go:171","msg":"trace[324619885] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"509.80973ms","start":"2026-06-10T02:08:56.433382Z","end":"2026-06-10T02:08:56.943192Z","steps":["trace[324619885] 'process raft request' (duration: 509.696308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.943245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.87323ms","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-10T02:08:56.943229Z","caller":"traceutil/trace.go:171","msg":"trace[406709142] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"511.40146ms","start":"2026-06-10T02:08:56.431772Z","end":"2026-06-10T02:08:56.943173Z","steps":["trace[406709142] 'process raft request' (duration: 511.205256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.943281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.433376Z","time spent":"509.873601ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:08:56.943146Z","caller":"traceutil/trace.go:171","msg":"trace[1486780792] linearizableReadLoop","detail":"{readStateIndex:26458; appliedIndex:26456; }","duration":"511.777968ms","start":"2026-06-10T02:08:56.431349Z","end":"2026-06-10T02:08:56.943127Z","steps":["trace[1486780792] 'read index received' (duration: 478.220521ms)","trace[1486780792] 'applied index is now lower than readState.Index' (duration: 33.556457ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:56.943457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.431761Z","time spent":"511.584523ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:08:56.943293Z","caller":"traceutil/trace.go:171","msg":"trace[945833747] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25618; }","duration":"511.960161ms","start":"2026-06-10T02:08:56.431323Z","end":"2026-06-10T02:08:56.943283Z","steps":["trace[945833747] 'agreement among raft nodes before linearized reading' (duration: 511.869049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.94365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.485871ms","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-10T02:08:56.943695Z","caller":"traceutil/trace.go:171","msg":"trace[2116650443] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25618; }","duration":"424.566753ms","start":"2026-06-10T02:08:56.519118Z","end":"2026-06-10T02:08:56.943685Z","steps":["trace[2116650443] 'agreement among raft nodes before linearized reading' (duration: 424.487392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.943648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.431309Z","time spent":"512.264887ms","remote":"127.0.0.1:41278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-06-10T02:08:56.943735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.519111Z","time spent":"424.615274ms","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-10T02:08:56.94396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.992561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-06-10T02:08:56.943986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.885078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-10T02:08:56.944028Z","caller":"traceutil/trace.go:171","msg":"trace[1860705670] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25618; }","duration":"318.94108ms","start":"2026-06-10T02:08:56.625074Z","end":"2026-06-10T02:08:56.944015Z","steps":["trace[1860705670] 'agreement among raft nodes before linearized reading' (duration: 318.774156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.944069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.624797Z","time spent":"319.265296ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-10T02:08:56.945277Z","caller":"traceutil/trace.go:171","msg":"trace[230477736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25618; }","duration":"338.052923ms","start":"2026-06-10T02:08:56.605934Z","end":"2026-06-10T02:08:56.943987Z","steps":["trace[230477736] 'agreement among raft nodes before linearized reading' (duration: 337.935651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:56.945357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.60592Z","time spent":"339.418988ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T02:08:56.943149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.430062Z","time spent":"512.821817ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T02:08:56.945823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.430296Z","time spent":"512.958909ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:08:57.287243Z","caller":"traceutil/trace.go:171","msg":"trace[37948946] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"337.156065ms","start":"2026-06-10T02:08:56.95003Z","end":"2026-06-10T02:08:57.287186Z","steps":["trace[37948946] 'process raft request' (duration: 336.453932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:57.290372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.950007Z","time spent":"337.862529ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:08:57.319134Z","caller":"traceutil/trace.go:171","msg":"trace[1960688267] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"367.97095ms","start":"2026-06-10T02:08:56.951133Z","end":"2026-06-10T02:08:57.319104Z","steps":["trace[1960688267] 'process raft request' (duration: 367.774515ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:57.319163Z","caller":"traceutil/trace.go:171","msg":"trace[2098048942] linearizableReadLoop","detail":"{readStateIndex:26463; appliedIndex:26460; }","duration":"367.732696ms","start":"2026-06-10T02:08:56.951403Z","end":"2026-06-10T02:08:57.319135Z","steps":["trace[2098048942] 'read index received' (duration: 334.968974ms)","trace[2098048942] 'applied index is now lower than readState.Index' (duration: 32.762612ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:08:57.319276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.951119Z","time spent":"368.081212ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:08:57.319455Z","caller":"traceutil/trace.go:171","msg":"trace[290509167] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"368.155073ms","start":"2026-06-10T02:08:56.951288Z","end":"2026-06-10T02:08:57.319443Z","steps":["trace[290509167] 'process raft request' (duration: 367.711774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:57.31955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.951281Z","time spent":"368.215444ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T02:08:57.319699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.088685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:57.319736Z","caller":"traceutil/trace.go:171","msg":"trace[642146408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25622; }","duration":"240.166726ms","start":"2026-06-10T02:08:57.07956Z","end":"2026-06-10T02:08:57.319727Z","steps":["trace[642146408] 'agreement among raft nodes before linearized reading' (duration: 240.106165ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:08:57.319851Z","caller":"traceutil/trace.go:171","msg":"trace[1981088799] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"344.960963ms","start":"2026-06-10T02:08:56.974882Z","end":"2026-06-10T02:08:57.319843Z","steps":["trace[1981088799] 'process raft request' (duration: 344.180207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:57.319899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.974873Z","time spent":"345.001454ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T02:08:57.319942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.53439ms","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-10T02:08:57.319985Z","caller":"traceutil/trace.go:171","msg":"trace[1774694140] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25622; }","duration":"368.576021ms","start":"2026-06-10T02:08:56.951397Z","end":"2026-06-10T02:08:57.319973Z","steps":["trace[1774694140] 'agreement among raft nodes before linearized reading' (duration: 368.51864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:57.32003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.543599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-10T02:08:57.32005Z","caller":"traceutil/trace.go:171","msg":"trace[1991262278] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25622; }","duration":"185.62056ms","start":"2026-06-10T02:08:57.134422Z","end":"2026-06-10T02:08:57.320043Z","steps":["trace[1991262278] 'agreement among raft nodes before linearized reading' (duration: 185.549939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:08:57.320056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:08:56.95138Z","time spent":"368.665953ms","remote":"127.0.0.1:41360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-10T02:08:57.319891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.974013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:08:57.320194Z","caller":"traceutil/trace.go:171","msg":"trace[1254821823] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25622; }","duration":"132.309099ms","start":"2026-06-10T02:08:57.187877Z","end":"2026-06-10T02:08:57.320186Z","steps":["trace[1254821823] 'agreement among raft nodes before linearized reading' (duration: 131.976763ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:05.539519Z","caller":"traceutil/trace.go:171","msg":"trace[1192213716] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"144.939ms","start":"2026-06-10T02:09:05.394554Z","end":"2026-06-10T02:09:05.539493Z","steps":["trace[1192213716] 'process raft request' (duration: 144.752956ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:06.429138Z","caller":"traceutil/trace.go:171","msg":"trace[1017583233] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"188.494446ms","start":"2026-06-10T02:09:06.240611Z","end":"2026-06-10T02:09:06.429106Z","steps":["trace[1017583233] 'process raft request' (duration: 163.500532ms)","trace[1017583233] 'compare' (duration: 24.198759ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:06.610977Z","caller":"traceutil/trace.go:171","msg":"trace[1512382064] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26594; }","duration":"152.33948ms","start":"2026-06-10T02:09:06.4586Z","end":"2026-06-10T02:09:06.61094Z","steps":["trace[1512382064] 'read index received' (duration: 150.944074ms)","trace[1512382064] 'applied index is now lower than readState.Index' (duration: 1.393646ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:06.611035Z","caller":"traceutil/trace.go:171","msg":"trace[1327997509] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"154.568461ms","start":"2026-06-10T02:09:06.456433Z","end":"2026-06-10T02:09:06.611001Z","steps":["trace[1327997509] 'process raft request' (duration: 153.150905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:06.611277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.651046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-06-10T02:09:06.61134Z","caller":"traceutil/trace.go:171","msg":"trace[138300072] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25751; }","duration":"152.739637ms","start":"2026-06-10T02:09:06.458586Z","end":"2026-06-10T02:09:06.611326Z","steps":["trace[138300072] 'agreement among raft nodes before linearized reading' (duration: 152.511943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:06.697379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.40203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ae2a37bf-3f89-44d4-a02d-5506744a3942\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-06-10T02:09:06.697472Z","caller":"traceutil/trace.go:171","msg":"trace[33937489] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ae2a37bf-3f89-44d4-a02d-5506744a3942; range_end:; response_count:1; response_revision:25752; }","duration":"152.575984ms","start":"2026-06-10T02:09:06.544876Z","end":"2026-06-10T02:09:06.697452Z","steps":["trace[33937489] 'agreement among raft nodes before linearized reading' (duration: 152.276908ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:06.697728Z","caller":"traceutil/trace.go:171","msg":"trace[179506635] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"238.391182ms","start":"2026-06-10T02:09:06.459309Z","end":"2026-06-10T02:09:06.6977Z","steps":["trace[179506635] 'process raft request' (duration: 237.289451ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:06.812346Z","caller":"traceutil/trace.go:171","msg":"trace[1168764706] linearizableReadLoop","detail":"{readStateIndex:26597; appliedIndex:26596; }","duration":"101.692339ms","start":"2026-06-10T02:09:06.710625Z","end":"2026-06-10T02:09:06.812317Z","steps":["trace[1168764706] 'read index received' (duration: 49.598431ms)","trace[1168764706] 'applied index is now lower than readState.Index' (duration: 52.092388ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:06.812833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.187958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-06-10T02:09:06.812924Z","caller":"traceutil/trace.go:171","msg":"trace[865852662] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"112.438132ms","start":"2026-06-10T02:09:06.700446Z","end":"2026-06-10T02:09:06.812884Z","steps":["trace[865852662] 'process raft request' (duration: 59.725412ms)","trace[865852662] 'compare' (duration: 51.966257ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:06.812929Z","caller":"traceutil/trace.go:171","msg":"trace[1350711175] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25753; }","duration":"102.322871ms","start":"2026-06-10T02:09:06.710586Z","end":"2026-06-10T02:09:06.812909Z","steps":["trace[1350711175] 'agreement among raft nodes before linearized reading' (duration: 102.037436ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:06.98308Z","caller":"traceutil/trace.go:171","msg":"trace[1693380518] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"161.728608ms","start":"2026-06-10T02:09:06.821325Z","end":"2026-06-10T02:09:06.983054Z","steps":["trace[1693380518] 'process raft request' (duration: 106.442519ms)","trace[1693380518] 'compare' (duration: 54.651987ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:12.231562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.60731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-10T02:09:12.231609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.821844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-06-10T02:09:12.231668Z","caller":"traceutil/trace.go:171","msg":"trace[1746501826] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25812; }","duration":"195.795814ms","start":"2026-06-10T02:09:12.035849Z","end":"2026-06-10T02:09:12.231645Z","steps":["trace[1746501826] 'count revisions from in-memory index tree' (duration: 195.496197ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:12.231696Z","caller":"traceutil/trace.go:171","msg":"trace[888351891] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25812; }","duration":"254.960346ms","start":"2026-06-10T02:09:11.976711Z","end":"2026-06-10T02:09:12.231672Z","steps":["trace[888351891] 'range keys from in-memory index tree' (duration: 254.609879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:12.232157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.584969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:09:12.232203Z","caller":"traceutil/trace.go:171","msg":"trace[1507678036] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"137.235534ms","start":"2026-06-10T02:09:12.094929Z","end":"2026-06-10T02:09:12.232164Z","steps":["trace[1507678036] 'process raft request' (duration: 83.591565ms)","trace[1507678036] 'compare' (duration: 53.385133ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:12.232331Z","caller":"traceutil/trace.go:171","msg":"trace[1706474115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25812; }","duration":"149.011887ms","start":"2026-06-10T02:09:12.083284Z","end":"2026-06-10T02:09:12.232296Z","steps":["trace[1706474115] 'range keys from in-memory index tree' (duration: 148.474857ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:12.408536Z","caller":"traceutil/trace.go:171","msg":"trace[1442298072] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"142.077926ms","start":"2026-06-10T02:09:12.2664Z","end":"2026-06-10T02:09:12.408478Z","steps":["trace[1442298072] 'process raft request' (duration: 141.720048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:12.408725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.187807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-10T02:09:12.408796Z","caller":"traceutil/trace.go:171","msg":"trace[346007706] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25814; }","duration":"114.333139ms","start":"2026-06-10T02:09:12.294448Z","end":"2026-06-10T02:09:12.408781Z","steps":["trace[346007706] 'agreement among raft nodes before linearized reading' (duration: 114.090185ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:12.408906Z","caller":"traceutil/trace.go:171","msg":"trace[594327836] linearizableReadLoop","detail":"{readStateIndex:26660; appliedIndex:26659; }","duration":"113.83996ms","start":"2026-06-10T02:09:12.29452Z","end":"2026-06-10T02:09:12.40836Z","steps":["trace[594327836] 'read index received' (duration: 113.543783ms)","trace[594327836] 'applied index is now lower than readState.Index' (duration: 295.187µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:19.296279Z","caller":"traceutil/trace.go:171","msg":"trace[424522059] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"196.668881ms","start":"2026-06-10T02:09:19.099558Z","end":"2026-06-10T02:09:19.296227Z","steps":["trace[424522059] 'process raft request' (duration: 196.498368ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:19.375292Z","caller":"traceutil/trace.go:171","msg":"trace[2002017977] linearizableReadLoop","detail":"{readStateIndex:26722; appliedIndex:26718; }","duration":"112.467683ms","start":"2026-06-10T02:09:19.262803Z","end":"2026-06-10T02:09:19.375271Z","steps":["trace[2002017977] 'read index received' (duration: 33.259271ms)","trace[2002017977] 'applied index is now lower than readState.Index' (duration: 79.207642ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:19.37558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.756789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-06-10T02:09:19.375569Z","caller":"traceutil/trace.go:171","msg":"trace[66333398] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"275.434345ms","start":"2026-06-10T02:09:19.100099Z","end":"2026-06-10T02:09:19.375533Z","steps":["trace[66333398] 'process raft request' (duration: 275.110938ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:19.375619Z","caller":"traceutil/trace.go:171","msg":"trace[315259347] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25875; }","duration":"112.858611ms","start":"2026-06-10T02:09:19.262748Z","end":"2026-06-10T02:09:19.375607Z","steps":["trace[315259347] 'agreement among raft nodes before linearized reading' (duration: 112.601615ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:19.375628Z","caller":"traceutil/trace.go:171","msg":"trace[2061642132] transaction","detail":"{read_only:false; response_revision:25873; number_of_response:1; }","duration":"275.561707ms","start":"2026-06-10T02:09:19.100015Z","end":"2026-06-10T02:09:19.375577Z","steps":["trace[2061642132] 'process raft request' (duration: 275.078967ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:19.375741Z","caller":"traceutil/trace.go:171","msg":"trace[2085495536] transaction","detail":"{read_only:false; response_revision:25875; number_of_response:1; }","duration":"275.537717ms","start":"2026-06-10T02:09:19.100153Z","end":"2026-06-10T02:09:19.37569Z","steps":["trace[2085495536] 'process raft request' (duration: 275.086617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:19.784379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.424845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T02:09:19.784679Z","caller":"traceutil/trace.go:171","msg":"trace[786385559] linearizableReadLoop","detail":"{readStateIndex:26724; appliedIndex:26722; }","duration":"348.570932ms","start":"2026-06-10T02:09:19.436075Z","end":"2026-06-10T02:09:19.784646Z","steps":["trace[786385559] 'read index received' (duration: 141.763559ms)","trace[786385559] 'applied index is now lower than readState.Index' (duration: 206.806173ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:19.784676Z","caller":"traceutil/trace.go:171","msg":"trace[768117073] transaction","detail":"{read_only:false; response_revision:25876; number_of_response:1; }","duration":"385.635246ms","start":"2026-06-10T02:09:19.398955Z","end":"2026-06-10T02:09:19.78459Z","steps":["trace[768117073] 'process raft request' (duration: 178.813063ms)","trace[768117073] 'compare' (duration: 205.042148ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:19.784876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.799036ms","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-10T02:09:19.78492Z","caller":"traceutil/trace.go:171","msg":"trace[2137509438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25878; }","duration":"348.897247ms","start":"2026-06-10T02:09:19.436011Z","end":"2026-06-10T02:09:19.784909Z","steps":["trace[2137509438] 'agreement among raft nodes before linearized reading' (duration: 348.722754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:19.784959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:09:19.435997Z","time spent":"348.952469ms","remote":"127.0.0.1:41344","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-10T02:09:19.785075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:09:19.398917Z","time spent":"385.89652ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:09:19.78534Z","caller":"traceutil/trace.go:171","msg":"trace[51792999] transaction","detail":"{read_only:false; response_revision:25878; number_of_response:1; }","duration":"319.961058ms","start":"2026-06-10T02:09:19.465363Z","end":"2026-06-10T02:09:19.785324Z","steps":["trace[51792999] 'process raft request' (duration: 319.212594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:19.785426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:09:19.465328Z","time spent":"320.05174ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:09:19.785569Z","caller":"traceutil/trace.go:171","msg":"trace[434978969] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"349.717414ms","start":"2026-06-10T02:09:19.435488Z","end":"2026-06-10T02:09:19.785206Z","steps":["trace[434978969] 'process raft request' (duration: 348.995061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:19.785644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:09:19.435475Z","time spent":"350.130961ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:09:19.941812Z","caller":"traceutil/trace.go:171","msg":"trace[890299266] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"351.857834ms","start":"2026-06-10T02:09:19.589926Z","end":"2026-06-10T02:09:19.941784Z","steps":["trace[890299266] 'process raft request' (duration: 330.019229ms)","trace[890299266] 'compare' (duration: 21.620631ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:19.941983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:09:19.589892Z","time spent":"352.005536ms","remote":"127.0.0.1:41344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T02:09:19.954937Z","caller":"traceutil/trace.go:171","msg":"trace[1343960815] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"160.095056ms","start":"2026-06-10T02:09:19.794806Z","end":"2026-06-10T02:09:19.954901Z","steps":["trace[1343960815] 'process raft request' (duration: 159.823921ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:20.921906Z","caller":"traceutil/trace.go:171","msg":"trace[788706138] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"102.705518ms","start":"2026-06-10T02:09:20.819172Z","end":"2026-06-10T02:09:20.921878Z","steps":["trace[788706138] 'process raft request' (duration: 101.909582ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:21.049805Z","caller":"traceutil/trace.go:171","msg":"trace[1807258704] linearizableReadLoop","detail":"{readStateIndex:26733; appliedIndex:26731; }","duration":"223.874556ms","start":"2026-06-10T02:09:20.825883Z","end":"2026-06-10T02:09:21.049758Z","steps":["trace[1807258704] 'read index received' (duration: 95.228086ms)","trace[1807258704] 'applied index is now lower than readState.Index' (duration: 128.64402ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:21.050019Z","caller":"traceutil/trace.go:171","msg":"trace[686377508] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"226.347764ms","start":"2026-06-10T02:09:20.823653Z","end":"2026-06-10T02:09:21.050001Z","steps":["trace[686377508] 'process raft request' (duration: 167.17041ms)","trace[686377508] 'compare' (duration: 58.5126ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:21.050223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.288505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T02:09:21.050246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.357491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-06-10T02:09:21.050348Z","caller":"traceutil/trace.go:171","msg":"trace[1432253739] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25885; }","duration":"170.518775ms","start":"2026-06-10T02:09:20.879805Z","end":"2026-06-10T02:09:21.050324Z","steps":["trace[1432253739] 'agreement among raft nodes before linearized reading' (duration: 170.24602ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:21.05035Z","caller":"traceutil/trace.go:171","msg":"trace[1550718286] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25885; }","duration":"224.493308ms","start":"2026-06-10T02:09:20.825827Z","end":"2026-06-10T02:09:21.05032Z","steps":["trace[1550718286] 'agreement among raft nodes before linearized reading' (duration: 224.243124ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:37.287976Z","caller":"traceutil/trace.go:171","msg":"trace[196962442] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"157.888875ms","start":"2026-06-10T02:09:37.130054Z","end":"2026-06-10T02:09:37.287943Z","steps":["trace[196962442] 'process raft request' (duration: 157.64388ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:37.607108Z","caller":"traceutil/trace.go:171","msg":"trace[162635213] linearizableReadLoop","detail":"{readStateIndex:26866; appliedIndex:26865; }","duration":"128.552189ms","start":"2026-06-10T02:09:37.47853Z","end":"2026-06-10T02:09:37.607082Z","steps":["trace[162635213] 'read index received' (duration: 57.45617ms)","trace[162635213] 'applied index is now lower than readState.Index' (duration: 71.094999ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:37.607424Z","caller":"traceutil/trace.go:171","msg":"trace[786018606] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"135.945179ms","start":"2026-06-10T02:09:37.471461Z","end":"2026-06-10T02:09:37.607407Z","steps":["trace[786018606] 'process raft request' (duration: 64.655357ms)","trace[786018606] 'compare' (duration: 70.834823ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:37.60766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.817568ms","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-10T02:09:37.60771Z","caller":"traceutil/trace.go:171","msg":"trace[326060635] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26014; }","duration":"123.91461ms","start":"2026-06-10T02:09:37.48378Z","end":"2026-06-10T02:09:37.607695Z","steps":["trace[326060635] 'agreement among raft nodes before linearized reading' (duration: 123.813498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:37.607679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.251651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-10T02:09:37.607691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.141599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-10T02:09:37.607743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.304902ms","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-10T02:09:37.607789Z","caller":"traceutil/trace.go:171","msg":"trace[1776932681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26014; }","duration":"139.403673ms","start":"2026-06-10T02:09:37.468361Z","end":"2026-06-10T02:09:37.607765Z","steps":["trace[1776932681] 'agreement among raft nodes before linearized reading' (duration: 139.175289ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:37.607803Z","caller":"traceutil/trace.go:171","msg":"trace[1792178918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26014; }","duration":"138.338773ms","start":"2026-06-10T02:09:37.469438Z","end":"2026-06-10T02:09:37.607777Z","steps":["trace[1792178918] 'agreement among raft nodes before linearized reading' (duration: 138.112479ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:37.60785Z","caller":"traceutil/trace.go:171","msg":"trace[1756420845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26014; }","duration":"138.480075ms","start":"2026-06-10T02:09:37.469346Z","end":"2026-06-10T02:09:37.607826Z","steps":["trace[1756420845] 'agreement among raft nodes before linearized reading' (duration: 138.239681ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:37.770394Z","caller":"traceutil/trace.go:171","msg":"trace[1796677132] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"157.545718ms","start":"2026-06-10T02:09:37.612808Z","end":"2026-06-10T02:09:37.770354Z","steps":["trace[1796677132] 'process raft request' (duration: 144.067922ms)","trace[1796677132] 'compare' (duration: 12.972346ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:37.770551Z","caller":"traceutil/trace.go:171","msg":"trace[502561536] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"157.64323ms","start":"2026-06-10T02:09:37.612872Z","end":"2026-06-10T02:09:37.770515Z","steps":["trace[502561536] 'process raft request' (duration: 157.316133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:37.77079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.252734ms","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-10T02:09:37.770329Z","caller":"traceutil/trace.go:171","msg":"trace[1289294494] linearizableReadLoop","detail":"{readStateIndex:26869; appliedIndex:26867; }","duration":"155.815395ms","start":"2026-06-10T02:09:37.614491Z","end":"2026-06-10T02:09:37.770306Z","steps":["trace[1289294494] 'read index received' (duration: 59.715883ms)","trace[1289294494] 'applied index is now lower than readState.Index' (duration: 96.098132ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:37.770917Z","caller":"traceutil/trace.go:171","msg":"trace[561786579] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26017; }","duration":"156.445698ms","start":"2026-06-10T02:09:37.614439Z","end":"2026-06-10T02:09:37.770885Z","steps":["trace[561786579] 'agreement among raft nodes before linearized reading' (duration: 156.196763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:37.771291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.517079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:09:37.771341Z","caller":"traceutil/trace.go:171","msg":"trace[345664271] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26017; }","duration":"117.58727ms","start":"2026-06-10T02:09:37.653739Z","end":"2026-06-10T02:09:37.771327Z","steps":["trace[345664271] 'agreement among raft nodes before linearized reading' (duration: 117.491738ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:38.003807Z","caller":"traceutil/trace.go:171","msg":"trace[1923847638] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"145.944188ms","start":"2026-06-10T02:09:37.857824Z","end":"2026-06-10T02:09:38.003768Z","steps":["trace[1923847638] 'process raft request' (duration: 145.665293ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:38.003869Z","caller":"traceutil/trace.go:171","msg":"trace[3523512] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"145.784855ms","start":"2026-06-10T02:09:37.858061Z","end":"2026-06-10T02:09:38.003846Z","steps":["trace[3523512] 'process raft request' (duration: 145.527231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:38.154951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.630741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-10T02:09:38.155399Z","caller":"traceutil/trace.go:171","msg":"trace[551490822] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"148.1919ms","start":"2026-06-10T02:09:38.007174Z","end":"2026-06-10T02:09:38.155366Z","steps":["trace[551490822] 'process raft request' (duration: 40.030219ms)","trace[551490822] 'compare' (duration: 107.481898ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:38.155649Z","caller":"traceutil/trace.go:171","msg":"trace[1282434814] linearizableReadLoop","detail":"{readStateIndex:26874; appliedIndex:26872; }","duration":"115.603523ms","start":"2026-06-10T02:09:38.040026Z","end":"2026-06-10T02:09:38.15563Z","steps":["trace[1282434814] 'read index received' (duration: 7.190506ms)","trace[1282434814] 'applied index is now lower than readState.Index' (duration: 108.411387ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:38.155764Z","caller":"traceutil/trace.go:171","msg":"trace[263103475] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"119.408965ms","start":"2026-06-10T02:09:38.036347Z","end":"2026-06-10T02:09:38.155756Z","steps":["trace[263103475] 'process raft request' (duration: 119.181831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:38.155936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.911629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-10T02:09:38.156508Z","caller":"traceutil/trace.go:171","msg":"trace[1150525271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26022; }","duration":"116.00357ms","start":"2026-06-10T02:09:38.039963Z","end":"2026-06-10T02:09:38.155967Z","steps":["trace[1150525271] 'agreement among raft nodes before linearized reading' (duration: 115.823827ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:38.387462Z","caller":"traceutil/trace.go:171","msg":"trace[1140652994] transaction","detail":"{read_only:false; response_revision:26023; number_of_response:1; }","duration":"227.357172ms","start":"2026-06-10T02:09:38.160087Z","end":"2026-06-10T02:09:38.387444Z","steps":["trace[1140652994] 'process raft request' (duration: 207.341863ms)","trace[1140652994] 'compare' (duration: 19.701143ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:39.406678Z","caller":"traceutil/trace.go:171","msg":"trace[1204129064] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"113.391201ms","start":"2026-06-10T02:09:39.293222Z","end":"2026-06-10T02:09:39.406613Z","steps":["trace[1204129064] 'process raft request' (duration: 113.173937ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:39.936619Z","caller":"traceutil/trace.go:171","msg":"trace[115369742] linearizableReadLoop","detail":"{readStateIndex:26884; appliedIndex:26882; }","duration":"140.131837ms","start":"2026-06-10T02:09:39.796461Z","end":"2026-06-10T02:09:39.936593Z","steps":["trace[115369742] 'read index received' (duration: 66.297117ms)","trace[115369742] 'applied index is now lower than readState.Index' (duration: 73.83331ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:39.936605Z","caller":"traceutil/trace.go:171","msg":"trace[977880925] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"141.29251ms","start":"2026-06-10T02:09:39.795262Z","end":"2026-06-10T02:09:39.936555Z","steps":["trace[977880925] 'process raft request' (duration: 67.48973ms)","trace[977880925] 'compare' (duration: 73.364561ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:39.936715Z","caller":"traceutil/trace.go:171","msg":"trace[479732955] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"140.732689ms","start":"2026-06-10T02:09:39.795969Z","end":"2026-06-10T02:09:39.936702Z","steps":["trace[479732955] 'process raft request' (duration: 140.522706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:39.93682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.338302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:09:39.936877Z","caller":"traceutil/trace.go:171","msg":"trace[1795171085] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:26032; }","duration":"140.434653ms","start":"2026-06-10T02:09:39.796427Z","end":"2026-06-10T02:09:39.936861Z","steps":["trace[1795171085] 'agreement among raft nodes before linearized reading' (duration: 140.323351ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:40.155624Z","caller":"traceutil/trace.go:171","msg":"trace[314087820] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"139.988406ms","start":"2026-06-10T02:09:40.015606Z","end":"2026-06-10T02:09:40.155594Z","steps":["trace[314087820] 'process raft request' (duration: 139.596329ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:40.155651Z","caller":"traceutil/trace.go:171","msg":"trace[1116401176] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"146.389296ms","start":"2026-06-10T02:09:40.009218Z","end":"2026-06-10T02:09:40.155608Z","steps":["trace[1116401176] 'process raft request' (duration: 74.638656ms)","trace[1116401176] 'compare' (duration: 70.972526ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:40.347108Z","caller":"traceutil/trace.go:171","msg":"trace[395580746] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"183.47765ms","start":"2026-06-10T02:09:40.163602Z","end":"2026-06-10T02:09:40.347079Z","steps":["trace[395580746] 'process raft request' (duration: 134.393169ms)","trace[395580746] 'compare' (duration: 48.954059ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:41.92289Z","caller":"traceutil/trace.go:171","msg":"trace[264556990] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"123.435441ms","start":"2026-06-10T02:09:41.799414Z","end":"2026-06-10T02:09:41.92285Z","steps":["trace[264556990] 'process raft request' (duration: 123.211487ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.192232Z","caller":"traceutil/trace.go:171","msg":"trace[491654494] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"246.397644ms","start":"2026-06-10T02:09:41.945808Z","end":"2026-06-10T02:09:42.192206Z","steps":["trace[491654494] 'process raft request' (duration: 246.20001ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.192467Z","caller":"traceutil/trace.go:171","msg":"trace[955428076] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"170.637267ms","start":"2026-06-10T02:09:42.021808Z","end":"2026-06-10T02:09:42.192445Z","steps":["trace[955428076] 'process raft request' (duration: 170.342982ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.192445Z","caller":"traceutil/trace.go:171","msg":"trace[1746462160] linearizableReadLoop","detail":"{readStateIndex:26901; appliedIndex:26899; }","duration":"112.192158ms","start":"2026-06-10T02:09:42.080201Z","end":"2026-06-10T02:09:42.192393Z","steps":["trace[1746462160] 'read index received' (duration: 111.805071ms)","trace[1746462160] 'applied index is now lower than readState.Index' (duration: 385.237µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:42.19257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.359371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:09:42.192634Z","caller":"traceutil/trace.go:171","msg":"trace[556038622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26048; }","duration":"112.482483ms","start":"2026-06-10T02:09:42.08014Z","end":"2026-06-10T02:09:42.192622Z","steps":["trace[556038622] 'agreement among raft nodes before linearized reading' (duration: 112.363141ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.301089Z","caller":"traceutil/trace.go:171","msg":"trace[510657076] linearizableReadLoop","detail":"{readStateIndex:26903; appliedIndex:26901; }","duration":"107.167892ms","start":"2026-06-10T02:09:42.193895Z","end":"2026-06-10T02:09:42.301063Z","steps":["trace[510657076] 'read index received' (duration: 104.869749ms)","trace[510657076] 'applied index is now lower than readState.Index' (duration: 2.297033ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:42.301159Z","caller":"traceutil/trace.go:171","msg":"trace[1393235893] transaction","detail":"{read_only:false; response_revision:26049; number_of_response:1; }","duration":"139.843502ms","start":"2026-06-10T02:09:42.161274Z","end":"2026-06-10T02:09:42.301117Z","steps":["trace[1393235893] 'process raft request' (duration: 137.560629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:42.301258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.333566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:09:42.301231Z","caller":"traceutil/trace.go:171","msg":"trace[1579090850] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"138.664571ms","start":"2026-06-10T02:09:42.162517Z","end":"2026-06-10T02:09:42.301182Z","steps":["trace[1579090850] 'process raft request' (duration: 138.486197ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.301293Z","caller":"traceutil/trace.go:171","msg":"trace[999466397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26050; }","duration":"107.439258ms","start":"2026-06-10T02:09:42.193841Z","end":"2026-06-10T02:09:42.30128Z","steps":["trace[999466397] 'agreement among raft nodes before linearized reading' (duration: 107.328835ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.473648Z","caller":"traceutil/trace.go:171","msg":"trace[833596933] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"121.115258ms","start":"2026-06-10T02:09:42.352487Z","end":"2026-06-10T02:09:42.473603Z","steps":["trace[833596933] 'process raft request' (duration: 94.445452ms)","trace[833596933] 'compare' (duration: 26.468363ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:42.658865Z","caller":"traceutil/trace.go:171","msg":"trace[1326809013] linearizableReadLoop","detail":"{readStateIndex:26906; appliedIndex:26905; }","duration":"185.270044ms","start":"2026-06-10T02:09:42.473568Z","end":"2026-06-10T02:09:42.658838Z","steps":["trace[1326809013] 'read index received' (duration: 109.969365ms)","trace[1326809013] 'applied index is now lower than readState.Index' (duration: 75.299219ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:42.65909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.862823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-10T02:09:42.659183Z","caller":"traceutil/trace.go:171","msg":"trace[1588019477] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26053; }","duration":"228.029006ms","start":"2026-06-10T02:09:42.431132Z","end":"2026-06-10T02:09:42.659161Z","steps":["trace[1588019477] 'agreement among raft nodes before linearized reading' (duration: 227.760461ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.658961Z","caller":"traceutil/trace.go:171","msg":"trace[263806010] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"259.284508ms","start":"2026-06-10T02:09:42.399609Z","end":"2026-06-10T02:09:42.658893Z","steps":["trace[263806010] 'process raft request' (duration: 183.946128ms)","trace[263806010] 'compare' (duration: 75.135666ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:42.659324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.351172ms","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-10T02:09:42.659469Z","caller":"traceutil/trace.go:171","msg":"trace[1341336523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26053; }","duration":"256.583977ms","start":"2026-06-10T02:09:42.40287Z","end":"2026-06-10T02:09:42.659454Z","steps":["trace[1341336523] 'agreement among raft nodes before linearized reading' (duration: 256.249831ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.764194Z","caller":"traceutil/trace.go:171","msg":"trace[381990765] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"100.072068ms","start":"2026-06-10T02:09:42.664083Z","end":"2026-06-10T02:09:42.764155Z","steps":["trace[381990765] 'process raft request' (duration: 99.878484ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:42.765197Z","caller":"traceutil/trace.go:171","msg":"trace[942304223] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"100.546977ms","start":"2026-06-10T02:09:42.664613Z","end":"2026-06-10T02:09:42.76516Z","steps":["trace[942304223] 'process raft request' (duration: 100.211561ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:48.267279Z","caller":"traceutil/trace.go:171","msg":"trace[347708139] linearizableReadLoop","detail":"{readStateIndex:26948; appliedIndex:26948; }","duration":"160.541845ms","start":"2026-06-10T02:09:48.106671Z","end":"2026-06-10T02:09:48.267213Z","steps":["trace[347708139] 'read index received' (duration: 160.527885ms)","trace[347708139] 'applied index is now lower than readState.Index' (duration: 11.65µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:48.267Z","caller":"traceutil/trace.go:171","msg":"trace[630769227] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"169.868642ms","start":"2026-06-10T02:09:48.097101Z","end":"2026-06-10T02:09:48.26697Z","steps":["trace[630769227] 'process raft request' (duration: 169.688408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:48.267512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.830671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-06-10T02:09:48.26766Z","caller":"traceutil/trace.go:171","msg":"trace[2078417769] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26094; }","duration":"161.011524ms","start":"2026-06-10T02:09:48.106624Z","end":"2026-06-10T02:09:48.267636Z","steps":["trace[2078417769] 'agreement among raft nodes before linearized reading' (duration: 160.670937ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:53.586003Z","caller":"traceutil/trace.go:171","msg":"trace[1623229052] linearizableReadLoop","detail":"{readStateIndex:26998; appliedIndex:26997; }","duration":"124.98448ms","start":"2026-06-10T02:09:53.460993Z","end":"2026-06-10T02:09:53.585978Z","steps":["trace[1623229052] 'read index received' (duration: 124.799027ms)","trace[1623229052] 'applied index is now lower than readState.Index' (duration: 184.353µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:09:53.586071Z","caller":"traceutil/trace.go:171","msg":"trace[1728926500] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"127.341135ms","start":"2026-06-10T02:09:53.458687Z","end":"2026-06-10T02:09:53.586028Z","steps":["trace[1728926500] 'process raft request' (duration: 127.103041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:53.586315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.704921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128369"} {"level":"info","ts":"2026-06-10T02:09:53.586366Z","caller":"traceutil/trace.go:171","msg":"trace[739772124] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:26143; }","duration":"119.816233ms","start":"2026-06-10T02:09:53.466537Z","end":"2026-06-10T02:09:53.586353Z","steps":["trace[739772124] 'agreement among raft nodes before linearized reading' (duration: 119.610399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:53.586943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.918658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:92 size:120321"} {"level":"info","ts":"2026-06-10T02:09:53.587052Z","caller":"traceutil/trace.go:171","msg":"trace[172806109] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:92; response_revision:26143; }","duration":"126.098622ms","start":"2026-06-10T02:09:53.460935Z","end":"2026-06-10T02:09:53.587033Z","steps":["trace[172806109] 'agreement among raft nodes before linearized reading' (duration: 125.190124ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:53.880587Z","caller":"traceutil/trace.go:171","msg":"trace[1701010716] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"136.831555ms","start":"2026-06-10T02:09:53.743721Z","end":"2026-06-10T02:09:53.880552Z","steps":["trace[1701010716] 'process raft request' (duration: 136.620162ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:09:54.161224Z","caller":"traceutil/trace.go:171","msg":"trace[453650315] linearizableReadLoop","detail":"{readStateIndex:27000; appliedIndex:26998; }","duration":"367.242146ms","start":"2026-06-10T02:09:53.793948Z","end":"2026-06-10T02:09:54.16119Z","steps":["trace[453650315] 'read index received' (duration: 86.41155ms)","trace[453650315] 'applied index is now lower than readState.Index' (duration: 280.829256ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:54.161703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.726125ms","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-10T02:09:54.161837Z","caller":"traceutil/trace.go:171","msg":"trace[432867738] 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:26145; }","duration":"367.948899ms","start":"2026-06-10T02:09:53.793857Z","end":"2026-06-10T02:09:54.161806Z","steps":["trace[432867738] 'agreement among raft nodes before linearized reading' (duration: 367.615582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:09:54.162097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:09:53.79383Z","time spent":"368.245725ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"info","ts":"2026-06-10T02:09:54.162266Z","caller":"traceutil/trace.go:171","msg":"trace[1159522853] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"416.518701ms","start":"2026-06-10T02:09:53.745323Z","end":"2026-06-10T02:09:54.161842Z","steps":["trace[1159522853] 'process raft request' (duration: 405.044892ms)","trace[1159522853] 'compare' (duration: 10.177884ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T02:09:54.162635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T02:09:53.745286Z","time spent":"417.236334ms","remote":"127.0.0.1:41344","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-10T02:09:54.162824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.77051ms","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-10T02:09:54.162909Z","caller":"traceutil/trace.go:171","msg":"trace[861862567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26145; }","duration":"178.927313ms","start":"2026-06-10T02:09:53.98396Z","end":"2026-06-10T02:09:54.162888Z","steps":["trace[861862567] 'agreement among raft nodes before linearized reading' (duration: 177.70236ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:10:03.750582Z","caller":"traceutil/trace.go:171","msg":"trace[2005488247] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"125.904708ms","start":"2026-06-10T02:10:03.624632Z","end":"2026-06-10T02:10:03.750537Z","steps":["trace[2005488247] 'process raft request' (duration: 125.703814ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:10:04.061679Z","caller":"traceutil/trace.go:171","msg":"trace[730002543] linearizableReadLoop","detail":"{readStateIndex:27090; appliedIndex:27089; }","duration":"130.090278ms","start":"2026-06-10T02:10:03.931562Z","end":"2026-06-10T02:10:04.061652Z","steps":["trace[730002543] 'read index received' (duration: 129.891944ms)","trace[730002543] 'applied index is now lower than readState.Index' (duration: 197.063µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:10:04.061742Z","caller":"traceutil/trace.go:171","msg":"trace[1447832572] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"168.033597ms","start":"2026-06-10T02:10:03.893669Z","end":"2026-06-10T02:10:04.061703Z","steps":["trace[1447832572] 'process raft request' (duration: 167.818324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T02:10:04.061822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.25286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T02:10:04.061867Z","caller":"traceutil/trace.go:171","msg":"trace[872123565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26233; }","duration":"130.312261ms","start":"2026-06-10T02:10:03.931543Z","end":"2026-06-10T02:10:04.061855Z","steps":["trace[872123565] 'agreement among raft nodes before linearized reading' (duration: 130.2196ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:10:04.444897Z","caller":"traceutil/trace.go:171","msg":"trace[1742385857] transaction","detail":"{read_only:false; response_revision:26235; number_of_response:1; }","duration":"184.52015ms","start":"2026-06-10T02:10:04.260342Z","end":"2026-06-10T02:10:04.444862Z","steps":["trace[1742385857] 'process raft request' (duration: 109.712751ms)","trace[1742385857] 'compare' (duration: 74.574085ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T02:10:04.445257Z","caller":"traceutil/trace.go:171","msg":"trace[644251165] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"183.49937ms","start":"2026-06-10T02:10:04.261724Z","end":"2026-06-10T02:10:04.445223Z","steps":["trace[644251165] 'process raft request' (duration: 183.085553ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:10:04.445298Z","caller":"traceutil/trace.go:171","msg":"trace[1137372080] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"105.256796ms","start":"2026-06-10T02:10:04.340006Z","end":"2026-06-10T02:10:04.445263Z","steps":["trace[1137372080] 'process raft request' (duration: 105.082863ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:10:04.491351Z","caller":"traceutil/trace.go:171","msg":"trace[1513916477] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"106.085573ms","start":"2026-06-10T02:10:04.38524Z","end":"2026-06-10T02:10:04.491326Z","steps":["trace[1513916477] 'process raft request' (duration: 106.037472ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:10:04.491508Z","caller":"traceutil/trace.go:171","msg":"trace[1166740245] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"111.057666ms","start":"2026-06-10T02:10:04.380404Z","end":"2026-06-10T02:10:04.491462Z","steps":["trace[1166740245] 'process raft request' (duration: 110.640499ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:10:04.49154Z","caller":"traceutil/trace.go:171","msg":"trace[410885252] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"109.743031ms","start":"2026-06-10T02:10:04.381765Z","end":"2026-06-10T02:10:04.491508Z","steps":["trace[410885252] 'process raft request' (duration: 109.457056ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T02:10:30.287499Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b0d.wal"} {"level":"info","ts":"2026-06-10T02:13:52.065543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25586} {"level":"info","ts":"2026-06-10T02:13:52.227553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25586,"took":"155.493469ms","hash":2563401997,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":36413440,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-10T02:13:52.22763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2563401997,"revision":25586,"compact-revision":22165} {"level":"info","ts":"2026-06-10T02:15:00.5806Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T02:15:00.588573Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-06-10T02:15:00.588807Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-06-10T02:18:52.074416Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28536} {"level":"info","ts":"2026-06-10T02:18:52.225846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28536,"took":"134.887336ms","hash":3536472820,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":31735808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T02:18:52.225908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3536472820,"revision":28536,"compact-revision":25586} {"level":"info","ts":"2026-06-10T02:23:52.08485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30793} {"level":"info","ts":"2026-06-10T02:23:52.2249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30793,"took":"135.263784ms","hash":3981073347,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":30261248,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-10T02:23:52.22501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3981073347,"revision":30793,"compact-revision":28536} {"level":"info","ts":"2026-06-10T02:28:52.093348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33050} {"level":"info","ts":"2026-06-10T02:28:52.259254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33050,"took":"160.776914ms","hash":2517333857,"current-db-size-bytes":58933248,"current-db-size":"59 MB","current-db-size-in-use-bytes":30556160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-10T02:28:52.259343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2517333857,"revision":33050,"compact-revision":30793} {"level":"info","ts":"2026-06-10T02:33:52.107148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35307} {"level":"info","ts":"2026-06-10T02:33:52.250773Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35307,"took":"138.443872ms","hash":2969378112,"current-db-size-bytes":58933248,"current-db-size":"59 MB","current-db-size-in-use-bytes":32104448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T02:33:52.250867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2969378112,"revision":35307,"compact-revision":33050} {"level":"info","ts":"2026-06-10T02:36:26.820957Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T02:36:26.826652Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-06-10T02:36:26.826899Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-06-10T02:38:52.114188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37580} {"level":"info","ts":"2026-06-10T02:38:52.237483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37580,"took":"117.207889ms","hash":3951234537,"current-db-size-bytes":60620800,"current-db-size":"61 MB","current-db-size-in-use-bytes":32268288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T02:38:52.237555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3951234537,"revision":37580,"compact-revision":35307}