[MDEV-19018] Deadlock at unlocking tables in parallel replcation Created: 2019-03-22  Updated: 2019-09-10

Status: Open
Project: MariaDB Server
Component/s: Server, Storage Engine - InnoDB
Affects Version/s: 10.1.22
Fix Version/s: 10.1

Type: Bug Priority: Major
Reporter: wy Assignee: Andrei Elkin
Resolution: Unresolved Votes: 1
Labels: deadlock, innodb, parallelreplication
Environment:

debian 8.2 with binary MariaDB package


Issue Links:
Relates
relates to MDEV-20398 Slave sql thread blocked by Update_ro... Closed
relates to MDEV-14336 Deadlock at unlocking tables when loc... Open

 Description   

We have 1 master and 2 slaves in same one replcation cluster,one slave deadlock at unlocking tables stage,another one is ok.It is not possible to kill the process within bad MariaDB slave, you have to kill the whole mysql-process with kill -9.

some variables as below.

show global variables like '%version%';

+-------------------------+------------------+
| Variable_name           | Value            |
+-------------------------+------------------+
| innodb_version          | 5.6.35-80.0      |
| protocol_version        | 10               |
| slave_type_conversions  |                  |
| version                 | 10.1.22-MariaDB  |
| version_comment         | MariaDB Server   |
| version_compile_machine | x86_64           |
| version_compile_os      | Linux            |
| version_malloc_library  | bundled jemalloc |
| version_ssl_library     | YaSSL 2.4.2      |
| wsrep_patch_version     | wsrep_25.16      |
+-------------------------+------------------+
10 rows in set (0.00 sec)

show global variables like '%repl%';

+----------------------------------+-----------+
| Variable_name                    | Value     |
+----------------------------------+-----------+
| innodb_replication_delay         | 0         |
| replicate_annotate_row_events    | OFF       |
| replicate_do_db                  |           |
| replicate_do_table               |           |
| replicate_events_marked_for_skip | REPLICATE |
| replicate_ignore_db              |           |
| replicate_ignore_table           |           |
| replicate_wild_do_table          |           |
| replicate_wild_ignore_table      |           |
| wsrep_mysql_replication_bundle   | 0         |
| wsrep_replicate_myisam           | OFF       |
+----------------------------------+-----------+
11 rows in set (0.00 sec)

show global variables like '%para%';

+-------------------------------+--------------+
| Variable_name                 | Value        |
+-------------------------------+--------------+
| slave_domain_parallel_threads | 0            |
| slave_parallel_max_queued     | 131072       |
| slave_parallel_mode           | conservative |
| slave_parallel_threads        | 8            |
+-------------------------------+--------------+
4 rows in set (0.00 sec)

show global variables like '%binlog%';

+-----------------------------------------+----------------------------------------------+
| Variable_name                           | Value                                        |
+-----------------------------------------+----------------------------------------------+
| binlog_annotate_row_events              | OFF                                          |
| binlog_cache_size                       | 2097152                                      |
| binlog_checksum                         | NONE                                         |
| binlog_commit_wait_count                | 0                                            |
| binlog_commit_wait_usec                 | 100000                                       |
| binlog_direct_non_transactional_updates | OFF                                          |
| binlog_format                           | ROW                                          |
| binlog_optimize_thread_scheduling       | ON                                           |
| binlog_row_image                        | FULL                                         |
| binlog_stmt_cache_size                  | 1048576                                      |
| encrypt_binlog                          | OFF                                          |
| gtid_binlog_pos                         | 0-16413378-19559664203                       |
| gtid_binlog_state                       | 0-16533378-8252184987,0-16413378-19559664203 |
| innodb_api_enable_binlog                | OFF                                          |
| innodb_locks_unsafe_for_binlog          | OFF                                          |
| max_binlog_cache_size                   | 18446744073709547520                         |
| max_binlog_size                         | 1073741824                                   |
| max_binlog_stmt_cache_size              | 18446744073709547520                         |
| sync_binlog                             | 1                                            |
| wsrep_forced_binlog_format              | NONE                                         |
+-----------------------------------------+----------------------------------------------+
20 rows in set (0.00 sec)

operation step and log as below
1)stop slave after in error log

2019-03-22  3:11:15 140208124291840 [ERROR] Slave SQL: Connection was killed, Gtid 0-16413378-19526117602, Internal MariaDB error code: 1927
2019-03-22  3:11:15 140208124291840 [Warning] Slave: Connection was killed Error_code: 1927
2019-03-22  3:11:15 140208124291840 [ERROR] Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'mysql-bin.004093' position 153683982
2019-03-22  3:11:15 140207194102528 [Warning] Slave: Connection was killed Error_code: 1927
2019-03-22  3:11:15 140207194102528 [ERROR] Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'mysql-bin.004093' position 153683982

