Starting base64 and md5 algorithm tests
Starting Resource Agent tests
Testing RA: Dummy
Beginning tests for /usr/lib/ocf/resource.d//heartbeat/Dummy...
* Your agent does not support the notify action (optional)
* Your agent does not support the demote action (optional)
* Your agent does not support the promote action (optional)
* Your agent does not support master/slave (optional)
/usr/lib/ocf/resource.d//heartbeat/Dummy passed all tests
Testing RA: IPaddr
Beginning tests for /usr/lib/ocf/resource.d//heartbeat/IPaddr...
* Your agent does not support the notify action (optional)
* Your agent does not support the demote action (optional)
* Your agent does not support the promote action (optional)
* Your agent does not support master/slave (optional)
* Your agent does not support the reload action (optional)
/usr/lib/ocf/resource.d//heartbeat/IPaddr passed all tests
Testing RA: IPaddr2
Beginning tests for /usr/lib/ocf/resource.d//heartbeat/IPaddr2...
* Your agent does not support the notify action (optional)
* Your agent does not support the demote action (optional)
* Your agent does not support the promote action (optional)
* Your agent does not support master/slave (optional)
* Your agent does not support the reload action (optional)
/usr/lib/ocf/resource.d//heartbeat/IPaddr2 passed all tests
Testing RA: Filesystem
Beginning tests for /usr/lib/ocf/resource.d//heartbeat/Filesystem...
* Your agent does not support the notify action (optional)
* Your agent does not support the demote action (optional)
* Your agent does not support the promote action (optional)
* Your agent does not support master/slave (optional)
* Your agent does not support the reload action (optional)
/usr/lib/ocf/resource.d//heartbeat/Filesystem passed all tests
RA tests PASSED
Starting IPC tests
ipctest[22141]: 2007/10/12_11:58:40 info: Echo client: 10000 reps pid 22141.
ipctest[22140]: 2007/10/12_11:58:40 info: Echo server: 10000 reps pid 22140.
ipctest[22141]: 2007/10/12_11:58:42 info: echoclient: 0 errors
ipctest[22140]: 2007/10/12_11:58:43 info: echoserver: 0 errors
ipctest[22143]: 2007/10/12_11:58:43 info: Async Echo client: 10000 reps pid 22143.
ipctest[22142]: 2007/10/12_11:58:43 info: Asyn echo server: 10000 reps pid 22142.
ipctest[22143]: 2007/10/12_11:58:54 info: Async echoclient: 0 errors, 10000 reads, 10000 writes
ipctest[22142]: 2007/10/12_11:58:54 info: asyn_echoserver: 0 errors
ipctest[22145]: 2007/10/12_11:58:54 info: Mainloop echo client: 10000 reps pid 22145.
ipctest[22144]: 2007/10/12_11:58:54 info: Mainloop echo server: 10000 reps pid 22144.
ipctest[22145]: 2007/10/12_11:58:55 info: Mainloop echo client: 0 errors, 10000 read 10000 written
ipctest[22144]: 2007/10/12_11:58:55 info: Mainloop echo server: 0 errors
ipctest[22144]: 2007/10/12_11:58:55 info: TOTAL errors: 0
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(deadtime,5)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(initdead,5)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(auto_failback,on)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(ping,127.0.0.1)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(node,dac.uftmasterad.org)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(node,ImAlwaysDead.com)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(use_logd,off)
heartbeat[22263]: 2007/10/12_11:58:56 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[22263]: 2007/10/12_11:58:56 info: respawn directive: hacluster /usr/lib/heartbeat/ccm
heartbeat[22263]: 2007/10/12_11:58:56 info: respawn directive: hacluster /usr/lib/heartbeat/ipfail
heartbeat[22263]: 2007/10/12_11:58:56 debug: uid=root, gid=<null>
heartbeat[22263]: 2007/10/12_11:58:56 debug: uid=hacluster, gid=<null>
heartbeat[22263]: 2007/10/12_11:58:56 debug: uid=hacluster, gid=<null>
heartbeat[22263]: 2007/10/12_11:58:56 debug: uid=<null>, gid=haclient
heartbeat[22263]: 2007/10/12_11:58:56 debug: uid=root, gid=<null>
heartbeat[22263]: 2007/10/12_11:58:56 debug: uid=<null>, gid=haclient
heartbeat[22263]: 2007/10/12_11:58:56 debug: Beginning authentication parsing
heartbeat[22263]: 2007/10/12_11:58:56 debug: 16 max authentication methods
heartbeat[22263]: 2007/10/12_11:58:56 debug: Keyfile opened
heartbeat[22263]: 2007/10/12_11:58:56 debug: Keyfile perms OK
heartbeat[22263]: 2007/10/12_11:58:56 debug: 16 max authentication methods
heartbeat[22263]: 2007/10/12_11:58:56 debug: Found authentication method [sha1]
heartbeat[22263]: 2007/10/12_11:58:56 info: AUTH: i=1: key = 0x9aada98, auth=0x11421c, authname=sha1
heartbeat[22263]: 2007/10/12_11:58:56 debug: Outbound signing method is 1
heartbeat[22263]: 2007/10/12_11:58:56 debug: Found authentication method [md5]
heartbeat[22263]: 2007/10/12_11:58:56 info: AUTH: i=2: key = 0x9aae0a0, auth=0x7b78d0, authname=md5
heartbeat[22263]: 2007/10/12_11:58:56 debug: Found authentication method [crc]
heartbeat[22263]: 2007/10/12_11:58:56 info: AUTH: i=3: key = 0x9aae690, auth=0x116c5c, authname=crc
heartbeat[22263]: 2007/10/12_11:58:56 debug: Authentication parsing complete [1]
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(cluster,linux-ha)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(hopfudge,1)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(baud,19200)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(hbgenmethod,file)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(realtime,true)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(msgfmt,classic)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(conn_logd_time,60)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(log_badpack,true)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(coredumps,true)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(crm,false)
heartbeat[22263]: 2007/10/12_11:58:56 info: Version 2 support: false
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(autojoin,none)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(uuidfrom,file)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(compression,zlib)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(compression_threshold,2)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(traditional_compression,no)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(max_rexmit_delay,250)
heartbeat[22263]: 2007/10/12_11:58:56 debug: Setting max_rexmit_delay to 250 ms
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(record_config_changes,on)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(record_pengine_inputs,on)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(enable_config_writes,on)
heartbeat[22263]: 2007/10/12_11:58:56 debug: add_option(memreserve,6500)
heartbeat[22263]: 2007/10/12_11:58:56 WARN: Initial dead time [5000 ms] may be too small!
heartbeat[22263]: 2007/10/12_11:58:56 info: Initial dead time accounts for slow network startup time
heartbeat[22263]: 2007/10/12_11:58:56 info: It should be >= deadtime and >= 10 seconds
heartbeat[22263]: 2007/10/12_11:58:56 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[22263]: 2007/10/12_11:58:56 info: **************************
heartbeat[22263]: 2007/10/12_11:58:56 info: Configuration validated. Starting heartbeat 2.1.2
heartbeat[22263]: 2007/10/12_11:58:56 debug: HA configuration OK.  Heartbeat starting.
heartbeat[22264]: 2007/10/12_11:58:56 info: heartbeat: version 2.1.2
heartbeat[22264]: 2007/10/12_11:58:56 info: Heartbeat generation: 1191438893
heartbeat[22264]: 2007/10/12_11:58:56 debug: uuid is:add366cf-0375-4bd4-8bcd-3c392b9a84b2
heartbeat[22264]: 2007/10/12_11:58:56 info: G_main_add_TriggerHandler: Added signal manual handler
heartbeat[22264]: 2007/10/12_11:58:56 info: G_main_add_TriggerHandler: Added signal manual handler
heartbeat[22264]: 2007/10/12_11:58:56 info: Removing /var/run/heartbeat/rsctmp failed, recreating.
heartbeat[22264]: 2007/10/12_11:58:56 debug: opening mcast eth0 (UDP/IP multicast)
heartbeat[22264]: 2007/10/12_11:58:56 debug: opening ping 127.0.0.1 (ping membership)
heartbeat[22264]: 2007/10/12_11:58:56 debug: opening ping_group pingus_please (ping group membership)
heartbeat[22264]: 2007/10/12_11:58:56 debug: FIFO process pid: 22281
heartbeat[22264]: 2007/10/12_11:58:56 debug: glib: looking up address for eth0
heartbeat[22264]: 2007/10/12_11:58:56 debug: glib: looking up address for eth0
heartbeat[22264]: 2007/10/12_11:58:56 debug: glib: Successfully joined multicast group 225.224.223.222 oninterface eth0
heartbeat[22264]: 2007/10/12_11:58:56 info: glib: UDP multicast heartbeat started for group 225.224.223.222 port 694 interface eth0 (ttl=0 loop=0)
heartbeat[22264]: 2007/10/12_11:58:56 debug: write process pid: 22282
heartbeat[22264]: 2007/10/12_11:58:56 debug: read child process pid: 22283
heartbeat[22264]: 2007/10/12_11:58:56 info: glib: ping heartbeat started.
heartbeat[22264]: 2007/10/12_11:58:56 debug: write process pid: 22284
heartbeat[22264]: 2007/10/12_11:58:56 debug: read child process pid: 22285
heartbeat[22264]: 2007/10/12_11:58:56 info: glib: ping group heartbeat started.
heartbeat[22264]: 2007/10/12_11:58:56 debug: write process pid: 22286
heartbeat[22264]: 2007/10/12_11:58:56 debug: read child process pid: 22287
heartbeat[22264]: 2007/10/12_11:58:56 info: G_main_add_SignalHandler: Added signal handler for signal 17
heartbeat[22264]: 2007/10/12_11:58:56 debug: Limiting CPU: 42 CPU seconds every 60000 milliseconds
heartbeat[22264]: 2007/10/12_11:58:56 debug: pid 22264 locked in memory.
heartbeat[22264]: 2007/10/12_11:58:56 debug: Waiting for child processes to start
heartbeat[22264]: 2007/10/12_11:58:56 info: Local status now set to: 'up'
heartbeat[22264]: 2007/10/12_11:58:56 debug: All your child process are belong to us
heartbeat[22264]: 2007/10/12_11:58:56 debug: Starting local status message @ 10 ms intervals
heartbeat[22281]: 2007/10/12_11:58:57 debug: pid 22281 locked in memory.
heartbeat[22281]: 2007/10/12_11:58:57 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[22282]: 2007/10/12_11:58:57 debug: pid 22282 locked in memory.
heartbeat[22282]: 2007/10/12_11:58:57 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[22283]: 2007/10/12_11:58:57 debug: pid 22283 locked in memory.
heartbeat[22283]: 2007/10/12_11:58:57 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[22285]: 2007/10/12_11:58:57 debug: pid 22285 locked in memory.
heartbeat[22285]: 2007/10/12_11:58:57 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[22286]: 2007/10/12_11:58:57 debug: pid 22286 locked in memory.
heartbeat[22286]: 2007/10/12_11:58:57 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[22287]: 2007/10/12_11:58:57 debug: pid 22287 locked in memory.
heartbeat[22287]: 2007/10/12_11:58:57 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[22284]: 2007/10/12_11:58:57 debug: pid 22284 locked in memory.
heartbeat[22284]: 2007/10/12_11:58:57 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[22264]: 2007/10/12_11:58:57 info: Link 127.0.0.1:127.0.0.1 up.
heartbeat[22264]: 2007/10/12_11:58:57 debug: CreateInitialFilter: ask_resources
heartbeat[22264]: 2007/10/12_11:58:57 debug: CreateInitialFilter: ip-request-resp
heartbeat[22264]: 2007/10/12_11:58:57 debug: CreateInitialFilter: ip-request
heartbeat[22264]: 2007/10/12_11:58:57 debug: CreateInitialFilter: status
heartbeat[22264]: 2007/10/12_11:58:57 debug: CreateInitialFilter: hb_takeover
heartbeat[22264]: 2007/10/12_11:58:57 info: Status update for node 127.0.0.1: status ping
heartbeat[22264]: 2007/10/12_11:58:57 debug: Status seqno: 0 msgtime: 1192204736
heartbeat[22264]: 2007/10/12_11:58:57 info: Link pingus_please:pingus_please up.
heartbeat[22264]: 2007/10/12_11:58:57 info: Status update for node pingus_please: status ping
heartbeat[22264]: 2007/10/12_11:58:57 debug: Status seqno: 0 msgtime: 1192204736
heartbeat[22264]: 2007/10/12_11:58:57 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 20 ms (> 15 ms) before being called (GSource: 0x9abcf78)
heartbeat[22264]: 2007/10/12_11:58:57 info: Gmain_timeout_dispatch: started at 1735576396 should have started at 1735576394
heartbeat[22264]: 2007/10/12_11:59:01 WARN: node imalwaysdead.com: is dead
heartbeat[22264]: 2007/10/12_11:59:01 info: Comm_now_up(): updating status to active
heartbeat[22264]: 2007/10/12_11:59:01 info: Local status now set to: 'active'
heartbeat[22264]: 2007/10/12_11:59:01 debug: Sending local starting msg: resourcestate = 0
heartbeat[22264]: 2007/10/12_11:59:01 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 0, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 0
heartbeat[22264]: 2007/10/12_11:59:01 info: Starting child client "/usr/lib/heartbeat/ccm" (17,503)
heartbeat[22264]: 2007/10/12_11:59:01 info: Starting child client "/usr/lib/heartbeat/ipfail" (17,503)
heartbeat[22291]: 2007/10/12_11:59:01 info: Resetting node imalwaysdead.com with [NULL STONITH device]
heartbeat[22291]: 2007/10/12_11:59:01 info: glib: Host null-reset: imalwaysdead.com
heartbeat[22291]: 2007/10/12_11:59:01 info: node imalwaysdead.com now reset.
heartbeat[22291]: 2007/10/12_11:59:01 info: FIFO message [type stonith] written rc=50
heartbeat[22264]: 2007/10/12_11:59:01 info: Exiting STONITH imalwaysdead.com process 22291 returned rc 0.
heartbeat[22264]: 2007/10/12_11:59:01 info: Resources being acquired from imalwaysdead.com.
heartbeat[22264]: 2007/10/12_11:59:01 debug: StartNextRemoteRscReq() - calling hook
heartbeat[22264]: 2007/10/12_11:59:01 debug: notify_world: invoking harc: OLD status: active
heartbeat[22264]: 2007/10/12_11:59:01 debug: Process [status] started pid 22295
heartbeat[22264]: 2007/10/12_11:59:01 debug: Starting notify process [status]
heartbeat[22264]: 2007/10/12_11:59:01 debug: takeover_from_node: other now stable
heartbeat[22264]: 2007/10/12_11:59:01 debug: Process [req_our_resources] started pid 22296
heartbeat[22292]: 2007/10/12_11:59:01 info: Checking status of STONITH device [NULL STONITH device]
heartbeat[22264]: 2007/10/12_11:59:01 info: Exiting STONITH-stat process 22292 returned rc 0.
heartbeat[22293]: 2007/10/12_11:59:01 info: Starting "/usr/lib/heartbeat/ccm" as uid 17  gid 503 (pid 22293)
ccm[22293]: 2007/10/12_11:59:01 debug: Signing in with Heartbeat
heartbeat[22264]: 2007/10/12_11:59:01 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:01 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:01 debug: client->gsource = 0x9b1d178
heartbeat[22264]: 2007/10/12_11:59:01 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:01 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:01 debug: Checking client authorization for client ccm (17:503)
heartbeat[22264]: 2007/10/12_11:59:01 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:01 debug: Signing on API client 22293 (ccm)
heartbeat[22294]: 2007/10/12_11:59:01 info: Starting "/usr/lib/heartbeat/ipfail" as uid 17  gid 503 (pid 22294)
heartbeat[22264]: 2007/10/12_11:59:01 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:01 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:01 debug: client->gsource = 0x9b1b598
heartbeat[22264]: 2007/10/12_11:59:01 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:01 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:01 debug: Checking client authorization for client ipfail (17:503)
heartbeat[22264]: 2007/10/12_11:59:01 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:01 debug: Signing on API client 22294 (ipfail)
heartbeat[22295]: 2007/10/12_11:59:01 debug: notify_world: setting SIGCHLD Handler to SIG_DFL
heartbeat[22295]: 2007/10/12_11:59:01 debug: notify_world: Running harc status
heartbeat[22264]: 2007/10/12_11:59:01 debug: hb_rsc_isstable: ResourceMgmt_child_count: 2, other_is_stable: 1, takeover_in_progress: 1, going_standby: 0, standby running(ms): 0, resourcestate: 1
heartbeat[22296]: 2007/10/12_11:59:01 debug: req_our_resources(/usr/share/heartbeat/ResourceManager listkeys dac.uftmasterad.org)
ccm[22293]: 2007/10/12_11:59:01 info: Hostname: dac.uftmasterad.org
heartbeat[22296]: 2007/10/12_11:59:01 info: No local resources [/usr/share/heartbeat/ResourceManager listkeys dac.uftmasterad.org] to acquire.
heartbeat[22296]: 2007/10/12_11:59:01 debug: Sending hold resources msg: all, stable=1 # req_our_resources()
heartbeat[22296]: 2007/10/12_11:59:01 info: FIFO message [type resource] written rc=79
heartbeat[22264]: 2007/10/12_11:59:01 info: AnnounceTakeover(local 1, foreign 0, reason 'T_RESOURCES(us)' (0))
heartbeat[22264]: 2007/10/12_11:59:01 info: STATE 1 => 3
heartbeat[22264]: 2007/10/12_11:59:01 debug: hb_rsc_isstable: ResourceMgmt_child_count: 2, other_is_stable: 1, takeover_in_progress: 1, going_standby: 0, standby running(ms): 0, resourcestate: 3
heartbeat[22264]: 2007/10/12_11:59:01 info: Exiting req_our_resources process 22296 returned rc 0.
heartbeat[22264]: 2007/10/12_11:59:01 debug: RscMgmtProc 'req_our_resources' exited code 0
heartbeat[22264]: 2007/10/12_11:59:01 info: AnnounceTakeover(local 1, foreign 0, reason 'req_our_resources' (0))
heartbeat[22264]: 2007/10/12_11:59:01 debug: StartNextRemoteRscReq(): child count 1
harc[22295]:	2007/10/12_11:59:01 info: Running /etc/ha.d/rc.d/status status
heartbeat[22264]: 2007/10/12_11:59:02 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: client->gsource = 0x9b28e38
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: Checking client authorization for client 22329 (0:65)
heartbeat[22264]: 2007/10/12_11:59:02 WARN: Client [22329] pid 22329 failed authorization [client failed authorization]
heartbeat[22264]: 2007/10/12_11:59:02 ERROR: api_process_registration_msg: cannot add client(22329)
heartbeat[22264]: 2007/10/12_11:59:02 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:02 debug: Signing on API client 22329 ('casual')
heartbeat[22264]: 2007/10/12_11:59:02 debug: G_remove_client(pid=22329, reason='client failed authorization' gsource=0x9b28e38) {
heartbeat[22264]: 2007/10/12_11:59:02 debug: api_remove_client_int: removing pid [22329] reason: client failed authorization
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*G_remove_client;*/
heartbeat[22264]: 2007/10/12_11:59:02 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: client->gsource = 0x9b34d78
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: Checking client authorization for client 22330 (0:65)
heartbeat[22264]: 2007/10/12_11:59:02 WARN: Client [22330] pid 22330 failed authorization [client failed authorization]
heartbeat[22264]: 2007/10/12_11:59:02 ERROR: api_process_registration_msg: cannot add client(22330)
heartbeat[22264]: 2007/10/12_11:59:02 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:02 debug: Signing on API client 22330 ('casual')
heartbeat[22264]: 2007/10/12_11:59:02 debug: G_remove_client(pid=22330, reason='client failed authorization' gsource=0x9b34d78) {
heartbeat[22264]: 2007/10/12_11:59:02 debug: api_remove_client_int: removing pid [22330] reason: client failed authorization
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*G_remove_client;*/
heartbeat[22264]: 2007/10/12_11:59:02 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: client->gsource = 0x9add770
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: Checking client authorization for client 22331 (0:65)
heartbeat[22264]: 2007/10/12_11:59:02 WARN: Client [22331] pid 22331 failed authorization [client failed authorization]
heartbeat[22264]: 2007/10/12_11:59:02 ERROR: api_process_registration_msg: cannot add client(22331)
heartbeat[22264]: 2007/10/12_11:59:02 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:02 debug: Signing on API client 22331 ('casual')
heartbeat[22264]: 2007/10/12_11:59:02 debug: G_remove_client(pid=22331, reason='client failed authorization' gsource=0x9add770) {
heartbeat[22264]: 2007/10/12_11:59:02 debug: api_remove_client_int: removing pid [22331] reason: client failed authorization
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*G_remove_client;*/
/usr/bin/cl_status shows status of [dac.uftmasterad.org] as dead (11)
heartbeat[22264]: 2007/10/12_11:59:02 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: client->gsource = 0x9adc5b0
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: Checking client authorization for client 22335 (0:65)
heartbeat[22264]: 2007/10/12_11:59:02 WARN: Client [22335] pid 22335 failed authorization [client failed authorization]
heartbeat[22264]: 2007/10/12_11:59:02 ERROR: api_process_registration_msg: cannot add client(22335)
heartbeat[22264]: 2007/10/12_11:59:02 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:02 debug: Signing on API client 22335 ('casual')
heartbeat[22264]: 2007/10/12_11:59:02 debug: G_remove_client(pid=22335, reason='client failed authorization' gsource=0x9adc5b0) {
heartbeat[22264]: 2007/10/12_11:59:02 debug: api_remove_client_int: removing pid [22335] reason: client failed authorization
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*G_remove_client;*/
/usr/bin/cl_status shows status of [127.0.0.1] as dead (11)
heartbeat[22264]: 2007/10/12_11:59:02 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: client->gsource = 0x9acfcf0
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: Checking client authorization for client 22346 (0:65)
heartbeat[22264]: 2007/10/12_11:59:02 WARN: Client [22346] pid 22346 failed authorization [client failed authorization]
heartbeat[22264]: 2007/10/12_11:59:02 ERROR: api_process_registration_msg: cannot add client(22346)
heartbeat[22264]: 2007/10/12_11:59:02 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:02 debug: Signing on API client 22346 ('casual')
heartbeat[22264]: 2007/10/12_11:59:02 debug: G_remove_client(pid=22346, reason='client failed authorization' gsource=0x9acfcf0) {
heartbeat[22264]: 2007/10/12_11:59:02 debug: api_remove_client_int: removing pid [22346] reason: client failed authorization
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*G_remove_client;*/
/usr/bin/cl_status shows status of [pingus_please] as dead (11)
heartbeat[22264]: 2007/10/12_11:59:02 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:02 debug: client->gsource = 0x9ad1970
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:02 debug: Checking client authorization for client 22351 (0:65)
heartbeat[22264]: 2007/10/12_11:59:02 WARN: Client [22351] pid 22351 failed authorization [client failed authorization]
heartbeat[22264]: 2007/10/12_11:59:02 ERROR: api_process_registration_msg: cannot add client(22351)
heartbeat[22264]: 2007/10/12_11:59:02 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:02 debug: Signing on API client 22351 ('casual')
heartbeat[22264]: 2007/10/12_11:59:02 debug: G_remove_client(pid=22351, reason='client failed authorization' gsource=0x9ad1970) {
heartbeat[22264]: 2007/10/12_11:59:02 debug: api_remove_client_int: removing pid [22351] reason: client failed authorization
heartbeat[22264]: 2007/10/12_11:59:02 debug: }/*G_remove_client;*/
ccm[22293]: 2007/10/12_11:59:02 debug: node state CCM_STATE_NONE -> CCM_STATE_NONE
ccm[22293]: 2007/10/12_11:59:02 debug: No quorum selected,using default quorum plugin(majority:twonodes)
ccm[22293]: 2007/10/12_11:59:02 debug: quorum plugin: majority
ccm[22293]: 2007/10/12_11:59:02 debug: cluster:linux-ha, member_count=1, member_quorum_votes=100
ccm[22293]: 2007/10/12_11:59:02 debug: total_node_count=2, total_quorum_votes=200
ccm[22293]: 2007/10/12_11:59:02 debug: quorum plugin: twonodes
ccm[22293]: 2007/10/12_11:59:02 debug: cluster:linux-ha, member_count=1, member_quorum_votes=100
ccm[22293]: 2007/10/12_11:59:02 debug: total_node_count=2, total_quorum_votes=200
ccm[22293]: 2007/10/12_11:59:02 info: Break tie for 2 nodes cluster
ccm[22293]: 2007/10/12_11:59:02 debug: node state CCM_STATE_NONE -> CCM_STATE_JOINED
ccm[22293]: 2007/10/12_11:59:02 debug: dump current membership 0xb7f26018
ccm[22293]: 2007/10/12_11:59:02 debug: 	leader=dac.uftmasterad.org
ccm[22293]: 2007/10/12_11:59:02 debug: 	transition=1
ccm[22293]: 2007/10/12_11:59:02 debug: 	status=CCM_STATE_JOINED
ccm[22293]: 2007/10/12_11:59:02 debug: 	has_quorum=1
ccm[22293]: 2007/10/12_11:59:02 debug: 	nodename=dac.uftmasterad.org bornon=1
ccm[22293]: 2007/10/12_11:59:02 debug: quorum is 1
ccm[22293]: 2007/10/12_11:59:02 debug: delivering new membership to 0 clients: 
ccm[22293]: 2007/10/12_11:59:02 info: G_main_add_SignalHandler: Added signal handler for signal 15
mach_down[22323]:	2007/10/12_11:59:02 info: Taking over resource group IPaddr::10.253.252.251/30
ResourceManager[22370]:	2007/10/12_11:59:02 info: Acquiring resource group: imalwaysdead.com IPaddr::10.253.252.251/30
IPaddr[22396]:	2007/10/12_11:59:02 INFO:  Resource is stopped
ResourceManager[22370]:	2007/10/12_11:59:03 info: Running /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 start
ResourceManager[22370]:	2007/10/12_11:59:03 debug: Starting /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 start
ccm[22293]: 2007/10/12_11:59:03 debug: recv msg hbapi-clstat from dac.uftmasterad.org, status:join
heartbeat[22264]: 2007/10/12_11:59:03 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:03 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:03 debug: client->gsource = 0x9af5e58
heartbeat[22264]: 2007/10/12_11:59:03 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:03 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:03 debug: Checking client authorization for client 22507 (0:65)
heartbeat[22264]: 2007/10/12_11:59:03 WARN: Client [22507] pid 22507 failed authorization [client failed authorization]
heartbeat[22264]: 2007/10/12_11:59:03 ERROR: api_process_registration_msg: cannot add client(22507)
heartbeat[22264]: 2007/10/12_11:59:03 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:03 debug: Signing on API client 22507 ('casual')
heartbeat[22264]: 2007/10/12_11:59:03 debug: G_remove_client(pid=22507, reason='client failed authorization' gsource=0x9af5e58) {
heartbeat[22264]: 2007/10/12_11:59:03 debug: api_remove_client_int: removing pid [22507] reason: client failed authorization
heartbeat[22264]: 2007/10/12_11:59:03 debug: }/*G_remove_client;*/
/usr/bin/cl_status rscstatus failed [13]
IPaddr[22485]:	2007/10/12_11:59:03 INFO: Using calculated nic for 10.253.252.251: eth0
IPaddr[22485]:	2007/10/12_11:59:03 INFO: Using calculated netmask for 10.253.252.251: 255.255.255.252
IPaddr[22485]:	2007/10/12_11:59:03 DEBUG: Using calculated broadcast for 10.253.252.251: 10.253.252.251
IPaddr[22485]:	2007/10/12_11:59:03 INFO: eval ifconfig eth0:0 10.253.252.251 netmask 255.255.255.252 broadcast 10.253.252.251
IPaddr[22485]:	2007/10/12_11:59:03 DEBUG: Sending Gratuitous Arp for 10.253.252.251 on eth0:0 [eth0]
IPaddr[22459]:	2007/10/12_11:59:03 INFO:  Success
INFO:  Success
ResourceManager[22370]:	2007/10/12_11:59:03 debug: /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 start done. RC=0
mach_down[22323]:	2007/10/12_11:59:03 info: /usr/share/heartbeat/mach_down: nice_failback: foreign resources acquired
heartbeat[22264]: 2007/10/12_11:59:03 info: AnnounceTakeover(local 1, foreign 0, reason 'T_RESOURCES(us)' (0))
heartbeat[22264]: 2007/10/12_11:59:03 info: mach_down takeover complete.
heartbeat[22264]: 2007/10/12_11:59:03 debug: process_resources(3):  other now stable
heartbeat[22264]: 2007/10/12_11:59:03 info: AnnounceTakeover(local 1, foreign 1, reason 'mach_down' (0))
heartbeat[22264]: 2007/10/12_11:59:03 info: Initial resource acquisition complete (mach_down)
heartbeat[22264]: 2007/10/12_11:59:03 debug: hb_rsc_isstable: ResourceMgmt_child_count: 1, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 3
ccm[22293]: 2007/10/12_11:59:03 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
ccm[22293]: 2007/10/12_11:59:03 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
mach_down[22323]:	2007/10/12_11:59:03 info: mach_down takeover complete for node imalwaysdead.com.
heartbeat[22264]: 2007/10/12_11:59:03 info: Exiting status process 22295 returned rc 0.
heartbeat[22264]: 2007/10/12_11:59:03 debug: RscMgmtProc 'status' exited code 0
heartbeat[22264]: 2007/10/12_11:59:04 WARN: G_CH_dispatch_int: Dispatch function for read child was delayed 10 ms (> 2 ms) before being called (GSource: 0x9abcb98)
heartbeat[22264]: 2007/10/12_11:59:04 info: G_CH_dispatch_int: started at 1735577107 should have started at 1735577106
ccm[22293]: 2007/10/12_11:59:04 WARN: ccm_state_joined: received message with unknown cookie, just dropping
ccm[22293]: 2007/10/12_11:59:04 debug: dump current membership 0xb7f26018
ccm[22293]: 2007/10/12_11:59:04 debug: 	leader=dac.uftmasterad.org
ccm[22293]: 2007/10/12_11:59:04 debug: 	transition=1
ccm[22293]: 2007/10/12_11:59:04 debug: 	status=CCM_STATE_JOINED
ccm[22293]: 2007/10/12_11:59:04 debug: 	has_quorum=1
ccm[22293]: 2007/10/12_11:59:04 debug: 	nodename=dac.uftmasterad.org bornon=1
Performing ha.cf reload test
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(deadtime,5)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(initdead,5)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(auto_failback,on)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(ping,127.0.0.1)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(node,dac.uftmasterad.org)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(node,ImAlwaysDead.com)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(use_logd,off)
heartbeat[22662]: 2007/10/12_11:59:05 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[22662]: 2007/10/12_11:59:05 info: respawn directive: hacluster /usr/lib/heartbeat/ccm
heartbeat[22662]: 2007/10/12_11:59:05 info: respawn directive: hacluster /usr/lib/heartbeat/ipfail
heartbeat[22662]: 2007/10/12_11:59:05 debug: uid=root, gid=<null>
heartbeat[22662]: 2007/10/12_11:59:05 debug: uid=hacluster, gid=<null>
heartbeat[22662]: 2007/10/12_11:59:05 debug: uid=hacluster, gid=<null>
heartbeat[22662]: 2007/10/12_11:59:05 debug: uid=<null>, gid=haclient
heartbeat[22662]: 2007/10/12_11:59:05 debug: uid=root, gid=<null>
heartbeat[22662]: 2007/10/12_11:59:05 debug: uid=<null>, gid=haclient
heartbeat[22662]: 2007/10/12_11:59:05 debug: Beginning authentication parsing
heartbeat[22662]: 2007/10/12_11:59:05 debug: 16 max authentication methods
heartbeat[22662]: 2007/10/12_11:59:05 debug: Keyfile opened
heartbeat[22662]: 2007/10/12_11:59:05 debug: Keyfile perms OK
heartbeat[22662]: 2007/10/12_11:59:05 debug: 16 max authentication methods
heartbeat[22662]: 2007/10/12_11:59:05 debug: Found authentication method [sha1]
heartbeat[22662]: 2007/10/12_11:59:05 info: AUTH: i=1: key = 0x8d21a98, auth=0x92721c, authname=sha1
heartbeat[22662]: 2007/10/12_11:59:05 debug: Outbound signing method is 1
heartbeat[22662]: 2007/10/12_11:59:05 debug: Found authentication method [md5]
heartbeat[22662]: 2007/10/12_11:59:05 info: AUTH: i=2: key = 0x8d220a0, auth=0x8ac8d0, authname=md5
heartbeat[22662]: 2007/10/12_11:59:05 debug: Found authentication method [crc]
heartbeat[22662]: 2007/10/12_11:59:05 info: AUTH: i=3: key = 0x8d22690, auth=0x111c5c, authname=crc
heartbeat[22662]: 2007/10/12_11:59:05 debug: Authentication parsing complete [1]
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(cluster,linux-ha)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(hopfudge,1)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(baud,19200)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(hbgenmethod,file)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(realtime,true)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(msgfmt,classic)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(conn_logd_time,60)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(log_badpack,true)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(coredumps,true)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(crm,false)
heartbeat[22662]: 2007/10/12_11:59:05 info: Version 2 support: false
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(autojoin,none)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(uuidfrom,file)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(compression,zlib)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(compression_threshold,2)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(traditional_compression,no)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(max_rexmit_delay,250)
heartbeat[22662]: 2007/10/12_11:59:05 debug: Setting max_rexmit_delay to 250 ms
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(record_config_changes,on)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(record_pengine_inputs,on)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(enable_config_writes,on)
heartbeat[22662]: 2007/10/12_11:59:05 debug: add_option(memreserve,6500)
heartbeat[22662]: 2007/10/12_11:59:05 WARN: Initial dead time [5000 ms] may be too small!
heartbeat[22662]: 2007/10/12_11:59:05 info: Initial dead time accounts for slow network startup time
heartbeat[22662]: 2007/10/12_11:59:05 info: It should be >= deadtime and >= 10 seconds
heartbeat[22662]: 2007/10/12_11:59:05 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[22662]: 2007/10/12_11:59:05 info: Signalling heartbeat pid 22264 to reread config files
heartbeat[22264]: 2007/10/12_11:59:05 debug: stat of /etc/ha.d/ha.cf: 1192204745 versus old 1192204702
heartbeat[22264]: 2007/10/12_11:59:05 debug: hb_initiate_shutdown() called.
heartbeat[22264]: 2007/10/12_11:59:05 debug: hb_initiate_shutdown(): calling hb_mcp_final_shutdown()
heartbeat[22264]: 2007/10/12_11:59:05 debug: hb_mcp_final_shutdown() phase 0
heartbeat[22264]: 2007/10/12_11:59:05 debug: Shutting down client /usr/lib/heartbeat/ipfail
heartbeat[22264]: 2007/10/12_11:59:05 info: killing /usr/lib/heartbeat/ipfail process group 22294 with signal 15
heartbeat[22662]: 2007/10/12_11:59:05 debug: Exiting from pid 22662 [rc=0]
heartbeat[22264]: 2007/10/12_11:59:05 debug: Signing client 22294 off
heartbeat[22264]: 2007/10/12_11:59:05 debug: G_remove_client(pid=22294, reason='signoff' gsource=0x9b1b598) {
heartbeat[22264]: 2007/10/12_11:59:05 debug: api_remove_client_int: removing pid [22294] reason: signoff
heartbeat[22264]: 2007/10/12_11:59:05 debug: }/*G_remove_client;*/
heartbeat[22264]: 2007/10/12_11:59:05 debug: Shutting down client /usr/lib/heartbeat/ccm
heartbeat[22264]: 2007/10/12_11:59:05 info: killing /usr/lib/heartbeat/ccm process group 22293 with signal 15
ccm[22293]: 2007/10/12_11:59:05 info: received SIGTERM, going to shut down
ccm[22293]: 2007/10/12_11:59:05 debug: node state CCM_STATE_JOINED -> CCM_STATE_NONE
heartbeat[22264]: 2007/10/12_11:59:05 debug: EOF from client pid 22293
heartbeat[22264]: 2007/10/12_11:59:05 debug: G_remove_client(pid=22293, reason='EOF' gsource=0x9b1d178) {
heartbeat[22264]: 2007/10/12_11:59:05 debug: api_remove_client_int: removing pid [22293] reason: EOF
heartbeat[22264]: 2007/10/12_11:59:05 debug: }/*G_remove_client;*/
heartbeat[22264]: 2007/10/12_11:59:05 debug: Client /usr/lib/heartbeat/ccm exited with return code 1.
heartbeat[22264]: 2007/10/12_11:59:05 debug: Final client "/usr/lib/heartbeat/ccm" died.
heartbeat[22264]: 2007/10/12_11:59:05 debug: hb_mcp_final_shutdown() phase 1
heartbeat[22264]: 2007/10/12_11:59:06 debug: hb_mcp_final_shutdown() phase 2
heartbeat[22264]: 2007/10/12_11:59:06 info: killing HBWRITE process 22286 with signal 15
heartbeat[22264]: 2007/10/12_11:59:06 info: killing HBREAD process 22287 with signal 15
heartbeat[22264]: 2007/10/12_11:59:06 info: killing HBFIFO process 22281 with signal 15
heartbeat[22264]: 2007/10/12_11:59:06 info: killing HBWRITE process 22282 with signal 15
heartbeat[22264]: 2007/10/12_11:59:06 info: killing HBREAD process 22283 with signal 15
heartbeat[22264]: 2007/10/12_11:59:06 info: killing HBWRITE process 22284 with signal 15
heartbeat[22264]: 2007/10/12_11:59:06 info: killing HBREAD process 22285 with signal 15
heartbeat[22282]: 2007/10/12_11:59:06 debug: Process 22282 processing SIGTERM
heartbeat[22282]: 2007/10/12_11:59:06 debug: Exiting from pid 22282 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:06 info: Core process 22282 exited. 7 remaining
heartbeat[22284]: 2007/10/12_11:59:06 debug: Process 22284 processing SIGTERM
heartbeat[22284]: 2007/10/12_11:59:06 debug: Exiting from pid 22284 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:06 info: Core process 22284 exited. 6 remaining
heartbeat[22286]: 2007/10/12_11:59:06 debug: Process 22286 processing SIGTERM
heartbeat[22286]: 2007/10/12_11:59:06 debug: Exiting from pid 22286 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:06 info: Core process 22286 exited. 5 remaining
heartbeat[22287]: 2007/10/12_11:59:06 debug: Process 22287 processing SIGTERM
heartbeat[22287]: 2007/10/12_11:59:06 debug: Exiting from pid 22287 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:06 info: Core process 22287 exited. 4 remaining
heartbeat[22285]: 2007/10/12_11:59:06 debug: Process 22285 processing SIGTERM
heartbeat[22285]: 2007/10/12_11:59:06 debug: Exiting from pid 22285 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:06 info: Core process 22285 exited. 3 remaining
heartbeat[22283]: 2007/10/12_11:59:06 debug: Process 22283 processing SIGTERM
heartbeat[22283]: 2007/10/12_11:59:06 debug: Exiting from pid 22283 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:06 info: Core process 22283 exited. 2 remaining
heartbeat[22281]: 2007/10/12_11:59:06 debug: Process 22281 processing SIGTERM
heartbeat[22281]: 2007/10/12_11:59:06 debug: Exiting from pid 22281 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:06 info: Core process 22281 exited. 1 remaining
heartbeat[22264]: 2007/10/12_11:59:06 info: dac.uftmasterad.org Heartbeat shutdown complete.
heartbeat[22264]: 2007/10/12_11:59:06 info: Heartbeat restart triggered.
heartbeat[22264]: 2007/10/12_11:59:06 info: Restarting heartbeat.
heartbeat[22264]: 2007/10/12_11:59:06 info: Performing heartbeat restart exec.
heartbeat[22264]: 2007/10/12_11:59:06 info: Current resources: -R -C all
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(deadtime,5)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(initdead,5)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(auto_failback,on)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(ping,127.0.0.1)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(node,dac.uftmasterad.org)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(node,ImAlwaysDead.com)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(use_logd,off)
heartbeat[22264]: 2007/10/12_11:59:06 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[22264]: 2007/10/12_11:59:06 info: respawn directive: hacluster /usr/lib/heartbeat/ccm
heartbeat[22264]: 2007/10/12_11:59:06 info: respawn directive: hacluster /usr/lib/heartbeat/ipfail
heartbeat[22264]: 2007/10/12_11:59:06 debug: uid=root, gid=<null>
heartbeat[22264]: 2007/10/12_11:59:06 debug: uid=hacluster, gid=<null>
heartbeat[22264]: 2007/10/12_11:59:06 debug: uid=hacluster, gid=<null>
heartbeat[22264]: 2007/10/12_11:59:06 debug: uid=<null>, gid=haclient
heartbeat[22264]: 2007/10/12_11:59:06 debug: uid=root, gid=<null>
heartbeat[22264]: 2007/10/12_11:59:06 debug: uid=<null>, gid=haclient
heartbeat[22264]: 2007/10/12_11:59:06 debug: Beginning authentication parsing
heartbeat[22264]: 2007/10/12_11:59:06 debug: 16 max authentication methods
heartbeat[22264]: 2007/10/12_11:59:06 debug: Keyfile opened
heartbeat[22264]: 2007/10/12_11:59:06 debug: Keyfile perms OK
heartbeat[22264]: 2007/10/12_11:59:06 debug: 16 max authentication methods
heartbeat[22264]: 2007/10/12_11:59:06 debug: Found authentication method [sha1]
heartbeat[22264]: 2007/10/12_11:59:06 info: AUTH: i=1: key = 0x91c74d0, auth=0x11421c, authname=sha1
heartbeat[22264]: 2007/10/12_11:59:06 debug: Outbound signing method is 1
heartbeat[22264]: 2007/10/12_11:59:06 debug: Found authentication method [md5]
heartbeat[22264]: 2007/10/12_11:59:06 info: AUTH: i=2: key = 0x91c7ad8, auth=0x1168d0, authname=md5
heartbeat[22264]: 2007/10/12_11:59:06 debug: Found authentication method [crc]
heartbeat[22264]: 2007/10/12_11:59:06 info: AUTH: i=3: key = 0x91c80c8, auth=0x21cc5c, authname=crc
heartbeat[22264]: 2007/10/12_11:59:06 debug: Authentication parsing complete [1]
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(cluster,linux-ha)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(hopfudge,1)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(baud,19200)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(hbgenmethod,file)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(realtime,true)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(msgfmt,classic)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(conn_logd_time,60)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(log_badpack,true)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(coredumps,true)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(crm,false)
heartbeat[22264]: 2007/10/12_11:59:06 info: Version 2 support: false
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(autojoin,none)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(uuidfrom,file)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(compression,zlib)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(compression_threshold,2)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(traditional_compression,no)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(max_rexmit_delay,250)
heartbeat[22264]: 2007/10/12_11:59:06 debug: Setting max_rexmit_delay to 250 ms
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(record_config_changes,on)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(record_pengine_inputs,on)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(enable_config_writes,on)
heartbeat[22264]: 2007/10/12_11:59:06 debug: add_option(memreserve,6500)
heartbeat[22264]: 2007/10/12_11:59:06 WARN: Initial dead time [5000 ms] may be too small!
heartbeat[22264]: 2007/10/12_11:59:06 info: Initial dead time accounts for slow network startup time
heartbeat[22264]: 2007/10/12_11:59:06 info: It should be >= deadtime and >= 10 seconds
heartbeat[22264]: 2007/10/12_11:59:06 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[22264]: 2007/10/12_11:59:06 info: **************************
heartbeat[22264]: 2007/10/12_11:59:06 info: Configuration validated. Starting heartbeat 2.1.2
heartbeat[22264]: 2007/10/12_11:59:06 info: restart: i_hold_resources = all
heartbeat[22264]: 2007/10/12_11:59:06 debug: HA configuration OK.  Heartbeat starting.
heartbeat[22264]: 2007/10/12_11:59:06 info: heartbeat: version 2.1.2
heartbeat[22264]: 2007/10/12_11:59:06 info: Heartbeat generation: 1191438894
heartbeat[22264]: 2007/10/12_11:59:06 debug: uuid is:add366cf-0375-4bd4-8bcd-3c392b9a84b2
heartbeat[22264]: 2007/10/12_11:59:06 info: G_main_add_TriggerHandler: Added signal manual handler
heartbeat[22264]: 2007/10/12_11:59:06 info: G_main_add_TriggerHandler: Added signal manual handler
heartbeat[22264]: 2007/10/12_11:59:06 info: Removing /var/run/heartbeat/rsctmp failed, recreating.
heartbeat[22264]: 2007/10/12_11:59:06 debug: opening mcast eth0 (UDP/IP multicast)
heartbeat[22264]: 2007/10/12_11:59:06 debug: opening ping 127.0.0.1 (ping membership)
heartbeat[22264]: 2007/10/12_11:59:06 debug: opening ping_group pingus_please (ping group membership)
heartbeat[22264]: 2007/10/12_11:59:06 debug: FIFO process pid: 22665
heartbeat[22264]: 2007/10/12_11:59:06 debug: glib: looking up address for eth0
heartbeat[22264]: 2007/10/12_11:59:06 debug: glib: looking up address for eth0
heartbeat[22264]: 2007/10/12_11:59:06 debug: glib: Successfully joined multicast group 225.224.223.222 oninterface eth0
heartbeat[22264]: 2007/10/12_11:59:06 info: glib: UDP multicast heartbeat started for group 225.224.223.222 port 694 interface eth0 (ttl=0 loop=0)
heartbeat[22264]: 2007/10/12_11:59:06 debug: write process pid: 22666
heartbeat[22264]: 2007/10/12_11:59:06 debug: read child process pid: 22667
heartbeat[22264]: 2007/10/12_11:59:06 info: glib: ping heartbeat started.
heartbeat[22264]: 2007/10/12_11:59:06 debug: write process pid: 22668
heartbeat[22264]: 2007/10/12_11:59:06 debug: read child process pid: 22669
heartbeat[22264]: 2007/10/12_11:59:06 info: glib: ping group heartbeat started.
heartbeat[22264]: 2007/10/12_11:59:06 debug: write process pid: 22670
heartbeat[22264]: 2007/10/12_11:59:06 debug: read child process pid: 22671
heartbeat[22264]: 2007/10/12_11:59:06 info: G_main_add_SignalHandler: Added signal handler for signal 17
heartbeat[22264]: 2007/10/12_11:59:06 debug: Limiting CPU: 42 CPU seconds every 60000 milliseconds
heartbeat[22264]: 2007/10/12_11:59:06 debug: pid 22264 locked in memory.
heartbeat[22264]: 2007/10/12_11:59:06 debug: Waiting for child processes to start
heartbeat[22264]: 2007/10/12_11:59:06 info: Local status now set to: 'up'
heartbeat[22264]: 2007/10/12_11:59:06 debug: All your child process are belong to us
heartbeat[22264]: 2007/10/12_11:59:06 debug: Starting local status message @ 10 ms intervals
heartbeat[22665]: 2007/10/12_11:59:08 debug: pid 22665 locked in memory.
heartbeat[22665]: 2007/10/12_11:59:08 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[22666]: 2007/10/12_11:59:08 debug: pid 22666 locked in memory.
heartbeat[22666]: 2007/10/12_11:59:08 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[22667]: 2007/10/12_11:59:08 debug: pid 22667 locked in memory.
heartbeat[22667]: 2007/10/12_11:59:08 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[22668]: 2007/10/12_11:59:08 debug: pid 22668 locked in memory.
heartbeat[22668]: 2007/10/12_11:59:08 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[22669]: 2007/10/12_11:59:08 debug: pid 22669 locked in memory.
heartbeat[22669]: 2007/10/12_11:59:08 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[22264]: 2007/10/12_11:59:08 info: Link 127.0.0.1:127.0.0.1 up.
heartbeat[22264]: 2007/10/12_11:59:08 debug: CreateInitialFilter: ask_resources
heartbeat[22264]: 2007/10/12_11:59:08 debug: CreateInitialFilter: ip-request-resp
heartbeat[22264]: 2007/10/12_11:59:08 debug: CreateInitialFilter: ip-request
heartbeat[22264]: 2007/10/12_11:59:08 debug: CreateInitialFilter: status
heartbeat[22264]: 2007/10/12_11:59:08 debug: CreateInitialFilter: hb_takeover
heartbeat[22264]: 2007/10/12_11:59:08 info: Status update for node 127.0.0.1: status ping
heartbeat[22264]: 2007/10/12_11:59:08 debug: Status seqno: 0 msgtime: 1192204746
heartbeat[22264]: 2007/10/12_11:59:08 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 30 ms (> 15 ms) before being called (GSource: 0x91d6910)
heartbeat[22264]: 2007/10/12_11:59:08 info: Gmain_timeout_dispatch: started at 1735577484 should have started at 1735577481
heartbeat[22670]: 2007/10/12_11:59:08 debug: pid 22670 locked in memory.
heartbeat[22670]: 2007/10/12_11:59:08 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[22671]: 2007/10/12_11:59:08 debug: pid 22671 locked in memory.
heartbeat[22671]: 2007/10/12_11:59:08 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[22264]: 2007/10/12_11:59:08 info: Link pingus_please:pingus_please up.
heartbeat[22264]: 2007/10/12_11:59:08 info: Status update for node pingus_please: status ping
heartbeat[22264]: 2007/10/12_11:59:08 debug: Status seqno: 0 msgtime: 1192204746
Performing authkeys reload test
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(deadtime,5)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(initdead,5)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(auto_failback,on)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(ping,127.0.0.1)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(node,dac.uftmasterad.org)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(node,ImAlwaysDead.com)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(use_logd,off)
heartbeat[22703]: 2007/10/12_11:59:10 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[22703]: 2007/10/12_11:59:10 info: respawn directive: hacluster /usr/lib/heartbeat/ccm
heartbeat[22703]: 2007/10/12_11:59:10 info: respawn directive: hacluster /usr/lib/heartbeat/ipfail
heartbeat[22703]: 2007/10/12_11:59:10 debug: uid=root, gid=<null>
heartbeat[22703]: 2007/10/12_11:59:10 debug: uid=hacluster, gid=<null>
heartbeat[22703]: 2007/10/12_11:59:10 debug: uid=hacluster, gid=<null>
heartbeat[22703]: 2007/10/12_11:59:10 debug: uid=<null>, gid=haclient
heartbeat[22703]: 2007/10/12_11:59:10 debug: uid=root, gid=<null>
heartbeat[22703]: 2007/10/12_11:59:10 debug: uid=<null>, gid=haclient
heartbeat[22703]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22703]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22703]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22703]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22703]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22703]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22703]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x8cc4a98, auth=0x5bd21c, authname=sha1
heartbeat[22703]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22703]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x8cc50a0, auth=0x3e68d0, authname=md5
heartbeat[22703]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22703]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22703]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x8cc5690, auth=0x111c5c, authname=crc
heartbeat[22703]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(cluster,linux-ha)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(hopfudge,1)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(baud,19200)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(hbgenmethod,file)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(realtime,true)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(msgfmt,classic)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(conn_logd_time,60)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(log_badpack,true)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(coredumps,true)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(crm,false)
heartbeat[22703]: 2007/10/12_11:59:10 info: Version 2 support: false
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(autojoin,none)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(uuidfrom,file)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(compression,zlib)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(compression_threshold,2)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(traditional_compression,no)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(max_rexmit_delay,250)
heartbeat[22703]: 2007/10/12_11:59:10 debug: Setting max_rexmit_delay to 250 ms
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(record_config_changes,on)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(record_pengine_inputs,on)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(enable_config_writes,on)
heartbeat[22703]: 2007/10/12_11:59:10 debug: add_option(memreserve,6500)
heartbeat[22703]: 2007/10/12_11:59:10 WARN: Initial dead time [5000 ms] may be too small!
heartbeat[22703]: 2007/10/12_11:59:10 info: Initial dead time accounts for slow network startup time
heartbeat[22703]: 2007/10/12_11:59:10 info: It should be >= deadtime and >= 10 seconds
heartbeat[22703]: 2007/10/12_11:59:10 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[22703]: 2007/10/12_11:59:10 info: Signalling heartbeat pid 22264 to reread config files
heartbeat[22264]: 2007/10/12_11:59:10 debug: stat of /etc/ha.d/ha.cf: 1192204745 versus old 1192204745
heartbeat[22264]: 2007/10/12_11:59:10 info: Rereading authentication file.
heartbeat[22665]: 2007/10/12_11:59:10 info: Child rereading authentication file.
heartbeat[22665]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22665]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22665]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22665]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22665]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c6dd8/sha1)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c6dd8/sha1)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/sha1)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/sha1
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/sha1.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(104 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/sha1)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/sha1)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/sha1)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c6dd8/sha1)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7510/md5)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7510/md5)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/md5)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/md5
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/md5.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/md5)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/md5)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/md5)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7510/md5)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7b18/crc)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7b18/crc)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/crc)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/crc
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/crc.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/crc)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/crc)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/crc)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: RmAPILPluginType(HBauth)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: DelPILPluginType(HBauth)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7b18/crc)
heartbeat[22665]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Creating PluginType for HBauth
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILPlugintype(0x91c80c8)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1  init function: HBauth_LTX_sha1_pil_plugin_init
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c6f58)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1 loaded and constructed.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/sha1.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18:HBauth/sha1)*** user_data: 0x0 *******
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/sha1 registering.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [sha1] in hash table @ 0x091bd4a0
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x0086721c
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(101 + 1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22665]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x91c7fc8, auth=0x86721c, authname=sha1
heartbeat[22665]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5  init function: HBauth_LTX_md5_pil_plugin_init
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c7ad8)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5 loaded and constructed.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/md5.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510:HBauth/md5)*** user_data: 0x0 *******
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/md5 registering.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [md5] in hash table @ 0x091bd4a0
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x007ad8d0
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + 1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22665]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x91c79d8, auth=0x7ad8d0, authname=md5
heartbeat[22665]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22665]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc  init function: HBauth_LTX_crc_pil_plugin_init
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c74d0)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc loaded and constructed.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/crc.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8)
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8:HBauth/crc)*** user_data: 0x0 *******
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/crc registering.
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [crc] in hash table @ 0x091bd4a0
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00111c5c
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + 1 )
heartbeat[22665]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22665]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x91c73d0, auth=0x111c5c, authname=crc
heartbeat[22665]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22666]: 2007/10/12_11:59:10 info: Child rereading authentication file.
heartbeat[22666]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22666]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22666]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22666]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22666]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c6dd8/sha1)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c6dd8/sha1)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/sha1)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/sha1
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/sha1.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(104 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/sha1)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/sha1)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/sha1)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c6dd8/sha1)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7510/md5)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7510/md5)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/md5)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/md5
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/md5.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/md5)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/md5)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/md5)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7510/md5)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7b18/crc)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7b18/crc)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/crc)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/crc
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/crc.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/crc)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/crc)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/crc)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: RmAPILPluginType(HBauth)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: DelPILPluginType(HBauth)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7b18/crc)
heartbeat[22666]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Creating PluginType for HBauth
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILPlugintype(0x91c80c8)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1  init function: HBauth_LTX_sha1_pil_plugin_init
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c6f58)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1 loaded and constructed.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/sha1.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18:HBauth/sha1)*** user_data: 0x0 *******
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/sha1 registering.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [sha1] in hash table @ 0x091bd4a0
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x001b421c
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(101 + 1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22666]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x91c7fc8, auth=0x1b421c, authname=sha1
heartbeat[22666]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5  init function: HBauth_LTX_md5_pil_plugin_init
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c7ad8)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5 loaded and constructed.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/md5.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510:HBauth/md5)*** user_data: 0x0 *******
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/md5 registering.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [md5] in hash table @ 0x091bd4a0
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x001458d0
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + 1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22666]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x91c79d8, auth=0x1458d0, authname=md5
heartbeat[22666]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22666]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc  init function: HBauth_LTX_crc_pil_plugin_init
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c74d0)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc loaded and constructed.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/crc.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8)
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8:HBauth/crc)*** user_data: 0x0 *******
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/crc registering.
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [crc] in hash table @ 0x091bd4a0
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00111c5c
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + 1 )
heartbeat[22666]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22666]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x91c73d0, auth=0x111c5c, authname=crc
heartbeat[22666]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22667]: 2007/10/12_11:59:10 info: Child rereading authentication file.
heartbeat[22667]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22667]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22667]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22667]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22667]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c6dd8/sha1)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c6dd8/sha1)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/sha1)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/sha1
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/sha1.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(104 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/sha1)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/sha1)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/sha1)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c6dd8/sha1)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7510/md5)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7510/md5)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/md5)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/md5
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/md5.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/md5)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/md5)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/md5)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7510/md5)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7b18/crc)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7b18/crc)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/crc)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/crc
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/crc.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/crc)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/crc)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/crc)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: RmAPILPluginType(HBauth)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: DelPILPluginType(HBauth)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7b18/crc)
heartbeat[22667]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Creating PluginType for HBauth
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILPlugintype(0x91c80c8)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1  init function: HBauth_LTX_sha1_pil_plugin_init
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c6f58)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1 loaded and constructed.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/sha1.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18:HBauth/sha1)*** user_data: 0x0 *******
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/sha1 registering.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [sha1] in hash table @ 0x091bd4a0
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x0023e21c
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(101 + 1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22667]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x91c7fc8, auth=0x23e21c, authname=sha1
heartbeat[22667]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5  init function: HBauth_LTX_md5_pil_plugin_init
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c7ad8)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5 loaded and constructed.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/md5.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510:HBauth/md5)*** user_data: 0x0 *******
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/md5 registering.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [md5] in hash table @ 0x091bd4a0
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x002328d0
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + 1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22667]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x91c79d8, auth=0x2328d0, authname=md5
heartbeat[22667]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22667]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc  init function: HBauth_LTX_crc_pil_plugin_init
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c74d0)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc loaded and constructed.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/crc.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8)
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8:HBauth/crc)*** user_data: 0x0 *******
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/crc registering.
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [crc] in hash table @ 0x091bd4a0
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00111c5c
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + 1 )
heartbeat[22667]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22667]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x91c73d0, auth=0x111c5c, authname=crc
heartbeat[22667]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22668]: 2007/10/12_11:59:10 info: Child rereading authentication file.
heartbeat[22668]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22668]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22668]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22668]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22668]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c6dd8/sha1)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c6dd8/sha1)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/sha1)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/sha1
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/sha1.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(104 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/sha1)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/sha1)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/sha1)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c6dd8/sha1)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7510/md5)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7510/md5)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/md5)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/md5
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/md5.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/md5)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/md5)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/md5)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7510/md5)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7b18/crc)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7b18/crc)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/crc)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/crc
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/crc.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/crc)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/crc)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/crc)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: RmAPILPluginType(HBauth)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: DelPILPluginType(HBauth)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7b18/crc)
heartbeat[22668]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Creating PluginType for HBauth
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILPlugintype(0x91c80c8)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1  init function: HBauth_LTX_sha1_pil_plugin_init
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c6f58)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1 loaded and constructed.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/sha1.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18:HBauth/sha1)*** user_data: 0x0 *******
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/sha1 registering.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [sha1] in hash table @ 0x091bd4a0
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x0093221c
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(101 + 1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22668]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x91c7fc8, auth=0x93221c, authname=sha1
heartbeat[22668]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5  init function: HBauth_LTX_md5_pil_plugin_init
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c7ad8)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5 loaded and constructed.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/md5.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510:HBauth/md5)*** user_data: 0x0 *******
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/md5 registering.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [md5] in hash table @ 0x091bd4a0
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00cb98d0
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + 1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22668]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x91c79d8, auth=0xcb98d0, authname=md5
heartbeat[22668]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22668]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc  init function: HBauth_LTX_crc_pil_plugin_init
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c74d0)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc loaded and constructed.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/crc.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8)
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8:HBauth/crc)*** user_data: 0x0 *******
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/crc registering.
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [crc] in hash table @ 0x091bd4a0
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00111c5c
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + 1 )
heartbeat[22668]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22668]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x91c73d0, auth=0x111c5c, authname=crc
heartbeat[22668]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22669]: 2007/10/12_11:59:10 info: Child rereading authentication file.
heartbeat[22669]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22669]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22669]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22669]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22669]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c6dd8/sha1)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c6dd8/sha1)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/sha1)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/sha1
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/sha1.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(104 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/sha1)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/sha1)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/sha1)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c6dd8/sha1)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7510/md5)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7510/md5)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/md5)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/md5
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/md5.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/md5)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/md5)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/md5)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7510/md5)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7b18/crc)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7b18/crc)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/crc)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/crc
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/crc.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/crc)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/crc)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/crc)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: RmAPILPluginType(HBauth)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: DelPILPluginType(HBauth)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7b18/crc)
heartbeat[22669]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Creating PluginType for HBauth
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILPlugintype(0x91c80c8)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1  init function: HBauth_LTX_sha1_pil_plugin_init
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c6f58)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1 loaded and constructed.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/sha1.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18:HBauth/sha1)*** user_data: 0x0 *******
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/sha1 registering.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [sha1] in hash table @ 0x091bd4a0
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x0013221c
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(101 + 1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22669]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x91c7fc8, auth=0x13221c, authname=sha1
heartbeat[22669]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5  init function: HBauth_LTX_md5_pil_plugin_init
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c7ad8)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5 loaded and constructed.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/md5.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510:HBauth/md5)*** user_data: 0x0 *******
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/md5 registering.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [md5] in hash table @ 0x091bd4a0
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00fdb8d0
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + 1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22669]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x91c79d8, auth=0xfdb8d0, authname=md5
heartbeat[22669]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22669]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc  init function: HBauth_LTX_crc_pil_plugin_init
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c74d0)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc loaded and constructed.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/crc.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8)
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8:HBauth/crc)*** user_data: 0x0 *******
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/crc registering.
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [crc] in hash table @ 0x091bd4a0
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00111c5c
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + 1 )
heartbeat[22669]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22669]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x91c73d0, auth=0x111c5c, authname=crc
heartbeat[22669]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22670]: 2007/10/12_11:59:10 info: Child rereading authentication file.
heartbeat[22670]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22670]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22670]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22670]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22670]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c6dd8/sha1)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c6dd8/sha1)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/sha1)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/sha1
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/sha1.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(104 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/sha1)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/sha1)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/sha1)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c6dd8/sha1)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7510/md5)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7510/md5)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/md5)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/md5
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/md5.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/md5)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/md5)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/md5)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7510/md5)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7b18/crc)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7b18/crc)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/crc)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/crc
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/crc.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/crc)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/crc)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/crc)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: RmAPILPluginType(HBauth)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: DelPILPluginType(HBauth)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7b18/crc)
heartbeat[22670]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Creating PluginType for HBauth
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILPlugintype(0x91c80c8)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1  init function: HBauth_LTX_sha1_pil_plugin_init
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c6f58)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1 loaded and constructed.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/sha1.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18:HBauth/sha1)*** user_data: 0x0 *******
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/sha1 registering.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [sha1] in hash table @ 0x091bd4a0
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x0069221c
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(101 + 1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22670]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x91c7fc8, auth=0x69221c, authname=sha1
heartbeat[22670]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5  init function: HBauth_LTX_md5_pil_plugin_init
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c7ad8)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5 loaded and constructed.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/md5.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510:HBauth/md5)*** user_data: 0x0 *******
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/md5 registering.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [md5] in hash table @ 0x091bd4a0
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x0066e8d0
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + 1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22670]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x91c79d8, auth=0x66e8d0, authname=md5
heartbeat[22670]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22670]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc  init function: HBauth_LTX_crc_pil_plugin_init
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c74d0)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc loaded and constructed.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/crc.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8)
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8:HBauth/crc)*** user_data: 0x0 *******
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/crc registering.
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [crc] in hash table @ 0x091bd4a0
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00111c5c
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + 1 )
heartbeat[22670]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22670]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x91c73d0, auth=0x111c5c, authname=crc
heartbeat[22670]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22671]: 2007/10/12_11:59:10 info: Child rereading authentication file.
heartbeat[22671]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22671]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22671]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22671]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22671]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c6dd8/sha1)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c6dd8/sha1)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/sha1)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/sha1
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/sha1.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(104 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/sha1)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/sha1)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/sha1)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c6dd8/sha1)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7510/md5)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7510/md5)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/md5)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/md5
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/md5.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/md5)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/md5)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/md5)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7510/md5)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7b18/crc)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7b18/crc)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/crc)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/crc
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/crc.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/crc)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/crc)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/crc)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: RmAPILPluginType(HBauth)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: DelPILPluginType(HBauth)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7b18/crc)
heartbeat[22671]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Creating PluginType for HBauth
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILPlugintype(0x91c80c8)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1  init function: HBauth_LTX_sha1_pil_plugin_init
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c6f58)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1 loaded and constructed.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/sha1.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18:HBauth/sha1)*** user_data: 0x0 *******
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/sha1 registering.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [sha1] in hash table @ 0x091bd4a0
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00b6a21c
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(101 + 1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22671]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x91c7fc8, auth=0xb6a21c, authname=sha1
heartbeat[22671]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5  init function: HBauth_LTX_md5_pil_plugin_init
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c7ad8)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5 loaded and constructed.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/md5.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510:HBauth/md5)*** user_data: 0x0 *******
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/md5 registering.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [md5] in hash table @ 0x091bd4a0
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00a688d0
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + 1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22671]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x91c79d8, auth=0xa688d0, authname=md5
heartbeat[22671]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22671]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc  init function: HBauth_LTX_crc_pil_plugin_init
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c74d0)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc loaded and constructed.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/crc.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8)
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8:HBauth/crc)*** user_data: 0x0 *******
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/crc registering.
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [crc] in hash table @ 0x091bd4a0
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00111c5c
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + 1 )
heartbeat[22671]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22671]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x91c73d0, auth=0x111c5c, authname=crc
heartbeat[22671]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22264]: 2007/10/12_11:59:10 WARN: Gmain_timeout_dispatch: Dispatch function for send local status was delayed 50 ms (> 15 ms) before being called (GSource: 0x91d65d0)
heartbeat[22264]: 2007/10/12_11:59:10 info: Gmain_timeout_dispatch: started at 1735577708 should have started at 1735577703
heartbeat[22264]: 2007/10/12_11:59:10 debug: Beginning authentication parsing
heartbeat[22264]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22264]: 2007/10/12_11:59:10 debug: Keyfile opened
heartbeat[22264]: 2007/10/12_11:59:10 debug: Keyfile perms OK
heartbeat[22264]: 2007/10/12_11:59:10 debug: 16 max authentication methods
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c6dd8/sha1)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c6dd8/sha1)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/sha1)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/sha1
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/sha1.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(104 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/sha1)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/sha1)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/sha1)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c6dd8/sha1)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7510/md5)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7510/md5)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/md5)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/md5
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/md5.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/md5)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/md5)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/md5)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7510/md5)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(1 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RemoveAPILInterface(0x91c7b18/crc)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RmAPILInterface(0x91c7b18/crc)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PILunregister_interface(HBauth/crc)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Calling InterfaceClose on HBauth/crc
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: unregistering interface HBauth/crc.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(1 + -1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RemoveAPILPlugin(HBauth/crc)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RmAPILPlugin(HBauth/crc)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Closing dlhandle for (HBauth/crc)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: RmAPILPluginType(HBauth)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: DelPILPluginType(HBauth)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: DelPILInterface(0x91c7b18/crc)
heartbeat[22264]: 2007/10/12_11:59:10 debug: Found authentication method [sha1]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/sha1 => [/usr/lib/heartbeat/plugins/HBauth/sha1.so]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Creating PluginType for HBauth
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILPlugintype(0x91c80c8)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1  init function: HBauth_LTX_sha1_pil_plugin_init
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c6f58)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/sha1 loaded and constructed.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/sha1.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7b18:HBauth/sha1)*** user_data: 0x0 *******
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/sha1 registering.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [sha1] in hash table @ 0x091bd4a0
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x0034a21c
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(101 + 1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22264]: 2007/10/12_11:59:10 info: AUTH: i=1: key = 0x91c7fc8, auth=0x34a21c, authname=sha1
heartbeat[22264]: 2007/10/12_11:59:10 debug: Found authentication method [md5]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/md5 => [/usr/lib/heartbeat/plugins/HBauth/md5.so]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5  init function: HBauth_LTX_md5_pil_plugin_init
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c7ad8)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/md5 loaded and constructed.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/md5.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c7510:HBauth/md5)*** user_data: 0x0 *******
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/md5 registering.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [md5] in hash table @ 0x091bd4a0
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x0033e8d0
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(102 + 1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22264]: 2007/10/12_11:59:10 info: AUTH: i=2: key = 0x91c79d8, auth=0x33e8d0, authname=md5
heartbeat[22264]: 2007/10/12_11:59:10 debug: Outbound signing method is 2
heartbeat[22264]: 2007/10/12_11:59:10 debug: Found authentication method [crc]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PILS: Looking for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin path for HBauth/crc => [/usr/lib/heartbeat/plugins/HBauth/crc.so]
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PluginType HBauth already present
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc  init function: HBauth_LTX_crc_pil_plugin_init
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILPlugin(0x91c74d0)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Plugin HBauth/crc loaded and constructed.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: Calling init function in plugin HBauth/crc.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8)
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: NewPILInterface(0x91c6dd8:HBauth/crc)*** user_data: 0x0 *******
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: interface HBauth/crc registering.
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Inserted interface [crc] in hash table @ 0x091bd4a0
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: generic IF manager: Exports are here: 0x00111c5c
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: IfIncrRefCount(103 + 1 )
heartbeat[22264]: 2007/10/12_11:59:10 debug: glib: PluginIncrRefCount(0 + 1 )
heartbeat[22264]: 2007/10/12_11:59:10 info: AUTH: i=3: key = 0x91c73d0, auth=0x111c5c, authname=crc
heartbeat[22264]: 2007/10/12_11:59:10 debug: Authentication parsing complete [1]
heartbeat[22264]: 2007/10/12_11:59:10 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 50 ms (> 15 ms) before being called (GSource: 0x91d6910)
heartbeat[22264]: 2007/10/12_11:59:10 info: Gmain_timeout_dispatch: started at 1735577708 should have started at 1735577703
heartbeat[22703]: 2007/10/12_11:59:10 debug: Exiting from pid 22703 [rc=0]
heartbeat[22264]: 2007/10/12_11:59:11 WARN: node imalwaysdead.com: is dead
heartbeat[22264]: 2007/10/12_11:59:11 info: Comm_now_up(): updating status to active
heartbeat[22264]: 2007/10/12_11:59:11 info: Local status now set to: 'active'
heartbeat[22264]: 2007/10/12_11:59:11 debug: Sending local starting msg: resourcestate = 0
heartbeat[22264]: 2007/10/12_11:59:11 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 0, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 4
heartbeat[22264]: 2007/10/12_11:59:11 info: Starting child client "/usr/lib/heartbeat/ccm" (17,503)
heartbeat[22264]: 2007/10/12_11:59:11 info: Starting child client "/usr/lib/heartbeat/ipfail" (17,503)
heartbeat[22705]: 2007/10/12_11:59:11 info: Resetting node imalwaysdead.com with [NULL STONITH device]
heartbeat[22705]: 2007/10/12_11:59:11 info: glib: Host null-reset: imalwaysdead.com
heartbeat[22705]: 2007/10/12_11:59:11 info: node imalwaysdead.com now reset.
heartbeat[22705]: 2007/10/12_11:59:11 info: FIFO message [type stonith] written rc=50
heartbeat[22264]: 2007/10/12_11:59:11 info: Exiting STONITH imalwaysdead.com process 22705 returned rc 0.
heartbeat[22264]: 2007/10/12_11:59:11 info: Resources being acquired from imalwaysdead.com.
heartbeat[22264]: 2007/10/12_11:59:11 debug: StartNextRemoteRscReq() - calling hook
heartbeat[22264]: 2007/10/12_11:59:11 debug: notify_world: invoking harc: OLD status: active
heartbeat[22264]: 2007/10/12_11:59:11 debug: Process [status] started pid 22709
heartbeat[22264]: 2007/10/12_11:59:11 debug: Starting notify process [status]
heartbeat[22264]: 2007/10/12_11:59:11 debug: takeover_from_node: other now stable
heartbeat[22264]: 2007/10/12_11:59:11 debug: Process [req_our_resources] started pid 22710
heartbeat[22706]: 2007/10/12_11:59:11 info: Checking status of STONITH device [NULL STONITH device]
heartbeat[22264]: 2007/10/12_11:59:11 info: Exiting STONITH-stat process 22706 returned rc 0.
heartbeat[22707]: 2007/10/12_11:59:11 info: Starting "/usr/lib/heartbeat/ccm" as uid 17  gid 503 (pid 22707)
ccm[22707]: 2007/10/12_11:59:11 debug: Signing in with Heartbeat
heartbeat[22264]: 2007/10/12_11:59:11 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:11 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:11 debug: client->gsource = 0x9254598
heartbeat[22264]: 2007/10/12_11:59:11 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:11 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:11 debug: Checking client authorization for client ccm (17:503)
heartbeat[22264]: 2007/10/12_11:59:11 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:11 debug: Signing on API client 22707 (ccm)
heartbeat[22708]: 2007/10/12_11:59:11 info: Starting "/usr/lib/heartbeat/ipfail" as uid 17  gid 503 (pid 22708)
heartbeat[22709]: 2007/10/12_11:59:11 debug: notify_world: setting SIGCHLD Handler to SIG_DFL
heartbeat[22709]: 2007/10/12_11:59:11 debug: notify_world: Running harc status
heartbeat[22710]: 2007/10/12_11:59:12 debug: req_our_resources(/usr/share/heartbeat/ResourceManager listkeys dac.uftmasterad.org)
ccm[22707]: 2007/10/12_11:59:12 info: Hostname: dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:12 debug: APIregistration_dispatch() {
heartbeat[22264]: 2007/10/12_11:59:12 debug: process_registerevent() {
heartbeat[22264]: 2007/10/12_11:59:12 debug: client->gsource = 0x9256b98
heartbeat[22264]: 2007/10/12_11:59:12 debug: }/*process_registerevent*/;
heartbeat[22264]: 2007/10/12_11:59:12 debug: }/*APIregistration_dispatch*/;
heartbeat[22264]: 2007/10/12_11:59:12 debug: Checking client authorization for client ipfail (17:503)
heartbeat[22264]: 2007/10/12_11:59:12 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[22264]: 2007/10/12_11:59:12 debug: Signing on API client 22708 (ipfail)
heartbeat[22264]: 2007/10/12_11:59:12 debug: hb_rsc_isstable: ResourceMgmt_child_count: 2, other_is_stable: 1, takeover_in_progress: 1, going_standby: 0, standby running(ms): 0, resourcestate: 1
heartbeat[22710]: 2007/10/12_11:59:12 info: No local resources [/usr/share/heartbeat/ResourceManager listkeys dac.uftmasterad.org] to acquire.
heartbeat[22710]: 2007/10/12_11:59:12 debug: Sending hold resources msg: all, stable=1 # req_our_resources()
heartbeat[22710]: 2007/10/12_11:59:12 info: FIFO message [type resource] written rc=79
heartbeat[22264]: 2007/10/12_11:59:12 info: AnnounceTakeover(local 1, foreign 0, reason 'T_RESOURCES(us)' (0))
heartbeat[22264]: 2007/10/12_11:59:12 debug: hb_rsc_isstable: ResourceMgmt_child_count: 2, other_is_stable: 1, takeover_in_progress: 1, going_standby: 0, standby running(ms): 0, resourcestate: 4
heartbeat[22264]: 2007/10/12_11:59:12 info: Exiting req_our_resources process 22710 returned rc 0.
heartbeat[22264]: 2007/10/12_11:59:12 debug: RscMgmtProc 'req_our_resources' exited code 0
heartbeat[22264]: 2007/10/12_11:59:12 info: AnnounceTakeover(local 1, foreign 0, reason 'req_our_resources' (0))
heartbeat[22264]: 2007/10/12_11:59:12 debug: StartNextRemoteRscReq(): child count 1
harc[22709]:	2007/10/12_11:59:12 info: Running /etc/ha.d/rc.d/status status
mach_down[22737]:	2007/10/12_11:59:12 info: Taking over resource group IPaddr::10.253.252.251/30
ResourceManager[22762]:	2007/10/12_11:59:12 info: Acquiring resource group: imalwaysdead.com IPaddr::10.253.252.251/30
ccm[22707]: 2007/10/12_11:59:12 debug: node state CCM_STATE_NONE -> CCM_STATE_NONE
ccm[22707]: 2007/10/12_11:59:12 debug: No quorum selected,using default quorum plugin(majority:twonodes)
ccm[22707]: 2007/10/12_11:59:12 debug: quorum plugin: majority
ccm[22707]: 2007/10/12_11:59:12 debug: cluster:linux-ha, member_count=1, member_quorum_votes=100
ccm[22707]: 2007/10/12_11:59:12 debug: total_node_count=2, total_quorum_votes=200
ccm[22707]: 2007/10/12_11:59:12 debug: quorum plugin: twonodes
ccm[22707]: 2007/10/12_11:59:12 debug: cluster:linux-ha, member_count=1, member_quorum_votes=100
ccm[22707]: 2007/10/12_11:59:12 debug: total_node_count=2, total_quorum_votes=200
ccm[22707]: 2007/10/12_11:59:12 info: Break tie for 2 nodes cluster
ccm[22707]: 2007/10/12_11:59:12 debug: node state CCM_STATE_NONE -> CCM_STATE_JOINED
ccm[22707]: 2007/10/12_11:59:12 debug: dump current membership 0xb7e95018
ccm[22707]: 2007/10/12_11:59:12 debug: 	leader=dac.uftmasterad.org
ccm[22707]: 2007/10/12_11:59:12 debug: 	transition=1
ccm[22707]: 2007/10/12_11:59:12 debug: 	status=CCM_STATE_JOINED
ccm[22707]: 2007/10/12_11:59:12 debug: 	has_quorum=1
ccm[22707]: 2007/10/12_11:59:12 debug: 	nodename=dac.uftmasterad.org bornon=1
ccm[22707]: 2007/10/12_11:59:12 debug: quorum is 1
ccm[22707]: 2007/10/12_11:59:12 debug: delivering new membership to 0 clients: 
ccm[22707]: 2007/10/12_11:59:12 info: G_main_add_SignalHandler: Added signal handler for signal 15
IPaddr[22788]:	2007/10/12_11:59:12 INFO:  Running OK
mach_down[22737]:	2007/10/12_11:59:12 info: /usr/share/heartbeat/mach_down: nice_failback: foreign resources acquired
heartbeat[22264]: 2007/10/12_11:59:12 info: AnnounceTakeover(local 1, foreign 0, reason 'T_RESOURCES(us)' (0))
heartbeat[22264]: 2007/10/12_11:59:12 info: mach_down takeover complete.
heartbeat[22264]: 2007/10/12_11:59:12 debug: process_resources(3):  other now stable
heartbeat[22264]: 2007/10/12_11:59:12 info: AnnounceTakeover(local 1, foreign 1, reason 'mach_down' (0))
heartbeat[22264]: 2007/10/12_11:59:12 info: Initial resource acquisition complete (mach_down)
heartbeat[22264]: 2007/10/12_11:59:12 debug: hb_rsc_isstable: ResourceMgmt_child_count: 1, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 4
ccm[22707]: 2007/10/12_11:59:12 debug: recv msg hbapi-clstat from dac.uftmasterad.org, status:join
ccm[22707]: 2007/10/12_11:59:12 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
ccm[22707]: 2007/10/12_11:59:12 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
mach_down[22737]:	2007/10/12_11:59:12 info: mach_down takeover complete for node imalwaysdead.com.
heartbeat[22264]: 2007/10/12_11:59:12 info: Exiting status process 22709 returned rc 0.
heartbeat[22264]: 2007/10/12_11:59:12 debug: RscMgmtProc 'status' exited code 0
ccm[22707]: 2007/10/12_11:59:13 WARN: ccm_state_joined: received message with unknown cookie, just dropping
ccm[22707]: 2007/10/12_11:59:13 debug: dump current membership 0xb7e95018
ccm[22707]: 2007/10/12_11:59:13 debug: 	leader=dac.uftmasterad.org
ccm[22707]: 2007/10/12_11:59:13 debug: 	transition=1
ccm[22707]: 2007/10/12_11:59:13 debug: 	status=CCM_STATE_JOINED
ccm[22707]: 2007/10/12_11:59:13 debug: 	has_quorum=1
ccm[22707]: 2007/10/12_11:59:13 debug: 	nodename=dac.uftmasterad.org bornon=1
Stopping heartbeat.
heartbeat[22264]: 2007/10/12_11:59:17 debug: Process 22264 processing SIGTERM
heartbeat[22264]: 2007/10/12_11:59:17 debug: hb_initiate_shutdown() called.
heartbeat[22264]: 2007/10/12_11:59:17 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 4
heartbeat[22264]: 2007/10/12_11:59:17 info: hb_giveup_resources(): current status: active
heartbeat[22264]: 2007/10/12_11:59:17 debug: Sending hold resources msg: none, stable=0 # shutdown
heartbeat[22264]: 2007/10/12_11:59:17 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 5
ccm[22707]: 2007/10/12_11:59:17 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
heartbeat[22264]: 2007/10/12_11:59:17 info: Heartbeat shutdown in progress. (22264)
heartbeat[22872]: 2007/10/12_11:59:17 info: Giving up all HA resources.
heartbeat[22264]: 2007/10/12_11:59:17 debug: Process [hb_giveup_resources] started pid 22872
heartbeat[22264]: 2007/10/12_11:59:17 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 20 ms (> 15 ms) before being called (GSource: 0x91d6910)
heartbeat[22264]: 2007/10/12_11:59:17 info: Gmain_timeout_dispatch: started at 1735578438 should have started at 1735578436
heartbeat[22264]: 2007/10/12_11:59:17 WARN: Gmain_timeout_dispatch: Dispatch function for send local status was delayed 20 ms (> 15 ms) before being called (GSource: 0x91d65d0)
heartbeat[22264]: 2007/10/12_11:59:17 info: Gmain_timeout_dispatch: started at 1735578443 should have started at 1735578441
heartbeat[22264]: 2007/10/12_11:59:17 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 20 ms (> 15 ms) before being called (GSource: 0x91d6910)
heartbeat[22264]: 2007/10/12_11:59:17 info: Gmain_timeout_dispatch: started at 1735578443 should have started at 1735578441
heartbeat[22264]: 2007/10/12_11:59:17 WARN: Gmain_timeout_dispatch: Dispatch function for send local status was delayed 20 ms (> 15 ms) before being called (GSource: 0x91d65d0)
heartbeat[22264]: 2007/10/12_11:59:17 info: Gmain_timeout_dispatch: started at 1735578446 should have started at 1735578444
heartbeat[22264]: 2007/10/12_11:59:17 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 30 ms (> 15 ms) before being called (GSource: 0x91d6910)
heartbeat[22264]: 2007/10/12_11:59:17 info: Gmain_timeout_dispatch: started at 1735578447 should have started at 1735578444
ResourceManager[22885]:	2007/10/12_11:59:17 info: Releasing resource group: imalwaysdead.com IPaddr::10.253.252.251/30
ResourceManager[22885]:	2007/10/12_11:59:17 info: Running /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 stop
ResourceManager[22885]:	2007/10/12_11:59:17 debug: Starting /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 stop
In IP Stop
SIOCDELRT: No such process
IPaddr[22946]:	2007/10/12_11:59:18 INFO: ifconfig eth0:0 down
IPaddr[22920]:	2007/10/12_11:59:18 INFO:  Success
INFO:  Success
ResourceManager[22885]:	2007/10/12_11:59:18 debug: /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 stop done. RC=0
heartbeat[22872]: 2007/10/12_11:59:18 info: All HA resources relinquished.
heartbeat[22872]: 2007/10/12_11:59:18 debug: Sending T_SHUTDONE.
heartbeat[22872]: 2007/10/12_11:59:18 info: FIFO message [type shutdone] written rc=27
heartbeat[22264]: 2007/10/12_11:59:18 debug: hb_rsc_isstable: ResourceMgmt_child_count: 1, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 5
ccm[22707]: 2007/10/12_11:59:18 debug: recv msg shutdone from dac.uftmasterad.org, status:dead
heartbeat[22264]: 2007/10/12_11:59:18 debug: Received T_SHUTDONE from us.
heartbeat[22264]: 2007/10/12_11:59:18 debug: Calling hb_mcp_final_shutdown in a second.
heartbeat[22264]: 2007/10/12_11:59:18 debug: RscMgmtProc 'hb_giveup_resources' exited code 0
heartbeat[22264]: 2007/10/12_11:59:18 WARN: Gmain_timeout_dispatch: Dispatch function for send local status was delayed 20 ms (> 15 ms) before being called (GSource: 0x91d65d0)
heartbeat[22264]: 2007/10/12_11:59:18 info: Gmain_timeout_dispatch: started at 1735578487 should have started at 1735578485
heartbeat[22264]: 2007/10/12_11:59:18 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 20 ms (> 15 ms) before being called (GSource: 0x91d6910)
heartbeat[22264]: 2007/10/12_11:59:18 info: Gmain_timeout_dispatch: started at 1735578487 should have started at 1735578485
heartbeat[22264]: 2007/10/12_11:59:19 debug: hb_mcp_final_shutdown() phase 0
heartbeat[22264]: 2007/10/12_11:59:19 debug: Shutting down client /usr/lib/heartbeat/ipfail
heartbeat[22264]: 2007/10/12_11:59:19 info: killing /usr/lib/heartbeat/ipfail process group 22708 with signal 15
heartbeat[22264]: 2007/10/12_11:59:19 debug: G_remove_client(pid=22708, reason='died' gsource=0x9256b98) {
heartbeat[22264]: 2007/10/12_11:59:19 debug: api_remove_client_int: removing pid [22708] reason: died
heartbeat[22264]: 2007/10/12_11:59:19 debug: api_send_client: client 22708 died
heartbeat[22264]: 2007/10/12_11:59:19 debug: }/*G_remove_client;*/
heartbeat[22264]: 2007/10/12_11:59:19 debug: Shutting down client /usr/lib/heartbeat/ccm
heartbeat[22264]: 2007/10/12_11:59:19 info: killing /usr/lib/heartbeat/ccm process group 22707 with signal 15
ccm[22707]: 2007/10/12_11:59:19 info: received SIGTERM, going to shut down
ccm[22707]: 2007/10/12_11:59:19 debug: node state CCM_STATE_JOINED -> CCM_STATE_NONE
heartbeat[22264]: 2007/10/12_11:59:19 debug: G_remove_client(pid=22707, reason='died' gsource=0x9254598) {
heartbeat[22264]: 2007/10/12_11:59:19 debug: api_remove_client_int: removing pid [22707] reason: died
heartbeat[22264]: 2007/10/12_11:59:19 debug: api_send_client: client 22707 died
heartbeat[22264]: 2007/10/12_11:59:19 debug: }/*G_remove_client;*/
heartbeat[22264]: 2007/10/12_11:59:19 debug: Client /usr/lib/heartbeat/ccm exited with return code 1.
heartbeat[22264]: 2007/10/12_11:59:19 debug: Final client "/usr/lib/heartbeat/ccm" died.
heartbeat[22264]: 2007/10/12_11:59:19 debug: hb_mcp_final_shutdown() phase 1
heartbeat[22264]: 2007/10/12_11:59:20 debug: hb_mcp_final_shutdown() phase 2
heartbeat[22264]: 2007/10/12_11:59:20 info: killing HBREAD process 22671 with signal 15
heartbeat[22264]: 2007/10/12_11:59:20 info: killing HBFIFO process 22665 with signal 15
heartbeat[22264]: 2007/10/12_11:59:20 info: killing HBWRITE process 22666 with signal 15
heartbeat[22264]: 2007/10/12_11:59:20 info: killing HBREAD process 22667 with signal 15
heartbeat[22264]: 2007/10/12_11:59:20 info: killing HBWRITE process 22668 with signal 15
heartbeat[22264]: 2007/10/12_11:59:20 info: killing HBREAD process 22669 with signal 15
heartbeat[22264]: 2007/10/12_11:59:20 info: killing HBWRITE process 22670 with signal 15
heartbeat[22671]: 2007/10/12_11:59:20 debug: Process 22671 processing SIGTERM
heartbeat[22671]: 2007/10/12_11:59:20 debug: Exiting from pid 22671 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:20 info: Core process 22671 exited. 7 remaining
heartbeat[22670]: 2007/10/12_11:59:20 debug: Process 22670 processing SIGTERM
heartbeat[22670]: 2007/10/12_11:59:20 debug: Exiting from pid 22670 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:20 info: Core process 22670 exited. 6 remaining
heartbeat[22669]: 2007/10/12_11:59:20 debug: Process 22669 processing SIGTERM
heartbeat[22669]: 2007/10/12_11:59:20 debug: Exiting from pid 22669 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:20 info: Core process 22669 exited. 5 remaining
heartbeat[22668]: 2007/10/12_11:59:20 debug: Process 22668 processing SIGTERM
heartbeat[22668]: 2007/10/12_11:59:20 debug: Exiting from pid 22668 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:20 info: Core process 22668 exited. 4 remaining
heartbeat[22667]: 2007/10/12_11:59:20 debug: Process 22667 processing SIGTERM
heartbeat[22667]: 2007/10/12_11:59:20 debug: Exiting from pid 22667 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:20 info: Core process 22667 exited. 3 remaining
heartbeat[22666]: 2007/10/12_11:59:20 debug: Process 22666 processing SIGTERM
heartbeat[22666]: 2007/10/12_11:59:20 debug: Exiting from pid 22666 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:20 info: Core process 22666 exited. 2 remaining
heartbeat[22665]: 2007/10/12_11:59:20 debug: Process 22665 processing SIGTERM
heartbeat[22665]: 2007/10/12_11:59:20 debug: Exiting from pid 22665 [rc=15]
heartbeat[22264]: 2007/10/12_11:59:20 info: Core process 22665 exited. 1 remaining
heartbeat[22264]: 2007/10/12_11:59:20 info: dac.uftmasterad.org Heartbeat shutdown complete.
heartbeat[22264]: 2007/10/12_11:59:20 debug: Exiting from pid 22264 [rc=0]
Checking STONITH basic sanity.
Performing apphbd success case tests
apphbd[23145]: 2007/10/12_11:59:22 debug: Limiting CPU: 12 CPU seconds every 60000 milliseconds
apphbd[23145]: 2007/10/12_11:59:22 info: Starting apphbd
/usr/lib/heartbeat/apphbtest[23147]: 2007/10/12_11:59:28 info: ----Start 5 client(s) with hb interval 1000 ms----
/usr/lib/heartbeat/apphbtest[23148]: 2007/10/12_11:59:28 info: apphb_normal_23148 sleep randomly for 3 secs
/usr/lib/heartbeat/apphbtest[23149]: 2007/10/12_11:59:28 info: apphb_normal_23149 sleep randomly for 1 secs
/usr/lib/heartbeat/apphbtest[23150]: 2007/10/12_11:59:28 info: Client apphb_normal_23150 registering
apphbd[23145]: 2007/10/12_11:59:28 debug: apphb_new_dispatch: IPC_channel: 0x9074eb0 pid=23150
apphbd[23145]: 2007/10/12_11:59:28 debug: apphb_client_new: channel: 0x9074eb0 pid=23150
apphbd[23145]: 2007/10/12_11:59:28 debug: apphb_client_register: client: [apphb_normal]/[apphb_normal_23150] pid 23150 (uid,gid) = (0,0)

