Since we migrated from MariaDb 5.5 to MariaDB 10.2.13, MariaDBServer become often unreponsive:
- all thread are stuck in opening table status
- lot of InnoDB: A long semaphore wait Waring in lig
We have to kill and restart mariaDb server.
Example:
On 2018-03-23, MariaDb hang at 8:28. At this times, there is an attempt to restaure a dump generate with MySQL 5.5.18 for Win64.
.
Log
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140401323267840 has waited at row0row.cc line 988 for 241.00 seconds the semaphore:
|
S-lock on RW-latch at 0x7fbb5ed79b18 created in file buf0buf.cc line 1471
|
a writer (thread id 140401340053248) has reserved it in mode exclusive
|
number of readers 0, waiters flag 1, lock_word: ffffffffdfffffff
|
Last time read locked in file row0row.cc line 988
|
Last time write locked in file btr0cur.cc line 1917
|
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140401340053248 has waited at btr0cur.cc line 1917 for 241.00 seconds the semaphore:
|
X-lock (wait_ex) on RW-latch at 0x7fbd21a936a0 created in file buf0buf.cc line 1471
|
a writer (thread id 140401340053248) has reserved it in mode wait exclusive
|
number of readers 1, waiters flag 0, lock_word: ffffffffffffffff
|
Last time read locked in file row0row.cc line 988
|
Last time write locked in file buf0flu.cc line 1236
|
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140400859473664 has waited at row0ins.cc line 1926 for 241.00 seconds the semaphore:
|
S-lock on RW-latch at 0x55b134618c50 created in file dict0dict.cc line 1107
|
a writer (thread id 140401408464640) has reserved it in mode wait exclusive
|
number of readers 3, waiters flag 1, lock_word: fffffffffffffffd
|
Last time read locked in file row0ins.cc line 1926
|
Last time write locked in file row0mysql.cc line 3383
|
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140401314875136 has waited at row0purge.cc line 853 for 241.00 seconds the semaphore:
|
S-lock on RW-latch at 0x55b134618c50 created in file dict0dict.cc line 1107
|
a writer (thread id 140401408464640) has reserved it in mode wait exclusive
|
number of readers 3, waiters flag 1, lock_word: fffffffffffffffd
|
Last time read locked in file row0ins.cc line 1926
|
Last time write locked in file row0mysql.cc line 3383
|
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140401331660544 has waited at fsp0fsp.cc line 2821 for 241.00 seconds the semaphore:
|
X-lock on RW-latch at 0x55b1345efe40 created in file fil0fil.cc line 1551
|
a writer (thread id 140401340053248) has reserved it in mode exclusive
|
number of readers 0, waiters flag 1, lock_word: 0
|
Last time read locked in file not yet reserved line 0
|
Last time write locked in file fsp0fsp.cc line 2821
|
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140401408464640 has waited at row0mysql.cc line 3383 for 241.00 seconds the semaphore:
|
X-lock (wait_ex) on RW-latch at 0x55b134618c50 created in file dict0dict.cc line 1107
|
a writer (thread id 140401408464640) has reserved it in mode wait exclusive
|
number of readers 3, waiters flag 1, lock_word: fffffffffffffffd
|
Last time read locked in file row0ins.cc line 1926
|
Last time write locked in file row0mysql.cc line 3383
|
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140400655324928 has waited at row0ins.cc line 1926 for 241.00 seconds the semaphore:
|
S-lock on RW-latch at 0x55b134618c50 created in file dict0dict.cc line 1107
|
a writer (thread id 140401408464640) has reserved it in mode wait exclusive
|
number of readers 3, waiters flag 1, lock_word: fffffffffffffffd
|
Last time read locked in file row0ins.cc line 1926
|
Last time write locked in file row0mysql.cc line 3383
|
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140400030902016 has waited at row0mysql.cc line 1783 for 241.00 seconds the semaphore:
|
S-lock on RW-latch at 0x55b134618c50 created in file dict0dict.cc line 1107
|
a writer (thread id 140401408464640) has reserved it in mode wait exclusive
|
number of readers 3, waiters flag 1, lock_word: fffffffffffffffd
|
Last time read locked in file row0ins.cc line 1926
|
Last time write locked in file row0mysql.cc line 3383
|
2018-03-23 8:32:43 140401445676800 [Warning] InnoDB: A long semaphore wait:
|
--Thread 140400016656128 has waited at row0ins.cc line 1926 for 241.00 seconds the semaphore:
|
S-lock on RW-latch at 0x55b134618c50 created in file dict0dict.cc line 1107
|
a writer (thread id 140401408464640) has reserved it in mode wait exclusive
|
number of readers 3, waiters flag 1, lock_word: fffffffffffffffd
|
Last time read locked in file row0ins.cc line 1926
|
Last time write locked in file row0mysql.cc line 3383
|
2018-03-23 8:32:43 140401445676800 [Note] InnoDB: A semaphore wait:
|
--Thread 140400038479616 has waited at row0upd.cc line 183 for 150.00 seconds the semaphore:
|
S-lock on RW-latch at 0x55b134618c50 created in file dict0dict.cc line 1107
|
a writer (thread id 140401408464640) has reserved it in mode wait exclusive
|
number of readers 3, waiters flag 1, lock_word: fffffffffffffffd
|
Last time read locked in file row0ins.cc line 1926
|
Last time write locked in file row0mysql.cc line 3383
|
2018-03-23 8:32:43 140401445676800 [Note] InnoDB: A semaphore wait:
|
.h3 Dump
-- MySQL dump 10.13 Distrib 5.5.18, for Win64 (x86)
|
--
|
-- Host: 127.0.0.1 Database: db_horizon_transport_rodr04_01
|
-- ------------------------------------------------------
|
-- Server version 5.5.18
|
|
/*!40101 SET @OLD_CHARACTER_SET_CLIENT=@@CHARACTER_SET_CLIENT */;
|
/*!40101 SET @OLD_CHARACTER_SET_RESULTS=@@CHARACTER_SET_RESULTS */;
|
/*!40101 SET @OLD_COLLATION_CONNECTION=@@COLLATION_CONNECTION */;
|
/*!40101 SET NAMES latin1 */;
|
/*!40103 SET @OLD_TIME_ZONE=@@TIME_ZONE */;
|
/*!40103 SET TIME_ZONE='+00:00' */;
|
/*!40014 SET @OLD_UNIQUE_CHECKS=@@UNIQUE_CHECKS, UNIQUE_CHECKS=0 */;
|
/*!40014 SET @OLD_FOREIGN_KEY_CHECKS=@@FOREIGN_KEY_CHECKS, FOREIGN_KEY_CHECKS=0 */;
|
/*!40101 SET @OLD_SQL_MODE=@@SQL_MODE, SQL_MODE='NO_AUTO_VALUE_ON_ZERO' */;
|
/*!40111 SET @OLD_SQL_NOTES=@@SQL_NOTES, SQL_NOTES=0 */;
|
|
--
|
-- Current Database: `db_horizon_transport_rodr04_01`
|
--
|
|
/*!40000 DROP DATABASE IF EXISTS `db_horizon_transport_rodr04_01`*/;
|
|
CREATE DATABASE /*!32312 IF NOT EXISTS*/ `db_horizon_transport_rodr04_01` /*!40100 DEFAULT CHARACTER SET latin1 */;
|
|
USE `db_horizon_transport_rodr04_01`;
|
|
--
|
-- Table structure for table `db_version_ver`
|
--
|
|
DROP TABLE IF EXISTS `db_version_ver`;
|
/*!40101 SET @saved_cs_client = @@character_set_client */;
|
/*!40101 SET character_set_client = utf8 */;
|
CREATE TABLE `db_version_ver` (
|
`ver_id` int(11) NOT NULL AUTO_INCREMENT,
|
`ver_num` int(11) DEFAULT NULL,
|
`ver_libelle` varchar(45) DEFAULT NULL,
|
`ver_date` datetime DEFAULT NULL,
|
PRIMARY KEY (`ver_id`)
|
) ENGINE=InnoDB AUTO_INCREMENT=877 DEFAULT CHARSET=latin1;
|
/*!40101 SET character_set_client = @saved_cs_client */;
|
- relates to
-
MDEV-15707
Long semaphore wait : deadlock in Innodb IO code, caused by change buffering.
-
-
Closed
{"report":{"fcp":1000.5,"ttfb":236,"pageVisibility":"visible","entityId":66478,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"a900fdf7-e357-4f3a-96c9-b8c3dbc063ff","navigationType":0,"readyForUser":1128.2000000029802,"redirectCount":0,"resourceLoadedEnd":1170.3999999985099,"resourceLoadedStart":240.80000000447035,"resourceTiming":[{"duration":178.5,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2bv2/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":240.80000000447035,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":240.80000000447035,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":419.30000000447035,"responseStart":0,"secureConnectionStart":0},{"duration":178.5,"initiatorType":"link","name":"https://jira.mariadb.org/s/7ebd35e77e471bc30ff0eba799ebc151-CDN/lu2bv2/820016/12ta74/2380add21a9a1006587582385952de73/_/download/contextbatch/css/jira.browse.project,project.issue.navigator,jira.view.issue,jira.general,jira.global,atl.general,-_super/batch.css?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&slack-enabled=true","startTime":241.10000000149012,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":241.10000000149012,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":419.6000000014901,"responseStart":0,"secureConnectionStart":0},{"duration":178.29999999701977,"initiatorType":"script","name":"https://jira.mariadb.org/s/e9b27a47da5fb0f74a35acd57e9847fb-CDN/lu2bv2/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":241.30000000447035,"connectEnd":241.30000000447035,"connectStart":241.30000000447035,"domainLookupEnd":241.30000000447035,"domainLookupStart":241.30000000447035,"fetchStart":241.30000000447035,"redirectEnd":0,"redirectStart":0,"requestStart":241.30000000447035,"responseEnd":419.6000000014901,"responseStart":419.6000000014901,"secureConnectionStart":241.30000000447035},{"duration":282.90000000596046,"initiatorType":"script","name":"https://jira.mariadb.org/s/c32eb0da7ad9831253f8397e6cc26afd-CDN/lu2bv2/820016/12ta74/2380add21a9a1006587582385952de73/_/download/contextbatch/js/jira.browse.project,project.issue.navigator,jira.view.issue,jira.general,jira.global,atl.general,-_super/batch.js?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&locale=en&slack-enabled=true","startTime":241.39999999850988,"connectEnd":241.39999999850988,"connectStart":241.39999999850988,"domainLookupEnd":241.39999999850988,"domainLookupStart":241.39999999850988,"fetchStart":241.39999999850988,"redirectEnd":0,"redirectStart":0,"requestStart":241.39999999850988,"responseEnd":524.3000000044703,"responseStart":524.3000000044703,"secureConnectionStart":241.39999999850988},{"duration":287.6000000014901,"initiatorType":"script","name":"https://jira.mariadb.org/s/bc0bcb146314416123c992714ee00ff7-CDN/lu2bv2/820016/12ta74/c92c0caa9a024ae85b0ebdbed7fb4bd7/_/download/contextbatch/js/atl.global,-_super/batch.js?locale=en","startTime":241.60000000149012,"connectEnd":241.60000000149012,"connectStart":241.60000000149012,"domainLookupEnd":241.60000000149012,"domainLookupStart":241.60000000149012,"fetchStart":241.60000000149012,"redirectEnd":0,"redirectStart":0,"requestStart":241.60000000149012,"responseEnd":529.2000000029802,"responseStart":529.2000000029802,"secureConnectionStart":241.60000000149012},{"duration":288.59999999403954,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-en/jira.webresources:calendar-en.js","startTime":241.80000000447035,"connectEnd":241.80000000447035,"connectStart":241.80000000447035,"domainLookupEnd":241.80000000447035,"domainLookupStart":241.80000000447035,"fetchStart":241.80000000447035,"redirectEnd":0,"redirectStart":0,"requestStart":241.80000000447035,"responseEnd":530.3999999985099,"responseStart":530.3999999985099,"secureConnectionStart":241.80000000447035},{"duration":289.1000000014901,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-localisation-moment/jira.webresources:calendar-localisation-moment.js","startTime":241.89999999850988,"connectEnd":241.89999999850988,"connectStart":241.89999999850988,"domainLookupEnd":241.89999999850988,"domainLookupStart":241.89999999850988,"fetchStart":241.89999999850988,"redirectEnd":0,"redirectStart":0,"requestStart":241.89999999850988,"responseEnd":531,"responseStart":531,"secureConnectionStart":241.89999999850988},{"duration":329.3999999985099,"initiatorType":"link","name":"https://jira.mariadb.org/s/b04b06a02d1959df322d9cded3aeecc1-CDN/lu2bv2/820016/12ta74/a2ff6aa845ffc9a1d22fe23d9ee791fc/_/download/contextbatch/css/jira.global.look-and-feel,-_super/batch.css","startTime":242.10000000149012,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":242.10000000149012,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":571.5,"responseStart":0,"secureConnectionStart":0},{"duration":289.29999999701977,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":242.30000000447035,"connectEnd":242.30000000447035,"connectStart":242.30000000447035,"domainLookupEnd":242.30000000447035,"domainLookupStart":242.30000000447035,"fetchStart":242.30000000447035,"redirectEnd":0,"redirectStart":0,"requestStart":242.30000000447035,"responseEnd":531.6000000014901,"responseStart":531.6000000014901,"secureConnectionStart":242.30000000447035},{"duration":329.20000000298023,"initiatorType":"link","name":"https://jira.mariadb.org/s/3ac36323ba5e4eb0af2aa7ac7211b4bb-CDN/lu2bv2/820016/12ta74/d176f0986478cc64f24226b3d20c140d/_/download/contextbatch/css/com.atlassian.jira.projects.sidebar.init,-_super,-project.issue.navigator,-jira.view.issue/batch.css?jira.create.linked.issue=true","startTime":242.5,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":242.5,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":571.7000000029802,"responseStart":0,"secureConnectionStart":0},{"duration":289.5,"initiatorType":"script","name":"https://jira.mariadb.org/s/719848dd97ebe0663199f49a3936487a-CDN/lu2bv2/820016/12ta74/d176f0986478cc64f24226b3d20c140d/_/download/contextbatch/js/com.atlassian.jira.projects.sidebar.init,-_super,-project.issue.navigator,-jira.view.issue/batch.js?jira.create.linked.issue=true&locale=en","startTime":242.70000000298023,"connectEnd":242.70000000298023,"connectStart":242.70000000298023,"domainLookupEnd":242.70000000298023,"domainLookupStart":242.70000000298023,"fetchStart":242.70000000298023,"redirectEnd":0,"redirectStart":0,"requestStart":242.70000000298023,"responseEnd":532.2000000029802,"responseStart":532.2000000029802,"secureConnectionStart":242.70000000298023},{"duration":787.3000000044703,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-js/jira.webresources:bigpipe-js.js","startTime":248.89999999850988,"connectEnd":248.89999999850988,"connectStart":248.89999999850988,"domainLookupEnd":248.89999999850988,"domainLookupStart":248.89999999850988,"fetchStart":248.89999999850988,"redirectEnd":0,"redirectStart":0,"requestStart":248.89999999850988,"responseEnd":1036.2000000029802,"responseStart":1036.2000000029802,"secureConnectionStart":248.89999999850988},{"duration":787.3999999985099,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-init/jira.webresources:bigpipe-init.js","startTime":249.20000000298023,"connectEnd":249.20000000298023,"connectStart":249.20000000298023,"domainLookupEnd":249.20000000298023,"domainLookupStart":249.20000000298023,"fetchStart":249.20000000298023,"redirectEnd":0,"redirectStart":0,"requestStart":249.20000000298023,"responseEnd":1036.6000000014901,"responseStart":1036.6000000014901,"secureConnectionStart":249.20000000298023},{"duration":129.40000000596046,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":615.8999999985099,"connectEnd":615.8999999985099,"connectStart":615.8999999985099,"domainLookupEnd":615.8999999985099,"domainLookupStart":615.8999999985099,"fetchStart":615.8999999985099,"redirectEnd":0,"redirectStart":0,"requestStart":615.8999999985099,"responseEnd":745.3000000044703,"responseStart":745.3000000044703,"secureConnectionStart":615.8999999985099},{"duration":295.79999999701977,"initiatorType":"link","name":"https://jira.mariadb.org/s/d5715adaadd168a9002b108b2b039b50-CDN/lu2bv2/820016/12ta74/be4b45e9cec53099498fa61c8b7acba4/_/download/contextbatch/css/jira.project.sidebar,-_super,-project.issue.navigator,-jira.general,-jira.browse.project,-jira.view.issue,-jira.global,-atl.general,-com.atlassian.jira.projects.sidebar.init/batch.css?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&slack-enabled=true","startTime":874.6000000014901,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":874.6000000014901,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1170.3999999985099,"responseStart":0,"secureConnectionStart":0},{"duration":343.5,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/e65b778d185daf5aee24936755b43da6/_/download/contextbatch/js/browser-metrics-plugin.contrib,-_super,-project.issue.navigator,-jira.view.issue,-atl.general/batch.js?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&slack-enabled=true","startTime":875.7000000029802,"connectEnd":875.7000000029802,"connectStart":875.7000000029802,"domainLookupEnd":875.7000000029802,"domainLookupStart":875.7000000029802,"fetchStart":875.7000000029802,"redirectEnd":0,"redirectStart":0,"requestStart":875.7000000029802,"responseEnd":1219.2000000029802,"responseStart":1219.2000000029802,"secureConnectionStart":875.7000000029802},{"duration":348.6000000014901,"initiatorType":"script","name":"https://jira.mariadb.org/s/53a43b6764f587426c7bb9a150184c00-CDN/lu2bv2/820016/12ta74/be4b45e9cec53099498fa61c8b7acba4/_/download/contextbatch/js/jira.project.sidebar,-_super,-project.issue.navigator,-jira.general,-jira.browse.project,-jira.view.issue,-jira.global,-atl.general,-com.atlassian.jira.projects.sidebar.init/batch.js?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&locale=en&slack-enabled=true","startTime":876,"connectEnd":876,"connectStart":876,"domainLookupEnd":876,"domainLookupStart":876,"fetchStart":876,"redirectEnd":0,"redirectStart":0,"requestStart":876,"responseEnd":1224.6000000014901,"responseStart":1224.6000000014901,"secureConnectionStart":876},{"duration":286.19999999552965,"initiatorType":"script","name":"https://www.google-analytics.com/analytics.js","startTime":992.8000000044703,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":992.8000000044703,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1279,"responseStart":0,"secureConnectionStart":0}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":36,"responseStart":236,"responseEnd":248,"domLoading":239,"domInteractive":1207,"domContentLoadedEventStart":1207,"domContentLoadedEventEnd":1270,"domComplete":1975,"loadEventStart":1975,"loadEventEnd":1977,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":1187.3000000044703},{"name":"bigPipe.sidebar-id.end","time":1188.1000000014901},{"name":"bigPipe.activity-panel-pipe-id.start","time":1188.2000000029802},{"name":"bigPipe.activity-panel-pipe-id.end","time":1190.8000000044703},{"name":"activityTabFullyLoaded","time":1284.3000000044703}],"measures":[],"correlationId":"8a0bc9900575ac","effectiveType":"4g","downlink":10,"rtt":0,"serverDuration":102,"dbReadsTimeInMs":10,"dbConnsTimeInMs":17,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}