2)

 
show slave status\G
*************************** 1. row ***************************
               Slave_IO_State: Waiting for master to send event
                  Master_Host: 172.18.16.41
                  Master_User: repl
                  Master_Port: 3378
                Connect_Retry: 60
              Master_Log_File: mysql-bin.004094
          Read_Master_Log_Pos: 641148032
               Relay_Log_File: mysql-relay-log.012169
                Relay_Log_Pos: 153684270
        Relay_Master_Log_File: mysql-bin.004093
             Slave_IO_Running: Yes
            Slave_SQL_Running: Yes
              Replicate_Do_DB: 
          Replicate_Ignore_DB: 
           Replicate_Do_Table: 
       Replicate_Ignore_Table: 
      Replicate_Wild_Do_Table: 
  Replicate_Wild_Ignore_Table: 
                   Last_Errno: 1927
                   Last_Error: Connection was killed
                 Skip_Counter: 0
          Exec_Master_Log_Pos: 153683982
              Relay_Log_Space: 1714892968
              Until_Condition: None
               Until_Log_File: 
                Until_Log_Pos: 0
           Master_SSL_Allowed: No
           Master_SSL_CA_File: 
           Master_SSL_CA_Path: 
              Master_SSL_Cert: 
            Master_SSL_Cipher: 
               Master_SSL_Key: 
        Seconds_Behind_Master: 8376
Master_SSL_Verify_Server_Cert: No
                Last_IO_Errno: 0
                Last_IO_Error: 
               Last_SQL_Errno: 1927
               Last_SQL_Error: Connection was killed
  Replicate_Ignore_Server_Ids: 
             Master_Server_Id: 16413378
               Master_SSL_Crl: 
           Master_SSL_Crlpath: 
                   Using_Gtid: No
                  Gtid_IO_Pos: 
      Replicate_Do_Domain_Ids: 
  Replicate_Ignore_Domain_Ids: 
                Parallel_Mode: conservative
1 row in set (0.00 sec)

3)
show processlist;

+----+-----------------+---------------------+-----------------+-------------+------+-----------------------------------------------------------------------+------------------+----------+
| Id | User            | Host                | db              | Command     | Time | State                                                                 | Info             | Progress |
+----+-----------------+---------------------+-----------------+-------------+------+-----------------------------------------------------------------------+------------------+----------+
|  2 | event_scheduler | localhost           | NULL            | Daemon      |  191 | Waiting on empty queue                                                | NULL             |    0.000 |
|  4 | system user     |                     | NULL            | Connect     |  191 | Waiting for master to send event                                      | NULL             |    0.000 |
|  5 | system user     |                     | NULL            | Connect     |    0 | Waiting for prior transaction to commit                               | NULL             |    0.000 |
|  6 | system user     |                     | NULL            | Connect     |    0 | Unlocking tables                                                      | NULL             |    0.000 |
|  7 | system user     |                     | NULL            | Connect     |    0 | Unlocking tables                                                      | NULL             |    0.000 |
|  8 | system user     |                     | NULL            | Connect     |    0 | Unlocking tables                                                      | NULL             |    0.000 |
|  9 | system user     |                     | NULL            | Connect     |    0 | after apply log event                                                 | NULL             |    0.000 |
| 10 | system user     |                     | NULL            | Connect     |    0 | after apply log event                                                 | NULL             |    0.000 |
| 11 | system user     |                     | NULL            | Connect     |    0 | Update_rows_log_event::find_row(-1)                                   | NULL             |    0.000 |
| 12 | system user     |                     | NULL            | Connect     |    0 | Unlocking tables                                                      | NULL             |    0.000 |
| 13 | system user     |                     | NULL            | Connect     |  190 | Waiting for room in worker thread event queue                         | NULL             |    0.000 |

4)
show engine innodb status\G

 
*********************** 1. row **********************
  Type: InnoDB
  Name: 