apphbd[23145]: 2007/10/12_11:59:28 debug: apphb_process_msg: client: 0x90750f0 type=reg, rc=0
/usr/lib/heartbeat/apphbtest[23150]: 2007/10/12_11:59:28 info: Client apphb_normal_23150 setting 1000 ms heartbeat interval
apphbd[23145]: 2007/10/12_11:59:28 debug: apphb_process_msg: client: 0x90750f0 type=setint, rc=0
/usr/lib/heartbeat/apphbtest[23151]: 2007/10/12_11:59:28 info: apphb_normal_23151 sleep randomly for 4 secs
/usr/lib/heartbeat/apphbtest[23152]: 2007/10/12_11:59:28 info: apphb_normal_23152 sleep randomly for 1 secs
/usr/lib/heartbeat/apphbtest[23149]: 2007/10/12_11:59:29 info: Client apphb_normal_23149 registering
apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_new_dispatch: IPC_channel: 0x9077710 pid=23149
apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_client_new: channel: 0x9077710 pid=23149
apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_client_register: client: [apphb_normal]/[apphb_normal_23149] pid 23149 (uid,gid) = (0,0)

apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_process_msg: client: 0x90778d0 type=reg, rc=0
/usr/lib/heartbeat/apphbtest[23149]: 2007/10/12_11:59:29 info: Client apphb_normal_23149 setting 1000 ms heartbeat interval
apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_process_msg: client: 0x90778d0 type=setint, rc=0
/usr/lib/heartbeat/apphbtest[23152]: 2007/10/12_11:59:29 info: Client apphb_normal_23152 registering
apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_new_dispatch: IPC_channel: 0x9079e30 pid=23152
apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_client_new: channel: 0x9079e30 pid=23152
apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_client_register: client: [apphb_normal]/[apphb_normal_23152] pid 23152 (uid,gid) = (0,0)

apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_process_msg: client: 0x9079ff0 type=reg, rc=0
/usr/lib/heartbeat/apphbtest[23152]: 2007/10/12_11:59:29 info: Client apphb_normal_23152 setting 1000 ms heartbeat interval
apphbd[23145]: 2007/10/12_11:59:29 debug: apphb_process_msg: client: 0x9079ff0 type=setint, rc=0
/usr/lib/heartbeat/apphbtest[23150]: 2007/10/12_11:59:30 info: Client apphb_normal_23150 unregistering
apphbd[23145]: 2007/10/12_11:59:30 debug: apphb_process_msg: client: 0x90750f0 type=unreg, rc=0
apphbd[23145]: 2007/10/12_11:59:30 info: apphb_client_remove: client: 23150
apphbd[23145]: 2007/10/12_11:59:30 debug: apphb_client_remove: client pid: 23150
/usr/lib/heartbeat/apphbtest[23148]: 2007/10/12_11:59:31 info: Client apphb_normal_23148 registering
apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_new_dispatch: IPC_channel: 0x9074eb0 pid=23148
apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_client_new: channel: 0x9074eb0 pid=23148
apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_client_register: client: [apphb_normal]/[apphb_normal_23148] pid 23148 (uid,gid) = (0,0)

apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_process_msg: client: 0x907c910 type=reg, rc=0
/usr/lib/heartbeat/apphbtest[23148]: 2007/10/12_11:59:31 info: Client apphb_normal_23148 setting 1000 ms heartbeat interval
apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_process_msg: client: 0x907c910 type=setint, rc=0
/usr/lib/heartbeat/apphbtest[23149]: 2007/10/12_11:59:31 info: Client apphb_normal_23149 unregistering
apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_process_msg: client: 0x90778d0 type=unreg, rc=0
apphbd[23145]: 2007/10/12_11:59:31 info: apphb_client_remove: client: 23149
apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_client_remove: client pid: 23149
/usr/lib/heartbeat/apphbtest[23152]: 2007/10/12_11:59:31 info: Client apphb_normal_23152 unregistering
apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_process_msg: client: 0x9079ff0 type=unreg, rc=0
apphbd[23145]: 2007/10/12_11:59:31 info: apphb_client_remove: client: 23152
apphbd[23145]: 2007/10/12_11:59:31 debug: apphb_client_remove: client pid: 23152
/usr/lib/heartbeat/apphbtest[23151]: 2007/10/12_11:59:32 info: Client apphb_normal_23151 registering
apphbd[23145]: 2007/10/12_11:59:32 debug: apphb_new_dispatch: IPC_channel: 0x9079e30 pid=23151
apphbd[23145]: 2007/10/12_11:59:32 debug: apphb_client_new: channel: 0x9079e30 pid=23151
apphbd[23145]: 2007/10/12_11:59:32 debug: apphb_client_register: client: [apphb_normal]/[apphb_normal_23151] pid 23151 (uid,gid) = (0,0)

