[MDEV-13098]  [ERROR] mysqld got signal 11 Created: 2017-06-15  Updated: 2017-06-26  Resolved: 2017-06-26

Status: Closed
Project: MariaDB Server
Component/s: Query Cache, Storage Engine - Federated
Affects Version/s: 10.2.6
Fix Version/s: N/A

Type: Bug Priority: Major
Reporter: ssauravy Assignee: Unassigned
Resolution: Duplicate Votes: 0
Labels: None
Environment:

CentOS 6.9


Issue Links:
Duplicate
duplicates MDEV-12725 select on federated table crashes server Closed

 Description   

We have a slave server.
I have seen the following message in the mariadb error log.

170604 16:40:01 [ERROR] 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.

To report this bug, see https://mariadb.com/kb/en/reporting-bugs

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.

Server version: 10.2.6-MariaDB-log
key_buffer_size=48318382080
read_buffer_size=1048576
max_used_connections=2172
max_threads=65537
thread_count=641
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 181468904 K bytes of memory
Hope that's ok; if not, decrease some variables in the equation.

Thread pointer: 0x7f20a4bf5a68
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 = 0x7f23a12cccc8 thread_stack 0x40000
(my_addr_resolve failure: fork)
/usr/local/mysql/bin/mysqld(my_print_stacktrace+0x29) [0xc851a9]
/usr/local/mysql/bin/mysqld(handle_fatal_signal+0x47a) [0x789e1a]
/lib64/libpthread.so.0(+0xf370) [0x7f4ef4108370]
/usr/local/mysql/bin/mysqld(QUERY_PROFILE::new_status(char const*, char const*, char const*, unsigned int)+0x77) [0x6c1677]
/usr/local/mysql/bin/mysqld(set_thd_stage_info+0xc9) [0x5bf599]
/usr/local/mysql/bin/mysqld(Query_cache::try_lock(THD*, Query_cache::Cache_try_lock_mode)+0x79) [0x5b5cc9]
/usr/local/mysql/bin/mysqld(Query_cache::insert(THD*, Query_cache_tls*, char const*, unsigned long, unsigned int)+0x56) [0x5b8806]
/usr/local/mysql/bin/mysqld(net_real_write+0x45) [0x56aee5]
/usr/local/mysql/bin/mysqld(net_flush+0x1b) [0x56b36b]
/usr/local/mysql/bin/mysqld(net_write_command+0x177) [0x56b507]
/usr/local/mysql/bin/mysqld(cli_advanced_command+0x101) [0x76ab11]
/usr/local/mysql/bin/mysqld(mysql_send_query+0x2d) [0x7652cd]
/usr/local/mysql/bin/mysqld(mysql_real_query+0x9) [0x7652e9]
/usr/local/mysql/lib/plugin/ha_federated.so(ha_federated::info(unsigned int)+0x158) [0x7f4eb021c658]
/usr/local/mysql/bin/mysqld() [0x64d444]
/usr/local/mysql/bin/mysqld() [0x657169]
/usr/local/mysql/bin/mysqld(get_all_tables(THD*, TABLE_LIST*, Item*)+0x8fd) [0x657afd]
/usr/local/mysql/bin/mysqld(get_schema_tables_result(JOIN*, enum_schema_table_state)+0x287) [0x6486c7]
/usr/local/mysql/bin/mysqld(JOIN::exec_inner()+0x675) [0x643d15]
/usr/local/mysql/bin/mysqld(JOIN::exec()+0x31) [0x6442d1]
/usr/local/mysql/bin/mysqld(mysql_select(THD*, TABLE_LIST*, unsigned int, List<Item>&, Item*, unsigned int, st_order*, st_order*, Item*, st_order*, unsigned long long, select_result*, st_select_lex_unit*, st_select_lex*)+0x150) [0x642ac0]
/usr/local/mysql/bin/mysqld(handle_select(THD*, LEX*, select_result*, unsigned long)+0x26a) [0x6445aa]
/usr/local/mysql/bin/mysqld() [0x5ead9c]
/usr/local/mysql/bin/mysqld(mysql_execute_command(THD*)+0x5ebe) [0x5f39ae]
/usr/local/mysql/bin/mysqld(mysql_parse(THD*, char*, unsigned int, Parser_state*, bool, bool)+0x2ee) [0x5f61fe]
/usr/local/mysql/bin/mysqld(dispatch_command(enum_server_command, THD*, char*, unsigned int, bool, bool)+0x23a8) [0x5f8c38]
/usr/local/mysql/bin/mysqld(do_command(THD*)+0x159) [0x5f9299]
/usr/local/mysql/bin/mysqld(tp_callback(TP_connection*)+0x7f) [0x7090cf]
/usr/local/mysql/bin/mysqld() [0x76429d]
/lib64/libpthread.so.0(+0x7dc5) [0x7f4ef4100dc5]
/lib64/libc.so.6(clone+0x6d) [0x7f4ef254d73d]

Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0x7f20a4b02f10): is an invalid pointer
Connection ID (thread ID): 12097794
Status: NOT_KILLED

