[root@fabric1 mysql]# mysqlfabric group promote test --slave_id='a6dcd87a-8e64-11e4-a8b6-0800277db20d'
[DEBUG] 1419755175.674932 - XML-RPC-Server - Enqueuing request (<socket._socketobject object at 0x7feeb4031910>) from (('127.0.0.1', 60322)) through thread (<SessionThread(XML-RPC-Session-0, started daemon 140663031052032)>).
[DEBUG] 1419755175.675578 - XML-RPC-Session-0 - Processing request (<socket._socketobject object at 0x7feeb4031910>) from (('127.0.0.1', 60322)) through thread (<SessionThread(XML-RPC-Session-0, started daemon 140663031052032)>).
[DEBUG] 1419755175.679086 - XML-RPC-Session-0 - Finishing request (<socket._socketobject object at 0x7feeb4031910>) from (('127.0.0.1', 60322)) through thread (<SessionThread(XML-RPC-Session-0, started daemon 140663031052032)>).
[DEBUG] 1419755175.683904 - XML-RPC-Server - Enqueuing request (<socket._socketobject object at 0x2101c20>) from (('127.0.0.1', 60323)) through thread (<SessionThread(XML-RPC-Session-0, started daemon 140663031052032)>).
[DEBUG] 1419755175.684163 - XML-RPC-Session-0 - Processing request (<socket._socketobject object at 0x2101c20>) from (('127.0.0.1', 60323)) through thread (<SessionThread(XML-RPC-Session-0, started daemon 140663031052032)>).
[DEBUG] 1419755175.686641 - XML-RPC-Session-0 - Statement (SELECT username, protocol, passwordFROM users WHERE username = %s AND protocol = %s, Params(('admin', 'xmlrpc')).
[DEBUG] 1419755175.691197 - XML-RPC-Session-0 - Statement (SELECT p.subsystem, p.component, p.functionFROM users AS u LEFT JOIN user_roles AS ur USING (user_id)    LEFT JOIN role_permissions AS rp USING (role_id)    LEFT JOIN permissions AS p USING (permission_id)WHERE    u.username = %s AND protocol = %s, Params((u'admin', u'xmlrpc')).
[DEBUG] 1419755175.692968 - XML-RPC-Session-0 - Started command (group, promote).
[DEBUG] 1419755175.694499 - XML-RPC-Session-0 - Statement (INSERT INTO log (subject, reported, reporter, message, category, type) VALUES(%s, %s, %s, %s, %s, %s), Params(('group.promote', datetime.datetime(2014, 12, 28, 8, 26, 15), 'mysql.fabric.command', 'Started command (group, promote).', 1, 0)).
[DEBUG] 1419755175.696879 - XML-RPC-Session-0 - Triggering event <mysql.fabric.events.Event object at 0x18cfb10> in handler <mysql.fabric.events.Handler object at 0x193dc50>
[DEBUG] 1419755175.697019 - XML-RPC-Session-0 - Triggering event <mysql.fabric.events.Event object at 0x18cfb10>
[DEBUG] 1419755175.697440 - XML-RPC-Session-0 - Created procedure (1e1ea536-e9a9-4a3b-8327-24294ece7b7f).
[DEBUG] 1419755175.697732 - XML-RPC-Session-0 - Created job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 2996e3a3-b95e-45a7-93a6-ae63170a4e82, mysql.fabric.services.highavailability._define_ha_operation, Success).
[DEBUG] 1419755175.699219 - XML-RPC-Session-0 - Statement (BEGIN, Params(()).
[DEBUG] 1419755175.700247 - XML-RPC-Session-0 - Statement (INSERT INTO checkpoints(proc_uuid, lockable_objects, job_uuid, sequence, action_fqn, param_args, param_kwargs) VALUES (%s, %s, %s, %s, %s, %s, %s), Params(('1e1ea536-e9a9-4a3b-8327-24294ece7b7f', "c__builtin__\nset\np0\n((lp1\nS'lock'\np2\natp3\nRp4\n.", '2996e3a3-b95e-45a7-93a6-ae63170a4e82', 0, 'mysql.fabric.services.highavailability._define_ha_operation', "(S'test'\np0\nS'a6dcd87a-8e64-11e4-a8b6-0800277db20d'\np1\nI00\ntp2\n.", '(dp0\n.')).
[DEBUG] 1419755175.701355 - XML-RPC-Session-0 - Statement (COMMIT, Params(()).
[DEBUG] 1419755175.702864 - XML-RPC-Session-0 - Enqueued procedure (1e1ea536-e9a9-4a3b-8327-24294ece7b7f).
[DEBUG] 1419755175.703145 - Executor-0 - Locking procedure (1e1ea536-e9a9-4a3b-8327-24294ece7b7f).
[DEBUG] 1419755175.703263 - Executor-0 - LockManager - Enqueuing request for procedure (<Procedure object: uuid=1e1ea536-e9a9-4a3b-8327-24294ece7b7f, complete=False, exec_jobs=[], sche_jobs=['2996e3a3-b95e-45a7-93a6-ae63170a4e82']>) on objects(set(['lock'])).
[DEBUG] 1419755175.703455 - Executor-0 - Locked procedure (1e1ea536-e9a9-4a3b-8327-24294ece7b7f).
[DEBUG] 1419755175.703617 - Executor-0 - Executing procedure (<Procedure object: uuid=1e1ea536-e9a9-4a3b-8327-24294ece7b7f, complete=False, exec_jobs=[], sche_jobs=['2996e3a3-b95e-45a7-93a6-ae63170a4e82']>).
[DEBUG] 1419755175.703761 - Executor-0 - Reading next job from queue, found <Job object: uuid=2996e3a3-b95e-45a7-93a6-ae63170a4e82, status=[{'diagnosis': '', 'state': 3, 'when': 1419755175.697714, 'description': 'Triggered by <mysql.fabric.events.Event object at 0x18cfb10>.', 'success': 2}]>.
[DEBUG] 1419755175.703988 - Executor-0 - Processing job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 2996e3a3-b95e-45a7-93a6-ae63170a4e82, mysql.fabric.services.highavailability._define_ha_operation, Success).
[DEBUG] 1419755175.704372 - Executor-0 - Statement (UPDATE checkpoints set started = %s WHERE proc_uuid = %s and job_uuid = %s, Params((1419755175.704091, '1e1ea536-e9a9-4a3b-8327-24294ece7b7f', '2996e3a3-b95e-45a7-93a6-ae63170a4e82')).
[DEBUG] 1419755175.707999 - Executor-0 - Statement (BEGIN, Params(()).
[DEBUG] 1419755175.708516 - Executor-0 - Executing _define_ha_operation
[DEBUG] 1419755175.708641 - Executor-0 - Statement (SELECT group_id, description, master_uuid, master_defined, status FROM groups WHERE group_id = %s, Params(('test',)).
[DEBUG] 1419755175.709774 - Executor-0 - Triggering event <mysql.fabric.events.Event object at 0x2098a10> in handler <mysql.fabric.events.Handler object at 0x193dc50>
[DEBUG] 1419755175.709892 - Executor-0 - Triggering event <mysql.fabric.events.Event object at 0x2098a10>
[DEBUG] 1419755175.710218 - Executor-0 - Created job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 2db84b07-69df-482b-9d7b-1f4937e97308, mysql.fabric.services.highavailability._check_candidate_fail, Success).
[DEBUG] 1419755175.710600 - Executor-0 - Statement (INSERT INTO checkpoints(proc_uuid, lockable_objects, job_uuid, sequence, action_fqn, param_args, param_kwargs) VALUES (%s, %s, %s, %s, %s, %s, %s), Params(('1e1ea536-e9a9-4a3b-8327-24294ece7b7f', "c__builtin__\nset\np0\n((lp1\nS'lock'\np2\natp3\nRp4\n.", '2db84b07-69df-482b-9d7b-1f4937e97308', 1, 'mysql.fabric.services.highavailability._check_candidate_fail', "(S'test'\np0\nS'a6dcd87a-8e64-11e4-a8b6-0800277db20d'\np1\ntp2\n.", '(dp0\n.')).
[DEBUG] 1419755175.711454 - Executor-0 - Statement (UPDATE checkpoints set finished = %s WHERE proc_uuid = %s and job_uuid = %s, Params((1419755175.711405, '1e1ea536-e9a9-4a3b-8327-24294ece7b7f', '2996e3a3-b95e-45a7-93a6-ae63170a4e82')).
[DEBUG] 1419755175.712194 - Executor-0 - Statement (COMMIT, Params(()).
[DEBUG] 1419755175.714617 - Executor-0 - Complete job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 2996e3a3-b95e-45a7-93a6-ae63170a4e82, mysql.fabric.services.highavailability._define_ha_operation, Success).
[DEBUG] 1419755175.714826 - Executor-0 - Reading next job from queue, found <Job object: uuid=2db84b07-69df-482b-9d7b-1f4937e97308, status=[{'diagnosis': '', 'state': 3, 'when': 1419755175.7102, 'description': 'Triggered by <mysql.fabric.events.Event object at 0x2098a10>.', 'success': 2}]>.
[DEBUG] 1419755175.714961 - Executor-0 - Processing job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 2db84b07-69df-482b-9d7b-1f4937e97308, mysql.fabric.services.highavailability._check_candidate_fail, Success).
[DEBUG] 1419755175.715276 - Executor-0 - Statement (UPDATE checkpoints set started = %s WHERE proc_uuid = %s and job_uuid = %s, Params((1419755175.715064, '1e1ea536-e9a9-4a3b-8327-24294ece7b7f', '2db84b07-69df-482b-9d7b-1f4937e97308')).
[DEBUG] 1419755175.717203 - Executor-0 - Statement (BEGIN, Params(()).
[DEBUG] 1419755175.717678 - Executor-0 - Executing _check_candidate_fail
[DEBUG] 1419755175.717800 - Executor-0 - Statement (SELECT group_id, description, master_uuid, master_defined, status FROM groups WHERE group_id = %s, Params(('test',)).
[DEBUG] 1419755175.718858 - Executor-0 - Statement (SELECT server_uuid, server_address, mode, status, weight, group_id FROM servers WHERE server_uuid = %s, Params(('a6dcd87a-8e64-11e4-a8b6-0800277db20d',)).
[DEBUG] 1419755175.720925 - Executor-0 - Using connection (<mysql.connector.connection.MySQLConnection object at 0x7feeb4034a10>).
[DEBUG] 1419755175.721126 - Executor-0 - Statement (SELECT @@GLOBAL.SERVER_UUID as SERVER_UUID, Params(()).
[DEBUG] 1419755175.722597 - Executor-0 - Statement (SELECT @@GLOBAL.SERVER_ID as SERVER_ID, Params(()).
[DEBUG] 1419755175.723881 - Executor-0 - Statement (SELECT @@GLOBAL.VERSION as VERSION, Params(()).
[DEBUG] 1419755175.725224 - Executor-0 - Statement (SELECT @@GLOBAL.GTID_MODE as GTID_MODE, Params(()).
[DEBUG] 1419755175.726883 - Executor-0 - Statement (SELECT @@GLOBAL.LOG_BIN as LOG_BIN, Params(()).
[DEBUG] 1419755175.728072 - Executor-0 - Statement (SELECT @@GLOBAL.READ_ONLY as READ_ONLY, Params(()).
[DEBUG] 1419755175.729212 - Executor-0 - Connected to server with uuid (a6dcd87a-8e64-11e4-a8b6-0800277db20d), server_id (11), version (5.6.22-log), gtid (True), binlog (True), read_only (False).
[DEBUG] 1419755175.729449 - Executor-0 - Start executing function: check_master_issues((<mysql.fabric.server.MySQLServer object at 0x7feeb4036a50>,), {}).
[DEBUG] 1419755175.729574 - Executor-0 - Statement (SELECT @@GLOBAL.LOG_SLAVE_UPDATES as LOG_SLAVE_UPDATES, Params(()).
[DEBUG] 1419755175.730935 - Executor-0 - Start executing function: has_appropriate_privileges((<mysql.fabric.server.MySQLServer object at 0x7feeb4036a50>,), {}).
[DEBUG] 1419755175.731073 - Executor-0 - Statement (SHOW GRANTS, Params(()).
[DEBUG] 1419755175.733026 - Executor-0 - Finish executing function: has_appropriate_privileges.
[DEBUG] 1419755175.733161 - Executor-0 - Finish executing function: check_master_issues.
[DEBUG] 1419755175.733275 - Executor-0 - Triggering event <mysql.fabric.events.Event object at 0x2098a90> in handler <mysql.fabric.events.Handler object at 0x193dc50>
[DEBUG] 1419755175.733380 - Executor-0 - Triggering event <mysql.fabric.events.Event object at 0x2098a90>
[DEBUG] 1419755175.733691 - Executor-0 - Created job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 7ca34996-8794-4bca-b8c0-895082c78a1c, mysql.fabric.services.highavailability._wait_slave_fail, Success).
[DEBUG] 1419755175.733863 - Executor-0 - Disconnecting from server with uuid (a6dcd87a-8e64-11e4-a8b6-0800277db20d), server_id (11), version (5.6.22-log), gtid (True), binlog (True), read_only (False).
[DEBUG] 1419755175.734264 - Executor-0 - Statement (INSERT INTO checkpoints(proc_uuid, lockable_objects, job_uuid, sequence, action_fqn, param_args, param_kwargs) VALUES (%s, %s, %s, %s, %s, %s, %s), Params(('1e1ea536-e9a9-4a3b-8327-24294ece7b7f', "c__builtin__\nset\np0\n((lp1\nS'lock'\np2\natp3\nRp4\n.", '7ca34996-8794-4bca-b8c0-895082c78a1c', 2, 'mysql.fabric.services.highavailability._wait_slave_fail', "(S'test'\np0\nS'a6dcd87a-8e64-11e4-a8b6-0800277db20d'\np1\ntp2\n.", '(dp0\n.')).
[DEBUG] 1419755175.735392 - Executor-0 - Statement (UPDATE checkpoints set finished = %s WHERE proc_uuid = %s and job_uuid = %s, Params((1419755175.735342, '1e1ea536-e9a9-4a3b-8327-24294ece7b7f', '2db84b07-69df-482b-9d7b-1f4937e97308')).
[DEBUG] 1419755175.737203 - Executor-0 - Statement (COMMIT, Params(()).
[DEBUG] 1419755175.738856 - Executor-0 - Complete job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 2db84b07-69df-482b-9d7b-1f4937e97308, mysql.fabric.services.highavailability._check_candidate_fail, Success).
[DEBUG] 1419755175.739059 - Executor-0 - Reading next job from queue, found <Job object: uuid=7ca34996-8794-4bca-b8c0-895082c78a1c, status=[{'diagnosis': '', 'state': 3, 'when': 1419755175.733674, 'description': 'Triggered by <mysql.fabric.events.Event object at 0x2098a90>.', 'success': 2}]>.
[DEBUG] 1419755175.739219 - Executor-0 - Processing job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 7ca34996-8794-4bca-b8c0-895082c78a1c, mysql.fabric.services.highavailability._wait_slave_fail, Success).
[DEBUG] 1419755175.739527 - Executor-0 - Statement (UPDATE checkpoints set started = %s WHERE proc_uuid = %s and job_uuid = %s, Params((1419755175.739314, '1e1ea536-e9a9-4a3b-8327-24294ece7b7f', '7ca34996-8794-4bca-b8c0-895082c78a1c')).
[DEBUG] 1419755175.743454 - Executor-0 - Statement (BEGIN, Params(()).
[DEBUG] 1419755175.744845 - Executor-0 - Executing _wait_slave_fail
[DEBUG] 1419755175.745009 - Executor-0 - Statement (SELECT server_uuid, server_address, mode, status, weight, group_id FROM servers WHERE server_uuid = %s, Params(('a6dcd87a-8e64-11e4-a8b6-0800277db20d',)).
[DEBUG] 1419755175.746878 - Executor-0 - Using connection (<mysql.connector.connection.MySQLConnection object at 0x7feeb4034a10>).
[DEBUG] 1419755175.747043 - Executor-0 - Statement (SELECT @@GLOBAL.SERVER_UUID as SERVER_UUID, Params(()).
[DEBUG] 1419755175.748313 - Executor-0 - Statement (SELECT @@GLOBAL.SERVER_ID as SERVER_ID, Params(()).
[DEBUG] 1419755175.749469 - Executor-0 - Statement (SELECT @@GLOBAL.VERSION as VERSION, Params(()).
[DEBUG] 1419755175.750980 - Executor-0 - Statement (SELECT @@GLOBAL.GTID_MODE as GTID_MODE, Params(()).
[DEBUG] 1419755175.752977 - Executor-0 - Statement (SELECT @@GLOBAL.LOG_BIN as LOG_BIN, Params(()).
[DEBUG] 1419755175.754329 - Executor-0 - Statement (SELECT @@GLOBAL.READ_ONLY as READ_ONLY, Params(()).
[DEBUG] 1419755175.755548 - Executor-0 - Connected to server with uuid (a6dcd87a-8e64-11e4-a8b6-0800277db20d), server_id (11), version (5.6.22-log), gtid (True), binlog (True), read_only (False).
[DEBUG] 1419755175.755680 - Executor-0 - Start executing function: stop_slave((<mysql.fabric.server.MySQLServer object at 0x7feeb4036a10>,), {'wait': True}).
[DEBUG] 1419755175.755796 - Executor-0 - Statement (STOP SLAVE , Params(()).
[DEBUG] 1419755175.757165 - Executor-0 - Start executing function: wait_for_slave_thread((<mysql.fabric.server.MySQLServer object at 0x7feeb4036a10>,), {'wait_for_running': False, 'threads': (), 'timeout': None}).
[DEBUG] 1419755175.757296 - Executor-0 - Start executing function: get_slave_status((<mysql.fabric.server.MySQLServer object at 0x7feeb4036a10>,), {}).
[DEBUG] 1419755175.757408 - Executor-0 - Statement (SHOW SLAVE STATUS, Params(()).
[DEBUG] 1419755175.761551 - Executor-0 - Finish executing function: get_slave_status.
[DEBUG] 1419755175.761757 - Executor-0 - Start executing function: get_slave_status((<mysql.fabric.server.MySQLServer object at 0x7feeb4036a10>,), {}).
[DEBUG] 1419755175.761918 - Executor-0 - Statement (SHOW SLAVE STATUS, Params(()).
[DEBUG] 1419755175.767656 - Executor-0 - Error executing function: get_slave_status.
[DEBUG] 1419755175.767809 - Executor-0 - Error executing function: wait_for_slave_thread.
[DEBUG] 1419755175.767915 - Executor-0 - Error executing function: stop_slave.
[WARNING] 1419755175.768021 - Executor-0 - Error trying to process transactions in the relay log for candidate (<server(uuid=a6dcd87a-8e64-11e4-a8b6-0800277db20d, address=12.0.0.11, mode=READ_ONLY, status=SECONDARY>): Command (SHOW SLAVE STATUS, ()) failed accessing (12.0.0.11:3306). 2013: Lost connection to MySQL server during query..
[DEBUG] 1419755175.768150 - Executor-0 - Triggering event <mysql.fabric.events.Event object at 0x2098bd0> in handler <mysql.fabric.events.Handler object at 0x193dc50>
[DEBUG] 1419755175.768270 - Executor-0 - Triggering event <mysql.fabric.events.Event object at 0x2098bd0>
[DEBUG] 1419755175.768675 - Executor-0 - Created job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, b0669c36-a16c-4287-a6e5-baef82cc2ff1, mysql.fabric.services.highavailability._change_to_candidate, Success).
[DEBUG] 1419755175.768861 - Executor-0 - Disconnecting from server with uuid (a6dcd87a-8e64-11e4-a8b6-0800277db20d), server_id (11), version (5.6.22-log), gtid (True), binlog (True), read_only (False).
[DEBUG] 1419755175.769234 - Executor-0 - Statement (INSERT INTO checkpoints(proc_uuid, lockable_objects, job_uuid, sequence, action_fqn, param_args, param_kwargs) VALUES (%s, %s, %s, %s, %s, %s, %s), Params(('1e1ea536-e9a9-4a3b-8327-24294ece7b7f', "c__builtin__\nset\np0\n((lp1\nS'lock'\np2\natp3\nRp4\n.", 'b0669c36-a16c-4287-a6e5-baef82cc2ff1', 3, 'mysql.fabric.services.highavailability._change_to_candidate', "(S'test'\np0\nS'a6dcd87a-8e64-11e4-a8b6-0800277db20d'\np1\ntp2\n.", '(dp0\n.')).
[DEBUG] 1419755175.771503 - Executor-0 - Statement (UPDATE checkpoints set finished = %s WHERE proc_uuid = %s and job_uuid = %s, Params((1419755175.771446, '1e1ea536-e9a9-4a3b-8327-24294ece7b7f', '7ca34996-8794-4bca-b8c0-895082c78a1c')).
[DEBUG] 1419755175.772367 - Executor-0 - Statement (COMMIT, Params(()).
[DEBUG] 1419755175.774492 - Executor-0 - Complete job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, 7ca34996-8794-4bca-b8c0-895082c78a1c, mysql.fabric.services.highavailability._wait_slave_fail, Success).
[DEBUG] 1419755175.774724 - Executor-0 - Reading next job from queue, found <Job object: uuid=b0669c36-a16c-4287-a6e5-baef82cc2ff1, status=[{'diagnosis': '', 'state': 3, 'when': 1419755175.768656, 'description': 'Triggered by <mysql.fabric.events.Event object at 0x2098bd0>.', 'success': 2}]>.
[DEBUG] 1419755175.774872 - Executor-0 - Processing job (1e1ea536-e9a9-4a3b-8327-24294ece7b7f, b0669c36-a16c-4287-a6e5-baef82cc2ff1, mysql.fabric.services.highavailability._change_to_candidate, Success).
[DEBUG] 1419755175.775198 - Executor-0 - Statement (UPDATE checkpoints set started = %s WHERE proc_uuid = %s and job_uuid = %s, Params((1419755175.774975, '1e1ea536-e9a9-4a3b-8327-24294ece7b7f', 'b0669c36-a16c-4287-a6e5-baef82cc2ff1')).
[DEBUG] 1419755175.777226 - Executor-0 - Statement (BEGIN, Params(()).
[DEBUG] 1419755175.777733 - Executor-0 - Executing _change_to_candidate
[DEBUG] 1419755175.777891 - Executor-0 - Statement (SELECT server_uuid, server_address, mode, status, weight, group_id FROM servers WHERE server_uuid = %s, Params(('a6dcd87a-8e64-11e4-a8b6-0800277db20d',)).
[DEBUG] 1419755175.779147 - Executor-0 - Statement (UPDATE servers SET mode = %s WHERE server_uuid = %s, Params((3, 'a6dcd87a-8e64-11e4-a8b6-0800277db20d')).
[DEBUG] 1419755175.779905 - Executor-0 - Statement (UPDATE servers SET status = %s WHERE server_uuid = %s, Params((3, 'a6dcd87a-8e64-11e4-a8b6-0800277db20d')).
[DEBUG] 1419755229.928057 - Thread-1 - purged 7 expired clients