Status:
=====================================
2019-03-22 04:02:13 7f7251d86b00 INNODB MONITOR OUTPUT
=====================================
Per second averages calculated from the last 21 seconds
-----------------
BACKGROUND THREAD
-----------------
srv_master_thread loops: 40531425 srv_active, 0 srv_shutdown, 150699 srv_idle
srv_master_thread log flush and writes: 40681757
----------
SEMAPHORES
----------
OS WAIT ARRAY INFO: reservation count 9447206567
OS WAIT ARRAY INFO: signal count 10437990026
Mutex spin waits 945754110162, rounds 7723699928973, OS waits 6031972365
RW-shared spins 1385469837, rounds 9119611806, OS waits 68569143
RW-excl spins 7454870242, rounds 61749566591, OS waits 235509667
Spin rounds per wait: 8.17 mutex, 6.58 RW-shared, 8.28 RW-excl
------------
TRANSACTIONS
------------
Trx id counter 72323530086
Purge done for trx's n:o < 72321663909 undo n:o < 0 state: running but idle
History list length 24
LIST OF TRANSACTIONS FOR EACH SESSION:
---TRANSACTION 0, not started
MySQL thread id 8443428, OS thread handle 0x7f7251d86b00, query id 140263150527 localhost root init
show engine innodb status
---TRANSACTION 72323529147, not started
MySQL thread id 8443412, OS thread handle 0x7f71c0432b00, query id 140263147712 172.22.50.182 li_search cleaning up
---TRANSACTION 0, not started
MySQL thread id 8442794, OS thread handle 0x7f7252db6b00, query id 140258644073 localhost root checking permissions
start slave
---TRANSACTION 72323529443, not started
MySQL thread id 8441864, OS thread handle 0x7f72b8720b00, query id 140263148600 172.22.50.4 li_search cleaning up
---TRANSACTION 72323529329, not started
MySQL thread id 8441429, OS thread handle 0x7f72565cab00, query id 140263148258 172.22.50.154 li_search cleaning up
---TRANSACTION 72323530085, not started
MySQL thread id 8441330, OS thread handle 0x7f7258e42b00, query id 140263150526 172.22.50.162 li_search cleaning up
---TRANSACTION 72323529461, not started
MySQL thread id 8440893, OS thread handle 0x7f72527a4b00, query id 140263148654 172.22.50.55 li_search cleaning up
---TRANSACTION 72323529551, not started
MySQL thread id 8440181, OS thread handle 0x7f72533c8b00, query id 140263148924 172.22.50.43 li_search cleaning up
---TRANSACTION 18981431251, not started
MySQL thread id 180, OS thread handle 0x7f84bf760b00, query id 0 Waiting for room in worker thread event queue
---TRANSACTION 72315275363, not started
MySQL thread id 173, OS thread handle 0x7f84bf716b00, query id 140238348005 Waiting for prior transaction to start commit before starting next transaction
---TRANSACTION 72315275347, not started
MySQL thread id 172, OS thread handle 0x7f8493dc2b00, query id 140238347969 Waiting for prior transaction to start commit before starting next transaction
---TRANSACTION 72315275361, not started
MySQL thread id 176, OS thread handle 0x7f8493ce4b00, query id 140238348001 Waiting for prior transaction to commit
---TRANSACTION 72315275362, not started
MySQL thread id 177, OS thread handle 0x7f8487ffdb00, query id 140238348003 Waiting for prior transaction to start commit before starting next transaction
---TRANSACTION 2310, not started
MySQL thread id 171, OS thread handle 0x7f84bf7aab00, query id 0 Waiting for master to send event
---TRANSACTION 2307, not started
MySQL thread id 1, OS thread handle 0x7f84c0994b00, query id 0 Waiting for background binlog tasks
---TRANSACTION 72315275353, ACTIVE (PREPARED) 8561 sec
3 lock struct(s), heap size 1184, 1 row lock(s), undo log entries 2
MySQL thread id 179, OS thread handle 0x7f8493c9ab00, query id 140238347980 Unlocking tables
Trx #rec lock waits 330906860 #table lock waits 0
Trx total rec lock wait time 787891 SEC
Trx total table lock wait time 0 SEC
---TRANSACTION 72315275351, ACTIVE (PREPARED) 8561 sec
3 lock struct(s), heap size 1184, 1 row lock(s), undo log entries 2
MySQL thread id 175, OS thread handle 0x7f8493d78b00, query id 140238347977 Unlocking tables
Trx #rec lock waits 331065984 #table lock waits 0
Trx total rec lock wait time 788857 SEC
Trx total table lock wait time 0 SEC
---TRANSACTION 72315275349, ACTIVE (PREPARED) 8561 sec
3 lock struct(s), heap size 1184, 1 row lock(s), undo log entries 2
MySQL thread id 178, OS thread handle 0x7f8493c50b00, query id 140238347975 Unlocking tables
Trx #rec lock waits 330986199 #table lock waits 0
Trx total rec lock wait time 789185 SEC
Trx total table lock wait time 0 SEC
---TRANSACTION 72315275348, ACTIVE (PREPARED) 8561 sec
3 lock struct(s), heap size 1184, 1 row lock(s), undo log entries 2
MySQL thread id 174, OS thread handle 0x7f8493d2eb00, query id 140238347971 Unlocking tables
Trx #rec lock waits 331122811 #table lock waits 0
Trx total rec lock wait time 789145 SEC
Trx total table lock wait time 0 SEC
--------
FILE I/O
--------
I/O thread 0 state: waiting for completed aio requests (insert buffer thread)
I/O thread 1 state: waiting for completed aio requests (log thread)
I/O thread 2 state: waiting for completed aio requests (read thread)
I/O thread 3 state: waiting for completed aio requests (read thread)
I/O thread 4 state: waiting for completed aio requests (read thread)
I/O thread 5 state: waiting for completed aio requests (read thread)
I/O thread 6 state: waiting for completed aio requests (write thread)
I/O thread 7 state: waiting for completed aio requests (write thread)
I/O thread 8 state: waiting for completed aio requests (write thread)
I/O thread 9 state: waiting for completed aio requests (write thread)
I/O thread 10 state: waiting for completed aio requests (write thread)
I/O thread 11 state: waiting for completed aio requests (write thread)
I/O thread 12 state: waiting for completed aio requests (write thread)
I/O thread 13 state: waiting for completed aio requests (write thread)
Pending normal aio reads: 0 [0, 0, 0, 0] , aio writes: 0 [0, 0, 0, 0, 0, 0, 0, 0] ,
 ibuf aio reads: 0, log i/o's: 0, sync i/o's: 0
Pending flushes (fsync) log: 0; buffer pool: 0
4968882 OS file reads, 38303692643 OS file writes, 708650231 OS fsyncs
0.00 reads/s, 0 avg bytes/read, 4.38 writes/s, 4.38 fsyncs/s
-------------------------------------
INSERT BUFFER AND ADAPTIVE HASH INDEX
-------------------------------------
Ibuf: size 1, free list len 0, seg size 2, 799783 merges
merged operations:
 insert 292912, delete mark 507066, delete 25
discarded operations:
 insert 0, delete mark 0, delete 0
1397.41 hash searches/s, 19.00 non-hash searches/s
---
LOG
---
Log sequence number 17984575687814
Log flushed up to   17984575687814
Pages flushed up to 17984575687794
Last checkpoint at  17984575687794
Max checkpoint age    6955245159
Checkpoint age target 6737893748
Modified age          20
Checkpoint age        20
0 pending log writes, 0 pending chkp writes
35500575835 log i/o's done, 2.48 log i/o's/second
----------------------
BUFFER POOL AND MEMORY
----------------------
Total memory allocated 71974256640; in additional pool allocated 0
Total memory allocated by read views 2064
Internal hash tables (constant factor + variable factor)
    Adaptive hash index 5993782880      (1211076088 + 4782706792)
    Page hash           8851208 (buffer pool 0 only)
    Dictionary cache    285621838       (283213232 + 2408606)
    File system         1041888         (812272 + 229616)
    Lock system         170009104       (169998968 + 10136)
    Recovery system     0       (0 + 0)
