Details
-
Bug
-
Status: Closed (View Workflow)
-
Major
-
Resolution: Cannot Reproduce
-
11.4.5
-
None
-
MariaDB Server 11.4.5 on Ubuntu 22.04.5
-
Not for Release Notes
Description
I've got this WSREP bug in error log. Attached is table structure mentioned in this log.
250423 12:47:02 [ERROR] /usr/sbin/mariadbd got signal 11 ;
Sorry, we probably made a mistake, and this is a bug.
Your assistance in bug reporting will enable us to fix this for the next release.
To report this bug, see https://mariadb.com/kb/en/reporting-bugs about how to report
a bug on https://jira.mariadb.org/.
Please include the information from the server start above, to the end of the
information below.
Server version: 11.4.5-MariaDB-ubu2204-log source revision: 0771110266ff5c04216af4bf1243c65f8c67ccf4
WSREP: Suppressing further logging
WSREP: Shutting down network communications
The information page at https://mariadb.com/kb/en/how-to-produce-a-full-stack-trace-for-mariadbd/
contains instructions to obtain a better version of the backtrace below.
Following these instructions will help MariaDB developers provide a fix quicker.
Attempting backtrace. Include this in the bug report.
(note: Retrieving this information may fail)
Thread pointer: 0x74ef18000c68
stack_bottom = 0x7502d86fc000 thread_stack 0x80000
2025-04-23 12:47:02 0 [Note] /usr/sbin/mariadbd (initiated by: unknown): Normal shutdown
2025-04-23 12:47:02 0 [Note] WSREP: Shutdown replication
2025-04-23 12:47:02 0 [Note] WSREP: Server status change synced -> disconnecting
2025-04-23 12:47:02 0 [Note] WSREP: Closing send monitor...
2025-04-23 12:47:02 0 [Note] WSREP: Closed send monitor.
2025-04-23 12:47:02 0 [Note] WSREP: gcomm: terminating thread
2025-04-23 12:47:02 0 [Note] WSREP: gcomm: joining thread
2025-04-23 12:47:02 0 [Note] WSREP: gcomm: closing backend
2025-04-23 12:47:02 0 [Note] WSREP: (716b5b03-aa4f, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: tcp://192.168.200.202:4567 tcp://192.168.200.203:4567
/usr/sbin/mariadbd(my_print_stacktrace+0x30)[0x59ee175197b0]
/usr/sbin/mariadbd(handle_fatal_signal+0x365)[0x59ee170b7df5]
2025-04-23 12:47:03 0 [Note] WSREP: Failed to establish connection: Operation aborted.
/lib/x86_64-linux-gnu/libc.so.6(+0x42520)[0x750436642520]
2025-04-23 12:47:03 0 [Note] WSREP: Failed to establish connection: Operation aborted.
2025-04-23 12:47:03 0 [Note] WSREP: (716b5b03-aa4f, 'tcp://0.0.0.0:4567') reconnecting to 7d2dfe33-8c1c (tcp://192.168.200.202:4567), attempt 0
2025-04-23 12:47:03 0 [Note] WSREP: (716b5b03-aa4f, 'tcp://0.0.0.0:4567') reconnecting to 641105ac-a22a (tcp://192.168.200.203:4567), attempt 0
/usr/sbin/mariadbd(+0xee1dda)[0x59ee174a8dda]
/usr/sbin/mariadbd(+0xee6d94)[0x59ee174add94]
/usr/sbin/mariadbd(+0xe7d146)[0x59ee17444146]
/usr/sbin/mariadbd(+0xe222ef)[0x59ee173e92ef]
/usr/sbin/mariadbd(+0xe2c5f7)[0x59ee173f35f7]
/usr/sbin/mariadbd(+0xe4d4a7)[0x59ee174144a7]
/usr/sbin/mariadbd(+0xe4dc01)[0x59ee17414c01]
/usr/sbin/mariadbd(+0xe37c32)[0x59ee173fec32]
/usr/sbin/mariadbd(+0xda43fb)[0x59ee1736b3fb]
/usr/sbin/mariadbd(ZN7handler13ha_update_rowEPKhS1+0x34a)[0x59ee170cec9a]
/usr/sbin/mariadbd(_Z12write_recordP3THDP5TABLEP12st_copy_infoP13select_result+0xb37)[0x59ee16d71267]
/usr/sbin/mariadbd(_Z12mysql_insertP3THDP10TABLE_LISTR4ListI4ItemERS3_IS5_ES6_S6_15enum_duplicatesbP13select_result+0xe3b)[0x59ee16d772eb]
/usr/sbin/mariadbd(_Z21mysql_execute_commandP3THDb+0x1729)[0x59ee16dc07f9]
/usr/sbin/mariadbd(_Z11mysql_parseP3THDPcjP12Parser_state+0x1b1)[0x59ee16dcaaf1]
/usr/sbin/mariadbd(+0x80c261)[0x59ee16dd3261]
/usr/sbin/mariadbd(_Z16dispatch_command19enum_server_commandP3THDPcjb+0x2002)[0x59ee16dbd7f2]
/usr/sbin/mariadbd(_Z10do_commandP3THDb+0x175)[0x59ee16dbdf55]
/usr/sbin/mariadbd(_Z24do_handle_one_connectionP7CONNECTb+0x645)[0x59ee16f3be35]
/usr/sbin/mariadbd(handle_one_connection+0xf0)[0x59ee16f3c390]
/usr/sbin/mariadbd(+0xd0fe51)[0x59ee172d6e51]
/lib/x86_64-linux-gnu/libc.so.6(+0x94ac3)[0x750436694ac3]
/lib/x86_64-linux-gnu/libc.so.6(+0x126850)[0x750436726850]
Connection ID (thread ID): 2010786
Status: NOT_KILLED
Query (0x74ef18010e30): INSERT INTO gps_current_location (vehicle_id, compcode, latitude, longitude, location, speed, direction, engine_status, gps_time) VALUES ('THGNBKV53','nakhonborrikarn',8.221795,99.871275,ST_GeomFromText('POINT(99.871275 8.221795)'),41,26,1,'2025-04-23 19:47:00'),('THGNBKV25','nakhonborrikarn',8.040501,99.231676,ST_GeomFromText('POINT(99.231676 8.040501)'),39,251,1,'2025-04-23 19:46:50'),('THGNBKV10','nakhonborrikarn',6.999713,100.489645,ST_GeomFromText('POINT(100.489645 6.999713)'),0,210,1,'2025-04-23 19:46:21'),('THGNBKV66','nakhonborrikarn',8.379004,99.969486,ST_GeomFromText('POINT(99.969486 8.379004)'),0,67,0,'2025-04-23 19:46:11'),('THGNBKV9','nakhonborrikarn',8.37949,99.969265,ST_GeomFromText('POINT(99.969265 8.37949)'),0,247,0,'2025-04-23 19:46:22'),('THGNBKV58','nakhonborrikarn',7.823081,100.356991,ST_GeomFromText('POINT(100.356991 7.823081)'),91,347,1,'2025-04-23 19:46:56'),('THGNBKV60','nakhonborrikarn',8.379512,99.969318,ST_GeomFromText('POINT(99.969318 8.379512)'),0,68,0,'2025-04-23 19:46:30'),('THGNBKV43','nakhonborrikarn',8.061095,100.201006,ST_GeomFromText('POINT(100.201006 8.061095)'),60,274,1,'2025-04-23 19:47:00'),('THGNBKV18','nakhonborrikarn',8.434708,99.952296,ST_GeomFromText('POINT(99.952296 8.434708)'),0,347,1,'2025-04-23 19:46:54'),('THGNBKV40','nakhonborrikarn',8.246454,98.299333,ST_GeomFromText('POINT(98.299333 8.246454)'),29,225,1,'2025-04-23 19:46:49'),('THGNBKV28','nakhonborrikarn',8.025118,99.106681,ST_GeomFromText('POINT(99.106681 8.025118)'),82,120,1,'2025-04-23 19:46:46'),('THGNBKV3','nakhonborrikarn',8.379505,99.969453,ST_GeomFromText('POINT(99.969453 8.379505)'),0,278,0,'2025-04-23 19:46:35'),('THGNBKV45','nakhonborrikarn',6.993318,100.481933,ST_GeomFromText('POINT(100.481933 6.993318)'),0,106,0,'2025-04-23 19:46:52'),('THGNBKV2','nakhonborrikarn',6.993313,100.481928,ST_GeomFromText('POINT(100.481928 6.993313)'),0,113,0,'2025-04-23 19:46:59'),('THGNBKV54','nakhonborrikarn',7.895171,98.402508,ST_GeomFromText('POINT(98.402508 7.895171)'),28,216,1,'2025-04-23 19:46:56'),('THGNBKV16','nakhonborrikarn',8.379428,99.96946,ST_GeomFromText('POINT(99.96946 8.379428)'),0,75,0,'2025-04-23 19:46:04'),('THGNBKV55','nakhonborrikarn',8.379071,99.969491,ST_GeomFromText('POINT(99.969491 8.379071)'),0,342,0,'2025-04-23 19:46:04'),('THGNBKV34','nakhonborrikarn',7.877238,98.381265,ST_GeomFromText('POINT(98.381265 7.877238)'),0,85,0,'2025-04-23 19:43:50') ON DUPLICATE KEY UPDATE latitude=VALUE(latitude),longitude=VALUE(longitude),location=VALUE(location),speed=VALUE(speed), direction=VALUE(direction),engine_status=VALUE(engine_status),gps_time=VALUE(gps_time)
Optimizer switch: index_merge=on,index_merge_union=on,index_merge_sort_union=on,index_merge_intersection=on,index_merge_sort_intersection=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,hash_join_cardinality=on,cset_narrowing=off,sargable_casefold=on
Writing a core file...
Working directory at /var/lib/mysql
Resource Limits (excludes unlimited resources):
Limit Soft Limit Hard Limit Units
Max stack size 8388608 unlimited bytes
Max core file size 0 unlimited bytes
Max processes 1029864 1029864 processes
Max open files 32768 32768 files
Max locked memory 524288 524288 bytes
Max pending signals 1029864 1029864 signals
Max msgqueue size 819200 819200 bytes
Max nice priority 0 0
Max realtime priority 0 0
Core pattern: core
Kernel version: Linux version 6.8.4-3-pve (build@proxmox) (gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP PREEMPT_DYNAMIC PMX 6.8.4-3 (2024-05-02T11:55Z)
2025-04-23 12:47:27 0 [Note] Starting MariaDB 11.4.5-MariaDB-ubu2204-log source revision 0771110266ff5c04216af4bf1243c65f8c67ccf4 server_uid U9B87SzkssVZOH8/4CecJKC9xQc= as process 5687
2025-04-23 12:47:27 0 [Note] WSREP: Loading provider /usr/lib/galera/libgalera_smm.so initial position: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800
2025-04-23 12:47:27 0 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/galera/libgalera_smm.so'
2025-04-23 12:47:27 0 [Note] WSREP: wsrep_load(): Galera 26.4.21(rbf2cb1c3) by Codership Oy <info@codership.com> loaded successfully.
2025-04-23 12:47:27 0 [Note] WSREP: Initializing allowlist service v1
2025-04-23 12:47:27 0 [Note] WSREP: Initializing event service v1
2025-04-23 12:47:27 0 [Note] WSREP: Resolved symbol 'wsrep_node_isolation_mode_set_v1'
2025-04-23 12:47:27 0 [Note] WSREP: Symbol 'wsrep_certify_v1' not found from provider
2025-04-23 12:47:27 0 [Note] WSREP: CRC-32C: using 64-bit x86 acceleration.
2025-04-23 12:47:27 0 [Note] WSREP: Found saved state: f0eb829e-2a75-11ef-a75d-8b6a529e652a:-1, safe_to_bootstrap: 0
2025-04-23 12:47:27 0 [Note] WSREP: GCache DEBUG: opened preamble:
Version: 2
UUID: f0eb829e-2a75-11ef-a75d-8b6a529e652a
Seqno: -1 - -1
Offset: -1
Synced: 0
2025-04-23 12:47:27 0 [Note] WSREP: Recovering GCache ring buffer: version: 2, UUID: f0eb829e-2a75-11ef-a75d-8b6a529e652a, offset: -1
2025-04-23 12:47:27 0 [Note] WSREP: GCache::RingBuffer initial scan... 0.0% (0/5368709144 bytes) complete.
2025-04-23 12:47:28 0 [Note] WSREP: GCache::RingBuffer initial scan... 100.0% (5368709144/5368709144 bytes) complete.
2025-04-23 12:47:29 0 [Note] WSREP: Recovering GCache ring buffer: found gapless sequence 1732343740-1734420800
2025-04-23 12:47:29 0 [Note] WSREP: GCache::RingBuffer unused buffers scan... 0.0% (0/5368706768 bytes) complete.
2025-04-23 12:47:29 0 [Note] WSREP: Recovering GCache ring buffer: found 4/2077065 locked buffers
2025-04-23 12:47:29 0 [Note] WSREP: Recovering GCache ring buffer: free space: 3392/5368709120
2025-04-23 12:47:29 0 [Note] WSREP: GCache::RingBuffer unused buffers scan... 100.0% (5368706768/5368706768 bytes) complete.
2025-04-23 12:47:29 0 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = 192.168.200.201; base_port = 4567; cert.log_conflicts = no; cert.optimistic_pa = yes; debug = no; evs.auto_evict = 0; evs.delay_margin = PT1S; evs.delayed_keep_period = PT30S; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /var/lib/mysql/; gcache.keep_pages_size = 0; gcache.keep_plaintext_size = 5G; gcache.mem_size = 0; gcache.name = galera.cache; gcache.page_size = 5G; gcache.recover = yes; gcache.size = 5G; gcomm.thread_prio = ; gcs.fc_debug = 0; gcs.fc_factor = 0.8; gcs.fc_limit = 160; gcs.fc_master_slave = no; gcs.fc_single_primary = no; gcs.max_packet_size = 64500; gcs.max_throttle = 0.25; gcs.recv_q_hard_limit = 9223372036854775807; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.segment = 0; g
2025-04-23 12:47:29 0 [Note] WSREP: Service thread queue flushed.
2025-04-23 12:47:29 0 [Note] WSREP: ####### Assign initial position for certification: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800, protocol version: -1
2025-04-23 12:47:29 0 [Note] WSREP: Start replication
2025-04-23 12:47:29 0 [Note] WSREP: Connecting with bootstrap option: 0
2025-04-23 12:47:29 0 [Note] WSREP: Setting GCS initial position to f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800
2025-04-23 12:47:29 0 [Note] WSREP: protonet asio version 0
2025-04-23 12:47:29 0 [Note] WSREP: Using CRC-32C for message checksums.
2025-04-23 12:47:29 0 [Note] WSREP: backend: asio
2025-04-23 12:47:29 0 [Note] WSREP: gcomm thread scheduling priority set to other:0
2025-04-23 12:47:29 0 [Note] WSREP: restore pc from disk successfully
2025-04-23 12:47:29 0 [Note] WSREP: GMCast version 0
2025-04-23 12:47:29 0 [Note] WSREP: (716b5b03-aa50, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
2025-04-23 12:47:29 0 [Note] WSREP: (716b5b03-aa50, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
2025-04-23 12:47:29 0 [Note] WSREP: EVS version 1
2025-04-23 12:47:29 0 [Note] WSREP: gcomm: connecting to group 'dbinter galera cluster', peer '192.168.200.201:,192.168.200.202:,192.168.200.203:'
2025-04-23 12:47:29 0 [Note] WSREP: (716b5b03-aa50, 'tcp://0.0.0.0:4567') Found matching local endpoint for a connection, blacklisting address tcp://192.168.200.201:4567
2025-04-23 12:47:29 0 [Note] WSREP: (716b5b03-aa50, 'tcp://0.0.0.0:4567') connection established to 7d2dfe33-8c1c tcp://192.168.200.202:4567
2025-04-23 12:47:29 0 [Note] WSREP: (716b5b03-aa50, 'tcp://0.0.0.0:4567') connection established to 641105ac-a22a tcp://192.168.200.203:4567
2025-04-23 12:47:29 0 [Note] WSREP: EVS version upgrade 0 -> 1
2025-04-23 12:47:29 0 [Note] WSREP: declaring 641105ac-a22a at tcp://192.168.200.203:4567 stable
2025-04-23 12:47:29 0 [Note] WSREP: declaring 7d2dfe33-8c1c at tcp://192.168.200.202:4567 stable
2025-04-23 12:47:29 0 [Note] WSREP: PC protocol upgrade 0 -> 1
2025-04-23 12:47:29 0 [Note] WSREP: Node 641105ac-a22a state prim
2025-04-23 12:47:29 0 [Note] WSREP: view(view_id(PRIM,641105ac-a22a,1224) memb
joined {
} left {
} partitioned {
})
2025-04-23 12:47:29 0 [Note] WSREP: save pc into disk
2025-04-23 12:47:29 0 [Note] WSREP: clear restored view
2025-04-23 12:47:30 0 [Note] WSREP: gcomm: connected
2025-04-23 12:47:30 0 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2025-04-23 12:47:30 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2025-04-23 12:47:30 0 [Note] WSREP: Opened channel 'dbinter galera cluster'
2025-04-23 12:47:30 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 3
2025-04-23 12:47:30 0 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2025-04-23 12:47:30 0 [Note] WSREP: Initializing config service v1
2025-04-23 12:47:30 0 [Note] WSREP: STATE EXCHANGE: sent state msg: 1e122d2a-2041-11f0-b854-b7818d0f4865
2025-04-23 12:47:30 0 [Note] WSREP: STATE EXCHANGE: got state msg: 1e122d2a-2041-11f0-b854-b7818d0f4865 from 0 (dbinter-113)
2025-04-23 12:47:30 0 [Note] WSREP: STATE EXCHANGE: got state msg: 1e122d2a-2041-11f0-b854-b7818d0f4865 from 2 (dbinter-112)
2025-04-23 12:47:30 1 [Note] WSREP: Starting rollbacker thread 1
2025-04-23 12:47:30 0 [Note] WSREP: Deinitializing config service v1
2025-04-23 12:47:30 2 [Note] WSREP: Starting applier thread 2
2025-04-23 12:47:30 0 [Note] WSREP: STATE EXCHANGE: got state msg: 1e122d2a-2041-11f0-b854-b7818d0f4865 from 1 (dbinter-111)
2025-04-23 12:47:30 0 [Note] WSREP: Quorum results:
version = 6,
component = PRIMARY,
conf_id = 1219,
members = 2/3 (joined/total),
act_id = 1734420849,
last_appl. = 1734420800,
protocols = 5/11/4 (gcs/repl/appl),
vote policy= 0,
group UUID = f0eb829e-2a75-11ef-a75d-8b6a529e652a
2025-04-23 12:47:30 0 [Note] WSREP: Flow-control interval: [222, 277]
2025-04-23 12:47:30 0 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 1734420850)
2025-04-23 12:47:30 2 [Note] WSREP: ####### processing CC 1734420850, local, ordered
2025-04-23 12:47:30 2 [Note] WSREP: Process first view: f0eb829e-2a75-11ef-a75d-8b6a529e652a my uuid: 716b5b03-1e1f-11f0-aa50-f3b3f1f4a1ef
2025-04-23 12:47:30 2 [Note] WSREP: Server dbinter-111 connected to cluster at position f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420850 with ID 716b5b03-1e1f-11f0-aa50-f3b3f1f4a1ef
2025-04-23 12:47:30 2 [Note] WSREP: Server status change disconnected -> connected
2025-04-23 12:47:30 2 [Note] WSREP: ####### My UUID: 716b5b03-1e1f-11f0-aa50-f3b3f1f4a1ef
2025-04-23 12:47:30 2 [Note] WSREP: Cert index reset to 00000000-0000-0000-0000-000000000000:-1 (proto: 11), state transfer needed: yes
2025-04-23 12:47:30 0 [Note] WSREP: Service thread queue flushed.
2025-04-23 12:47:30 2 [Note] WSREP: ####### Assign initial position for certification: 00000000-0000-0000-0000-000000000000:-1, protocol version: -1
2025-04-23 12:47:30 2 [Note] WSREP: State transfer required:
Group state: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420850
Local state: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800
2025-04-23 12:47:30 2 [Note] WSREP: Server status change connected -> joiner
2025-04-23 12:47:30 0 [Note] WSREP: Joiner monitor thread started to monitor
2025-04-23 12:47:30 0 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '192.168.200.201' --datadir '/var/lib/mysql/' --parent 5687 --progress 0 --binlog '/var/log/mysql/master-bin' --binlog-index '/var/log/mysql/master-bin.index' --mysqld-args --wsrep_start_position=f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800,2-111-438259323'
WSREP_SST: [INFO] rsync SST started on joiner (20250423 12:47:30.361)
WSREP_SST: [INFO] 'ss' is selected as a socket information utility. (20250423 12:47:30.546)
2025-04-23 12:47:30 2 [Note] WSREP: ####### IST uuid:f0eb829e-2a75-11ef-a75d-8b6a529e652a f: 1734420801, l: 1734420850, STRv: 3
2025-04-23 12:47:30 2 [Note] WSREP: IST receiver addr using tcp://192.168.200.201:4568
2025-04-23 12:47:30 2 [Note] WSREP: Prepared IST receiver for 1734420801-1734420850, listening at: tcp://192.168.200.201:4568
2025-04-23 12:47:30 0 [Note] WSREP: Member 1.0 (dbinter-111) requested state transfer from 'any'. Selected 2.0 (dbinter-112)(SYNCED) as donor.
2025-04-23 12:47:30 0 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 1734420853)
2025-04-23 12:47:30 2 [Note] WSREP: Requesting state transfer: success, donor: 2
2025-04-23 12:47:31 0 [Note] WSREP: 2.0 (dbinter-112): State transfer to 1.0 (dbinter-111) complete.
2025-04-23 12:47:31 0 [Note] WSREP: Member 2.0 (dbinter-112) synced with group.
WSREP_SST: [INFO] Galera co-ords from recovery: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800 2 (20250423 12:47:31.743)
WSREP_SST: [INFO] rsync IST completed on joiner (20250423 12:47:31.748)
WSREP_SST: [INFO] Joiner cleanup: rsync PID=5817, stunnel PID=0 (20250423 12:47:31.755)
WSREP_SST: [INFO] Joiner cleanup done. (20250423 12:47:32.300)
2025-04-23 12:47:32 3 [Note] WSREP: SST received
2025-04-23 12:47:32 3 [Note] WSREP: Server status change joiner -> initializing
2025-04-23 12:47:32 0 [Warning] InnoDB: innodb_open_files 102400 should not be greater than the open_files_limit 32768
2025-04-23 12:47:32 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
2025-04-23 12:47:32 0 [Note] InnoDB: Using transactional memory
2025-04-23 12:47:32 0 [Note] InnoDB: Number of transaction pools: 1
2025-04-23 12:47:32 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
2025-04-23 12:47:32 0 [Note] InnoDB: Using liburing
2025-04-23 12:47:32 0 [Note] InnoDB: Initializing buffer pool, total size = 70.000GiB, chunk size = 1.094GiB
2025-04-23 12:47:32 0 [Note] WSREP: (716b5b03-aa50, 'tcp://0.0.0.0:4567') turning message relay requesting off
2025-04-23 12:47:32 0 [Note] InnoDB: Initialized memory pressure event listener
2025-04-23 12:47:32 0 [Note] InnoDB: Completed initialization of buffer pool
2025-04-23 12:47:33 0 [Note] InnoDB: File system buffers for log disabled (block size=512 bytes)
2025-04-23 12:47:33 0 [Note] InnoDB: End of log at LSN=2184376066947
2025-04-23 12:47:33 0 [Note] InnoDB: Opened 3 undo tablespaces
2025-04-23 12:47:33 0 [Note] InnoDB: 128 rollback segments in 3 undo tablespaces are active.
2025-04-23 12:47:33 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
2025-04-23 12:47:33 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
2025-04-23 12:47:33 0 [Note] InnoDB: log sequence number 2184376066947; transaction id 3547577656
2025-04-23 12:47:33 0 [Note] Plugin 'FEEDBACK' is disabled.
2025-04-23 12:47:33 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2025-04-23 12:47:34 0 [Note] Server socket created on IP: '0.0.0.0'.
2025-04-23 12:47:34 0 [Note] mariadbd: Event Scheduler: Loaded 0 events
2025-04-23 12:47:34 0 [Note] WSREP: wsrep_init_schema_and_SR (nil)
2025-04-23 12:47:34 0 [Note] WSREP: Server initialized
2025-04-23 12:47:34 0 [Note] WSREP: Server status change initializing -> initialized
2025-04-23 12:47:34 3 [Note] WSREP: Recovered position from storage: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800
2025-04-23 12:47:34 7 [Note] WSREP: Starting applier thread 7
2025-04-23 12:47:34 8 [Note] WSREP: Starting applier thread 8
2025-04-23 12:47:34 3 [Note] WSREP: Recovered view from SST:
id: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1733428626
status: 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: -1
members(3):
0: 641105ac-1e23-11f0-a22a-dfc2338159c5, dbinter-113
1: 716b5b03-1e1f-11f0-aa4f-f3b3f1f4a1ef, dbinter-111
2: 7d2dfe33-1e22-11f0-8c1c-b76be5793822, dbinter-112
2025-04-23 12:47:34 3 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2025-04-23 12:47:34 11 [Note] WSREP: Starting applier thread 11
2025-04-23 12:47:34 14 [Note] WSREP: Recovered cluster id f0eb829e-2a75-11ef-a75d-8b6a529e652a
2025-04-23 12:47:34 3 [Note] WSREP: SST received: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800
2025-04-23 12:47:34 3 [Note] WSREP: SST succeeded for position f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800
2025-04-23 12:47:34 2 [Note] WSREP: Installed new state from SST: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420800
2025-04-23 12:47:34 17 [Note] WSREP: Starting applier thread 17
2025-04-23 12:47:34 0 [Note] WSREP: Joiner monitor thread ended with total time 4 sec
2025-04-23 12:47:34 20 [Note] WSREP: Starting applier thread 20
2025-04-23 12:47:34 13 [Note] WSREP: Starting applier thread 13
2025-04-23 12:47:34 9 [Note] WSREP: Starting applier thread 9
2025-04-23 12:47:34 2 [Note] WSREP: Receiving IST: 50 writesets, seqnos 1734420801-1734420850
2025-04-23 12:47:34 0 [Note] WSREP: ####### IST applying starts with 1734420801
2025-04-23 12:47:34 0 [Note] WSREP: ####### IST current seqno initialized to 1734420674
2025-04-23 12:47:34 12 [Note] WSREP: Starting applier thread 12
2025-04-23 12:47:34 16 [Note] WSREP: Starting applier thread 16
2025-04-23 12:47:34 0 [Note] WSREP: Receiving IST... 0.0% (0/177 events) complete.
2025-04-23 12:47:34 0 [Note] WSREP: IST preload starting at 1734420674
2025-04-23 12:47:34 19 [Note] WSREP: Starting applier thread 19
2025-04-23 12:47:34 22 [Note] WSREP: Starting applier thread 22
2025-04-23 12:47:34 10 [Note] WSREP: Starting applier thread 10
2025-04-23 12:47:34 18 [Note] WSREP: Starting applier thread 18
2025-04-23 12:47:34 6 [Note] WSREP: Starting applier thread 6
2025-04-23 12:47:34 15 [Note] WSREP: Starting applier thread 15
2025-04-23 12:47:34 0 [Note] WSREP: Service thread queue flushed.
2025-04-23 12:47:34 0 [Note] WSREP: ####### Assign initial position for certification: 00000000-0000-0000-0000-000000000000:1734420673, protocol version: 6
2025-04-23 12:47:34 25 [Note] Slave I/O thread: Start asynchronous replication to master 'repl@192.168.100.174:3306' in log 'master-bin.002182' at position 156681672
2025-04-23 12:47:34 26 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.002182' at position 156681672, relay log '/var/log/mysql/slave-relay-bin.000001' position: 4; GTID position '1-101-1057172600,2-111-438259323,121-121-87'
2025-04-23 12:47:34 0 [Note] /usr/sbin/mariadbd: ready for connections.
Version: '11.4.5-MariaDB-ubu2204-log' socket: '/run/mysqld/mysqld.sock' port: 3306 mariadb.org binary distribution
2025-04-23 12:47:34 26 [Note] WSREP: Waiting to reach ready state
2025-04-23 12:47:34 0 [Note] WSREP: REPL Protocols: 11 (6)
2025-04-23 12:47:34 0 [Note] WSREP: ####### Adjusting cert position: 1734420806 -> 1734420807
2025-04-23 12:47:34 0 [Note] WSREP: Service thread queue flushed.
2025-04-23 12:47:34 0 [Note] WSREP: Lowest cert index boundary for CC from ist: 1734420674
2025-04-23 12:47:34 0 [Note] WSREP: Min available from gcache for CC from ist: 1732343807
2025-04-23 12:47:34 9 [Note] WSREP: ================================================
View:
id: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420807
status: 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: -1
members(2):
0: 641105ac-1e23-11f0-a22a-dfc2338159c5, dbinter-113
1: 7d2dfe33-1e22-11f0-8c1c-b76be5793822, dbinter-112
=================================================
2025-04-23 12:47:34 9 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2025-04-23 12:47:34 25 [Note] Slave I/O thread: connected to master 'repl@192.168.100.174:3306',replication starts at GTID position '1-101-1057172600,2-111-438259323,121-121-87'
2025-04-23 12:47:34 0 [Note] WSREP: REPL Protocols: 11 (6)
2025-04-23 12:47:34 0 [Note] WSREP: ####### Adjusting cert position: 1734420849 -> 1734420850
2025-04-23 12:47:34 0 [Note] WSREP: Service thread queue flushed.
2025-04-23 12:47:34 0 [Note] WSREP: Lowest cert index boundary for CC from ist: 1734420674
2025-04-23 12:47:34 0 [Note] WSREP: Min available from gcache for CC from ist: 1732343883
2025-04-23 12:47:34 0 [Note] WSREP: Receiving IST... 100.0% (177/177 events) complete.
2025-04-23 12:47:34 11 [Note] WSREP: ================================================
View:
id: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420850
status: 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: 1
members(3):
0: 641105ac-1e23-11f0-a22a-dfc2338159c5, dbinter-113
1: 716b5b03-1e1f-11f0-aa50-f3b3f1f4a1ef, dbinter-111
2: 7d2dfe33-1e22-11f0-8c1c-b76be5793822, dbinter-112
=================================================
2025-04-23 12:47:34 11 [Note] WSREP: Server status change initialized -> joined
2025-04-23 12:47:34 11 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2025-04-23 12:47:34 2 [Note] WSREP: Draining apply monitors after IST up to 1734420850
2025-04-23 12:47:34 2 [Note] WSREP: IST received: f0eb829e-2a75-11ef-a75d-8b6a529e652a:1734420850
2025-04-23 12:47:34 2 [Note] WSREP: Lowest cert index boundary for CC from sst: 1734420674
2025-04-23 12:47:34 2 [Note] WSREP: Min available from gcache for CC from sst: 1732343883
2025-04-23 12:47:34 0 [Note] WSREP: 1.0 (dbinter-111): State transfer from 2.0 (dbinter-112) complete.
2025-04-23 12:47:34 0 [Note] WSREP: Shifting JOINER -> JOINED (TO: 1734420880)
2025-04-23 12:47:34 0 [Note] WSREP: Processing event queue:... 0.0% (0/29 events) complete.
2025-04-23 12:47:34 0 [Note] WSREP: Member 1.0 (dbinter-111) synced with group.
2025-04-23 12:47:34 0 [Note] WSREP: Processing event queue:... 100.0% (30/30 events) complete.
2025-04-23 12:47:34 0 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 1734420880)
2025-04-23 12:47:34 2 [Note] WSREP: Server dbinter-111 synced with group
2025-04-23 12:47:34 2 [Note] WSREP: Server status change joined -> synced
2025-04-23 12:47:34 2 [Note] WSREP: Synchronized with group, ready for connections
2025-04-23 12:47:34 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2025-04-23 12:47:34 26 [Note] WSREP: ready state reached
2025-04-23 12:47:45 0 [Note] InnoDB: Buffer pool(s) load completed at 250423 12:47:45
2025-04-23 12:48:05 1003 [Note] Start binlog_dump to slave_server(101), pos(master-bin.002659, 233236880), using_gtid(1), gtid('1-101-1057172600,2-111-438259323,121-121-87')