Mysql timeout sur ma VM - où chercher?

Jaxom99 · April 12, 2021, 3:01pm

Bonjour à tous,

Problème récurrent de Mysql qui passe en Timeout (service failed mais présent dans htop), comment chercher la source du problème ?

Mon serveur YunoHost

Matériel: VPS pulseheberg
Version de YunoHost: 4.1
J’ai accès à mon serveur : En SSH
Êtes-vous dans un contexte particulier ou avez-vous effectué des modifications particulières sur votre instance ? : non
Apps installées : borg ffsync jirafeau kanboard my_webapp my_webapp__2 rainloop thelounge ttrss wallabag2

Description du problème

Après quelques minutes normales, le service Mysql part constamment en défaut. Que ce soit suite à un reboot VM ou une relance du service, il ne reste pas actif. Cela arrive depuis quelques semaines, sans que je puisse mettre le doigt sur la cause initiale.

Etat du service :

● mariadb.service - MariaDB 10.3.27 database server
   Loaded: loaded (/lib/systemd/system/mariadb.service; enabled; vendor preset: enabled)
   Active: failed (Result: timeout) since Sat 2021-04-10 08:08:30 CEST; 2 days ago
     Docs: man:mysqld(8)
           https://mariadb.com/kb/en/library/systemd/
  Process: 4693 ExecStartPre=/usr/bin/install -m 755 -o mysql -g root -d /var/run/mysqld (code=exited, status=0/SUCCESS)
  Process: 4697 ExecStartPre=/bin/sh -c systemctl unset-environment _WSREP_START_POSITION (code=exited, status=0/SUCCESS)
  Process: 4699 ExecStartPre=/bin/sh -c [ ! -e /usr/bin/galera_recovery ] && VAR= ||   VAR=`cd /usr/bin/..; /usr/bin/galera_recovery`
 Main PID: 4746
   Status: "Waiting for page cleaner"
    Tasks: 3 (limit: 2411)
   Memory: 19.6M
   CGroup: /system.slice/mariadb.service
           └─4746 /usr/sbin/mysqld

Journal Mysql dans /var/log/mysql/error.log : vide.
Seules traces dans syslog :

Apr 12 00:00:17 v logrotate[29258]: #007mysqladmin: connect to server at 'localhost' failed
Apr 12 00:00:17 v logrotate[29258]: error: 'Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (111)'
Apr 12 00:00:17 v logrotate[29258]: Check that mysqld is running and that the socket: '/var/run/mysqld/mysqld.sock' exists!
Apr 12 00:00:17 v logrotate[29258]: error: error running shared postrotate script for '/var/log/mysql/mysql.log /var/log/mysql/mysql-slow.log /var/log/mysql/mariadb-slow.log /var/log/mysql/error.log '

mais le fichier mysqld.sock existe bien.

=> où puis-je chercher les messages d’erreur pour avancer ? Merci pour votre aide

Jaxom99 · April 12, 2021, 3:32pm

quand je kill le service, j’ai la trace suivante :

Apr 12 00:06:48 v backup-with-borg[29259]: mysqldump: Got error: 2002: "Can't connect to local MySQL server through socket '/var/run/
mysqld/mysqld.sock' (111)" when trying to connect
Apr 12 17:02:31 v systemd[1]: mariadb.service: Found left-over process 4746 (mysqld) in control group while starting unit. Ignoring.
Apr 12 17:02:32 v systemd[1]: mariadb.service: Found left-over process 4746 (mysqld) in control group while starting unit. Ignoring.
Apr 12 17:02:33 v mysqld[10445]: 2021-04-12 17:02:33 0 [Warning] option 'table_open_cache': unsigned value 4 adjusted to 10
Apr 12 17:02:33 v mysqld[10445]: 2021-04-12 17:02:33 0 [Note] /usr/sbin/mysqld (mysqld 10.3.27-MariaDB-0+deb10u1) starting as process
 10445 ...
Apr 12 17:02:34 v mysqld[10445]: 2021-04-12 17:02:34 0 [ERROR] mysqld: Can't lock aria control file '/var/lib/mysql/aria_log_control'
 for exclusive use, error: 11. Will retry for 30 seconds
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [ERROR] mysqld: Got error 'Could not get an exclusive lock; file is probably in use by another process' when trying to use aria control file '/var/lib/mysql/aria_log_control'
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [ERROR] Plugin 'Aria' init function returned error.
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [ERROR] Plugin 'Aria' registration as a STORAGE ENGINE failed.
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Using Linux native AIO
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Uses event mutexes
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Number of pools: 1
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Using SSE2 crc32 instructions
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Completed initialization of buffer pool
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
Apr 12 17:03:05 v mysqld[10445]: 2021-04-12 17:03:05 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=2287221405
Apr 12 17:03:06 v mysqld[10445]: 2021-04-12 17:03:06 0 [Note] InnoDB: 128 out of 128 rollback segments are active.
Apr 12 17:03:06 v mysqld[10445]: 2021-04-12 17:03:06 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
Apr 12 17:03:06 v mysqld[10445]: 2021-04-12 17:03:06 0 [Note] InnoDB: Creating shared tablespace for temporary tables
Apr 12 17:03:06 v mysqld[10445]: 2021-04-12 17:03:06 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
Apr 12 17:03:06 v mysqld[10445]: 2021-04-12 17:03:06 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
Apr 12 17:03:06 v mysqld[10445]: 2021-04-12 17:03:06 0 [Note] InnoDB: Waiting for purge to start
Apr 12 17:03:07 v mysqld[10445]: 2021-04-12 17:03:07 0 [Note] InnoDB: 10.3.27 started; log sequence number 2287221414; transaction id 10581462
Apr 12 17:03:07 v mysqld[10445]: 2021-04-12 17:03:07 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
Apr 12 17:03:07 v mysqld[10445]: 2021-04-12 17:03:07 0 [Note] Plugin 'FEEDBACK' is disabled.
Apr 12 17:03:07 v mysqld[10445]: 2021-04-12 17:03:07 0 [ERROR] Aria engine is not enabled or did not start. The Aria engine must be enabled to continue as mysqld was configured with --with-aria-tmp-tables
Apr 12 17:03:07 v mysqld[10445]: 2021-04-12 17:03:07 0 [ERROR] Aborting
Apr 12 17:03:09 v mysqld[10445]: Warning: Memory not freed: 520

system · April 27, 2021, 3:33pm

This topic was automatically closed 15 days after the last reply. New replies are no longer allowed.