Dictionary memory allocated 2408606
Buffer pool size        4194296
Buffer pool size, bytes 68719345664
Free buffers            8193
Database pages          3894190
Old database pages      1437339
Modified db pages       1
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 188142885, not young 50188703
5.24 youngs/s, 0.00 non-youngs/s
Pages read 4779703, created 10188373, written 2737775498
0.00 reads/s, 0.00 creates/s, 0.95 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 2 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 3894190, unzip_LRU len: 0
I/O sum[400]:cur[0], unzip sum[0]:cur[0]
----------------------
INDIVIDUAL BUFFER POOL INFO
----------------------
---BUFFER POOL 0
Buffer pool size        524287
Buffer pool size, bytes 8589918208
Free buffers            1024
Database pages          486637
Old database pages      179617
Modified db pages       1
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 22730999, not young 6402634
0.52 youngs/s, 0.00 non-youngs/s
Pages read 598621, created 1274125, written 343294511
0.00 reads/s, 0.00 creates/s, 0.95 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 2 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 486637, unzip_LRU len: 0
I/O sum[50]:cur[0], unzip sum[0]:cur[0]
---BUFFER POOL 1
Buffer pool size        524287
Buffer pool size, bytes 8589918208
Free buffers            1024
Database pages          486841
Old database pages      179692
Modified db pages       0
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 24875356, not young 6265219
0.71 youngs/s, 0.00 non-youngs/s
Pages read 594823, created 1262517, written 338885016
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 3 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 486841, unzip_LRU len: 0
I/O sum[50]:cur[0], unzip sum[0]:cur[0]
---BUFFER POOL 2
Buffer pool size        524287
Buffer pool size, bytes 8589918208
Free buffers            1024
Database pages          486711
Old database pages      179644
Modified db pages       0
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 21215244, not young 6020941
0.81 youngs/s, 0.00 non-youngs/s
Pages read 582819, created 1275871, written 341678374
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 3 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 486711, unzip_LRU len: 0
I/O sum[50]:cur[0], unzip sum[0]:cur[0]
---BUFFER POOL 3
Buffer pool size        524287
Buffer pool size, bytes 8589918208
Free buffers            1024
Database pages          486880
Old database pages      179707
Modified db pages       0
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 22196336, not young 6341332
0.57 youngs/s, 0.00 non-youngs/s
Pages read 596546, created 1274963, written 345140893
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 2 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 486880, unzip_LRU len: 0
I/O sum[50]:cur[0], unzip sum[0]:cur[0]
---BUFFER POOL 4
Buffer pool size        524287
Buffer pool size, bytes 8589918208
Free buffers            1024
Database pages          486998
Old database pages      179750
Modified db pages       0
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 24771076, not young 6270540
0.76 youngs/s, 0.00 non-youngs/s
Pages read 592251, created 1271903, written 344117527
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 3 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 486998, unzip_LRU len: 0
I/O sum[50]:cur[0], unzip sum[0]:cur[0]
---BUFFER POOL 5
Buffer pool size        524287
Buffer pool size, bytes 8589918208
Free buffers            1025
Database pages          486733
Old database pages      179652
Modified db pages       0
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 22241102, not young 6352619
0.67 youngs/s, 0.00 non-youngs/s
Pages read 609116, created 1277639, written 344031490
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 2 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 486733, unzip_LRU len: 0
I/O sum[50]:cur[0], unzip sum[0]:cur[0]
---BUFFER POOL 6
Buffer pool size        524287
Buffer pool size, bytes 8589918208
Free buffers            1024
Database pages          486644
Old database pages      179620
Modified db pages       0
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 25346030, not young 6343041
0.57 youngs/s, 0.00 non-youngs/s
Pages read 607742, created 1277906, written 335465918
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 1 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 486644, unzip_LRU len: 0
I/O sum[50]:cur[0], unzip sum[0]:cur[0]
---BUFFER POOL 7
Buffer pool size        524287
Buffer pool size, bytes 8589918208
Free buffers            1024
Database pages          486746
Old database pages      179657
Modified db pages       0
Percent of dirty pages(LRU & free pages): 0.000
Max dirty pages percent: 75.000
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages made young 24766742, not young 6192377
0.62 youngs/s, 0.00 non-youngs/s
Pages read 597785, created 1273449, written 345161769
0.00 reads/s, 0.00 creates/s, 0.00 writes/s
Buffer pool hit rate 1000 / 1000, young-making rate 2 / 1000 not 0 / 1000
Pages read ahead 0.00/s, evicted without access 0.00/s, Random read ahead 0.00/s
LRU len: 486746, unzip_LRU len: 0
I/O sum[50]:cur[0], unzip sum[0]:cur[0]
--------------
ROW OPERATIONS
--------------
0 queries inside InnoDB, 0 queries in queue
0 read views open inside InnoDB
4 RW transactions active inside InnoDB
0 RO transactions active inside InnoDB
4 out of 1000 descriptors used
Main thread process no. 31732, id 140131931506432, state: sleeping
Number of rows inserted 1342177386, updated 18606012069, deleted 0, read 257704600451
0.00 inserts/s, 0.00 updates/s, 0.00 deletes/s, 1414.31 reads/s
Number of system rows inserted 19526117552, updated 0, deleted 19526117545, read 19526117545
0.00 inserts/s, 0.00 updates/s, 0.00 deletes/s, 0.00 reads/s
----------------------------
END OF INNODB MONITOR OUTPUT
============================
 
1 row in set (0.00 sec)



 Comments   
Comment by Andrei Elkin [ 2019-09-10 ]

There's not enough data to conclude about deadlock. Actually the following event applying

11 | system user     |                     | NULL            | Connect     |    0 | Update_rows_log_event::find_row(-1)     

may take long on tables without unique keys.

Would it be possible to clarify that now, ruochen?

If SHOW engine innodb status was contemporary to show processlist, and there's no active trx:s that are not prepared, which the long running Update_rows_log_event::find_row(-1) must be, that must indicate the Update event dealt with a non-Innodb table.
Maybe that is also possible to clarify.

Thanks!
Andrei

Comment by Andrei Elkin [ 2019-09-10 ]

Slow Update_rows_log_event on unique-key-less table cases may be duplicated by this issue.

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