He implementado un sistema que utiliza MySQL 8 como motor de base de datos y en dos meses han ocurrido 3 cortes de energía. Al encender el equipo nuevamente, el servicio de MySQL no es iniciado automáticamente y me he visto obligado a forzar la recuperación de InnoDB con innodb_force_recovery = 2 para recuperar el acceso a la base de datos. El servidor tiene instalado Windows 10 y todas mis tablas usan InnoDB. La configuración de MySQL es la predeterminada a excepción de las siguientes directivas (my.ini):
innodb_ft_min_token_size = 2
lc_time_names = 'es_AR'
lower_case_table_names = 2
Los primeros dos cortes ocurrieron durante la mañana temprano antes de que el usuario comience a utilizar mi sistema, por lo tanto MySQL estaba "en reposo" (idle) y no había ninguna transacción en proceso ni pendiente de escribir en el disco.
Estoy intentando descubrir la causa de este problema pero no encuentro información suficiente. He probado cambiando los valores de innodb_buffer_pool_dump_at_shutdown y innodb_buffer_pool_load_at_startup para evitar que MySQL utilice archivos antes de tiempo pero al encender el equipo noto que MySQL igualmente realiza modificaciones tan pronto es iniciado.
También he desactivado la caché de escritura en disco desde el Administrador de dispositivos de Windows esperando que solucione el problema, pero no puedo estar seguro hasta que se produzca otro corte de energía (y tampoco quiero arriesgarme a realizar pruebas de este tipo en mi equipo de desarrollo, que es el único que tengo para trabajar).
Ya he aconsejado a mi cliente comprar un nuevo UPS/SAI porque el que usa actualmente tiene sus baterías agotadas, pero me gustaría llegar al origen de este problema porque me resulta extraño que un motor de base de datos sea tan frágil, especialmente cuando tengo la certeza de que el motor simplemente ha estado esperando conexiones en los primeros dos cortes. Esto me hace asumir que probablemente el responsable sea el sistema operativo, ya sea porque no escribe en disco algunos cambios o porque al reiniciar el equipo ejecuta alguna tarea de recuperación.
Si alguien puede aportar algún dato adicional o experiencias relacionadas con este tema estaré muy agradecido.
PRIMERA EDICIÓN: Añado el error que aparece en el log de MySQL (archivo *.err) cuando el servidor es iniciado, ya sea automática o manualmente.
2020-07-14T11:33:32.760738Z 0 [System] [MY-010116] [Server] C:\MySQL\bin\mysqld.exe (mysqld 8.0.20) starting as process 3688
2020-07-14T11:33:33.005279Z 1 [System] [MY-013576] [InnoDB] InnoDB initialization has started.
2020-07-14T11:33:36.476226Z 1 [System] [MY-013577] [InnoDB] InnoDB initialization has ended.
2020-07-14T11:33:37.132226Z 0 [System] [MY-011323] [Server] X Plugin ready for connections. Bind-address: '::' port: 33060
2020-07-14T11:33:37.432990Z 0 [System] [MY-010229] [Server] Starting XA crash recovery...
2020-07-14T11:33:37.450230Z 0 [System] [MY-010232] [Server] XA crash recovery finished.
2020-07-14T11:33:38.404853Z 0 [Warning] [MY-010068] [Server] CA certificate ca.pem is self signed.
2020-07-14T11:33:38.453690Z 0 [System] [MY-010931] [Server] C:\MySQL\bin\mysqld.exe: ready for connections. Version: '8.0.20' socket: '' port: 3306 MySQL Community Server - GPL.
2020-07-14T11:33:38.768328Z 0 [ERROR] [MY-013183] [InnoDB] Assertion failure: dict0dict.cc:3344:for_table || ref_table thread 5308
InnoDB: We intentionally generate a memory trap.
InnoDB: Submit a detailed bug report to http://bugs.mysql.com.
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/8.0/en/forcing-innodb-recovery.html
InnoDB: about forcing recovery.
11:33:39 UTC - mysqld got exception 0x80000003 ;
Most likely, you have hit a bug, but this error can also be caused by malfunctioning hardware.
Thread pointer: 0x1ade21dcdc0
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...
7ff6a9e4de82 mysqld.exe!my_sigabrt_handler()[my_thr_init.cc:372]
7ffdef61cb7d ucrtbase.dll!raise()
7ffdef61db81 ucrtbase.dll!abort()
7ff6aa04fc60 mysqld.exe!ut_dbg_assertion_failed()[ut0dbg.cc:98]
7ff6aa0270cb mysqld.exe!dict_foreign_add_to_cache()[dict0dict.cc:3344]
7ff6aa06662b mysqld.exe!dd_table_load_fk_from_dd()[dict0dd.cc:3407]
7ff6aa065dbf mysqld.exe!dd_table_load_fk()[dict0dd.cc:3455]
7ff6aa05872f mysqld.exe!dd_open_table_one<dd::Table>()[dict0dd.cc:4232]
7ff6aa056a32 mysqld.exe!dd_open_table<dd::Table>()[dict0dd.cc:4370]
7ff6aa066cdb mysqld.exe!dd_table_open_on_dd_obj()[dict0dd.cc:378]
7ff6aa067c0a mysqld.exe!dd_table_open_on_id_low()[dict0dd.cc:525]
7ff6aa067621 mysqld.exe!dd_table_open_on_id()[dict0dd.cc:641]
7ff6aa166955 mysqld.exe!row_purge_parse_undo_rec()[row0purge.cc:913]
7ff6aa16615c mysqld.exe!row_purge()[row0purge.cc:1162]
7ff6aa16809d mysqld.exe!row_purge_step()[row0purge.cc:1239]
7ff6aa15c0af mysqld.exe!que_thr_step()[que0que.cc:923]
7ff6aa15b8c7 mysqld.exe!que_run_threads_low()[que0que.cc:977]
7ff6aa15b668 mysqld.exe!que_run_threads()[que0que.cc:1011]
7ff6a9f374d8 mysqld.exe!trx_purge()[trx0purge.cc:2251]
7ff6a9f2a8a7 mysqld.exe!srv_do_purge()[srv0srv.cc:2896]
7ff6a9f2e458 mysqld.exe!srv_purge_coordinator_thread()[srv0srv.cc:3059]
7ff6a9fba6c5 mysqld.exe!Runnable::operator()<void (__cdecl*)(void)>()[os0thread-create.h:103]
7ff6a9fbaa45 mysqld.exe!std::thread::_Invoke<std::tuple<Runnable,void (__cdecl*)(void)>,0,1>()[thread:40]
7ffdef5d0e82 ucrtbase.dll!_beginthreadex()
7ffdf1bf7bd4 KERNEL32.DLL!BaseThreadInitThunk()
7ffdf234ce51 ntdll.dll!RtlUserThreadStart()
Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0):
Connection ID (thread ID): 0
Status: NOT_KILLED
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.
SEGUNDA EDICIÓN (21/08/2020): Hoy ocurrió otro corte de energía (a 10 minutos de encender el equipo) y MySQL nuevamente no fue capaz de iniciarse. Esta vez, la caché de escritura en disco estaba deshabilitada desde el Administrador de dispositivos de Windows, así que descarto esa configuración como una solución al problema. A continuación adjunto el evento que aparece en el visor de Windows. Espero que mi cliente adquiera un UPS/SAI pronto, aún así cuando tenga la posibilidad evaluaré el comportamiento de otro motor de base de datos (probablemente PostreSQL) en situaciones de este tipo.
Nombre de registro:Application
Origen: Application Error
Fecha: 21/8/2020 8:09:05 a. m.
Id. del evento:1000
Categoría de la tarea:(100)
Nivel: Error
Palabras clave:Clásico
Usuario: No disponible
Equipo: SERVIDOR
Descripción:
Nombre de la aplicación con errores: mysqld.exe, versión: 8.0.20.0, marca de tiempo: 0x5e8338d2
Nombre del módulo con errores: mysqld.exe, versión: 8.0.20.0, marca de tiempo: 0x5e8338d2
Código de excepción: 0x80000003
Desplazamiento de errores: 0x0000000000edde82
Identificador del proceso con errores: 0xdd0
Hora de inicio de la aplicación con errores: 0x01d677ab3a45fe2d
Ruta de acceso de la aplicación con errores: C:\MySQL\bin\mysqld.exe
Ruta de acceso del módulo con errores: C:\MySQL\bin\mysqld.exe
Identificador del informe: 2869874e-040c-411b-8a9b-6527fda42a4b
Nombre completo del paquete con errores:
Identificador de aplicación relativa del paquete con errores: