galera.galera_gra_log 'innodb'           w4 [ fail ]

        Test ended at 2020-04-10 15:07:51

CURRENT_TEST: galera.galera_gra_log

Server [mysqld.2 - pid: 17845, winpid: 17845, exit: 256] failed during test run

Server log from this test:

----------SERVER LOG START-----------

2020-04-10 15:07:48 2 [ERROR] Slave SQL: Error 'Table 't1' already exists' on query. Default database: 'test'. Query: 'CREATE TABLE t1 (f1 INTEGER)', Internal MariaDB error code: 1050

2020-04-10 15:07:48 2 [Warning] WSREP: Event 1 Query apply failed: 1, seqno 109

2020-04-10 15:07:48 0 [Note] WSREP: Member 1(jw-xenial-1604-paiuhs) initiates vote on e80ccc91-7b3c-11ea-947c-4f2fa0a83401:109,83d3ac64c660f550:  Table 't1' already exists, Error_code: 1050;

2020-04-10 15:07:48 0 [Note] WSREP: Votes over e80ccc91-7b3c-11ea-947c-4f2fa0a83401:109:

   83d3ac64c660f550:   1/2

Waiting for more votes.

2020-04-10 15:07:48 0 [Note] WSREP: Member 0(jw-xenial-1604-paiuhs) responds to vote on e80ccc91-7b3c-11ea-947c-4f2fa0a83401:109,0000000000000000: Success

2020-04-10 15:07:48 0 [Note] WSREP: Votes over e80ccc91-7b3c-11ea-947c-4f2fa0a83401:109:

   0000000000000000:   1/2

   83d3ac64c660f550:   1/2

Winner: 0000000000000000

2020-04-10 15:07:48 2 [ERROR] WSREP: Inconsistency detected: Inconsistent by consensus on e80ccc91-7b3c-11ea-947c-4f2fa0a83401:109

	 at galera/src/replicator_smm.cpp:process_apply_error():1343

2020-04-10 15:07:48 2 [Note] WSREP: Closing send monitor...

2020-04-10 15:07:48 2 [Note] WSREP: Closed send monitor.

2020-04-10 15:07:48 2 [Note] WSREP: gcomm: terminating thread

2020-04-10 15:07:48 2 [Note] WSREP: gcomm: joining thread

2020-04-10 15:07:48 2 [Note] WSREP: gcomm: closing backend

2020-04-10 15:07:49 2 [Note] WSREP: view(view_id(NON_PRIM,e80bdbc3-bf38,4) memb {

	f85dcba0-a156,0

} joined {

} left {

} partitioned {

	e80bdbc3-bf38,0

})

2020-04-10 15:07:49 2 [Note] WSREP: PC protocol downgrade 1 -> 0

2020-04-10 15:07:49 2 [Note] WSREP: view((empty))

2020-04-10 15:07:49 2 [Note] WSREP: gcomm: closed

2020-04-10 15:07:49 0 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1

2020-04-10 15:07:49 0 [Note] WSREP: Flow-control interval: [16, 16]

2020-04-10 15:07:49 0 [Note] WSREP: Received NON-PRIMARY.

2020-04-10 15:07:49 0 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 109)

2020-04-10 15:07:49 0 [Note] WSREP: New SELF-LEAVE.

2020-04-10 15:07:49 0 [Note] WSREP: Flow-control interval: [0, 0]

2020-04-10 15:07:49 0 [Note] WSREP: Received SELF-LEAVE. Closing connection.

2020-04-10 15:07:49 0 [Note] WSREP: Shifting OPEN -> CLOSED (TO: -1)

2020-04-10 15:07:49 0 [Note] WSREP: RECV thread exiting 0: Success

2020-04-10 15:07:49 2 [Note] WSREP: recv_thread() joined.

2020-04-10 15:07:49 2 [Note] WSREP: Closing replication queue.

2020-04-10 15:07:49 2 [Note] WSREP: Closing slave action queue.

2020-04-10 15:07:49 2 [Note] WSREP: ================================================

View:

  id: e80ccc91-7b3c-11ea-947c-4f2fa0a83401:-1

  status: non-primary

  protocol_version: 4

  capabilities: MULTI-MASTER, CERTIFICATION, PARALLEL_APPLYING, REPLAY, ISOLATION, PAUSE, CAUSAL_READ, INCREMENTAL_WS, UNORDERED, PREORDERED, STREAMING, NBO

  final: no

  own_index: 0

  members(1):

	0: f85dcba0-7b3c-11ea-a156-fff5783deb6c, jw-xenial-1604-paiuhs

=================================================

2020-04-10 15:07:49 2 [Note] WSREP: Non-primary view

2020-04-10 15:07:49 2 [Note] WSREP: Server status change synced -> connected

2020-04-10 15:07:49 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2020-04-10 15:07:49 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2020-04-10 15:07:49 2 [Note] WSREP: ================================================