apphbd[23145]: 2007/10/12_11:59:32 debug: apphb_process_msg: client: 0x9079d90 type=reg, rc=0
/usr/lib/heartbeat/apphbtest[23151]: 2007/10/12_11:59:32 info: Client apphb_normal_23151 setting 1000 ms heartbeat interval
apphbd[23145]: 2007/10/12_11:59:32 debug: apphb_process_msg: client: 0x9079d90 type=setint, rc=0
/usr/lib/heartbeat/apphbtest[23148]: 2007/10/12_11:59:33 info: Client apphb_normal_23148 unregistering
apphbd[23145]: 2007/10/12_11:59:33 debug: apphb_process_msg: client: 0x907c910 type=unreg, rc=0
apphbd[23145]: 2007/10/12_11:59:33 info: apphb_client_remove: client: 23148
apphbd[23145]: 2007/10/12_11:59:33 debug: apphb_client_remove: client pid: 23148
/usr/lib/heartbeat/apphbtest[23151]: 2007/10/12_11:59:34 info: Client apphb_normal_23151 unregistering
apphbd[23145]: 2007/10/12_11:59:34 debug: apphb_process_msg: client: 0x9079d90 type=unreg, rc=0
apphbd[23145]: 2007/10/12_11:59:34 info: apphb_client_remove: client: 23151
apphbd[23145]: 2007/10/12_11:59:34 debug: apphb_client_remove: client pid: 23151
Performing apphbd failure case tests
apphbd[23145]: 2007/10/12_11:59:34 debug: apphb_new_dispatch: IPC_channel: 0x9079e30 pid=23161
apphbd[23145]: 2007/10/12_11:59:34 debug: apphb_client_new: channel: 0x9079e30 pid=23161
apphbd[23145]: 2007/10/12_11:59:34 debug: apphb_client_register: client: [apphb_normal]/[apphb_normal_23161] pid 23161 (uid,gid) = (0,0)

