Bug 2148124

Summary:	Stopping pcsd daemon by using 'systemctl stop' takes too long
Product:	Red Hat Enterprise Linux 9	Reporter:	Miroslav Lisik <mlisik>
Component:	pcs	Assignee:	Ondrej Mular <omular>
Status:	CLOSED ERRATA	QA Contact:	cluster-qe <cluster-qe>
Severity:	medium	Docs Contact:
Priority:	medium
Version:	9.2	CC:	cluster-maint, idevat, mlisik, mmazoure, mpospisi, omular, svalasti, tojeline
Target Milestone:	rc	Keywords:	Triaged
Target Release:	9.2
Hardware:	Unspecified
OS:	Unspecified
Whiteboard:
Fixed In Version:	pcs-0.11.4-2.el9	Doc Type:	No Doc Update
Doc Text:	affected packages haven't been released	Story Points:	---
Clone Of:		Environment:
Last Closed:	2023-05-09 07:18:34 UTC	Type:	Bug
Regression:	---	Mount Type:	---
Documentation:	---	CRM:
Verified Versions:		Category:	---
oVirt Team:	---	RHEL 7.3 requirements from Atomic Host:
Cloudforms Team:	---	Target Upstream Version:
Embargoed:

Description Miroslav Lisik 2022-11-24 13:00:18 UTC

Description of problem:
Stopping pcsd daemon by using 'systemctl  stop' takes too long.

Version-Release number of selected component (if applicable):
pcs-0.11.3-5.el9

How reproducible:
always

Steps to Reproduce:
1. start pcsd daemon and wait until started
systemctl start pcsd

2. execute systemctl stop pcsd and measure execution time of the command
time systemctl stop pcsd

Actual results:

[root@r92-1 pcs]# time systemctl stop pcsd

real    1m30.070s
user    0m0.010s
sys     0m0.017s

Expected results:
Pcsd daemon stop time should be under 1 second.

[root@r92-1 pcs]# rpm -q pcs
pcs-0.11.3-4.el9.x86_64
[root@r92-1 pcs]# systemctl start pcsd
[root@r92-1 pcs]# time systemctl stop pcsd

real    0m0.072s
user    0m0.011s
sys     0m0.020s

Comment 1 Ondrej Mular 2022-11-24 13:04:00 UTC

Upstream patch: https://github.com/ClusterLabs/pcs/commit/d486d9c9bafbfc13be7ff86c0ae781feed184d52

Comment 2 Michal Pospisil 2022-12-15 14:14:21 UTC

DevTestResults:

[root@r09-02-a ~]# rpm -q pcs
pcs-0.11.4-2.el9.x86_64

[root@r09-02-a ~]# time systemctl stop pcsd

real    0m0.182s
user    0m0.110s
sys     0m0.029s

Comment 7 svalasti 2023-01-17 14:33:54 UTC

[root@virt-528 ~]# rpm -q pcs
pcs-0.11.4-2.el9.x86_64

> Verification has been performed through an automated test. Test results are available below.