View:

  id: e80ccc91-7b3c-11ea-947c-4f2fa0a83401:-1

  status: non-primary

  protocol_version: 4

  capabilities: MULTI-MASTER, CERTIFICATION, PARALLEL_APPLYING, REPLAY, ISOLATION, PAUSE, CAUSAL_READ, INCREMENTAL_WS, UNORDERED, PREORDERED, STREAMING, NBO

  final: yes

  own_index: -1

  members(0):

=================================================

2020-04-10 15:07:49 2 [Note] WSREP: Non-primary view

2020-04-10 15:07:49 2 [Note] WSREP: Server status change connected -> disconnected

2020-04-10 15:07:49 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2020-04-10 15:07:49 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.

2020-04-10 15:07:49 0 [Note] WSREP: Service thread queue flushed.

2020-04-10 15:07:49 2 [Note] WSREP: ####### Assign initial position for certification: 00000000-0000-0000-0000-000000000000:-1, protocol version: 5

2020-04-10 15:07:49 2 [Note] WSREP: Applier thread exiting ret: 0 thd: 2

2020-04-10 15:07:49 2 [Warning] Aborted connection 2 to db: 'unconnected' user: 'unauthenticated' host: '' (This connection closed normally without authentication)

2020-04-10 15:07:51 0 [Warning] WSREP: option --wsrep-causal-reads is deprecated

2020-04-10 15:07:51 0 [Note] /home/jenkins/workspace/10.5e-MTR-GALERA/BuildType/RelWithDebInfo/label/ubuntu-1604/bin/mysqld (mysqld 10.5.2-0-MariaDB-enterprise-log) starting as process 17846 ...

2020-04-10 15:07:51 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)

2020-04-10 15:07:51 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)

2020-04-10 15:07:51 0 [Note] mysqld: Aria engine: starting recovery

recovered pages: 0%

Table ./mysql/event.MAD has wrong LSN: (1,0xaa4b) on page: 1

 12% 24% 36% 46% 57% 68%

Got error 175 when executing record redo_index

2020-04-10 15:07:51 0 [ERROR] mysqld: Aria engine: Redo phase failed

tables to flush: 1 0

2020-04-10 15:07:51 0 [ERROR] mysqld: Aria recovery failed. Please run aria_chk -r on all Aria tables and delete all aria_log.######## files

2020-04-10 15:07:51 0 [ERROR] Plugin 'Aria' init function returned error.

2020-04-10 15:07:51 0 [ERROR] Plugin 'Aria' registration as a STORAGE ENGINE failed.

2020-04-10 15:07:51 0 [ERROR] Failed to initialize plugins.

2020-04-10 15:07:51 0 [ERROR] Aborting

----------SERVER LOG END-------------

mysqltest failed but provided no output

The result from queries just before the failure was:

< snip >

ROLLBACK/*!*/;

ROLLBACK/*!*/;

use `test`/*!*/;

SET TIMESTAMP=<TIMESTAMP>/*!*/;

SET @@session.pseudo_thread_id=<PSEUDO_THREAD_ID>/*!*/;

SET @@session.foreign_key_checks=1, @@session.sql_auto_is_null=0, @@session.unique_checks=1, @@session.autocommit=1, @@session.check_constraint_checks=1, @@session.sql_if_exists=0/*!*/;

SET @@session.sql_mode=1411383296/*!*/;

SET @@session.auto_increment_increment=1, @@session.auto_increment_offset=1/*!*/;

/*!\C latin1 *//*!*/;

SET @@session.character_set_client=8,@@session.collation_connection=8,@@session.collation_server=8/*!*/;

SET @@session.lc_time_names=0/*!*/;

SET @@session.collation_database=DEFAULT/*!*/;

CREATE TABLE t1 (f1 INTEGER)

/*!*/;

DELIMITER ;

# End of log file

ROLLBACK /* added by mysqlbinlog */;

/*!50003 SET COMPLETION_TYPE=@OLD_COMPLETION_TYPE*/;

/*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=0*/;

Killing server ...

 - saving '/var/tmp/mtr/4/log/galera.galera_gra_log-innodb/' to '/var/tmp/mtr/log/galera.galera_gra_log-innodb/'

***Warnings generated in error logs during shutdown after running tests: galera.galera_gra_log

2020-04-10 15:07:48 2 [ERROR] WSREP: Inconsistency detected: Inconsistent by consensus on e80ccc91-7b3c-11ea-947c-4f2fa0a83401:109

2020-04-10 15:07:51 0 [ERROR] mysqld: Aria engine: Redo phase failed

2020-04-10 15:07:51 0 [ERROR] mysqld: Aria recovery failed. Please run aria_chk -r on all Aria tables and delete all aria_log.######## files

2020-04-10 15:07:51 0 [ERROR] Plugin 'Aria' init function returned error.

2020-04-10 15:07:51 0 [ERROR] Plugin 'Aria' registration as a STORAGE ENGINE failed.

2020-04-10 15:07:51 0 [ERROR] Failed to initialize plugins.

2020-04-10 15:07:51 0 [ERROR] Aborting