{"level":"warn","ts":"2026-02-25T19:30:15.095279Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T19:30:15.09537Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229: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.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229: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-02-25T19:30:15.095443Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T19:30:15.095456Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-02-25T19:30:15.095473Z","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-02-25T19:30:15.096104Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-02-25T19:30:15.096177Z","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.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229: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-02-25T19:30:15.103193Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.772686ms"} {"level":"info","ts":"2026-02-25T19:30:15.11498Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-02-25T19:30:15.115129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:30:15.115156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-02-25T19:30:15.115175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:30:15.115193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-02-25T19:30:15.115249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-02-25T19:30:15.121755Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:30:15.126106Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:30:15.127395Z","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-02-25T19:30:15.130151Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:30:15.130278Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:30:15.130543Z","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-02-25T19:30:15.13068Z","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-02-25T19:30:15.130699Z","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-02-25T19:30:15.131341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-02-25T19:30:15.131498Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-02-25T19:30:15.132918Z","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-02-25T19:30:15.133054Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-02-25T19:30:15.133103Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-02-25T19:30:15.133216Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:30:15.133258Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:30:15.916535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:30:15.916626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:30:15.916669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-02-25T19:30:15.916691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:30:15.916703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-02-25T19:30:15.916726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-02-25T19:30:15.916888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-02-25T19:30:15.918745Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:30:15.918751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:30:15.918821Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:30:15.91915Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:30:15.919342Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:30:15.919446Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:30:15.920493Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:30:15.920669Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:30:15.920719Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:30:15.920855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-02-25T19:30:15.921304Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:30:31.80026Z","caller":"traceutil/trace.go:171","msg":"trace[68462347] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"100.490077ms","start":"2026-02-25T19:30:31.699731Z","end":"2026-02-25T19:30:31.800221Z","steps":["trace[68462347] 'read index received' (duration: 100.143407ms)","trace[68462347] 'applied index is now lower than readState.Index' (duration: 345.5µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:31.800385Z","caller":"traceutil/trace.go:171","msg":"trace[1315132341] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"138.126827ms","start":"2026-02-25T19:30:31.662215Z","end":"2026-02-25T19:30:31.800342Z","steps":["trace[1315132341] 'process raft request' (duration: 137.746986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:31.800667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.804126ms","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-02-25T19:30:31.800903Z","caller":"traceutil/trace.go:171","msg":"trace[1390074388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"101.205527ms","start":"2026-02-25T19:30:31.699663Z","end":"2026-02-25T19:30:31.800869Z","steps":["trace[1390074388] 'agreement among raft nodes before linearized reading' (duration: 100.739403ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:54.65703Z","caller":"traceutil/trace.go:171","msg":"trace[320515096] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"106.041505ms","start":"2026-02-25T19:30:54.550958Z","end":"2026-02-25T19:30:54.656999Z","steps":["trace[320515096] 'read index received' (duration: 101.356144ms)","trace[320515096] 'applied index is now lower than readState.Index' (duration: 4.684291ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:54.65717Z","caller":"traceutil/trace.go:171","msg":"trace[2077043811] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"110.280696ms","start":"2026-02-25T19:30:54.546878Z","end":"2026-02-25T19:30:54.657159Z","steps":["trace[2077043811] 'process raft request' (duration: 105.512622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:54.657387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.388436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:54.657431Z","caller":"traceutil/trace.go:171","msg":"trace[1696425226] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:590; }","duration":"106.499809ms","start":"2026-02-25T19:30:54.550918Z","end":"2026-02-25T19:30:54.657418Z","steps":["trace[1696425226] 'agreement among raft nodes before linearized reading' (duration: 106.395796ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:54.660209Z","caller":"traceutil/trace.go:171","msg":"trace[402759860] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"108.266268ms","start":"2026-02-25T19:30:54.551919Z","end":"2026-02-25T19:30:54.660186Z","steps":["trace[402759860] 'process raft request' (duration: 108.133405ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:54.660272Z","caller":"traceutil/trace.go:171","msg":"trace[1771699818] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"108.826804ms","start":"2026-02-25T19:30:54.551423Z","end":"2026-02-25T19:30:54.66025Z","steps":["trace[1771699818] 'process raft request' (duration: 108.536386ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:54.660413Z","caller":"traceutil/trace.go:171","msg":"trace[1991186978] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"108.387771ms","start":"2026-02-25T19:30:54.551981Z","end":"2026-02-25T19:30:54.660368Z","steps":["trace[1991186978] 'process raft request' (duration: 108.117294ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:54.660522Z","caller":"traceutil/trace.go:171","msg":"trace[1325527546] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"106.819397ms","start":"2026-02-25T19:30:54.553694Z","end":"2026-02-25T19:30:54.660514Z","steps":["trace[1325527546] 'process raft request' (duration: 106.443377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:54.660819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.42483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:54.660873Z","caller":"traceutil/trace.go:171","msg":"trace[757815556] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:595; }","duration":"109.507883ms","start":"2026-02-25T19:30:54.551347Z","end":"2026-02-25T19:30:54.660855Z","steps":["trace[757815556] 'agreement among raft nodes before linearized reading' (duration: 109.387049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:54.660868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.823221ms","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:5"} {"level":"info","ts":"2026-02-25T19:30:54.661107Z","caller":"traceutil/trace.go:171","msg":"trace[324920260] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:595; }","duration":"110.104039ms","start":"2026-02-25T19:30:54.550986Z","end":"2026-02-25T19:30:54.66109Z","steps":["trace[324920260] 'agreement among raft nodes before linearized reading' (duration: 109.677477ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:23.707588Z","caller":"traceutil/trace.go:171","msg":"trace[2117308801] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"102.828696ms","start":"2026-02-25T19:31:23.60473Z","end":"2026-02-25T19:31:23.707559Z","steps":["trace[2117308801] 'process raft request' (duration: 102.64319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:24.027454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.771323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:31:24.027487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.090082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:31:24.027555Z","caller":"traceutil/trace.go:171","msg":"trace[1933460291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"216.916547ms","start":"2026-02-25T19:31:23.810617Z","end":"2026-02-25T19:31:24.027533Z","steps":["trace[1933460291] 'range keys from in-memory index tree' (duration: 216.550087ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:24.027599Z","caller":"traceutil/trace.go:171","msg":"trace[1020599267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"217.261687ms","start":"2026-02-25T19:31:23.810312Z","end":"2026-02-25T19:31:24.027573Z","steps":["trace[1020599267] 'range keys from in-memory index tree' (duration: 216.910387ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:11.055199Z","caller":"traceutil/trace.go:171","msg":"trace[437785612] linearizableReadLoop","detail":"{readStateIndex:1634; appliedIndex:1633; }","duration":"139.893095ms","start":"2026-02-25T19:32:10.91528Z","end":"2026-02-25T19:32:11.055173Z","steps":["trace[437785612] 'read index received' (duration: 139.68996ms)","trace[437785612] 'applied index is now lower than readState.Index' (duration: 201.975µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:11.055473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.174963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T19:32:11.055576Z","caller":"traceutil/trace.go:171","msg":"trace[142444654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1567; }","duration":"140.343758ms","start":"2026-02-25T19:32:10.915212Z","end":"2026-02-25T19:32:11.055555Z","steps":["trace[142444654] 'agreement among raft nodes before linearized reading' (duration: 140.06302ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:11.05571Z","caller":"traceutil/trace.go:171","msg":"trace[1434665843] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"200.064578ms","start":"2026-02-25T19:32:10.855187Z","end":"2026-02-25T19:32:11.055251Z","steps":["trace[1434665843] 'process raft request' (duration: 199.798811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:20.747643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.524093ms","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-02-25T19:32:20.747735Z","caller":"traceutil/trace.go:171","msg":"trace[728726047] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1651; }","duration":"269.671597ms","start":"2026-02-25T19:32:20.478033Z","end":"2026-02-25T19:32:20.747705Z","steps":["trace[728726047] 'range keys from in-memory index tree' (duration: 269.295276ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:20.747929Z","caller":"traceutil/trace.go:171","msg":"trace[537425729] linearizableReadLoop","detail":"{readStateIndex:1722; appliedIndex:1721; }","duration":"125.322917ms","start":"2026-02-25T19:32:20.622584Z","end":"2026-02-25T19:32:20.747906Z","steps":["trace[537425729] 'read index received' (duration: 45.151033ms)","trace[537425729] 'applied index is now lower than readState.Index' (duration: 80.170634ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:20.747951Z","caller":"traceutil/trace.go:171","msg":"trace[526234944] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"137.438996ms","start":"2026-02-25T19:32:20.610494Z","end":"2026-02-25T19:32:20.747933Z","steps":["trace[526234944] 'process raft request' (duration: 57.2007ms)","trace[526234944] 'compare' (duration: 79.998059ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:20.748152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.575255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-02-25T19:32:20.748221Z","caller":"traceutil/trace.go:171","msg":"trace[1457230863] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1652; }","duration":"125.715738ms","start":"2026-02-25T19:32:20.622485Z","end":"2026-02-25T19:32:20.748201Z","steps":["trace[1457230863] 'agreement among raft nodes before linearized reading' (duration: 125.505243ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.17219Z","caller":"traceutil/trace.go:171","msg":"trace[644034709] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"161.855324ms","start":"2026-02-25T19:33:01.010308Z","end":"2026-02-25T19:33:01.172164Z","steps":["trace[644034709] 'process raft request' (duration: 161.643549ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.460495Z","caller":"traceutil/trace.go:171","msg":"trace[1555477570] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1946; }","duration":"374.779242ms","start":"2026-02-25T19:33:01.085689Z","end":"2026-02-25T19:33:01.460469Z","steps":["trace[1555477570] 'read index received' (duration: 86.377349ms)","trace[1555477570] 'applied index is now lower than readState.Index' (duration: 288.400723ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:01.460561Z","caller":"traceutil/trace.go:171","msg":"trace[638007476] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"448.985342ms","start":"2026-02-25T19:33:01.011538Z","end":"2026-02-25T19:33:01.460524Z","steps":["trace[638007476] 'process raft request' (duration: 448.733965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.46086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.125663ms","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-02-25T19:33:01.460978Z","caller":"traceutil/trace.go:171","msg":"trace[1467499546] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1869; }","duration":"375.319258ms","start":"2026-02-25T19:33:01.085629Z","end":"2026-02-25T19:33:01.460948Z","steps":["trace[1467499546] 'agreement among raft nodes before linearized reading' (duration: 374.961818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.46104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:01.085602Z","time spent":"375.427241ms","remote":"127.0.0.1:34760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-25T19:33:01.461431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:01.011511Z","time spent":"449.130326ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:01.683311Z","caller":"traceutil/trace.go:171","msg":"trace[1839633346] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"193.084835ms","start":"2026-02-25T19:33:01.490195Z","end":"2026-02-25T19:33:01.68328Z","steps":["trace[1839633346] 'process raft request' (duration: 172.820709ms)","trace[1839633346] 'compare' (duration: 19.930276ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:46.078204Z","caller":"traceutil/trace.go:171","msg":"trace[819892725] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"173.697935ms","start":"2026-02-25T19:33:45.904464Z","end":"2026-02-25T19:33:46.078162Z","steps":["trace[819892725] 'process raft request' (duration: 173.371956ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:34:18.731469Z","caller":"traceutil/trace.go:171","msg":"trace[805867587] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"103.534435ms","start":"2026-02-25T19:34:18.627907Z","end":"2026-02-25T19:34:18.731442Z","steps":["trace[805867587] 'process raft request' (duration: 103.3574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:34:19.296977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.813767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.1897944d894f1000\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-02-25T19:34:19.297171Z","caller":"traceutil/trace.go:171","msg":"trace[2143372645] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.1897944d894f1000; range_end:; response_count:1; response_revision:2300; }","duration":"164.077315ms","start":"2026-02-25T19:34:19.133068Z","end":"2026-02-25T19:34:19.297145Z","steps":["trace[2143372645] 'range keys from in-memory index tree' (duration: 163.57134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:34:24.42473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.965169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:34:24.424884Z","caller":"traceutil/trace.go:171","msg":"trace[351336272] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"166.126021ms","start":"2026-02-25T19:34:24.258731Z","end":"2026-02-25T19:34:24.424857Z","steps":["trace[351336272] 'process raft request' (duration: 41.737421ms)","trace[351336272] 'compare' (duration: 123.835895ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:37:10.191991Z","caller":"traceutil/trace.go:171","msg":"trace[1703271912] transaction","detail":"{read_only:false; response_revision:3303; number_of_response:1; }","duration":"116.448788ms","start":"2026-02-25T19:37:10.075516Z","end":"2026-02-25T19:37:10.191965Z","steps":["trace[1703271912] 'process raft request' (duration: 116.302734ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:37:10.283204Z","caller":"traceutil/trace.go:171","msg":"trace[1560583941] transaction","detail":"{read_only:false; response_revision:3305; number_of_response:1; }","duration":"206.899713ms","start":"2026-02-25T19:37:10.076271Z","end":"2026-02-25T19:37:10.283171Z","steps":["trace[1560583941] 'process raft request' (duration: 206.836481ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:37:10.283283Z","caller":"traceutil/trace.go:171","msg":"trace[29101162] transaction","detail":"{read_only:false; response_revision:3304; number_of_response:1; }","duration":"207.030957ms","start":"2026-02-25T19:37:10.076217Z","end":"2026-02-25T19:37:10.283248Z","steps":["trace[29101162] 'process raft request' (duration: 206.486722ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:25.935039Z","caller":"traceutil/trace.go:171","msg":"trace[1412688574] transaction","detail":"{read_only:false; response_revision:4210; number_of_response:1; }","duration":"124.511569ms","start":"2026-02-25T19:38:25.810487Z","end":"2026-02-25T19:38:25.934999Z","steps":["trace[1412688574] 'process raft request' (duration: 73.567219ms)","trace[1412688574] 'compare' (duration: 50.668003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:38:31.183713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.730154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:38:31.18402Z","caller":"traceutil/trace.go:171","msg":"trace[2109101899] linearizableReadLoop","detail":"{readStateIndex:4589; appliedIndex:4587; }","duration":"477.120456ms","start":"2026-02-25T19:38:30.706882Z","end":"2026-02-25T19:38:31.184002Z","steps":["trace[2109101899] 'read index received' (duration: 247.100915ms)","trace[2109101899] 'applied index is now lower than readState.Index' (duration: 230.018401ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:38:31.184124Z","caller":"traceutil/trace.go:171","msg":"trace[1408616937] transaction","detail":"{read_only:false; response_revision:4408; number_of_response:1; }","duration":"482.495879ms","start":"2026-02-25T19:38:30.701617Z","end":"2026-02-25T19:38:31.184113Z","steps":["trace[1408616937] 'process raft request' (duration: 482.20541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.184197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:30.701598Z","time spent":"482.559421ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:38:31.184234Z","caller":"traceutil/trace.go:171","msg":"trace[1607239810] transaction","detail":"{read_only:false; response_revision:4409; number_of_response:1; }","duration":"475.1319ms","start":"2026-02-25T19:38:30.709074Z","end":"2026-02-25T19:38:31.184206Z","steps":["trace[1607239810] 'process raft request' (duration: 474.892093ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:31.184Z","caller":"traceutil/trace.go:171","msg":"trace[584327630] transaction","detail":"{read_only:false; response_revision:4407; number_of_response:1; }","duration":"485.520905ms","start":"2026-02-25T19:38:30.698447Z","end":"2026-02-25T19:38:31.183968Z","steps":["trace[584327630] 'process raft request' (duration: 255.446131ms)","trace[584327630] 'compare' (duration: 229.524638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:38:31.184391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:30.709063Z","time spent":"475.244993ms","remote":"127.0.0.1:43050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:38:31.184492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.729939ms","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-02-25T19:38:31.184566Z","caller":"traceutil/trace.go:171","msg":"trace[436255117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4408; }","duration":"480.807651ms","start":"2026-02-25T19:38:30.703743Z","end":"2026-02-25T19:38:31.18455Z","steps":["trace[436255117] 'agreement among raft nodes before linearized reading' (duration: 480.394209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.184649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.730174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-02-25T19:38:31.184783Z","caller":"traceutil/trace.go:171","msg":"trace[43941957] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4409; }","duration":"171.915999ms","start":"2026-02-25T19:38:31.012836Z","end":"2026-02-25T19:38:31.184752Z","steps":["trace[43941957] 'agreement among raft nodes before linearized reading' (duration: 171.484857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.184764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.233838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T19:38:31.184714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:30.703713Z","time spent":"480.993166ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:38:31.184865Z","caller":"traceutil/trace.go:171","msg":"trace[272927859] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:4409; }","duration":"103.372932ms","start":"2026-02-25T19:38:31.081463Z","end":"2026-02-25T19:38:31.184836Z","steps":["trace[272927859] 'agreement among raft nodes before linearized reading' (duration: 103.175846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.184847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.591398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-25T19:38:31.184653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.891204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:38:31.18496Z","caller":"traceutil/trace.go:171","msg":"trace[1158973060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4408; }","duration":"496.778493ms","start":"2026-02-25T19:38:30.688159Z","end":"2026-02-25T19:38:31.184937Z","steps":["trace[1158973060] 'agreement among raft nodes before linearized reading' (duration: 495.95516ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:31.184995Z","caller":"traceutil/trace.go:171","msg":"trace[1327400482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4409; }","duration":"203.249484ms","start":"2026-02-25T19:38:30.981709Z","end":"2026-02-25T19:38:31.184958Z","steps":["trace[1327400482] 'agreement among raft nodes before linearized reading' (duration: 202.905255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.185019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:30.688136Z","time spent":"496.871996ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:38:31.184639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:38:30.698426Z","time spent":"485.943706ms","remote":"127.0.0.1:43264","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-02-25T19:38:31.185813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.317417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:753815"} {"level":"info","ts":"2026-02-25T19:38:31.185907Z","caller":"traceutil/trace.go:171","msg":"trace[1588234699] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4409; }","duration":"273.41485ms","start":"2026-02-25T19:38:30.912445Z","end":"2026-02-25T19:38:31.18586Z","steps":["trace[1588234699] 'agreement among raft nodes before linearized reading' (duration: 271.829484ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:31.463694Z","caller":"traceutil/trace.go:171","msg":"trace[323668037] transaction","detail":"{read_only:false; response_revision:4411; number_of_response:1; }","duration":"274.055387ms","start":"2026-02-25T19:38:31.189614Z","end":"2026-02-25T19:38:31.46367Z","steps":["trace[323668037] 'process raft request' (duration: 273.863142ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:31.463894Z","caller":"traceutil/trace.go:171","msg":"trace[1770416618] linearizableReadLoop","detail":"{readStateIndex:4593; appliedIndex:4591; }","duration":"201.478935ms","start":"2026-02-25T19:38:31.262381Z","end":"2026-02-25T19:38:31.463859Z","steps":["trace[1770416618] 'read index received' (duration: 201.031653ms)","trace[1770416618] 'applied index is now lower than readState.Index' (duration: 442.592µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:38:31.463953Z","caller":"traceutil/trace.go:171","msg":"trace[171349445] transaction","detail":"{read_only:false; response_revision:4412; number_of_response:1; }","duration":"273.600114ms","start":"2026-02-25T19:38:31.190341Z","end":"2026-02-25T19:38:31.463941Z","steps":["trace[171349445] 'process raft request' (duration: 273.281525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.464645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.251697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4qclb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-25T19:38:31.464691Z","caller":"traceutil/trace.go:171","msg":"trace[1502358386] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4qclb; range_end:; response_count:1; response_revision:4412; }","duration":"202.392481ms","start":"2026-02-25T19:38:31.262288Z","end":"2026-02-25T19:38:31.464681Z","steps":["trace[1502358386] 'agreement among raft nodes before linearized reading' (duration: 201.98988ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:31.612364Z","caller":"traceutil/trace.go:171","msg":"trace[1884182143] linearizableReadLoop","detail":"{readStateIndex:4594; appliedIndex:4593; }","duration":"129.563842ms","start":"2026-02-25T19:38:31.482774Z","end":"2026-02-25T19:38:31.612338Z","steps":["trace[1884182143] 'read index received' (duration: 115.654639ms)","trace[1884182143] 'applied index is now lower than readState.Index' (duration: 13.904803ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:38:31.612446Z","caller":"traceutil/trace.go:171","msg":"trace[1449973036] transaction","detail":"{read_only:false; response_revision:4413; number_of_response:1; }","duration":"136.834358ms","start":"2026-02-25T19:38:31.475567Z","end":"2026-02-25T19:38:31.612401Z","steps":["trace[1449973036] 'process raft request' (duration: 122.832983ms)","trace[1449973036] 'compare' (duration: 13.80687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:38:31.612775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.889257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ec28e0b-9e0c-4fac-9201-a4cf4f1898ee\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-02-25T19:38:31.612904Z","caller":"traceutil/trace.go:171","msg":"trace[1114398092] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ec28e0b-9e0c-4fac-9201-a4cf4f1898ee; range_end:; response_count:1; response_revision:4413; }","duration":"115.060233ms","start":"2026-02-25T19:38:31.497819Z","end":"2026-02-25T19:38:31.612879Z","steps":["trace[1114398092] 'agreement among raft nodes before linearized reading' (duration: 114.712173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:31.612957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.151279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:753815"} {"level":"info","ts":"2026-02-25T19:38:31.61301Z","caller":"traceutil/trace.go:171","msg":"trace[1541343101] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4413; }","duration":"130.240441ms","start":"2026-02-25T19:38:31.482754Z","end":"2026-02-25T19:38:31.612995Z","steps":["trace[1541343101] 'agreement among raft nodes before linearized reading' (duration: 129.740007ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:41.62763Z","caller":"traceutil/trace.go:171","msg":"trace[1449300711] linearizableReadLoop","detail":"{readStateIndex:4770; appliedIndex:4769; }","duration":"113.082596ms","start":"2026-02-25T19:38:41.514524Z","end":"2026-02-25T19:38:41.627606Z","steps":["trace[1449300711] 'read index received' (duration: 112.981383ms)","trace[1449300711] 'applied index is now lower than readState.Index' (duration: 100.233µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:38:41.627689Z","caller":"traceutil/trace.go:171","msg":"trace[944405460] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"115.798863ms","start":"2026-02-25T19:38:41.511861Z","end":"2026-02-25T19:38:41.62766Z","steps":["trace[944405460] 'process raft request' (duration: 115.599957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:38:41.627862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.322823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4qclb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-25T19:38:41.627908Z","caller":"traceutil/trace.go:171","msg":"trace[1455479497] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4qclb; range_end:; response_count:1; response_revision:4582; }","duration":"113.424065ms","start":"2026-02-25T19:38:41.514471Z","end":"2026-02-25T19:38:41.627895Z","steps":["trace[1455479497] 'agreement among raft nodes before linearized reading' (duration: 113.265461ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:41.628336Z","caller":"traceutil/trace.go:171","msg":"trace[1011831494] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"112.895811ms","start":"2026-02-25T19:38:41.515423Z","end":"2026-02-25T19:38:41.628319Z","steps":["trace[1011831494] 'process raft request' (duration: 112.782218ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:42.958735Z","caller":"traceutil/trace.go:171","msg":"trace[927274023] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"106.011447ms","start":"2026-02-25T19:38:42.852698Z","end":"2026-02-25T19:38:42.958709Z","steps":["trace[927274023] 'process raft request' (duration: 105.924245ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:50.405732Z","caller":"traceutil/trace.go:171","msg":"trace[734758022] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"120.019483ms","start":"2026-02-25T19:38:50.28569Z","end":"2026-02-25T19:38:50.405709Z","steps":["trace[734758022] 'process raft request' (duration: 119.874938ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:38:50.426499Z","caller":"traceutil/trace.go:171","msg":"trace[992989543] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"110.757041ms","start":"2026-02-25T19:38:50.315713Z","end":"2026-02-25T19:38:50.42647Z","steps":["trace[992989543] 'process raft request' (duration: 110.576616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:39:00.07644Z","caller":"traceutil/trace.go:171","msg":"trace[55584793] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"122.355219ms","start":"2026-02-25T19:38:59.954069Z","end":"2026-02-25T19:39:00.076424Z","steps":["trace[55584793] 'process raft request' (duration: 122.226395ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:39:04.09645Z","caller":"traceutil/trace.go:171","msg":"trace[598399605] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5147; }","duration":"109.115815ms","start":"2026-02-25T19:39:03.987308Z","end":"2026-02-25T19:39:04.096424Z","steps":["trace[598399605] 'read index received' (duration: 108.892359ms)","trace[598399605] 'applied index is now lower than readState.Index' (duration: 222.646µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:39:04.096492Z","caller":"traceutil/trace.go:171","msg":"trace[1820598874] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"111.741829ms","start":"2026-02-25T19:39:03.984725Z","end":"2026-02-25T19:39:04.096467Z","steps":["trace[1820598874] 'process raft request' (duration: 111.539644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:39:04.09687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.517396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-02-25T19:39:04.097195Z","caller":"traceutil/trace.go:171","msg":"trace[1525777609] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4951; }","duration":"109.914307ms","start":"2026-02-25T19:39:03.987255Z","end":"2026-02-25T19:39:04.09717Z","steps":["trace[1525777609] 'agreement among raft nodes before linearized reading' (duration: 109.380332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:39:04.526753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.005936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:39:04.526891Z","caller":"traceutil/trace.go:171","msg":"trace[644246755] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"423.45365ms","start":"2026-02-25T19:39:04.103416Z","end":"2026-02-25T19:39:04.52687Z","steps":["trace[644246755] 'process raft request' (duration: 248.246898ms)","trace[644246755] 'compare' (duration: 174.898384ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:39:04.526957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:39:04.103389Z","time spent":"423.536743ms","remote":"127.0.0.1:43146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:39:04.541259Z","caller":"traceutil/trace.go:171","msg":"trace[178767202] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"437.384194ms","start":"2026-02-25T19:39:04.103848Z","end":"2026-02-25T19:39:04.541233Z","steps":["trace[178767202] 'process raft request' (duration: 437.166658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:39:04.541427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:39:04.103837Z","time spent":"437.501227ms","remote":"127.0.0.1:43146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:39:04.541726Z","caller":"traceutil/trace.go:171","msg":"trace[1103107586] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"437.737055ms","start":"2026-02-25T19:39:04.103964Z","end":"2026-02-25T19:39:04.541701Z","steps":["trace[1103107586] 'process raft request' (duration: 437.205929ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:39:04.541752Z","caller":"traceutil/trace.go:171","msg":"trace[539744811] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5148; }","duration":"434.157592ms","start":"2026-02-25T19:39:04.107558Z","end":"2026-02-25T19:39:04.541715Z","steps":["trace[539744811] 'read index received' (duration: 244.11542ms)","trace[539744811] 'applied index is now lower than readState.Index' (duration: 190.041272ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:39:04.541844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:39:04.103939Z","time spent":"437.844647ms","remote":"127.0.0.1:43270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:39:04.541859Z","caller":"traceutil/trace.go:171","msg":"trace[1080182845] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"435.81995ms","start":"2026-02-25T19:39:04.106027Z","end":"2026-02-25T19:39:04.541847Z","steps":["trace[1080182845] 'process raft request' (duration: 435.546372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:39:04.542001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:39:04.106012Z","time spent":"435.896783ms","remote":"127.0.0.1:43270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:39:04.542047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.464501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T19:39:04.54212Z","caller":"traceutil/trace.go:171","msg":"trace[262108404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4955; }","duration":"434.561884ms","start":"2026-02-25T19:39:04.107541Z","end":"2026-02-25T19:39:04.542103Z","steps":["trace[262108404] 'agreement among raft nodes before linearized reading' (duration: 434.361328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:39:04.542161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:39:04.107535Z","time spent":"434.617255ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:39:04.590289Z","caller":"traceutil/trace.go:171","msg":"trace[1147214652] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"106.1319ms","start":"2026-02-25T19:39:04.484121Z","end":"2026-02-25T19:39:04.590253Z","steps":["trace[1147214652] 'process raft request' (duration: 105.940775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:39:04.5903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.422759ms","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-02-25T19:39:04.590379Z","caller":"traceutil/trace.go:171","msg":"trace[1285498905] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4956; }","duration":"100.533532ms","start":"2026-02-25T19:39:04.489829Z","end":"2026-02-25T19:39:04.590363Z","steps":["trace[1285498905] 'agreement among raft nodes before linearized reading' (duration: 100.333177ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:40:15.947593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2572} {"level":"info","ts":"2026-02-25T19:40:16.003631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2572,"took":"54.777207ms","hash":4213098056,"current-db-size-bytes":40976384,"current-db-size":"41 MB","current-db-size-in-use-bytes":30031872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-25T19:40:16.003704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4213098056,"revision":2572,"compact-revision":-1} {"level":"info","ts":"2026-02-25T19:44:48.744492Z","caller":"traceutil/trace.go:171","msg":"trace[697567167] linearizableReadLoop","detail":"{readStateIndex:7698; appliedIndex:7697; }","duration":"239.19785ms","start":"2026-02-25T19:44:48.505266Z","end":"2026-02-25T19:44:48.744464Z","steps":["trace[697567167] 'read index received' (duration: 239.099287ms)","trace[697567167] 'applied index is now lower than readState.Index' (duration: 97.413µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:44:48.744601Z","caller":"traceutil/trace.go:171","msg":"trace[587388280] transaction","detail":"{read_only:false; response_revision:7418; number_of_response:1; }","duration":"283.243915ms","start":"2026-02-25T19:44:48.461306Z","end":"2026-02-25T19:44:48.74455Z","steps":["trace[587388280] 'process raft request' (duration: 283.003568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:44:48.744695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.396965ms","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-02-25T19:44:48.744735Z","caller":"traceutil/trace.go:171","msg":"trace[438664341] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7418; }","duration":"239.493298ms","start":"2026-02-25T19:44:48.505229Z","end":"2026-02-25T19:44:48.744722Z","steps":["trace[438664341] 'agreement among raft nodes before linearized reading' (duration: 239.336303ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:44:48.893634Z","caller":"traceutil/trace.go:171","msg":"trace[197015000] transaction","detail":"{read_only:false; response_revision:7419; number_of_response:1; }","duration":"144.948045ms","start":"2026-02-25T19:44:48.748623Z","end":"2026-02-25T19:44:48.893571Z","steps":["trace[197015000] 'process raft request' (duration: 139.608186ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:01.630396Z","caller":"traceutil/trace.go:171","msg":"trace[88188372] linearizableReadLoop","detail":"{readStateIndex:7764; appliedIndex:7763; }","duration":"202.463999ms","start":"2026-02-25T19:45:01.427902Z","end":"2026-02-25T19:45:01.630366Z","steps":["trace[88188372] 'read index received' (duration: 194.926698ms)","trace[88188372] 'applied index is now lower than readState.Index' (duration: 7.536111ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:45:01.630473Z","caller":"traceutil/trace.go:171","msg":"trace[275238892] transaction","detail":"{read_only:false; response_revision:7481; number_of_response:1; }","duration":"277.638607ms","start":"2026-02-25T19:45:01.352812Z","end":"2026-02-25T19:45:01.630451Z","steps":["trace[275238892] 'process raft request' (duration: 270.140217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:01.630555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.628043ms","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:5"} {"level":"info","ts":"2026-02-25T19:45:01.630644Z","caller":"traceutil/trace.go:171","msg":"trace[375559027] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:7481; }","duration":"202.764097ms","start":"2026-02-25T19:45:01.427859Z","end":"2026-02-25T19:45:01.630624Z","steps":["trace[375559027] 'agreement among raft nodes before linearized reading' (duration: 202.616933ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:15.953419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5532} {"level":"info","ts":"2026-02-25T19:45:16.040886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5532,"took":"85.682783ms","hash":134545036,"current-db-size-bytes":42143744,"current-db-size":"42 MB","current-db-size-in-use-bytes":21934080,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-02-25T19:45:16.040941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":134545036,"revision":5532,"compact-revision":2572} {"level":"warn","ts":"2026-02-25T19:45:28.20836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.392375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:45:28.2085Z","caller":"traceutil/trace.go:171","msg":"trace[1126607447] transaction","detail":"{read_only:false; response_revision:7662; number_of_response:1; }","duration":"400.073012ms","start":"2026-02-25T19:45:27.808404Z","end":"2026-02-25T19:45:28.208477Z","steps":["trace[1126607447] 'process raft request' (duration: 263.46631ms)","trace[1126607447] 'compare' (duration: 136.280322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:45:28.208589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:45:27.808385Z","time spent":"400.155735ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:45:28.26109Z","caller":"traceutil/trace.go:171","msg":"trace[691210821] linearizableReadLoop","detail":"{readStateIndex:7954; appliedIndex:7952; }","duration":"342.898419ms","start":"2026-02-25T19:45:27.918167Z","end":"2026-02-25T19:45:28.261065Z","steps":["trace[691210821] 'read index received' (duration: 153.777584ms)","trace[691210821] 'applied index is now lower than readState.Index' (duration: 189.119925ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:45:28.261228Z","caller":"traceutil/trace.go:171","msg":"trace[88785078] transaction","detail":"{read_only:false; response_revision:7663; number_of_response:1; }","duration":"406.298077ms","start":"2026-02-25T19:45:27.854885Z","end":"2026-02-25T19:45:28.261183Z","steps":["trace[88785078] 'process raft request' (duration: 406.064161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:28.261313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.129585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-02-25T19:45:28.261361Z","caller":"traceutil/trace.go:171","msg":"trace[1056193551] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7663; }","duration":"343.226148ms","start":"2026-02-25T19:45:27.918122Z","end":"2026-02-25T19:45:28.261348Z","steps":["trace[1056193551] 'agreement among raft nodes before linearized reading' (duration: 343.058333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:28.26139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:45:27.918104Z","time spent":"343.277859ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-02-25T19:45:28.261422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:45:27.854863Z","time spent":"406.460812ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:45:28.262176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.795327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4qclb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-25T19:45:28.262243Z","caller":"traceutil/trace.go:171","msg":"trace[1859952872] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4qclb; range_end:; response_count:1; response_revision:7664; }","duration":"259.87393ms","start":"2026-02-25T19:45:28.002331Z","end":"2026-02-25T19:45:28.262205Z","steps":["trace[1859952872] 'agreement among raft nodes before linearized reading' (duration: 259.774528ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:28.262488Z","caller":"traceutil/trace.go:171","msg":"trace[588538898] transaction","detail":"{read_only:false; response_revision:7664; number_of_response:1; }","duration":"105.458679ms","start":"2026-02-25T19:45:28.156944Z","end":"2026-02-25T19:45:28.262403Z","steps":["trace[588538898] 'process raft request' (duration: 105.113059ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:42.476752Z","caller":"traceutil/trace.go:171","msg":"trace[480358743] linearizableReadLoop","detail":"{readStateIndex:8069; appliedIndex:8068; }","duration":"216.137613ms","start":"2026-02-25T19:45:42.260581Z","end":"2026-02-25T19:45:42.476719Z","steps":["trace[480358743] 'read index received' (duration: 216.04674ms)","trace[480358743] 'applied index is now lower than readState.Index' (duration: 89.833µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:45:42.476831Z","caller":"traceutil/trace.go:171","msg":"trace[351711701] transaction","detail":"{read_only:false; response_revision:7772; number_of_response:1; }","duration":"334.6956ms","start":"2026-02-25T19:45:42.142106Z","end":"2026-02-25T19:45:42.476802Z","steps":["trace[351711701] 'process raft request' (duration: 334.401692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:42.476987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:45:42.142088Z","time spent":"334.798092ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:45:42.477021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.426631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:45:42.478717Z","caller":"traceutil/trace.go:171","msg":"trace[342255021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7772; }","duration":"218.16504ms","start":"2026-02-25T19:45:42.26053Z","end":"2026-02-25T19:45:42.478695Z","steps":["trace[342255021] 'agreement among raft nodes before linearized reading' (duration: 216.355809ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:42.524616Z","caller":"traceutil/trace.go:171","msg":"trace[698289719] transaction","detail":"{read_only:false; response_revision:7774; number_of_response:1; }","duration":"106.273831ms","start":"2026-02-25T19:45:42.418315Z","end":"2026-02-25T19:45:42.524589Z","steps":["trace[698289719] 'process raft request' (duration: 106.199189ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:42.524621Z","caller":"traceutil/trace.go:171","msg":"trace[1126430527] transaction","detail":"{read_only:false; response_revision:7773; number_of_response:1; }","duration":"214.750535ms","start":"2026-02-25T19:45:42.309822Z","end":"2026-02-25T19:45:42.524573Z","steps":["trace[1126430527] 'process raft request' (duration: 210.045852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:42.524759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.515927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-02-25T19:45:42.524815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.453291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4qclb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-25T19:45:42.524854Z","caller":"traceutil/trace.go:171","msg":"trace[1821029700] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7774; }","duration":"204.62308ms","start":"2026-02-25T19:45:42.320208Z","end":"2026-02-25T19:45:42.524831Z","steps":["trace[1821029700] 'agreement among raft nodes before linearized reading' (duration: 204.363213ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:42.524901Z","caller":"traceutil/trace.go:171","msg":"trace[987250029] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4qclb; range_end:; response_count:1; response_revision:7774; }","duration":"191.569393ms","start":"2026-02-25T19:45:42.333308Z","end":"2026-02-25T19:45:42.524877Z","steps":["trace[987250029] 'agreement among raft nodes before linearized reading' (duration: 191.311606ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:45:43.542225Z","caller":"traceutil/trace.go:171","msg":"trace[1354615904] transaction","detail":"{read_only:false; response_revision:7779; number_of_response:1; }","duration":"315.956894ms","start":"2026-02-25T19:45:43.226247Z","end":"2026-02-25T19:45:43.542204Z","steps":["trace[1354615904] 'process raft request' (duration: 257.3606ms)","trace[1354615904] 'compare' (duration: 58.500061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:45:43.542532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:45:43.226221Z","time spent":"316.253411ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:45:43.553325Z","caller":"traceutil/trace.go:171","msg":"trace[116655845] transaction","detail":"{read_only:false; response_revision:7780; number_of_response:1; }","duration":"317.870326ms","start":"2026-02-25T19:45:43.235421Z","end":"2026-02-25T19:45:43.553291Z","steps":["trace[116655845] 'process raft request' (duration: 317.693591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:45:43.55349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:45:43.235394Z","time spent":"318.01053ms","remote":"127.0.0.1:34308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:46:49.52698Z","caller":"traceutil/trace.go:171","msg":"trace[2111048242] transaction","detail":"{read_only:false; response_revision:8377; number_of_response:1; }","duration":"109.752589ms","start":"2026-02-25T19:46:49.417194Z","end":"2026-02-25T19:46:49.526947Z","steps":["trace[2111048242] 'process raft request' (duration: 46.30353ms)","trace[2111048242] 'compare' (duration: 63.208712ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:49:32.769352Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"98aaa0557ab926b1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-25T19:49:32.775358Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-25T19:49:32.775428Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-25T19:50:15.959944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7555} {"level":"info","ts":"2026-02-25T19:50:16.023943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7555,"took":"61.742267ms","hash":1486273078,"current-db-size-bytes":42143744,"current-db-size":"42 MB","current-db-size-in-use-bytes":26898432,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-02-25T19:50:16.024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1486273078,"revision":7555,"compact-revision":5532} {"level":"info","ts":"2026-02-25T19:50:30.024583Z","caller":"traceutil/trace.go:171","msg":"trace[1878228672] transaction","detail":"{read_only:false; response_revision:10160; number_of_response:1; }","duration":"111.383023ms","start":"2026-02-25T19:50:29.913174Z","end":"2026-02-25T19:50:30.024557Z","steps":["trace[1878228672] 'process raft request' (duration: 111.212359ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:55:15.966629Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10090} {"level":"info","ts":"2026-02-25T19:55:16.045285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10090,"took":"76.083015ms","hash":126213590,"current-db-size-bytes":42143744,"current-db-size":"42 MB","current-db-size-in-use-bytes":23670784,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-25T19:55:16.04534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":126213590,"revision":10090,"compact-revision":7555} {"level":"info","ts":"2026-02-25T19:56:56.218624Z","caller":"traceutil/trace.go:171","msg":"trace[1701078715] linearizableReadLoop","detail":"{readStateIndex:13138; appliedIndex:13137; }","duration":"237.3159ms","start":"2026-02-25T19:56:55.98128Z","end":"2026-02-25T19:56:56.218596Z","steps":["trace[1701078715] 'read index received' (duration: 237.124365ms)","trace[1701078715] 'applied index is now lower than readState.Index' (duration: 190.425µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:56:56.218728Z","caller":"traceutil/trace.go:171","msg":"trace[1240416500] transaction","detail":"{read_only:false; response_revision:12677; number_of_response:1; }","duration":"245.582442ms","start":"2026-02-25T19:56:55.973105Z","end":"2026-02-25T19:56:56.218688Z","steps":["trace[1240416500] 'process raft request' (duration: 245.358405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:56:56.218897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.590228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:56:56.219016Z","caller":"traceutil/trace.go:171","msg":"trace[329646082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12677; }","duration":"237.735401ms","start":"2026-02-25T19:56:55.981261Z","end":"2026-02-25T19:56:56.218997Z","steps":["trace[329646082] 'agreement among raft nodes before linearized reading' (duration: 237.564316ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:56:59.79644Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000339d.wal"} {"level":"warn","ts":"2026-02-25T19:58:33.520983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.198443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:58:33.521158Z","caller":"traceutil/trace.go:171","msg":"trace[2029952253] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:13704; }","duration":"118.43954ms","start":"2026-02-25T19:58:33.402693Z","end":"2026-02-25T19:58:33.521133Z","steps":["trace[2029952253] 'count revisions from in-memory index tree' (duration: 118.09032ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:58:59.011534Z","caller":"traceutil/trace.go:171","msg":"trace[1688365884] linearizableReadLoop","detail":"{readStateIndex:14338; appliedIndex:14337; }","duration":"172.706231ms","start":"2026-02-25T19:58:58.838798Z","end":"2026-02-25T19:58:59.011504Z","steps":["trace[1688365884] 'read index received' (duration: 172.472164ms)","trace[1688365884] 'applied index is now lower than readState.Index' (duration: 232.967µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:58:59.013669Z","caller":"traceutil/trace.go:171","msg":"trace[1027174210] transaction","detail":"{read_only:false; response_revision:13841; number_of_response:1; }","duration":"181.94349ms","start":"2026-02-25T19:58:58.831714Z","end":"2026-02-25T19:58:59.013657Z","steps":["trace[1027174210] 'process raft request' (duration: 179.632726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:58:59.013907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.089887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-02-25T19:58:59.013937Z","caller":"traceutil/trace.go:171","msg":"trace[1850377051] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13841; }","duration":"175.152759ms","start":"2026-02-25T19:58:58.838774Z","end":"2026-02-25T19:58:59.013926Z","steps":["trace[1850377051] 'agreement among raft nodes before linearized reading' (duration: 175.032636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:58:59.016298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.796885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-02-25T19:58:59.016369Z","caller":"traceutil/trace.go:171","msg":"trace[1625371515] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13841; }","duration":"105.899477ms","start":"2026-02-25T19:58:58.910456Z","end":"2026-02-25T19:58:59.016355Z","steps":["trace[1625371515] 'agreement among raft nodes before linearized reading' (duration: 103.469479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:58:59.016371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.304684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:58:59.016424Z","caller":"traceutil/trace.go:171","msg":"trace[1457124406] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:13841; }","duration":"134.382787ms","start":"2026-02-25T19:58:58.882027Z","end":"2026-02-25T19:58:59.016409Z","steps":["trace[1457124406] 'agreement among raft nodes before linearized reading' (duration: 131.836575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:58:59.016608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.97217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:1 size:4957"} {"level":"info","ts":"2026-02-25T19:58:59.016661Z","caller":"traceutil/trace.go:171","msg":"trace[982375925] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:1; response_revision:13841; }","duration":"106.054491ms","start":"2026-02-25T19:58:58.910593Z","end":"2026-02-25T19:58:59.016647Z","steps":["trace[982375925] 'agreement among raft nodes before linearized reading' (duration: 103.321005ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:59:00.651185Z","caller":"traceutil/trace.go:171","msg":"trace[1532590873] transaction","detail":"{read_only:false; response_revision:13858; number_of_response:1; }","duration":"148.887203ms","start":"2026-02-25T19:59:00.50227Z","end":"2026-02-25T19:59:00.651157Z","steps":["trace[1532590873] 'process raft request' (duration: 148.716548ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:00:15.973938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11975} {"level":"info","ts":"2026-02-25T20:00:16.062017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11975,"took":"85.458326ms","hash":221587165,"current-db-size-bytes":42143744,"current-db-size":"42 MB","current-db-size-in-use-bytes":27983872,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-25T20:00:16.062069Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":221587165,"revision":11975,"compact-revision":10090} {"level":"info","ts":"2026-02-25T20:04:12.826627Z","caller":"traceutil/trace.go:171","msg":"trace[869250100] transaction","detail":"{read_only:false; response_revision:16394; number_of_response:1; }","duration":"110.753169ms","start":"2026-02-25T20:04:12.715848Z","end":"2026-02-25T20:04:12.826601Z","steps":["trace[869250100] 'process raft request' (duration: 110.596805ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:04:13.585916Z","caller":"traceutil/trace.go:171","msg":"trace[699487451] transaction","detail":"{read_only:false; response_revision:16398; number_of_response:1; }","duration":"107.759096ms","start":"2026-02-25T20:04:13.478107Z","end":"2026-02-25T20:04:13.585866Z","steps":["trace[699487451] 'process raft request' (duration: 106.728957ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:04:19.577101Z","caller":"traceutil/trace.go:171","msg":"trace[187310931] linearizableReadLoop","detail":"{readStateIndex:17013; appliedIndex:17012; }","duration":"141.226753ms","start":"2026-02-25T20:04:19.435853Z","end":"2026-02-25T20:04:19.577079Z","steps":["trace[187310931] 'read index received' (duration: 141.183161ms)","trace[187310931] 'applied index is now lower than readState.Index' (duration: 42.702µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T20:04:19.577246Z","caller":"traceutil/trace.go:171","msg":"trace[1020851932] transaction","detail":"{read_only:false; response_revision:16434; number_of_response:1; }","duration":"234.929825ms","start":"2026-02-25T20:04:19.342299Z","end":"2026-02-25T20:04:19.577228Z","steps":["trace[1020851932] 'process raft request' (duration: 234.649367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.577378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.495151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-25T20:04:19.577496Z","caller":"traceutil/trace.go:171","msg":"trace[15211495] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16434; }","duration":"141.674095ms","start":"2026-02-25T20:04:19.435794Z","end":"2026-02-25T20:04:19.577468Z","steps":["trace[15211495] 'agreement among raft nodes before linearized reading' (duration: 141.395648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.971805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.693687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-25T20:04:19.971901Z","caller":"traceutil/trace.go:171","msg":"trace[34404421] linearizableReadLoop","detail":"{readStateIndex:17016; appliedIndex:17014; }","duration":"373.560014ms","start":"2026-02-25T20:04:19.598326Z","end":"2026-02-25T20:04:19.971886Z","steps":["trace[34404421] 'read index received' (duration: 16.975595ms)","trace[34404421] 'applied index is now lower than readState.Index' (duration: 356.582459ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T20:04:19.971999Z","caller":"traceutil/trace.go:171","msg":"trace[856288775] transaction","detail":"{read_only:false; response_revision:16436; number_of_response:1; }","duration":"378.07071ms","start":"2026-02-25T20:04:19.593897Z","end":"2026-02-25T20:04:19.971967Z","steps":["trace[856288775] 'process raft request' (duration: 39.132015ms)","trace[856288775] 'compare' (duration: 338.338477ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T20:04:19.972056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.736469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-25T20:04:19.972085Z","caller":"traceutil/trace.go:171","msg":"trace[853568287] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:16436; }","duration":"373.802041ms","start":"2026-02-25T20:04:19.598274Z","end":"2026-02-25T20:04:19.972076Z","steps":["trace[853568287] 'agreement among raft nodes before linearized reading' (duration: 373.685857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.972116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T20:04:19.598254Z","time spent":"373.855163ms","remote":"127.0.0.1:43222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" "} {"level":"warn","ts":"2026-02-25T20:04:19.972174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T20:04:19.593848Z","time spent":"378.220813ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T20:04:19.972366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.603091ms","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-02-25T20:04:19.972403Z","caller":"traceutil/trace.go:171","msg":"trace[49935997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16436; }","duration":"365.665342ms","start":"2026-02-25T20:04:19.606729Z","end":"2026-02-25T20:04:19.972395Z","steps":["trace[49935997] 'agreement among raft nodes before linearized reading' (duration: 365.552249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.972433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T20:04:19.606715Z","time spent":"365.707884ms","remote":"127.0.0.1:43264","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-02-25T20:04:19.972717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.97174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-25T20:04:19.972766Z","caller":"traceutil/trace.go:171","msg":"trace[1357187638] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:16436; }","duration":"297.061242ms","start":"2026-02-25T20:04:19.675693Z","end":"2026-02-25T20:04:19.972754Z","steps":["trace[1357187638] 'agreement among raft nodes before linearized reading' (duration: 296.96906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.97277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.038523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-25T20:04:19.972772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.676973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-25T20:04:19.972826Z","caller":"traceutil/trace.go:171","msg":"trace[16456468] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:16436; }","duration":"361.136196ms","start":"2026-02-25T20:04:19.611676Z","end":"2026-02-25T20:04:19.972813Z","steps":["trace[16456468] 'agreement among raft nodes before linearized reading' (duration: 361.036913ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:04:19.972841Z","caller":"traceutil/trace.go:171","msg":"trace[334877488] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:16436; }","duration":"360.774556ms","start":"2026-02-25T20:04:19.612047Z","end":"2026-02-25T20:04:19.972822Z","steps":["trace[334877488] 'agreement among raft nodes before linearized reading' (duration: 360.629822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.972857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T20:04:19.611659Z","time spent":"361.193337ms","remote":"127.0.0.1:43222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/senlin-ks-user\" "} {"level":"warn","ts":"2026-02-25T20:04:19.972851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.466905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-25T20:04:19.972885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T20:04:19.612036Z","time spent":"360.837428ms","remote":"127.0.0.1:43222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" "} {"level":"warn","ts":"2026-02-25T20:04:19.972937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.64289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-25T20:04:19.972902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.675421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-02-25T20:04:19.972979Z","caller":"traceutil/trace.go:171","msg":"trace[1904415414] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:16436; }","duration":"227.742732ms","start":"2026-02-25T20:04:19.745227Z","end":"2026-02-25T20:04:19.97297Z","steps":["trace[1904415414] 'agreement among raft nodes before linearized reading' (duration: 227.678351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:04:19.973035Z","caller":"traceutil/trace.go:171","msg":"trace[1918949048] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:1; response_revision:16436; }","duration":"361.851796ms","start":"2026-02-25T20:04:19.611135Z","end":"2026-02-25T20:04:19.972987Z","steps":["trace[1918949048] 'agreement among raft nodes before linearized reading' (duration: 361.530907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.973086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.922502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-02-25T20:04:19.972934Z","caller":"traceutil/trace.go:171","msg":"trace[1349241823] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:16436; }","duration":"227.604389ms","start":"2026-02-25T20:04:19.74531Z","end":"2026-02-25T20:04:19.972915Z","steps":["trace[1349241823] 'agreement among raft nodes before linearized reading' (duration: 227.448144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.973089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T20:04:19.611118Z","time spent":"361.959658ms","remote":"127.0.0.1:43222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5046,"request content":"key:\"/registry/jobs/openstack/senlin-db-sync\" "} {"level":"info","ts":"2026-02-25T20:04:19.973124Z","caller":"traceutil/trace.go:171","msg":"trace[95893694] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:1; response_revision:16436; }","duration":"225.979944ms","start":"2026-02-25T20:04:19.747135Z","end":"2026-02-25T20:04:19.973115Z","steps":["trace[95893694] 'agreement among raft nodes before linearized reading' (duration: 225.8261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.973144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.283081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-dlnvm\" ","response":"range_response_count:1 size:20865"} {"level":"info","ts":"2026-02-25T20:04:19.973194Z","caller":"traceutil/trace.go:171","msg":"trace[1747077584] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-dlnvm; range_end:; response_count:1; response_revision:16436; }","duration":"281.372323ms","start":"2026-02-25T20:04:19.691814Z","end":"2026-02-25T20:04:19.973186Z","steps":["trace[1747077584] 'agreement among raft nodes before linearized reading' (duration: 281.232519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T20:04:19.97321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.798684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-25T20:04:19.97323Z","caller":"traceutil/trace.go:171","msg":"trace[1105535850] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:16436; }","duration":"227.949118ms","start":"2026-02-25T20:04:19.745275Z","end":"2026-02-25T20:04:19.973224Z","steps":["trace[1105535850] 'agreement among raft nodes before linearized reading' (duration: 227.919178ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T20:05:15.988841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14421} {"level":"info","ts":"2026-02-25T20:05:16.091441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14421,"took":"96.560772ms","hash":564804310,"current-db-size-bytes":42143744,"current-db-size":"42 MB","current-db-size-in-use-bytes":29323264,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-25T20:05:16.091499Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":564804310,"revision":14421,"compact-revision":11975} {"level":"info","ts":"2026-02-25T20:10:15.995082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16866} {"level":"info","ts":"2026-02-25T20:10:16.092629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16866,"took":"94.427075ms","hash":1145266875,"current-db-size-bytes":42143744,"current-db-size":"42 MB","current-db-size-in-use-bytes":25088000,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-02-25T20:10:16.092691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1145266875,"revision":16866,"compact-revision":14421}