[Pacemaker] CentOS problem starting openais

Tue Aug 25 19:55:37 UTC 2009

Hi,

I just installed pacemaker from OSBS on fully patched CentOS 5.3.
when I call aisexec -f manually everything works as expected. When Is use 
/etc/init.d/openais start I get the following in /var/log/messages

Aug 25 23:46:02 localhost openais[16897]: [MAIN ] AIS Executive Service 
RELEASE 'subrev 1152 version 0.80'
Aug 25 23:46:02 localhost openais[16897]: [MAIN ] Copyright (C) 2002-2006 
MontaVista Software, Inc and contributors.
Aug 25 23:46:02 localhost openais[16897]: [MAIN ] Copyright (C) 2006 Red Hat, 
Inc.
Aug 25 23:46:02 localhost openais[16897]: [MAIN ] AIS Executive Service: 
started and ready to provide service.
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] Token Timeout (3000 ms) 
retransmit timeout (294 ms)
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] token hold (225 ms) 
retransmits before loss (10 retrans)
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] join (60 ms) send_join (0 
ms) consensus (1500 ms) merge (200 ms)
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] downcheck (1000 ms) fail to 
recv const (50 msgs)
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] seqno unchanged const (30 
rotations) Maximum network MTU 1500
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] window size per rotation (50 
messages) maximum messages per rotation (20 messages)
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] send threads (0 threads)
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] RRP token expired timeout 
(294 ms)
Aug 25 23:46:02 localhost openais[16897]: [TOTEM] RRP token problem counter 
(2000 ms)
Aug 25 23:46:03 localhost openais[16897]: [TOTEM] RRP threshold (10 problem 
count)
Aug 25 23:46:03 localhost openais[16897]: [TOTEM] RRP mode set to none.
Aug 25 23:46:03 localhost openais[16897]: [TOTEM] heartbeat_failures_allowed 
(0)
Aug 25 23:46:03 localhost openais[16897]: [TOTEM] max_network_delay (50 ms)
Aug 25 23:46:03 localhost openais[16897]: [TOTEM] HeartBeat is Disabled. To 
enable set heartbeat_failures_allowed > 0
Aug 25 23:46:03 localhost openais[16897]: [TOTEM] The network interface 
[172.19.93.1] is now up.
Aug 25 23:46:03 localhost openais[16897]: [TOTEM] Created or loaded sequence 
id 24.172.19.93.1 for this ring.
Aug 25 23:46:03 localhost openais[16897]: [TOTEM] entering GATHER state from 
15.
Aug 25 23:46:03 localhost openais[16897]: [crm  ] info: process_ais_conf: 
Reading configure
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: config_find_next: 
Processing additional logging options...
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: get_config_opt: Found 
'off' for option: debug
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: get_config_opt: 
Defaulting to 'off' for option: to_file
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: get_config_opt: Found 
'daemon' for option: syslog_facility
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: config_find_next: 
Processing additional service options...
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: get_config_opt: 
Defaulting to 'no' for option: use_logd
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: get_config_opt: Found 
'yes' for option: use_mgmtd
Aug 25 23:46:03 localhost openais[16897]: [crm  ] pcmk_plugin_init: Could not 
enable /proc/sys/kernel/core_uses_pid: (22) Invalid argument
Aug 25 23:46:03 localhost openais[16897]: [crm  ] info: pcmk_plugin_init: CRM: 
Initialized
Aug 25 23:46:03 localhost openais[16897]: [crm  ] Logging: Initialized 
pcmk_plugin_init
Aug 25 23:46:03 localhost openais[16897]: [crm  ] info: pcmk_plugin_init: 
Service: 9
Aug 25 23:46:03 localhost openais[16897]: [crm  ] info: pcmk_plugin_init: 
Local node id: 22877100
Aug 25 23:46:03 localhost openais[16897]: [crm  ] info: pcmk_plugin_init: 
Local hostname: localhost.localdomain
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: update_member: 
Creating entry for node 22877100 born on 0
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: update_member: 
0x9c4c6d8 Node 22877100 now known as localhost.localdomain (was: (null))
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: update_member: Node 
localhost.localdomain now has 1 quorum votes (was 0)
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: update_member: Node 
22877100/localhost.localdomain is now: member
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: spawn_child: Forked 
child 16903 for process stonithd
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: spawn_child: Forked 
child 16904 for process cib
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: spawn_child: Forked 
child 16905 for process lrmd
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: spawn_child: Forked 
child 16906 for process attrd
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: spawn_child: Forked 
child 16907 for process pengine
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: spawn_child: Forked 
child 16908 for process crmd
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] info: spawn_child: Forked 
child 16909 for process mgmtd
Aug 25 23:46:03 localhost openais[16897]: [crm  ] info: pcmk_startup: CRM: 
Initialized
Aug 25 23:46:03 localhost openais[16897]: [MAIN ] Service initialized 
'Pacemaker Cluster Manager'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
extended virtual synchrony service'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
cluster membership service B.01.01'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
availability management framework B.01.01'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
checkpoint service B.01.01'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
event service B.01.01'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
distributed locking service B.01.01'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
message service B.01.01'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
configuration service'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
cluster closed process group service v1.01'
Aug 25 23:46:03 localhost openais[16897]: [SERV ] Service initialized 'openais 
cluster config database access v1.01'
Aug 25 23:46:03 localhost openais[16897]: [SYNC ] Not using a virtual 
synchrony filter.
Aug 25 23:46:07 localhost openais[16897]: [TOTEM] The consensus timeout 
expired.
Aug 25 23:46:07 localhost openais[16897]: [TOTEM] entering GATHER state from 
3.
Aug 25 23:46:11 localhost openais[16897]: [TOTEM] The consensus timeout 
expired.
Aug 25 23:46:11 localhost openais[16897]: [TOTEM] entering GATHER state from 
3.
Aug 25 23:46:16 localhost openais[16897]: [TOTEM] The consensus timeout 
expired.
Aug 25 23:46:16 localhost openais[16897]: [TOTEM] entering GATHER state from 
3.
Aug 25 23:46:21 localhost openais[16897]: [TOTEM] The consensus timeout 
expired.
Aug 25 23:46:21 localhost openais[16897]: [TOTEM] entering GATHER state from 
3.

and the cluster does not work. what I do not unterstand: In 
/etc/init.d/openais the main line is also just: "aisexec"

any help?

-- 
Dr. Michael Schwartzkopff
MultiNET Services GmbH
Addresse: Bretonischer Ring 7; 85630 Grasbrunn; Germany
Tel: +49 - 89 - 45 69 11 0
Fax: +49 - 89 - 45 69 11 21
mob: +49 - 174 - 343 28 75

mail: misch at multinet.de
web: www.multinet.de

Sitz der Gesellschaft: 85630 Grasbrunn
Registergericht: Amtsgericht München HRB 114375
Geschäftsführer: Günter Jurgeneit, Hubert Martens

---

PGP Fingerprint: F919 3919 FF12 ED5A 2801 DEA6 AA77 57A4 EDD8 979B
Skype: misch42