apphbd[23145]: 2007/10/12_11:59:34 debug: apphb_process_msg: client: 0x907a090 type=reg, rc=0
apphbd[23145]: 2007/10/12_11:59:34 debug: apphb_process_msg: client: 0x907a090 type=setint, rc=0
apphbd[23145]: 2007/10/12_11:59:37 debug: apphb_process_msg: client: 0x907a090 type=unreg, rc=0
apphbd[23145]: 2007/10/12_11:59:37 info: apphb_client_remove: client: 23161
apphbd[23145]: 2007/10/12_11:59:37 debug: apphb_client_remove: client pid: 23161
apphbd[23145]: 2007/10/12_11:59:37 debug: apphb_new_dispatch: IPC_channel: 0x9079e30 pid=23162
apphbd[23145]: 2007/10/12_11:59:37 debug: apphb_client_new: channel: 0x9079e30 pid=23162
apphbd[23145]: 2007/10/12_11:59:37 debug: apphb_client_register: client: [failtest]/[failtest_23162] pid 23162 (uid,gid) = (0,0)

apphbd[23145]: 2007/10/12_11:59:37 debug: apphb_process_msg: client: 0x9077830 type=reg, rc=0
apphbd[23145]: 2007/10/12_11:59:37 debug: apphb_process_msg: client: 0x9077830 type=setint, rc=0
apphbd[23145]: 2007/10/12_11:59:46 debug: apphb_process_msg: client: 0x9077830 type=setwarn, rc=0
apphbd[23145]: 2007/10/12_11:59:47 info: apphb client 'failtest' / 'failtest_23162' (pid 23162) late heartbeat: 1000 ms
apphbd[23145]: 2007/10/12_11:59:49 WARN: apphb client 'failtest' / 'failtest_23162' (pid 23162) failed to heartbeat
apphbd[23145]: 2007/10/12_11:59:50 info: apphb client 'failtest' / 'failtest_23162' (pid 23162) resumed heartbeats
apphbd[23145]: 2007/10/12_11:59:50 info: apphb client 'failtest' / 'failtest_23162' (pid 23162) late heartbeat: 3000 ms
apphbd[23145]: 2007/10/12_11:59:50 debug: apphb_process_msg: client: 0x9077830 type=unreg, rc=0
apphbd[23145]: 2007/10/12_11:59:50 info: apphb_client_remove: client: 23162
apphbd[23145]: 2007/10/12_11:59:50 debug: apphb_client_remove: client pid: 23162
apphbd[23145]: 2007/10/12_11:59:50 debug: apphb_new_dispatch: IPC_channel: 0x9079e30 pid=23162
apphbd[23145]: 2007/10/12_11:59:50 debug: apphb_client_new: channel: 0x9079e30 pid=23162
apphbd[23145]: 2007/10/12_11:59:50 debug: apphb_client_register: client: [failtest_23162]/[HANGUP] pid 23162 (uid,gid) = (0,0)

