[MDEV-23623] galera_sr.GCF-1051 MTR failed: trans_commit_stmt(THD*): Assertion `thd->in_active_multi_stmt_transaction() || thd->m_transaction_psi == __null' failed Created: 2020-08-28  Updated: 2020-10-28  Resolved: 2020-10-28

Status: Closed
Project: MariaDB Server
Component/s: Galera, Tests
Affects Version/s: 10.5.6
Fix Version/s: 10.4.16, 10.5.7, 10.6.0

Type: Bug Priority: Major
Reporter: Stepan Patryshev (Inactive) Assignee: Jan Lindström (Inactive)
Resolution: Fixed Votes: 0
Labels: None
Environment:

kvm-rpm-centos74-amd64-debug



 Description   

galera_sr.GCF-1051 failed on BB 10.5:
"mariadbd: /home/buildbot/buildbot/padding_for_CPACK_RPM_BUILD_SOURCE_DIRS_PREFIX/mariadb-10.5.6/sql/transaction.cc:503: bool trans_commit_stmt(THD*): Assertion `thd->in_active_multi_stmt_transaction() || thd->m_transaction_psi == __null' failed.".
It seems to be a sporadic issue.
This bug was already fixed in MDEV-21988.

stdio.log:

10.5.6 ee61c8c0b762840ab5badaa9d6b3a24b1c2ee8c7

galera_sr.GCF-1051 'innodb'              w2 [ fail ]
        Test ended at 2020-08-21 23:16:06
 
CURRENT_TEST: galera_sr.GCF-1051
mysqltest: At line 29: query 'SELECT COUNT(*) = 0 FROM t1' failed: 2013: Lost connection to MySQL server during query
 
The result from queries just before the failure was:
< snip >
SET SESSION wsrep_trx_fragment_size=1;
connection node_1;
START TRANSACTION;
SAVEPOINT A;
INSERT INTO t1 VALUES (1);
ROLLBACK TO SAVEPOINT A;
COMMIT;
connection node_1;
SELECT COUNT(*) = 0 FROM t1;
COUNT(*) = 0
1
SELECT COUNT(*) = 0 FROM mysql.wsrep_streaming_log;
COUNT(*) = 0
1
connection node_2;
SELECT COUNT(*) = 0 FROM t1;
 
