{"level":"warn","ts":"2026-04-27T21:00:47.634143Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T21:00:47.634226Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228: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-04-27T21:00:47.634318Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T21:00:47.634328Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-04-27T21:00:47.634343Z","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-04-27T21:00:47.635004Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]} {"level":"info","ts":"2026-04-27T21:00:47.635084Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228: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-04-27T21:00:47.641093Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.782089ms"} {"level":"info","ts":"2026-04-27T21:00:47.647003Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"} {"level":"info","ts":"2026-04-27T21:00:47.647095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T21:00:47.647121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"} {"level":"info","ts":"2026-04-27T21:00:47.647145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T21:00:47.647161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"} {"level":"info","ts":"2026-04-27T21:00:47.647271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"warn","ts":"2026-04-27T21:00:47.654566Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T21:00:47.65707Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T21:00:47.658951Z","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-04-27T21:00:47.662692Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T21:00:47.663085Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T21:00:47.663212Z","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-04-27T21:00:47.663277Z","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-04-27T21:00:47.66329Z","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-04-27T21:00:47.664134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"info","ts":"2026-04-27T21:00:47.66621Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-04-27T21:00:47.668106Z","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-04-27T21:00:47.668309Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-04-27T21:00:47.668347Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-04-27T21:00:47.668299Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T21:00:47.66836Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T21:00:47.847593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T21:00:47.847662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T21:00:47.847696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"} {"level":"info","ts":"2026-04-27T21:00:47.847719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"} {"level":"info","ts":"2026-04-27T21:00:47.847744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"} {"level":"info","ts":"2026-04-27T21:00:47.847788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"} {"level":"info","ts":"2026-04-27T21:00:47.847819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"} {"level":"info","ts":"2026-04-27T21:00:47.84962Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T21:00:47.849651Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:47.849828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T21:00:47.849882Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T21:00:47.850609Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T21:00:47.85063Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T21:00:47.852769Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"} {"level":"info","ts":"2026-04-27T21:00:47.852961Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T21:00:47.857124Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:47.857201Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:47.857222Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:01:23.609904Z","caller":"traceutil/trace.go:171","msg":"trace[832416781] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"135.602127ms","start":"2026-04-27T21:01:23.474249Z","end":"2026-04-27T21:01:23.609851Z","steps":["trace[832416781] 'process raft request' (duration: 135.456044ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:23.830617Z","caller":"traceutil/trace.go:171","msg":"trace[2009285339] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"130.241877ms","start":"2026-04-27T21:01:23.70035Z","end":"2026-04-27T21:01:23.830592Z","steps":["trace[2009285339] 'read index received' (duration: 129.447943ms)","trace[2009285339] 'applied index is now lower than readState.Index' (duration: 793.054µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:23.83071Z","caller":"traceutil/trace.go:171","msg":"trace[217235329] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"292.595441ms","start":"2026-04-27T21:01:23.538078Z","end":"2026-04-27T21:01:23.830674Z","steps":["trace[217235329] 'process raft request' (duration: 291.98956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:23.830783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.607084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T21:01:23.830862Z","caller":"traceutil/trace.go:171","msg":"trace[69820863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"130.753897ms","start":"2026-04-27T21:01:23.700095Z","end":"2026-04-27T21:01:23.830848Z","steps":["trace[69820863] 'agreement among raft nodes before linearized reading' (duration: 130.614593ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:24.413038Z","caller":"traceutil/trace.go:171","msg":"trace[67122052] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"463.546834ms","start":"2026-04-27T21:01:23.949345Z","end":"2026-04-27T21:01:24.412892Z","steps":["trace[67122052] 'read index received' (duration: 450.676465ms)","trace[67122052] 'applied index is now lower than readState.Index' (duration: 12.868919ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:24.413207Z","caller":"traceutil/trace.go:171","msg":"trace[824636570] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"515.053168ms","start":"2026-04-27T21:01:23.898137Z","end":"2026-04-27T21:01:24.41319Z","steps":["trace[824636570] 'process raft request' (duration: 501.970336ms)","trace[824636570] 'compare' (duration: 12.447541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:01:24.413608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.175785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k97bp\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-27T21:01:24.413659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.305088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T21:01:24.413645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.308263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T21:01:24.413701Z","caller":"traceutil/trace.go:171","msg":"trace[2011619165] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k97bp; range_end:; response_count:1; response_revision:617; }","duration":"318.321559ms","start":"2026-04-27T21:01:24.09536Z","end":"2026-04-27T21:01:24.413681Z","steps":["trace[2011619165] 'agreement among raft nodes before linearized reading' (duration: 318.158776ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:24.413712Z","caller":"traceutil/trace.go:171","msg":"trace[267233254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"464.380409ms","start":"2026-04-27T21:01:23.949317Z","end":"2026-04-27T21:01:24.413698Z","steps":["trace[267233254] 'agreement among raft nodes before linearized reading' (duration: 464.184566ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:24.41375Z","caller":"traceutil/trace.go:171","msg":"trace[85605394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"405.421295ms","start":"2026-04-27T21:01:24.008299Z","end":"2026-04-27T21:01:24.41372Z","steps":["trace[85605394] 'agreement among raft nodes before linearized reading' (duration: 405.13759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:24.413752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:24.095338Z","time spent":"318.39807ms","remote":"127.0.0.1:39746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-k97bp\" "} {"level":"warn","ts":"2026-04-27T21:01:24.413794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:23.949308Z","time spent":"464.478161ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T21:01:24.413809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:24.008242Z","time spent":"405.555557ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:01:24.413814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:23.898115Z","time spent":"515.129941ms","remote":"127.0.0.1:40088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T21:01:45.994798Z","caller":"traceutil/trace.go:171","msg":"trace[223928613] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"212.473382ms","start":"2026-04-27T21:01:45.782292Z","end":"2026-04-27T21:01:45.994765Z","steps":["trace[223928613] 'process raft request' (duration: 212.209937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:58.647626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.9364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:02:58.647791Z","caller":"traceutil/trace.go:171","msg":"trace[634086278] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"200.68258ms","start":"2026-04-27T21:02:58.44706Z","end":"2026-04-27T21:02:58.647742Z","steps":["trace[634086278] 'process raft request' (duration: 44.1989ms)","trace[634086278] 'compare' (duration: 155.727836ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:02:58.64798Z","caller":"traceutil/trace.go:171","msg":"trace[763418223] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"161.99185ms","start":"2026-04-27T21:02:58.485963Z","end":"2026-04-27T21:02:58.647955Z","steps":["trace[763418223] 'read index received' (duration: 5.234695ms)","trace[763418223] 'applied index is now lower than readState.Index' (duration: 156.754565ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:02:58.648138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.161213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:02:58.648206Z","caller":"traceutil/trace.go:171","msg":"trace[1580389102] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1842; }","duration":"162.230274ms","start":"2026-04-27T21:02:58.485956Z","end":"2026-04-27T21:02:58.648186Z","steps":["trace[1580389102] 'agreement among raft nodes before linearized reading' (duration: 162.099892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:58.64851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.136069ms","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-04-27T21:02:58.648568Z","caller":"traceutil/trace.go:171","msg":"trace[804619105] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1842; }","duration":"153.220021ms","start":"2026-04-27T21:02:58.495331Z","end":"2026-04-27T21:02:58.648551Z","steps":["trace[804619105] 'agreement among raft nodes before linearized reading' (duration: 152.879605ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:11.580769Z","caller":"traceutil/trace.go:171","msg":"trace[979446337] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"101.900312ms","start":"2026-04-27T21:03:11.478845Z","end":"2026-04-27T21:03:11.580745Z","steps":["trace[979446337] 'process raft request' (duration: 101.76181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:43.994316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.157186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:03:43.994449Z","caller":"traceutil/trace.go:171","msg":"trace[1146407527] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2224; }","duration":"274.850016ms","start":"2026-04-27T21:03:43.719579Z","end":"2026-04-27T21:03:43.994429Z","steps":["trace[1146407527] 'read index received' (duration: 16.13957ms)","trace[1146407527] 'applied index is now lower than readState.Index' (duration: 258.709256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:03:43.994564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.993619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:03:43.994598Z","caller":"traceutil/trace.go:171","msg":"trace[1472503978] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2144; }","duration":"275.086881ms","start":"2026-04-27T21:03:43.719501Z","end":"2026-04-27T21:03:43.994588Z","steps":["trace[1472503978] 'agreement among raft nodes before linearized reading' (duration: 274.973349ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:43.99463Z","caller":"traceutil/trace.go:171","msg":"trace[725507468] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"298.294213ms","start":"2026-04-27T21:03:43.696299Z","end":"2026-04-27T21:03:43.994593Z","steps":["trace[725507468] 'process raft request' (duration: 158.72564ms)","trace[725507468] 'compare' (duration: 139.003783ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:05:12.066138Z","caller":"traceutil/trace.go:171","msg":"trace[1047468302] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"108.049865ms","start":"2026-04-27T21:05:11.958042Z","end":"2026-04-27T21:05:12.066092Z","steps":["trace[1047468302] 'process raft request' (duration: 107.76248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:05:12.329309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.650514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:05:12.32947Z","caller":"traceutil/trace.go:171","msg":"trace[1160324837] linearizableReadLoop","detail":"{readStateIndex:2926; appliedIndex:2925; }","duration":"211.396372ms","start":"2026-04-27T21:05:12.11804Z","end":"2026-04-27T21:05:12.329436Z","steps":["trace[1160324837] 'read index received' (duration: 30.131µs)","trace[1160324837] 'applied index is now lower than readState.Index' (duration: 211.364891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:05:12.329674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.629557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-27T21:05:12.329717Z","caller":"traceutil/trace.go:171","msg":"trace[1644033733] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2816; }","duration":"211.698499ms","start":"2026-04-27T21:05:12.118005Z","end":"2026-04-27T21:05:12.329703Z","steps":["trace[1644033733] 'agreement among raft nodes before linearized reading' (duration: 211.510794ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:05:12.329836Z","caller":"traceutil/trace.go:171","msg":"trace[2011914392] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"261.096914ms","start":"2026-04-27T21:05:12.068594Z","end":"2026-04-27T21:05:12.329691Z","steps":["trace[2011914392] 'process raft request' (duration: 14.92106ms)","trace[2011914392] 'compare' (duration: 245.47248ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:09:11.151991Z","caller":"traceutil/trace.go:171","msg":"trace[2011416103] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"118.440717ms","start":"2026-04-27T21:09:11.033509Z","end":"2026-04-27T21:09:11.15195Z","steps":["trace[2011416103] 'process raft request' (duration: 118.186762ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:09:11.330452Z","caller":"traceutil/trace.go:171","msg":"trace[1214538933] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"169.665251ms","start":"2026-04-27T21:09:11.160747Z","end":"2026-04-27T21:09:11.330412Z","steps":["trace[1214538933] 'process raft request' (duration: 169.436347ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:09:11.358679Z","caller":"traceutil/trace.go:171","msg":"trace[369848490] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"191.357071ms","start":"2026-04-27T21:09:11.16729Z","end":"2026-04-27T21:09:11.358647Z","steps":["trace[369848490] 'process raft request' (duration: 191.131977ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:09:11.358748Z","caller":"traceutil/trace.go:171","msg":"trace[1237328137] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"136.846614ms","start":"2026-04-27T21:09:11.221869Z","end":"2026-04-27T21:09:11.358716Z","steps":["trace[1237328137] 'process raft request' (duration: 136.745983ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:09:11.359043Z","caller":"traceutil/trace.go:171","msg":"trace[506233479] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"137.677601ms","start":"2026-04-27T21:09:11.221313Z","end":"2026-04-27T21:09:11.35899Z","steps":["trace[506233479] 'process raft request' (duration: 137.246913ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:09:20.983793Z","caller":"traceutil/trace.go:171","msg":"trace[1421273319] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"100.348913ms","start":"2026-04-27T21:09:20.883415Z","end":"2026-04-27T21:09:20.983763Z","steps":["trace[1421273319] 'process raft request' (duration: 31.678375ms)","trace[1421273319] 'compare' (duration: 68.533885ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:09:39.672814Z","caller":"traceutil/trace.go:171","msg":"trace[2022793486] linearizableReadLoop","detail":"{readStateIndex:5734; appliedIndex:5732; }","duration":"139.726889ms","start":"2026-04-27T21:09:39.533053Z","end":"2026-04-27T21:09:39.67278Z","steps":["trace[2022793486] 'read index received' (duration: 21.748522ms)","trace[2022793486] 'applied index is now lower than readState.Index' (duration: 117.977247ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:09:39.672905Z","caller":"traceutil/trace.go:171","msg":"trace[1508529161] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"165.021015ms","start":"2026-04-27T21:09:39.507828Z","end":"2026-04-27T21:09:39.672849Z","steps":["trace[1508529161] 'process raft request' (duration: 142.674563ms)","trace[1508529161] 'compare' (duration: 22.084478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:09:39.673153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.079595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jv2vx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-27T21:09:39.673205Z","caller":"traceutil/trace.go:171","msg":"trace[1035353683] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jv2vx; range_end:; response_count:1; response_revision:5527; }","duration":"140.210747ms","start":"2026-04-27T21:09:39.532973Z","end":"2026-04-27T21:09:39.673184Z","steps":["trace[1035353683] 'agreement among raft nodes before linearized reading' (duration: 140.000413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:09:39.673273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.732869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-zknh2\" ","response":"range_response_count:1 size:4760"} {"level":"info","ts":"2026-04-27T21:09:39.673367Z","caller":"traceutil/trace.go:171","msg":"trace[2050026798] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-cf54cb88c-zknh2; range_end:; response_count:1; response_revision:5527; }","duration":"119.924893ms","start":"2026-04-27T21:09:39.553424Z","end":"2026-04-27T21:09:39.673349Z","steps":["trace[2050026798] 'agreement among raft nodes before linearized reading' (duration: 119.611098ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:09:39.928326Z","caller":"traceutil/trace.go:171","msg":"trace[1749629398] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"108.300919ms","start":"2026-04-27T21:09:39.819986Z","end":"2026-04-27T21:09:39.928286Z","steps":["trace[1749629398] 'process raft request' (duration: 69.37553ms)","trace[1749629398] 'compare' (duration: 38.668594ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:09:39.928359Z","caller":"traceutil/trace.go:171","msg":"trace[1479970858] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"108.204246ms","start":"2026-04-27T21:09:39.820125Z","end":"2026-04-27T21:09:39.928329Z","steps":["trace[1479970858] 'process raft request' (duration: 108.080764ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:10:47.99898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3012} {"level":"info","ts":"2026-04-27T21:10:48.075259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3012,"took":"74.760126ms","hash":1058270224,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":31842304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T21:10:48.075323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1058270224,"revision":3012,"compact-revision":-1} {"level":"info","ts":"2026-04-27T21:15:31.07655Z","caller":"traceutil/trace.go:171","msg":"trace[1651436407] linearizableReadLoop","detail":"{readStateIndex:8497; appliedIndex:8496; }","duration":"311.661605ms","start":"2026-04-27T21:15:30.76484Z","end":"2026-04-27T21:15:31.076502Z","steps":["trace[1651436407] 'read index received' (duration: 311.207246ms)","trace[1651436407] 'applied index is now lower than readState.Index' (duration: 452.379µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:31.076665Z","caller":"traceutil/trace.go:171","msg":"trace[783976642] transaction","detail":"{read_only:false; response_revision:8205; number_of_response:1; }","duration":"350.749765ms","start":"2026-04-27T21:15:30.725848Z","end":"2026-04-27T21:15:31.076598Z","steps":["trace[783976642] 'process raft request' (duration: 350.292776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:31.076864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:30.725786Z","time spent":"350.98439ms","remote":"127.0.0.1:39730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:15:31.076913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.042102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:15:31.077002Z","caller":"traceutil/trace.go:171","msg":"trace[1711760551] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8205; }","duration":"312.214405ms","start":"2026-04-27T21:15:30.764767Z","end":"2026-04-27T21:15:31.076982Z","steps":["trace[1711760551] 'agreement among raft nodes before linearized reading' (duration: 312.004521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:31.077048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:30.76474Z","time spent":"312.298746ms","remote":"127.0.0.1:39670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:15:31.083094Z","caller":"traceutil/trace.go:171","msg":"trace[1557837505] transaction","detail":"{read_only:false; response_revision:8206; number_of_response:1; }","duration":"173.255418ms","start":"2026-04-27T21:15:30.909822Z","end":"2026-04-27T21:15:31.083078Z","steps":["trace[1557837505] 'process raft request' (duration: 173.078645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:31.083341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.774043ms","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-04-27T21:15:31.083375Z","caller":"traceutil/trace.go:171","msg":"trace[663112354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8207; }","duration":"291.850935ms","start":"2026-04-27T21:15:30.791515Z","end":"2026-04-27T21:15:31.083366Z","steps":["trace[663112354] 'agreement among raft nodes before linearized reading' (duration: 291.697651ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:31.083396Z","caller":"traceutil/trace.go:171","msg":"trace[2073943242] transaction","detail":"{read_only:false; response_revision:8207; number_of_response:1; }","duration":"134.748259ms","start":"2026-04-27T21:15:30.948592Z","end":"2026-04-27T21:15:31.08334Z","steps":["trace[2073943242] 'process raft request' (duration: 134.446183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:31.083441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.536869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-27T21:15:31.08355Z","caller":"traceutil/trace.go:171","msg":"trace[1703299977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8207; }","duration":"222.712392ms","start":"2026-04-27T21:15:30.860813Z","end":"2026-04-27T21:15:31.083525Z","steps":["trace[1703299977] 'agreement among raft nodes before linearized reading' (duration: 222.507588ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:42.906217Z","caller":"traceutil/trace.go:171","msg":"trace[97975014] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"263.487455ms","start":"2026-04-27T21:15:42.642691Z","end":"2026-04-27T21:15:42.906178Z","steps":["trace[97975014] 'process raft request' (duration: 263.27754ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:43.440936Z","caller":"traceutil/trace.go:171","msg":"trace[1365908310] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"794.799114ms","start":"2026-04-27T21:15:42.646097Z","end":"2026-04-27T21:15:43.440896Z","steps":["trace[1365908310] 'process raft request' (duration: 699.080435ms)","trace[1365908310] 'compare' (duration: 95.366432ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:43.440935Z","caller":"traceutil/trace.go:171","msg":"trace[1235099942] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"794.0131ms","start":"2026-04-27T21:15:42.646898Z","end":"2026-04-27T21:15:43.440911Z","steps":["trace[1235099942] 'process raft request' (duration: 793.892616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:43.441177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:42.646084Z","time spent":"795.000048ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:15:43.441237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:42.646885Z","time spent":"794.247035ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:15:43.482651Z","caller":"traceutil/trace.go:171","msg":"trace[1397159039] linearizableReadLoop","detail":"{readStateIndex:8568; appliedIndex:8564; }","duration":"346.543965ms","start":"2026-04-27T21:15:43.13607Z","end":"2026-04-27T21:15:43.482614Z","steps":["trace[1397159039] 'read index received' (duration: 209.223489ms)","trace[1397159039] 'applied index is now lower than readState.Index' (duration: 137.319476ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:43.482678Z","caller":"traceutil/trace.go:171","msg":"trace[1928236678] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"356.89187ms","start":"2026-04-27T21:15:43.125753Z","end":"2026-04-27T21:15:43.482645Z","steps":["trace[1928236678] 'process raft request' (duration: 356.689266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:43.48285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:43.125725Z","time spent":"357.024003ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:15:43.48294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.859663ms","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-04-27T21:15:43.483003Z","caller":"traceutil/trace.go:171","msg":"trace[287100468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8272; }","duration":"346.978095ms","start":"2026-04-27T21:15:43.136011Z","end":"2026-04-27T21:15:43.48299Z","steps":["trace[287100468] 'agreement among raft nodes before linearized reading' (duration: 346.75878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:43.483039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:43.135994Z","time spent":"347.034886ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T21:15:43.483106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.195221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T21:15:43.48337Z","caller":"traceutil/trace.go:171","msg":"trace[70396940] transaction","detail":"{read_only:false; response_revision:8272; number_of_response:1; }","duration":"349.238237ms","start":"2026-04-27T21:15:43.1341Z","end":"2026-04-27T21:15:43.483338Z","steps":["trace[70396940] 'process raft request' (duration: 348.468982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:43.483548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:43.134055Z","time spent":"349.3912ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:15:43.483717Z","caller":"traceutil/trace.go:171","msg":"trace[1426884981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8272; }","duration":"345.399046ms","start":"2026-04-27T21:15:43.137792Z","end":"2026-04-27T21:15:43.483191Z","steps":["trace[1426884981] 'agreement among raft nodes before linearized reading' (duration: 344.990397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:43.483799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:43.13778Z","time spent":"345.998726ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T21:15:45.785764Z","caller":"traceutil/trace.go:171","msg":"trace[574673913] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"160.160604ms","start":"2026-04-27T21:15:45.625571Z","end":"2026-04-27T21:15:45.785731Z","steps":["trace[574673913] 'process raft request' (duration: 159.94317ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:48.005442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6119} {"level":"info","ts":"2026-04-27T21:15:48.095405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6119,"took":"87.812451ms","hash":4024545752,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":23216128,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-27T21:15:48.095492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4024545752,"revision":6119,"compact-revision":3012} {"level":"info","ts":"2026-04-27T21:16:25.796751Z","caller":"traceutil/trace.go:171","msg":"trace[721694821] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"113.92861ms","start":"2026-04-27T21:16:25.682764Z","end":"2026-04-27T21:16:25.796692Z","steps":["trace[721694821] 'process raft request' (duration: 113.751357ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:16:25.797093Z","caller":"traceutil/trace.go:171","msg":"trace[1569576665] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"114.680944ms","start":"2026-04-27T21:16:25.682241Z","end":"2026-04-27T21:16:25.796922Z","steps":["trace[1569576665] 'process raft request' (duration: 114.127684ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:16:26.804546Z","caller":"traceutil/trace.go:171","msg":"trace[1040942904] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"270.328752ms","start":"2026-04-27T21:16:26.534186Z","end":"2026-04-27T21:16:26.804514Z","steps":["trace[1040942904] 'process raft request' (duration: 270.022975ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:17:14.09213Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247b.wal"} {"level":"info","ts":"2026-04-27T21:17:42.473539Z","caller":"traceutil/trace.go:171","msg":"trace[186332317] transaction","detail":"{read_only:false; response_revision:9348; number_of_response:1; }","duration":"115.859688ms","start":"2026-04-27T21:17:42.357638Z","end":"2026-04-27T21:17:42.473498Z","steps":["trace[186332317] 'process raft request' (duration: 86.218168ms)","trace[186332317] 'compare' (duration: 29.363625ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:17:42.473922Z","caller":"traceutil/trace.go:171","msg":"trace[589604745] transaction","detail":"{read_only:false; response_revision:9349; number_of_response:1; }","duration":"115.129733ms","start":"2026-04-27T21:17:42.358743Z","end":"2026-04-27T21:17:42.473873Z","steps":["trace[589604745] 'process raft request' (duration: 114.686455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:24.723302Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:18:24.729126Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-27T21:18:24.729244Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-27T21:20:48.013838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8302} {"level":"info","ts":"2026-04-27T21:20:48.107017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8302,"took":"90.795035ms","hash":4189713430,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":25350144,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-27T21:20:48.107099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4189713430,"revision":8302,"compact-revision":6119} {"level":"info","ts":"2026-04-27T21:21:08.841697Z","caller":"traceutil/trace.go:171","msg":"trace[1403959070] transaction","detail":"{read_only:false; response_revision:11098; number_of_response:1; }","duration":"101.07393ms","start":"2026-04-27T21:21:08.740537Z","end":"2026-04-27T21:21:08.841611Z","steps":["trace[1403959070] 'process raft request' (duration: 100.609201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:21:27.287749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.889256ms","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:7"} {"level":"info","ts":"2026-04-27T21:21:27.288386Z","caller":"traceutil/trace.go:171","msg":"trace[673088280] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:11201; }","duration":"117.596949ms","start":"2026-04-27T21:21:27.170765Z","end":"2026-04-27T21:21:27.288362Z","steps":["trace[673088280] 'count revisions from in-memory index tree' (duration: 116.781954ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:25:48.020459Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10799} {"level":"info","ts":"2026-04-27T21:25:48.107812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10799,"took":"84.78479ms","hash":3691385380,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":27680768,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-27T21:25:48.107874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3691385380,"revision":10799,"compact-revision":8302} {"level":"info","ts":"2026-04-27T21:27:14.785182Z","caller":"traceutil/trace.go:171","msg":"trace[898069333] transaction","detail":"{read_only:false; response_revision:13581; number_of_response:1; }","duration":"130.010567ms","start":"2026-04-27T21:27:14.655125Z","end":"2026-04-27T21:27:14.785135Z","steps":["trace[898069333] 'process raft request' (duration: 129.774093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:27:53.826466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.319424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:594793"} {"level":"info","ts":"2026-04-27T21:27:53.826632Z","caller":"traceutil/trace.go:171","msg":"trace[159297763] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:13802; }","duration":"138.502167ms","start":"2026-04-27T21:27:53.688095Z","end":"2026-04-27T21:27:53.826597Z","steps":["trace[159297763] 'range keys from in-memory index tree' (duration: 135.763445ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:27:54.018435Z","caller":"traceutil/trace.go:171","msg":"trace[1475194944] transaction","detail":"{read_only:false; response_revision:13803; number_of_response:1; }","duration":"183.355898ms","start":"2026-04-27T21:27:53.835042Z","end":"2026-04-27T21:27:54.018398Z","steps":["trace[1475194944] 'process raft request' (duration: 183.090913ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:27:54.0408Z","caller":"traceutil/trace.go:171","msg":"trace[1824988846] transaction","detail":"{read_only:false; response_revision:13804; number_of_response:1; }","duration":"205.594814ms","start":"2026-04-27T21:27:53.83518Z","end":"2026-04-27T21:27:54.040775Z","steps":["trace[1824988846] 'process raft request' (duration: 205.277468ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:27:54.040972Z","caller":"traceutil/trace.go:171","msg":"trace[2012640167] linearizableReadLoop","detail":"{readStateIndex:14283; appliedIndex:14280; }","duration":"184.105171ms","start":"2026-04-27T21:27:53.856835Z","end":"2026-04-27T21:27:54.04094Z","steps":["trace[2012640167] 'read index received' (duration: 161.234513ms)","trace[2012640167] 'applied index is now lower than readState.Index' (duration: 22.868618ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:27:54.041138Z","caller":"traceutil/trace.go:171","msg":"trace[165693756] transaction","detail":"{read_only:false; response_revision:13805; number_of_response:1; }","duration":"201.60522ms","start":"2026-04-27T21:27:53.839524Z","end":"2026-04-27T21:27:54.041129Z","steps":["trace[165693756] 'process raft request' (duration: 201.163682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:27:54.04122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.369956ms","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-04-27T21:27:54.04127Z","caller":"traceutil/trace.go:171","msg":"trace[716647798] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13805; }","duration":"184.485448ms","start":"2026-04-27T21:27:53.856769Z","end":"2026-04-27T21:27:54.041254Z","steps":["trace[716647798] 'agreement among raft nodes before linearized reading' (duration: 184.268954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:28:03.272836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.339682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:28:03.272955Z","caller":"traceutil/trace.go:171","msg":"trace[1797258553] linearizableReadLoop","detail":"{readStateIndex:14453; appliedIndex:14452; }","duration":"127.901119ms","start":"2026-04-27T21:28:03.145036Z","end":"2026-04-27T21:28:03.272937Z","steps":["trace[1797258553] 'read index received' (duration: 19.378134ms)","trace[1797258553] 'applied index is now lower than readState.Index' (duration: 108.521665ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:28:03.273079Z","caller":"traceutil/trace.go:171","msg":"trace[385646026] transaction","detail":"{read_only:false; response_revision:13972; number_of_response:1; }","duration":"225.871464ms","start":"2026-04-27T21:28:03.047166Z","end":"2026-04-27T21:28:03.273037Z","steps":["trace[385646026] 'process raft request' (duration: 117.215547ms)","trace[385646026] 'compare' (duration: 108.175559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:28:03.273146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.124273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T21:28:03.273181Z","caller":"traceutil/trace.go:171","msg":"trace[241258648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13972; }","duration":"128.202515ms","start":"2026-04-27T21:28:03.144966Z","end":"2026-04-27T21:28:03.273168Z","steps":["trace[241258648] 'agreement among raft nodes before linearized reading' (duration: 128.019581ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:29:32.736703Z","caller":"traceutil/trace.go:171","msg":"trace[583756707] transaction","detail":"{read_only:false; response_revision:15004; number_of_response:1; }","duration":"163.501335ms","start":"2026-04-27T21:29:32.573166Z","end":"2026-04-27T21:29:32.736667Z","steps":["trace[583756707] 'process raft request' (duration: 108.556435ms)","trace[583756707] 'compare' (duration: 54.638815ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:29:32.762303Z","caller":"traceutil/trace.go:171","msg":"trace[328513559] transaction","detail":"{read_only:false; response_revision:15005; number_of_response:1; }","duration":"188.531535ms","start":"2026-04-27T21:29:32.573721Z","end":"2026-04-27T21:29:32.762253Z","steps":["trace[328513559] 'process raft request' (duration: 188.262069ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:48.027916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12970} {"level":"info","ts":"2026-04-27T21:30:48.106185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12970,"took":"74.51034ms","hash":1486177824,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-27T21:30:48.106239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1486177824,"revision":12970,"compact-revision":10799} {"level":"info","ts":"2026-04-27T21:35:20.429512Z","caller":"traceutil/trace.go:171","msg":"trace[1232530947] linearizableReadLoop","detail":"{readStateIndex:18219; appliedIndex:18218; }","duration":"120.576719ms","start":"2026-04-27T21:35:20.308909Z","end":"2026-04-27T21:35:20.429486Z","steps":["trace[1232530947] 'read index received' (duration: 120.389465ms)","trace[1232530947] 'applied index is now lower than readState.Index' (duration: 186.033µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:35:20.429646Z","caller":"traceutil/trace.go:171","msg":"trace[1557195570] transaction","detail":"{read_only:false; response_revision:17622; number_of_response:1; }","duration":"122.580677ms","start":"2026-04-27T21:35:20.307036Z","end":"2026-04-27T21:35:20.429616Z","steps":["trace[1557195570] 'process raft request' (duration: 122.248301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:35:20.429887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.944955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9610"} {"level":"info","ts":"2026-04-27T21:35:20.429982Z","caller":"traceutil/trace.go:171","msg":"trace[2016089580] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:17622; }","duration":"121.072927ms","start":"2026-04-27T21:35:20.308887Z","end":"2026-04-27T21:35:20.42996Z","steps":["trace[2016089580] 'agreement among raft nodes before linearized reading' (duration: 120.772162ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:20.430769Z","caller":"traceutil/trace.go:171","msg":"trace[990123637] transaction","detail":"{read_only:false; response_revision:17623; number_of_response:1; }","duration":"121.086318ms","start":"2026-04-27T21:35:20.309656Z","end":"2026-04-27T21:35:20.430742Z","steps":["trace[990123637] 'process raft request' (duration: 120.660499ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:20.430771Z","caller":"traceutil/trace.go:171","msg":"trace[561846486] transaction","detail":"{read_only:false; response_revision:17624; number_of_response:1; }","duration":"108.084636ms","start":"2026-04-27T21:35:20.322633Z","end":"2026-04-27T21:35:20.430718Z","steps":["trace[561846486] 'process raft request' (duration: 107.792329ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:26.424919Z","caller":"traceutil/trace.go:171","msg":"trace[1603420254] linearizableReadLoop","detail":"{readStateIndex:18274; appliedIndex:18273; }","duration":"141.711643ms","start":"2026-04-27T21:35:26.283158Z","end":"2026-04-27T21:35:26.424869Z","steps":["trace[1603420254] 'read index received' (duration: 97.523626ms)","trace[1603420254] 'applied index is now lower than readState.Index' (duration: 44.186797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:35:26.425177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.963348ms","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-04-27T21:35:26.425268Z","caller":"traceutil/trace.go:171","msg":"trace[1532686700] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:17675; }","duration":"142.147641ms","start":"2026-04-27T21:35:26.283109Z","end":"2026-04-27T21:35:26.425257Z","steps":["trace[1532686700] 'agreement among raft nodes before linearized reading' (duration: 141.964748ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:26.790491Z","caller":"traceutil/trace.go:171","msg":"trace[1103730790] transaction","detail":"{read_only:false; response_revision:17679; number_of_response:1; }","duration":"155.258638ms","start":"2026-04-27T21:35:26.635193Z","end":"2026-04-27T21:35:26.790452Z","steps":["trace[1103730790] 'process raft request' (duration: 154.942373ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:26.790714Z","caller":"traceutil/trace.go:171","msg":"trace[907944656] linearizableReadLoop","detail":"{readStateIndex:18278; appliedIndex:18277; }","duration":"141.410528ms","start":"2026-04-27T21:35:26.649252Z","end":"2026-04-27T21:35:26.790663Z","steps":["trace[907944656] 'read index received' (duration: 140.899849ms)","trace[907944656] 'applied index is now lower than readState.Index' (duration: 235.954µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:35:26.791374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.842587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-27T21:35:26.791555Z","caller":"traceutil/trace.go:171","msg":"trace[49715520] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17679; }","duration":"142.339836ms","start":"2026-04-27T21:35:26.649195Z","end":"2026-04-27T21:35:26.791535Z","steps":["trace[49715520] 'agreement among raft nodes before linearized reading' (duration: 141.347027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:35:26.791627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.205769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-27T21:35:26.791684Z","caller":"traceutil/trace.go:171","msg":"trace[1577971335] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17679; }","duration":"130.338271ms","start":"2026-04-27T21:35:26.661329Z","end":"2026-04-27T21:35:26.791667Z","steps":["trace[1577971335] 'agreement among raft nodes before linearized reading' (duration: 130.160958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:35:27.105423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.78602ms","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-04-27T21:35:27.105609Z","caller":"traceutil/trace.go:171","msg":"trace[461777027] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17681; }","duration":"241.033284ms","start":"2026-04-27T21:35:26.864548Z","end":"2026-04-27T21:35:27.105581Z","steps":["trace[461777027] 'range keys from in-memory index tree' (duration: 240.609917ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:27.118338Z","caller":"traceutil/trace.go:171","msg":"trace[33731572] transaction","detail":"{read_only:false; response_revision:17682; number_of_response:1; }","duration":"235.559382ms","start":"2026-04-27T21:35:26.88275Z","end":"2026-04-27T21:35:27.11831Z","steps":["trace[33731572] 'process raft request' (duration: 235.326067ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:27.343739Z","caller":"traceutil/trace.go:171","msg":"trace[2016262351] linearizableReadLoop","detail":"{readStateIndex:18282; appliedIndex:18281; }","duration":"225.417452ms","start":"2026-04-27T21:35:27.118282Z","end":"2026-04-27T21:35:27.343699Z","steps":["trace[2016262351] 'read index received' (duration: 162.378941ms)","trace[2016262351] 'applied index is now lower than readState.Index' (duration: 63.036801ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:35:27.343874Z","caller":"traceutil/trace.go:171","msg":"trace[1523709457] transaction","detail":"{read_only:false; response_revision:17683; number_of_response:1; }","duration":"284.355646ms","start":"2026-04-27T21:35:27.05943Z","end":"2026-04-27T21:35:27.343786Z","steps":["trace[1523709457] 'process raft request' (duration: 221.276714ms)","trace[1523709457] 'compare' (duration: 62.839107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:35:27.344001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.542212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:35:27.344119Z","caller":"traceutil/trace.go:171","msg":"trace[160955726] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17683; }","duration":"234.722475ms","start":"2026-04-27T21:35:27.109383Z","end":"2026-04-27T21:35:27.344106Z","steps":["trace[160955726] 'agreement among raft nodes before linearized reading' (duration: 234.537302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:35:27.34524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.187244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942857"} {"level":"info","ts":"2026-04-27T21:35:27.345328Z","caller":"traceutil/trace.go:171","msg":"trace[1402444803] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17683; }","duration":"220.320646ms","start":"2026-04-27T21:35:27.124988Z","end":"2026-04-27T21:35:27.345308Z","steps":["trace[1402444803] 'agreement among raft nodes before linearized reading' (duration: 219.028162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:35:27.346097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.02891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:132 size:1635748"} {"level":"info","ts":"2026-04-27T21:35:27.346162Z","caller":"traceutil/trace.go:171","msg":"trace[1749320480] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:132; response_revision:17683; }","duration":"135.154382ms","start":"2026-04-27T21:35:27.210989Z","end":"2026-04-27T21:35:27.346144Z","steps":["trace[1749320480] 'agreement among raft nodes before linearized reading' (duration: 133.032192ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:48.034452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15488} {"level":"info","ts":"2026-04-27T21:35:48.131704Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15488,"took":"93.619623ms","hash":3165123702,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":33652736,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-27T21:35:48.131808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3165123702,"revision":15488,"compact-revision":12970} {"level":"info","ts":"2026-04-27T21:38:47.893402Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:38:47.900004Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-27T21:38:47.900122Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-27T21:40:26.680388Z","caller":"traceutil/trace.go:171","msg":"trace[1701830368] transaction","detail":"{read_only:false; response_revision:20012; number_of_response:1; }","duration":"141.24588ms","start":"2026-04-27T21:40:26.53911Z","end":"2026-04-27T21:40:26.680356Z","steps":["trace[1701830368] 'process raft request' (duration: 60.631152ms)","trace[1701830368] 'compare' (duration: 80.482135ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:40:26.76744Z","caller":"traceutil/trace.go:171","msg":"trace[127998822] linearizableReadLoop","detail":"{readStateIndex:20687; appliedIndex:20686; }","duration":"141.960674ms","start":"2026-04-27T21:40:26.625453Z","end":"2026-04-27T21:40:26.767414Z","steps":["trace[127998822] 'read index received' (duration: 141.815981ms)","trace[127998822] 'applied index is now lower than readState.Index' (duration: 143.423µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:40:26.767573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.108227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:40:26.76761Z","caller":"traceutil/trace.go:171","msg":"trace[2047865837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20013; }","duration":"142.207728ms","start":"2026-04-27T21:40:26.625389Z","end":"2026-04-27T21:40:26.767597Z","steps":["trace[2047865837] 'agreement among raft nodes before linearized reading' (duration: 142.126557ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:40:26.768238Z","caller":"traceutil/trace.go:171","msg":"trace[1035673251] transaction","detail":"{read_only:false; response_revision:20013; number_of_response:1; }","duration":"228.253697ms","start":"2026-04-27T21:40:26.53922Z","end":"2026-04-27T21:40:26.767473Z","steps":["trace[1035673251] 'process raft request' (duration: 228.044353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:40:27.067913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.470159ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-27T21:40:48.042357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18044} {"level":"info","ts":"2026-04-27T21:40:48.15671Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18044,"took":"109.944644ms","hash":1045670785,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":29360128,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-27T21:40:48.156817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1045670785,"revision":18044,"compact-revision":15488} {"level":"info","ts":"2026-04-27T21:44:07.912919Z","caller":"traceutil/trace.go:171","msg":"trace[618430838] transaction","detail":"{read_only:false; response_revision:22335; number_of_response:1; }","duration":"104.779448ms","start":"2026-04-27T21:44:07.808116Z","end":"2026-04-27T21:44:07.912895Z","steps":["trace[618430838] 'process raft request' (duration: 103.332001ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:44:08.041651Z","caller":"traceutil/trace.go:171","msg":"trace[983483471] linearizableReadLoop","detail":"{readStateIndex:23084; appliedIndex:23083; }","duration":"116.508158ms","start":"2026-04-27T21:44:07.92512Z","end":"2026-04-27T21:44:08.041628Z","steps":["trace[983483471] 'read index received' (duration: 116.484387ms)","trace[983483471] 'applied index is now lower than readState.Index' (duration: 22.901µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:44:08.041702Z","caller":"traceutil/trace.go:171","msg":"trace[1575800028] transaction","detail":"{read_only:false; response_revision:22336; number_of_response:1; }","duration":"167.538622ms","start":"2026-04-27T21:44:07.874128Z","end":"2026-04-27T21:44:08.041666Z","steps":["trace[1575800028] 'process raft request' (duration: 167.335389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:44:08.04187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.727121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-27T21:44:08.041917Z","caller":"traceutil/trace.go:171","msg":"trace[1491002710] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22336; }","duration":"116.791153ms","start":"2026-04-27T21:44:07.925113Z","end":"2026-04-27T21:44:08.041904Z","steps":["trace[1491002710] 'agreement among raft nodes before linearized reading' (duration: 116.62534ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:45:48.049675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20127} {"level":"info","ts":"2026-04-27T21:45:48.166064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20127,"took":"112.550874ms","hash":2722784457,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":42057728,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-27T21:45:48.166144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2722784457,"revision":20127,"compact-revision":18044} {"level":"info","ts":"2026-04-27T21:47:31.297125Z","caller":"traceutil/trace.go:171","msg":"trace[1924962740] transaction","detail":"{read_only:false; response_revision:24422; number_of_response:1; }","duration":"103.175359ms","start":"2026-04-27T21:47:31.193924Z","end":"2026-04-27T21:47:31.2971Z","steps":["trace[1924962740] 'process raft request' (duration: 102.583297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:31.706542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.594844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:31.706951Z","caller":"traceutil/trace.go:171","msg":"trace[1479002659] transaction","detail":"{read_only:false; response_revision:24427; number_of_response:1; }","duration":"265.539103ms","start":"2026-04-27T21:47:31.441388Z","end":"2026-04-27T21:47:31.706927Z","steps":["trace[1479002659] 'process raft request' (duration: 265.474242ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:31.707233Z","caller":"traceutil/trace.go:171","msg":"trace[858315803] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"368.389956ms","start":"2026-04-27T21:47:31.338827Z","end":"2026-04-27T21:47:31.707217Z","steps":["trace[858315803] 'process raft request' (duration: 367.925058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:31.707307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:31.338801Z","time spent":"368.467478ms","remote":"127.0.0.1:39832","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-04-27T21:47:31.707345Z","caller":"traceutil/trace.go:171","msg":"trace[521128314] linearizableReadLoop","detail":"{readStateIndex:25228; appliedIndex:25223; }","duration":"288.32107ms","start":"2026-04-27T21:47:31.41894Z","end":"2026-04-27T21:47:31.707261Z","steps":["trace[521128314] 'read index received' (duration: 17.986686ms)","trace[521128314] 'applied index is now lower than readState.Index' (duration: 270.327253ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:31.707472Z","caller":"traceutil/trace.go:171","msg":"trace[904491895] transaction","detail":"{read_only:false; response_revision:24426; number_of_response:1; }","duration":"348.340562ms","start":"2026-04-27T21:47:31.359073Z","end":"2026-04-27T21:47:31.707414Z","steps":["trace[904491895] 'process raft request' (duration: 347.72325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:31.707587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.649435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:31.707638Z","caller":"traceutil/trace.go:171","msg":"trace[1816417448] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24427; }","duration":"288.755997ms","start":"2026-04-27T21:47:31.418866Z","end":"2026-04-27T21:47:31.707622Z","steps":["trace[1816417448] 'agreement among raft nodes before linearized reading' (duration: 288.605955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:31.70764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.335799ms","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-04-27T21:47:31.707331Z","caller":"traceutil/trace.go:171","msg":"trace[960168751] transaction","detail":"{read_only:false; response_revision:24423; number_of_response:1; }","duration":"404.608893ms","start":"2026-04-27T21:47:31.302684Z","end":"2026-04-27T21:47:31.707293Z","steps":["trace[960168751] 'process raft request' (duration: 267.128273ms)","trace[960168751] 'compare' (duration: 136.203986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:31.707813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:31.359041Z","time spent":"348.555846ms","remote":"127.0.0.1:39832","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-04-27T21:47:31.707867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:31.302654Z","time spent":"405.170013ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:31.707732Z","caller":"traceutil/trace.go:171","msg":"trace[276673087] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:24427; }","duration":"273.494913ms","start":"2026-04-27T21:47:31.434217Z","end":"2026-04-27T21:47:31.707712Z","steps":["trace[276673087] 'agreement among raft nodes before linearized reading' (duration: 273.33123ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:31.708261Z","caller":"traceutil/trace.go:171","msg":"trace[426886493] transaction","detail":"{read_only:false; response_revision:24424; number_of_response:1; }","duration":"394.655557ms","start":"2026-04-27T21:47:31.312383Z","end":"2026-04-27T21:47:31.707038Z","steps":["trace[426886493] 'process raft request' (duration: 394.28631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:31.709093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:31.312359Z","time spent":"395.954922ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:31.988107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.329202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:31.988203Z","caller":"traceutil/trace.go:171","msg":"trace[661038027] linearizableReadLoop","detail":"{readStateIndex:25230; appliedIndex:25229; }","duration":"272.085535ms","start":"2026-04-27T21:47:31.716103Z","end":"2026-04-27T21:47:31.988188Z","steps":["trace[661038027] 'read index received' (duration: 130.528479ms)","trace[661038027] 'applied index is now lower than readState.Index' (duration: 141.555856ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:31.98829Z","caller":"traceutil/trace.go:171","msg":"trace[234764112] transaction","detail":"{read_only:false; response_revision:24428; number_of_response:1; }","duration":"273.241837ms","start":"2026-04-27T21:47:31.715038Z","end":"2026-04-27T21:47:31.98828Z","steps":["trace[234764112] 'process raft request' (duration: 131.64497ms)","trace[234764112] 'compare' (duration: 140.983476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:31.98852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.841234ms","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-04-27T21:47:31.988625Z","caller":"traceutil/trace.go:171","msg":"trace[515014530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24428; }","duration":"217.002616ms","start":"2026-04-27T21:47:31.771599Z","end":"2026-04-27T21:47:31.988602Z","steps":["trace[515014530] 'agreement among raft nodes before linearized reading' (duration: 216.735191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:31.988926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.141399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-04-27T21:47:31.988939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.652121ms","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-04-27T21:47:31.988981Z","caller":"traceutil/trace.go:171","msg":"trace[1367045575] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24428; }","duration":"103.24307ms","start":"2026-04-27T21:47:31.885721Z","end":"2026-04-27T21:47:31.988964Z","steps":["trace[1367045575] 'agreement among raft nodes before linearized reading' (duration: 103.034657ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:31.988993Z","caller":"traceutil/trace.go:171","msg":"trace[2021966679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24428; }","duration":"215.732423ms","start":"2026-04-27T21:47:31.773248Z","end":"2026-04-27T21:47:31.98898Z","steps":["trace[2021966679] 'agreement among raft nodes before linearized reading' (duration: 215.57287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:31.988991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.84704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-27T21:47:31.989091Z","caller":"traceutil/trace.go:171","msg":"trace[354888473] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24428; }","duration":"272.980943ms","start":"2026-04-27T21:47:31.716087Z","end":"2026-04-27T21:47:31.989068Z","steps":["trace[354888473] 'agreement among raft nodes before linearized reading' (duration: 272.711458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.601935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:41.216747Z","time spent":"385.180113ms","remote":"127.0.0.1:39592","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T21:47:41.885711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.045985ms","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-04-27T21:47:41.885846Z","caller":"traceutil/trace.go:171","msg":"trace[1862795884] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24498; }","duration":"145.194238ms","start":"2026-04-27T21:47:41.740629Z","end":"2026-04-27T21:47:41.885824Z","steps":["trace[1862795884] 'range keys from in-memory index tree' (duration: 145.004695ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.892128Z","caller":"traceutil/trace.go:171","msg":"trace[60082962] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"529.024247ms","start":"2026-04-27T21:47:41.363078Z","end":"2026-04-27T21:47:41.892102Z","steps":["trace[60082962] 'process raft request' (duration: 528.835224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.892283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:41.363046Z","time spent":"529.15127ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:41.900937Z","caller":"traceutil/trace.go:171","msg":"trace[2095339790] linearizableReadLoop","detail":"{readStateIndex:25304; appliedIndex:25302; }","duration":"294.130308ms","start":"2026-04-27T21:47:41.60679Z","end":"2026-04-27T21:47:41.90092Z","steps":["trace[2095339790] 'read index received' (duration: 285.124ms)","trace[2095339790] 'applied index is now lower than readState.Index' (duration: 9.005428ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:41.901013Z","caller":"traceutil/trace.go:171","msg":"trace[1919840134] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"141.813605ms","start":"2026-04-27T21:47:41.759192Z","end":"2026-04-27T21:47:41.901006Z","steps":["trace[1919840134] 'process raft request' (duration: 141.699283ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.901021Z","caller":"traceutil/trace.go:171","msg":"trace[1876657708] transaction","detail":"{read_only:false; response_revision:24501; number_of_response:1; }","duration":"154.141936ms","start":"2026-04-27T21:47:41.746854Z","end":"2026-04-27T21:47:41.900996Z","steps":["trace[1876657708] 'process raft request' (duration: 153.997914ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.901007Z","caller":"traceutil/trace.go:171","msg":"trace[2085135190] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"297.738966ms","start":"2026-04-27T21:47:41.603237Z","end":"2026-04-27T21:47:41.900976Z","steps":["trace[2085135190] 'process raft request' (duration: 297.493131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.901201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.751424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:41.901245Z","caller":"traceutil/trace.go:171","msg":"trace[492388623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24502; }","duration":"275.850475ms","start":"2026-04-27T21:47:41.625384Z","end":"2026-04-27T21:47:41.901234Z","steps":["trace[492388623] 'agreement among raft nodes before linearized reading' (duration: 275.769224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.901443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.643268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:41.901492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.829162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:41.901497Z","caller":"traceutil/trace.go:171","msg":"trace[1218831516] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24502; }","duration":"294.736549ms","start":"2026-04-27T21:47:41.606746Z","end":"2026-04-27T21:47:41.901483Z","steps":["trace[1218831516] 'agreement among raft nodes before linearized reading' (duration: 294.652347ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.901525Z","caller":"traceutil/trace.go:171","msg":"trace[1138780182] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24502; }","duration":"167.909104ms","start":"2026-04-27T21:47:41.733605Z","end":"2026-04-27T21:47:41.901514Z","steps":["trace[1138780182] 'agreement among raft nodes before linearized reading' (duration: 167.855543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.901553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.987658ms","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-04-27T21:47:41.901606Z","caller":"traceutil/trace.go:171","msg":"trace[990741614] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:24502; }","duration":"269.075679ms","start":"2026-04-27T21:47:41.632513Z","end":"2026-04-27T21:47:41.901589Z","steps":["trace[990741614] 'agreement among raft nodes before linearized reading' (duration: 268.963367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.901711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.910937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-27T21:47:41.901751Z","caller":"traceutil/trace.go:171","msg":"trace[1587487888] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24502; }","duration":"135.013188ms","start":"2026-04-27T21:47:41.766725Z","end":"2026-04-27T21:47:41.901738Z","steps":["trace[1587487888] 'agreement among raft nodes before linearized reading' (duration: 134.933617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.901627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.336438ms","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-04-27T21:47:41.901872Z","caller":"traceutil/trace.go:171","msg":"trace[781295349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24502; }","duration":"116.606985ms","start":"2026-04-27T21:47:41.78525Z","end":"2026-04-27T21:47:41.901857Z","steps":["trace[781295349] 'agreement among raft nodes before linearized reading' (duration: 116.284738ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:42.032344Z","caller":"traceutil/trace.go:171","msg":"trace[1091409891] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"122.921823ms","start":"2026-04-27T21:47:41.909401Z","end":"2026-04-27T21:47:42.032323Z","steps":["trace[1091409891] 'process raft request' (duration: 119.858976ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:42.083059Z","caller":"traceutil/trace.go:171","msg":"trace[175516893] linearizableReadLoop","detail":"{readStateIndex:25308; appliedIndex:25306; }","duration":"172.339427ms","start":"2026-04-27T21:47:41.910697Z","end":"2026-04-27T21:47:42.083037Z","steps":["trace[175516893] 'read index received' (duration: 118.582381ms)","trace[175516893] 'applied index is now lower than readState.Index' (duration: 53.755856ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:42.083131Z","caller":"traceutil/trace.go:171","msg":"trace[186895018] transaction","detail":"{read_only:false; response_revision:24504; number_of_response:1; }","duration":"173.55272ms","start":"2026-04-27T21:47:41.909535Z","end":"2026-04-27T21:47:42.083087Z","steps":["trace[186895018] 'process raft request' (duration: 173.347817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:42.083317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.585122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-27T21:47:42.083392Z","caller":"traceutil/trace.go:171","msg":"trace[489539013] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24504; }","duration":"172.679394ms","start":"2026-04-27T21:47:41.910681Z","end":"2026-04-27T21:47:42.08336Z","steps":["trace[489539013] 'agreement among raft nodes before linearized reading' (duration: 172.45755ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:42.359965Z","caller":"traceutil/trace.go:171","msg":"trace[590500089] transaction","detail":"{read_only:false; response_revision:24505; number_of_response:1; }","duration":"272.920231ms","start":"2026-04-27T21:47:42.087016Z","end":"2026-04-27T21:47:42.359937Z","steps":["trace[590500089] 'process raft request' (duration: 256.233559ms)","trace[590500089] 'compare' (duration: 16.57131ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:42.361724Z","caller":"traceutil/trace.go:171","msg":"trace[113662453] transaction","detail":"{read_only:false; response_revision:24506; number_of_response:1; }","duration":"274.638453ms","start":"2026-04-27T21:47:42.087065Z","end":"2026-04-27T21:47:42.361704Z","steps":["trace[113662453] 'process raft request' (duration: 274.376268ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:42.361756Z","caller":"traceutil/trace.go:171","msg":"trace[628501899] transaction","detail":"{read_only:false; response_revision:24508; number_of_response:1; }","duration":"161.358651ms","start":"2026-04-27T21:47:42.200364Z","end":"2026-04-27T21:47:42.361723Z","steps":["trace[628501899] 'process raft request' (duration: 161.28173ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:42.361819Z","caller":"traceutil/trace.go:171","msg":"trace[485396204] transaction","detail":"{read_only:false; response_revision:24507; number_of_response:1; }","duration":"273.003012ms","start":"2026-04-27T21:47:42.088787Z","end":"2026-04-27T21:47:42.36179Z","steps":["trace[485396204] 'process raft request' (duration: 272.816119ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:42.362021Z","caller":"traceutil/trace.go:171","msg":"trace[603543986] linearizableReadLoop","detail":"{readStateIndex:25311; appliedIndex:25308; }","duration":"223.720779ms","start":"2026-04-27T21:47:42.138275Z","end":"2026-04-27T21:47:42.361996Z","steps":["trace[603543986] 'read index received' (duration: 204.988879ms)","trace[603543986] 'applied index is now lower than readState.Index' (duration: 18.72814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:42.36222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.935853ms","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-04-27T21:47:42.362253Z","caller":"traceutil/trace.go:171","msg":"trace[527328818] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24510; }","duration":"224.049255ms","start":"2026-04-27T21:47:42.138194Z","end":"2026-04-27T21:47:42.362243Z","steps":["trace[527328818] 'agreement among raft nodes before linearized reading' (duration: 223.896673ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:42.779292Z","caller":"traceutil/trace.go:171","msg":"trace[29490280] transaction","detail":"{read_only:false; response_revision:24511; number_of_response:1; }","duration":"321.849437ms","start":"2026-04-27T21:47:42.4574Z","end":"2026-04-27T21:47:42.779249Z","steps":["trace[29490280] 'process raft request' (duration: 321.392109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:42.77945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:42.457378Z","time spent":"321.98902ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:44.084257Z","caller":"traceutil/trace.go:171","msg":"trace[1247269294] transaction","detail":"{read_only:false; response_revision:24515; number_of_response:1; }","duration":"176.927153ms","start":"2026-04-27T21:47:43.907297Z","end":"2026-04-27T21:47:44.084224Z","steps":["trace[1247269294] 'process raft request' (duration: 168.014657ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:44.08427Z","caller":"traceutil/trace.go:171","msg":"trace[1194753535] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"176.050557ms","start":"2026-04-27T21:47:43.908178Z","end":"2026-04-27T21:47:44.084229Z","steps":["trace[1194753535] 'process raft request' (duration: 175.910254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:44.546731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.426388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-27T21:47:44.546998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.433589ms","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-04-27T21:47:44.54713Z","caller":"traceutil/trace.go:171","msg":"trace[585724099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24518; }","duration":"181.628651ms","start":"2026-04-27T21:47:44.36548Z","end":"2026-04-27T21:47:44.547109Z","steps":["trace[585724099] 'range keys from in-memory index tree' (duration: 181.109212ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:44.546865Z","caller":"traceutil/trace.go:171","msg":"trace[783980640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24518; }","duration":"181.618411ms","start":"2026-04-27T21:47:44.365218Z","end":"2026-04-27T21:47:44.546837Z","steps":["trace[783980640] 'range keys from in-memory index tree' (duration: 181.280896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:44.54766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.898329ms","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-04-27T21:47:44.547767Z","caller":"traceutil/trace.go:171","msg":"trace[140883129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24518; }","duration":"182.126601ms","start":"2026-04-27T21:47:44.365626Z","end":"2026-04-27T21:47:44.547752Z","steps":["trace[140883129] 'range keys from in-memory index tree' (duration: 180.670184ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:44.564841Z","caller":"traceutil/trace.go:171","msg":"trace[1882306527] transaction","detail":"{read_only:false; response_revision:24519; number_of_response:1; }","duration":"197.767364ms","start":"2026-04-27T21:47:44.367039Z","end":"2026-04-27T21:47:44.564806Z","steps":["trace[1882306527] 'process raft request' (duration: 197.234923ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:44.796413Z","caller":"traceutil/trace.go:171","msg":"trace[1379555602] linearizableReadLoop","detail":"{readStateIndex:25328; appliedIndex:25323; }","duration":"170.447282ms","start":"2026-04-27T21:47:44.625941Z","end":"2026-04-27T21:47:44.796388Z","steps":["trace[1379555602] 'read index received' (duration: 136.919904ms)","trace[1379555602] 'applied index is now lower than readState.Index' (duration: 33.526268ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:44.796557Z","caller":"traceutil/trace.go:171","msg":"trace[463083740] transaction","detail":"{read_only:false; response_revision:24520; number_of_response:1; }","duration":"429.029144ms","start":"2026-04-27T21:47:44.367515Z","end":"2026-04-27T21:47:44.796544Z","steps":["trace[463083740] 'process raft request' (duration: 395.389984ms)","trace[463083740] 'compare' (duration: 32.995718ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:44.796669Z","caller":"traceutil/trace.go:171","msg":"trace[1510055162] transaction","detail":"{read_only:false; response_revision:24523; number_of_response:1; }","duration":"244.0813ms","start":"2026-04-27T21:47:44.552516Z","end":"2026-04-27T21:47:44.796597Z","steps":["trace[1510055162] 'process raft request' (duration: 243.787975ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:44.796759Z","caller":"traceutil/trace.go:171","msg":"trace[61835208] transaction","detail":"{read_only:false; response_revision:24524; number_of_response:1; }","duration":"243.51412ms","start":"2026-04-27T21:47:44.553236Z","end":"2026-04-27T21:47:44.79675Z","steps":["trace[61835208] 'process raft request' (duration: 243.112382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:44.796707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:44.367497Z","time spent":"429.093485ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:44.796954Z","caller":"traceutil/trace.go:171","msg":"trace[160231847] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"426.547647ms","start":"2026-04-27T21:47:44.370387Z","end":"2026-04-27T21:47:44.796935Z","steps":["trace[160231847] 'process raft request' (duration: 425.780193ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:44.797021Z","caller":"traceutil/trace.go:171","msg":"trace[615506557] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"244.529548ms","start":"2026-04-27T21:47:44.55248Z","end":"2026-04-27T21:47:44.797009Z","steps":["trace[615506557] 'process raft request' (duration: 243.780405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:44.796999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.199625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:44.797041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:44.370369Z","time spent":"426.622338ms","remote":"127.0.0.1:39832","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-04-27T21:47:44.797107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.119434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:44.797205Z","caller":"traceutil/trace.go:171","msg":"trace[1347974037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24524; }","duration":"171.318839ms","start":"2026-04-27T21:47:44.625865Z","end":"2026-04-27T21:47:44.797184Z","steps":["trace[1347974037] 'agreement among raft nodes before linearized reading' (duration: 171.047313ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:44.797133Z","caller":"traceutil/trace.go:171","msg":"trace[150821310] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24524; }","duration":"124.344907ms","start":"2026-04-27T21:47:44.672721Z","end":"2026-04-27T21:47:44.797066Z","steps":["trace[150821310] 'agreement among raft nodes before linearized reading' (duration: 124.159714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:45.167599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.962459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:45.167701Z","caller":"traceutil/trace.go:171","msg":"trace[1363993515] linearizableReadLoop","detail":"{readStateIndex:25330; appliedIndex:25329; }","duration":"147.478101ms","start":"2026-04-27T21:47:45.020207Z","end":"2026-04-27T21:47:45.167685Z","steps":["trace[1363993515] 'read index received' (duration: 29.38031ms)","trace[1363993515] 'applied index is now lower than readState.Index' (duration: 118.096551ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:45.167814Z","caller":"traceutil/trace.go:171","msg":"trace[223102827] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"239.077037ms","start":"2026-04-27T21:47:44.928707Z","end":"2026-04-27T21:47:45.167784Z","steps":["trace[223102827] 'process raft request' (duration: 120.859654ms)","trace[223102827] 'compare' (duration: 117.658223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:45.16789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.681285ms","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-04-27T21:47:45.167932Z","caller":"traceutil/trace.go:171","msg":"trace[848077737] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24526; }","duration":"147.771417ms","start":"2026-04-27T21:47:45.020148Z","end":"2026-04-27T21:47:45.16792Z","steps":["trace[848077737] 'agreement among raft nodes before linearized reading' (duration: 147.582033ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:45.375236Z","caller":"traceutil/trace.go:171","msg":"trace[1750783164] linearizableReadLoop","detail":"{readStateIndex:25331; appliedIndex:25330; }","duration":"204.169733ms","start":"2026-04-27T21:47:45.17104Z","end":"2026-04-27T21:47:45.375209Z","steps":["trace[1750783164] 'read index received' (duration: 204.115062ms)","trace[1750783164] 'applied index is now lower than readState.Index' (duration: 53.371µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:45.375339Z","caller":"traceutil/trace.go:171","msg":"trace[520652898] transaction","detail":"{read_only:false; response_revision:24527; number_of_response:1; }","duration":"338.662581ms","start":"2026-04-27T21:47:45.036631Z","end":"2026-04-27T21:47:45.375293Z","steps":["trace[520652898] 'process raft request' (duration: 338.305495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:45.375475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.427749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-91095601-72b5-4529-8bca-c3cb412fba6a\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-04-27T21:47:45.375522Z","caller":"traceutil/trace.go:171","msg":"trace[1362739881] range","detail":"{range_begin:/registry/persistentvolumes/pvc-91095601-72b5-4529-8bca-c3cb412fba6a; range_end:; response_count:1; response_revision:24527; }","duration":"204.50457ms","start":"2026-04-27T21:47:45.171001Z","end":"2026-04-27T21:47:45.375506Z","steps":["trace[1362739881] 'agreement among raft nodes before linearized reading' (duration: 204.332556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:45.37554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:45.036606Z","time spent":"338.838295ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:45.376234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.843145ms","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-04-27T21:47:45.376324Z","caller":"traceutil/trace.go:171","msg":"trace[1591399808] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24528; }","duration":"190.001328ms","start":"2026-04-27T21:47:45.186303Z","end":"2026-04-27T21:47:45.376304Z","steps":["trace[1591399808] 'agreement among raft nodes before linearized reading' (duration: 189.756144ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:51.150792Z","caller":"traceutil/trace.go:171","msg":"trace[140632363] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"332.301873ms","start":"2026-04-27T21:47:50.818463Z","end":"2026-04-27T21:47:51.150765Z","steps":["trace[140632363] 'process raft request' (duration: 332.1276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.151136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.818411Z","time spent":"332.561389ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:51.663887Z","caller":"traceutil/trace.go:171","msg":"trace[1831890538] linearizableReadLoop","detail":"{readStateIndex:25429; appliedIndex:25427; }","duration":"841.972497ms","start":"2026-04-27T21:47:50.821893Z","end":"2026-04-27T21:47:51.663865Z","steps":["trace[1831890538] 'read index received' (duration: 328.796167ms)","trace[1831890538] 'applied index is now lower than readState.Index' (duration: 513.17526ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:51.664017Z","caller":"traceutil/trace.go:171","msg":"trace[1543062632] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"842.63095ms","start":"2026-04-27T21:47:50.821334Z","end":"2026-04-27T21:47:51.663965Z","steps":["trace[1543062632] 'process raft request' (duration: 842.367645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.664094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.184851ms","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-04-27T21:47:51.664133Z","caller":"traceutil/trace.go:171","msg":"trace[203657309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24624; }","duration":"842.254624ms","start":"2026-04-27T21:47:50.821866Z","end":"2026-04-27T21:47:51.66412Z","steps":["trace[203657309] 'agreement among raft nodes before linearized reading' (duration: 842.118341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.664166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.821859Z","time spent":"842.298334ms","remote":"127.0.0.1:39832","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-04-27T21:47:51.66419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.82131Z","time spent":"842.777433ms","remote":"127.0.0.1:39832","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-04-27T21:47:52.146151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.506544ms","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-04-27T21:47:52.146299Z","caller":"traceutil/trace.go:171","msg":"trace[1616979913] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24624; }","duration":"405.666997ms","start":"2026-04-27T21:47:51.740603Z","end":"2026-04-27T21:47:52.14627Z","steps":["trace[1616979913] 'range keys from in-memory index tree' (duration: 405.484924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.146539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.757506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:52.146717Z","caller":"traceutil/trace.go:171","msg":"trace[887363306] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"959.960767ms","start":"2026-04-27T21:47:51.186719Z","end":"2026-04-27T21:47:52.146679Z","steps":["trace[887363306] 'process raft request' (duration: 723.967907ms)","trace[887363306] 'compare' (duration: 235.633173ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:52.146751Z","caller":"traceutil/trace.go:171","msg":"trace[92782395] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"478.540422ms","start":"2026-04-27T21:47:51.66819Z","end":"2026-04-27T21:47:52.14673Z","steps":["trace[92782395] 'process raft request' (duration: 478.45507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.14683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.668172Z","time spent":"478.624683ms","remote":"127.0.0.1:39832","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-04-27T21:47:52.146877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.18669Z","time spent":"960.09742ms","remote":"127.0.0.1:39832","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-04-27T21:47:52.148456Z","caller":"traceutil/trace.go:171","msg":"trace[48141408] linearizableReadLoop","detail":"{readStateIndex:25430; appliedIndex:25429; }","duration":"484.482333ms","start":"2026-04-27T21:47:51.663955Z","end":"2026-04-27T21:47:52.148437Z","steps":["trace[48141408] 'read index received' (duration: 246.72939ms)","trace[48141408] 'applied index is now lower than readState.Index' (duration: 237.750793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:52.148675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.369872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-27T21:47:52.148702Z","caller":"traceutil/trace.go:171","msg":"trace[2103502887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f; range_end:; response_count:1; response_revision:24626; }","duration":"491.440903ms","start":"2026-04-27T21:47:51.657251Z","end":"2026-04-27T21:47:52.148692Z","steps":["trace[2103502887] 'agreement among raft nodes before linearized reading' (duration: 491.26586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.148732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.657231Z","time spent":"491.489384ms","remote":"127.0.0.1:39746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f\" "} {"level":"warn","ts":"2026-04-27T21:47:52.148788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.606669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:52.14882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289364706s","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-04-27T21:47:52.148889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305728972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-04-27T21:47:52.14893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.081513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-04-27T21:47:52.148879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323816951s","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-04-27T21:47:52.148954Z","caller":"traceutil/trace.go:171","msg":"trace[904120212] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:24626; }","duration":"933.105144ms","start":"2026-04-27T21:47:51.215842Z","end":"2026-04-27T21:47:52.148948Z","steps":["trace[904120212] 'agreement among raft nodes before linearized reading' (duration: 933.037603ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:52.148955Z","caller":"traceutil/trace.go:171","msg":"trace[1869236775] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f; range_end:; response_count:1; response_revision:24626; }","duration":"1.305822294s","start":"2026-04-27T21:47:50.843116Z","end":"2026-04-27T21:47:52.148938Z","steps":["trace[1869236775] 'agreement among raft nodes before linearized reading' (duration: 1.305581609s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.148973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.215781Z","time spent":"933.187495ms","remote":"127.0.0.1:39592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.19.213.228\" "} {"level":"info","ts":"2026-04-27T21:47:52.148991Z","caller":"traceutil/trace.go:171","msg":"trace[910228126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24626; }","duration":"1.323944053s","start":"2026-04-27T21:47:50.825022Z","end":"2026-04-27T21:47:52.148966Z","steps":["trace[910228126] 'agreement among raft nodes before linearized reading' (duration: 1.323641278s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.149002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.843106Z","time spent":"1.305885185s","remote":"127.0.0.1:39746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f\" "} {"level":"warn","ts":"2026-04-27T21:47:52.149023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.825017Z","time spent":"1.324000514s","remote":"127.0.0.1:39832","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-04-27T21:47:52.148899Z","caller":"traceutil/trace.go:171","msg":"trace[1079010208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24626; }","duration":"1.289506699s","start":"2026-04-27T21:47:50.85937Z","end":"2026-04-27T21:47:52.148877Z","steps":["trace[1079010208] 'agreement among raft nodes before linearized reading' (duration: 1.289230954s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.149082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.859348Z","time spent":"1.289722483s","remote":"127.0.0.1:39832","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-04-27T21:47:52.148852Z","caller":"traceutil/trace.go:171","msg":"trace[323408538] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24626; }","duration":"677.713082ms","start":"2026-04-27T21:47:51.471122Z","end":"2026-04-27T21:47:52.148835Z","steps":["trace[323408538] 'agreement among raft nodes before linearized reading' (duration: 677.59339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.149141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.471103Z","time spent":"678.027577ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:52.149424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326545633s","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-04-27T21:47:52.149464Z","caller":"traceutil/trace.go:171","msg":"trace[77281701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24626; }","duration":"1.326593374s","start":"2026-04-27T21:47:50.822859Z","end":"2026-04-27T21:47:52.149452Z","steps":["trace[77281701] 'agreement among raft nodes before linearized reading' (duration: 1.326475232s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.149514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.822854Z","time spent":"1.326648485s","remote":"127.0.0.1:39832","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-04-27T21:47:52.16209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.350423ms","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-04-27T21:47:52.16217Z","caller":"traceutil/trace.go:171","msg":"trace[677506066] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24629; }","duration":"372.485855ms","start":"2026-04-27T21:47:51.789659Z","end":"2026-04-27T21:47:52.162145Z","steps":["trace[677506066] 'agreement among raft nodes before linearized reading' (duration: 372.288472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.162201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.789629Z","time spent":"372.566757ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-27T21:47:52.162468Z","caller":"traceutil/trace.go:171","msg":"trace[911536074] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"222.272863ms","start":"2026-04-27T21:47:51.940188Z","end":"2026-04-27T21:47:52.16246Z","steps":["trace[911536074] 'process raft request' (duration: 221.564989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.162688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.859686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:52.162712Z","caller":"traceutil/trace.go:171","msg":"trace[55076807] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:24629; }","duration":"244.943106ms","start":"2026-04-27T21:47:51.917763Z","end":"2026-04-27T21:47:52.162706Z","steps":["trace[55076807] 'agreement among raft nodes before linearized reading' (duration: 244.884515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.475604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.150457Z","time spent":"325.13355ms","remote":"127.0.0.1:39592","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T21:47:52.475725Z","caller":"traceutil/trace.go:171","msg":"trace[478329903] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"320.312458ms","start":"2026-04-27T21:47:52.155374Z","end":"2026-04-27T21:47:52.475686Z","steps":["trace[478329903] 'process raft request' (duration: 320.246417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.475883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.15536Z","time spent":"320.433331ms","remote":"127.0.0.1:39746","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-04-27T21:47:52.475973Z","caller":"traceutil/trace.go:171","msg":"trace[1850576710] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"321.952809ms","start":"2026-04-27T21:47:52.153989Z","end":"2026-04-27T21:47:52.475942Z","steps":["trace[1850576710] 'process raft request' (duration: 321.541292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.476138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.153966Z","time spent":"322.076972ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:52.476251Z","caller":"traceutil/trace.go:171","msg":"trace[1610801236] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"323.283264ms","start":"2026-04-27T21:47:52.152956Z","end":"2026-04-27T21:47:52.476239Z","steps":["trace[1610801236] 'process raft request' (duration: 322.449208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.476314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.152933Z","time spent":"323.346415ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:52.476317Z","caller":"traceutil/trace.go:171","msg":"trace[998654357] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"321.458839ms","start":"2026-04-27T21:47:52.154818Z","end":"2026-04-27T21:47:52.476277Z","steps":["trace[998654357] 'process raft request' (duration: 320.766947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.476491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.154809Z","time spent":"321.587582ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:52.484265Z","caller":"traceutil/trace.go:171","msg":"trace[1951717280] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"310.20677ms","start":"2026-04-27T21:47:52.174034Z","end":"2026-04-27T21:47:52.484241Z","steps":["trace[1951717280] 'process raft request' (duration: 309.907714ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:52.484164Z","caller":"traceutil/trace.go:171","msg":"trace[129967828] linearizableReadLoop","detail":"{readStateIndex:25440; appliedIndex:25434; }","duration":"309.528017ms","start":"2026-04-27T21:47:52.174615Z","end":"2026-04-27T21:47:52.484143Z","steps":["trace[129967828] 'read index received' (duration: 296.683356ms)","trace[129967828] 'applied index is now lower than readState.Index' (duration: 12.844071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:52.484413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.777311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-27T21:47:52.484446Z","caller":"traceutil/trace.go:171","msg":"trace[461096344] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24634; }","duration":"309.853712ms","start":"2026-04-27T21:47:52.174582Z","end":"2026-04-27T21:47:52.484436Z","steps":["trace[461096344] 'agreement among raft nodes before linearized reading' (duration: 309.72673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.484465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.174573Z","time spent":"309.887642ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-27T21:47:52.484459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.17401Z","time spent":"310.363873ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:52.640687Z","caller":"traceutil/trace.go:171","msg":"trace[2003577345] linearizableReadLoop","detail":"{readStateIndex:25441; appliedIndex:25440; }","duration":"156.221425ms","start":"2026-04-27T21:47:52.484406Z","end":"2026-04-27T21:47:52.640628Z","steps":["trace[2003577345] 'read index received' (duration: 142.700963ms)","trace[2003577345] 'applied index is now lower than readState.Index' (duration: 13.519662ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:52.640756Z","caller":"traceutil/trace.go:171","msg":"trace[167167105] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"161.940463ms","start":"2026-04-27T21:47:52.478773Z","end":"2026-04-27T21:47:52.640714Z","steps":["trace[167167105] 'process raft request' (duration: 148.323538ms)","trace[167167105] 'compare' (duration: 13.40014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:52.640989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.854614ms","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-04-27T21:47:52.641062Z","caller":"traceutil/trace.go:171","msg":"trace[1013566772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24635; }","duration":"465.947726ms","start":"2026-04-27T21:47:52.175094Z","end":"2026-04-27T21:47:52.641042Z","steps":["trace[1013566772] 'agreement among raft nodes before linearized reading' (duration: 465.689431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.641132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.175083Z","time spent":"466.038567ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T21:47:52.966448Z","caller":"traceutil/trace.go:171","msg":"trace[1162298257] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"477.909359ms","start":"2026-04-27T21:47:52.488505Z","end":"2026-04-27T21:47:52.966415Z","steps":["trace[1162298257] 'process raft request' (duration: 443.334322ms)","trace[1162298257] 'compare' (duration: 34.120669ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:52.96657Z","caller":"traceutil/trace.go:171","msg":"trace[1919741936] linearizableReadLoop","detail":"{readStateIndex:25443; appliedIndex:25441; }","duration":"325.78129ms","start":"2026-04-27T21:47:52.640757Z","end":"2026-04-27T21:47:52.966538Z","steps":["trace[1919741936] 'read index received' (duration: 291.182143ms)","trace[1919741936] 'applied index is now lower than readState.Index' (duration: 34.596737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:52.966602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.488476Z","time spent":"478.041991ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:52.966734Z","caller":"traceutil/trace.go:171","msg":"trace[780680964] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"362.226693ms","start":"2026-04-27T21:47:52.604486Z","end":"2026-04-27T21:47:52.966712Z","steps":["trace[780680964] 'process raft request' (duration: 361.848606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.96687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.604465Z","time spent":"362.307484ms","remote":"127.0.0.1:39832","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-04-27T21:47:52.966855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.039204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:52.967245Z","caller":"traceutil/trace.go:171","msg":"trace[911242550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24637; }","duration":"343.207188ms","start":"2026-04-27T21:47:52.623755Z","end":"2026-04-27T21:47:52.966962Z","steps":["trace[911242550] 'agreement among raft nodes before linearized reading' (duration: 342.970553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:52.967322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.623742Z","time spent":"343.560454ms","remote":"127.0.0.1:39564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:47:53.118695Z","caller":"traceutil/trace.go:171","msg":"trace[529781411] transaction","detail":"{read_only:false; response_revision:24638; number_of_response:1; }","duration":"469.586644ms","start":"2026-04-27T21:47:52.649064Z","end":"2026-04-27T21:47:53.11865Z","steps":["trace[529781411] 'process raft request' (duration: 442.18198ms)","trace[529781411] 'compare' (duration: 27.20377ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:53.118669Z","caller":"traceutil/trace.go:171","msg":"trace[112217542] linearizableReadLoop","detail":"{readStateIndex:25444; appliedIndex:25443; }","duration":"151.969416ms","start":"2026-04-27T21:47:52.966668Z","end":"2026-04-27T21:47:53.118638Z","steps":["trace[112217542] 'read index received' (duration: 124.510182ms)","trace[112217542] 'applied index is now lower than readState.Index' (duration: 27.457824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:53.119143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.300399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-04-27T21:47:53.119208Z","caller":"traceutil/trace.go:171","msg":"trace[835334765] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24638; }","duration":"476.395021ms","start":"2026-04-27T21:47:52.642792Z","end":"2026-04-27T21:47:53.119187Z","steps":["trace[835334765] 'agreement among raft nodes before linearized reading' (duration: 476.170097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:53.119268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.642781Z","time spent":"476.470703ms","remote":"127.0.0.1:39724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-27T21:47:53.119292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.654253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:53.119331Z","caller":"traceutil/trace.go:171","msg":"trace[929517316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24638; }","duration":"149.746505ms","start":"2026-04-27T21:47:52.969571Z","end":"2026-04-27T21:47:53.119317Z","steps":["trace[929517316] 'agreement among raft nodes before linearized reading' (duration: 149.654583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:53.120497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:52.649036Z","time spent":"470.085793ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:53.315141Z","caller":"traceutil/trace.go:171","msg":"trace[610660891] linearizableReadLoop","detail":"{readStateIndex:25445; appliedIndex:25444; }","duration":"115.089505ms","start":"2026-04-27T21:47:53.200017Z","end":"2026-04-27T21:47:53.315107Z","steps":["trace[610660891] 'read index received' (duration: 80.874544ms)","trace[610660891] 'applied index is now lower than readState.Index' (duration: 34.213501ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:53.315344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.298128ms","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-04-27T21:47:53.31539Z","caller":"traceutil/trace.go:171","msg":"trace[634372153] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24639; }","duration":"115.417301ms","start":"2026-04-27T21:47:53.199958Z","end":"2026-04-27T21:47:53.315375Z","steps":["trace[634372153] 'agreement among raft nodes before linearized reading' (duration: 115.271428ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:53.315598Z","caller":"traceutil/trace.go:171","msg":"trace[1230526899] transaction","detail":"{read_only:false; response_revision:24639; number_of_response:1; }","duration":"157.087303ms","start":"2026-04-27T21:47:53.158476Z","end":"2026-04-27T21:47:53.315564Z","steps":["trace[1230526899] 'process raft request' (duration: 122.391493ms)","trace[1230526899] 'compare' (duration: 34.063818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:54.160055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.36003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:54.160143Z","caller":"traceutil/trace.go:171","msg":"trace[1910587175] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24640; }","duration":"336.509693ms","start":"2026-04-27T21:47:53.823613Z","end":"2026-04-27T21:47:54.160123Z","steps":["trace[1910587175] 'count revisions from in-memory index tree' (duration: 336.204736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:54.160187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:53.823588Z","time spent":"336.586714ms","remote":"127.0.0.1:59296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:54.160404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.309886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:54.160471Z","caller":"traceutil/trace.go:171","msg":"trace[1643224251] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:24640; }","duration":"181.428588ms","start":"2026-04-27T21:47:53.979023Z","end":"2026-04-27T21:47:54.160452Z","steps":["trace[1643224251] 'count revisions from in-memory index tree' (duration: 181.197433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:54.160873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.320063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:268870"} {"level":"info","ts":"2026-04-27T21:47:54.160946Z","caller":"traceutil/trace.go:171","msg":"trace[1161610803] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:24640; }","duration":"332.446525ms","start":"2026-04-27T21:47:53.828481Z","end":"2026-04-27T21:47:54.160928Z","steps":["trace[1161610803] 'range keys from in-memory index tree' (duration: 331.792663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:54.160992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:53.82845Z","time spent":"332.527997ms","remote":"127.0.0.1:40044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":31,"response size":268893,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-04-27T21:47:54.161046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.879596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-27T21:47:54.161136Z","caller":"traceutil/trace.go:171","msg":"trace[788617740] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24640; }","duration":"175.042159ms","start":"2026-04-27T21:47:53.986072Z","end":"2026-04-27T21:47:54.161114Z","steps":["trace[788617740] 'range keys from in-memory index tree' (duration: 174.692541ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:56.274213Z","caller":"traceutil/trace.go:171","msg":"trace[77665000] linearizableReadLoop","detail":"{readStateIndex:25464; appliedIndex:25463; }","duration":"102.262855ms","start":"2026-04-27T21:47:56.171928Z","end":"2026-04-27T21:47:56.274191Z","steps":["trace[77665000] 'read index received' (duration: 102.145033ms)","trace[77665000] 'applied index is now lower than readState.Index' (duration: 116.922µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:56.274277Z","caller":"traceutil/trace.go:171","msg":"trace[1384334194] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"105.653139ms","start":"2026-04-27T21:47:56.168595Z","end":"2026-04-27T21:47:56.274248Z","steps":["trace[1384334194] 'process raft request' (duration: 105.433225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:56.27441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.470089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-04-27T21:47:56.274446Z","caller":"traceutil/trace.go:171","msg":"trace[1501208076] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f; range_end:; response_count:1; response_revision:24658; }","duration":"102.54824ms","start":"2026-04-27T21:47:56.171885Z","end":"2026-04-27T21:47:56.274433Z","steps":["trace[1501208076] 'agreement among raft nodes before linearized reading' (duration: 102.411018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:56.774951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079349776403114889,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T21:47:56.781709Z","caller":"traceutil/trace.go:171","msg":"trace[1565436945] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"599.493347ms","start":"2026-04-27T21:47:56.182191Z","end":"2026-04-27T21:47:56.781684Z","steps":["trace[1565436945] 'process raft request' (duration: 599.426226ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:56.78194Z","caller":"traceutil/trace.go:171","msg":"trace[1715222606] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"602.069026ms","start":"2026-04-27T21:47:56.179813Z","end":"2026-04-27T21:47:56.781882Z","steps":["trace[1715222606] 'process raft request' (duration: 601.617678ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:56.782044Z","caller":"traceutil/trace.go:171","msg":"trace[774479940] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"601.556176ms","start":"2026-04-27T21:47:56.180478Z","end":"2026-04-27T21:47:56.782034Z","steps":["trace[774479940] 'process raft request' (duration: 601.093487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:56.782664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.179804Z","time spent":"602.732909ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:56.782464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.182179Z","time spent":"600.191231ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:56.782882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.180465Z","time spent":"602.268949ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:57.678125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.620807ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-27T21:47:57.678611Z","caller":"traceutil/trace.go:171","msg":"trace[1492262796] linearizableReadLoop","detail":"{readStateIndex:25468; appliedIndex:25467; }","duration":"1.404298858s","start":"2026-04-27T21:47:56.274278Z","end":"2026-04-27T21:47:57.678577Z","steps":["trace[1492262796] 'read index received' (duration: 507.566244ms)","trace[1492262796] 'applied index is now lower than readState.Index' (duration: 896.729324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:57.678708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473536766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:57.678784Z","caller":"traceutil/trace.go:171","msg":"trace[1726359361] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24661; }","duration":"1.473655297s","start":"2026-04-27T21:47:56.205108Z","end":"2026-04-27T21:47:57.678763Z","steps":["trace[1726359361] 'agreement among raft nodes before linearized reading' (duration: 1.473516235s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.678845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.205094Z","time spent":"1.473740578s","remote":"127.0.0.1:59872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:57.679071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50265735s","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-04-27T21:47:57.679185Z","caller":"traceutil/trace.go:171","msg":"trace[1386890486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24661; }","duration":"1.502831543s","start":"2026-04-27T21:47:56.176328Z","end":"2026-04-27T21:47:57.67916Z","steps":["trace[1386890486] 'agreement among raft nodes before linearized reading' (duration: 1.502466787s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.6793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.176319Z","time spent":"1.502962005s","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T21:47:57.680796Z","caller":"traceutil/trace.go:171","msg":"trace[364168539] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"676.731452ms","start":"2026-04-27T21:47:57.004047Z","end":"2026-04-27T21:47:57.680778Z","steps":["trace[364168539] 'process raft request' (duration: 676.436427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.681167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.004024Z","time spent":"676.952257ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:57.681613Z","caller":"traceutil/trace.go:171","msg":"trace[1427842387] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"700.633171ms","start":"2026-04-27T21:47:56.980951Z","end":"2026-04-27T21:47:57.681584Z","steps":["trace[1427842387] 'process raft request' (duration: 697.326858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.681805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.980927Z","time spent":"700.764164ms","remote":"127.0.0.1:39832","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-04-27T21:47:57.83298Z","caller":"traceutil/trace.go:171","msg":"trace[1816844359] linearizableReadLoop","detail":"{readStateIndex:25471; appliedIndex:25470; }","duration":"154.241029ms","start":"2026-04-27T21:47:57.678716Z","end":"2026-04-27T21:47:57.832957Z","steps":["trace[1816844359] 'read index received' (duration: 154.088016ms)","trace[1816844359] 'applied index is now lower than readState.Index' (duration: 151.973µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:57.833169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342304078s","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-04-27T21:47:57.833213Z","caller":"traceutil/trace.go:171","msg":"trace[2127083403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24664; }","duration":"1.342384819s","start":"2026-04-27T21:47:56.490815Z","end":"2026-04-27T21:47:57.8332Z","steps":["trace[2127083403] 'agreement among raft nodes before linearized reading' (duration: 1.342254467s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.833244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.490805Z","time spent":"1.34243017s","remote":"127.0.0.1:39832","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-04-27T21:47:57.833276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34192135s","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-04-27T21:47:57.833331Z","caller":"traceutil/trace.go:171","msg":"trace[224462156] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"500.153056ms","start":"2026-04-27T21:47:57.333165Z","end":"2026-04-27T21:47:57.833318Z","steps":["trace[224462156] 'process raft request' (duration: 499.650297ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.833355Z","caller":"traceutil/trace.go:171","msg":"trace[1875613983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24664; }","duration":"1.342028761s","start":"2026-04-27T21:47:56.491309Z","end":"2026-04-27T21:47:57.833338Z","steps":["trace[1875613983] 'agreement among raft nodes before linearized reading' (duration: 1.341765237s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.833437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.333147Z","time spent":"500.226757ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:57.833461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343470069s","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-04-27T21:47:57.83349Z","caller":"traceutil/trace.go:171","msg":"trace[551500536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24664; }","duration":"1.34352296s","start":"2026-04-27T21:47:56.489958Z","end":"2026-04-27T21:47:57.833481Z","steps":["trace[551500536] 'agreement among raft nodes before linearized reading' (duration: 1.343441099s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.833412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.491302Z","time spent":"1.342094643s","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T21:47:57.833517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.489947Z","time spent":"1.343562521s","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T21:47:57.83365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411494873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18aa5501e7382724\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-04-27T21:47:57.833663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.102769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-27T21:47:57.83369Z","caller":"traceutil/trace.go:171","msg":"trace[107616898] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18aa5501e7382724; range_end:; response_count:1; response_revision:24664; }","duration":"1.411571595s","start":"2026-04-27T21:47:56.422107Z","end":"2026-04-27T21:47:57.833679Z","steps":["trace[107616898] 'agreement among raft nodes before linearized reading' (duration: 1.411473253s)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.833696Z","caller":"traceutil/trace.go:171","msg":"trace[1816840805] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24664; }","duration":"850.174131ms","start":"2026-04-27T21:47:56.983509Z","end":"2026-04-27T21:47:57.833684Z","steps":["trace[1816840805] 'agreement among raft nodes before linearized reading' (duration: 850.082799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.83371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.114812ms","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":"warn","ts":"2026-04-27T21:47:57.83317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.797919ms","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-04-27T21:47:57.833747Z","caller":"traceutil/trace.go:171","msg":"trace[1515643953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24664; }","duration":"697.445422ms","start":"2026-04-27T21:47:57.136293Z","end":"2026-04-27T21:47:57.833738Z","steps":["trace[1515643953] 'agreement among raft nodes before linearized reading' (duration: 696.721368ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.833747Z","caller":"traceutil/trace.go:171","msg":"trace[38842513] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24664; }","duration":"235.190015ms","start":"2026-04-27T21:47:57.598545Z","end":"2026-04-27T21:47:57.833736Z","steps":["trace[38842513] 'agreement among raft nodes before linearized reading' (duration: 235.099852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.833775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.136269Z","time spent":"697.499753ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T21:47:57.833813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082171576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:47:57.833851Z","caller":"traceutil/trace.go:171","msg":"trace[236046885] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24664; }","duration":"1.082234677s","start":"2026-04-27T21:47:56.751607Z","end":"2026-04-27T21:47:57.833842Z","steps":["trace[236046885] 'agreement among raft nodes before linearized reading' (duration: 1.082156725s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.833878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.751595Z","time spent":"1.082276238s","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":20,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:57.833744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.983489Z","time spent":"850.239643ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-27T21:47:57.833931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.028383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:57.833968Z","caller":"traceutil/trace.go:171","msg":"trace[1402160533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24664; }","duration":"540.064734ms","start":"2026-04-27T21:47:57.293894Z","end":"2026-04-27T21:47:57.833959Z","steps":["trace[1402160533] 'agreement among raft nodes before linearized reading' (duration: 540.005163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.833998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.293858Z","time spent":"540.133155ms","remote":"127.0.0.1:39564","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-04-27T21:47:57.834118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210023909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:57.834176Z","caller":"traceutil/trace.go:171","msg":"trace[772069483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24664; }","duration":"1.210103171s","start":"2026-04-27T21:47:56.624047Z","end":"2026-04-27T21:47:57.83415Z","steps":["trace[772069483] 'agreement among raft nodes before linearized reading' (duration: 1.21002918s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.833718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.422088Z","time spent":"1.411622275s","remote":"127.0.0.1:39630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1033,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18aa5501e7382724\" "} {"level":"warn","ts":"2026-04-27T21:47:57.834212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.624032Z","time spent":"1.210173952s","remote":"127.0.0.1:39574","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-04-27T21:47:57.83432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.660929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-04-27T21:47:57.834427Z","caller":"traceutil/trace.go:171","msg":"trace[2028153339] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f; range_end:; response_count:1; response_revision:24664; }","duration":"553.806072ms","start":"2026-04-27T21:47:57.280601Z","end":"2026-04-27T21:47:57.834407Z","steps":["trace[2028153339] 'agreement among raft nodes before linearized reading' (duration: 553.449355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.834495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.280581Z","time spent":"553.902223ms","remote":"127.0.0.1:39746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3973,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cdee834-420b-4051-b196-2b169b147c7f\" "} {"level":"info","ts":"2026-04-27T21:47:57.840472Z","caller":"traceutil/trace.go:171","msg":"trace[1527674132] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"122.926422ms","start":"2026-04-27T21:47:57.717533Z","end":"2026-04-27T21:47:57.840459Z","steps":["trace[1527674132] 'process raft request' (duration: 122.896612ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.840776Z","caller":"traceutil/trace.go:171","msg":"trace[819229774] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"155.490513ms","start":"2026-04-27T21:47:57.685258Z","end":"2026-04-27T21:47:57.840748Z","steps":["trace[819229774] 'process raft request' (duration: 155.094205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.840885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.273001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:57.840932Z","caller":"traceutil/trace.go:171","msg":"trace[1310085960] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24666; }","duration":"161.372673ms","start":"2026-04-27T21:47:57.679547Z","end":"2026-04-27T21:47:57.840919Z","steps":["trace[1310085960] 'agreement among raft nodes before linearized reading' (duration: 161.298032ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.567639Z","caller":"traceutil/trace.go:171","msg":"trace[2009892218] linearizableReadLoop","detail":"{readStateIndex:25503; appliedIndex:25502; }","duration":"174.937715ms","start":"2026-04-27T21:48:00.392673Z","end":"2026-04-27T21:48:00.56761Z","steps":["trace[2009892218] 'read index received' (duration: 174.687131ms)","trace[2009892218] 'applied index is now lower than readState.Index' (duration: 249.274µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:48:00.567842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.17685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:48:00.567882Z","caller":"traceutil/trace.go:171","msg":"trace[1091777477] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24696; }","duration":"175.285272ms","start":"2026-04-27T21:48:00.392585Z","end":"2026-04-27T21:48:00.56787Z","steps":["trace[1091777477] 'agreement among raft nodes before linearized reading' (duration: 175.14749ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.568248Z","caller":"traceutil/trace.go:171","msg":"trace[908662031] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"272.248319ms","start":"2026-04-27T21:48:00.295987Z","end":"2026-04-27T21:48:00.568236Z","steps":["trace[908662031] 'process raft request' (duration: 271.445093ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.583958Z","caller":"traceutil/trace.go:171","msg":"trace[293787898] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"179.963401ms","start":"2026-04-27T21:48:00.403976Z","end":"2026-04-27T21:48:00.58394Z","steps":["trace[293787898] 'process raft request' (duration: 179.854429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:01.122524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079349776403114970,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T21:48:01.173134Z","caller":"traceutil/trace.go:171","msg":"trace[655809667] transaction","detail":"{read_only:false; response_revision:24698; number_of_response:1; }","duration":"560.749281ms","start":"2026-04-27T21:48:00.612358Z","end":"2026-04-27T21:48:01.173107Z","steps":["trace[655809667] 'process raft request' (duration: 560.567157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:01.173296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:00.612331Z","time spent":"560.872274ms","remote":"127.0.0.1:39746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9172,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:48:01.208828Z","caller":"traceutil/trace.go:171","msg":"trace[1662969449] transaction","detail":"{read_only:false; response_revision:24699; number_of_response:1; }","duration":"403.443045ms","start":"2026-04-27T21:48:00.805359Z","end":"2026-04-27T21:48:01.208802Z","steps":["trace[1662969449] 'process raft request' (duration: 403.222881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:01.208986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:00.80532Z","time spent":"403.579447ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:48:01.209161Z","caller":"traceutil/trace.go:171","msg":"trace[1455126125] linearizableReadLoop","detail":"{readStateIndex:25508; appliedIndex:25504; }","duration":"587.690075ms","start":"2026-04-27T21:48:00.621451Z","end":"2026-04-27T21:48:01.209141Z","steps":["trace[1455126125] 'read index received' (duration: 551.506777ms)","trace[1455126125] 'applied index is now lower than readState.Index' (duration: 36.182398ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:01.20921Z","caller":"traceutil/trace.go:171","msg":"trace[187129303] transaction","detail":"{read_only:false; response_revision:24700; number_of_response:1; }","duration":"400.565641ms","start":"2026-04-27T21:48:00.808606Z","end":"2026-04-27T21:48:01.209172Z","steps":["trace[187129303] 'process raft request' (duration: 400.139093ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:01.209291Z","caller":"traceutil/trace.go:171","msg":"trace[498559380] transaction","detail":"{read_only:false; response_revision:24701; number_of_response:1; }","duration":"399.230086ms","start":"2026-04-27T21:48:00.809997Z","end":"2026-04-27T21:48:01.209227Z","steps":["trace[498559380] 'process raft request' (duration: 399.063343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:01.209324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.843378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:48:01.209426Z","caller":"traceutil/trace.go:171","msg":"trace[398524739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24701; }","duration":"587.99278ms","start":"2026-04-27T21:48:00.621406Z","end":"2026-04-27T21:48:01.209399Z","steps":["trace[398524739] 'agreement among raft nodes before linearized reading' (duration: 587.818337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:01.20942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:00.808563Z","time spent":"400.725414ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:01.209485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:00.62139Z","time spent":"588.085354ms","remote":"127.0.0.1:39564","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-04-27T21:48:01.209499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:00.809971Z","time spent":"399.42421ms","remote":"127.0.0.1:39832","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-04-27T21:48:01.234721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.360436ms","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-04-27T21:48:01.234873Z","caller":"traceutil/trace.go:171","msg":"trace[481778950] 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:24702; }","duration":"425.555559ms","start":"2026-04-27T21:48:00.809293Z","end":"2026-04-27T21:48:01.234848Z","steps":["trace[481778950] 'agreement among raft nodes before linearized reading' (duration: 425.325504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:01.234974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:00.80927Z","time spent":"425.681961ms","remote":"127.0.0.1:51132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:48:01.234729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.81255ms","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":"info","ts":"2026-04-27T21:48:01.235347Z","caller":"traceutil/trace.go:171","msg":"trace[1573250412] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:24702; }","duration":"147.443601ms","start":"2026-04-27T21:48:01.087882Z","end":"2026-04-27T21:48:01.235326Z","steps":["trace[1573250412] 'agreement among raft nodes before linearized reading' (duration: 146.722157ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:31.619652Z","caller":"traceutil/trace.go:171","msg":"trace[1528352894] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"303.669337ms","start":"2026-04-27T21:48:31.31594Z","end":"2026-04-27T21:48:31.61961Z","steps":["trace[1528352894] 'process raft request' (duration: 281.861608ms)","trace[1528352894] 'compare' (duration: 21.511254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:48:31.619877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:31.315908Z","time spent":"303.858481ms","remote":"127.0.0.1:39832","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-04-27T21:48:31.623487Z","caller":"traceutil/trace.go:171","msg":"trace[1400427907] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"306.267255ms","start":"2026-04-27T21:48:31.31719Z","end":"2026-04-27T21:48:31.623457Z","steps":["trace[1400427907] 'process raft request' (duration: 306.216734ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:31.623526Z","caller":"traceutil/trace.go:171","msg":"trace[826544809] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"307.00049ms","start":"2026-04-27T21:48:31.316492Z","end":"2026-04-27T21:48:31.623492Z","steps":["trace[826544809] 'process raft request' (duration: 306.797486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:31.623631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:31.316458Z","time spent":"307.122652ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:31.623676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:31.317175Z","time spent":"306.396748ms","remote":"127.0.0.1:39832","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-04-27T21:48:34.529955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079349776403115660,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T21:48:34.617873Z","caller":"traceutil/trace.go:171","msg":"trace[1328235439] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"767.921191ms","start":"2026-04-27T21:48:33.849923Z","end":"2026-04-27T21:48:34.617844Z","steps":["trace[1328235439] 'process raft request' (duration: 767.719067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.618088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:33.84989Z","time spent":"768.086024ms","remote":"127.0.0.1:39832","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-04-27T21:48:34.997646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.327314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:48:34.998009Z","caller":"traceutil/trace.go:171","msg":"trace[766589677] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"312.316619ms","start":"2026-04-27T21:48:34.685655Z","end":"2026-04-27T21:48:34.997971Z","steps":["trace[766589677] 'process raft request' (duration: 312.273488ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:34.99799Z","caller":"traceutil/trace.go:171","msg":"trace[568516108] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"999.111621ms","start":"2026-04-27T21:48:33.998822Z","end":"2026-04-27T21:48:34.997933Z","steps":["trace[568516108] 'process raft request' (duration: 776.40032ms)","trace[568516108] 'compare' (duration: 221.962507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:48:34.998069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.685619Z","time spent":"312.426551ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:48:34.998355Z","caller":"traceutil/trace.go:171","msg":"trace[1171887489] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"397.455954ms","start":"2026-04-27T21:48:34.600847Z","end":"2026-04-27T21:48:34.998303Z","steps":["trace[1171887489] 'process raft request' (duration: 396.992056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.998234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:33.998794Z","time spent":"999.305494ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:34.998503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.600813Z","time spent":"397.623066ms","remote":"127.0.0.1:39630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":739,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T21:48:34.998528Z","caller":"traceutil/trace.go:171","msg":"trace[541790568] linearizableReadLoop","detail":"{readStateIndex:25787; appliedIndex:25785; }","duration":"968.63199ms","start":"2026-04-27T21:48:34.029865Z","end":"2026-04-27T21:48:34.998497Z","steps":["trace[541790568] 'read index received' (duration: 588.558373ms)","trace[541790568] 'applied index is now lower than readState.Index' (duration: 380.069557ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:34.998553Z","caller":"traceutil/trace.go:171","msg":"trace[124501365] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"998.847495ms","start":"2026-04-27T21:48:33.999693Z","end":"2026-04-27T21:48:34.99854Z","steps":["trace[124501365] 'process raft request' (duration: 998.060721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.998621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:33.999684Z","time spent":"998.912216ms","remote":"127.0.0.1:39832","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-04-27T21:48:34.998762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.192442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-27T21:48:34.998779Z","caller":"traceutil/trace.go:171","msg":"trace[1419602522] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24974; }","duration":"921.227582ms","start":"2026-04-27T21:48:34.077543Z","end":"2026-04-27T21:48:34.998771Z","steps":["trace[1419602522] 'agreement among raft nodes before linearized reading' (duration: 921.129341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.998774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.30654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-04-27T21:48:34.998802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.077532Z","time spent":"921.266933ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-27T21:48:34.998775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.891025ms","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-04-27T21:48:34.998816Z","caller":"traceutil/trace.go:171","msg":"trace[286013690] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24974; }","duration":"955.399192ms","start":"2026-04-27T21:48:34.043402Z","end":"2026-04-27T21:48:34.998801Z","steps":["trace[286013690] 'agreement among raft nodes before linearized reading' (duration: 955.250179ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:34.998844Z","caller":"traceutil/trace.go:171","msg":"trace[1905751026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24974; }","duration":"969.010517ms","start":"2026-04-27T21:48:34.029815Z","end":"2026-04-27T21:48:34.998825Z","steps":["trace[1905751026] 'agreement among raft nodes before linearized reading' (duration: 968.800153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.998854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.043375Z","time spent":"955.469693ms","remote":"127.0.0.1:39746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-27T21:48:34.998891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.135629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-27T21:48:34.998887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.029796Z","time spent":"969.081169ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T21:48:34.998909Z","caller":"traceutil/trace.go:171","msg":"trace[779651487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24974; }","duration":"899.17503ms","start":"2026-04-27T21:48:34.09973Z","end":"2026-04-27T21:48:34.998905Z","steps":["trace[779651487] 'agreement among raft nodes before linearized reading' (duration: 899.115728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.998889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.928903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"warn","ts":"2026-04-27T21:48:34.998968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.868412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:48:34.998976Z","caller":"traceutil/trace.go:171","msg":"trace[925694845] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24974; }","duration":"311.067357ms","start":"2026-04-27T21:48:34.687887Z","end":"2026-04-27T21:48:34.998955Z","steps":["trace[925694845] 'agreement among raft nodes before linearized reading' (duration: 310.853772ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:34.998989Z","caller":"traceutil/trace.go:171","msg":"trace[718045522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24974; }","duration":"373.946913ms","start":"2026-04-27T21:48:34.625037Z","end":"2026-04-27T21:48:34.998984Z","steps":["trace[718045522] 'agreement among raft nodes before linearized reading' (duration: 373.911962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.998992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.146176ms","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:2083"} {"level":"warn","ts":"2026-04-27T21:48:34.999011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.624958Z","time spent":"374.049806ms","remote":"127.0.0.1:39564","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-04-27T21:48:34.999007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.136356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-27T21:48:34.999023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.687867Z","time spent":"311.147688ms","remote":"127.0.0.1:45240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3258,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-04-27T21:48:34.999048Z","caller":"traceutil/trace.go:171","msg":"trace[214419366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24974; }","duration":"909.204587ms","start":"2026-04-27T21:48:34.089832Z","end":"2026-04-27T21:48:34.999037Z","steps":["trace[214419366] 'agreement among raft nodes before linearized reading' (duration: 909.040364ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:34.999043Z","caller":"traceutil/trace.go:171","msg":"trace[2014718924] 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:24974; }","duration":"642.216527ms","start":"2026-04-27T21:48:34.356807Z","end":"2026-04-27T21:48:34.999024Z","steps":["trace[2014718924] 'agreement among raft nodes before linearized reading' (duration: 641.856539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.999076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.089825Z","time spent":"909.245878ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T21:48:34.99909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.356788Z","time spent":"642.293149ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-27T21:48:34.998889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.207921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-27T21:48:34.999139Z","caller":"traceutil/trace.go:171","msg":"trace[834699650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24974; }","duration":"929.542168ms","start":"2026-04-27T21:48:34.069589Z","end":"2026-04-27T21:48:34.999131Z","steps":["trace[834699650] 'agreement among raft nodes before linearized reading' (duration: 929.089089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.999132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.636906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-27T21:48:34.999166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.069574Z","time spent":"929.582808ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T21:48:34.998928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.099718Z","time spent":"899.20703ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T21:48:34.999174Z","caller":"traceutil/trace.go:171","msg":"trace[676133428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24974; }","duration":"948.694766ms","start":"2026-04-27T21:48:34.050468Z","end":"2026-04-27T21:48:34.999163Z","steps":["trace[676133428] 'agreement among raft nodes before linearized reading' (duration: 948.572894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:34.999208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:34.05046Z","time spent":"948.741248ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T21:48:35.2079Z","caller":"traceutil/trace.go:171","msg":"trace[110468810] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"201.392572ms","start":"2026-04-27T21:48:35.006486Z","end":"2026-04-27T21:48:35.207879Z","steps":["trace[110468810] 'process raft request' (duration: 201.266549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:35.469301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.751548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:48:35.469583Z","caller":"traceutil/trace.go:171","msg":"trace[729606456] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25789; }","duration":"460.390961ms","start":"2026-04-27T21:48:35.009174Z","end":"2026-04-27T21:48:35.469565Z","steps":["trace[729606456] 'read index received' (duration: 198.525527ms)","trace[729606456] 'applied index is now lower than readState.Index' (duration: 261.864274ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:35.469689Z","caller":"traceutil/trace.go:171","msg":"trace[802713397] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"462.526572ms","start":"2026-04-27T21:48:35.007149Z","end":"2026-04-27T21:48:35.469675Z","steps":["trace[802713397] 'process raft request' (duration: 462.309899ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:35.469655Z","caller":"traceutil/trace.go:171","msg":"trace[1531643443] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"463.007411ms","start":"2026-04-27T21:48:35.006611Z","end":"2026-04-27T21:48:35.469618Z","steps":["trace[1531643443] 'process raft request' (duration: 462.789567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:35.469758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.007137Z","time spent":"462.584963ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:48:35.469748Z","caller":"traceutil/trace.go:171","msg":"trace[561489818] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"463.159974ms","start":"2026-04-27T21:48:35.006546Z","end":"2026-04-27T21:48:35.469706Z","steps":["trace[561489818] 'process raft request' (duration: 307.918267ms)","trace[561489818] 'compare' (duration: 154.629975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:48:35.46982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.00659Z","time spent":"463.145304ms","remote":"127.0.0.1:39832","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-04-27T21:48:35.469866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.667317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-04-27T21:48:35.469828Z","caller":"traceutil/trace.go:171","msg":"trace[84394796] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"462.447829ms","start":"2026-04-27T21:48:35.00734Z","end":"2026-04-27T21:48:35.469788Z","steps":["trace[84394796] 'process raft request' (duration: 462.154674ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:35.469929Z","caller":"traceutil/trace.go:171","msg":"trace[280945458] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24980; }","duration":"460.765269ms","start":"2026-04-27T21:48:35.009151Z","end":"2026-04-27T21:48:35.469916Z","steps":["trace[280945458] 'agreement among raft nodes before linearized reading' (duration: 460.555465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:35.469923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.006534Z","time spent":"463.295356ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:35.469937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.2084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:48:35.469968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.009141Z","time spent":"460.82094ms","remote":"127.0.0.1:39650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1178,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-04-27T21:48:35.469975Z","caller":"traceutil/trace.go:171","msg":"trace[1955513207] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24980; }","duration":"396.286671ms","start":"2026-04-27T21:48:35.073676Z","end":"2026-04-27T21:48:35.469963Z","steps":["trace[1955513207] 'agreement among raft nodes before linearized reading' (duration: 396.22773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:35.470008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.07365Z","time spent":"396.351292ms","remote":"127.0.0.1:40044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-27T21:48:35.469983Z","caller":"traceutil/trace.go:171","msg":"trace[369077191] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"462.388619ms","start":"2026-04-27T21:48:35.007504Z","end":"2026-04-27T21:48:35.469893Z","steps":["trace[369077191] 'process raft request' (duration: 462.022043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:35.470094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.00733Z","time spent":"462.610423ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:35.470246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.007491Z","time spent":"462.631173ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:48:35.766337Z","caller":"traceutil/trace.go:171","msg":"trace[1783863414] linearizableReadLoop","detail":"{readStateIndex:25796; appliedIndex:25795; }","duration":"288.098416ms","start":"2026-04-27T21:48:35.478213Z","end":"2026-04-27T21:48:35.766312Z","steps":["trace[1783863414] 'read index received' (duration: 288.060195ms)","trace[1783863414] 'applied index is now lower than readState.Index' (duration: 36.861µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:35.766527Z","caller":"traceutil/trace.go:171","msg":"trace[315484298] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"288.661336ms","start":"2026-04-27T21:48:35.477846Z","end":"2026-04-27T21:48:35.766507Z","steps":["trace[315484298] 'process raft request' (duration: 288.279858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:35.766733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.490583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-04-27T21:48:35.767033Z","caller":"traceutil/trace.go:171","msg":"trace[384163916] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24981; }","duration":"288.819529ms","start":"2026-04-27T21:48:35.4782Z","end":"2026-04-27T21:48:35.76702Z","steps":["trace[384163916] 'agreement among raft nodes before linearized reading' (duration: 288.374621ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:36.08178Z","caller":"traceutil/trace.go:171","msg":"trace[271306498] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"439.917758ms","start":"2026-04-27T21:48:35.641811Z","end":"2026-04-27T21:48:36.081728Z","steps":["trace[271306498] 'process raft request' (duration: 401.704633ms)","trace[271306498] 'compare' (duration: 37.833768ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:36.081857Z","caller":"traceutil/trace.go:171","msg":"trace[1780039334] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"411.839203ms","start":"2026-04-27T21:48:35.669987Z","end":"2026-04-27T21:48:36.081826Z","steps":["trace[1780039334] 'process raft request' (duration: 411.64909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:36.081943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.641796Z","time spent":"440.05473ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:36.082034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.669943Z","time spent":"411.990865ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:48:36.082264Z","caller":"traceutil/trace.go:171","msg":"trace[601344165] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"411.892803ms","start":"2026-04-27T21:48:35.67035Z","end":"2026-04-27T21:48:36.082242Z","steps":["trace[601344165] 'process raft request' (duration: 411.417955ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:36.082383Z","caller":"traceutil/trace.go:171","msg":"trace[467047192] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"411.968725ms","start":"2026-04-27T21:48:35.670407Z","end":"2026-04-27T21:48:36.082376Z","steps":["trace[467047192] 'process raft request' (duration: 411.68837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:36.082423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.670387Z","time spent":"412.016635ms","remote":"127.0.0.1:39832","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-04-27T21:48:36.08252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.67034Z","time spent":"412.041066ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:48:36.083145Z","caller":"traceutil/trace.go:171","msg":"trace[1167478025] linearizableReadLoop","detail":"{readStateIndex:25801; appliedIndex:25796; }","duration":"316.338194ms","start":"2026-04-27T21:48:35.766786Z","end":"2026-04-27T21:48:36.083125Z","steps":["trace[1167478025] 'read index received' (duration: 276.746613ms)","trace[1167478025] 'applied index is now lower than readState.Index' (duration: 39.590291ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:36.083182Z","caller":"traceutil/trace.go:171","msg":"trace[788726032] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"340.105509ms","start":"2026-04-27T21:48:35.743055Z","end":"2026-04-27T21:48:36.08316Z","steps":["trace[788726032] 'process raft request' (duration: 339.271003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:36.083267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.798545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:48:36.083289Z","caller":"traceutil/trace.go:171","msg":"trace[1112549231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24986; }","duration":"456.864245ms","start":"2026-04-27T21:48:35.626417Z","end":"2026-04-27T21:48:36.083281Z","steps":["trace[1112549231] 'agreement among raft nodes before linearized reading' (duration: 456.807844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:36.083309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.626391Z","time spent":"456.912677ms","remote":"127.0.0.1:39564","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-04-27T21:48:36.0833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.743015Z","time spent":"340.229981ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:36.08336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.572222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-27T21:48:36.083402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.250747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-04-27T21:48:36.083457Z","caller":"traceutil/trace.go:171","msg":"trace[115798839] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24986; }","duration":"304.360959ms","start":"2026-04-27T21:48:35.779082Z","end":"2026-04-27T21:48:36.083443Z","steps":["trace[115798839] 'agreement among raft nodes before linearized reading' (duration: 304.180866ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:36.083462Z","caller":"traceutil/trace.go:171","msg":"trace[1418398957] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:24986; }","duration":"329.739995ms","start":"2026-04-27T21:48:35.7537Z","end":"2026-04-27T21:48:36.08344Z","steps":["trace[1418398957] 'agreement among raft nodes before linearized reading' (duration: 329.48739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:36.083466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.35908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"warn","ts":"2026-04-27T21:48:36.083488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.779051Z","time spent":"304.429141ms","remote":"127.0.0.1:53498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5707,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-27T21:48:36.083525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.753681Z","time spent":"329.831986ms","remote":"127.0.0.1:39730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:48:36.083535Z","caller":"traceutil/trace.go:171","msg":"trace[1862442558] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24986; }","duration":"602.457442ms","start":"2026-04-27T21:48:35.481059Z","end":"2026-04-27T21:48:36.083517Z","steps":["trace[1862442558] 'agreement among raft nodes before linearized reading' (duration: 602.225938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:36.083587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:35.481044Z","time spent":"602.530533ms","remote":"127.0.0.1:39752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":1469,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-27T21:48:36.457704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.708824ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-27T21:48:36.997351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.322639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-27T21:48:36.997439Z","caller":"traceutil/trace.go:171","msg":"trace[331797550] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24987; }","duration":"154.462602ms","start":"2026-04-27T21:48:36.842955Z","end":"2026-04-27T21:48:36.997417Z","steps":["trace[331797550] 'count revisions from in-memory index tree' (duration: 154.190377ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.113338Z","caller":"traceutil/trace.go:171","msg":"trace[1662774737] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"107.114337ms","start":"2026-04-27T21:48:37.006192Z","end":"2026-04-27T21:48:37.113307Z","steps":["trace[1662774737] 'process raft request' (duration: 106.846791ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.113467Z","caller":"traceutil/trace.go:171","msg":"trace[1306360527] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"106.891082ms","start":"2026-04-27T21:48:37.00655Z","end":"2026-04-27T21:48:37.113441Z","steps":["trace[1306360527] 'process raft request' (duration: 106.650328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:37.491632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.151098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-27T21:48:37.491683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.644794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:48:37.491733Z","caller":"traceutil/trace.go:171","msg":"trace[1952653556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24990; }","duration":"279.294151ms","start":"2026-04-27T21:48:37.212417Z","end":"2026-04-27T21:48:37.491711Z","steps":["trace[1952653556] 'range keys from in-memory index tree' (duration: 278.997524ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.491753Z","caller":"traceutil/trace.go:171","msg":"trace[1682758242] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24990; }","duration":"164.751415ms","start":"2026-04-27T21:48:37.326981Z","end":"2026-04-27T21:48:37.491733Z","steps":["trace[1682758242] 'count revisions from in-memory index tree' (duration: 164.565382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:37.491962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.837194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:48:37.492003Z","caller":"traceutil/trace.go:171","msg":"trace[190810304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24990; }","duration":"197.878635ms","start":"2026-04-27T21:48:37.294115Z","end":"2026-04-27T21:48:37.491994Z","steps":["trace[190810304] 'range keys from in-memory index tree' (duration: 197.727252ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.658349Z","caller":"traceutil/trace.go:171","msg":"trace[1261317156] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"161.709439ms","start":"2026-04-27T21:48:37.496615Z","end":"2026-04-27T21:48:37.658325Z","steps":["trace[1261317156] 'process raft request' (duration: 161.546106ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.673704Z","caller":"traceutil/trace.go:171","msg":"trace[89523209] linearizableReadLoop","detail":"{readStateIndex:25812; appliedIndex:25806; }","duration":"168.637189ms","start":"2026-04-27T21:48:37.505046Z","end":"2026-04-27T21:48:37.673683Z","steps":["trace[89523209] 'read index received' (duration: 153.136309ms)","trace[89523209] 'applied index is now lower than readState.Index' (duration: 15.49922ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:37.673817Z","caller":"traceutil/trace.go:171","msg":"trace[442769729] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"175.809113ms","start":"2026-04-27T21:48:37.497999Z","end":"2026-04-27T21:48:37.673808Z","steps":["trace[442769729] 'process raft request' (duration: 175.602009ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.673825Z","caller":"traceutil/trace.go:171","msg":"trace[1519700114] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"175.604818ms","start":"2026-04-27T21:48:37.498209Z","end":"2026-04-27T21:48:37.673813Z","steps":["trace[1519700114] 'process raft request' (duration: 175.429165ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.673905Z","caller":"traceutil/trace.go:171","msg":"trace[1182081082] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"177.063816ms","start":"2026-04-27T21:48:37.496836Z","end":"2026-04-27T21:48:37.673899Z","steps":["trace[1182081082] 'process raft request' (duration: 176.529826ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.673951Z","caller":"traceutil/trace.go:171","msg":"trace[709580137] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"176.991045ms","start":"2026-04-27T21:48:37.496955Z","end":"2026-04-27T21:48:37.673946Z","steps":["trace[709580137] 'process raft request' (duration: 176.562887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:37.674116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.053916ms","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-04-27T21:48:37.674142Z","caller":"traceutil/trace.go:171","msg":"trace[979142308] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24996; }","duration":"169.094127ms","start":"2026-04-27T21:48:37.50504Z","end":"2026-04-27T21:48:37.674134Z","steps":["trace[979142308] 'agreement among raft nodes before linearized reading' (duration: 168.999625ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.674174Z","caller":"traceutil/trace.go:171","msg":"trace[1009588250] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"177.045485ms","start":"2026-04-27T21:48:37.497114Z","end":"2026-04-27T21:48:37.674159Z","steps":["trace[1009588250] 'process raft request' (duration: 176.451395ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:37.835711Z","caller":"traceutil/trace.go:171","msg":"trace[1084507314] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"156.974191ms","start":"2026-04-27T21:48:37.678701Z","end":"2026-04-27T21:48:37.835676Z","steps":["trace[1084507314] 'process raft request' (duration: 156.745366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:38.525089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.405177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:48:38.525349Z","caller":"traceutil/trace.go:171","msg":"trace[105925654] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25814; }","duration":"339.899455ms","start":"2026-04-27T21:48:38.185431Z","end":"2026-04-27T21:48:38.52533Z","steps":["trace[105925654] 'read index received' (duration: 48.126402ms)","trace[105925654] 'applied index is now lower than readState.Index' (duration: 291.771963ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:38.525442Z","caller":"traceutil/trace.go:171","msg":"trace[188422241] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"435.947783ms","start":"2026-04-27T21:48:38.089484Z","end":"2026-04-27T21:48:38.525432Z","steps":["trace[188422241] 'process raft request' (duration: 435.705989ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:38.525448Z","caller":"traceutil/trace.go:171","msg":"trace[887052719] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"436.394272ms","start":"2026-04-27T21:48:38.089023Z","end":"2026-04-27T21:48:38.525417Z","steps":["trace[887052719] 'process raft request' (duration: 144.557407ms)","trace[887052719] 'compare' (duration: 291.239884ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:48:38.525501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:38.089471Z","time spent":"435.994765ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:38.525594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:38.088994Z","time spent":"436.514834ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:48:38.525828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.530233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:48:38.525863Z","caller":"traceutil/trace.go:171","msg":"trace[979655814] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25001; }","duration":"194.608725ms","start":"2026-04-27T21:48:38.331242Z","end":"2026-04-27T21:48:38.525851Z","steps":["trace[979655814] 'agreement among raft nodes before linearized reading' (duration: 194.492082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:38.525965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.531798ms","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-04-27T21:48:38.526008Z","caller":"traceutil/trace.go:171","msg":"trace[1643754389] 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:25001; }","duration":"340.613369ms","start":"2026-04-27T21:48:38.185381Z","end":"2026-04-27T21:48:38.525994Z","steps":["trace[1643754389] 'agreement among raft nodes before linearized reading' (duration: 340.300213ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:38.526024Z","caller":"traceutil/trace.go:171","msg":"trace[1500114288] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"169.333581ms","start":"2026-04-27T21:48:38.35668Z","end":"2026-04-27T21:48:38.526014Z","steps":["trace[1500114288] 'process raft request' (duration: 168.615447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:38.526041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:48:38.185363Z","time spent":"340.668611ms","remote":"127.0.0.1:59380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-04-27T21:48:39.002575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.018661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:48:39.002729Z","caller":"traceutil/trace.go:171","msg":"trace[623106702] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"297.292047ms","start":"2026-04-27T21:48:38.705411Z","end":"2026-04-27T21:48:39.002703Z","steps":["trace[623106702] 'process raft request' (duration: 172.031592ms)","trace[623106702] 'compare' (duration: 124.839728ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:39.234877Z","caller":"traceutil/trace.go:171","msg":"trace[1009810840] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"145.332182ms","start":"2026-04-27T21:48:39.089485Z","end":"2026-04-27T21:48:39.234818Z","steps":["trace[1009810840] 'process raft request' (duration: 47.958689ms)","trace[1009810840] 'compare' (duration: 96.965106ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:39.234883Z","caller":"traceutil/trace.go:171","msg":"trace[1977690962] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"116.500391ms","start":"2026-04-27T21:48:39.118329Z","end":"2026-04-27T21:48:39.23483Z","steps":["trace[1977690962] 'process raft request' (duration: 116.302618ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:39.234925Z","caller":"traceutil/trace.go:171","msg":"trace[1173758190] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"113.800831ms","start":"2026-04-27T21:48:39.12108Z","end":"2026-04-27T21:48:39.234881Z","steps":["trace[1173758190] 'process raft request' (duration: 113.612638ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:39.849813Z","caller":"traceutil/trace.go:171","msg":"trace[104308536] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"140.863638ms","start":"2026-04-27T21:48:39.708909Z","end":"2026-04-27T21:48:39.849773Z","steps":["trace[104308536] 'process raft request' (duration: 127.676132ms)","trace[104308536] 'compare' (duration: 12.987143ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:39.869801Z","caller":"traceutil/trace.go:171","msg":"trace[954234449] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"160.76756ms","start":"2026-04-27T21:48:39.709007Z","end":"2026-04-27T21:48:39.869775Z","steps":["trace[954234449] 'process raft request' (duration: 160.593977ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:39.870188Z","caller":"traceutil/trace.go:171","msg":"trace[1637939699] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"161.071387ms","start":"2026-04-27T21:48:39.709082Z","end":"2026-04-27T21:48:39.870153Z","steps":["trace[1637939699] 'process raft request' (duration: 160.655958ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:39.870272Z","caller":"traceutil/trace.go:171","msg":"trace[2147037898] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"161.149247ms","start":"2026-04-27T21:48:39.709111Z","end":"2026-04-27T21:48:39.87026Z","steps":["trace[2147037898] 'process raft request' (duration: 160.838912ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:39.87052Z","caller":"traceutil/trace.go:171","msg":"trace[1732158039] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"161.327531ms","start":"2026-04-27T21:48:39.70918Z","end":"2026-04-27T21:48:39.870508Z","steps":["trace[1732158039] 'process raft request' (duration: 160.946814ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:40.652773Z","caller":"traceutil/trace.go:171","msg":"trace[1625120589] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"111.5684ms","start":"2026-04-27T21:48:40.54118Z","end":"2026-04-27T21:48:40.652748Z","steps":["trace[1625120589] 'process raft request' (duration: 71.362017ms)","trace[1625120589] 'compare' (duration: 40.070491ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:42.797051Z","caller":"traceutil/trace.go:171","msg":"trace[2079383040] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"138.587726ms","start":"2026-04-27T21:48:42.658436Z","end":"2026-04-27T21:48:42.797024Z","steps":["trace[2079383040] 'process raft request' (duration: 138.416023ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:09.210463Z","caller":"traceutil/trace.go:171","msg":"trace[19782271] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"286.932903ms","start":"2026-04-27T21:49:08.923502Z","end":"2026-04-27T21:49:09.210435Z","steps":["trace[19782271] 'process raft request' (duration: 286.724379ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:09.318938Z","caller":"traceutil/trace.go:171","msg":"trace[1164496539] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"101.904568ms","start":"2026-04-27T21:49:09.216951Z","end":"2026-04-27T21:49:09.318856Z","steps":["trace[1164496539] 'process raft request' (duration: 39.299886ms)","trace[1164496539] 'compare' (duration: 62.121113ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:09.749914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.630646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:49:09.750074Z","caller":"traceutil/trace.go:171","msg":"trace[1441434500] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"426.217032ms","start":"2026-04-27T21:49:09.323829Z","end":"2026-04-27T21:49:09.750046Z","steps":["trace[1441434500] 'process raft request' (duration: 162.357241ms)","trace[1441434500] 'compare' (duration: 263.478504ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:09.750164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:09.32381Z","time spent":"426.308403ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:09.774375Z","caller":"traceutil/trace.go:171","msg":"trace[1046175669] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"362.066622ms","start":"2026-04-27T21:49:09.412275Z","end":"2026-04-27T21:49:09.774341Z","steps":["trace[1046175669] 'process raft request' (duration: 361.715565ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:09.774403Z","caller":"traceutil/trace.go:171","msg":"trace[76475825] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"361.98201ms","start":"2026-04-27T21:49:09.412379Z","end":"2026-04-27T21:49:09.774361Z","steps":["trace[76475825] 'process raft request' (duration: 361.843387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:09.774535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:09.41225Z","time spent":"362.200704ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:09.774592Z","caller":"traceutil/trace.go:171","msg":"trace[1722913728] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"362.123282ms","start":"2026-04-27T21:49:09.412435Z","end":"2026-04-27T21:49:09.774558Z","steps":["trace[1722913728] 'process raft request' (duration: 361.846417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:09.774649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:09.412371Z","time spent":"362.132442ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:49:09.774727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:09.412428Z","time spent":"362.231254ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:09.945303Z","caller":"traceutil/trace.go:171","msg":"trace[581118075] linearizableReadLoop","detail":"{readStateIndex:26203; appliedIndex:26202; }","duration":"320.808517ms","start":"2026-04-27T21:49:09.624452Z","end":"2026-04-27T21:49:09.94526Z","steps":["trace[581118075] 'read index received' (duration: 150.437676ms)","trace[581118075] 'applied index is now lower than readState.Index' (duration: 170.369651ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:09.945353Z","caller":"traceutil/trace.go:171","msg":"trace[1776006033] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"365.331671ms","start":"2026-04-27T21:49:09.579996Z","end":"2026-04-27T21:49:09.945327Z","steps":["trace[1776006033] 'process raft request' (duration: 311.675277ms)","trace[1776006033] 'compare' (duration: 53.458781ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:09.94549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:09.579971Z","time spent":"365.433704ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:49:09.945476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.008701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:49:09.945554Z","caller":"traceutil/trace.go:171","msg":"trace[426312183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25380; }","duration":"321.136653ms","start":"2026-04-27T21:49:09.624398Z","end":"2026-04-27T21:49:09.945534Z","steps":["trace[426312183] 'agreement among raft nodes before linearized reading' (duration: 320.99481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:09.9456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:09.624378Z","time spent":"321.213805ms","remote":"127.0.0.1:39564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:50:39.100138Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069ab.wal"} {"level":"info","ts":"2026-04-27T21:50:48.065412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23425} {"level":"info","ts":"2026-04-27T21:50:48.213494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23425,"took":"144.502006ms","hash":2449207697,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":37019648,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-27T21:50:48.213555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2449207697,"revision":23425,"compact-revision":20127} {"level":"info","ts":"2026-04-27T21:55:48.071544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26289} {"level":"info","ts":"2026-04-27T21:55:48.21601Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26289,"took":"139.501923ms","hash":4219852291,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":33001472,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-27T21:55:48.216087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4219852291,"revision":26289,"compact-revision":23425} {"level":"info","ts":"2026-04-27T21:56:18.244321Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:56:18.251558Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-27T21:56:18.251688Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-27T22:00:48.078105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28859} {"level":"info","ts":"2026-04-27T22:00:48.232229Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28859,"took":"149.557843ms","hash":2464668562,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":30736384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-27T22:00:48.232339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2464668562,"revision":28859,"compact-revision":26289} {"level":"info","ts":"2026-04-27T22:05:48.083927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31146} {"level":"info","ts":"2026-04-27T22:05:48.213463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31146,"took":"125.059703ms","hash":3143128030,"current-db-size-bytes":57638912,"current-db-size":"58 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-27T22:05:48.213551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3143128030,"revision":31146,"compact-revision":28859} {"level":"info","ts":"2026-04-27T22:10:48.090437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33498} {"level":"info","ts":"2026-04-27T22:10:48.241764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33498,"took":"146.374752ms","hash":1804541218,"current-db-size-bytes":58683392,"current-db-size":"59 MB","current-db-size-in-use-bytes":32714752,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-27T22:10:48.24188Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1804541218,"revision":33498,"compact-revision":31146} {"level":"info","ts":"2026-04-27T22:15:48.097224Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35736} {"level":"info","ts":"2026-04-27T22:15:48.244587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35736,"took":"142.009901ms","hash":3335990863,"current-db-size-bytes":58683392,"current-db-size":"59 MB","current-db-size-in-use-bytes":31330304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-27T22:15:48.244699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3335990863,"revision":35736,"compact-revision":33498} {"level":"info","ts":"2026-04-27T22:17:34.430617Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db79120667ce701f","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T22:17:34.436365Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-27T22:17:34.436536Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}