apphbd[23145]: 2007/10/12_11:59:50 debug: apphb_process_msg: client: 0x9074fd0 type=reg, rc=0
apphbd[23145]: 2007/10/12_11:59:50 WARN: apphb client 'failtest_23162' / 'HANGUP' (pid 23162) hangup
apphbd[23145]: 2007/10/12_11:59:50 info: apphb_client_remove: client: 23162
apphbd[23145]: 2007/10/12_11:59:50 debug: apphb_client_remove: client pid: 23162
apphbd[23145]: 2007/10/12_11:59:55 debug: testing write to syslog when i am a nobody
apphbd[23145]: 2007/10/12_11:59:55 info: [apphbd] stopped
Starting LRM tests
lrmd is running [pid: 23182]
lrmd is stopped.
There are 4 RA classes supported:
stonith
heartbeat
ocf
lsb
Succeeded in adding this resource.

Resource ID:ip
Resource agent class:heartbeat
Resource agent type:IPaddr
Resource agent provider:default
Resource agent parameters:192.168.58.4  
Succeeded in deleting this resource.
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(deadtime,5)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(initdead,5)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(auto_failback,on)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(ping,127.0.0.1)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(node,dac.uftmasterad.org)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(node,ImAlwaysDead.com)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(use_logd,off)
heartbeat[23294]: 2007/10/12_11:59:58 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[23294]: 2007/10/12_11:59:58 info: respawn directive: hacluster /usr/lib/heartbeat/ccm
heartbeat[23294]: 2007/10/12_11:59:58 info: respawn directive: hacluster /usr/lib/heartbeat/ipfail
heartbeat[23294]: 2007/10/12_11:59:58 debug: uid=root, gid=<null>
heartbeat[23294]: 2007/10/12_11:59:58 debug: uid=hacluster, gid=<null>
heartbeat[23294]: 2007/10/12_11:59:58 debug: uid=hacluster, gid=<null>
heartbeat[23294]: 2007/10/12_11:59:58 debug: uid=<null>, gid=haclient
heartbeat[23294]: 2007/10/12_11:59:58 debug: uid=root, gid=<null>
heartbeat[23294]: 2007/10/12_11:59:58 debug: uid=<null>, gid=haclient
heartbeat[23294]: 2007/10/12_11:59:58 debug: Beginning authentication parsing
heartbeat[23294]: 2007/10/12_11:59:58 debug: 16 max authentication methods
heartbeat[23294]: 2007/10/12_11:59:58 debug: Keyfile opened
heartbeat[23294]: 2007/10/12_11:59:58 debug: Keyfile perms OK
heartbeat[23294]: 2007/10/12_11:59:58 debug: 16 max authentication methods
heartbeat[23294]: 2007/10/12_11:59:58 debug: Found authentication method [sha1]
heartbeat[23294]: 2007/10/12_11:59:58 info: AUTH: i=1: key = 0x9043a98, auth=0x11421c, authname=sha1
heartbeat[23294]: 2007/10/12_11:59:58 debug: Found authentication method [md5]
heartbeat[23294]: 2007/10/12_11:59:58 info: AUTH: i=2: key = 0x90440a0, auth=0x1168d0, authname=md5
heartbeat[23294]: 2007/10/12_11:59:58 debug: Outbound signing method is 2
heartbeat[23294]: 2007/10/12_11:59:58 debug: Found authentication method [crc]
heartbeat[23294]: 2007/10/12_11:59:58 info: AUTH: i=3: key = 0x9044690, auth=0x118c5c, authname=crc
heartbeat[23294]: 2007/10/12_11:59:58 debug: Authentication parsing complete [1]
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(cluster,linux-ha)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(hopfudge,1)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(baud,19200)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(hbgenmethod,file)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(realtime,true)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(msgfmt,classic)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(conn_logd_time,60)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(log_badpack,true)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(coredumps,true)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(crm,false)
heartbeat[23294]: 2007/10/12_11:59:58 info: Version 2 support: false
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(autojoin,none)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(uuidfrom,file)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(compression,zlib)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(compression_threshold,2)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(traditional_compression,no)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(max_rexmit_delay,250)
heartbeat[23294]: 2007/10/12_11:59:58 debug: Setting max_rexmit_delay to 250 ms
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(record_config_changes,on)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(record_pengine_inputs,on)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(enable_config_writes,on)
heartbeat[23294]: 2007/10/12_11:59:58 debug: add_option(memreserve,6500)
heartbeat[23294]: 2007/10/12_11:59:58 WARN: Initial dead time [5000 ms] may be too small!
heartbeat[23294]: 2007/10/12_11:59:58 info: Initial dead time accounts for slow network startup time
heartbeat[23294]: 2007/10/12_11:59:58 info: It should be >= deadtime and >= 10 seconds
heartbeat[23294]: 2007/10/12_11:59:58 WARN: Logging daemon is disabled --enabling logging daemon is recommended
heartbeat[23294]: 2007/10/12_11:59:58 info: **************************
heartbeat[23294]: 2007/10/12_11:59:58 info: Configuration validated. Starting heartbeat 2.1.2
heartbeat[23294]: 2007/10/12_11:59:58 debug: HA configuration OK.  Heartbeat starting.
heartbeat[23295]: 2007/10/12_11:59:58 info: heartbeat: version 2.1.2
heartbeat[23295]: 2007/10/12_11:59:58 info: Heartbeat generation: 1191438895
heartbeat[23295]: 2007/10/12_11:59:58 debug: uuid is:add366cf-0375-4bd4-8bcd-3c392b9a84b2
heartbeat[23295]: 2007/10/12_11:59:58 info: G_main_add_TriggerHandler: Added signal manual handler
heartbeat[23295]: 2007/10/12_11:59:58 info: G_main_add_TriggerHandler: Added signal manual handler
heartbeat[23295]: 2007/10/12_11:59:58 info: Removing /var/run/heartbeat/rsctmp failed, recreating.
heartbeat[23295]: 2007/10/12_11:59:58 debug: opening mcast eth0 (UDP/IP multicast)
heartbeat[23295]: 2007/10/12_11:59:58 debug: opening ping 127.0.0.1 (ping membership)
heartbeat[23295]: 2007/10/12_11:59:58 debug: opening ping_group pingus_please (ping group membership)
heartbeat[23295]: 2007/10/12_11:59:58 debug: FIFO process pid: 23300
heartbeat[23295]: 2007/10/12_11:59:58 debug: glib: looking up address for eth0
heartbeat[23295]: 2007/10/12_11:59:58 debug: glib: looking up address for eth0
heartbeat[23295]: 2007/10/12_11:59:58 debug: glib: Successfully joined multicast group 225.224.223.222 oninterface eth0
heartbeat[23295]: 2007/10/12_11:59:58 info: glib: UDP multicast heartbeat started for group 225.224.223.222 port 694 interface eth0 (ttl=0 loop=0)
heartbeat[23295]: 2007/10/12_11:59:58 debug: write process pid: 23301
heartbeat[23295]: 2007/10/12_11:59:58 debug: read child process pid: 23302
heartbeat[23295]: 2007/10/12_11:59:58 info: glib: ping heartbeat started.
heartbeat[23295]: 2007/10/12_11:59:58 debug: write process pid: 23303
heartbeat[23295]: 2007/10/12_11:59:58 debug: read child process pid: 23304
heartbeat[23295]: 2007/10/12_11:59:58 info: glib: ping group heartbeat started.
heartbeat[23295]: 2007/10/12_11:59:58 debug: write process pid: 23305
heartbeat[23295]: 2007/10/12_11:59:58 debug: read child process pid: 23306
heartbeat[23295]: 2007/10/12_11:59:58 info: G_main_add_SignalHandler: Added signal handler for signal 17
heartbeat[23295]: 2007/10/12_11:59:58 debug: Limiting CPU: 42 CPU seconds every 60000 milliseconds
heartbeat[23295]: 2007/10/12_11:59:58 debug: pid 23295 locked in memory.
heartbeat[23295]: 2007/10/12_11:59:58 debug: Waiting for child processes to start
heartbeat[23295]: 2007/10/12_11:59:58 info: Local status now set to: 'up'
heartbeat[23295]: 2007/10/12_11:59:58 debug: All your child process are belong to us
heartbeat[23295]: 2007/10/12_11:59:58 debug: Starting local status message @ 10 ms intervals
heartbeat[23300]: 2007/10/12_11:59:59 debug: pid 23300 locked in memory.
heartbeat[23300]: 2007/10/12_11:59:59 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[23301]: 2007/10/12_11:59:59 debug: pid 23301 locked in memory.
heartbeat[23301]: 2007/10/12_11:59:59 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[23302]: 2007/10/12_11:59:59 debug: pid 23302 locked in memory.
heartbeat[23302]: 2007/10/12_11:59:59 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[23303]: 2007/10/12_11:59:59 debug: pid 23303 locked in memory.
heartbeat[23303]: 2007/10/12_11:59:59 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[23304]: 2007/10/12_11:59:59 debug: pid 23304 locked in memory.
heartbeat[23304]: 2007/10/12_11:59:59 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[23305]: 2007/10/12_11:59:59 debug: pid 23305 locked in memory.
heartbeat[23305]: 2007/10/12_11:59:59 debug: Limiting CPU: 24 CPU seconds every 60000 milliseconds
heartbeat[23295]: 2007/10/12_11:59:59 info: Link 127.0.0.1:127.0.0.1 up.
heartbeat[23295]: 2007/10/12_11:59:59 debug: CreateInitialFilter: ask_resources
heartbeat[23295]: 2007/10/12_11:59:59 debug: CreateInitialFilter: ip-request-resp
heartbeat[23295]: 2007/10/12_11:59:59 debug: CreateInitialFilter: ip-request
heartbeat[23295]: 2007/10/12_11:59:59 debug: CreateInitialFilter: status
heartbeat[23295]: 2007/10/12_11:59:59 debug: CreateInitialFilter: hb_takeover
heartbeat[23295]: 2007/10/12_11:59:59 info: Status update for node 127.0.0.1: status ping
heartbeat[23295]: 2007/10/12_11:59:59 debug: Status seqno: 0 msgtime: 1192204798
heartbeat[23295]: 2007/10/12_11:59:59 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 20 ms (> 15 ms) before being called (GSource: 0x9052f78)
heartbeat[23295]: 2007/10/12_11:59:59 info: Gmain_timeout_dispatch: started at 1735582604 should have started at 1735582602
heartbeat[23306]: 2007/10/12_11:59:59 debug: pid 23306 locked in memory.
heartbeat[23306]: 2007/10/12_11:59:59 debug: Limiting CPU: 6 CPU seconds every 60000 milliseconds
heartbeat[23295]: 2007/10/12_11:59:59 info: Link pingus_please:pingus_please up.
heartbeat[23295]: 2007/10/12_11:59:59 info: Status update for node pingus_please: status ping
heartbeat[23295]: 2007/10/12_11:59:59 debug: Status seqno: 0 msgtime: 1192204798
heartbeat[23295]: 2007/10/12_12:00:03 WARN: node imalwaysdead.com: is dead
heartbeat[23295]: 2007/10/12_12:00:03 info: Comm_now_up(): updating status to active
heartbeat[23295]: 2007/10/12_12:00:03 info: Local status now set to: 'active'
heartbeat[23295]: 2007/10/12_12:00:03 debug: Sending local starting msg: resourcestate = 0
heartbeat[23295]: 2007/10/12_12:00:03 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 0, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 0
heartbeat[23295]: 2007/10/12_12:00:03 info: Starting child client "/usr/lib/heartbeat/ccm" (17,503)
heartbeat[23295]: 2007/10/12_12:00:03 info: Starting child client "/usr/lib/heartbeat/ipfail" (17,503)
heartbeat[23329]: 2007/10/12_12:00:03 info: Resetting node imalwaysdead.com with [NULL STONITH device]
heartbeat[23329]: 2007/10/12_12:00:03 info: glib: Host null-reset: imalwaysdead.com
heartbeat[23329]: 2007/10/12_12:00:03 info: node imalwaysdead.com now reset.
heartbeat[23329]: 2007/10/12_12:00:03 info: FIFO message [type stonith] written rc=50
heartbeat[23295]: 2007/10/12_12:00:03 info: Exiting STONITH imalwaysdead.com process 23329 returned rc 0.
heartbeat[23295]: 2007/10/12_12:00:03 info: Resources being acquired from imalwaysdead.com.
heartbeat[23295]: 2007/10/12_12:00:03 debug: StartNextRemoteRscReq() - calling hook
heartbeat[23295]: 2007/10/12_12:00:03 debug: notify_world: invoking harc: OLD status: active
heartbeat[23295]: 2007/10/12_12:00:03 debug: Process [status] started pid 23333
heartbeat[23295]: 2007/10/12_12:00:03 debug: Starting notify process [status]
heartbeat[23295]: 2007/10/12_12:00:03 debug: takeover_from_node: other now stable
heartbeat[23295]: 2007/10/12_12:00:03 debug: Process [req_our_resources] started pid 23334
heartbeat[23330]: 2007/10/12_12:00:03 info: Checking status of STONITH device [NULL STONITH device]
heartbeat[23295]: 2007/10/12_12:00:03 info: Exiting STONITH-stat process 23330 returned rc 0.
heartbeat[23332]: 2007/10/12_12:00:03 info: Starting "/usr/lib/heartbeat/ipfail" as uid 17  gid 503 (pid 23332)
heartbeat[23295]: 2007/10/12_12:00:03 debug: APIregistration_dispatch() {
heartbeat[23295]: 2007/10/12_12:00:03 debug: process_registerevent() {
heartbeat[23295]: 2007/10/12_12:00:03 debug: client->gsource = 0x90cdcf8
heartbeat[23295]: 2007/10/12_12:00:03 debug: }/*process_registerevent*/;
heartbeat[23295]: 2007/10/12_12:00:03 debug: }/*APIregistration_dispatch*/;
heartbeat[23295]: 2007/10/12_12:00:03 debug: Checking client authorization for client ipfail (17:503)
heartbeat[23295]: 2007/10/12_12:00:03 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[23295]: 2007/10/12_12:00:03 debug: Signing on API client 23332 (ipfail)
heartbeat[23331]: 2007/10/12_12:00:03 info: Starting "/usr/lib/heartbeat/ccm" as uid 17  gid 503 (pid 23331)
heartbeat[23295]: 2007/10/12_12:00:03 debug: hb_rsc_isstable: ResourceMgmt_child_count: 2, other_is_stable: 1, takeover_in_progress: 1, going_standby: 0, standby running(ms): 0, resourcestate: 1
ccm[23331]: 2007/10/12_12:00:03 debug: Signing in with Heartbeat
heartbeat[23295]: 2007/10/12_12:00:03 debug: APIregistration_dispatch() {
heartbeat[23295]: 2007/10/12_12:00:03 debug: process_registerevent() {
heartbeat[23295]: 2007/10/12_12:00:03 debug: client->gsource = 0x90cc118
heartbeat[23295]: 2007/10/12_12:00:03 debug: }/*process_registerevent*/;
heartbeat[23295]: 2007/10/12_12:00:03 debug: }/*APIregistration_dispatch*/;
heartbeat[23295]: 2007/10/12_12:00:03 debug: Checking client authorization for client ccm (17:503)
heartbeat[23295]: 2007/10/12_12:00:03 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[23295]: 2007/10/12_12:00:03 debug: Signing on API client 23331 (ccm)
heartbeat[23333]: 2007/10/12_12:00:03 debug: notify_world: setting SIGCHLD Handler to SIG_DFL
heartbeat[23333]: 2007/10/12_12:00:03 debug: notify_world: Running harc status
heartbeat[23334]: 2007/10/12_12:00:03 debug: req_our_resources(/usr/share/heartbeat/ResourceManager listkeys dac.uftmasterad.org)
ccm[23331]: 2007/10/12_12:00:03 info: Hostname: dac.uftmasterad.org
heartbeat[23334]: 2007/10/12_12:00:03 info: No local resources [/usr/share/heartbeat/ResourceManager listkeys dac.uftmasterad.org] to acquire.
heartbeat[23334]: 2007/10/12_12:00:03 debug: Sending hold resources msg: all, stable=1 # req_our_resources()
heartbeat[23334]: 2007/10/12_12:00:03 info: FIFO message [type resource] written rc=79
heartbeat[23295]: 2007/10/12_12:00:03 info: AnnounceTakeover(local 1, foreign 0, reason 'T_RESOURCES(us)' (0))
heartbeat[23295]: 2007/10/12_12:00:03 info: STATE 1 => 3
heartbeat[23295]: 2007/10/12_12:00:03 debug: hb_rsc_isstable: ResourceMgmt_child_count: 2, other_is_stable: 1, takeover_in_progress: 1, going_standby: 0, standby running(ms): 0, resourcestate: 3
heartbeat[23295]: 2007/10/12_12:00:03 info: Exiting req_our_resources process 23334 returned rc 0.
heartbeat[23295]: 2007/10/12_12:00:03 debug: RscMgmtProc 'req_our_resources' exited code 0
heartbeat[23295]: 2007/10/12_12:00:03 info: AnnounceTakeover(local 1, foreign 0, reason 'req_our_resources' (0))
heartbeat[23295]: 2007/10/12_12:00:03 debug: StartNextRemoteRscReq(): child count 1
harc[23333]:	2007/10/12_12:00:03 info: Running /etc/ha.d/rc.d/status status
mach_down[23361]:	2007/10/12_12:00:03 info: Taking over resource group IPaddr::10.253.252.251/30
ccm[23331]: 2007/10/12_12:00:03 debug: node state CCM_STATE_NONE -> CCM_STATE_NONE
ccm[23331]: 2007/10/12_12:00:03 debug: No quorum selected,using default quorum plugin(majority:twonodes)
ccm[23331]: 2007/10/12_12:00:03 debug: quorum plugin: majority
ccm[23331]: 2007/10/12_12:00:03 debug: cluster:linux-ha, member_count=1, member_quorum_votes=100
ccm[23331]: 2007/10/12_12:00:03 debug: total_node_count=2, total_quorum_votes=200
ccm[23331]: 2007/10/12_12:00:03 debug: quorum plugin: twonodes
ccm[23331]: 2007/10/12_12:00:03 debug: cluster:linux-ha, member_count=1, member_quorum_votes=100
ccm[23331]: 2007/10/12_12:00:03 debug: total_node_count=2, total_quorum_votes=200
ccm[23331]: 2007/10/12_12:00:03 info: Break tie for 2 nodes cluster
ccm[23331]: 2007/10/12_12:00:03 debug: node state CCM_STATE_NONE -> CCM_STATE_JOINED
ccm[23331]: 2007/10/12_12:00:03 debug: dump current membership 0xb7ede018
ccm[23331]: 2007/10/12_12:00:03 debug: 	leader=dac.uftmasterad.org
ccm[23331]: 2007/10/12_12:00:03 debug: 	transition=1
ccm[23331]: 2007/10/12_12:00:03 debug: 	status=CCM_STATE_JOINED
ccm[23331]: 2007/10/12_12:00:03 debug: 	has_quorum=1
ccm[23331]: 2007/10/12_12:00:03 debug: 	nodename=dac.uftmasterad.org bornon=1
ccm[23331]: 2007/10/12_12:00:03 debug: quorum is 1
ccm[23331]: 2007/10/12_12:00:03 debug: delivering new membership to 0 clients: 
ccm[23331]: 2007/10/12_12:00:03 info: G_main_add_SignalHandler: Added signal handler for signal 15
ResourceManager[23386]:	2007/10/12_12:00:03 info: Acquiring resource group: imalwaysdead.com IPaddr::10.253.252.251/30
IPaddr[23412]:	2007/10/12_12:00:04 INFO:  Resource is stopped
ResourceManager[23386]:	2007/10/12_12:00:04 info: Running /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 start
ResourceManager[23386]:	2007/10/12_12:00:04 debug: Starting /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 start
heartbeat[23295]: 2007/10/12_12:00:04 debug: APIregistration_dispatch() {
heartbeat[23295]: 2007/10/12_12:00:04 debug: process_registerevent() {
heartbeat[23295]: 2007/10/12_12:00:04 debug: client->gsource = 0x90649f0
heartbeat[23295]: 2007/10/12_12:00:04 debug: }/*process_registerevent*/;
heartbeat[23295]: 2007/10/12_12:00:04 debug: }/*APIregistration_dispatch*/;
heartbeat[23295]: 2007/10/12_12:00:04 debug: Checking client authorization for client 23511 (0:65)
heartbeat[23295]: 2007/10/12_12:00:04 WARN: Client [23511] pid 23511 failed authorization [client failed authorization]
heartbeat[23295]: 2007/10/12_12:00:04 ERROR: api_process_registration_msg: cannot add client(23511)
heartbeat[23295]: 2007/10/12_12:00:04 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[23295]: 2007/10/12_12:00:04 debug: Signing on API client 23511 ('casual')
heartbeat[23295]: 2007/10/12_12:00:04 debug: G_remove_client(pid=23511, reason='client failed authorization' gsource=0x90649f0) {
heartbeat[23295]: 2007/10/12_12:00:04 debug: api_remove_client_int: removing pid [23511] reason: client failed authorization
heartbeat[23295]: 2007/10/12_12:00:04 debug: }/*G_remove_client;*/
heartbeat[23295]: 2007/10/12_12:00:04 debug: APIregistration_dispatch() {
heartbeat[23295]: 2007/10/12_12:00:04 debug: process_registerevent() {
heartbeat[23295]: 2007/10/12_12:00:04 debug: client->gsource = 0x9084b90
heartbeat[23295]: 2007/10/12_12:00:04 debug: }/*process_registerevent*/;
heartbeat[23295]: 2007/10/12_12:00:04 debug: }/*APIregistration_dispatch*/;
heartbeat[23295]: 2007/10/12_12:00:04 debug: Checking client authorization for client 23512 (0:65)
heartbeat[23295]: 2007/10/12_12:00:04 WARN: Client [23512] pid 23512 failed authorization [client failed authorization]
heartbeat[23295]: 2007/10/12_12:00:04 ERROR: api_process_registration_msg: cannot add client(23512)
heartbeat[23295]: 2007/10/12_12:00:04 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[23295]: 2007/10/12_12:00:04 debug: Signing on API client 23512 ('casual')
heartbeat[23295]: 2007/10/12_12:00:04 debug: G_remove_client(pid=23512, reason='client failed authorization' gsource=0x9084b90) {
heartbeat[23295]: 2007/10/12_12:00:04 debug: api_remove_client_int: removing pid [23512] reason: client failed authorization
heartbeat[23295]: 2007/10/12_12:00:04 debug: }/*G_remove_client;*/
starting STONITH Daemon tests
stonithd[23517]: 2007/10/12_12:00:04 notice: cannot get daemon PID to kill it.
IPaddr[23501]:	2007/10/12_12:00:04 INFO: Using calculated nic for 10.253.252.251: eth0
IPaddr[23501]:	2007/10/12_12:00:04 INFO: Using calculated netmask for 10.253.252.251: 255.255.255.252
IPaddr[23501]:	2007/10/12_12:00:04 DEBUG: Using calculated broadcast for 10.253.252.251: 10.253.252.251
IPaddr[23501]:	2007/10/12_12:00:04 INFO: eval ifconfig eth0:0 10.253.252.251 netmask 255.255.255.252 broadcast 10.253.252.251
IPaddr[23501]:	2007/10/12_12:00:04 DEBUG: Sending Gratuitous Arp for 10.253.252.251 on eth0:0 [eth0]
IPaddr[23475]:	2007/10/12_12:00:04 INFO:  Success
INFO:  Success
ResourceManager[23386]:	2007/10/12_12:00:04 debug: /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 start done. RC=0
ccm[23331]: 2007/10/12_12:00:04 debug: recv msg hbapi-clstat from dac.uftmasterad.org, status:join
mach_down[23361]:	2007/10/12_12:00:04 info: /usr/share/heartbeat/mach_down: nice_failback: foreign resources acquired
heartbeat[23295]: 2007/10/12_12:00:04 info: AnnounceTakeover(local 1, foreign 0, reason 'T_RESOURCES(us)' (0))
heartbeat[23295]: 2007/10/12_12:00:04 info: mach_down takeover complete.
heartbeat[23295]: 2007/10/12_12:00:04 debug: process_resources(3):  other now stable
heartbeat[23295]: 2007/10/12_12:00:04 info: AnnounceTakeover(local 1, foreign 1, reason 'mach_down' (0))
heartbeat[23295]: 2007/10/12_12:00:04 info: Initial resource acquisition complete (mach_down)
heartbeat[23295]: 2007/10/12_12:00:04 debug: hb_rsc_isstable: ResourceMgmt_child_count: 1, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 3
ccm[23331]: 2007/10/12_12:00:04 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
ccm[23331]: 2007/10/12_12:00:04 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
mach_down[23361]:	2007/10/12_12:00:04 info: mach_down takeover complete for node imalwaysdead.com.
heartbeat[23295]: 2007/10/12_12:00:04 info: Exiting status process 23333 returned rc 0.
heartbeat[23295]: 2007/10/12_12:00:04 debug: RscMgmtProc 'status' exited code 0
heartbeat[23295]: 2007/10/12_12:00:05 debug: APIregistration_dispatch() {
heartbeat[23295]: 2007/10/12_12:00:05 debug: process_registerevent() {
heartbeat[23295]: 2007/10/12_12:00:05 debug: client->gsource = 0x90aa258
heartbeat[23295]: 2007/10/12_12:00:05 debug: }/*process_registerevent*/;
heartbeat[23295]: 2007/10/12_12:00:05 debug: }/*APIregistration_dispatch*/;
heartbeat[23295]: 2007/10/12_12:00:05 debug: Checking client authorization for client stonithd (0:0)
heartbeat[23295]: 2007/10/12_12:00:05 debug: create_seq_snapshot_table:no missing packets found for node dac.uftmasterad.org
heartbeat[23295]: 2007/10/12_12:00:05 debug: Signing on API client 23616 (stonithd)
ccm[23331]: 2007/10/12_12:00:05 WARN: ccm_state_joined: received message with unknown cookie, just dropping
ccm[23331]: 2007/10/12_12:00:05 debug: dump current membership 0xb7ede018
ccm[23331]: 2007/10/12_12:00:05 debug: 	leader=dac.uftmasterad.org
ccm[23331]: 2007/10/12_12:00:05 debug: 	transition=1
ccm[23331]: 2007/10/12_12:00:05 debug: 	status=CCM_STATE_JOINED
ccm[23331]: 2007/10/12_12:00:05 debug: 	has_quorum=1
ccm[23331]: 2007/10/12_12:00:05 debug: 	nodename=dac.uftmasterad.org bornon=1
stonithd[23618]: 2007/10/12_12:00:06 info: stonithd is already running.
lrmd is running [pid: 23620]
Succeeded in adding this resource.
Waiting for lrmd to callback...
----------------operation--------------
type:start
operation status:succeed
op_status: 0
return code: 0
output data: 
[null]
---------------------------------------

