segmentation faults from mtr rpl suite
Affects | Status | Importance | Assigned to | Milestone | ||
---|---|---|---|---|---|---|
MySQL patches by Codership | Status tracked in 5.6 | |||||
5.5 |
Fix Committed
|
High
|
Teemu Ollakka | |||
5.6 |
Fix Released
|
High
|
Teemu Ollakka | |||
Percona XtraDB Cluster moved to https://jira.percona.com/projects/PXC | Status tracked in 5.6 | |||||
5.5 |
New
|
Undecided
|
Unassigned | |||
5.6 |
Fix Released
|
Undecided
|
Unassigned |
Bug Description
Run mtr rpl tests without wsrep provider loaded, some of the rpl tests fail:
rpl.rpl_
Test ended at 2014-09-09 11:57:46
CURRENT_TEST: rpl.rpl_
Server [mysqld.2 - pid: 13456, winpid: 13456, exit: 256] failed during test run
Server log from this test:
----------SERVER LOG START-----------
2014-09-09 11:57:41 0 [Warning] The syntax '--master-
2014-09-09 11:57:41 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_
2014-09-09 11:57:41 13337 [Warning] Buffered warning: Could not increase number of max_open_files to more than 1024 (request: 4161)
2014-09-09 11:57:41 13337 [Warning] Buffered warning: Changed limits: table_cache: 431 (requested 2000)
2014-09-09 11:57:41 13337 [Note] Plugin 'FEDERATED' is disabled.
2014-09-09 11:57:41 7f7e5dbd5780 InnoDB: Warning: Using innodb_
2014-09-09 11:57:41 13337 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-09-09 11:57:41 7f7e5dbd5780 InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
2014-09-09 11:57:41 7f7e5dbd5780 InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
2014-09-09 11:57:41 13337 [Note] InnoDB: The InnoDB memory heap is disabled
2014-09-09 11:57:41 13337 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-09-09 11:57:41 13337 [Note] InnoDB: Memory barrier is not used
2014-09-09 11:57:41 13337 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-09-09 11:57:41 13337 [Note] InnoDB: Using Linux native AIO
2014-09-09 11:57:41 13337 [Note] InnoDB: Using CPU crc32 instructions
2014-09-09 11:57:41 13337 [Note] InnoDB: Initializing buffer pool, size = 256.0M
2014-09-09 11:57:41 13337 [Note] InnoDB: Completed initialization of buffer pool
2014-09-09 11:57:41 13337 [Note] InnoDB: Highest supported file format is Barracuda.
2014-09-09 11:57:42 13337 [Note] InnoDB: 128 rollback segment(s) are active.
2014-09-09 11:57:42 13337 [Note] InnoDB: Waiting for purge to start
2014-09-09 11:57:42 13337 [Note] InnoDB: 5.6.20 started; log sequence number 1625977
2014-09-09 11:57:42 13337 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: 5bec9613-
2014-09-09 11:57:42 13337 [Note] Server hostname (bind-address): '*'; port: 13001
2014-09-09 11:57:42 13337 [Note] IPv6 is available.
2014-09-09 11:57:42 13337 [Note] - '::' resolves to '::';
2014-09-09 11:57:42 13337 [Note] Server socket created on IP: '::'.
2014-09-09 11:57:42 13337 [Note] Event Scheduler: Loaded 0 events
2014-09-09 11:57:42 13337 [Note] WSREP: Read nil XID from storage engines, skipping position init
2014-09-09 11:57:42 13337 [Note] WSREP: wsrep_load(): loading provider library 'none'
2014-09-09 11:57:42 13337 [Note] /home/teemu/
Version: '5.6.20-debug-log' socket: '/home/
2014-09-09 11:57:42 13337 [Note] 'CHANGE MASTER TO executed'. Previous state master_host='', master_port= 3306, master_log_file='', master_log_pos= 4, master_bind=''. New state master_
2014-09-09 11:57:43 13337 [Warning] Storing MySQL user name or password information in the master info repository is not secure and is therefore not recommended. Please consider using the USER and PASSWORD connection options for START SLAVE; see the 'START SLAVE Syntax' in the MySQL Manual for more information.
2014-09-09 11:57:43 13337 [Note] Slave I/O thread: connected to master 'root@127.
2014-09-09 11:57:43 13337 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 4, relay log './slave-
2014-09-09 11:57:43 13337 [Note] Error reading relay log event: slave SQL thread was killed
2014-09-09 11:57:43 13337 [ERROR] Error reading packet from server: Lost connection to MySQL server during query ( server_errno=2013)
2014-09-09 11:57:43 13337 [Note] Slave I/O thread killed while reading event
2014-09-09 11:57:43 13337 [Note] Slave I/O thread exiting, read up to log 'master-
2014-09-09 11:57:43 13337 [Warning] Storing MySQL user name or password information in the master info repository is not secure and is therefore not recommended. Please consider using the USER and PASSWORD connection options for START SLAVE; see the 'START SLAVE Syntax' in the MySQL Manual for more information.
2014-09-09 11:57:43 13337 [Note] Slave I/O thread: connected to master 'root@127.
2014-09-09 11:57:43 13337 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 1588, relay log './slave-
2014-09-09 11:57:43 13337 [Note] Error reading relay log event: slave SQL thread was killed
2014-09-09 11:57:43 13337 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 11425, relay log './slave-
2014-09-09 11:57:44 13337 [Note] Crashing crash_after_apply.
SIGKILL myself
2014-09-09 11:57:44 0 [Warning] The syntax '--master-
2014-09-09 11:57:44 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_
2014-09-09 11:57:44 13457 [Warning] Buffered warning: Could not increase number of max_open_files to more than 1024 (request: 4161)
2014-09-09 11:57:44 13457 [Warning] Buffered warning: Changed limits: table_cache: 431 (requested 2000)
2014-09-09 11:57:44 13457 [Note] Plugin 'FEDERATED' is disabled.
2014-09-09 11:57:44 7fb6571da780 InnoDB: Warning: Using innodb_
2014-09-09 11:57:44 13457 [Note] InnoDB: Using atomics to ref count buffer pool pages
2014-09-09 11:57:44 7fb6571da780 InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
2014-09-09 11:57:44 7fb6571da780 InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
2014-09-09 11:57:44 13457 [Note] InnoDB: The InnoDB memory heap is disabled
2014-09-09 11:57:44 13457 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2014-09-09 11:57:44 13457 [Note] InnoDB: Memory barrier is not used
2014-09-09 11:57:44 13457 [Note] InnoDB: Compressed tables use zlib 1.2.3
2014-09-09 11:57:44 13457 [Note] InnoDB: Using Linux native AIO
2014-09-09 11:57:44 13457 [Note] InnoDB: Using CPU crc32 instructions
2014-09-09 11:57:44 13457 [Note] InnoDB: Initializing buffer pool, size = 256.0M
2014-09-09 11:57:44 13457 [Note] InnoDB: Completed initialization of buffer pool
2014-09-09 11:57:44 13457 [Note] InnoDB: Highest supported file format is Barracuda.
2014-09-09 11:57:44 13457 [Note] InnoDB: Log scan progressed past the checkpoint lsn 1625977
2014-09-09 11:57:44 13457 [Note] InnoDB: Database was not shutdown normally!
2014-09-09 11:57:44 13457 [Note] InnoDB: Starting crash recovery.
2014-09-09 11:57:44 13457 [Note] InnoDB: Reading tablespace information from the .ibd files...
2014-09-09 11:57:44 13457 [Note] InnoDB: Restoring possible half-written data pages
2014-09-09 11:57:44 13457 [Note] InnoDB: from the doublewrite buffer...
InnoDB: Doing recovery: scanned up to log sequence number 1711704
InnoDB: Transaction 1368 was in the XA prepared state.
InnoDB: Transaction 1368 was in the XA prepared state.
InnoDB: 1 transaction(s) which must be rolled back or cleaned up
InnoDB: in total 0 row operations to undo
InnoDB: Trx id counter is 1792
2014-09-09 11:57:44 13457 [Note] InnoDB: Starting an apply batch of log records to the database...
InnoDB: Progress in percent: 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99
InnoDB: Apply batch completed
InnoDB: Last MySQL binlog file position 0 6447, file name slave-bin.000001
InnoDB: Starting in background the rollback of uncommitted transactions
2014-09-09 11:57:46 7fb63d0ed700 InnoDB: Rollback of non-prepared transactions completed
2014-09-09 11:57:46 13457 [Note] InnoDB: 128 rollback segment(s) are active.
2014-09-09 11:57:46 13457 [Note] InnoDB: Waiting for purge to start
2014-09-09 11:57:46 13457 [Note] InnoDB: 5.6.20 started; log sequence number 1711704
2014-09-09 11:57:46 13457 [Note] Recovering after a crash using slave-bin
2014-09-09 11:57:46 13457 [Note] WSREP: Binlog recovery, found wsrep position 00000000-
2014-09-09 11:57:46 13457 [Note] WSREP: Binlog recovery scan stopped at Xid event 436
2014-09-09 11:57:46 13457 [Note] Starting crash recovery...
2014-09-09 11:57:46 7fb6571da780 InnoDB: Starting recovery for XA transactions...
2014-09-09 11:57:46 7fb6571da780 InnoDB: Transaction 1368 in prepared state after recovery
2014-09-09 11:57:46 7fb6571da780 InnoDB: Transaction contains changes to 2 rows
2014-09-09 11:57:46 7fb6571da780 InnoDB: 1 transactions in prepared state after recovery
2014-09-09 11:57:46 13457 [Note] Found 1 prepared transaction(s) in InnoDB
2014-09-09 11:57:46 13457 [Note] commit xid 'MySQLXid\
2014-09-09 11:57:46 13457 [Note] Crash recovery finished.
2014-09-09 11:57:46 13457 [Note] Server hostname (bind-address): '*'; port: 13001
2014-09-09 11:57:46 13457 [Note] IPv6 is available.
2014-09-09 11:57:46 13457 [Note] - '::' resolves to '::';
2014-09-09 11:57:46 13457 [Note] Server socket created on IP: '::'.
2014-09-09 11:57:46 13457 [Warning] Recovery from master pos 11623 and file master-bin.000001.
08:57:46 UTC - mysqld got signal 11 ;
This could be because you hit a bug. It is also possible that this binary
or one of the libraries it was linked against is corrupt, improperly built,
or misconfigured. This error can also be caused by malfunctioning hardware.
We will try our best to scrape up some info that will hopefully help
diagnose the problem, but since we have already crashed,
something is definitely wrong and this may fail.
key_buffer_
read_buffer_
max_used_
max_threads=151
thread_count=0
connection_count=0
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_
Hope that's ok; if not, decrease some variables in the equation.
Thread pointer: 0x3133d40
Attempting backtrace. You can use the following information to find out
where mysqld died. If you see no messages after this, something went
terribly wrong...
stack_bottom = 7fff324bfc98 thread_stack 0x40000
/home/teemu/
/home/teemu/
/lib/x86_
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/home/teemu/
/lib/x86_
/home/teemu/
Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0): Connection ID (thread ID): 0
Status: NOT_KILLED
The manual page at http://
information that should help you find out what is causing the crash.
----------SERVER LOG END-------------
mysqltest failed but provided no output
The result from queries just before the failure was:
< snip >
rpl_mixing_
-b-b-b-
INSERT INTO tt_1(trans_id, stmt_id) VALUES (7, 1);
include/
Log_name Pos Event_type Server_id End_log_pos Info
master-bin.000001 # Query # # BEGIN
master-bin.000001 # Table_map # # table_id: # (test.tt_1)
master-bin.000001 # Write_rows # # table_id: # flags: STMT_END_F
master-bin.000001 # Xid # # COMMIT /* XID */
-e-e-e-
-b-b-b-
include/
Log_name Pos Event_type Server_id End_log_pos Info
master-bin.000001 # Query # # BEGIN
master-bin.000001 # Table_map # # table_id: # (test.tt_1)
master-bin.000001 # Write_rows # # table_id: # flags: STMT_END_F
master-bin.000001 # Xid # # COMMIT /* XID */
-e-e-e-
include/
- saving '/home/
Retrying test rpl.rpl_
The stack trace shows that wsrep keys are appended, but they should not be since since wsrep provider is not loaded.
Fix: bazaar. launchpad. net/~codership/ codership- mysql/wsrep- 5.5/revision/ 4024 bazaar. launchpad. net/~codership/ codership- mysql/5. 6/revision/ 4135
http://
http://