Optimizer switch: index_merge=on,index_merge_union=on,index_merge_sort_union=on,index_merge_intersection=on,index_merge_sort_intersection=off,engine_condition_pushdown=off,index_condition_pushdown=on,derived_merge=on,derived_with_keys=on,firstmatch=on,loosescan=on,materialization=on,in_to_exists=on,semijoin=on,partial_match_rowid_merge=on,partial_match_table_scan=on,subquery_cache=on,mrr=off,mrr_cost_based=off,mrr_sort_keys=off,outer_join_with_cache=on,semijoin_with_cache=on,join_cache_incremental=on,join_cache_hashed=on,join_cache_bka=on,optimize_join_buffer_size=off,table_elimination=on,extended_keys=on,exists_to_in=on,orderby_uses_equalities=on,condition_pushdown_for_derived=on

...
...

170612 10:31:28 [ERROR] 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.

To report this bug, see https://mariadb.com/kb/en/reporting-bugs

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.

Server version: 10.2.6-MariaDB-log
key_buffer_size=42949672960
read_buffer_size=1048576
max_used_connections=2433
max_threads=4001
thread_count=1230
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 50200296 K bytes of memory
Hope that's ok; if not, decrease some variables in the equation.

Thread pointer: 0x7fb309102ee8
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 = 0x7fb575df5cc8 thread_stack 0x40000
(my_addr_resolve failure: fork)
/usr/local/mysql/bin/mysqld(my_print_stacktrace+0x29) [0xc851a9]
/usr/local/mysql/bin/mysqld(handle_fatal_signal+0x47a) [0x789e1a]
/lib64/libpthread.so.0(+0xf370) [0x7fdd5f0d6370]
/usr/local/mysql/bin/mysqld(QUERY_PROFILE::new_status(char const*, char const*, char const*, unsigned int)+0x77) [0x6c1677]
/usr/local/mysql/bin/mysqld(set_thd_stage_info+0xc9) [0x5bf599]
/usr/local/mysql/bin/mysqld(Query_cache::try_lock(THD*, Query_cache::Cache_try_lock_mode)+0x79) [0x5b5cc9]
/usr/local/mysql/bin/mysqld(Query_cache::insert(THD*, Query_cache_tls*, char const*, unsigned long, unsigned int)+0x56) [0x5b8806]
/usr/local/mysql/bin/mysqld(net_real_write+0x45) [0x56aee5]
/usr/local/mysql/bin/mysqld(net_flush+0x1b) [0x56b36b]
/usr/local/mysql/bin/mysqld(net_write_command+0x177) [0x56b507]
/usr/local/mysql/bin/mysqld(cli_advanced_command+0x101) [0x76ab11]
/usr/local/mysql/bin/mysqld(mysql_send_query+0x2d) [0x7652cd]
/usr/local/mysql/bin/mysqld(mysql_real_query+0x9) [0x7652e9]
/usr/local/mysql/lib/plugin/ha_federated.so(ha_federated::info(unsigned int)+0x158) [0x7fdcdb1ea658]
/usr/local/mysql/bin/mysqld() [0x64d444]
/usr/local/mysql/bin/mysqld() [0x657169]
/usr/local/mysql/bin/mysqld(get_all_tables(THD*, TABLE_LIST*, Item*)+0x8fd) [0x657afd]
/usr/local/mysql/bin/mysqld(get_schema_tables_result(JOIN*, enum_schema_table_state)+0x287) [0x6486c7]
/usr/local/mysql/bin/mysqld(JOIN::exec_inner()+0x675) [0x643d15]
/usr/local/mysql/bin/mysqld(JOIN::exec()+0x31) [0x6442d1]
/usr/local/mysql/bin/mysqld(mysql_select(THD*, TABLE_LIST*, unsigned int, List<Item>&, Item*, unsigned int, st_order*, st_order*, Item*, st_order*, unsigned long long, select_result*, st_select_lex_unit*, st_select_lex*)+0x150) [0x642ac0]
/usr/local/mysql/bin/mysqld(handle_select(THD*, LEX*, select_result*, unsigned long)+0x26a) [0x6445aa]
/usr/local/mysql/bin/mysqld() [0x5ead9c]
/usr/local/mysql/bin/mysqld(mysql_execute_command(THD*)+0x5ebe) [0x5f39ae]
/usr/local/mysql/bin/mysqld(mysql_parse(THD*, char*, unsigned int, Parser_state*, bool, bool)+0x2ee) [0x5f61fe]
/usr/local/mysql/bin/mysqld(dispatch_command(enum_server_command, THD*, char*, unsigned int, bool, bool)+0x23a8) [0x5f8c38]
/usr/local/mysql/bin/mysqld(do_command(THD*)+0x159) [0x5f9299]
/usr/local/mysql/bin/mysqld(tp_callback(TP_connection*)+0x7f) [0x7090cf]
/usr/local/mysql/bin/mysqld() [0x76429d]
/lib64/libpthread.so.0(+0x7dc5) [0x7fdd5f0cedc5]
/lib64/libc.so.6(clone+0x6d) [0x7fdd5d51b73d]

Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0x7fb309110390): is an invalid pointer
Connection ID (thread ID): 12369093
Status: NOT_KILLED