optype=0, node_name=dac.uftmasterad.org, result=0, node_list=dac.uftmasterad.org dac.uftmasterad.org
optype=1, node_name=dac.uftmasterad.org, result=0, node_list=dac.uftmasterad.org
heartbeat[23295]: 2007/10/12_12:00:13 info: Local Resource acquisition completed. (none)
heartbeat[23295]: 2007/10/12_12:00:13 info: local resource transition completed.
heartbeat[23295]: 2007/10/12_12:00:13 debug: Sending hold resources msg: all, stable=1 # <none>
heartbeat[23295]: 2007/10/12_12:00:13 info: AnnounceTakeover(local 1, foreign 1, reason 'T_RESOURCES(us)' (1))
heartbeat[23295]: 2007/10/12_12:00:13 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 4
heartbeat[23295]: 2007/10/12_12:00:13 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 4
ccm[23331]: 2007/10/12_12:00:13 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
optype=3, node_name=notexist, result=2, node_list=
heartbeat[23295]: 2007/10/12_12:00:16 debug: Signing client 23616 off
heartbeat[23295]: 2007/10/12_12:00:16 debug: G_remove_client(pid=23616, reason='signoff' gsource=0x90aa258) {
heartbeat[23295]: 2007/10/12_12:00:16 debug: api_remove_client_int: removing pid [23616] reason: signoff
heartbeat[23295]: 2007/10/12_12:00:16 debug: }/*G_remove_client;*/
stonithd[23632]: 2007/10/12_12:00:16 info: Signal sent to a runnig stonithd daemon, its pid is 23616,
All tests for stonithd are ok.
heartbeat[23295]: 2007/10/12_12:00:16 debug: Process 23295 processing SIGTERM
heartbeat[23295]: 2007/10/12_12:00:16 debug: hb_initiate_shutdown() called.
heartbeat[23295]: 2007/10/12_12:00:16 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 4
heartbeat[23295]: 2007/10/12_12:00:16 info: hb_giveup_resources(): current status: active
heartbeat[23295]: 2007/10/12_12:00:16 debug: Sending hold resources msg: none, stable=0 # shutdown
heartbeat[23295]: 2007/10/12_12:00:16 debug: hb_rsc_isstable: ResourceMgmt_child_count: 0, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 5
ccm[23331]: 2007/10/12_12:00:16 debug: recv msg resource from dac.uftmasterad.org, status:[null ptr]
heartbeat[23295]: 2007/10/12_12:00:16 info: Heartbeat shutdown in progress. (23295)
heartbeat[23646]: 2007/10/12_12:00:16 info: Giving up all HA resources.
heartbeat[23295]: 2007/10/12_12:00:16 debug: Process [hb_giveup_resources] started pid 23646
ResourceManager[23659]:	2007/10/12_12:00:16 info: Releasing resource group: imalwaysdead.com IPaddr::10.253.252.251/30
ResourceManager[23659]:	2007/10/12_12:00:16 info: Running /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 stop
ResourceManager[23659]:	2007/10/12_12:00:17 debug: Starting /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 stop
In IP Stop
SIOCDELRT: No such process
IPaddr[23720]:	2007/10/12_12:00:17 INFO: ifconfig eth0:0 down
IPaddr[23694]:	2007/10/12_12:00:17 INFO:  Success
INFO:  Success
ResourceManager[23659]:	2007/10/12_12:00:17 debug: /etc/ha.d/resource.d/IPaddr 10.253.252.251/30 stop done. RC=0
heartbeat[23646]: 2007/10/12_12:00:17 info: All HA resources relinquished.
heartbeat[23646]: 2007/10/12_12:00:17 debug: Sending T_SHUTDONE.
heartbeat[23646]: 2007/10/12_12:00:17 info: FIFO message [type shutdone] written rc=27
heartbeat[23295]: 2007/10/12_12:00:17 debug: hb_rsc_isstable: ResourceMgmt_child_count: 1, other_is_stable: 1, takeover_in_progress: 0, going_standby: 0, standby running(ms): 0, resourcestate: 5
ccm[23331]: 2007/10/12_12:00:17 debug: recv msg shutdone from dac.uftmasterad.org, status:dead
heartbeat[23295]: 2007/10/12_12:00:17 debug: Received T_SHUTDONE from us.
heartbeat[23295]: 2007/10/12_12:00:17 debug: Calling hb_mcp_final_shutdown in a second.
heartbeat[23295]: 2007/10/12_12:00:17 debug: RscMgmtProc 'hb_giveup_resources' exited code 0
heartbeat[23295]: 2007/10/12_12:00:18 debug: hb_mcp_final_shutdown() phase 0
heartbeat[23295]: 2007/10/12_12:00:18 debug: Shutting down client /usr/lib/heartbeat/ipfail
heartbeat[23295]: 2007/10/12_12:00:18 info: killing /usr/lib/heartbeat/ipfail process group 23332 with signal 15
heartbeat[23295]: 2007/10/12_12:00:18 WARN: Gmain_timeout_dispatch: Dispatch function for send local status was delayed 20 ms (> 15 ms) before being called (GSource: 0x9052c38)
heartbeat[23295]: 2007/10/12_12:00:18 info: Gmain_timeout_dispatch: started at 1735584506 should have started at 1735584504
heartbeat[23295]: 2007/10/12_12:00:18 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 20 ms (> 15 ms) before being called (GSource: 0x9052f78)
heartbeat[23295]: 2007/10/12_12:00:18 info: Gmain_timeout_dispatch: started at 1735584506 should have started at 1735584504
heartbeat[23295]: 2007/10/12_12:00:18 WARN: Gmain_timeout_dispatch: Dispatch function for send local status was delayed 20 ms (> 15 ms) before being called (GSource: 0x9052c38)
heartbeat[23295]: 2007/10/12_12:00:18 info: Gmain_timeout_dispatch: started at 1735584540 should have started at 1735584538
heartbeat[23295]: 2007/10/12_12:00:18 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 20 ms (> 15 ms) before being called (GSource: 0x9052f78)
heartbeat[23295]: 2007/10/12_12:00:18 info: Gmain_timeout_dispatch: started at 1735584540 should have started at 1735584538
heartbeat[23295]: 2007/10/12_12:00:18 WARN: Gmain_timeout_dispatch: Dispatch function for send local status was delayed 20 ms (> 15 ms) before being called (GSource: 0x9052c38)
heartbeat[23295]: 2007/10/12_12:00:18 info: Gmain_timeout_dispatch: started at 1735584548 should have started at 1735584546
heartbeat[23295]: 2007/10/12_12:00:18 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 20 ms (> 15 ms) before being called (GSource: 0x9052f78)
heartbeat[23295]: 2007/10/12_12:00:18 info: Gmain_timeout_dispatch: started at 1735584548 should have started at 1735584546
heartbeat[23295]: 2007/10/12_12:00:18 debug: G_remove_client(pid=23332, reason='died' gsource=0x90cdcf8) {
heartbeat[23295]: 2007/10/12_12:00:18 debug: api_remove_client_int: removing pid [23332] reason: died
heartbeat[23295]: 2007/10/12_12:00:18 debug: api_send_client: client 23332 died
heartbeat[23295]: 2007/10/12_12:00:18 debug: }/*G_remove_client;*/
heartbeat[23295]: 2007/10/12_12:00:18 debug: Shutting down client /usr/lib/heartbeat/ccm
heartbeat[23295]: 2007/10/12_12:00:18 info: killing /usr/lib/heartbeat/ccm process group 23331 with signal 15
ccm[23331]: 2007/10/12_12:00:18 info: received SIGTERM, going to shut down
ccm[23331]: 2007/10/12_12:00:18 debug: node state CCM_STATE_JOINED -> CCM_STATE_NONE
heartbeat[23295]: 2007/10/12_12:00:18 debug: G_remove_client(pid=23331, reason='died' gsource=0x90cc118) {
heartbeat[23295]: 2007/10/12_12:00:18 debug: api_remove_client_int: removing pid [23331] reason: died
heartbeat[23295]: 2007/10/12_12:00:18 debug: api_send_client: client 23331 died
heartbeat[23295]: 2007/10/12_12:00:18 debug: }/*G_remove_client;*/
heartbeat[23295]: 2007/10/12_12:00:18 debug: Client /usr/lib/heartbeat/ccm exited with return code 1.
heartbeat[23295]: 2007/10/12_12:00:18 debug: Final client "/usr/lib/heartbeat/ccm" died.
heartbeat[23295]: 2007/10/12_12:00:18 debug: hb_mcp_final_shutdown() phase 1
heartbeat[23295]: 2007/10/12_12:00:18 WARN: Gmain_timeout_dispatch: Dispatch function for send local status was delayed 30 ms (> 15 ms) before being called (GSource: 0x9052c38)
heartbeat[23295]: 2007/10/12_12:00:18 info: Gmain_timeout_dispatch: started at 1735584559 should have started at 1735584556
heartbeat[23295]: 2007/10/12_12:00:18 WARN: Gmain_timeout_dispatch: Dispatch function for check for signals was delayed 30 ms (> 15 ms) before being called (GSource: 0x9052f78)
heartbeat[23295]: 2007/10/12_12:00:18 info: Gmain_timeout_dispatch: started at 1735584559 should have started at 1735584556
heartbeat[23295]: 2007/10/12_12:00:19 debug: hb_mcp_final_shutdown() phase 2
heartbeat[23295]: 2007/10/12_12:00:19 info: killing HBFIFO process 23300 with signal 15
heartbeat[23295]: 2007/10/12_12:00:19 info: killing HBWRITE process 23301 with signal 15
heartbeat[23295]: 2007/10/12_12:00:19 info: killing HBREAD process 23302 with signal 15
heartbeat[23295]: 2007/10/12_12:00:19 info: killing HBWRITE process 23303 with signal 15
heartbeat[23295]: 2007/10/12_12:00:19 info: killing HBREAD process 23304 with signal 15
heartbeat[23295]: 2007/10/12_12:00:19 info: killing HBWRITE process 23305 with signal 15
heartbeat[23295]: 2007/10/12_12:00:19 info: killing HBREAD process 23306 with signal 15
heartbeat[23306]: 2007/10/12_12:00:19 debug: Process 23306 processing SIGTERM
heartbeat[23306]: 2007/10/12_12:00:19 debug: Exiting from pid 23306 [rc=15]
heartbeat[23295]: 2007/10/12_12:00:19 info: Core process 23306 exited. 7 remaining
heartbeat[23305]: 2007/10/12_12:00:19 debug: Process 23305 processing SIGTERM
heartbeat[23305]: 2007/10/12_12:00:19 debug: Exiting from pid 23305 [rc=15]
heartbeat[23295]: 2007/10/12_12:00:19 info: Core process 23305 exited. 6 remaining
heartbeat[23304]: 2007/10/12_12:00:19 debug: Process 23304 processing SIGTERM
heartbeat[23304]: 2007/10/12_12:00:19 debug: Exiting from pid 23304 [rc=15]
heartbeat[23295]: 2007/10/12_12:00:19 info: Core process 23304 exited. 5 remaining
heartbeat[23303]: 2007/10/12_12:00:19 debug: Process 23303 processing SIGTERM
heartbeat[23303]: 2007/10/12_12:00:19 debug: Exiting from pid 23303 [rc=15]
heartbeat[23295]: 2007/10/12_12:00:19 info: Core process 23303 exited. 4 remaining
heartbeat[23302]: 2007/10/12_12:00:19 debug: Process 23302 processing SIGTERM
heartbeat[23302]: 2007/10/12_12:00:19 debug: Exiting from pid 23302 [rc=15]
heartbeat[23295]: 2007/10/12_12:00:19 info: Core process 23302 exited. 3 remaining
heartbeat[23301]: 2007/10/12_12:00:19 debug: Process 23301 processing SIGTERM
heartbeat[23301]: 2007/10/12_12:00:19 debug: Exiting from pid 23301 [rc=15]
heartbeat[23295]: 2007/10/12_12:00:19 info: Core process 23301 exited. 2 remaining
heartbeat[23300]: 2007/10/12_12:00:19 debug: Process 23300 processing SIGTERM
heartbeat[23300]: 2007/10/12_12:00:19 debug: Exiting from pid 23300 [rc=15]
heartbeat[23295]: 2007/10/12_12:00:19 info: Core process 23300 exited. 1 remaining
heartbeat[23295]: 2007/10/12_12:00:19 info: dac.uftmasterad.org Heartbeat shutdown complete.
heartbeat[23295]: 2007/10/12_12:00:19 debug: Exiting from pid 23295 [rc=0]
Starting CRM tests
CRM tests failed (rc=1).