[root@virt-521 ~]# $v -t pcs,cli,DaemonStopMeasure
Starting tests, logging all output to /tmp/vedder.CHERRY.STSRHTS11895.202301101513
Starting XMLRPC server....
DEBUG:STSXMLRPC_DAEMON:Debugging enabled
INFO:STSXMLRPC:Server started. Logging to /tmp/vedder.CHERRY.STSRHTS11895.202301101513/stsxmlrpc.log
XMLRPC server started at http://virt-521.cluster-qe.lab.eng.brq.redhat.com:36767/
<start name="pcs,cli,DaemonStopMeasure" id="pcs,cli,DaemonStopMeasure" pid="60529" time="Tue Jan 10 15:13:50 2023 +0100" type="cmd" />
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:13:50 INFO:	== SUBTEST: DaemonStopMeasure ==
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:13:52 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:13:52 INFO:	Starting pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:13:54 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:13:57 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:00 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:00 INFO:	Starting pcsd daemon on: virt-533
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:02 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:04 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:07 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:07 INFO:	Starting pcsd daemon on: virt-565
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:09 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:11 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:14 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:14 INFO:	Starting pcsd daemon on: virt-566
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:16 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:19 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:21 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:21 INFO:	Starting pcsd daemon on: virt-567
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:23 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:26 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:29 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:29 INFO:	Starting pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:31 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:33 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:36 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:36 INFO:	Starting pcsd daemon on: virt-533
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:36 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:39 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:41 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:41 INFO:	Starting pcsd daemon on: virt-565
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:42 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:44 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:47 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:47 INFO:	Starting pcsd daemon on: virt-566
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:47 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:50 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:52 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:52 INFO:	Starting pcsd daemon on: virt-567
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:52 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:55 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:58 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:14:58 INFO:	Starting pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:00 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:02 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:05 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:05 INFO:	Starting pcsd daemon on: virt-533
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:05 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:08 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:11 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:11 INFO:	Starting pcsd daemon on: virt-565
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:11 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:13 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:16 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:16 INFO:	Starting pcsd daemon on: virt-566
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:16 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:19 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:21 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:21 INFO:	Starting pcsd daemon on: virt-567
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:21 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:24 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:27 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:27 INFO:	Starting pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:29 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:31 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:34 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:34 INFO:	Starting pcsd daemon on: virt-533
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:34 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:37 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:40 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:40 INFO:	Starting pcsd daemon on: virt-565
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:40 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:42 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:45 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:45 INFO:	Starting pcsd daemon on: virt-566
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:45 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:48 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:50 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:50 INFO:	Starting pcsd daemon on: virt-567
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:51 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:53 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:56 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:56 INFO:	Starting pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:15:58 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:00 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:03 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:03 INFO:	Starting pcsd daemon on: virt-533
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:03 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:06 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:09 INFO:	START_DAEMON
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:09 INFO:	Starting pcsd daemon on: virt-565
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:09 INFO:	
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:11 INFO:	Stopping pcsd daemon on: virt-528
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:14 INFO:	Number of measurements = 23
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:14 INFO:	Measured real-time range (min-max) = <0.0 - 0.2> (sec.)
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:14 INFO:	Average stop time = 0.04 (sec.)
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:15 INFO:	CHECK_LOGS
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:15 INFO:	CHECK_LOGS_FOR_CIB_REPLACE
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:15 INFO:	PCS_CLUSTER_DESTROY
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:18 INFO:	cluster destroyed.
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:18 INFO:	CHECK_CLUSTER_HEALTH
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:18 INFO:	SETUP_PCSD_PORTS
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:18 INFO:	CLEAR_DAEMON_CONFIGS
[pcs,cli,DaemonStopMeasure] 2023-01-10 15:16:18 INFO:	RESTART_DAEMON
<pass name="pcs,cli,DaemonStopMeasure" id="pcs,cli,DaemonStopMeasure" pid="60529" time="Tue Jan 10 15:16:23 2023 +0100" type="cmd" duration="153" />
------------------- Summary ---------------------
Testcase                                 Result    
--------                                 ------    
pcs,cli,DaemonStopMeasure         PASS      
=================================================
Total Tests Run: 1
Total PASS:      1
Total FAIL:      0
Total TIMEOUT:   0
Total KILLED:    0
Total STOPPED:   0
Test output in /tmp/vedder.CHERRY.STSRHTS11895.202301101513
Killing XMLRPC server...
DEBUG:STSXMLRPC_DAEMON:Debugging enabled
DEBUG:STSXMLRPC:Killing server with PID 60527 (SIGTERM)
INFO:STSXMLRPC:Server terminated.

> Marking as VERIFIED for pcs-0.11.4-2.el9.x86_64

Comment 9 errata-xmlrpc 2023-05-09 07:18:34 UTC

Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory (pcs bug fix and enhancement update), and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

https://access.redhat.com/errata/RHBA-2023:2151