Can you tell what's wrong?



 Comments   
Comment by Elena Stepanova [ 2017-06-15 ]

There should be a few more lines after Status: NOT_KILLED in the error log, could you please paste them as well?

Comment by ssauravy [ 2017-06-16 ]

error_log~

...
2017-06-11 13:58:29 140417849784064 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction
2017-06-11 13:58:29 140417849784064 [ERROR] mysqld: Deadlock found when trying to get lock; try restarting transaction
...
2017-06-12 2:01:42 140417345513216 [Warning] Event Scheduler: [prd@%][xxxxx.event_ad_exchange] Column 'sdate' in group statement is ambiguous
2017-06-12 10:30:24 140417330603776 [Warning] Aborted connection 12364650 to db: 'xxxxx' user: 'prd' host: '192.168.2.103' (Got an error reading communication packets)
170612 10:31:28 [ERROR] 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.

To report this bug, see https://mariadb.com/kb/en/reporting-bugs

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.

Server version: 10.2.6-MariaDB-log
key_buffer_size=42949672960
read_buffer_size=1048576
max_used_connections=2433
max_threads=4001
thread_count=1230
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 50200296 K bytes of memory
Hope that's ok; if not, decrease some variables in the equation.

Thread pointer: 0x7fb309102ee8
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 = 0x7fb575df5cc8 thread_stack 0x40000
(my_addr_resolve failure: fork)
/usr/local/mysql/bin/mysqld(my_print_stacktrace+0x29) [0xc851a9]
/usr/local/mysql/bin/mysqld(handle_fatal_signal+0x47a) [0x789e1a]
/lib64/libpthread.so.0(+0xf370) [0x7fdd5f0d6370]
/usr/local/mysql/bin/mysqld(QUERY_PROFILE::new_status(char const*, char const*, char const*, unsigned int)+0x77) [0x6c1677]
/usr/local/mysql/bin/mysqld(set_thd_stage_info+0xc9) [0x5bf599]
/usr/local/mysql/bin/mysqld(Query_cache::try_lock(THD*, Query_cache::Cache_try_lock_mode)+0x79) [0x5b5cc9]
/usr/local/mysql/bin/mysqld(Query_cache::insert(THD*, Query_cache_tls*, char const*, unsigned long, unsigned int)+0x56) [0x5b8806]
/usr/local/mysql/bin/mysqld(net_real_write+0x45) [0x56aee5]
/usr/local/mysql/bin/mysqld(net_flush+0x1b) [0x56b36b]
/usr/local/mysql/bin/mysqld(net_write_command+0x177) [0x56b507]
/usr/local/mysql/bin/mysqld(cli_advanced_command+0x101) [0x76ab11]
/usr/local/mysql/bin/mysqld(mysql_send_query+0x2d) [0x7652cd]
/usr/local/mysql/bin/mysqld(mysql_real_query+0x9) [0x7652e9]
/usr/local/mysql/lib/plugin/ha_federated.so(ha_federated::info(unsigned int)+0x158) [0x7fdcdb1ea658]
/usr/local/mysql/bin/mysqld() [0x64d444]
/usr/local/mysql/bin/mysqld() [0x657169]
/usr/local/mysql/bin/mysqld(get_all_tables(THD*, TABLE_LIST*, Item*)+0x8fd) [0x657afd]
/usr/local/mysql/bin/mysqld(get_schema_tables_result(JOIN*, enum_schema_table_state)+0x287) [0x6486c7]
/usr/local/mysql/bin/mysqld(JOIN::exec_inner()+0x675) [0x643d15]
/usr/local/mysql/bin/mysqld(JOIN::exec()+0x31) [0x6442d1]
/usr/local/mysql/bin/mysqld(mysql_select(THD*, TABLE_LIST*, unsigned int, List<Item>&, Item*, unsigned int, st_order*, st_order*, Item*, st_order*, unsigned long long, select_result*, st_select_lex_unit*, st_select_lex*)+0x150) [0x642ac0]
/usr/local/mysql/bin/mysqld(handle_select(THD*, LEX*, select_result*, unsigned long)+0x26a) [0x6445aa]
/usr/local/mysql/bin/mysqld() [0x5ead9c]
/usr/local/mysql/bin/mysqld(mysql_execute_command(THD*)+0x5ebe) [0x5f39ae]
/usr/local/mysql/bin/mysqld(mysql_parse(THD*, char*, unsigned int, Parser_state*, bool, bool)+0x2ee) [0x5f61fe]
/usr/local/mysql/bin/mysqld(dispatch_command(enum_server_command, THD*, char*, unsigned int, bool, bool)+0x23a8) [0x5f8c38]
/usr/local/mysql/bin/mysqld(do_command(THD*)+0x159) [0x5f9299]
/usr/local/mysql/bin/mysqld(tp_callback(TP_connection*)+0x7f) [0x7090cf]
/usr/local/mysql/bin/mysqld() [0x76429d]
/lib64/libpthread.so.0(+0x7dc5) [0x7fdd5f0cedc5]
/lib64/libc.so.6(clone+0x6d) [0x7fdd5d51b73d]

Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0x7fb309110390): is an invalid pointer
Connection ID (thread ID): 12369093
Status: NOT_KILLED

