Jan 14 10:57:21 [30029] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:21 [30029] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:22 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7877320 for uid=0 gid=0 pid=30125 id=1073073e-6a71-4c98-9fe9-54641a65b7a0 Jan 14 10:57:22 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/2, version=0.36.6) Jan 14 10:57:22 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:22 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7877320 for uid=0 gid=0 pid=30127 id=47cb044d-c5aa-4d84-8fd5-3bf48c79f75d Jan 14 10:57:22 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.36.6) Jan 14 10:57:22 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251315']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.36.6) Jan 14 10:57:22 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:44 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/32, version=0.36.7) Jan 14 10:57:44 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='fail-count-pgsql']: No such device or address (rc=-6, origin=local/attrd/23, version=0.36.7) Jan 14 10:57:44 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='last-failure-pgsql']: No such device or address (rc=-6, origin=local/attrd/24, version=0.36.7) Jan 14 10:57:44 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/112, version=0.36.8) Jan 14 10:57:44 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/115, version=0.36.9) Jan 14 10:57:44 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=80:4104:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:44 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:56 Jan 14 10:57:44 [30247] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:44 [30247] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:45 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:56 pid:30237 exit-code:0 exec-time:211ms queue-time:0ms Jan 14 10:57:45 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=56, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:45 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='master-pgsql']: OK (rc=0, origin=local/attrd/25, version=0.36.9) Jan 14 10:57:45 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/26) Jan 14 10:57:45 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/117, version=0.36.10) Jan 14 10:57:45 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='pgsql-master-baseline']: No such device or address (rc=-6, origin=local/attrd/27, version=0.36.10) Jan 14 10:57:45 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/119, version=0.36.11) Jan 14 10:57:52 [30372] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:52 [30372] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:52 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d76a0b10 for uid=0 gid=0 pid=30468 id=4c59bc36-a095-44af-85ab-cc4de2dcff9a Jan 14 10:57:52 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/2, version=0.36.11) Jan 14 10:57:52 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:52 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d76a0b10 for uid=0 gid=0 pid=30470 id=e7f2d442-923a-457e-8c0f-692e5ebd0765 Jan 14 10:57:52 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.36.11) Jan 14 10:57:52 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251315']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.36.11) Jan 14 10:57:52 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='pgsql-status']: OK (rc=0, origin=local/attrd/28, version=0.36.11) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/29) Jan 14 10:57:54 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=81:4104:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:54 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:59 Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/123, version=0.36.12) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/33, version=0.36.13) Jan 14 10:57:54 [30488] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:54 [30488] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 pgsql(pgsql)[30478]: 2016/01/14_10:57:54 INFO: My master baseline : 000004AFF4000090. Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=30659 id=8e0912aa-f2c3-4678-941e-666184670be1 Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.36.13) Jan 14 10:57:54 [20557] nbgprepdb5 attrd: notice: attrd_trigger_update: Sending flush op to all hosts for: pgsql-master-baseline (000004AFF4000090) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='pgsql-master-baseline']: No such device or address (rc=-6, origin=local/attrd/30, version=0.36.13) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section /cib: OK (rc=0, origin=local/attrd/31, version=0.36.13) Jan 14 10:57:54 [20557] nbgprepdb5 attrd: notice: attrd_perform_update: Sent update 32: pgsql-master-baseline=000004AFF4000090 Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/32) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=30665 id=4bec77df-b85e-4525-a177-4bc37f578e11 Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/2, version=0.36.13) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/32, version=0.36.14) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=30666 id=916df6d9-a62a-4294-813f-f89863241292 Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.36.14) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='pgsql-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.36.14) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events pgsql(pgsql)[30478]: 2016/01/14_10:57:54 INFO: Changing pgsql-status on nbgprepdb5 : HS:async->HS:alone. Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=30672 id=eb1cc42b-ac60-4c4f-97f1-06df755e4523 Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.36.14) Jan 14 10:57:54 [20557] nbgprepdb5 attrd: notice: attrd_trigger_update: Sending flush op to all hosts for: pgsql-status (HS:alone) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='pgsql-status']: OK (rc=0, origin=local/attrd/33, version=0.36.14) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:54 [20557] nbgprepdb5 attrd: notice: attrd_perform_update: Sent update 34: pgsql-status=HS:alone Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/34) Jan 14 10:57:54 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_notify_0:30478:stderr [ 2016/01/14_10:57:54 INFO: My master baseline : 000004AFF4000090. ] Jan 14 10:57:54 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_notify_0:30478:stderr [ 2016/01/14_10:57:54 INFO: Changing pgsql-status on nbgprepdb5 : HS:async->HS:alone. ] Jan 14 10:57:54 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:59 pid:30478 exit-code:0 exec-time:387ms queue-time:0ms Jan 14 10:57:54 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=59, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/34, version=0.36.15) Jan 14 10:57:54 [20556] nbgprepdb5 lrmd: info: cancel_recurring_action: Cancelling operation vip-slave_monitor_10000 Jan 14 10:57:54 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=17:4105:0:48387f7e-8de3-4eed-bab6-3db414426226 op=vip-slave_stop_0 Jan 14 10:57:54 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:vip-slave action:stop call_id:63 Jan 14 10:57:54 [20556] nbgprepdb5 lrmd: info: cancel_recurring_action: Cancelling operation pgsql_monitor_30000 Jan 14 10:57:54 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=75:4105:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:54 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:67 Jan 14 10:57:54 [20559] nbgprepdb5 crmd: info: process_lrm_event: LRM operation vip-slave_monitor_10000 (call=53, status=1, cib-update=0, confirmed=true) Cancelled Jan 14 10:57:54 [20559] nbgprepdb5 crmd: info: process_lrm_event: LRM operation pgsql_monitor_30000 (call=47, status=1, cib-update=0, confirmed=true) Cancelled Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_delete operation for section status to master (origin=local/crmd/22) Jan 14 10:57:54 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/22, version=0.36.16) Jan 14 10:57:54 [30691] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:54 [30691] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 IPaddr2(vip-slave)[30673]: 2016/01/14_10:57:55 INFO: IP status = ok, IP_CIP= Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-slave_stop_0:30673:stderr [ 2016/01/14_10:57:55 INFO: IP status = ok, IP_CIP= ] Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:vip-slave action:stop call_id:63 pid:30673 exit-code:0 exec-time:66ms queue-time:0ms Jan 14 10:57:55 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation vip-slave_stop_0 (call=63, rc=0, cib-update=23, confirmed=true) ok Jan 14 10:57:55 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/23) Jan 14 10:57:55 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/23, version=0.36.17) Jan 14 10:57:55 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/34, version=0.36.18) Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:67 pid:30675 exit-code:0 exec-time:182ms queue-time:0ms Jan 14 10:57:55 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=67, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:55 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=70:4106:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:73 Jan 14 10:57:55 [30801] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:55 [30801] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:55 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/35, version=0.36.19) Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:73 pid:30791 exit-code:0 exec-time:184ms queue-time:0ms Jan 14 10:57:55 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=73, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:55 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=71:4106:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:76 Jan 14 10:57:55 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/36, version=0.36.20) Jan 14 10:57:55 [30881] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:55 [30881] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:76 pid:30871 exit-code:0 exec-time:160ms queue-time:0ms Jan 14 10:57:55 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=76, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:55 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=67:4106:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:79 Jan 14 10:57:55 [30967] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:55 [30967] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:55 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:79 pid:30957 exit-code:0 exec-time:149ms queue-time:0ms Jan 14 10:57:55 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=79, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:56 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=68:4106:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:56 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:82 Jan 14 10:57:56 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/37, version=0.36.21) Jan 14 10:57:56 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='fail-count-pgsql']: No such device or address (rc=-6, origin=local/attrd/35, version=0.36.21) Jan 14 10:57:56 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='last-failure-pgsql']: No such device or address (rc=-6, origin=local/attrd/36, version=0.36.21) Jan 14 10:57:56 [31048] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:56 [31048] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:56 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/128, version=0.36.22) Jan 14 10:57:56 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='fail-count-pgsql']: No such device or address (rc=-6, origin=local/attrd/37, version=0.36.22) Jan 14 10:57:56 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='last-failure-pgsql']: No such device or address (rc=-6, origin=local/attrd/38, version=0.36.22) Jan 14 10:57:56 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/130, version=0.36.23) Jan 14 10:57:56 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:82 pid:31038 exit-code:0 exec-time:159ms queue-time:0ms Jan 14 10:57:56 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=82, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:56 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=66:4107:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:56 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:85 Jan 14 10:57:56 [31134] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:56 [31134] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:56 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:85 pid:31124 exit-code:0 exec-time:160ms queue-time:0ms Jan 14 10:57:56 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=85, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:56 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=67:4107:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:56 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:88 Jan 14 10:57:56 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/38, version=0.36.24) Jan 14 10:57:56 [31217] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:56 [31217] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:57 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:88 pid:31207 exit-code:0 exec-time:180ms queue-time:0ms Jan 14 10:57:57 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=88, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:57 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=69:4107:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:57:57 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:91 Jan 14 10:57:57 [31303] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:57 [31303] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:57 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:91 pid:31293 exit-code:0 exec-time:160ms queue-time:0ms Jan 14 10:57:57 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=91, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:57:57 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=23:4107:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_promote_0 Jan 14 10:57:57 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:promote call_id:94 Jan 14 10:57:57 [31391] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:57:57 [31391] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31466 id=f59cdd96-dd6e-40cb-8ed9-16735080f88b Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/2, version=0.36.24) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31467 id=0733ba9c-e131-41e7-8019-1f85a58ba92e Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.36.24) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251316']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.36.24) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events pgsql(pgsql)[31381]: 2016/01/14_10:57:57 INFO: Changing pgsql-data-status on nbgprepdb6 : LATEST->DISCONNECT. Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31474 id=db061626-d31a-4ffc-80e3-91aecf90527c Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.36.24) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251316']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.36.24) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section nodes to master (origin=local/crm_attribute/4) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section nodes: OK (rc=0, origin=nbgprepwitness56/crm_attribute/4, version=0.37.1) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: write_cib_contents: Archived previous version as /var/lib/pacemaker/cib/cib-59.raw Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: write_cib_contents: Wrote version 0.37.0 of the CIB to disk (digest: a2c9162c203c21d99ad1367e366d47a0) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: retrieveCib: Reading cluster configuration from: /var/lib/pacemaker/cib/cib.9UvrZ7 (digest: /var/lib/pacemaker/cib/cib.Jxc9EA) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31477 id=50050d5e-061f-40ba-8fe7-915219f09574 Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.37.1) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251316']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.37.1) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31483 id=c9b52a1c-fe82-46a9-a027-11a3d633c8be Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/2, version=0.37.1) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31486 id=9aa8f1af-6e59-46ef-9e3e-b6874a8e9761 Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.37.1) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251316']//transient_attributes//nvpair[@name='master-pgsql']: OK (rc=0, origin=local/crm_attribute/3, version=0.37.1) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events pgsql(pgsql)[31381]: 2016/01/14_10:57:57 INFO: Creating /var/lib/pgsql/tmp/PGSQL.lock. pgsql(pgsql)[31381]: 2016/01/14_10:57:57 INFO: My master baseline : 000004AFF4000090. Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31547 id=5a481cc6-1239-401d-85e1-de997140e6f4 Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.37.1) Jan 14 10:57:57 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events pgsql(pgsql)[31381]: 2016/01/14_10:57:57 INFO: server promoting pgsql(pgsql)[31381]: 2016/01/14_10:57:57 INFO: PostgreSQL promote command sent. pgsql(pgsql)[31381]: 2016/01/14_10:58:02 INFO: PostgreSQL is promoted. Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31715 id=a2d015ab-91c1-47c0-89c2-d8e8ae8a6063 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/2, version=0.37.1) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31716 id=a71ce106-de43-4b0e-94d5-8fe846252265 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.37.1) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251315']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.37.1) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events pgsql(pgsql)[31381]: 2016/01/14_10:58:02 INFO: Changing pgsql-data-status on nbgprepdb5 : STREAMING|ASYNC->LATEST. Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31723 id=2a45e889-c870-4621-8a71-6e165af346d2 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.37.1) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251315']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.37.1) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section nodes to master (origin=local/crm_attribute/4) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section nodes: OK (rc=0, origin=nbgprepwitness56/crm_attribute/4, version=0.38.1) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: write_cib_contents: Archived previous version as /var/lib/pacemaker/cib/cib-60.raw Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: write_cib_contents: Wrote version 0.38.0 of the CIB to disk (digest: f035641a5f8a33bc8907a0f852cc607a) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: retrieveCib: Reading cluster configuration from: /var/lib/pacemaker/cib/cib.oeyrxm (digest: /var/lib/pacemaker/cib/cib.MWEFC0) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31726 id=881b7040-b639-49a3-8a73-a39e3151a6a2 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.38.1) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251315']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.38.1) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31730 id=dccf8138-e4b5-4b27-a29f-fee708b6bc6b Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.38.1) Jan 14 10:58:02 [20557] nbgprepdb5 attrd: notice: attrd_trigger_update: Sending flush op to all hosts for: master-pgsql (1000) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='master-pgsql']: OK (rc=0, origin=local/attrd/39, version=0.38.1) Jan 14 10:58:02 [20557] nbgprepdb5 attrd: notice: attrd_perform_update: Sent update 40: master-pgsql=1000 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/40) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31736 id=a0d2e7c9-640f-4bca-8b5c-2e3aed1d7aad Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/40, version=0.38.2) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/2, version=0.38.2) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31737 id=892b5ef3-5d0a-4f6c-9c40-8f59f80edeb0 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.38.2) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='pgsql-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.38.2) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events pgsql(pgsql)[31381]: 2016/01/14_10:58:02 INFO: Changing pgsql-status on nbgprepdb5 : HS:alone->PRI. Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31743 id=014581c7-82d2-4086-9418-a63e7f6fc6bc Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.38.2) Jan 14 10:58:02 [20557] nbgprepdb5 attrd: notice: attrd_trigger_update: Sending flush op to all hosts for: pgsql-status (PRI) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/status//node_state[@id='739251315']//transient_attributes//nvpair[@name='pgsql-status']: OK (rc=0, origin=local/attrd/41, version=0.38.2) Jan 14 10:58:02 [20557] nbgprepdb5 attrd: notice: attrd_perform_update: Sent update 42: pgsql-status=PRI Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/attrd/42) Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_promote_0:31381:stderr [ 2016/01/14_10:57:57 INFO: Changing pgsql-data-status on nbgprepdb6 : LATEST->DISCONNECT. ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_promote_0:31381:stderr [ 2016/01/14_10:57:57 INFO: Creating /var/lib/pgsql/tmp/PGSQL.lock. ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_promote_0:31381:stderr [ 2016/01/14_10:57:57 INFO: My master baseline : 000004AFF4000090. ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_promote_0:31381:stderr [ 2016/01/14_10:57:57 INFO: server promoting ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_promote_0:31381:stderr [ 2016/01/14_10:57:57 INFO: PostgreSQL promote command sent. ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_promote_0:31381:stderr [ 2016/01/14_10:58:02 INFO: PostgreSQL is promoted. ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_promote_0:31381:stderr [ 2016/01/14_10:58:02 INFO: Changing pgsql-data-status on nbgprepdb5 : STREAMING|ASYNC->LATEST. ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: pgsql_promote_0:31381:stderr [ 2016/01/14_10:58:02 INFO: Changing pgsql-status on nbgprepdb5 : HS:alone->PRI. ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:promote call_id:94 pid:31381 exit-code:0 exec-time:5406ms queue-time:0ms Jan 14 10:58:02 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_promote_0 (call=94, rc=0, cib-update=24, confirmed=true) ok Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/24) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/attrd/42, version=0.38.3) Jan 14 10:58:02 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=70:4107:0:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_notify_0 Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:pgsql action:notify call_id:97 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/24, version=0.38.4) Jan 14 10:58:02 [31754] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:58:02 [31754] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=31824 id=278e06ec-2d5d-4d6a-924b-8bc3507c6961 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.38.4) Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:pgsql action:notify call_id:97 pid:31744 exit-code:0 exec-time:175ms queue-time:1ms Jan 14 10:58:02 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation pgsql_notify_0 (call=97, rc=0, cib-update=0, confirmed=true) ok Jan 14 10:58:02 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=12:4108:0:48387f7e-8de3-4eed-bab6-3db414426226 op=vip-slave_start_0 Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:vip-slave action:start call_id:100 Jan 14 10:58:02 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=14:4108:0:48387f7e-8de3-4eed-bab6-3db414426226 op=vip-master_start_0 Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:vip-master action:start call_id:102 Jan 14 10:58:02 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=26:4108:8:48387f7e-8de3-4eed-bab6-3db414426226 op=pgsql_monitor_10000 Jan 14 10:58:02 [31854] nbgprepdb5 pacemakerd: info: crm_log_init: Changed active directory to /var/lib/heartbeat/cores/root Jan 14 10:58:02 [31854] nbgprepdb5 pacemakerd: info: crm_xml_cleanup: Cleaning up memory from libxml2 IPaddr2(vip-slave)[31831]: 2016/01/14_10:58:02 INFO: Adding IPv4 address 192.168.20.101/24 with broadcast address 192.168.20.255 to device eth0 IPaddr2(vip-master)[31832]: 2016/01/14_10:58:02 INFO: Adding IPv4 address 192.168.20.100/24 with broadcast address 192.168.20.255 to device eth0 IPaddr2(vip-slave)[31831]: 2016/01/14_10:58:02 INFO: Bringing device eth0 up IPaddr2(vip-master)[31832]: 2016/01/14_10:58:02 INFO: Bringing device eth0 up IPaddr2(vip-slave)[31831]: 2016/01/14_10:58:02 INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-192.168.20.101 eth0 192.168.20.101 auto not_used not_used IPaddr2(vip-master)[31832]: 2016/01/14_10:58:02 INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-192.168.20.100 eth0 192.168.20.100 auto not_used not_used Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-master_start_0:31832:stderr [ 2016/01/14_10:58:02 INFO: Adding IPv4 address 192.168.20.100/24 with broadcast address 192.168.20.255 to device eth0 ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-master_start_0:31832:stderr [ 2016/01/14_10:58:02 INFO: Bringing device eth0 up ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-master_start_0:31832:stderr [ 2016/01/14_10:58:02 INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-192.168.20.100 eth0 192.168.20.100 auto not_used not_used ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:vip-master action:start call_id:102 pid:31832 exit-code:0 exec-time:109ms queue-time:0ms Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-slave_start_0:31831:stderr [ 2016/01/14_10:58:02 INFO: Adding IPv4 address 192.168.20.101/24 with broadcast address 192.168.20.255 to device eth0 ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-slave_start_0:31831:stderr [ 2016/01/14_10:58:02 INFO: Bringing device eth0 up ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-slave_start_0:31831:stderr [ 2016/01/14_10:58:02 INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-192.168.20.101 eth0 192.168.20.101 auto not_used not_used ] Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:vip-slave action:start call_id:100 pid:31831 exit-code:0 exec-time:111ms queue-time:0ms Jan 14 10:58:02 [20559] nbgprepdb5 crmd: info: services_os_action_execute: Managed IPaddr2_meta-data_0 process 31992 exited with rc=0 Jan 14 10:58:02 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation vip-master_start_0 (call=102, rc=0, cib-update=25, confirmed=true) ok Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/25) Jan 14 10:58:02 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation vip-slave_start_0 (call=100, rc=0, cib-update=26, confirmed=true) ok Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/26) Jan 14 10:58:02 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=15:4108:0:48387f7e-8de3-4eed-bab6-3db414426226 op=vip-master_monitor_10000 Jan 14 10:58:02 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=16:4108:0:48387f7e-8de3-4eed-bab6-3db414426226 op=vip-rep_start_0 Jan 14 10:58:02 [20556] nbgprepdb5 lrmd: info: log_execute: executing - rsc:vip-rep action:start call_id:110 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/25, version=0.38.5) Jan 14 10:58:02 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=13:4108:0:48387f7e-8de3-4eed-bab6-3db414426226 op=vip-slave_monitor_10000 Jan 14 10:58:02 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/26, version=0.38.6) Jan 14 10:58:03 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation vip-slave_monitor_10000 (call=112, rc=0, cib-update=27, confirmed=false) ok Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/27) Jan 14 10:58:03 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation vip-master_monitor_10000 (call=108, rc=0, cib-update=28, confirmed=false) ok Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/27, version=0.38.7) Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/28) Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/28, version=0.38.8) IPaddr2(vip-rep)[32011]: 2016/01/14_10:58:03 INFO: Adding IPv4 address 192.168.100.156/24 with broadcast address 192.168.100.255 to device eth2 IPaddr2(vip-rep)[32011]: 2016/01/14_10:58:03 INFO: Bringing device eth2 up IPaddr2(vip-rep)[32011]: 2016/01/14_10:58:03 INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-192.168.100.156 eth2 192.168.100.156 auto not_used not_used Jan 14 10:58:03 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-rep_start_0:32011:stderr [ 2016/01/14_10:58:03 INFO: Adding IPv4 address 192.168.100.156/24 with broadcast address 192.168.100.255 to device eth2 ] Jan 14 10:58:03 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-rep_start_0:32011:stderr [ 2016/01/14_10:58:03 INFO: Bringing device eth2 up ] Jan 14 10:58:03 [20556] nbgprepdb5 lrmd: notice: operation_finished: vip-rep_start_0:32011:stderr [ 2016/01/14_10:58:03 INFO: /usr/lib/heartbeat/send_arp -i 200 -r 5 -p /var/run/resource-agents/send_arp-192.168.100.156 eth2 192.168.100.156 auto not_used not_used ] Jan 14 10:58:03 [20556] nbgprepdb5 lrmd: info: log_finished: finished - rsc:vip-rep action:start call_id:110 pid:32011 exit-code:0 exec-time:172ms queue-time:0ms Jan 14 10:58:03 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation vip-rep_start_0 (call=110, rc=0, cib-update=29, confirmed=true) ok Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/29) Jan 14 10:58:03 [20559] nbgprepdb5 crmd: info: do_lrm_rsc_op: Performing key=17:4108:0:48387f7e-8de3-4eed-bab6-3db414426226 op=vip-rep_monitor_10000 Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/29, version=0.38.9) Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d7a4d720 for uid=0 gid=0 pid=32201 id=2f0db6d3-dd4d-4aa1-a55e-eea50e578af5 Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section 'all': OK (rc=0, origin=local/crm_mon/2, version=0.38.9) Jan 14 10:58:03 [20559] nbgprepdb5 crmd: notice: process_lrm_event: LRM operation vip-rep_monitor_10000 (call=117, rc=0, cib-update=30, confirmed=false) ok Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Forwarding cib_modify operation for section status to master (origin=local/crmd/30) Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: crm_client_destroy: Destroying 0 events Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_apply_diff operation for section status: OK (rc=0, origin=nbgprepwitness56/crmd/30, version=0.38.10) Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: crm_client_new: Connecting 0x7f02d78fec40 for uid=0 gid=0 pid=32202 id=87fe7545-1960-4081-92d4-7b0b6c8e6597 Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section nodes: OK (rc=0, origin=local/crm_attribute/2, version=0.38.10) Jan 14 10:58:03 [20554] nbgprepdb5 cib: info: cib_process_request: Completed cib_query operation for section //cib/configuration/nodes//node[@id='739251315']//instance_attributes//nvpair[@name='pgsql-data-status']: OK (rc=0, origin=local/crm_attribute/3, version=0.38.10)