More results from queries before failure can be found in /dev/shm/var/2/log/GCF-1051.log
 
 
Server [mysqld.2 - pid: 8092, winpid: 8092, exit: 256] failed during test run
Server log from this test:
----------SERVER LOG START-----------
mariadbd: /home/buildbot/buildbot/padding_for_CPACK_RPM_BUILD_SOURCE_DIRS_PREFIX/mariadb-10.5.6/sql/transaction.cc:503: bool trans_commit_stmt(THD*): Assertion `thd->in_active_multi_stmt_transaction() || thd->m_transaction_psi == __null' failed.
200821 23:15:45 [ERROR] mysqld got signal 6 ;
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.5.6-MariaDB-debug-log
key_buffer_size=1048576
read_buffer_size=131072
max_used_connections=2
max_threads=153
thread_count=5
It is possible that mysqld could use up to 
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 63737 K  bytes of memory
Hope that's ok; if not, decrease some variables in the equation.
 
Thread pointer: 0x55a36267c088
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 = 0x7fd728521c48 thread_stack 0x49000
/usr/sbin/mariadbd(my_print_stacktrace+0x2b)[0x55a358f36c0b]
/usr/sbin/mariadbd(handle_fatal_signal+0x308)[0x55a3587dcc62]
addr2line: Dwarf Error: Offset (17530251) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (2550293559) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (449586242) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6516329) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 6516329.
addr2line: Dwarf Error: Offset (1295892) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 1295892.
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 6514020.
addr2line: Dwarf Error: Offset (27591073) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (547102867) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (566436277) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Could not find abbrev number 149.
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (2197891747) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (2416014755) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (363181825) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628147) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (7629154) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (4076936330) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (1140879717) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (1140879717) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628147) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628135) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (3657903871) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Could not find abbrev number 160.
sigaction.c:0(__restore_rt)[0x7fd727da15e0]
:0(__GI_raise)[0x7fd7260361f7]
:0(__GI_abort)[0x7fd7260378e8]
:0(__assert_fail_base)[0x7fd72602f266]
:0(__GI___assert_fail)[0x7fd72602f312]
/usr/sbin/mariadbd(_Z17trans_commit_stmtP3THD+0x2ba)[0x55a3586ae70b]
/usr/sbin/mariadbd(_ZN14rpl_group_info25slave_close_thread_tablesEP3THD+0x5a)[0x55a35868e6fa]
/usr/sbin/mariadbd(_ZN15Query_log_event14do_apply_eventEP14rpl_group_infoPKcj+0x21d)[0x55a3589136dd]
/usr/sbin/mariadbd(_ZN15Query_log_event14do_apply_eventEP14rpl_group_info+0x16)[0x55a3589147ea]
/usr/sbin/mariadbd(_Z18wsrep_apply_eventsP3THDP14Relay_log_infoPKvm+0x4ff)[0x55a358aef05d]
/usr/sbin/mariadbd(+0xcb7901)[0x55a358ad8901]
/usr/sbin/mariadbd(_ZN27Wsrep_high_priority_service9apply_toiERKN5wsrep7ws_metaERKNS0_12const_bufferERNS0_14mutable_bufferE+0x133)[0x55a358ad9a6f]
/usr/sbin/mariadbd(_ZN5wsrep12server_state8on_applyERNS_21high_priority_serviceERKNS_9ws_handleERKNS_7ws_metaERKNS_12const_bufferE+0x82)[0x55a358fba216]
/usr/sbin/mariadbd(+0x11abae1)[0x55a358fccae1]
addr2line: Dwarf Error: Offset (17530251) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (2550293559) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (449586242) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6516329) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 6516329.
addr2line: Dwarf Error: Offset (1295892) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 1295892.
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 6514020.
addr2line: Dwarf Error: Offset (27591073) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (547102867) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (566436277) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Could not find abbrev number 149.
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (2197891747) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (2416014755) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (363181825) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628147) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (7629154) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (4076936330) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (1140879717) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (1140879717) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628147) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628135) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (3657903871) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Could not find abbrev number 160.
src/trx_handle.cpp:387(galera::TrxHandleSlave::apply(void*, wsrep_cb_status (*)(void*, wsrep_ws_handle const*, unsigned int, wsrep_buf const*, wsrep_trx_meta const*, bool*), wsrep_trx_meta const&, bool&))[0x7fd723403900]
src/replicator_smm.cpp:504(galera::ReplicatorSMM::apply_trx(void*, galera::TrxHandleSlave&))[0x7fd72343bf2a]
src/replicator_smm.cpp:2145(galera::ReplicatorSMM::process_trx(void*, boost::shared_ptr<galera::TrxHandleSlave> const&))[0x7fd723441a19]
src/gcs_action_source.cpp:63(galera::GcsActionSource::process_writeset(void*, gcs_action const&, bool&))[0x7fd72341a8f9]
src/gcs_action_source.cpp:110(galera::GcsActionSource::dispatch(void*, gcs_action const&, bool&))[0x7fd72341af3a]
src/gcs_action_source.cpp:29(galera::GcsActionSource::process(void*, bool&))[0x7fd72341b16e]
src/replicator_smm.cpp:390(galera::ReplicatorSMM::async_recv(void*))[0x7fd723441f9b]
src/wsrep_provider.cpp:263(galera_recv)[0x7fd723454a88]
/usr/sbin/mariadbd(_ZN5wsrep18wsrep_provider_v2611run_applierEPNS_21high_priority_serviceE+0xe)[0x55a358fcd4ee]
/usr/sbin/mariadbd(+0xcd017c)[0x55a358af117c]
/usr/sbin/mariadbd(_Z15start_wsrep_THDPv+0x37d)[0x55a358ae443c]
/usr/sbin/mariadbd(+0xc5132e)[0x55a358a7232e]
addr2line: Dwarf Error: Offset (17530251) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (2550293559) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (449586242) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6516329) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 6516329.
addr2line: Dwarf Error: Offset (1295892) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 1295892.
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_info size (873810).
addr2line: Dwarf Error: Unable to read alt ref 6514020.
addr2line: Dwarf Error: Offset (27591073) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (547102867) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (566436277) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Could not find abbrev number 149.
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (2197891747) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (2416014755) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (363181825) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628147) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (6514020) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (7500912) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (7629154) greater than or equal to .debug_str size (1545347).
addr2line: Dwarf Error: Offset (4076936330) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (1140879717) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (1140879717) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628147) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (7628135) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Offset (3657903871) greater than or equal to .debug_str size (4155956).
addr2line: Dwarf Error: Could not find abbrev number 160.
pthread_create.c:0(start_thread)[0x7fd727d99e25]
??:0(__clone)[0x7fd7260f934d]
 
Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0x0): (null)
Connection ID (thread ID): 2
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=on,table_elimination=on,extended_keys=on,exists_to_in=on,orderby_uses_equalities=on,condition_pushdown_for_derived=on,split_materialized=on,condition_pushdown_for_subquery=on,rowid_filter=on,condition_pushdown_from_having=on,not_null_range_scan=off
 
The manual page at https://mariadb.com/kb/en/how-to-produce-a-full-stack-trace-for-mysqld/ 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: 
 
Writing a core file...
Working directory at /dev/shm/var/2/mysqld.2/data
Resource Limits:
Limit                     Soft Limit           Hard Limit           Units     
Max cpu time              unlimited            unlimited            seconds   
Max file size             unlimited            unlimited            bytes     
Max data size             unlimited            unlimited            bytes     
Max stack size            8388608              unlimited            bytes     
Max core file size        unlimited            unlimited            bytes     
Max resident set          unlimited            unlimited            bytes     
Max processes             4096                 23142                processes 
Max open files            1024                 1024                 files     
Max locked memory         65536                65536                bytes     
Max address space         unlimited            unlimited            bytes     
Max file locks            unlimited            unlimited            locks     
Max pending signals       23142                23142                signals   
Max msgqueue size         819200               819200               bytes     
Max nice priority         0                    0                    
Max realtime priority     0                    0                    
Max realtime timeout      unlimited            unlimited            us        
Core pattern: core
 
----------SERVER LOG END-------------
 
 
 - found 'core.8094' (0/0)
 
Trying 'dbx' to get a backtrace
gdb not found, cannot get the stack trace
 
Trying 'lldb' to get a backtrace from coredump /dev/shm/var/2/log/galera_sr.GCF-1051-innodb/mysqld.2/data/core.8094
 - deleting it, already saved 0
 - skipping '/dev/shm/var/2/log/galera_sr.GCF-1051-innodb/'
 
Retrying test galera_sr.GCF-1051, attempt(2/3)...
 
worker[2] > Restart  - not started
worker[2] > Restart  - not started
***Warnings generated in error logs during shutdown after running tests: galera_sr.GCF-1008 galera_sr.GCF-1043B galera_sr.GCF-1051 galera_sr.GCF-1018
 
mariadbd: /home/buildbot/buildbot/padding_for_CPACK_RPM_BUILD_SOURCE_DIRS_PREFIX/mariadb-10.5.6/sql/transaction.cc:503: bool trans_commit_stmt(THD*): Assertion `thd->in_active_multi_stmt_transaction() || thd->m_transaction_psi == __null' failed.
Attempting backtrace. You can use the following information to find out


Generated at Thu Feb 08 09:23:47 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.