{"level":"warn","ts":"2026-06-26T03:37:18.305168Z","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-26T03:37:18.30526Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-26T03:37:18.305323Z","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-26T03:37:18.305334Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-06-26T03:37:18.305355Z","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-26T03:37:18.305996Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-06-26T03:37:18.306071Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-26T03:37:18.309942Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.616289ms"} {"level":"info","ts":"2026-06-26T03:37:18.314994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-06-26T03:37:18.315069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-26T03:37:18.315087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-06-26T03:37:18.315101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-26T03:37:18.315114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-06-26T03:37:18.315152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-06-26T03:37:18.321076Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-26T03:37:18.322873Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-26T03:37:18.32385Z","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-26T03:37:18.326004Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-26T03:37:18.32612Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-26T03:37:18.326243Z","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-26T03:37:18.326349Z","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-26T03:37:18.326378Z","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-26T03:37:18.330165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-06-26T03:37:18.330336Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-06-26T03:37:18.33072Z","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-26T03:37:18.330898Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-26T03:37:18.330925Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-26T03:37:18.330986Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-06-26T03:37:18.331003Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-06-26T03:37:18.716329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-26T03:37:18.7164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-26T03:37:18.716424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-06-26T03:37:18.716444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-06-26T03:37:18.716461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-06-26T03:37:18.716483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-06-26T03:37:18.716524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-06-26T03:37:18.717468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:37:18.718623Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T03:37:18.718668Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-06-26T03:37:18.718715Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T03:37:18.71916Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-26T03:37:18.719282Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-26T03:37:18.719398Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:37:18.71951Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:37:18.719544Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:37:18.7222Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-06-26T03:37:18.722368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-26T03:37:43.816744Z","caller":"traceutil/trace.go:171","msg":"trace[1795432987] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"106.293909ms","start":"2026-06-26T03:37:43.710424Z","end":"2026-06-26T03:37:43.816718Z","steps":["trace[1795432987] 'process raft request' (duration: 106.165326ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:43.942805Z","caller":"traceutil/trace.go:171","msg":"trace[1710682163] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"232.143955ms","start":"2026-06-26T03:37:43.710632Z","end":"2026-06-26T03:37:43.942776Z","steps":["trace[1710682163] 'process raft request' (duration: 155.798456ms)","trace[1710682163] 'compare' (duration: 76.145984ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:37:43.94297Z","caller":"traceutil/trace.go:171","msg":"trace[154028452] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"101.040331ms","start":"2026-06-26T03:37:43.841892Z","end":"2026-06-26T03:37:43.942932Z","steps":["trace[154028452] 'process raft request' (duration: 100.803485ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:54.426245Z","caller":"traceutil/trace.go:171","msg":"trace[1324228916] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"121.51105ms","start":"2026-06-26T03:37:54.30471Z","end":"2026-06-26T03:37:54.426221Z","steps":["trace[1324228916] 'process raft request' (duration: 121.329105ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:54.430179Z","caller":"traceutil/trace.go:171","msg":"trace[54123004] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"120.201307ms","start":"2026-06-26T03:37:54.309873Z","end":"2026-06-26T03:37:54.430075Z","steps":["trace[54123004] 'process raft request' (duration: 119.334127ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:54.430287Z","caller":"traceutil/trace.go:171","msg":"trace[1129250423] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"110.231106ms","start":"2026-06-26T03:37:54.320046Z","end":"2026-06-26T03:37:54.430277Z","steps":["trace[1129250423] 'process raft request' (duration: 109.512108ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:54.430368Z","caller":"traceutil/trace.go:171","msg":"trace[263156209] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"109.074277ms","start":"2026-06-26T03:37:54.321286Z","end":"2026-06-26T03:37:54.43036Z","steps":["trace[263156209] 'process raft request' (duration: 108.337539ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:54.43026Z","caller":"traceutil/trace.go:171","msg":"trace[300985649] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"110.529253ms","start":"2026-06-26T03:37:54.319699Z","end":"2026-06-26T03:37:54.430228Z","steps":["trace[300985649] 'process raft request' (duration: 109.754834ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:54.430528Z","caller":"traceutil/trace.go:171","msg":"trace[471308001] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"112.07923ms","start":"2026-06-26T03:37:54.318441Z","end":"2026-06-26T03:37:54.430521Z","steps":["trace[471308001] 'process raft request' (duration: 110.908071ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:54.430599Z","caller":"traceutil/trace.go:171","msg":"trace[155057623] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"120.545007ms","start":"2026-06-26T03:37:54.310047Z","end":"2026-06-26T03:37:54.430592Z","steps":["trace[155057623] 'process raft request' (duration: 119.234834ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:37:54.430659Z","caller":"traceutil/trace.go:171","msg":"trace[1720315365] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"112.211103ms","start":"2026-06-26T03:37:54.31844Z","end":"2026-06-26T03:37:54.430651Z","steps":["trace[1720315365] 'process raft request' (duration: 110.950132ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:38:00.429998Z","caller":"traceutil/trace.go:171","msg":"trace[1525283657] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"157.67642ms","start":"2026-06-26T03:38:00.272286Z","end":"2026-06-26T03:38:00.429962Z","steps":["trace[1525283657] 'process raft request' (duration: 157.488106ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:38:00.804076Z","caller":"traceutil/trace.go:171","msg":"trace[778779684] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"103.064311ms","start":"2026-06-26T03:38:00.700985Z","end":"2026-06-26T03:38:00.804049Z","steps":["trace[778779684] 'read index received' (duration: 102.855356ms)","trace[778779684] 'applied index is now lower than readState.Index' (duration: 208.155µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:38:00.804154Z","caller":"traceutil/trace.go:171","msg":"trace[386685542] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"253.001483ms","start":"2026-06-26T03:38:00.551117Z","end":"2026-06-26T03:38:00.804118Z","steps":["trace[386685542] 'process raft request' (duration: 252.776828ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:38:00.804372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.360588ms","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-26T03:38:00.804503Z","caller":"traceutil/trace.go:171","msg":"trace[561463417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"103.545492ms","start":"2026-06-26T03:38:00.700934Z","end":"2026-06-26T03:38:00.804479Z","steps":["trace[561463417] 'agreement among raft nodes before linearized reading' (duration: 103.234135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:38:01.309333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.950893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:38:01.309578Z","caller":"traceutil/trace.go:171","msg":"trace[980094105] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"500.757819ms","start":"2026-06-26T03:38:00.808778Z","end":"2026-06-26T03:38:01.309536Z","steps":["trace[980094105] 'process raft request' (duration: 15.021447ms)","trace[980094105] 'compare' (duration: 484.79588ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:38:01.309896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:38:00.80876Z","time spent":"500.922283ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:38:01.310294Z","caller":"traceutil/trace.go:171","msg":"trace[1381441412] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"360.80305ms","start":"2026-06-26T03:38:00.949474Z","end":"2026-06-26T03:38:01.310277Z","steps":["trace[1381441412] 'read index received' (duration: 101.304058ms)","trace[1381441412] 'applied index is now lower than readState.Index' (duration: 259.498112ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:38:01.310449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.965444ms","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-26T03:38:01.310492Z","caller":"traceutil/trace.go:171","msg":"trace[852983356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"361.048756ms","start":"2026-06-26T03:38:00.949433Z","end":"2026-06-26T03:38:01.310482Z","steps":["trace[852983356] 'agreement among raft nodes before linearized reading' (duration: 360.902312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:38:01.310535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:38:00.94941Z","time spent":"361.113227ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-26T03:38:01.31034Z","caller":"traceutil/trace.go:171","msg":"trace[644692375] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"467.675593ms","start":"2026-06-26T03:38:00.842645Z","end":"2026-06-26T03:38:01.310321Z","steps":["trace[644692375] 'process raft request' (duration: 466.988005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:38:01.310885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:38:00.842613Z","time spent":"468.081633ms","remote":"127.0.0.1:34948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:39:34.833224Z","caller":"traceutil/trace.go:171","msg":"trace[1608781341] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"233.582571ms","start":"2026-06-26T03:39:34.599614Z","end":"2026-06-26T03:39:34.833196Z","steps":["trace[1608781341] 'read index received' (duration: 175.147307ms)","trace[1608781341] 'applied index is now lower than readState.Index' (duration: 58.433564ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:39:34.833282Z","caller":"traceutil/trace.go:171","msg":"trace[236238617] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"245.243615ms","start":"2026-06-26T03:39:34.588001Z","end":"2026-06-26T03:39:34.833245Z","steps":["trace[236238617] 'process raft request' (duration: 186.738339ms)","trace[236238617] 'compare' (duration: 58.214889ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:39:34.833495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.870328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-26T03:39:34.833543Z","caller":"traceutil/trace.go:171","msg":"trace[785407835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"233.971261ms","start":"2026-06-26T03:39:34.599557Z","end":"2026-06-26T03:39:34.833529Z","steps":["trace[785407835] 'agreement among raft nodes before linearized reading' (duration: 233.750546ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:24.138411Z","caller":"traceutil/trace.go:171","msg":"trace[705528656] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"112.87013ms","start":"2026-06-26T03:40:24.025498Z","end":"2026-06-26T03:40:24.138368Z","steps":["trace[705528656] 'process raft request' (duration: 112.606803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:24.138617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.506227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-26T03:40:24.138694Z","caller":"traceutil/trace.go:171","msg":"trace[73305527] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2183; }","duration":"111.61987ms","start":"2026-06-26T03:40:24.027059Z","end":"2026-06-26T03:40:24.138679Z","steps":["trace[73305527] 'agreement among raft nodes before linearized reading' (duration: 111.384243ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:24.138395Z","caller":"traceutil/trace.go:171","msg":"trace[551448210] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"111.25087ms","start":"2026-06-26T03:40:24.027078Z","end":"2026-06-26T03:40:24.138329Z","steps":["trace[551448210] 'read index received' (duration: 111.125677ms)","trace[551448210] 'applied index is now lower than readState.Index' (duration: 123.993µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:40:24.282014Z","caller":"traceutil/trace.go:171","msg":"trace[1384525254] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"253.661641ms","start":"2026-06-26T03:40:24.028315Z","end":"2026-06-26T03:40:24.281977Z","steps":["trace[1384525254] 'process raft request' (duration: 194.946361ms)","trace[1384525254] 'compare' (duration: 58.472014ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:40:24.282545Z","caller":"traceutil/trace.go:171","msg":"trace[1127227155] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"138.201177ms","start":"2026-06-26T03:40:24.144308Z","end":"2026-06-26T03:40:24.282509Z","steps":["trace[1127227155] 'process raft request' (duration: 138.088864ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:24.282797Z","caller":"traceutil/trace.go:171","msg":"trace[42474067] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"133.842641ms","start":"2026-06-26T03:40:24.148935Z","end":"2026-06-26T03:40:24.282778Z","steps":["trace[42474067] 'process raft request' (duration: 133.537474ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:44.801942Z","caller":"traceutil/trace.go:171","msg":"trace[1038252720] linearizableReadLoop","detail":"{readStateIndex:2931; appliedIndex:2930; }","duration":"125.950724ms","start":"2026-06-26T03:41:44.675962Z","end":"2026-06-26T03:41:44.801912Z","steps":["trace[1038252720] 'read index received' (duration: 57.38023ms)","trace[1038252720] 'applied index is now lower than readState.Index' (duration: 68.568974ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:44.802384Z","caller":"traceutil/trace.go:171","msg":"trace[804627600] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"151.394805ms","start":"2026-06-26T03:41:44.650965Z","end":"2026-06-26T03:41:44.80236Z","steps":["trace[804627600] 'process raft request' (duration: 82.480963ms)","trace[804627600] 'compare' (duration: 68.01907ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:41:44.802628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.657261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-26T03:41:44.802728Z","caller":"traceutil/trace.go:171","msg":"trace[1241266858] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2820; }","duration":"126.790294ms","start":"2026-06-26T03:41:44.675899Z","end":"2026-06-26T03:41:44.80269Z","steps":["trace[1241266858] 'agreement among raft nodes before linearized reading' (duration: 126.149968ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:44:25.224171Z","caller":"traceutil/trace.go:171","msg":"trace[856428249] transaction","detail":"{read_only:false; response_revision:3863; number_of_response:1; }","duration":"114.885772ms","start":"2026-06-26T03:44:25.109259Z","end":"2026-06-26T03:44:25.224145Z","steps":["trace[856428249] 'process raft request' (duration: 114.738618ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:44:25.364951Z","caller":"traceutil/trace.go:171","msg":"trace[2137175250] transaction","detail":"{read_only:false; response_revision:3864; number_of_response:1; }","duration":"254.972704ms","start":"2026-06-26T03:44:25.10995Z","end":"2026-06-26T03:44:25.364923Z","steps":["trace[2137175250] 'process raft request' (duration: 253.027427ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:46:07.892932Z","caller":"traceutil/trace.go:171","msg":"trace[1695400784] linearizableReadLoop","detail":"{readStateIndex:5457; appliedIndex:5456; }","duration":"124.645723ms","start":"2026-06-26T03:46:07.768261Z","end":"2026-06-26T03:46:07.892907Z","steps":["trace[1695400784] 'read index received' (duration: 124.475547ms)","trace[1695400784] 'applied index is now lower than readState.Index' (duration: 169.076µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:46:07.893023Z","caller":"traceutil/trace.go:171","msg":"trace[63339951] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"136.424853ms","start":"2026-06-26T03:46:07.756584Z","end":"2026-06-26T03:46:07.893009Z","steps":["trace[63339951] 'process raft request' (duration: 136.170856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:46:07.893423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.150685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:9 size:368403"} {"level":"info","ts":"2026-06-26T03:46:07.893515Z","caller":"traceutil/trace.go:171","msg":"trace[1636117450] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:9; response_revision:5255; }","duration":"125.253748ms","start":"2026-06-26T03:46:07.768243Z","end":"2026-06-26T03:46:07.893496Z","steps":["trace[1636117450] 'agreement among raft nodes before linearized reading' (duration: 124.801717ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:46:08.015534Z","caller":"traceutil/trace.go:171","msg":"trace[396285636] linearizableReadLoop","detail":"{readStateIndex:5458; appliedIndex:5457; }","duration":"109.625052ms","start":"2026-06-26T03:46:07.90588Z","end":"2026-06-26T03:46:08.015505Z","steps":["trace[396285636] 'read index received' (duration: 82.024812ms)","trace[396285636] 'applied index is now lower than readState.Index' (duration: 27.59929ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:46:08.015652Z","caller":"traceutil/trace.go:171","msg":"trace[737731115] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"118.803199ms","start":"2026-06-26T03:46:07.896804Z","end":"2026-06-26T03:46:08.015607Z","steps":["trace[737731115] 'process raft request' (duration: 91.156277ms)","trace[737731115] 'compare' (duration: 27.422146ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:46:08.016047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.135814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:9 size:368403"} {"level":"info","ts":"2026-06-26T03:46:08.016115Z","caller":"traceutil/trace.go:171","msg":"trace[441532014] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:9; response_revision:5256; }","duration":"110.255009ms","start":"2026-06-26T03:46:07.905845Z","end":"2026-06-26T03:46:08.0161Z","steps":["trace[441532014] 'agreement among raft nodes before linearized reading' (duration: 109.844528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:46:08.496917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.778253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:46:08.497177Z","caller":"traceutil/trace.go:171","msg":"trace[1016079264] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"332.380283ms","start":"2026-06-26T03:46:08.164756Z","end":"2026-06-26T03:46:08.497136Z","steps":["trace[1016079264] 'process raft request' (duration: 186.277832ms)","trace[1016079264] 'compare' (duration: 145.602869ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:46:08.497334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:46:08.164732Z","time spent":"332.523146ms","remote":"127.0.0.1:35018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:46:08.497397Z","caller":"traceutil/trace.go:171","msg":"trace[628479405] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"332.596428ms","start":"2026-06-26T03:46:08.16478Z","end":"2026-06-26T03:46:08.497376Z","steps":["trace[628479405] 'process raft request' (duration: 332.24747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:46:08.497487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:46:08.164756Z","time spent":"332.681681ms","remote":"127.0.0.1:35116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:46:08.497508Z","caller":"traceutil/trace.go:171","msg":"trace[215008413] linearizableReadLoop","detail":"{readStateIndex:5463; appliedIndex:5459; }","duration":"305.84826ms","start":"2026-06-26T03:46:08.191633Z","end":"2026-06-26T03:46:08.497481Z","steps":["trace[215008413] 'read index received' (duration: 4.113292ms)","trace[215008413] 'applied index is now lower than readState.Index' (duration: 301.731958ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:46:08.49754Z","caller":"traceutil/trace.go:171","msg":"trace[1427274170] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"332.606309ms","start":"2026-06-26T03:46:08.164868Z","end":"2026-06-26T03:46:08.497474Z","steps":["trace[1427274170] 'process raft request' (duration: 332.396094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:46:08.497739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:46:08.164845Z","time spent":"332.790734ms","remote":"127.0.0.1:35018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:46:08.497786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.153257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-26T03:46:08.497846Z","caller":"traceutil/trace.go:171","msg":"trace[685737235] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5262; }","duration":"306.28552ms","start":"2026-06-26T03:46:08.191545Z","end":"2026-06-26T03:46:08.49783Z","steps":["trace[685737235] 'agreement among raft nodes before linearized reading' (duration: 306.062915ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:46:08.497815Z","caller":"traceutil/trace.go:171","msg":"trace[1153008090] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"287.382974ms","start":"2026-06-26T03:46:08.210085Z","end":"2026-06-26T03:46:08.497468Z","steps":["trace[1153008090] 'process raft request' (duration: 287.297041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:46:08.497889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:46:08.191518Z","time spent":"306.357231ms","remote":"127.0.0.1:35012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-06-26T03:46:08.504749Z","caller":"traceutil/trace.go:171","msg":"trace[1759216143] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"146.099311ms","start":"2026-06-26T03:46:08.358634Z","end":"2026-06-26T03:46:08.504733Z","steps":["trace[1759216143] 'process raft request' (duration: 145.795024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:46:08.504736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.984851ms","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-26T03:46:08.504808Z","caller":"traceutil/trace.go:171","msg":"trace[337899908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5263; }","duration":"295.102514ms","start":"2026-06-26T03:46:08.20969Z","end":"2026-06-26T03:46:08.504792Z","steps":["trace[337899908] 'agreement among raft nodes before linearized reading' (duration: 294.872569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:46:08.504838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.898445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-26T03:46:08.50548Z","caller":"traceutil/trace.go:171","msg":"trace[2086374281] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5263; }","duration":"294.069439ms","start":"2026-06-26T03:46:08.21087Z","end":"2026-06-26T03:46:08.504939Z","steps":["trace[2086374281] 'agreement among raft nodes before linearized reading' (duration: 293.70077ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:46:08.996714Z","caller":"traceutil/trace.go:171","msg":"trace[516828662] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"117.049496ms","start":"2026-06-26T03:46:08.879634Z","end":"2026-06-26T03:46:08.996683Z","steps":["trace[516828662] 'process raft request' (duration: 91.625918ms)","trace[516828662] 'compare' (duration: 25.119759ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:46:08.996958Z","caller":"traceutil/trace.go:171","msg":"trace[863653282] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"114.293418ms","start":"2026-06-26T03:46:08.882654Z","end":"2026-06-26T03:46:08.996948Z","steps":["trace[863653282] 'process raft request' (duration: 113.883057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:46:31.267781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.900886ms","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-26T03:46:31.267971Z","caller":"traceutil/trace.go:171","msg":"trace[261773276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5658; }","duration":"163.177823ms","start":"2026-06-26T03:46:31.104768Z","end":"2026-06-26T03:46:31.267946Z","steps":["trace[261773276] 'range keys from in-memory index tree' (duration: 162.634321ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:46:31.408798Z","caller":"traceutil/trace.go:171","msg":"trace[758067243] transaction","detail":"{read_only:false; response_revision:5659; number_of_response:1; }","duration":"136.128656ms","start":"2026-06-26T03:46:31.272651Z","end":"2026-06-26T03:46:31.408779Z","steps":["trace[758067243] 'process raft request' (duration: 135.971903ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:47:18.743536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3005} {"level":"info","ts":"2026-06-26T03:47:18.824615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3005,"took":"79.622784ms","hash":3706859300,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":31682560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-26T03:47:18.824762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3706859300,"revision":3005,"compact-revision":-1} {"level":"info","ts":"2026-06-26T03:52:18.752528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6074} {"level":"info","ts":"2026-06-26T03:52:18.849771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6074,"took":"95.020677ms","hash":2486819289,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":21774336,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-26T03:52:18.849839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2486819289,"revision":6074,"compact-revision":3005} {"level":"info","ts":"2026-06-26T03:52:46.735213Z","caller":"traceutil/trace.go:171","msg":"trace[148700323] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"125.730804ms","start":"2026-06-26T03:52:46.609447Z","end":"2026-06-26T03:52:46.735177Z","steps":["trace[148700323] 'process raft request' (duration: 88.406192ms)","trace[148700323] 'compare' (duration: 37.106386ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:52:46.984244Z","caller":"traceutil/trace.go:171","msg":"trace[927674849] linearizableReadLoop","detail":"{readStateIndex:8752; appliedIndex:8751; }","duration":"218.294486ms","start":"2026-06-26T03:52:46.765926Z","end":"2026-06-26T03:52:46.984221Z","steps":["trace[927674849] 'read index received' (duration: 218.103431ms)","trace[927674849] 'applied index is now lower than readState.Index' (duration: 189.795µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:52:46.984368Z","caller":"traceutil/trace.go:171","msg":"trace[1585362064] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"221.208667ms","start":"2026-06-26T03:52:46.763146Z","end":"2026-06-26T03:52:46.984354Z","steps":["trace[1585362064] 'process raft request' (duration: 220.91795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:52:46.984586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.641864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-26T03:52:46.984666Z","caller":"traceutil/trace.go:171","msg":"trace[1154422910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8449; }","duration":"218.747627ms","start":"2026-06-26T03:52:46.765898Z","end":"2026-06-26T03:52:46.984646Z","steps":["trace[1154422910] 'agreement among raft nodes before linearized reading' (duration: 218.550812ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:52:47.486688Z","caller":"traceutil/trace.go:171","msg":"trace[515470178] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"113.922214ms","start":"2026-06-26T03:52:47.372737Z","end":"2026-06-26T03:52:47.486659Z","steps":["trace[515470178] 'process raft request' (duration: 113.73625ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:52:47.709231Z","caller":"traceutil/trace.go:171","msg":"trace[1010398691] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"250.090194ms","start":"2026-06-26T03:52:47.459106Z","end":"2026-06-26T03:52:47.709196Z","steps":["trace[1010398691] 'process raft request' (duration: 208.516928ms)","trace[1010398691] 'compare' (duration: 41.380442ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:52:47.720304Z","caller":"traceutil/trace.go:171","msg":"trace[299804038] linearizableReadLoop","detail":"{readStateIndex:8757; appliedIndex:8755; }","duration":"194.949817ms","start":"2026-06-26T03:52:47.525336Z","end":"2026-06-26T03:52:47.720286Z","steps":["trace[299804038] 'read index received' (duration: 142.379701ms)","trace[299804038] 'applied index is now lower than readState.Index' (duration: 52.569286ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:52:47.720362Z","caller":"traceutil/trace.go:171","msg":"trace[1916771990] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"229.594683ms","start":"2026-06-26T03:52:47.490744Z","end":"2026-06-26T03:52:47.720338Z","steps":["trace[1916771990] 'process raft request' (duration: 229.46177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:52:47.720388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.041289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:52:47.720418Z","caller":"traceutil/trace.go:171","msg":"trace[1293785186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8453; }","duration":"195.126031ms","start":"2026-06-26T03:52:47.525284Z","end":"2026-06-26T03:52:47.72041Z","steps":["trace[1293785186] 'agreement among raft nodes before linearized reading' (duration: 195.068499ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:52:48.275555Z","caller":"traceutil/trace.go:171","msg":"trace[1763146146] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"155.256515ms","start":"2026-06-26T03:52:48.120266Z","end":"2026-06-26T03:52:48.275522Z","steps":["trace[1763146146] 'process raft request' (duration: 154.875776ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:53:18.55049Z","caller":"traceutil/trace.go:171","msg":"trace[43027403] linearizableReadLoop","detail":"{readStateIndex:8985; appliedIndex:8984; }","duration":"108.216144ms","start":"2026-06-26T03:53:18.442251Z","end":"2026-06-26T03:53:18.550467Z","steps":["trace[43027403] 'read index received' (duration: 108.016539ms)","trace[43027403] 'applied index is now lower than readState.Index' (duration: 198.235µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:53:18.550554Z","caller":"traceutil/trace.go:171","msg":"trace[1378843873] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"125.318584ms","start":"2026-06-26T03:53:18.425199Z","end":"2026-06-26T03:53:18.550518Z","steps":["trace[1378843873] 'process raft request' (duration: 125.103808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:53:18.55082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.550033ms","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-26T03:53:18.550914Z","caller":"traceutil/trace.go:171","msg":"trace[1263385521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8673; }","duration":"108.700087ms","start":"2026-06-26T03:53:18.442192Z","end":"2026-06-26T03:53:18.550892Z","steps":["trace[1263385521] 'agreement among raft nodes before linearized reading' (duration: 108.389259ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:53:18.672524Z","caller":"traceutil/trace.go:171","msg":"trace[1002324399] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"116.95659ms","start":"2026-06-26T03:53:18.555534Z","end":"2026-06-26T03:53:18.67249Z","steps":["trace[1002324399] 'process raft request' (duration: 79.315179ms)","trace[1002324399] 'compare' (duration: 37.494117ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:53:32.855754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.797481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:53:32.855902Z","caller":"traceutil/trace.go:171","msg":"trace[839590983] linearizableReadLoop","detail":"{readStateIndex:9110; appliedIndex:9109; }","duration":"126.00306ms","start":"2026-06-26T03:53:32.729881Z","end":"2026-06-26T03:53:32.855884Z","steps":["trace[839590983] 'read index received' (duration: 59.341µs)","trace[839590983] 'applied index is now lower than readState.Index' (duration: 125.942499ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:53:32.85598Z","caller":"traceutil/trace.go:171","msg":"trace[632707687] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"237.514956ms","start":"2026-06-26T03:53:32.618453Z","end":"2026-06-26T03:53:32.855968Z","steps":["trace[632707687] 'process raft request' (duration: 94.404498ms)","trace[632707687] 'compare' (duration: 142.573566ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:53:32.856199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.315829ms","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-26T03:53:32.856275Z","caller":"traceutil/trace.go:171","msg":"trace[217805727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8791; }","duration":"126.444381ms","start":"2026-06-26T03:53:32.729812Z","end":"2026-06-26T03:53:32.856257Z","steps":["trace[217805727] 'agreement among raft nodes before linearized reading' (duration: 126.215536ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:53:32.989881Z","caller":"traceutil/trace.go:171","msg":"trace[1194368657] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"129.298221ms","start":"2026-06-26T03:53:32.860554Z","end":"2026-06-26T03:53:32.989853Z","steps":["trace[1194368657] 'process raft request' (duration: 116.55672ms)","trace[1194368657] 'compare' (duration: 12.614058ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:53:33.009342Z","caller":"traceutil/trace.go:171","msg":"trace[1692809321] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"102.822983ms","start":"2026-06-26T03:53:32.906488Z","end":"2026-06-26T03:53:33.009311Z","steps":["trace[1692809321] 'process raft request' (duration: 102.611918ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:53:33.009505Z","caller":"traceutil/trace.go:171","msg":"trace[1677430722] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"102.824004ms","start":"2026-06-26T03:53:32.906646Z","end":"2026-06-26T03:53:33.00947Z","steps":["trace[1677430722] 'process raft request' (duration: 102.597928ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:53:33.704527Z","caller":"traceutil/trace.go:171","msg":"trace[63812398] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"152.894158ms","start":"2026-06-26T03:53:33.551614Z","end":"2026-06-26T03:53:33.704508Z","steps":["trace[63812398] 'process raft request' (duration: 152.768425ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:53:42.156321Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e6.wal"} {"level":"info","ts":"2026-06-26T03:54:48.22092Z","caller":"traceutil/trace.go:171","msg":"trace[363135851] linearizableReadLoop","detail":"{readStateIndex:9873; appliedIndex:9872; }","duration":"102.525666ms","start":"2026-06-26T03:54:48.118368Z","end":"2026-06-26T03:54:48.220893Z","steps":["trace[363135851] 'read index received' (duration: 102.394023ms)","trace[363135851] 'applied index is now lower than readState.Index' (duration: 130.273µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:54:48.220968Z","caller":"traceutil/trace.go:171","msg":"trace[1051282951] transaction","detail":"{read_only:false; response_revision:9535; number_of_response:1; }","duration":"160.535703ms","start":"2026-06-26T03:54:48.060406Z","end":"2026-06-26T03:54:48.220942Z","steps":["trace[1051282951] 'process raft request' (duration: 160.297287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:54:48.221125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.732191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:54:48.221221Z","caller":"traceutil/trace.go:171","msg":"trace[2146822701] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9535; }","duration":"102.876545ms","start":"2026-06-26T03:54:48.118323Z","end":"2026-06-26T03:54:48.2212Z","steps":["trace[2146822701] 'agreement among raft nodes before linearized reading' (duration: 102.70628ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:54:48.3326Z","caller":"traceutil/trace.go:171","msg":"trace[1202839547] linearizableReadLoop","detail":"{readStateIndex:9874; appliedIndex:9873; }","duration":"103.361174ms","start":"2026-06-26T03:54:48.229215Z","end":"2026-06-26T03:54:48.332577Z","steps":["trace[1202839547] 'read index received' (duration: 103.237111ms)","trace[1202839547] 'applied index is now lower than readState.Index' (duration: 118.973µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:54:48.332705Z","caller":"traceutil/trace.go:171","msg":"trace[248619161] transaction","detail":"{read_only:false; response_revision:9536; number_of_response:1; }","duration":"103.693143ms","start":"2026-06-26T03:54:48.228982Z","end":"2026-06-26T03:54:48.332676Z","steps":["trace[248619161] 'process raft request' (duration: 103.454738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:54:48.332902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.669453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19336"} {"level":"info","ts":"2026-06-26T03:54:48.332985Z","caller":"traceutil/trace.go:171","msg":"trace[1335858805] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9536; }","duration":"103.804076ms","start":"2026-06-26T03:54:48.229162Z","end":"2026-06-26T03:54:48.332966Z","steps":["trace[1335858805] 'agreement among raft nodes before linearized reading' (duration: 103.517489ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:55:02.273198Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T03:55:02.279909Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-26T03:55:02.280207Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-26T03:57:18.769581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8132} {"level":"info","ts":"2026-06-26T03:57:18.840995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8132,"took":"69.19322ms","hash":28309211,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":28348416,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-26T03:57:18.841067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":28309211,"revision":8132,"compact-revision":6074} {"level":"info","ts":"2026-06-26T04:00:31.615771Z","caller":"traceutil/trace.go:171","msg":"trace[142187230] transaction","detail":"{read_only:false; response_revision:11995; number_of_response:1; }","duration":"132.088445ms","start":"2026-06-26T04:00:31.483619Z","end":"2026-06-26T04:00:31.615708Z","steps":["trace[142187230] 'process raft request' (duration: 119.010976ms)","trace[142187230] 'compare' (duration: 12.880944ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:00:31.862724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.311502ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-26T04:02:18.775801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10531} {"level":"info","ts":"2026-06-26T04:02:18.861408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10531,"took":"82.864928ms","hash":1860506964,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":26075136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-26T04:02:18.861602Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1860506964,"revision":10531,"compact-revision":8132} {"level":"info","ts":"2026-06-26T04:07:01.6445Z","caller":"traceutil/trace.go:171","msg":"trace[926373799] linearizableReadLoop","detail":"{readStateIndex:15147; appliedIndex:15146; }","duration":"205.938619ms","start":"2026-06-26T04:07:01.438518Z","end":"2026-06-26T04:07:01.644457Z","steps":["trace[926373799] 'read index received' (duration: 205.620392ms)","trace[926373799] 'applied index is now lower than readState.Index' (duration: 313.167µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:07:01.644593Z","caller":"traceutil/trace.go:171","msg":"trace[831176607] transaction","detail":"{read_only:false; response_revision:14638; number_of_response:1; }","duration":"210.628473ms","start":"2026-06-26T04:07:01.433928Z","end":"2026-06-26T04:07:01.644557Z","steps":["trace[831176607] 'process raft request' (duration: 210.312315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:07:01.644836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.289317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-26T04:07:01.644893Z","caller":"traceutil/trace.go:171","msg":"trace[2121401505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14638; }","duration":"206.429891ms","start":"2026-06-26T04:07:01.438445Z","end":"2026-06-26T04:07:01.644875Z","steps":["trace[2121401505] 'agreement among raft nodes before linearized reading' (duration: 206.205316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:07:01.645056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.459816ms","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-26T04:07:01.645194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.832382ms","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-26T04:07:01.645244Z","caller":"traceutil/trace.go:171","msg":"trace[1588675366] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14638; }","duration":"200.652031ms","start":"2026-06-26T04:07:01.444524Z","end":"2026-06-26T04:07:01.645176Z","steps":["trace[1588675366] 'agreement among raft nodes before linearized reading' (duration: 200.245871ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:01.645342Z","caller":"traceutil/trace.go:171","msg":"trace[479345872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14638; }","duration":"205.048887ms","start":"2026-06-26T04:07:01.440256Z","end":"2026-06-26T04:07:01.645305Z","steps":["trace[479345872] 'agreement among raft nodes before linearized reading' (duration: 204.601876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:07:01.645546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.909347ms","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-26T04:07:01.645667Z","caller":"traceutil/trace.go:171","msg":"trace[1069045581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14638; }","duration":"206.293777ms","start":"2026-06-26T04:07:01.439342Z","end":"2026-06-26T04:07:01.645635Z","steps":["trace[1069045581] 'agreement among raft nodes before linearized reading' (duration: 205.365794ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:01.780028Z","caller":"traceutil/trace.go:171","msg":"trace[383931082] transaction","detail":"{read_only:false; response_revision:14639; number_of_response:1; }","duration":"129.585191ms","start":"2026-06-26T04:07:01.650405Z","end":"2026-06-26T04:07:01.77999Z","steps":["trace[383931082] 'process raft request' (duration: 128.160157ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:01.788495Z","caller":"traceutil/trace.go:171","msg":"trace[1365675030] transaction","detail":"{read_only:false; response_revision:14640; number_of_response:1; }","duration":"137.470433ms","start":"2026-06-26T04:07:01.650997Z","end":"2026-06-26T04:07:01.788468Z","steps":["trace[1365675030] 'process raft request' (duration: 137.281618ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:01.788952Z","caller":"traceutil/trace.go:171","msg":"trace[1679403647] transaction","detail":"{read_only:false; response_revision:14642; number_of_response:1; }","duration":"137.845182ms","start":"2026-06-26T04:07:01.651096Z","end":"2026-06-26T04:07:01.788942Z","steps":["trace[1679403647] 'process raft request' (duration: 137.596526ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:01.788907Z","caller":"traceutil/trace.go:171","msg":"trace[1069510112] transaction","detail":"{read_only:false; response_revision:14641; number_of_response:1; }","duration":"137.797471ms","start":"2026-06-26T04:07:01.651035Z","end":"2026-06-26T04:07:01.788833Z","steps":["trace[1069510112] 'process raft request' (duration: 137.381531ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:07.805996Z","caller":"traceutil/trace.go:171","msg":"trace[479894245] transaction","detail":"{read_only:false; response_revision:14687; number_of_response:1; }","duration":"133.2485ms","start":"2026-06-26T04:07:07.672721Z","end":"2026-06-26T04:07:07.805969Z","steps":["trace[479894245] 'process raft request' (duration: 132.549123ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:07.986099Z","caller":"traceutil/trace.go:171","msg":"trace[1069212795] linearizableReadLoop","detail":"{readStateIndex:15200; appliedIndex:15198; }","duration":"172.852223ms","start":"2026-06-26T04:07:07.813212Z","end":"2026-06-26T04:07:07.986064Z","steps":["trace[1069212795] 'read index received' (duration: 89.592299ms)","trace[1069212795] 'applied index is now lower than readState.Index' (duration: 83.258454ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:07:07.98621Z","caller":"traceutil/trace.go:171","msg":"trace[144633198] transaction","detail":"{read_only:false; response_revision:14689; number_of_response:1; }","duration":"173.89981ms","start":"2026-06-26T04:07:07.812258Z","end":"2026-06-26T04:07:07.986158Z","steps":["trace[144633198] 'process raft request' (duration: 150.0635ms)","trace[144633198] 'compare' (duration: 23.612484ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:07:07.986396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.142001ms","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-26T04:07:07.986441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.165778ms","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-26T04:07:07.986442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.187518ms","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-26T04:07:07.98648Z","caller":"traceutil/trace.go:171","msg":"trace[1094731066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14689; }","duration":"173.276784ms","start":"2026-06-26T04:07:07.813182Z","end":"2026-06-26T04:07:07.986459Z","steps":["trace[1094731066] 'agreement among raft nodes before linearized reading' (duration: 172.997197ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:07.986493Z","caller":"traceutil/trace.go:171","msg":"trace[873738105] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14689; }","duration":"172.283241ms","start":"2026-06-26T04:07:07.814196Z","end":"2026-06-26T04:07:07.986479Z","steps":["trace[873738105] 'agreement among raft nodes before linearized reading' (duration: 172.143067ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:07.986509Z","caller":"traceutil/trace.go:171","msg":"trace[1918121122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14689; }","duration":"172.296281ms","start":"2026-06-26T04:07:07.8142Z","end":"2026-06-26T04:07:07.986496Z","steps":["trace[1918121122] 'agreement among raft nodes before linearized reading' (duration: 172.117717ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:07:18.783044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12723} {"level":"info","ts":"2026-06-26T04:07:18.859483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12723,"took":"73.026205ms","hash":1874869752,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":28438528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-26T04:07:18.859547Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1874869752,"revision":12723,"compact-revision":10531} {"level":"warn","ts":"2026-06-26T04:08:56.011231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.257808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T04:08:56.011339Z","caller":"traceutil/trace.go:171","msg":"trace[1661795625] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15927; }","duration":"218.413482ms","start":"2026-06-26T04:08:55.792895Z","end":"2026-06-26T04:08:56.011308Z","steps":["trace[1661795625] 'range keys from in-memory index tree' (duration: 218.150446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:08:56.011804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.097623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T04:08:56.011867Z","caller":"traceutil/trace.go:171","msg":"trace[1925131026] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15927; }","duration":"102.170834ms","start":"2026-06-26T04:08:55.90968Z","end":"2026-06-26T04:08:56.011851Z","steps":["trace[1925131026] 'range keys from in-memory index tree' (duration: 102.064362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:08:56.012086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.578795ms","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-26T04:08:56.012129Z","caller":"traceutil/trace.go:171","msg":"trace[1477872871] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15927; }","duration":"102.622676ms","start":"2026-06-26T04:08:55.909494Z","end":"2026-06-26T04:08:56.012117Z","steps":["trace[1477872871] 'range keys from in-memory index tree' (duration: 102.306087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:08:56.012444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.647822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"info","ts":"2026-06-26T04:08:56.012484Z","caller":"traceutil/trace.go:171","msg":"trace[631628766] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15927; }","duration":"101.750514ms","start":"2026-06-26T04:08:55.910724Z","end":"2026-06-26T04:08:56.012474Z","steps":["trace[631628766] 'range keys from in-memory index tree' (duration: 101.406996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:08:56.012686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.343535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-26T04:08:56.012721Z","caller":"traceutil/trace.go:171","msg":"trace[1011380851] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15927; }","duration":"101.394916ms","start":"2026-06-26T04:08:55.911318Z","end":"2026-06-26T04:08:56.012713Z","steps":["trace[1011380851] 'range keys from in-memory index tree' (duration: 101.241292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:08:56.012889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.409317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-26T04:08:56.012923Z","caller":"traceutil/trace.go:171","msg":"trace[1649227531] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15927; }","duration":"101.463199ms","start":"2026-06-26T04:08:55.911451Z","end":"2026-06-26T04:08:56.012914Z","steps":["trace[1649227531] 'range keys from in-memory index tree' (duration: 101.334976ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:12:18.78956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14961} {"level":"info","ts":"2026-06-26T04:12:18.889726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14961,"took":"96.860394ms","hash":2358573303,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":30654464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-26T04:12:18.889794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2358573303,"revision":14961,"compact-revision":12723} {"level":"info","ts":"2026-06-26T04:14:29.289168Z","caller":"traceutil/trace.go:171","msg":"trace[712091550] transaction","detail":"{read_only:false; response_revision:18390; number_of_response:1; }","duration":"211.841671ms","start":"2026-06-26T04:14:29.077297Z","end":"2026-06-26T04:14:29.289138Z","steps":["trace[712091550] 'process raft request' (duration: 211.696018ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:14:29.300819Z","caller":"traceutil/trace.go:171","msg":"trace[635610404] linearizableReadLoop","detail":"{readStateIndex:19019; appliedIndex:19017; }","duration":"189.181241ms","start":"2026-06-26T04:14:29.111612Z","end":"2026-06-26T04:14:29.300794Z","steps":["trace[635610404] 'read index received' (duration: 177.524548ms)","trace[635610404] 'applied index is now lower than readState.Index' (duration: 11.655853ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:14:29.301109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.48444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8f7p8\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-06-26T04:14:29.301187Z","caller":"traceutil/trace.go:171","msg":"trace[1958992172] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8f7p8; range_end:; response_count:1; response_revision:18392; }","duration":"189.609493ms","start":"2026-06-26T04:14:29.111565Z","end":"2026-06-26T04:14:29.301175Z","steps":["trace[1958992172] 'agreement among raft nodes before linearized reading' (duration: 189.380987ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:14:29.301556Z","caller":"traceutil/trace.go:171","msg":"trace[1294673890] transaction","detail":"{read_only:false; response_revision:18391; number_of_response:1; }","duration":"208.821209ms","start":"2026-06-26T04:14:29.09272Z","end":"2026-06-26T04:14:29.301541Z","steps":["trace[1294673890] 'process raft request' (duration: 207.923057ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:14:29.301664Z","caller":"traceutil/trace.go:171","msg":"trace[181695430] transaction","detail":"{read_only:false; response_revision:18392; number_of_response:1; }","duration":"208.383259ms","start":"2026-06-26T04:14:29.093232Z","end":"2026-06-26T04:14:29.301615Z","steps":["trace[181695430] 'process raft request' (duration: 207.520227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:14:37.320708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.883028ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-06-26T04:15:13.022032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832748770573012,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-26T04:15:13.3787Z","caller":"traceutil/trace.go:171","msg":"trace[1874065435] linearizableReadLoop","detail":"{readStateIndex:19599; appliedIndex:19598; }","duration":"857.207238ms","start":"2026-06-26T04:15:12.521463Z","end":"2026-06-26T04:15:13.37867Z","steps":["trace[1874065435] 'read index received' (duration: 856.979483ms)","trace[1874065435] 'applied index is now lower than readState.Index' (duration: 225.345µs)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:15:13.378972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.488735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-06-26T04:15:13.379007Z","caller":"traceutil/trace.go:171","msg":"trace[1730255332] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:18954; }","duration":"857.588157ms","start":"2026-06-26T04:15:12.521408Z","end":"2026-06-26T04:15:13.378996Z","steps":["trace[1730255332] 'agreement among raft nodes before linearized reading' (duration: 857.424473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.379038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.521388Z","time spent":"857.643089ms","remote":"127.0.0.1:35012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-06-26T04:15:13.379007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.399607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.686399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.404761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:15:13.379135Z","caller":"traceutil/trace.go:171","msg":"trace[167233319] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18954; }","duration":"562.809572ms","start":"2026-06-26T04:15:12.816311Z","end":"2026-06-26T04:15:13.37912Z","steps":["trace[167233319] 'agreement among raft nodes before linearized reading' (duration: 562.633908ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:15:13.379171Z","caller":"traceutil/trace.go:171","msg":"trace[401811156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18954; }","duration":"852.606397ms","start":"2026-06-26T04:15:12.526535Z","end":"2026-06-26T04:15:13.379142Z","steps":["trace[401811156] 'agreement among raft nodes before linearized reading' (duration: 852.378911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.379169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.092178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-26T04:15:13.37918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.816299Z","time spent":"562.872373ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"warn","ts":"2026-06-26T04:15:13.379226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.526503Z","time spent":"852.712948ms","remote":"127.0.0.1:34842","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-26T04:15:13.379267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.300451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:15:13.379293Z","caller":"traceutil/trace.go:171","msg":"trace[102919070] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:18954; }","duration":"471.350731ms","start":"2026-06-26T04:15:12.907934Z","end":"2026-06-26T04:15:13.379285Z","steps":["trace[102919070] 'agreement among raft nodes before linearized reading' (duration: 471.31294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.379332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.907923Z","time spent":"471.401643ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-06-26T04:15:13.379391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.390746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:15:13.379503Z","caller":"traceutil/trace.go:171","msg":"trace[666711001] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18954; }","duration":"563.495338ms","start":"2026-06-26T04:15:12.815993Z","end":"2026-06-26T04:15:13.379488Z","steps":["trace[666711001] 'agreement among raft nodes before linearized reading' (duration: 563.369255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.379511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.645408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.072159ms","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-26T04:15:13.379547Z","caller":"traceutil/trace.go:171","msg":"trace[968742161] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18954; }","duration":"471.688849ms","start":"2026-06-26T04:15:12.90785Z","end":"2026-06-26T04:15:13.379539Z","steps":["trace[968742161] 'agreement among raft nodes before linearized reading' (duration: 471.638248ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:15:13.379559Z","caller":"traceutil/trace.go:171","msg":"trace[2071468855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18954; }","duration":"493.102839ms","start":"2026-06-26T04:15:12.886446Z","end":"2026-06-26T04:15:13.379549Z","steps":["trace[2071468855] 'agreement among raft nodes before linearized reading' (duration: 493.026148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.379562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.56315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.907843Z","time spent":"471.732131ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"info","ts":"2026-06-26T04:15:13.379596Z","caller":"traceutil/trace.go:171","msg":"trace[2109149103] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:18954; }","duration":"563.671622ms","start":"2026-06-26T04:15:12.815916Z","end":"2026-06-26T04:15:13.379588Z","steps":["trace[2109149103] 'agreement among raft nodes before linearized reading' (duration: 563.610171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.379583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.886399Z","time spent":"493.179311ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-26T04:15:13.379615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.815884Z","time spent":"563.727164ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-06-26T04:15:13.379581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.588217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.827676ms","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-26T04:15:13.379671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.455769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.048722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:15:13.379657Z","caller":"traceutil/trace.go:171","msg":"trace[1790405061] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18954; }","duration":"562.684119ms","start":"2026-06-26T04:15:12.816962Z","end":"2026-06-26T04:15:13.379646Z","steps":["trace[1790405061] 'agreement among raft nodes before linearized reading' (duration: 562.573396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.379733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.17974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.827138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:15:13.379102Z","caller":"traceutil/trace.go:171","msg":"trace[807056396] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18954; }","duration":"472.549341ms","start":"2026-06-26T04:15:12.90653Z","end":"2026-06-26T04:15:13.379079Z","steps":["trace[807056396] 'agreement among raft nodes before linearized reading' (duration: 472.387827ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:15:13.37977Z","caller":"traceutil/trace.go:171","msg":"trace[1275290305] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18954; }","duration":"472.873389ms","start":"2026-06-26T04:15:12.906889Z","end":"2026-06-26T04:15:13.379763Z","steps":["trace[1275290305] 'agreement among raft nodes before linearized reading' (duration: 472.835248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.379806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.906877Z","time spent":"472.9242ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-06-26T04:15:13.379808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.906508Z","time spent":"473.291688ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-06-26T04:15:13.379866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.776344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.419082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-26T04:15:13.379919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.913784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-26T04:15:13.379793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.335699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:15:13.379704Z","caller":"traceutil/trace.go:171","msg":"trace[476198685] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18954; }","duration":"472.51038ms","start":"2026-06-26T04:15:12.907186Z","end":"2026-06-26T04:15:13.379696Z","steps":["trace[476198685] 'agreement among raft nodes before linearized reading' (duration: 472.467009ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:15:13.379979Z","caller":"traceutil/trace.go:171","msg":"trace[1208614519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18954; }","duration":"591.051147ms","start":"2026-06-26T04:15:12.788876Z","end":"2026-06-26T04:15:13.379927Z","steps":["trace[1208614519] 'agreement among raft nodes before linearized reading' (duration: 590.053004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.380013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.788865Z","time spent":"591.138819ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-26T04:15:13.380003Z","caller":"traceutil/trace.go:171","msg":"trace[850890248] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18954; }","duration":"743.849286ms","start":"2026-06-26T04:15:12.636061Z","end":"2026-06-26T04:15:13.379911Z","steps":["trace[850890248] 'agreement among raft nodes before linearized reading' (duration: 743.770514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.380049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.02125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.38006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.63605Z","time spent":"743.997129ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"info","ts":"2026-06-26T04:15:13.380071Z","caller":"traceutil/trace.go:171","msg":"trace[1189174052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18954; }","duration":"803.980166ms","start":"2026-06-26T04:15:12.575979Z","end":"2026-06-26T04:15:13.379959Z","steps":["trace[1189174052] 'agreement among raft nodes before linearized reading' (duration: 803.888134ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:15:13.380078Z","caller":"traceutil/trace.go:171","msg":"trace[1331155454] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18954; }","duration":"744.062691ms","start":"2026-06-26T04:15:12.636007Z","end":"2026-06-26T04:15:13.380069Z","steps":["trace[1331155454] 'agreement among raft nodes before linearized reading' (duration: 744.02308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.380113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.635998Z","time spent":"744.110713ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-06-26T04:15:13.380123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.575968Z","time spent":"804.14107ms","remote":"127.0.0.1:35112","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":"info","ts":"2026-06-26T04:15:13.379228Z","caller":"traceutil/trace.go:171","msg":"trace[312959460] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18954; }","duration":"455.19922ms","start":"2026-06-26T04:15:12.924008Z","end":"2026-06-26T04:15:13.379207Z","steps":["trace[312959460] 'agreement among raft nodes before linearized reading' (duration: 455.123728ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:15:13.379672Z","caller":"traceutil/trace.go:171","msg":"trace[213701711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18954; }","duration":"605.900457ms","start":"2026-06-26T04:15:12.773761Z","end":"2026-06-26T04:15:13.379661Z","steps":["trace[213701711] 'agreement among raft nodes before linearized reading' (duration: 605.788204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.380214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.773744Z","time spent":"606.461801ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-26T04:15:13.380216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.923982Z","time spent":"456.211205ms","remote":"127.0.0.1:55100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-26T04:15:13.380224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.699967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:15:13.380261Z","caller":"traceutil/trace.go:171","msg":"trace[329065734] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:18954; }","duration":"744.765999ms","start":"2026-06-26T04:15:12.635485Z","end":"2026-06-26T04:15:13.380251Z","steps":["trace[329065734] 'agreement among raft nodes before linearized reading' (duration: 744.694758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.37988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.816953Z","time spent":"562.797931ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-06-26T04:15:13.379547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.815884Z","time spent":"563.652862ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-06-26T04:15:13.380016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.339178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.38035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.503644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.379983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.907176Z","time spent":"472.800528ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"info","ts":"2026-06-26T04:15:13.380383Z","caller":"traceutil/trace.go:171","msg":"trace[1828645598] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18954; }","duration":"473.547695ms","start":"2026-06-26T04:15:12.906826Z","end":"2026-06-26T04:15:13.380374Z","steps":["trace[1828645598] 'agreement among raft nodes before linearized reading' (duration: 473.500424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.37945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.830639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:15:13.380417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.906819Z","time spent":"473.590266ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"info","ts":"2026-06-26T04:15:13.380426Z","caller":"traceutil/trace.go:171","msg":"trace[1729219682] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18954; }","duration":"562.805052ms","start":"2026-06-26T04:15:12.81761Z","end":"2026-06-26T04:15:13.380415Z","steps":["trace[1729219682] 'agreement among raft nodes before linearized reading' (duration: 561.810048ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:15:13.380269Z","caller":"traceutil/trace.go:171","msg":"trace[2117834223] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18954; }","duration":"744.578454ms","start":"2026-06-26T04:15:12.635389Z","end":"2026-06-26T04:15:13.379968Z","steps":["trace[2117834223] 'agreement among raft nodes before linearized reading' (duration: 744.356529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.380479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.635364Z","time spent":"745.102687ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"info","ts":"2026-06-26T04:15:13.379766Z","caller":"traceutil/trace.go:171","msg":"trace[560455009] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18954; }","duration":"743.230001ms","start":"2026-06-26T04:15:12.636527Z","end":"2026-06-26T04:15:13.379757Z","steps":["trace[560455009] 'agreement among raft nodes before linearized reading' (duration: 743.16378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.380584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.636515Z","time spent":"744.02829ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-06-26T04:15:13.380303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.635461Z","time spent":"744.83391ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"info","ts":"2026-06-26T04:15:13.380383Z","caller":"traceutil/trace.go:171","msg":"trace[1231706177] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18954; }","duration":"744.712577ms","start":"2026-06-26T04:15:12.63565Z","end":"2026-06-26T04:15:13.380363Z","steps":["trace[1231706177] 'agreement among raft nodes before linearized reading' (duration: 744.349198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.380686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.635639Z","time spent":"745.037714ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-06-26T04:15:13.380515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.817582Z","time spent":"562.913184ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"info","ts":"2026-06-26T04:15:13.378829Z","caller":"traceutil/trace.go:171","msg":"trace[737732495] transaction","detail":"{read_only:false; response_revision:18954; number_of_response:1; }","duration":"955.604537ms","start":"2026-06-26T04:15:12.423161Z","end":"2026-06-26T04:15:13.378766Z","steps":["trace[737732495] 'process raft request' (duration: 955.30895ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:15:13.379707Z","caller":"traceutil/trace.go:171","msg":"trace[1604351860] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18954; }","duration":"564.088763ms","start":"2026-06-26T04:15:12.815611Z","end":"2026-06-26T04:15:13.3797Z","steps":["trace[1604351860] 'agreement among raft nodes before linearized reading' (duration: 564.054392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:15:13.380867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.8156Z","time spent":"565.256462ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-06-26T04:15:13.3809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:15:12.423145Z","time spent":"957.643657ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:16:02.025093Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T04:16:02.034161Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-26T04:16:02.034293Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-26T04:17:18.797958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17487} {"level":"info","ts":"2026-06-26T04:17:18.898557Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17487,"took":"97.705441ms","hash":721254127,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":34926592,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-26T04:17:18.898614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":721254127,"revision":17487,"compact-revision":14961} {"level":"info","ts":"2026-06-26T04:20:03.317508Z","caller":"traceutil/trace.go:171","msg":"trace[1203357065] transaction","detail":"{read_only:false; response_revision:20990; number_of_response:1; }","duration":"143.34506ms","start":"2026-06-26T04:20:03.174125Z","end":"2026-06-26T04:20:03.31747Z","steps":["trace[1203357065] 'process raft request' (duration: 143.124254ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:20:03.548977Z","caller":"traceutil/trace.go:171","msg":"trace[1221747676] transaction","detail":"{read_only:false; response_revision:20992; number_of_response:1; }","duration":"149.233033ms","start":"2026-06-26T04:20:03.399697Z","end":"2026-06-26T04:20:03.54893Z","steps":["trace[1221747676] 'process raft request' (duration: 117.767259ms)","trace[1221747676] 'compare' (duration: 31.267689ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:20:04.088945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.012277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-64644d875b-vxfwz.18bc85be05aa222a\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-06-26T04:20:04.089068Z","caller":"traceutil/trace.go:171","msg":"trace[807863031] range","detail":"{range_begin:/registry/events/openstack/cinder-api-64644d875b-vxfwz.18bc85be05aa222a; range_end:; response_count:1; response_revision:20992; }","duration":"369.196052ms","start":"2026-06-26T04:20:03.719843Z","end":"2026-06-26T04:20:04.089039Z","steps":["trace[807863031] 'range keys from in-memory index tree' (duration: 368.791262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:20:04.089074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.761785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-06-26T04:20:04.089117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:20:03.719819Z","time spent":"369.287724ms","remote":"127.0.0.1:34930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1202,"request content":"key:\"/registry/events/openstack/cinder-api-64644d875b-vxfwz.18bc85be05aa222a\" "} {"level":"info","ts":"2026-06-26T04:20:04.08917Z","caller":"traceutil/trace.go:171","msg":"trace[1256324083] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:20992; }","duration":"247.97584ms","start":"2026-06-26T04:20:03.841172Z","end":"2026-06-26T04:20:04.089148Z","steps":["trace[1256324083] 'range keys from in-memory index tree' (duration: 247.460107ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:20:04.385531Z","caller":"traceutil/trace.go:171","msg":"trace[883883333] linearizableReadLoop","detail":"{readStateIndex:21707; appliedIndex:21706; }","duration":"287.759465ms","start":"2026-06-26T04:20:04.097737Z","end":"2026-06-26T04:20:04.385497Z","steps":["trace[883883333] 'read index received' (duration: 287.493929ms)","trace[883883333] 'applied index is now lower than readState.Index' (duration: 264.566µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:20:04.385602Z","caller":"traceutil/trace.go:171","msg":"trace[944155904] transaction","detail":"{read_only:false; response_revision:20993; number_of_response:1; }","duration":"293.984317ms","start":"2026-06-26T04:20:04.091576Z","end":"2026-06-26T04:20:04.385561Z","steps":["trace[944155904] 'process raft request' (duration: 293.6868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:20:04.385819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.060262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-06-26T04:20:04.385868Z","caller":"traceutil/trace.go:171","msg":"trace[501957150] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:20993; }","duration":"288.156495ms","start":"2026-06-26T04:20:04.0977Z","end":"2026-06-26T04:20:04.385856Z","steps":["trace[501957150] 'agreement among raft nodes before linearized reading' (duration: 287.95011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:20:04.385828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.442895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:20:04.386Z","caller":"traceutil/trace.go:171","msg":"trace[1708695970] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:20993; }","duration":"263.648261ms","start":"2026-06-26T04:20:04.12228Z","end":"2026-06-26T04:20:04.385929Z","steps":["trace[1708695970] 'agreement among raft nodes before linearized reading' (duration: 263.407955ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:20:04.806505Z","caller":"traceutil/trace.go:171","msg":"trace[1277079989] transaction","detail":"{read_only:false; response_revision:20994; number_of_response:1; }","duration":"111.117717ms","start":"2026-06-26T04:20:04.695364Z","end":"2026-06-26T04:20:04.806481Z","steps":["trace[1277079989] 'process raft request' (duration: 110.965244ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:20:04.822231Z","caller":"traceutil/trace.go:171","msg":"trace[1947167057] transaction","detail":"{read_only:false; response_revision:20995; number_of_response:1; }","duration":"107.576162ms","start":"2026-06-26T04:20:04.714625Z","end":"2026-06-26T04:20:04.822201Z","steps":["trace[1947167057] 'process raft request' (duration: 107.378706ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:22:18.808212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19863} {"level":"info","ts":"2026-06-26T04:22:18.927396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19863,"took":"115.859173ms","hash":1590452293,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":30371840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-26T04:22:18.927482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1590452293,"revision":19863,"compact-revision":17487} {"level":"info","ts":"2026-06-26T04:27:18.815848Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22087} {"level":"info","ts":"2026-06-26T04:27:18.924971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22087,"took":"103.92795ms","hash":2173592872,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":45113344,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-06-26T04:27:18.925033Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2173592872,"revision":22087,"compact-revision":19863} {"level":"info","ts":"2026-06-26T04:27:23.543498Z","caller":"traceutil/trace.go:171","msg":"trace[155242175] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"231.827142ms","start":"2026-06-26T04:27:23.311631Z","end":"2026-06-26T04:27:23.543458Z","steps":["trace[155242175] 'process raft request' (duration: 231.534805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:23.894021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.598678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-26T04:27:23.894101Z","caller":"traceutil/trace.go:171","msg":"trace[245011758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25528; }","duration":"243.72994ms","start":"2026-06-26T04:27:23.650354Z","end":"2026-06-26T04:27:23.894084Z","steps":["trace[245011758] 'range keys from in-memory index tree' (duration: 243.388502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:23.894123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.779747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:23.894212Z","caller":"traceutil/trace.go:171","msg":"trace[1543596717] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25528; }","duration":"122.91684ms","start":"2026-06-26T04:27:23.771277Z","end":"2026-06-26T04:27:23.894194Z","steps":["trace[1543596717] 'count revisions from in-memory index tree' (duration: 122.686255ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:23.94839Z","caller":"traceutil/trace.go:171","msg":"trace[567291197] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"129.165162ms","start":"2026-06-26T04:27:23.819197Z","end":"2026-06-26T04:27:23.948362Z","steps":["trace[567291197] 'process raft request' (duration: 129.007748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:24.150929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.497842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:27:24.151033Z","caller":"traceutil/trace.go:171","msg":"trace[1519891466] linearizableReadLoop","detail":"{readStateIndex:26373; appliedIndex:26372; }","duration":"121.084506ms","start":"2026-06-26T04:27:24.029932Z","end":"2026-06-26T04:27:24.151016Z","steps":["trace[1519891466] 'read index received' (duration: 14.41654ms)","trace[1519891466] 'applied index is now lower than readState.Index' (duration: 106.666526ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:24.15106Z","caller":"traceutil/trace.go:171","msg":"trace[1692656779] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"186.912713ms","start":"2026-06-26T04:27:23.964112Z","end":"2026-06-26T04:27:24.151025Z","steps":["trace[1692656779] 'process raft request' (duration: 80.240916ms)","trace[1692656779] 'compare' (duration: 105.727113ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:24.151219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.278041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:24.15126Z","caller":"traceutil/trace.go:171","msg":"trace[1125843061] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25532; }","duration":"121.365614ms","start":"2026-06-26T04:27:24.029881Z","end":"2026-06-26T04:27:24.151247Z","steps":["trace[1125843061] 'agreement among raft nodes before linearized reading' (duration: 121.237611ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:29.281917Z","caller":"traceutil/trace.go:171","msg":"trace[1552466783] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"193.526373ms","start":"2026-06-26T04:27:29.088366Z","end":"2026-06-26T04:27:29.281892Z","steps":["trace[1552466783] 'process raft request' (duration: 193.329769ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:29.282192Z","caller":"traceutil/trace.go:171","msg":"trace[223577858] linearizableReadLoop","detail":"{readStateIndex:26408; appliedIndex:26406; }","duration":"144.206127ms","start":"2026-06-26T04:27:29.137921Z","end":"2026-06-26T04:27:29.282127Z","steps":["trace[223577858] 'read index received' (duration: 143.824908ms)","trace[223577858] 'applied index is now lower than readState.Index' (duration: 377.549µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:29.282304Z","caller":"traceutil/trace.go:171","msg":"trace[1035600490] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"193.910022ms","start":"2026-06-26T04:27:29.088385Z","end":"2026-06-26T04:27:29.282295Z","steps":["trace[1035600490] 'process raft request' (duration: 193.462211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:29.282565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.604616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-26T04:27:29.282617Z","caller":"traceutil/trace.go:171","msg":"trace[201771558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25566; }","duration":"144.729519ms","start":"2026-06-26T04:27:29.137873Z","end":"2026-06-26T04:27:29.282603Z","steps":["trace[201771558] 'agreement among raft nodes before linearized reading' (duration: 144.471493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:29.364536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.34472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-26T04:27:29.364607Z","caller":"traceutil/trace.go:171","msg":"trace[1311390427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25567; }","duration":"224.446973ms","start":"2026-06-26T04:27:29.140146Z","end":"2026-06-26T04:27:29.364593Z","steps":["trace[1311390427] 'agreement among raft nodes before linearized reading' (duration: 224.262578ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:29.364525Z","caller":"traceutil/trace.go:171","msg":"trace[2088476045] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"189.2724ms","start":"2026-06-26T04:27:29.175213Z","end":"2026-06-26T04:27:29.364486Z","steps":["trace[2088476045] 'process raft request' (duration: 188.402748ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:29.701466Z","caller":"traceutil/trace.go:171","msg":"trace[2023142213] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"118.487523ms","start":"2026-06-26T04:27:29.582944Z","end":"2026-06-26T04:27:29.701432Z","steps":["trace[2023142213] 'process raft request' (duration: 118.269747ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:30.184338Z","caller":"traceutil/trace.go:171","msg":"trace[1346568198] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"197.305534ms","start":"2026-06-26T04:27:29.987007Z","end":"2026-06-26T04:27:30.184313Z","steps":["trace[1346568198] 'process raft request' (duration: 197.16164ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:30.29822Z","caller":"traceutil/trace.go:171","msg":"trace[722017978] linearizableReadLoop","detail":"{readStateIndex:26414; appliedIndex:26412; }","duration":"303.371426ms","start":"2026-06-26T04:27:29.994818Z","end":"2026-06-26T04:27:30.29819Z","steps":["trace[722017978] 'read index received' (duration: 189.401502ms)","trace[722017978] 'applied index is now lower than readState.Index' (duration: 113.968844ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:30.298384Z","caller":"traceutil/trace.go:171","msg":"trace[490983835] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"307.148349ms","start":"2026-06-26T04:27:29.991223Z","end":"2026-06-26T04:27:30.298371Z","steps":["trace[490983835] 'process raft request' (duration: 303.998962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:30.298622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:29.991196Z","time spent":"307.22495ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:27:30.298883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.665782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:27:30.298896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.049042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-26T04:27:30.299023Z","caller":"traceutil/trace.go:171","msg":"trace[508448585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25572; }","duration":"304.173125ms","start":"2026-06-26T04:27:29.994791Z","end":"2026-06-26T04:27:30.298965Z","steps":["trace[508448585] 'agreement among raft nodes before linearized reading' (duration: 303.917799ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:30.29897Z","caller":"traceutil/trace.go:171","msg":"trace[617610534] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25572; }","duration":"251.882047ms","start":"2026-06-26T04:27:30.047067Z","end":"2026-06-26T04:27:30.298949Z","steps":["trace[617610534] 'agreement among raft nodes before linearized reading' (duration: 251.5722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:30.29908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:29.994772Z","time spent":"304.295978ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-26T04:27:30.453832Z","caller":"traceutil/trace.go:171","msg":"trace[189107764] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"142.989547ms","start":"2026-06-26T04:27:30.310812Z","end":"2026-06-26T04:27:30.453801Z","steps":["trace[189107764] 'process raft request' (duration: 120.00843ms)","trace[189107764] 'compare' (duration: 22.841764ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:30.617449Z","caller":"traceutil/trace.go:171","msg":"trace[528309486] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"143.968861ms","start":"2026-06-26T04:27:30.473448Z","end":"2026-06-26T04:27:30.617417Z","steps":["trace[528309486] 'process raft request' (duration: 136.726225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:30.971187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.207352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:30.971287Z","caller":"traceutil/trace.go:171","msg":"trace[835628222] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25575; }","duration":"117.363385ms","start":"2026-06-26T04:27:30.853899Z","end":"2026-06-26T04:27:30.971263Z","steps":["trace[835628222] 'count revisions from in-memory index tree' (duration: 117.10574ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:31.302961Z","caller":"traceutil/trace.go:171","msg":"trace[1096269575] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"280.856462ms","start":"2026-06-26T04:27:31.022077Z","end":"2026-06-26T04:27:31.302933Z","steps":["trace[1096269575] 'process raft request' (duration: 280.653827ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:31.31936Z","caller":"traceutil/trace.go:171","msg":"trace[981098632] linearizableReadLoop","detail":"{readStateIndex:26419; appliedIndex:26417; }","duration":"270.111809ms","start":"2026-06-26T04:27:31.049218Z","end":"2026-06-26T04:27:31.31933Z","steps":["trace[981098632] 'read index received' (duration: 253.449965ms)","trace[981098632] 'applied index is now lower than readState.Index' (duration: 16.660054ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:31.319612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.368907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:27:31.319394Z","caller":"traceutil/trace.go:171","msg":"trace[1865937948] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"288.626759ms","start":"2026-06-26T04:27:31.030731Z","end":"2026-06-26T04:27:31.319358Z","steps":["trace[1865937948] 'process raft request' (duration: 288.358813ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:31.319665Z","caller":"traceutil/trace.go:171","msg":"trace[629722033] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25578; }","duration":"270.49676ms","start":"2026-06-26T04:27:31.049156Z","end":"2026-06-26T04:27:31.319653Z","steps":["trace[629722033] 'agreement among raft nodes before linearized reading' (duration: 270.369037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:31.319852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.444145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:31.319933Z","caller":"traceutil/trace.go:171","msg":"trace[1422429424] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25578; }","duration":"234.565048ms","start":"2026-06-26T04:27:31.085348Z","end":"2026-06-26T04:27:31.319913Z","steps":["trace[1422429424] 'agreement among raft nodes before linearized reading' (duration: 234.334003ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:31.542379Z","caller":"traceutil/trace.go:171","msg":"trace[388959257] linearizableReadLoop","detail":"{readStateIndex:26422; appliedIndex:26421; }","duration":"171.792806ms","start":"2026-06-26T04:27:31.370549Z","end":"2026-06-26T04:27:31.542342Z","steps":["trace[388959257] 'read index received' (duration: 88.405843ms)","trace[388959257] 'applied index is now lower than readState.Index' (duration: 83.384903ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:31.54251Z","caller":"traceutil/trace.go:171","msg":"trace[859131453] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"217.554885ms","start":"2026-06-26T04:27:31.324941Z","end":"2026-06-26T04:27:31.542496Z","steps":["trace[859131453] 'process raft request' (duration: 134.011489ms)","trace[859131453] 'compare' (duration: 83.180428ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:31.542781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.220055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-26T04:27:31.543097Z","caller":"traceutil/trace.go:171","msg":"trace[1538272404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25580; }","duration":"172.579755ms","start":"2026-06-26T04:27:31.370483Z","end":"2026-06-26T04:27:31.543063Z","steps":["trace[1538272404] 'agreement among raft nodes before linearized reading' (duration: 172.110654ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:31.638986Z","caller":"traceutil/trace.go:171","msg":"trace[1574061408] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"265.671402ms","start":"2026-06-26T04:27:31.37329Z","end":"2026-06-26T04:27:31.638961Z","steps":["trace[1574061408] 'process raft request' (duration: 265.528838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:31.639311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.648628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-26T04:27:31.639351Z","caller":"traceutil/trace.go:171","msg":"trace[748619809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25581; }","duration":"264.784491ms","start":"2026-06-26T04:27:31.374557Z","end":"2026-06-26T04:27:31.639341Z","steps":["trace[748619809] 'agreement among raft nodes before linearized reading' (duration: 264.379171ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:31.748864Z","caller":"traceutil/trace.go:171","msg":"trace[1083185231] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"202.171172ms","start":"2026-06-26T04:27:31.546667Z","end":"2026-06-26T04:27:31.748838Z","steps":["trace[1083185231] 'process raft request' (duration: 200.308957ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:31.748908Z","caller":"traceutil/trace.go:171","msg":"trace[1395643200] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"105.54742ms","start":"2026-06-26T04:27:31.643317Z","end":"2026-06-26T04:27:31.748864Z","steps":["trace[1395643200] 'process raft request' (duration: 105.286054ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:32.092136Z","caller":"traceutil/trace.go:171","msg":"trace[1535745057] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"144.307088ms","start":"2026-06-26T04:27:31.947795Z","end":"2026-06-26T04:27:32.092102Z","steps":["trace[1535745057] 'process raft request' (duration: 132.524343ms)","trace[1535745057] 'compare' (duration: 11.451048ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:32.473939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.495659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:27:32.474031Z","caller":"traceutil/trace.go:171","msg":"trace[670052599] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"284.660333ms","start":"2026-06-26T04:27:32.189356Z","end":"2026-06-26T04:27:32.474016Z","steps":["trace[670052599] 'process raft request' (duration: 132.018251ms)","trace[670052599] 'compare' (duration: 152.397026ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:32.485437Z","caller":"traceutil/trace.go:171","msg":"trace[1067164633] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"238.653008ms","start":"2026-06-26T04:27:32.246734Z","end":"2026-06-26T04:27:32.485387Z","steps":["trace[1067164633] 'process raft request' (duration: 238.253818ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:32.485598Z","caller":"traceutil/trace.go:171","msg":"trace[381627432] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"180.013066ms","start":"2026-06-26T04:27:32.305552Z","end":"2026-06-26T04:27:32.485565Z","steps":["trace[381627432] 'process raft request' (duration: 179.585846ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:32.626901Z","caller":"traceutil/trace.go:171","msg":"trace[1816760622] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"137.569046ms","start":"2026-06-26T04:27:32.48929Z","end":"2026-06-26T04:27:32.626859Z","steps":["trace[1816760622] 'process raft request' (duration: 133.225421ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:32.626992Z","caller":"traceutil/trace.go:171","msg":"trace[790190057] linearizableReadLoop","detail":"{readStateIndex:26432; appliedIndex:26431; }","duration":"134.836809ms","start":"2026-06-26T04:27:32.492131Z","end":"2026-06-26T04:27:32.626967Z","steps":["trace[790190057] 'read index received' (duration: 130.273419ms)","trace[790190057] 'applied index is now lower than readState.Index' (duration: 4.56179ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:32.627138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.993083ms","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-26T04:27:32.627185Z","caller":"traceutil/trace.go:171","msg":"trace[754584339] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25590; }","duration":"135.086855ms","start":"2026-06-26T04:27:32.492083Z","end":"2026-06-26T04:27:32.62717Z","steps":["trace[754584339] 'agreement among raft nodes before linearized reading' (duration: 134.996593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:32.627418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.159861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:32.627515Z","caller":"traceutil/trace.go:171","msg":"trace[1758600901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25590; }","duration":"103.410867ms","start":"2026-06-26T04:27:32.52406Z","end":"2026-06-26T04:27:32.627471Z","steps":["trace[1758600901] 'agreement among raft nodes before linearized reading' (duration: 103.154831ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:32.969022Z","caller":"traceutil/trace.go:171","msg":"trace[1764814453] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"237.052097ms","start":"2026-06-26T04:27:32.731948Z","end":"2026-06-26T04:27:32.969Z","steps":["trace[1764814453] 'process raft request' (duration: 236.841072ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:45.663103Z","caller":"traceutil/trace.go:171","msg":"trace[1968567125] linearizableReadLoop","detail":"{readStateIndex:26585; appliedIndex:26583; }","duration":"267.135588ms","start":"2026-06-26T04:27:45.395936Z","end":"2026-06-26T04:27:45.663072Z","steps":["trace[1968567125] 'read index received' (duration: 66.41065ms)","trace[1968567125] 'applied index is now lower than readState.Index' (duration: 200.723688ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:45.663133Z","caller":"traceutil/trace.go:171","msg":"trace[645726130] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"283.674329ms","start":"2026-06-26T04:27:45.379409Z","end":"2026-06-26T04:27:45.663083Z","steps":["trace[645726130] 'process raft request' (duration: 255.695551ms)","trace[645726130] 'compare' (duration: 27.791274ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:45.663345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.395555ms","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-26T04:27:45.663418Z","caller":"traceutil/trace.go:171","msg":"trace[156946828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25739; }","duration":"267.516277ms","start":"2026-06-26T04:27:45.395884Z","end":"2026-06-26T04:27:45.6634Z","steps":["trace[156946828] 'agreement among raft nodes before linearized reading' (duration: 267.292242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:45.663659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.348981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:45.663709Z","caller":"traceutil/trace.go:171","msg":"trace[110982902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25739; }","duration":"137.434062ms","start":"2026-06-26T04:27:45.526262Z","end":"2026-06-26T04:27:45.663697Z","steps":["trace[110982902] 'agreement among raft nodes before linearized reading' (duration: 137.33906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:45.663739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.644439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9975ab5e-83c8-4bce-b4bd-74067386b16f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-26T04:27:45.663831Z","caller":"traceutil/trace.go:171","msg":"trace[366884411] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9975ab5e-83c8-4bce-b4bd-74067386b16f; range_end:; response_count:1; response_revision:25739; }","duration":"262.766542ms","start":"2026-06-26T04:27:45.401048Z","end":"2026-06-26T04:27:45.663815Z","steps":["trace[366884411] 'agreement among raft nodes before linearized reading' (duration: 262.542627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:45.664094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.480576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-06-26T04:27:45.664293Z","caller":"traceutil/trace.go:171","msg":"trace[1816784111] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25739; }","duration":"140.608899ms","start":"2026-06-26T04:27:45.52355Z","end":"2026-06-26T04:27:45.664159Z","steps":["trace[1816784111] 'agreement among raft nodes before linearized reading' (duration: 140.092077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:46.042537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.885444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:27:46.042717Z","caller":"traceutil/trace.go:171","msg":"trace[1499547972] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"371.739074ms","start":"2026-06-26T04:27:45.670964Z","end":"2026-06-26T04:27:46.042703Z","steps":["trace[1499547972] 'process raft request' (duration: 371.680423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:46.042756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:45.67087Z","time spent":"371.867118ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:27:46.042729Z","caller":"traceutil/trace.go:171","msg":"trace[1737028079] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"374.901111ms","start":"2026-06-26T04:27:45.667798Z","end":"2026-06-26T04:27:46.042699Z","steps":["trace[1737028079] 'process raft request' (duration: 104.789382ms)","trace[1737028079] 'compare' (duration: 269.762091ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:46.042734Z","caller":"traceutil/trace.go:171","msg":"trace[1544171959] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"374.612185ms","start":"2026-06-26T04:27:45.668095Z","end":"2026-06-26T04:27:46.042707Z","steps":["trace[1544171959] 'process raft request' (duration: 374.512572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:46.042857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:45.667771Z","time spent":"375.020714ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:27:46.042875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:45.668074Z","time spent":"374.729357ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:27:46.042897Z","caller":"traceutil/trace.go:171","msg":"trace[2027074201] linearizableReadLoop","detail":"{readStateIndex:26589; appliedIndex:26585; }","duration":"224.124154ms","start":"2026-06-26T04:27:45.818745Z","end":"2026-06-26T04:27:46.04287Z","steps":["trace[2027074201] 'read index received' (duration: 223.345446ms)","trace[2027074201] 'applied index is now lower than readState.Index' (duration: 777.288µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:46.043015Z","caller":"traceutil/trace.go:171","msg":"trace[807419647] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"349.31203ms","start":"2026-06-26T04:27:45.693692Z","end":"2026-06-26T04:27:46.043004Z","steps":["trace[807419647] 'process raft request' (duration: 348.982562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:46.043115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:45.693667Z","time spent":"349.374012ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:27:46.043206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.117919ms","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-26T04:27:46.043227Z","caller":"traceutil/trace.go:171","msg":"trace[1921504768] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25743; }","duration":"211.177891ms","start":"2026-06-26T04:27:45.832042Z","end":"2026-06-26T04:27:46.04322Z","steps":["trace[1921504768] 'agreement among raft nodes before linearized reading' (duration: 211.099639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:46.04323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.746856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-26T04:27:46.043248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.505734ms","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-26T04:27:46.043259Z","caller":"traceutil/trace.go:171","msg":"trace[639490688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25743; }","duration":"216.815318ms","start":"2026-06-26T04:27:45.826436Z","end":"2026-06-26T04:27:46.043251Z","steps":["trace[639490688] 'agreement among raft nodes before linearized reading' (duration: 216.704305ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:46.043291Z","caller":"traceutil/trace.go:171","msg":"trace[425050323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25743; }","duration":"224.587806ms","start":"2026-06-26T04:27:45.818689Z","end":"2026-06-26T04:27:46.043277Z","steps":["trace[425050323] 'agreement among raft nodes before linearized reading' (duration: 224.448613ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:46.279678Z","caller":"traceutil/trace.go:171","msg":"trace[1341608923] linearizableReadLoop","detail":"{readStateIndex:26592; appliedIndex:26590; }","duration":"130.224629ms","start":"2026-06-26T04:27:46.149435Z","end":"2026-06-26T04:27:46.27966Z","steps":["trace[1341608923] 'read index received' (duration: 100.831306ms)","trace[1341608923] 'applied index is now lower than readState.Index' (duration: 29.392393ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:46.279822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.382292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:27:46.279847Z","caller":"traceutil/trace.go:171","msg":"trace[889310821] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25746; }","duration":"130.461904ms","start":"2026-06-26T04:27:46.149375Z","end":"2026-06-26T04:27:46.279837Z","steps":["trace[889310821] 'agreement among raft nodes before linearized reading' (duration: 130.377102ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:46.279834Z","caller":"traceutil/trace.go:171","msg":"trace[1868422072] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"230.142951ms","start":"2026-06-26T04:27:46.049641Z","end":"2026-06-26T04:27:46.279784Z","steps":["trace[1868422072] 'process raft request' (duration: 229.984467ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:46.280056Z","caller":"traceutil/trace.go:171","msg":"trace[1191625909] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"231.623757ms","start":"2026-06-26T04:27:46.048403Z","end":"2026-06-26T04:27:46.280027Z","steps":["trace[1191625909] 'process raft request' (duration: 201.890246ms)","trace[1191625909] 'compare' (duration: 28.954473ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:46.280225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.926783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9975ab5e-83c8-4bce-b4bd-74067386b16f\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-26T04:27:46.280329Z","caller":"traceutil/trace.go:171","msg":"trace[910065223] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9975ab5e-83c8-4bce-b4bd-74067386b16f; range_end:; response_count:1; response_revision:25746; }","duration":"126.085477ms","start":"2026-06-26T04:27:46.154219Z","end":"2026-06-26T04:27:46.280304Z","steps":["trace[910065223] 'agreement among raft nodes before linearized reading' (duration: 125.79315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:46.843714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.873747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:27:46.843892Z","caller":"traceutil/trace.go:171","msg":"trace[1473770653] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"167.760218ms","start":"2026-06-26T04:27:46.676115Z","end":"2026-06-26T04:27:46.843875Z","steps":["trace[1473770653] 'process raft request' (duration: 167.701267ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:46.844035Z","caller":"traceutil/trace.go:171","msg":"trace[568398700] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"315.47603ms","start":"2026-06-26T04:27:46.528524Z","end":"2026-06-26T04:27:46.844Z","steps":["trace[568398700] 'process raft request' (duration: 155.217564ms)","trace[568398700] 'compare' (duration: 159.720103ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:46.844109Z","caller":"traceutil/trace.go:171","msg":"trace[1719512675] linearizableReadLoop","detail":"{readStateIndex:26594; appliedIndex:26593; }","duration":"238.967024ms","start":"2026-06-26T04:27:46.605131Z","end":"2026-06-26T04:27:46.844098Z","steps":["trace[1719512675] 'read index received' (duration: 78.621526ms)","trace[1719512675] 'applied index is now lower than readState.Index' (duration: 160.344398ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:46.844188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:46.528498Z","time spent":"315.610152ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:27:46.844379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.21093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:46.844489Z","caller":"traceutil/trace.go:171","msg":"trace[1651685107] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25749; }","duration":"239.392035ms","start":"2026-06-26T04:27:46.60507Z","end":"2026-06-26T04:27:46.844462Z","steps":["trace[1651685107] 'agreement among raft nodes before linearized reading' (duration: 239.138259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:46.853759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.801643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-26T04:27:46.853801Z","caller":"traceutil/trace.go:171","msg":"trace[1968938404] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"136.49576ms","start":"2026-06-26T04:27:46.717231Z","end":"2026-06-26T04:27:46.853727Z","steps":["trace[1968938404] 'process raft request' (duration: 136.243014ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:46.853828Z","caller":"traceutil/trace.go:171","msg":"trace[1979429121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25750; }","duration":"163.867104ms","start":"2026-06-26T04:27:46.68994Z","end":"2026-06-26T04:27:46.853807Z","steps":["trace[1979429121] 'agreement among raft nodes before linearized reading' (duration: 163.67726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:47.172491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.40885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:27:47.172616Z","caller":"traceutil/trace.go:171","msg":"trace[143245536] linearizableReadLoop","detail":"{readStateIndex:26597; appliedIndex:26596; }","duration":"311.859593ms","start":"2026-06-26T04:27:46.86074Z","end":"2026-06-26T04:27:47.1726Z","steps":["trace[143245536] 'read index received' (duration: 165.165595ms)","trace[143245536] 'applied index is now lower than readState.Index' (duration: 146.692398ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:47.172646Z","caller":"traceutil/trace.go:171","msg":"trace[847412099] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"314.539348ms","start":"2026-06-26T04:27:46.858076Z","end":"2026-06-26T04:27:47.172615Z","steps":["trace[847412099] 'process raft request' (duration: 167.919722ms)","trace[847412099] 'compare' (duration: 146.268977ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:47.172714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.978296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:47.172745Z","caller":"traceutil/trace.go:171","msg":"trace[1818138649] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25751; }","duration":"312.066678ms","start":"2026-06-26T04:27:46.860668Z","end":"2026-06-26T04:27:47.172735Z","steps":["trace[1818138649] 'agreement among raft nodes before linearized reading' (duration: 312.000316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:47.172786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:46.860648Z","time spent":"312.129079ms","remote":"127.0.0.1:54388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-06-26T04:27:47.172812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:46.858051Z","time spent":"314.677612ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:27:47.620208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.542523ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-26T04:27:47.620494Z","caller":"traceutil/trace.go:171","msg":"trace[584033898] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"151.291078ms","start":"2026-06-26T04:27:47.469185Z","end":"2026-06-26T04:27:47.620476Z","steps":["trace[584033898] 'process raft request' (duration: 151.142085ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:47.620497Z","caller":"traceutil/trace.go:171","msg":"trace[2135391778] linearizableReadLoop","detail":"{readStateIndex:26598; appliedIndex:26597; }","duration":"447.790567ms","start":"2026-06-26T04:27:47.172642Z","end":"2026-06-26T04:27:47.620433Z","steps":["trace[2135391778] 'read index received' (duration: 114.988068ms)","trace[2135391778] 'applied index is now lower than readState.Index' (duration: 332.799689ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:47.620672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.888981ms","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-26T04:27:47.620852Z","caller":"traceutil/trace.go:171","msg":"trace[2119297842] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25752; }","duration":"486.117838ms","start":"2026-06-26T04:27:47.134713Z","end":"2026-06-26T04:27:47.620831Z","steps":["trace[2119297842] 'agreement among raft nodes before linearized reading' (duration: 485.882101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:47.620912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:47.134692Z","time spent":"486.20218ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-06-26T04:27:47.622881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.891948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9975ab5e-83c8-4bce-b4bd-74067386b16f\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-26T04:27:47.62294Z","caller":"traceutil/trace.go:171","msg":"trace[1929443639] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9975ab5e-83c8-4bce-b4bd-74067386b16f; range_end:; response_count:1; response_revision:25753; }","duration":"338.98836ms","start":"2026-06-26T04:27:47.283935Z","end":"2026-06-26T04:27:47.622924Z","steps":["trace[1929443639] 'agreement among raft nodes before linearized reading' (duration: 338.807815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:47.622968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:47.283913Z","time spent":"339.049702ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9975ab5e-83c8-4bce-b4bd-74067386b16f\" "} {"level":"info","ts":"2026-06-26T04:27:47.623152Z","caller":"traceutil/trace.go:171","msg":"trace[2053458833] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"117.892399ms","start":"2026-06-26T04:27:47.505252Z","end":"2026-06-26T04:27:47.623144Z","steps":["trace[2053458833] 'process raft request' (duration: 117.370516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:47.623459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.937805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:47.623495Z","caller":"traceutil/trace.go:171","msg":"trace[289198968] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25753; }","duration":"269.979696ms","start":"2026-06-26T04:27:47.353506Z","end":"2026-06-26T04:27:47.623486Z","steps":["trace[289198968] 'agreement among raft nodes before linearized reading' (duration: 269.925735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:47.912145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.540091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:27:47.912253Z","caller":"traceutil/trace.go:171","msg":"trace[1685435061] linearizableReadLoop","detail":"{readStateIndex:26601; appliedIndex:26600; }","duration":"223.005628ms","start":"2026-06-26T04:27:47.689228Z","end":"2026-06-26T04:27:47.912234Z","steps":["trace[1685435061] 'read index received' (duration: 81.25409ms)","trace[1685435061] 'applied index is now lower than readState.Index' (duration: 141.750008ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:47.91248Z","caller":"traceutil/trace.go:171","msg":"trace[411727135] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"242.018859ms","start":"2026-06-26T04:27:47.670447Z","end":"2026-06-26T04:27:47.912465Z","steps":["trace[411727135] 'process raft request' (duration: 100.022676ms)","trace[411727135] 'compare' (duration: 141.415879ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:47.912788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.72152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:47.912915Z","caller":"traceutil/trace.go:171","msg":"trace[692061692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25754; }","duration":"118.932094ms","start":"2026-06-26T04:27:47.793953Z","end":"2026-06-26T04:27:47.912885Z","steps":["trace[692061692] 'agreement among raft nodes before linearized reading' (duration: 118.678318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:47.913291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.044912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:47.913403Z","caller":"traceutil/trace.go:171","msg":"trace[674476678] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25754; }","duration":"224.150785ms","start":"2026-06-26T04:27:47.689211Z","end":"2026-06-26T04:27:47.913361Z","steps":["trace[674476678] 'agreement among raft nodes before linearized reading' (duration: 223.991771ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:48.149833Z","caller":"traceutil/trace.go:171","msg":"trace[524165056] linearizableReadLoop","detail":"{readStateIndex:26602; appliedIndex:26601; }","duration":"101.573243ms","start":"2026-06-26T04:27:48.048225Z","end":"2026-06-26T04:27:48.149798Z","steps":["trace[524165056] 'read index received' (duration: 91.470549ms)","trace[524165056] 'applied index is now lower than readState.Index' (duration: 10.100914ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:48.150231Z","caller":"traceutil/trace.go:171","msg":"trace[2054828099] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"102.118867ms","start":"2026-06-26T04:27:48.048096Z","end":"2026-06-26T04:27:48.150214Z","steps":["trace[2054828099] 'process raft request' (duration: 91.697544ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:48.150602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.169848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-26T04:27:48.150652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.403343ms","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-26T04:27:48.150681Z","caller":"traceutil/trace.go:171","msg":"trace[1198343702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25755; }","duration":"102.307731ms","start":"2026-06-26T04:27:48.048352Z","end":"2026-06-26T04:27:48.15066Z","steps":["trace[1198343702] 'agreement among raft nodes before linearized reading' (duration: 102.086376ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:48.150728Z","caller":"traceutil/trace.go:171","msg":"trace[498105474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25755; }","duration":"102.542636ms","start":"2026-06-26T04:27:48.048166Z","end":"2026-06-26T04:27:48.150709Z","steps":["trace[498105474] 'agreement among raft nodes before linearized reading' (duration: 102.299861ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:48.488356Z","caller":"traceutil/trace.go:171","msg":"trace[916069801] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"333.18431ms","start":"2026-06-26T04:27:48.155131Z","end":"2026-06-26T04:27:48.488315Z","steps":["trace[916069801] 'process raft request' (duration: 332.935534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:48.488573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:48.155114Z","time spent":"333.346073ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:27:48.556661Z","caller":"traceutil/trace.go:171","msg":"trace[1851189709] linearizableReadLoop","detail":"{readStateIndex:26605; appliedIndex:26602; }","duration":"390.473529ms","start":"2026-06-26T04:27:48.166164Z","end":"2026-06-26T04:27:48.556638Z","steps":["trace[1851189709] 'read index received' (duration: 322.018889ms)","trace[1851189709] 'applied index is now lower than readState.Index' (duration: 68.45389ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:48.556772Z","caller":"traceutil/trace.go:171","msg":"trace[70231614] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"400.269156ms","start":"2026-06-26T04:27:48.156493Z","end":"2026-06-26T04:27:48.556762Z","steps":["trace[70231614] 'process raft request' (duration: 388.037739ms)","trace[70231614] 'compare' (duration: 11.743504ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:48.55687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:48.156476Z","time spent":"400.319398ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:27:48.55688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.698845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:48.556961Z","caller":"traceutil/trace.go:171","msg":"trace[1090446998] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25758; }","duration":"390.842088ms","start":"2026-06-26T04:27:48.1661Z","end":"2026-06-26T04:27:48.556942Z","steps":["trace[1090446998] 'agreement among raft nodes before linearized reading' (duration: 390.706885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:48.557004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:48.166072Z","time spent":"390.92307ms","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-06-26T04:27:48.557113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.327308ms","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-26T04:27:48.557143Z","caller":"traceutil/trace.go:171","msg":"trace[1068040888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25758; }","duration":"273.39516ms","start":"2026-06-26T04:27:48.28374Z","end":"2026-06-26T04:27:48.557135Z","steps":["trace[1068040888] 'agreement among raft nodes before linearized reading' (duration: 273.293618ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:48.557203Z","caller":"traceutil/trace.go:171","msg":"trace[126583357] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"396.767861ms","start":"2026-06-26T04:27:48.160421Z","end":"2026-06-26T04:27:48.557189Z","steps":["trace[126583357] 'process raft request' (duration: 396.134956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:48.55729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:48.160404Z","time spent":"396.829683ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:27:48.55755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.918614ms","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-26T04:27:48.557585Z","caller":"traceutil/trace.go:171","msg":"trace[169101386] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25758; }","duration":"271.983985ms","start":"2026-06-26T04:27:48.285589Z","end":"2026-06-26T04:27:48.557573Z","steps":["trace[169101386] 'agreement among raft nodes before linearized reading' (duration: 271.907814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:48.910446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.205423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18433"} {"level":"info","ts":"2026-06-26T04:27:48.910512Z","caller":"traceutil/trace.go:171","msg":"trace[541008517] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25761; }","duration":"143.328645ms","start":"2026-06-26T04:27:48.76717Z","end":"2026-06-26T04:27:48.910498Z","steps":["trace[541008517] 'range keys from in-memory index tree' (duration: 143.061599ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:49.155469Z","caller":"traceutil/trace.go:171","msg":"trace[1722430107] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"306.613135ms","start":"2026-06-26T04:27:48.848831Z","end":"2026-06-26T04:27:49.155444Z","steps":["trace[1722430107] 'process raft request' (duration: 306.455471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:49.155636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:48.848806Z","time spent":"306.735317ms","remote":"127.0.0.1:35112","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-26T04:27:49.177129Z","caller":"traceutil/trace.go:171","msg":"trace[1189003339] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26608; }","duration":"319.006005ms","start":"2026-06-26T04:27:48.858102Z","end":"2026-06-26T04:27:49.177108Z","steps":["trace[1189003339] 'read index received' (duration: 297.229497ms)","trace[1189003339] 'applied index is now lower than readState.Index' (duration: 21.775478ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:49.177258Z","caller":"traceutil/trace.go:171","msg":"trace[260843067] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"318.176945ms","start":"2026-06-26T04:27:48.859069Z","end":"2026-06-26T04:27:49.177246Z","steps":["trace[260843067] 'process raft request' (duration: 317.933759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:49.177366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:48.859052Z","time spent":"318.238717ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:27:49.177539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.706428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:49.177509Z","caller":"traceutil/trace.go:171","msg":"trace[1325649775] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"135.740602ms","start":"2026-06-26T04:27:49.04171Z","end":"2026-06-26T04:27:49.177451Z","steps":["trace[1325649775] 'process raft request' (duration: 135.343922ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:49.177585Z","caller":"traceutil/trace.go:171","msg":"trace[1435350521] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25765; }","duration":"271.782441ms","start":"2026-06-26T04:27:48.905791Z","end":"2026-06-26T04:27:49.177574Z","steps":["trace[1435350521] 'agreement among raft nodes before linearized reading' (duration: 271.707029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:49.177656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.506918ms","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-26T04:27:49.177784Z","caller":"traceutil/trace.go:171","msg":"trace[639334679] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25765; }","duration":"319.703943ms","start":"2026-06-26T04:27:48.858049Z","end":"2026-06-26T04:27:49.177752Z","steps":["trace[639334679] 'agreement among raft nodes before linearized reading' (duration: 319.336304ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:49.177231Z","caller":"traceutil/trace.go:171","msg":"trace[906376118] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"327.558673ms","start":"2026-06-26T04:27:48.849631Z","end":"2026-06-26T04:27:49.17719Z","steps":["trace[906376118] 'process raft request' (duration: 327.233386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:49.17784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.991329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-26T04:27:49.177848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:48.858029Z","time spent":"319.805445ms","remote":"127.0.0.1:54918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-06-26T04:27:49.177863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.937872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-26T04:27:49.177892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:27:48.849612Z","time spent":"328.223729ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:27:49.177916Z","caller":"traceutil/trace.go:171","msg":"trace[742318650] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:25765; }","duration":"135.028934ms","start":"2026-06-26T04:27:49.042874Z","end":"2026-06-26T04:27:49.177902Z","steps":["trace[742318650] 'agreement among raft nodes before linearized reading' (duration: 134.921342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:49.177923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.347413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:49.177953Z","caller":"traceutil/trace.go:171","msg":"trace[992897692] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25765; }","duration":"220.378513ms","start":"2026-06-26T04:27:48.957567Z","end":"2026-06-26T04:27:49.177945Z","steps":["trace[992897692] 'agreement among raft nodes before linearized reading' (duration: 220.331512ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:49.177877Z","caller":"traceutil/trace.go:171","msg":"trace[721759942] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25765; }","duration":"141.067461ms","start":"2026-06-26T04:27:49.036798Z","end":"2026-06-26T04:27:49.177866Z","steps":["trace[721759942] 'agreement among raft nodes before linearized reading' (duration: 140.999429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:49.17816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.255637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-06-26T04:27:49.178213Z","caller":"traceutil/trace.go:171","msg":"trace[482045564] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:25765; }","duration":"259.368769ms","start":"2026-06-26T04:27:48.918833Z","end":"2026-06-26T04:27:49.178201Z","steps":["trace[482045564] 'agreement among raft nodes before linearized reading' (duration: 259.125223ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:49.450769Z","caller":"traceutil/trace.go:171","msg":"trace[663525770] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"268.154443ms","start":"2026-06-26T04:27:49.182589Z","end":"2026-06-26T04:27:49.450743Z","steps":["trace[663525770] 'process raft request' (duration: 192.123529ms)","trace[663525770] 'compare' (duration: 75.84025ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:27:49.450745Z","caller":"traceutil/trace.go:171","msg":"trace[671750105] linearizableReadLoop","detail":"{readStateIndex:26613; appliedIndex:26612; }","duration":"267.20348ms","start":"2026-06-26T04:27:49.183505Z","end":"2026-06-26T04:27:49.450709Z","steps":["trace[671750105] 'read index received' (duration: 191.144156ms)","trace[671750105] 'applied index is now lower than readState.Index' (duration: 76.057654ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:49.45089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.350564ms","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-26T04:27:49.450965Z","caller":"traceutil/trace.go:171","msg":"trace[804928239] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:25766; }","duration":"267.484127ms","start":"2026-06-26T04:27:49.183461Z","end":"2026-06-26T04:27:49.450945Z","steps":["trace[804928239] 'agreement among raft nodes before linearized reading' (duration: 267.347504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:49.451018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.867145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-26T04:27:49.451032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.385492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:49.451068Z","caller":"traceutil/trace.go:171","msg":"trace[2137105799] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25766; }","duration":"135.944317ms","start":"2026-06-26T04:27:49.315106Z","end":"2026-06-26T04:27:49.45105Z","steps":["trace[2137105799] 'agreement among raft nodes before linearized reading' (duration: 135.852615ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:49.45111Z","caller":"traceutil/trace.go:171","msg":"trace[1583170155] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:25766; }","duration":"196.519335ms","start":"2026-06-26T04:27:49.254563Z","end":"2026-06-26T04:27:49.451082Z","steps":["trace[1583170155] 'agreement among raft nodes before linearized reading' (duration: 196.390062ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:53.241703Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006823.wal"} {"level":"info","ts":"2026-06-26T04:27:54.706802Z","caller":"traceutil/trace.go:171","msg":"trace[2058124736] linearizableReadLoop","detail":"{readStateIndex:26670; appliedIndex:26669; }","duration":"180.871886ms","start":"2026-06-26T04:27:54.525905Z","end":"2026-06-26T04:27:54.706777Z","steps":["trace[2058124736] 'read index received' (duration: 180.818655ms)","trace[2058124736] 'applied index is now lower than readState.Index' (duration: 52.261µs)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:54.706938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.0234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:27:54.706915Z","caller":"traceutil/trace.go:171","msg":"trace[867867295] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"189.70301ms","start":"2026-06-26T04:27:54.517168Z","end":"2026-06-26T04:27:54.706871Z","steps":["trace[867867295] 'process raft request' (duration: 189.448924ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:54.706962Z","caller":"traceutil/trace.go:171","msg":"trace[1079317123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25821; }","duration":"181.130262ms","start":"2026-06-26T04:27:54.525824Z","end":"2026-06-26T04:27:54.706954Z","steps":["trace[1079317123] 'agreement among raft nodes before linearized reading' (duration: 181.066661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:54.751967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.2852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-26T04:27:54.751986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.965863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-26T04:27:54.752052Z","caller":"traceutil/trace.go:171","msg":"trace[1252543317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25822; }","duration":"170.430703ms","start":"2026-06-26T04:27:54.581599Z","end":"2026-06-26T04:27:54.75203Z","steps":["trace[1252543317] 'agreement among raft nodes before linearized reading' (duration: 170.174887ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:54.75209Z","caller":"traceutil/trace.go:171","msg":"trace[1344163649] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25822; }","duration":"142.123607ms","start":"2026-06-26T04:27:54.609941Z","end":"2026-06-26T04:27:54.752064Z","steps":["trace[1344163649] 'agreement among raft nodes before linearized reading' (duration: 141.782099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:54.752069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.830608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-06-26T04:27:54.752175Z","caller":"traceutil/trace.go:171","msg":"trace[322027460] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25822; }","duration":"162.974232ms","start":"2026-06-26T04:27:54.589184Z","end":"2026-06-26T04:27:54.752158Z","steps":["trace[322027460] 'agreement among raft nodes before linearized reading' (duration: 162.699445ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:54.752176Z","caller":"traceutil/trace.go:171","msg":"trace[772170078] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"145.010467ms","start":"2026-06-26T04:27:54.607143Z","end":"2026-06-26T04:27:54.752154Z","steps":["trace[772170078] 'process raft request' (duration: 131.998062ms)","trace[772170078] 'compare' (duration: 12.295588ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:27:54.752253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.049286ms","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-26T04:27:54.752281Z","caller":"traceutil/trace.go:171","msg":"trace[1923395278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25822; }","duration":"140.104448ms","start":"2026-06-26T04:27:54.612168Z","end":"2026-06-26T04:27:54.752273Z","steps":["trace[1923395278] 'agreement among raft nodes before linearized reading' (duration: 140.013556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:27:54.752304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.744467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-26T04:27:54.751965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.653591ms","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-26T04:27:54.752334Z","caller":"traceutil/trace.go:171","msg":"trace[624880507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25822; }","duration":"167.811769ms","start":"2026-06-26T04:27:54.584513Z","end":"2026-06-26T04:27:54.752325Z","steps":["trace[624880507] 'agreement among raft nodes before linearized reading' (duration: 167.725837ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:27:54.752348Z","caller":"traceutil/trace.go:171","msg":"trace[242573430] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25822; }","duration":"141.0756ms","start":"2026-06-26T04:27:54.611262Z","end":"2026-06-26T04:27:54.752337Z","steps":["trace[242573430] 'agreement among raft nodes before linearized reading' (duration: 140.424895ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:17.087387Z","caller":"traceutil/trace.go:171","msg":"trace[1930591521] transaction","detail":"{read_only:false; response_revision:26010; number_of_response:1; }","duration":"158.028552ms","start":"2026-06-26T04:28:16.929304Z","end":"2026-06-26T04:28:17.087333Z","steps":["trace[1930591521] 'process raft request' (duration: 83.887484ms)","trace[1930591521] 'compare' (duration: 73.757849ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:17.295599Z","caller":"traceutil/trace.go:171","msg":"trace[1231254702] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"366.189879ms","start":"2026-06-26T04:28:16.929374Z","end":"2026-06-26T04:28:17.295564Z","steps":["trace[1231254702] 'process raft request' (duration: 356.74547ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:17.295803Z","caller":"traceutil/trace.go:171","msg":"trace[1935833708] linearizableReadLoop","detail":"{readStateIndex:26866; appliedIndex:26863; }","duration":"174.178844ms","start":"2026-06-26T04:28:17.121603Z","end":"2026-06-26T04:28:17.295782Z","steps":["trace[1935833708] 'read index received' (duration: 164.53513ms)","trace[1935833708] 'applied index is now lower than readState.Index' (duration: 9.642574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:17.29594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.331528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:28:17.295973Z","caller":"traceutil/trace.go:171","msg":"trace[540036508] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:26013; }","duration":"174.41896ms","start":"2026-06-26T04:28:17.121542Z","end":"2026-06-26T04:28:17.295961Z","steps":["trace[540036508] 'agreement among raft nodes before linearized reading' (duration: 174.317358ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:17.296081Z","caller":"traceutil/trace.go:171","msg":"trace[1146733539] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"366.550778ms","start":"2026-06-26T04:28:16.929498Z","end":"2026-06-26T04:28:17.296049Z","steps":["trace[1146733539] 'process raft request' (duration: 366.209519ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:17.29612Z","caller":"traceutil/trace.go:171","msg":"trace[1593677162] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"366.59513ms","start":"2026-06-26T04:28:16.929433Z","end":"2026-06-26T04:28:17.296028Z","steps":["trace[1593677162] 'process raft request' (duration: 366.060326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:17.296224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:16.929491Z","time spent":"366.650901ms","remote":"127.0.0.1:35112","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-26T04:28:17.296628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:16.929397Z","time spent":"367.03699ms","remote":"127.0.0.1:35112","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-26T04:28:17.296292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:16.929356Z","time spent":"366.338313ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:28:17.710446Z","caller":"traceutil/trace.go:171","msg":"trace[436785409] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"105.447187ms","start":"2026-06-26T04:28:17.604972Z","end":"2026-06-26T04:28:17.710419Z","steps":["trace[436785409] 'process raft request' (duration: 105.292593ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:17.902525Z","caller":"traceutil/trace.go:171","msg":"trace[1477645511] linearizableReadLoop","detail":"{readStateIndex:26872; appliedIndex:26871; }","duration":"146.483671ms","start":"2026-06-26T04:28:17.756016Z","end":"2026-06-26T04:28:17.902499Z","steps":["trace[1477645511] 'read index received' (duration: 146.388519ms)","trace[1477645511] 'applied index is now lower than readState.Index' (duration: 94.262µs)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:17.902661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.629986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:28:17.902695Z","caller":"traceutil/trace.go:171","msg":"trace[154869995] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26018; }","duration":"146.725957ms","start":"2026-06-26T04:28:17.755959Z","end":"2026-06-26T04:28:17.902685Z","steps":["trace[154869995] 'agreement among raft nodes before linearized reading' (duration: 146.629655ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:17.903169Z","caller":"traceutil/trace.go:171","msg":"trace[101668438] transaction","detail":"{read_only:false; response_revision:26018; number_of_response:1; }","duration":"239.845716ms","start":"2026-06-26T04:28:17.663288Z","end":"2026-06-26T04:28:17.903134Z","steps":["trace[101668438] 'process raft request' (duration: 239.053747ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:19.033838Z","caller":"traceutil/trace.go:171","msg":"trace[711764121] transaction","detail":"{read_only:false; response_revision:26023; number_of_response:1; }","duration":"105.744174ms","start":"2026-06-26T04:28:18.927828Z","end":"2026-06-26T04:28:19.033573Z","steps":["trace[711764121] 'process raft request' (duration: 105.56043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:19.322199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.316464ms","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-26T04:28:19.322273Z","caller":"traceutil/trace.go:171","msg":"trace[390835341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26023; }","duration":"230.460368ms","start":"2026-06-26T04:28:19.0918Z","end":"2026-06-26T04:28:19.32226Z","steps":["trace[390835341] 'range keys from in-memory index tree' (duration: 230.165061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:19.322351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.223783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-26T04:28:19.322411Z","caller":"traceutil/trace.go:171","msg":"trace[1296729834] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26023; }","duration":"143.350645ms","start":"2026-06-26T04:28:19.179043Z","end":"2026-06-26T04:28:19.322393Z","steps":["trace[1296729834] 'range keys from in-memory index tree' (duration: 143.029808ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:19.322446Z","caller":"traceutil/trace.go:171","msg":"trace[1347443873] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"118.083593ms","start":"2026-06-26T04:28:19.204346Z","end":"2026-06-26T04:28:19.322429Z","steps":["trace[1347443873] 'process raft request' (duration: 72.321404ms)","trace[1347443873] 'compare' (duration: 45.305398ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:21.587096Z","caller":"traceutil/trace.go:171","msg":"trace[1613254504] linearizableReadLoop","detail":"{readStateIndex:26898; appliedIndex:26891; }","duration":"147.201239ms","start":"2026-06-26T04:28:21.439868Z","end":"2026-06-26T04:28:21.587069Z","steps":["trace[1613254504] 'read index received' (duration: 6.411935ms)","trace[1613254504] 'applied index is now lower than readState.Index' (duration: 140.788254ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:21.587355Z","caller":"traceutil/trace.go:171","msg":"trace[598036337] transaction","detail":"{read_only:false; response_revision:26039; number_of_response:1; }","duration":"162.421639ms","start":"2026-06-26T04:28:21.42492Z","end":"2026-06-26T04:28:21.587342Z","steps":["trace[598036337] 'process raft request' (duration: 134.536823ms)","trace[598036337] 'compare' (duration: 27.007265ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:21.587506Z","caller":"traceutil/trace.go:171","msg":"trace[556100016] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"163.194968ms","start":"2026-06-26T04:28:21.424305Z","end":"2026-06-26T04:28:21.5875Z","steps":["trace[556100016] 'process raft request' (duration: 162.583803ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:21.587604Z","caller":"traceutil/trace.go:171","msg":"trace[784852964] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"162.494701ms","start":"2026-06-26T04:28:21.425104Z","end":"2026-06-26T04:28:21.587598Z","steps":["trace[784852964] 'process raft request' (duration: 161.838554ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:21.588109Z","caller":"traceutil/trace.go:171","msg":"trace[1301230971] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"160.429411ms","start":"2026-06-26T04:28:21.42767Z","end":"2026-06-26T04:28:21.5881Z","steps":["trace[1301230971] 'process raft request' (duration: 159.363654ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:21.588209Z","caller":"traceutil/trace.go:171","msg":"trace[2128841352] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"161.64928ms","start":"2026-06-26T04:28:21.426551Z","end":"2026-06-26T04:28:21.5882Z","steps":["trace[2128841352] 'process raft request' (duration: 160.42765ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:21.588287Z","caller":"traceutil/trace.go:171","msg":"trace[464966214] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"160.709258ms","start":"2026-06-26T04:28:21.427571Z","end":"2026-06-26T04:28:21.58828Z","steps":["trace[464966214] 'process raft request' (duration: 159.432097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:21.588623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.674805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:28:21.588755Z","caller":"traceutil/trace.go:171","msg":"trace[73343775] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26044; }","duration":"148.887201ms","start":"2026-06-26T04:28:21.439843Z","end":"2026-06-26T04:28:21.58873Z","steps":["trace[73343775] 'agreement among raft nodes before linearized reading' (duration: 148.598634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:21.864825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.481947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-26T04:28:21.864927Z","caller":"traceutil/trace.go:171","msg":"trace[112860881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26044; }","duration":"140.651561ms","start":"2026-06-26T04:28:21.724249Z","end":"2026-06-26T04:28:21.864901Z","steps":["trace[112860881] 'range keys from in-memory index tree' (duration: 140.047956ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:22.147301Z","caller":"traceutil/trace.go:171","msg":"trace[556975424] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"275.051479ms","start":"2026-06-26T04:28:21.872224Z","end":"2026-06-26T04:28:22.147276Z","steps":["trace[556975424] 'process raft request' (duration: 274.716881ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:22.147594Z","caller":"traceutil/trace.go:171","msg":"trace[365961348] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"211.433586ms","start":"2026-06-26T04:28:21.93613Z","end":"2026-06-26T04:28:22.147563Z","steps":["trace[365961348] 'process raft request' (duration: 211.012026ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:22.25696Z","caller":"traceutil/trace.go:171","msg":"trace[988148115] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"292.219795ms","start":"2026-06-26T04:28:21.96471Z","end":"2026-06-26T04:28:22.256929Z","steps":["trace[988148115] 'process raft request' (duration: 268.606073ms)","trace[988148115] 'compare' (duration: 23.491699ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:22.734505Z","caller":"traceutil/trace.go:171","msg":"trace[461892192] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"100.953638ms","start":"2026-06-26T04:28:22.633525Z","end":"2026-06-26T04:28:22.734479Z","steps":["trace[461892192] 'process raft request' (duration: 100.716992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:22.88935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.693536ms","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-26T04:28:22.889436Z","caller":"traceutil/trace.go:171","msg":"trace[931487648] 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:26050; }","duration":"113.895462ms","start":"2026-06-26T04:28:22.775521Z","end":"2026-06-26T04:28:22.889417Z","steps":["trace[931487648] 'range keys from in-memory index tree' (duration: 113.590564ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:23.072333Z","caller":"traceutil/trace.go:171","msg":"trace[1037657322] linearizableReadLoop","detail":"{readStateIndex:26906; appliedIndex:26905; }","duration":"122.268845ms","start":"2026-06-26T04:28:22.950041Z","end":"2026-06-26T04:28:23.07231Z","steps":["trace[1037657322] 'read index received' (duration: 122.215044ms)","trace[1037657322] 'applied index is now lower than readState.Index' (duration: 52.561µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:23.072438Z","caller":"traceutil/trace.go:171","msg":"trace[697208020] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"161.023166ms","start":"2026-06-26T04:28:22.911381Z","end":"2026-06-26T04:28:23.072405Z","steps":["trace[697208020] 'process raft request' (duration: 160.736498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:23.072582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.517681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-26T04:28:23.072622Z","caller":"traceutil/trace.go:171","msg":"trace[1990128340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26051; }","duration":"122.625764ms","start":"2026-06-26T04:28:22.949987Z","end":"2026-06-26T04:28:23.072613Z","steps":["trace[1990128340] 'agreement among raft nodes before linearized reading' (duration: 122.449619ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:23.24131Z","caller":"traceutil/trace.go:171","msg":"trace[1928244009] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"169.491861ms","start":"2026-06-26T04:28:23.071774Z","end":"2026-06-26T04:28:23.241266Z","steps":["trace[1928244009] 'process raft request' (duration: 82.503912ms)","trace[1928244009] 'compare' (duration: 86.702222ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:23.241253Z","caller":"traceutil/trace.go:171","msg":"trace[293000687] linearizableReadLoop","detail":"{readStateIndex:26907; appliedIndex:26906; }","duration":"168.815103ms","start":"2026-06-26T04:28:23.072406Z","end":"2026-06-26T04:28:23.241221Z","steps":["trace[293000687] 'read index received' (duration: 81.868864ms)","trace[293000687] 'applied index is now lower than readState.Index' (duration: 86.944519ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:23.241528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.803002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-26T04:28:23.242495Z","caller":"traceutil/trace.go:171","msg":"trace[2088396073] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26052; }","duration":"244.795406ms","start":"2026-06-26T04:28:22.997666Z","end":"2026-06-26T04:28:23.242462Z","steps":["trace[2088396073] 'agreement among raft nodes before linearized reading' (duration: 243.664149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:23.244599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.204696ms","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-26T04:28:23.244646Z","caller":"traceutil/trace.go:171","msg":"trace[1420102166] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26053; }","duration":"139.251267ms","start":"2026-06-26T04:28:23.105378Z","end":"2026-06-26T04:28:23.24463Z","steps":["trace[1420102166] 'agreement among raft nodes before linearized reading' (duration: 139.181106ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:23.244835Z","caller":"traceutil/trace.go:171","msg":"trace[2049103776] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"168.191138ms","start":"2026-06-26T04:28:23.076636Z","end":"2026-06-26T04:28:23.244827Z","steps":["trace[2049103776] 'process raft request' (duration: 167.85827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:23.801431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.581033ms","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-26T04:28:23.801536Z","caller":"traceutil/trace.go:171","msg":"trace[1059771808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26055; }","duration":"207.725266ms","start":"2026-06-26T04:28:23.59379Z","end":"2026-06-26T04:28:23.801516Z","steps":["trace[1059771808] 'range keys from in-memory index tree' (duration: 207.41795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:23.801439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.872015ms","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-26T04:28:23.801706Z","caller":"traceutil/trace.go:171","msg":"trace[2125149599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26055; }","duration":"209.178772ms","start":"2026-06-26T04:28:23.592494Z","end":"2026-06-26T04:28:23.801673Z","steps":["trace[2125149599] 'range keys from in-memory index tree' (duration: 208.699061ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:23.802124Z","caller":"traceutil/trace.go:171","msg":"trace[384059538] linearizableReadLoop","detail":"{readStateIndex:26911; appliedIndex:26910; }","duration":"208.032654ms","start":"2026-06-26T04:28:23.594063Z","end":"2026-06-26T04:28:23.802096Z","steps":["trace[384059538] 'read index received' (duration: 138.060887ms)","trace[384059538] 'applied index is now lower than readState.Index' (duration: 69.969897ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:23.802308Z","caller":"traceutil/trace.go:171","msg":"trace[395495515] transaction","detail":"{read_only:false; response_revision:26056; number_of_response:1; }","duration":"208.24909ms","start":"2026-06-26T04:28:23.594031Z","end":"2026-06-26T04:28:23.80228Z","steps":["trace[395495515] 'process raft request' (duration: 138.113549ms)","trace[395495515] 'compare' (duration: 69.246839ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:23.802439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.381433ms","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-26T04:28:23.802495Z","caller":"traceutil/trace.go:171","msg":"trace[1076406043] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26056; }","duration":"208.491495ms","start":"2026-06-26T04:28:23.593986Z","end":"2026-06-26T04:28:23.802478Z","steps":["trace[1076406043] 'agreement among raft nodes before linearized reading' (duration: 208.287861ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:23.80535Z","caller":"traceutil/trace.go:171","msg":"trace[484468065] transaction","detail":"{read_only:false; response_revision:26057; number_of_response:1; }","duration":"209.832288ms","start":"2026-06-26T04:28:23.595495Z","end":"2026-06-26T04:28:23.805328Z","steps":["trace[484468065] 'process raft request' (duration: 209.524991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:23.805487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.196041ms","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-26T04:28:23.805601Z","caller":"traceutil/trace.go:171","msg":"trace[615184232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26057; }","duration":"211.366115ms","start":"2026-06-26T04:28:23.594206Z","end":"2026-06-26T04:28:23.805573Z","steps":["trace[615184232] 'agreement among raft nodes before linearized reading' (duration: 211.047098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:23.805746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.722431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:28:23.805865Z","caller":"traceutil/trace.go:171","msg":"trace[1126171369] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26057; }","duration":"194.889275ms","start":"2026-06-26T04:28:23.61095Z","end":"2026-06-26T04:28:23.805839Z","steps":["trace[1126171369] 'agreement among raft nodes before linearized reading' (duration: 194.636828ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:39.361159Z","caller":"traceutil/trace.go:171","msg":"trace[2119869259] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"152.308523ms","start":"2026-06-26T04:28:39.208829Z","end":"2026-06-26T04:28:39.361137Z","steps":["trace[2119869259] 'process raft request' (duration: 152.145429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:39.704482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.867974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:28:39.704658Z","caller":"traceutil/trace.go:171","msg":"trace[376011763] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"400.636235ms","start":"2026-06-26T04:28:39.303998Z","end":"2026-06-26T04:28:39.704634Z","steps":["trace[376011763] 'process raft request' (duration: 163.534835ms)","trace[376011763] 'compare' (duration: 236.529216ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:39.704686Z","caller":"traceutil/trace.go:171","msg":"trace[2138060369] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"194.141458ms","start":"2026-06-26T04:28:39.510527Z","end":"2026-06-26T04:28:39.704668Z","steps":["trace[2138060369] 'process raft request' (duration: 194.076037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:39.70477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.303974Z","time spent":"400.735497ms","remote":"127.0.0.1:35112","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-26T04:28:39.704864Z","caller":"traceutil/trace.go:171","msg":"trace[1460773147] linearizableReadLoop","detail":"{readStateIndex:27027; appliedIndex:27025; }","duration":"396.367772ms","start":"2026-06-26T04:28:39.308489Z","end":"2026-06-26T04:28:39.704857Z","steps":["trace[1460773147] 'read index received' (duration: 52.502113ms)","trace[1460773147] 'applied index is now lower than readState.Index' (duration: 343.864939ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:39.704982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.495625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-26T04:28:39.70501Z","caller":"traceutil/trace.go:171","msg":"trace[317244768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26170; }","duration":"396.540846ms","start":"2026-06-26T04:28:39.308462Z","end":"2026-06-26T04:28:39.705003Z","steps":["trace[317244768] 'agreement among raft nodes before linearized reading' (duration: 396.448884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:39.705033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.308456Z","time spent":"396.571167ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-26T04:28:39.705128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.411775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:28:39.705146Z","caller":"traceutil/trace.go:171","msg":"trace[1765781908] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26170; }","duration":"347.488507ms","start":"2026-06-26T04:28:39.357651Z","end":"2026-06-26T04:28:39.705139Z","steps":["trace[1765781908] 'agreement among raft nodes before linearized reading' (duration: 347.430725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:39.705166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.357624Z","time spent":"347.536627ms","remote":"127.0.0.1:35270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-26T04:28:40.147198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.527639ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:28:40.14739Z","caller":"traceutil/trace.go:171","msg":"trace[2040653696] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26170; }","duration":"152.730384ms","start":"2026-06-26T04:28:39.994633Z","end":"2026-06-26T04:28:40.147363Z","steps":["trace[2040653696] 'range keys from in-memory index tree' (duration: 152.507079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:40.147511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.25853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:28:40.147581Z","caller":"traceutil/trace.go:171","msg":"trace[1133289734] linearizableReadLoop","detail":"{readStateIndex:27029; appliedIndex:27028; }","duration":"359.842096ms","start":"2026-06-26T04:28:39.787728Z","end":"2026-06-26T04:28:40.14757Z","steps":["trace[1133289734] 'read index received' (duration: 97.366571ms)","trace[1133289734] 'applied index is now lower than readState.Index' (duration: 262.474365ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:40.147622Z","caller":"traceutil/trace.go:171","msg":"trace[345122445] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"438.443212ms","start":"2026-06-26T04:28:39.709173Z","end":"2026-06-26T04:28:40.147616Z","steps":["trace[345122445] 'process raft request' (duration: 176.021488ms)","trace[345122445] 'compare' (duration: 262.033215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:40.147649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.709155Z","time spent":"438.478313ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:28:40.14782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.091082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:28:40.147836Z","caller":"traceutil/trace.go:171","msg":"trace[2079953370] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26171; }","duration":"360.142063ms","start":"2026-06-26T04:28:39.787688Z","end":"2026-06-26T04:28:40.14783Z","steps":["trace[2079953370] 'agreement among raft nodes before linearized reading' (duration: 360.095572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:40.147851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.787668Z","time spent":"360.179254ms","remote":"127.0.0.1:35288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-26T04:28:40.497911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.296815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:28:40.498107Z","caller":"traceutil/trace.go:171","msg":"trace[1595496385] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"291.524049ms","start":"2026-06-26T04:28:40.206573Z","end":"2026-06-26T04:28:40.498097Z","steps":["trace[1595496385] 'process raft request' (duration: 291.484868ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:40.498143Z","caller":"traceutil/trace.go:171","msg":"trace[1221059095] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"643.305189ms","start":"2026-06-26T04:28:39.854776Z","end":"2026-06-26T04:28:40.498081Z","steps":["trace[1221059095] 'process raft request' (duration: 643.213947ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:40.498134Z","caller":"traceutil/trace.go:171","msg":"trace[1773709041] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"643.387021ms","start":"2026-06-26T04:28:39.85468Z","end":"2026-06-26T04:28:40.498067Z","steps":["trace[1773709041] 'process raft request' (duration: 454.85195ms)","trace[1773709041] 'compare' (duration: 187.967128ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:40.498328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.854768Z","time spent":"643.479713ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:28:40.498385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.854655Z","time spent":"643.612547ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:28:40.498403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.52238ms","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-26T04:28:40.498172Z","caller":"traceutil/trace.go:171","msg":"trace[1927070627] linearizableReadLoop","detail":"{readStateIndex:27031; appliedIndex:27029; }","duration":"350.523359ms","start":"2026-06-26T04:28:40.147594Z","end":"2026-06-26T04:28:40.498117Z","steps":["trace[1927070627] 'read index received' (duration: 161.951457ms)","trace[1927070627] 'applied index is now lower than readState.Index' (duration: 188.562722ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:40.498443Z","caller":"traceutil/trace.go:171","msg":"trace[1862580754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26174; }","duration":"595.618994ms","start":"2026-06-26T04:28:39.902814Z","end":"2026-06-26T04:28:40.498433Z","steps":["trace[1862580754] 'agreement among raft nodes before linearized reading' (duration: 595.417329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:40.4984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.435644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-26T04:28:40.49848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.902792Z","time spent":"595.681455ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-26T04:28:40.498484Z","caller":"traceutil/trace.go:171","msg":"trace[319852733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26174; }","duration":"594.556687ms","start":"2026-06-26T04:28:39.903911Z","end":"2026-06-26T04:28:40.498468Z","steps":["trace[319852733] 'agreement among raft nodes before linearized reading' (duration: 594.403333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:40.498473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.704925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-26T04:28:40.498487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.781189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-06-26T04:28:40.498521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.903891Z","time spent":"594.618849ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-26T04:28:40.498526Z","caller":"traceutil/trace.go:171","msg":"trace[1603551041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26174; }","duration":"588.793547ms","start":"2026-06-26T04:28:39.909721Z","end":"2026-06-26T04:28:40.498514Z","steps":["trace[1603551041] 'agreement among raft nodes before linearized reading' (duration: 588.592702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:40.498554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.909711Z","time spent":"588.839359ms","remote":"127.0.0.1:35112","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":"info","ts":"2026-06-26T04:28:40.498558Z","caller":"traceutil/trace.go:171","msg":"trace[2103519362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26174; }","duration":"586.87219ms","start":"2026-06-26T04:28:39.911665Z","end":"2026-06-26T04:28:40.498537Z","steps":["trace[2103519362] 'agreement among raft nodes before linearized reading' (duration: 586.686946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:40.49861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:39.911657Z","time spent":"586.940852ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-26T04:28:40.503602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.833344ms","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-26T04:28:40.503647Z","caller":"traceutil/trace.go:171","msg":"trace[994896932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26175; }","duration":"274.880565ms","start":"2026-06-26T04:28:40.228755Z","end":"2026-06-26T04:28:40.503636Z","steps":["trace[994896932] 'agreement among raft nodes before linearized reading' (duration: 274.751442ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:40.503754Z","caller":"traceutil/trace.go:171","msg":"trace[1900647030] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"109.032905ms","start":"2026-06-26T04:28:40.394697Z","end":"2026-06-26T04:28:40.50373Z","steps":["trace[1900647030] 'process raft request' (duration: 108.731737ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:40.675819Z","caller":"traceutil/trace.go:171","msg":"trace[1524586681] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"172.437291ms","start":"2026-06-26T04:28:40.503347Z","end":"2026-06-26T04:28:40.675784Z","steps":["trace[1524586681] 'process raft request' (duration: 149.636988ms)","trace[1524586681] 'compare' (duration: 22.647829ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:40.712696Z","caller":"traceutil/trace.go:171","msg":"trace[99513010] transaction","detail":"{read_only:false; response_revision:26177; number_of_response:1; }","duration":"209.033289ms","start":"2026-06-26T04:28:40.503634Z","end":"2026-06-26T04:28:40.712667Z","steps":["trace[99513010] 'process raft request' (duration: 208.836985ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:40.713094Z","caller":"traceutil/trace.go:171","msg":"trace[1185803847] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"209.408628ms","start":"2026-06-26T04:28:40.503657Z","end":"2026-06-26T04:28:40.713065Z","steps":["trace[1185803847] 'process raft request' (duration: 208.962907ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:40.713138Z","caller":"traceutil/trace.go:171","msg":"trace[901333794] linearizableReadLoop","detail":"{readStateIndex:27039; appliedIndex:27033; }","duration":"182.70279ms","start":"2026-06-26T04:28:40.530409Z","end":"2026-06-26T04:28:40.713112Z","steps":["trace[901333794] 'read index received' (duration: 122.679924ms)","trace[901333794] 'applied index is now lower than readState.Index' (duration: 60.021936ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:40.71315Z","caller":"traceutil/trace.go:171","msg":"trace[1348446338] transaction","detail":"{read_only:false; response_revision:26179; number_of_response:1; }","duration":"209.414348ms","start":"2026-06-26T04:28:40.503703Z","end":"2026-06-26T04:28:40.713117Z","steps":["trace[1348446338] 'process raft request' (duration: 209.200863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:40.713323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.883094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:28:40.71339Z","caller":"traceutil/trace.go:171","msg":"trace[439347269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26181; }","duration":"183.003627ms","start":"2026-06-26T04:28:40.53037Z","end":"2026-06-26T04:28:40.713374Z","steps":["trace[439347269] 'agreement among raft nodes before linearized reading' (duration: 182.844914ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:40.713385Z","caller":"traceutil/trace.go:171","msg":"trace[1823700438] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"205.855422ms","start":"2026-06-26T04:28:40.507503Z","end":"2026-06-26T04:28:40.713358Z","steps":["trace[1823700438] 'process raft request' (duration: 205.518504ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:40.713436Z","caller":"traceutil/trace.go:171","msg":"trace[166606219] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"201.775173ms","start":"2026-06-26T04:28:40.511614Z","end":"2026-06-26T04:28:40.713389Z","steps":["trace[166606219] 'process raft request' (duration: 201.452495ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:45.854992Z","caller":"traceutil/trace.go:171","msg":"trace[1546568202] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"130.151336ms","start":"2026-06-26T04:28:45.724799Z","end":"2026-06-26T04:28:45.854969Z","steps":["trace[1546568202] 'process raft request' (duration: 130.030593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:46.040862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.174338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-26T04:28:46.040946Z","caller":"traceutil/trace.go:171","msg":"trace[583993813] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26225; }","duration":"163.323461ms","start":"2026-06-26T04:28:45.8776Z","end":"2026-06-26T04:28:46.040923Z","steps":["trace[583993813] 'count revisions from in-memory index tree' (duration: 163.095406ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:46.386296Z","caller":"traceutil/trace.go:171","msg":"trace[720052703] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"197.660364ms","start":"2026-06-26T04:28:46.188604Z","end":"2026-06-26T04:28:46.386265Z","steps":["trace[720052703] 'process raft request' (duration: 197.460459ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:46.669532Z","caller":"traceutil/trace.go:171","msg":"trace[149057690] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"146.43624ms","start":"2026-06-26T04:28:46.523057Z","end":"2026-06-26T04:28:46.669493Z","steps":["trace[149057690] 'process raft request' (duration: 146.317958ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:46.669594Z","caller":"traceutil/trace.go:171","msg":"trace[348881175] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"149.32867ms","start":"2026-06-26T04:28:46.520238Z","end":"2026-06-26T04:28:46.669567Z","steps":["trace[348881175] 'process raft request' (duration: 148.991392ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:46.669569Z","caller":"traceutil/trace.go:171","msg":"trace[159420421] transaction","detail":"{read_only:false; response_revision:26230; number_of_response:1; }","duration":"146.352649ms","start":"2026-06-26T04:28:46.523186Z","end":"2026-06-26T04:28:46.669539Z","steps":["trace[159420421] 'process raft request' (duration: 146.229026ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:46.855216Z","caller":"traceutil/trace.go:171","msg":"trace[1791776943] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"154.049495ms","start":"2026-06-26T04:28:46.701138Z","end":"2026-06-26T04:28:46.855188Z","steps":["trace[1791776943] 'process raft request' (duration: 78.569245ms)","trace[1791776943] 'compare' (duration: 75.205954ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:46.906694Z","caller":"traceutil/trace.go:171","msg":"trace[1234300894] linearizableReadLoop","detail":"{readStateIndex:27091; appliedIndex:27089; }","duration":"170.374571ms","start":"2026-06-26T04:28:46.736295Z","end":"2026-06-26T04:28:46.90667Z","steps":["trace[1234300894] 'read index received' (duration: 44.084189ms)","trace[1234300894] 'applied index is now lower than readState.Index' (duration: 126.289622ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:46.906881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.565495ms","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-26T04:28:46.906909Z","caller":"traceutil/trace.go:171","msg":"trace[1380255385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26232; }","duration":"170.650598ms","start":"2026-06-26T04:28:46.73625Z","end":"2026-06-26T04:28:46.906901Z","steps":["trace[1380255385] 'agreement among raft nodes before linearized reading' (duration: 170.526855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:46.906969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.65502ms","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-26T04:28:46.907034Z","caller":"traceutil/trace.go:171","msg":"trace[1705264670] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26232; }","duration":"149.744891ms","start":"2026-06-26T04:28:46.757272Z","end":"2026-06-26T04:28:46.907017Z","steps":["trace[1705264670] 'agreement among raft nodes before linearized reading' (duration: 149.644259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:46.907083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.315741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-26T04:28:46.907171Z","caller":"traceutil/trace.go:171","msg":"trace[1468385796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26232; }","duration":"168.450944ms","start":"2026-06-26T04:28:46.738696Z","end":"2026-06-26T04:28:46.907147Z","steps":["trace[1468385796] 'agreement among raft nodes before linearized reading' (duration: 168.217399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:46.9072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.495291ms","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-26T04:28:46.907236Z","caller":"traceutil/trace.go:171","msg":"trace[1664433296] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26232; }","duration":"167.570403ms","start":"2026-06-26T04:28:46.739653Z","end":"2026-06-26T04:28:46.907224Z","steps":["trace[1664433296] 'agreement among raft nodes before linearized reading' (duration: 167.480931ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:46.907188Z","caller":"traceutil/trace.go:171","msg":"trace[19288650] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"173.401185ms","start":"2026-06-26T04:28:46.733596Z","end":"2026-06-26T04:28:46.906997Z","steps":["trace[19288650] 'process raft request' (duration: 172.941884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:46.908507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.462641ms","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-26T04:28:46.908583Z","caller":"traceutil/trace.go:171","msg":"trace[2118047773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26232; }","duration":"168.813213ms","start":"2026-06-26T04:28:46.739752Z","end":"2026-06-26T04:28:46.908565Z","steps":["trace[2118047773] 'agreement among raft nodes before linearized reading' (duration: 167.332478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:47.155022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.20469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-26T04:28:47.155305Z","caller":"traceutil/trace.go:171","msg":"trace[2069354670] transaction","detail":"{read_only:false; response_revision:26235; number_of_response:1; }","duration":"242.872179ms","start":"2026-06-26T04:28:46.912418Z","end":"2026-06-26T04:28:47.15529Z","steps":["trace[2069354670] 'process raft request' (duration: 242.791997ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:47.15542Z","caller":"traceutil/trace.go:171","msg":"trace[898155909] transaction","detail":"{read_only:false; response_revision:26234; number_of_response:1; }","duration":"243.192966ms","start":"2026-06-26T04:28:46.91221Z","end":"2026-06-26T04:28:47.155403Z","steps":["trace[898155909] 'process raft request' (duration: 242.906879ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:47.155445Z","caller":"traceutil/trace.go:171","msg":"trace[2144429068] linearizableReadLoop","detail":"{readStateIndex:27095; appliedIndex:27091; }","duration":"241.335482ms","start":"2026-06-26T04:28:46.914081Z","end":"2026-06-26T04:28:47.155416Z","steps":["trace[2144429068] 'read index received' (duration: 41.671291ms)","trace[2144429068] 'applied index is now lower than readState.Index' (duration: 199.663291ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:47.155532Z","caller":"traceutil/trace.go:171","msg":"trace[1514907589] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"242.698895ms","start":"2026-06-26T04:28:46.912822Z","end":"2026-06-26T04:28:47.155521Z","steps":["trace[1514907589] 'process raft request' (duration: 242.426908ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:47.155256Z","caller":"traceutil/trace.go:171","msg":"trace[1538009089] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"244.066699ms","start":"2026-06-26T04:28:46.911154Z","end":"2026-06-26T04:28:47.155221Z","steps":["trace[1538009089] 'process raft request' (duration: 44.586882ms)","trace[1538009089] 'compare' (duration: 198.719608ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:47.155715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.61998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-06-26T04:28:47.155759Z","caller":"traceutil/trace.go:171","msg":"trace[1096055241] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26236; }","duration":"241.688551ms","start":"2026-06-26T04:28:46.914059Z","end":"2026-06-26T04:28:47.155748Z","steps":["trace[1096055241] 'agreement among raft nodes before linearized reading' (duration: 241.451865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:47.468728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.333265ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-26T04:28:48.057853Z","caller":"traceutil/trace.go:171","msg":"trace[1929480769] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"196.999988ms","start":"2026-06-26T04:28:47.860827Z","end":"2026-06-26T04:28:48.057827Z","steps":["trace[1929480769] 'process raft request' (duration: 196.750501ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:48.287499Z","caller":"traceutil/trace.go:171","msg":"trace[1894031593] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"183.788567ms","start":"2026-06-26T04:28:48.103671Z","end":"2026-06-26T04:28:48.287459Z","steps":["trace[1894031593] 'process raft request' (duration: 96.759086ms)","trace[1894031593] 'compare' (duration: 86.855456ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:48.572388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.240125ms","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-26T04:28:48.572476Z","caller":"traceutil/trace.go:171","msg":"trace[1897750040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26239; }","duration":"181.382368ms","start":"2026-06-26T04:28:48.39107Z","end":"2026-06-26T04:28:48.572453Z","steps":["trace[1897750040] 'range keys from in-memory index tree' (duration: 180.996198ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:28:48.785925Z","caller":"traceutil/trace.go:171","msg":"trace[1259987534] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"168.408674ms","start":"2026-06-26T04:28:48.617469Z","end":"2026-06-26T04:28:48.785878Z","steps":["trace[1259987534] 'process raft request' (duration: 153.592155ms)","trace[1259987534] 'compare' (duration: 14.558103ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:48.979987Z","caller":"traceutil/trace.go:171","msg":"trace[1717461368] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"304.35559ms","start":"2026-06-26T04:28:48.675609Z","end":"2026-06-26T04:28:48.979964Z","steps":["trace[1717461368] 'process raft request' (duration: 242.771477ms)","trace[1717461368] 'compare' (duration: 61.399119ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T04:28:48.980098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:48.675589Z","time spent":"304.450533ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T04:28:48.980112Z","caller":"traceutil/trace.go:171","msg":"trace[572390801] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"304.411112ms","start":"2026-06-26T04:28:48.675668Z","end":"2026-06-26T04:28:48.980079Z","steps":["trace[572390801] 'process raft request' (duration: 304.228627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:48.980262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:48.675663Z","time spent":"304.523734ms","remote":"127.0.0.1:35112","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-26T04:28:48.980639Z","caller":"traceutil/trace.go:171","msg":"trace[387983267] linearizableReadLoop","detail":"{readStateIndex:27104; appliedIndex:27100; }","duration":"295.493116ms","start":"2026-06-26T04:28:48.685136Z","end":"2026-06-26T04:28:48.980629Z","steps":["trace[387983267] 'read index received' (duration: 86.17172ms)","trace[387983267] 'applied index is now lower than readState.Index' (duration: 209.320646ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T04:28:48.98064Z","caller":"traceutil/trace.go:171","msg":"trace[1484524363] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"304.105485ms","start":"2026-06-26T04:28:48.676511Z","end":"2026-06-26T04:28:48.980616Z","steps":["trace[1484524363] 'process raft request' (duration: 303.417258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:48.980734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.600138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-26T04:28:48.980763Z","caller":"traceutil/trace.go:171","msg":"trace[373982221] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26245; }","duration":"295.633009ms","start":"2026-06-26T04:28:48.685119Z","end":"2026-06-26T04:28:48.980752Z","steps":["trace[373982221] 'agreement among raft nodes before linearized reading' (duration: 295.581828ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T04:28:48.980762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T04:28:48.676502Z","time spent":"304.194127ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T04:28:48.980985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.22876ms","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-26T04:28:48.981078Z","caller":"traceutil/trace.go:171","msg":"trace[789678418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26245; }","duration":"121.375573ms","start":"2026-06-26T04:28:48.85968Z","end":"2026-06-26T04:28:48.981056Z","steps":["trace[789678418] 'agreement among raft nodes before linearized reading' (duration: 121.144888ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T04:32:18.821447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25482} {"level":"info","ts":"2026-06-26T04:32:18.979094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25482,"took":"153.911401ms","hash":1037899088,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":36225024,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-26T04:32:18.979149Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1037899088,"revision":25482,"compact-revision":22087} {"level":"info","ts":"2026-06-26T04:33:43.170278Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T04:33:43.176376Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-26T04:33:43.176511Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-26T04:37:18.830135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28424} {"level":"info","ts":"2026-06-26T04:37:18.971329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28424,"took":"137.065032ms","hash":2905047099,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":31678464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-26T04:37:18.971396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2905047099,"revision":28424,"compact-revision":25482} {"level":"info","ts":"2026-06-26T04:42:18.837183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30674} {"level":"info","ts":"2026-06-26T04:42:18.966925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30674,"took":"126.032213ms","hash":2712135656,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":30355456,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-26T04:42:18.967007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2712135656,"revision":30674,"compact-revision":28424} {"level":"info","ts":"2026-06-26T04:47:18.845661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32930} {"level":"info","ts":"2026-06-26T04:47:18.997924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32930,"took":"144.238865ms","hash":1240274919,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-26T04:47:18.997995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1240274919,"revision":32930,"compact-revision":30674} {"level":"info","ts":"2026-06-26T04:52:18.8525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35229} {"level":"info","ts":"2026-06-26T04:52:18.996198Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35229,"took":"139.715424ms","hash":2209092841,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":31662080,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-26T04:52:18.996271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2209092841,"revision":35229,"compact-revision":32930} {"level":"info","ts":"2026-06-26T04:55:10.95367Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-26T04:55:10.958351Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-26T04:55:10.958445Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-26T04:57:18.858925Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37460} {"level":"info","ts":"2026-06-26T04:57:18.982507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37460,"took":"120.18617ms","hash":2781928209,"current-db-size-bytes":58646528,"current-db-size":"59 MB","current-db-size-in-use-bytes":32022528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-26T04:57:18.982578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2781928209,"revision":37460,"compact-revision":35229}