Optimizer switch: index_merge=on,index_merge_union=on,index_merge_sort_union=on,index_merge_intersection=on,index_merge_sort_intersection=off,engine_condition_pushdown=off,index_condition_pushdown=on,derived_merge=on,derived_with_keys=on,firstmatch=on,loosescan=on,materialization=on,in_to_exists=on,semijoin=on,partial_match_rowid_merge=on,partial_match_table_scan=on,subquery_cache=on,mrr=off,mrr_cost_based=off,mrr_sort_keys=off,outer_join_with_cache=on,semijoin_with_cache=on,join_cache_incremental=on,join_cache_hashed=on,join_cache_bka=on,optimize_join_buffer_size=off,table_elimination=on,extended_keys=on,exists_to_in=on,orderby_uses_equalities=on,condition_pushdown_for_derived=on

The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
information that should help you find out what is causing the crash.

We think the query pointer is invalid, but we will try to print it anyway.
Query: SHOW TABLE STATUS FROM `xxxxx`

170612 10:31:36 mysqld_safe Number of processes running now: 0
170612 10:31:36 mysqld_safe mysqld restarted
2017-06-12 10:31:37 140060626782272 [Note] /usr/local/mysql/bin/mysqld (mysqld 10.2.6-MariaDB-log) starting as process 29831 ...
2017-06-12 10:31:39 140060626782272 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2017-06-12 10:31:39 140060626782272 [Note] InnoDB: Uses event mutexes
2017-06-12 10:31:39 140060626782272 [Note] InnoDB: Compressed tables use zlib 1.2.3
2017-06-12 10:31:39 140060626782272 [Note] InnoDB: Number of pools: 1
2017-06-12 10:31:39 140060626782272 [Note] InnoDB: Using SSE2 crc32 instructions
2017-06-12 10:31:39 140060626782272 [Note] InnoDB: Initializing buffer pool, total size = 112G, instances = 32, chunk size = 128M
2017-06-12 10:31:44 140060626782272 [Note] InnoDB: Completed initialization of buffer pool
2017-06-12 10:31:44 139885843298048 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
2017-06-12 10:31:44 140060626782272 [Note] InnoDB: Highest supported file format is Barracuda.
2017-06-12 10:31:45 140060626782272 [Note] InnoDB: Starting crash recovery from checkpoint LSN=90660363112910
2017-06-12 10:31:59 140060626782272 [Note] InnoDB: Read redo log up to LSN=90659880710144
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398068 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398068 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398079 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398079 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398095 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398095 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398097 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398097 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398098 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398098 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398099 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398099 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398118 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398119 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398119 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398122 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398123 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398126 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398126 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398128 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398129 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Transaction 246032398129 was in the XA prepared state.
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: 24 transaction(s) which must be rolled back or cleaned up in total 28 row operations to undo
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Trx id counter is 246032398592
2017-06-12 10:32:08 140060626782272 [Note] InnoDB: Starting final batch to recover 49431 pages from redo log.
2017-06-12 10:32:14 139888009058048 [Note] InnoDB: To recover: 9424 pages from log
InnoDB: Last MySQL binlog file position 0 90390232, file name /home/binlog/mysql-bin.003259
2017-06-12 10:32:33 140060626782272 [Note] InnoDB: 128 out of 128 rollback segments are active.
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Starting in background the rollback of recovered transactions
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398131, 2 rows to undo
2017-06-12 10:32:33 140060626782272 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2017-06-12 10:32:33 140060626782272 [Note] InnoDB: Creating shared tablespace for temporary tables
2017-06-12 10:32:33 140060626782272 [Note] InnoDB: Setting file '/data/db/ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398131 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398130, 3 rows to undo
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398130 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398127, 3 rows to undo
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398127 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398124, 3 rows to undo
2017-06-12 10:32:33 140060626782272 [Note] InnoDB: File '/data/db/ibtmp1' size is now 12 MB.
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398124 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398121, 4 rows to undo
2017-06-12 10:32:33 140060626782272 [Note] InnoDB: 5.7.14 started; log sequence number 90660382842638
2017-06-12 10:32:33 139885843298048 [Note] InnoDB: page_cleaner: 1000ms intended loop took 48156ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2017-06-12 10:32:33 139890557703936 [Note] InnoDB: Loading buffer pool(s) from /data/db/ib_buffer_pool
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398121 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398120, 6 rows to undo
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398120 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398117, 1 rows to undo
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398117 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398073, 3 rows to undo
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398073 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398070, 1 rows to undo
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398070 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032398014, 1 rows to undo
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032398014 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rolling back trx with id 246032397752, 1 rows to undo
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of trx with id 246032397752 completed
2017-06-12 10:32:33 139885398714112 [Note] InnoDB: Rollback of non-prepared transactions completed
2017-06-12 10:32:41 140060626782272 [Note] Plugin 'FEEDBACK' is disabled.
2017-06-12 10:32:41 140060626782272 [Note] Recovering after a crash using /home/binlog/mysql-bin
2017-06-12 10:32:42 140060626782272 [Note] Starting crash recovery...
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Starting recovery for XA transactions...
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398129 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 3 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398128 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 1 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398126 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 3 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398123 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 1 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398122 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 1 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398119 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 3 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398118 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 1 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398099 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 3 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398098 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 3 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398097 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 4 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398095 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 3 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398079 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 3 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction 246032398068 in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: Transaction contains changes to 3 rows
2017-06-12 10:32:42 140060626782272 [Note] InnoDB: 13 transactions in prepared state after recovery
2017-06-12 10:32:42 140060626782272 [Note] Found 13 prepared transaction(s) in InnoDB
2017-06-12 10:32:42 140060626782272 [Note] Crash recovery finished.
2017-06-12 10:32:42 140060626782272 [Note] Server socket created on IP: '::'.
...

Comment by ssauravy [ 2017-06-26 ]

Can you tell what's going on?

Comment by Elena Stepanova [ 2017-06-26 ]

The problem appears to be the same as described and confirmed in MDEV-12725. You can track further progress there. Thanks for the report.

Generated at Thu Feb 08 08:02:53 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.