Uploaded image for project: 'MariaDB Server'
  1. MariaDB Server
  2. MDEV-14727

innodb_fts.fulltext2 failed in buildbot with long semaphore wait

    Details

      Description

      http://buildbot.askmonty.org/buildbot/builders/bld-starfs-debug/builds/610/steps/test_1/logs/stdio

      innodb_fts.fulltext2 'xtradb'            w2 [ fail ]
              Test ended at 2017-12-15 10:22:34
       
      CURRENT_TEST: innodb_fts.fulltext2
      mysqltest: At line 228: query 'drop table t1' failed: 2013: Lost connection to MySQL server during query
       
      The result from queries just before the failure was:
      < snip >
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test'),
      ('test'),('test'),('test'),('test'),('test'),('test'),('test'),('test');
      delete from t1 limit 1;
      truncate table t1;
      insert into t1 values('ab c d');
      update t1 set a='ab c d';
      select * from t1 where match a against('ab c' in boolean mode);
      a
      select * from t1 where match a against('ab	c' in boolean mode);
      a
      drop table t1;
       
      More results from queries before failure can be found in /opt/buildbot-slave/mariadb/starfsmenn1/build/mysql-test/var/2/log/fulltext2.log
       
       
      Server [mysqld.1 - pid: 26597, winpid: 26597, exit: 256] failed during test run
      Server log from this test:
      ----------SERVER LOG START-----------
      2017-12-15 10:11:51 7f50d0dfa700 InnoDB: FTS Optimize Removing table test/t1
      2017-12-15 10:12:24 7f50d0dfa700 InnoDB: FTS Optimize Removing table test/t1
      2017-12-15 10:12:28 7f50d0dfa700 InnoDB: FTS Optimize Removing table test/t1
      InnoDB: Error: Waited for 5 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 10 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 15 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 20 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 25 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 30 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 35 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 40 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 45 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 50 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 55 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 60 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 65 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 70 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 75 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 80 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 85 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 90 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 95 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 100 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 105 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 110 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 115 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 120 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 125 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 130 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 135 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 140 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 145 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 150 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 155 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 160 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 165 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 170 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 175 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 180 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 185 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 190 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 195 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 200 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 205 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 210 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 215 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 220 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 225 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 230 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 235 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 240 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Warning: a long semaphore wait:
      --Thread 139985099409152 has waited at srv0srv.cc line 2841 for 241.00 seconds the semaphore:
      X-lock on RW-latch at 0x7f50ead13a40 '&dict_operation_lock'
      a writer (thread id 139985335802624) has reserved it in mode  exclusive
      number of readers 0, waiters flag 1, lock_word: 0
      Last time read locked in file row0purge.cc line 772
      Last time write locked in file row0mysql.cc line 4055
      Holder thread 0 file not yet reserved line 0
      InnoDB: Warning: semaphore wait:
      --Thread 139985099409152 has waited at srv0srv.cc line 2841 for 241.00 seconds the semaphore:
      X-lock on RW-latch at 0x7f50ead13a40 '&dict_operation_lock'
      a writer (thread id 139985335802624) has reserved it in mode  exclusive
      number of readers 0, waiters flag 1, lock_word: 0
      Last time read locked in file row0purge.cc line 772
      Last time write locked in file row0mysql.cc line 4055
      Holder thread 0 file not yet reserved line 0
      InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
      InnoDB: Pending reads 0, writes 0
      InnoDB: Error: Waited for 245 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 250 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
       
      =====================================
      2017-12-15 10:16:46 7f50d3fff700 INNODB MONITOR OUTPUT
      =====================================
      Per second averages calculated from the last 33 seconds
      -----------------
      BACKGROUND THREAD
      -----------------
      srv_master_thread loops: 112 srv_active, 0 srv_shutdown, 17 srv_idle
      srv_master_thread log flush and writes: 128
      ----------
      SEMAPHORES
      ----------
      ----------
      MUTEX INFO
      ----------
      Locked mutex: addr 0x7f50ece119f8 thread 139985335802624 file /opt/buildbot-slave/mariadb/starfsmenn1/build/storage/xtradb/row/row0mysql.cc line 2269
      Locked mutex: addr 0x7f50eacfe760 thread 139985130878720 file /opt/buildbot-slave/mariadb/starfsmenn1/build/storage/xtradb/srv/srv0srv.cc line 1441
      Total number of mutexes 691
      -------------
      RW-LATCH INFO
      -------------
      RW-LOCK: 0x7f50ead13a40  Waiters for the lock exist
      Locked: thread 139985335802624 file /opt/buildbot-slave/mariadb/starfsmenn1/build/storage/xtradb/row/row0mysql.cc line 4055  X-LOCK
      Total number of rw-locks 1066
      OS WAIT ARRAY INFO: reservation count 1410
      --Thread 139985099409152 has waited at srv0srv.cc line 2841 for 256.00 seconds the semaphore:
      X-lock on RW-latch at 0x7f50ead13a40 '&dict_operation_lock'
      a writer (thread id 139985335802624) has reserved it in mode  exclusive
      number of readers 0, waiters flag 1, lock_word: 0
      Last time read locked in file row0purge.cc line 772
      Last time write locked in file row0mysql.cc line 4055
      Holder thread 0 file not yet reserved line 0
      OS WAIT ARRAY INFO: signal count 1760
      Mutex spin waits 25589, rounds 91596, OS waits 811
      RW-shared spins 693, rounds 16816, OS waits 364
      RW-excl spins 25, rounds 10181, OS waits 179
      Spin rounds per wait: 3.58 mutex, 24.27 RW-shared, 407.24 RW-excl
      ------------
      TRANSACTIONS
      ------------
      Trx id counter 14470
      Purge done for trx's n:o < 14438 undo n:o < 0 state: running but idle
      History list length 44
      Total number of lock structs in row lock hash table 9
      LIST OF TRANSACTIONS FOR EACH SESSION:
      ---TRANSACTION 14435, not started
      MySQL thread id 10, OS thread handle 0x7f50e036db00, query id 5615 localhost root Unlocking tables
      drop table t1
      ---TRANSACTION 14445, ACTIVE 258 sec
      18 lock struct(s), heap size 3112, 112 row lock(s), undo log entries 97
      MySQL thread id 10, OS thread handle 0x7f50e036db00, query id 5615 localhost root Unlocking tables
      drop table t1
      Trx read view will not see trx with id >= 14448, sees < 14448
      Trx #rec lock waits 0 #table lock waits 0
      Trx total rec lock wait time 0 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 (write thread)
      I/O thread 5 state: waiting for completed aio requests (write thread)
      Pending normal aio reads: 0 [0, 0] , aio writes: 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
      1081 OS file reads, 7103 OS file writes, 4124 OS fsyncs
      0.00 reads/s, 0 avg bytes/read, 0.00 writes/s, 0.00 fsyncs/s
      -------------------------------------
      INSERT BUFFER AND ADAPTIVE HASH INDEX
      -------------------------------------
      Ibuf: size 1, free list len 0, seg size 2, 0 merges
      merged operations:
       insert 0, delete mark 0, delete 0
      discarded operations:
       insert 0, delete mark 0, delete 0
      0.00 hash searches/s, 0.00 non-hash searches/s
      ---
      LOG
      ---
      Log sequence number 9458385
      Log flushed up to   9458385
      Pages flushed up to 9458385
      Last checkpoint at  9430130
      Max checkpoint age    7782360
      Checkpoint age target 7539162
      Modified age          0
      Checkpoint age        28255
      0 pending log writes, 0 pending chkp writes
      1965 log i/o's done, 0.00 log i/o's/second
      ----------------------
      BUFFER POOL AND MEMORY
      ----------------------
      Total memory allocated 9060352; in additional pool allocated 0
      Total memory allocated by read views 384
      InnoDB: Error: Waited for 255 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 260 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 265 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: ###### Diagnostic info printed to the standard error stream
      InnoDB: Error: Waited for 270 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Warning: a long semaphore wait:
      --Thread 139985099409152 has waited at srv0srv.cc line 2841 for 272.00 seconds the semaphore:
      X-lock on RW-latch at 0x7f50ead13a40 '&dict_operation_lock'
      a writer (thread id 139985335802624) has reserved it in mode  exclusive
      number of readers 0, waiters flag 1, lock_word: 0
      Last time read locked in file row0purge.cc line 772
      Last time write locked in file row0mysql.cc line 4055
      Holder thread 0 file not yet reserved line 0
      InnoDB: Warning: semaphore wait:
      --Thread 139985099409152 has waited at srv0srv.cc line 2841 for 272.00 seconds the semaphore:
      X-lock on RW-latch at 0x7f50ead13a40 '&dict_operation_lock'
      a writer (thread id 139985335802624) has reserved it in mode  exclusive
      number of readers 0, waiters flag 1, lock_word: 0
      Last time read locked in file row0purge.cc line 772
      Last time write locked in file row0mysql.cc line 4055
      Holder thread 0 file not yet reserved line 0
      InnoDB: Warning: semaphore wait:
      --Thread 139985130878720 has waited at dict0dict.cc line 7278 for 16.000 seconds the semaphore:
      Mutex at 0x7f50ece119f8 '&dict_sys->mutex', lock var 1
      Last time reserved by thread 139985335802624 in file /opt/buildbot-slave/mariadb/starfsmenn1/build/storage/xtradb/row/row0mysql.cc line 2269, waiters flag 1
      InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
      InnoDB: Pending reads 0, writes 0
      InnoDB: Error: Waited for 275 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 280 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 285 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 290 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 295 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 300 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: ###### Diagnostic info printed to the standard error stream
      InnoDB: Warning: a long semaphore wait:
      --Thread 139985099409152 has waited at srv0srv.cc line 2841 for 303.00 seconds the semaphore:
      X-lock on RW-latch at 0x7f50ead13a40 '&dict_operation_lock'
      a writer (thread id 139985335802624) has reserved it in mode  exclusive
      number of readers 0, waiters flag 1, lock_word: 0
      Last time read locked in file row0purge.cc line 772
      Last time write locked in file row0mysql.cc line 4055
      Holder thread 0 file not yet reserved line 0
      InnoDB: Warning: semaphore wait:
      --Thread 139985099409152 has waited at srv0srv.cc line 2841 for 303.00 seconds the semaphore:
      X-lock on RW-latch at 0x7f50ead13a40 '&dict_operation_lock'
      a writer (thread id 139985335802624) has reserved it in mode  exclusive
      number of readers 0, waiters flag 1, lock_word: 0
      Last time read locked in file row0purge.cc line 772
      Last time write locked in file row0mysql.cc line 4055
      Holder thread 0 file not yet reserved line 0
      InnoDB: Warning: semaphore wait:
      --Thread 139985130878720 has waited at dict0dict.cc line 7278 for 47.000 seconds the semaphore:
      Mutex at 0x7f50ece119f8 '&dict_sys->mutex', lock var 1
      Last time reserved by thread 139985335802624 in file /opt/buildbot-slave/mariadb/starfsmenn1/build/storage/xtradb/row/row0mysql.cc line 2269, waiters flag 1
      InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
      InnoDB: Pending reads 0, writes 0
      InnoDB: Error: Waited for 305 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 310 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 315 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 320 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 325 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 330 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: ###### Diagnostic info printed to the standard error stream
      InnoDB: Error: Waited for 335 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 340 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 345 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 350 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 355 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 360 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 365 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 370 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 375 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 380 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 385 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 390 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 395 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 400 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 405 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 410 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 415 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 420 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 425 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 430 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 435 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 440 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 445 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 450 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 455 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 460 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 465 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 470 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 475 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 480 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 485 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 490 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 495 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 500 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 505 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 510 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 515 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 520 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 525 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 530 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 535 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 540 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 545 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 550 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 555 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 560 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 565 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 570 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 575 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 580 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 585 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 590 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 595 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      InnoDB: Error: Waited for 600 secs for hash index ref_count (1) to drop to 0.
      index: "GEN_CLUST_INDEX" table: "test/t1"
      2017-12-15 10:22:34 7f50e036db00  InnoDB: Assertion failure in thread 139985335802624 in file dict0dict.cc line 2756
      InnoDB: We intentionally generate a memory trap.
      InnoDB: Submit a detailed bug report to https://jira.mariadb.org/
      InnoDB: If you get repeated assertion failures or crashes, even
      InnoDB: immediately after the mysqld startup, there may be
      InnoDB: corruption in the InnoDB tablespace. Please refer to
      InnoDB: http://dev.mysql.com/doc/refman/5.6/en/forcing-innodb-recovery.html
      InnoDB: about forcing recovery.
      "171215 10:22:34 [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.1.30-MariaDB-debug
      key_buffer_size=1048576
      read_buffer_size=131072
      max_used_connections=1
      max_threads=153
      thread_count=1
      It is possible that mysqld could use up to 
      key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 63042 K  bytes of memory
      Hope that's ok; if not, decrease some variables in the equation.
       
      Thread pointer: 0x7f50ecccfec0
      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 = 0x7f50e036d230 thread_stack 0x48400
      /opt/buildbot-slave/mariadb/starfsmenn1/build/sql/mysqld(my_print_stacktrace+0x38)[0x7f50e9a5e14d]
      /opt/buildbot-slave/mariadb/starfsmenn1/build/sql/mysqld(handle_fatal_signal+0x38f)[0x7f50e93fedac]
      /lib/x86_64-linux-gnu/libpthread.so.0(+0x10340)[0x7f50e7a23340]
      /lib/x86_64-linux-gnu/libc.so.6(gsignal+0x39)[0x7f50e707acc9]
      mysys/stacktrace.c:267(my_print_stacktrace)[0x7f50e707e0d8]
      dict/dict0dict.cc:2758(dict_index_remove_from_cache_low)[0x7f50e997a97b]
      dict/dict0dict.cc:2085(dict_table_remove_from_cache_low(dict_table_t*, unsigned long))[0x7f50e99794b2]
      dict/dict0dict.cc:2148(dict_table_remove_from_cache(dict_table_t*))[0x7f50e997995b]
      row/row0mysql.cc:4468(row_drop_table_for_mysql(char const*, trx_t*, bool, unsigned long, bool))[0x7f50e985bdc1]
      handler/ha_innodb.cc:13175(ha_innobase::delete_table(char const*))[0x7f50e974f10d]
      sql/handler.cc:4327(handler::ha_delete_table(char const*))[0x7f50e9409824]
      sql/handler.cc:2382(ha_delete_table(THD*, handlerton*, char const*, char const*, char const*, bool))[0x7f50e940497d]
      sql/sql_table.cc:2469(mysql_rm_table_no_locks(THD*, TABLE_LIST*, bool, bool, bool, bool, bool))[0x7f50e9273c9b]
      sql/sql_table.cc:2084(mysql_rm_table(THD*, TABLE_LIST*, char, char))[0x7f50e9272ed2]
      sql/sql_parse.cc:4238(mysql_execute_command(THD*))[0x7f50e91c17e8]
      sql/sql_parse.cc:7353(mysql_parse(THD*, char*, unsigned int, Parser_state*))[0x7f50e91caf7f]
      sql/sql_parse.cc:1479(dispatch_command(enum_server_command, THD*, char*, unsigned int))[0x7f50e91b9b49]
      sql/sql_parse.cc:1106(do_command(THD*))[0x7f50e91b88c9]
      sql/sql_connect.cc:1349(do_handle_one_connection(THD*))[0x7f50e92f1ff1]
      sql/sql_connect.cc:1262(handle_one_connection)[0x7f50e92f1d55]
      perfschema/pfs.cc:1863(pfs_spawn_thread)[0x7f50e95eed58]
      /lib/x86_64-linux-gnu/libpthread.so.0(+0x8182)[0x7f50e7a1b182]
      /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d)[0x7f50e713e47d]
       
      Trying to get some variables.
      Some pointers may be invalid and cause the dump to abort.
      Query (0x7f50980173d8): drop table t1
      Connection ID (thread ID): 10
      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=off
       
      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.
      Writing a core file
      ----------SERVER LOG END-------------
      

        Attachments

          Issue Links

            Activity

              People

              • Assignee:
                marko Marko Mäkelä
                Reporter:
                elenst Elena Stepanova
              • Votes:
                0 Vote for this issue
                Watchers:
                2 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved: