[MDEV-20467] Multiple "Warning: Enabling keys got errno 127 on ., retrying" Created: 2019-09-02  Updated: 2019-09-16

Status: Open
Project: MariaDB Server
Component/s: Storage Engine - InnoDB
Affects Version/s: 10.3.10
Fix Version/s: None

Type: Bug Priority: Major
Reporter: Dennis Melnikov Assignee: Unassigned
Resolution: Unresolved Votes: 0
Labels: None
Environment:

CentOS Linux release 7.5.1804
Several databases on InnoDB, total size 27G.


Issue Links:
Relates
relates to MDEV-17622 Assertion `block->type == PAGECACHE_E... Closed
relates to MDEV-18805 ASAN heap-buffer-overflow in strmake_... Closed
relates to MDEV-19662 MariaDB crashes after "Enabling keys ... Open
relates to MDEV-19663 Got error 127 and ER_CRASHED_ON_USAGE... Closed

 Description   

Every now and then the warning is logged in MariaDB's error log.

2019-09-02 14:44:00 119159 [Warning] Warning: Enabling keys got errno 127 on ., retrying

Some 3 times an hour. It doesn't crash, logs only.



 Comments   
Comment by Elena Stepanova [ 2019-09-11 ]

We have a number of open bugs which have this warning as a symptom. Unless you can provide a reproducible test case so that we could verify, we'll have to consider it to be one of them.

Comment by Dennis Melnikov [ 2019-09-16 ]

A query causing the warning is,

UPDATE shed
SET dateMake = 1568300080,
    wid = '',
    dateUse = 0
WHERE id = 40;

shed table is defined as follows:

CREATE TABLE shed (
  dateAdd int(11) DEFAULT NULL,
  usrid int(11) DEFAULT NULL,
  type_act tinyint(4) DEFAULT NULL,
  day smallint(6) DEFAULT NULL,
  dateMake int(11) DEFAULT NULL,
  action smallint(6) DEFAULT NULL,
  rem text DEFAULT NULL,
  docid int(11) DEFAULT NULL,
  doc_type tinyint(4) DEFAULT NULL,
  name text DEFAULT NULL,
  data text DEFAULT NULL,
  wid text DEFAULT NULL,
  dateUse int(11) DEFAULT NULL,
  active tinyint(4) DEFAULT NULL,
  id int(10) UNSIGNED NOT NULL AUTO_INCREMENT,
  hours int(11) DEFAULT NULL,
  minutes int(11) DEFAULT NULL,
  dateBeg int(11) NOT NULL,
  tolog tinyint(4) NOT NULL,
  timeout int(11) NOT NULL,
  seconds int(11) NOT NULL,
  timeoff int(11) NOT NULL,
  timeon int(11) NOT NULL,
  hours_boost int(11) NOT NULL,
  hours_run int(11) NOT NULL,
  minutes_boost int(11) NOT NULL,
  minutes_run int(11) NOT NULL,
  seconds_boost int(11) NOT NULL,
  seconds_run int(11) NOT NULL,
  includes text NOT NULL,
  function_count text NOT NULL,
  boost tinyint(4) NOT NULL,
  boost_date int(11) NOT NULL,
  last_count int(11) DEFAULT NULL,
  date_change int(11) NOT NULL,
  changer int(11) NOT NULL,
  boost_count_on tinyint(4) NOT NULL,
  comment text NOT NULL,
  off_time int(11) NOT NULL,
  on_time int(11) NOT NULL,
  del tinyint(4) NOT NULL,
  PRIMARY KEY (id)
)
ENGINE = INNODB
AUTO_INCREMENT = 94
AVG_ROW_LENGTH = 534
CHARACTER SET koi8r
COLLATE koi8r_general_ci
ROW_FORMAT = DYNAMIC;

Copying the data into a similar table doesn't recreate the problem:

CREATE TABLE shed2 LIKE shed;
INSERT INTO shed2 SELECT * FROM shed;
UPDATE shed2
SET dateMake = 1568300080,
    wid = '',
    dateUse = 0
WHERE id = 40;

Optimizing the table doesn't fix the problem:

OPTIMIZE TABLE shed;

Please pay your attention to timestamps that the warnings logged in:

2019-09-13 21:28:00 176694 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-13 21:50:40 176744 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-13 22:13:21 176793 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-13 22:36:21 176843 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-13 23:00:01 176894 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16  9:59:21 181241 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 10:22:03 181310 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 10:44:20 181382 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 11:07:00 181453 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 11:29:20 181527 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 11:51:40 181597 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 12:14:41 181670 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 13:18:41 181796 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 13:38:41 181839 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 14:01:40 181902 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 14:24:40 181991 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 14:47:01 182225 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:01:20 182306 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:03:40 182316 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:26:00 182443 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:34:21 182494 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:34:40 182497 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:35:01 182498 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:35:20 182500 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:36:01 182503 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:37:00 182507 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:37:20 182510 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:46:01 182583 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:46:21 182586 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:48:40 182597 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 15:58:40 182656 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 16:00:20 182666 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 16:10:21 182717 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 16:11:01 182720 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 16:11:40 182722 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 16:12:01 182725 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 16:15:00 182739 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 16:16:00 182743 [Warning] Warning: Enabling keys got errno 127 on ., retrying
2019-09-16 16:23:01 182779 [Warning] Warning: Enabling keys got errno 127 on ., retrying

Mostly it occurs in 0, 20, 40 seconds or a second later.

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