Facebook
TwitterMichael Huber
Basic Pleskian
hello,
I have this problem on two servers. today on a Plesk 12.5 and last week on a Onyx installation (Debian linux)
after a reboot the psa try to start mysql but it look like it runs already. he cannot start the server.. after some seconds the mysql run .. i dont know why :/
root@servername:/var/log# cat syslog|grep mysql|grep 'Jun 19 09:01'|more
Jun 19 09:01:03 servername mysqld_safe: Starting mysqld daemon with databases from /var/lib/mysql
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] /usr/sbin/mysqld (mysqld 10.0.30-MariaDB-0+deb8u2) starting as process 1221 ...
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Using mutexes to ref count buffer pool pages
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: The InnoDB memory heap is disabled
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Compressed tables use zlib 1.2.8
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Using Linux native AIO
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Using CPU crc32 instructions
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Initializing buffer pool, size = 128.0M
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Completed initialization of buffer pool
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Highest supported file format is Barracuda.
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Log scan progressed past the checkpoint lsn 851333675371
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Database was not shutdown normally!
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Starting crash recovery.
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Reading tablespace information from the .ibd files...
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]: 0 processes alive and '/usr/bin/mysqladmin --defaults-file=/etc/mysql/debian.cnf ping' resulted in
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]: #007/usr/bin/mysqladmin: connect to server at 'localhost' failed
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]: error: 'Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (2 "No such file or directory")'
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]: Check that mysqld is running and that the socket: '/var/run/mysqld/mysqld.sock' exists!
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]:
Jun 19 09:01:30 servername mysql[685]: Starting MariaDB database server: mysqld . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . failed!
Jun 19 09:01:30 servername systemd[1]: mysql.service: control process exited, code=exited status=1
Jun 19 09:01:30 servername systemd[1]: Failed to start LSB: Start and stop the mysql database server daemon.
Jun 19 09:01:30 servername systemd[1]: Unit mysql.service entered failed state.
Jun 19 09:01:30 servername psa_service[675]: Job for mysql.service failed. See 'systemctl status mysql.service' and 'journalctl -xn' for details.
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld is stopped
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld (pid 926) is running...
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld (pid 926) is running...
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld (pid 926) is running...
Jun 19 09:01:37 servername psa_service[675]: ERROR 2002 (HY000): Can't connect to local MySQL server
.....
.....
.....
Jun 19 09:01:37 servername psa_service[675]: ERROR 2002 (HY000): Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (2 "No such file or directory")
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld is stopped
Jun 19 09:01:37 servername psa_service[675]: * mysql.service - LSB: Start and stop the mysql database server daemon
Jun 19 09:01:37 servername psa_service[675]: Loaded: loaded (/etc/init.d/mysql)
Jun 19 09:01:37 servername psa_service[675]: Process: 685 ExecStart=/etc/init.d/mysql start (code=exited, status=1/FAILURE)
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:04 servername.fuyonet.net mysqld[1222]: 170619 9:01:04 [Note] InnoDB: Reading tablespace information from the .ibd files...
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]: 0 processes alive and '/usr/bin/mysqladmin --defaults-file=/etc/mysql/debian.cnf ping' resulted in
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]: [61B blob data]
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]: error: 'Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (2 "No such file o
r directory")'
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]: Check that mysqld is running and that the socket: '/var/run/mysqld/mysqld.sock' exists!
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]:
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net mysql[685]: Starting MariaDB database server: mysqld . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . failed!
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net systemd[1]: mysql.service: control process exited, code=exited status=1
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net systemd[1]: Failed to start LSB: Start and stop the mysql database server daemon.
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net systemd[1]: Unit mysql.service entered failed state.
Jun 19 09:01:37 servername psa_service[675]: Some problems are found during start service mysql(see log file: /var/log/plesk/psa_service.log)
Jun 19 09:01:41 servername mysqld: 170619 9:01:41 [Note] InnoDB: Restoring possible half-written data pages
Jun 19 09:01:41 servername mysqld: 170619 9:01:41 [Note] InnoDB: from the doublewrite buffer...
Jun 19 09:01:41 servername mysqld: InnoDB: Doing recovery: scanned up to log sequence number 851333700329
Jun 19 09:01:53 servername mysqld: InnoDB: 11 transaction(s) which must be rolled back or cleaned up
Jun 19 09:01:53 servername mysqld: InnoDB: in total 10 row operations to undo
Jun 19 09:01:53 servername mysqld: InnoDB: Trx id counter is 2935632384
Jun 19 09:01:53 servername mysqld: 170619 9:01:53 [Note] InnoDB: Starting an apply batch of log records to the database...
Jun 19 09:01:53 servername mysqld: InnoDB: Progress in percent: 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63
64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99
Jun 19 09:01:53 servername mysqld: InnoDB: Apply batch completed
Jun 19 09:01:54 servername mysqld: InnoDB: Starting in background the rollback of uncommitted transactions
Jun 19 09:01:54 servername mysqld: 2017-06-19 09:01:54 7f5bd73fe700 InnoDB: Rolling back trx with id 2935631800, 1 rows to undo
Jun 19 09:01:54 servername mysqld: 170619 9:01:54 [Note] InnoDB: 128 rollback segment(s) are active.
Jun 19 09:01:54 servername mysqld: 170619 9:01:54 [Note] InnoDB: Waiting for purge to start
Jun 19 09:01:54 servername mysqld: 170619 9:01:54 [Note] InnoDB: Percona XtraDB (Experts in Database Performance Management) 5.6.35-80.0 started; log sequence number 851333700329
Jun 19 09:01:56 servername mysqld: 170619 9:01:56 [Note] Plugin 'FEEDBACK' is disabled.
Jun 19 09:01:56 servername mysqld: 170619 9:01:56 [Note] Server socket created on IP: '::'.
Jun 19 09:01:57 servername mysqld: 170619 9:01:57 [Warning] 'proxies_priv' entry '@% root@debian8plesk12tpl' ignored in --skip-name-resolve mode.
Jun 19 09:01:59 servername mysqld: 170619 9:01:59 [Note] /usr/sbin/mysqld: ready for connections.
Jun 19 09:01:59 servername mysqld: Version: '10.0.30-MariaDB-0+deb8u2' socket: '/var/run/mysqld/mysqld.sock' port: 3306 (Debian)
I have this problem on two servers. today on a Plesk 12.5 and last week on a Onyx installation (Debian linux)
after a reboot the psa try to start mysql but it look like it runs already. he cannot start the server.. after some seconds the mysql run .. i dont know why :/
root@servername:/var/log# cat syslog|grep mysql|grep 'Jun 19 09:01'|more
Jun 19 09:01:03 servername mysqld_safe: Starting mysqld daemon with databases from /var/lib/mysql
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] /usr/sbin/mysqld (mysqld 10.0.30-MariaDB-0+deb8u2) starting as process 1221 ...
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Using mutexes to ref count buffer pool pages
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: The InnoDB memory heap is disabled
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Compressed tables use zlib 1.2.8
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Using Linux native AIO
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Using CPU crc32 instructions
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Initializing buffer pool, size = 128.0M
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Completed initialization of buffer pool
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Highest supported file format is Barracuda.
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Log scan progressed past the checkpoint lsn 851333675371
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Database was not shutdown normally!
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Starting crash recovery.
Jun 19 09:01:04 servername mysqld: 170619 9:01:04 [Note] InnoDB: Reading tablespace information from the .ibd files...
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]: 0 processes alive and '/usr/bin/mysqladmin --defaults-file=/etc/mysql/debian.cnf ping' resulted in
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]: #007/usr/bin/mysqladmin: connect to server at 'localhost' failed
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]: error: 'Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (2 "No such file or directory")'
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]: Check that mysqld is running and that the socket: '/var/run/mysqld/mysqld.sock' exists!
Jun 19 09:01:30 servername /etc/init.d/mysql[2038]:
Jun 19 09:01:30 servername mysql[685]: Starting MariaDB database server: mysqld . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . failed!
Jun 19 09:01:30 servername systemd[1]: mysql.service: control process exited, code=exited status=1
Jun 19 09:01:30 servername systemd[1]: Failed to start LSB: Start and stop the mysql database server daemon.
Jun 19 09:01:30 servername systemd[1]: Unit mysql.service entered failed state.
Jun 19 09:01:30 servername psa_service[675]: Job for mysql.service failed. See 'systemctl status mysql.service' and 'journalctl -xn' for details.
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld is stopped
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld (pid 926) is running...
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld (pid 926) is running...
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld (pid 926) is running...
Jun 19 09:01:37 servername psa_service[675]: ERROR 2002 (HY000): Can't connect to local MySQL server
.....
.....
.....
Jun 19 09:01:37 servername psa_service[675]: ERROR 2002 (HY000): Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (2 "No such file or directory")
Jun 19 09:01:37 servername psa_service[675]: Trying to start service mysql... /usr/sbin/mysqld is stopped
Jun 19 09:01:37 servername psa_service[675]: * mysql.service - LSB: Start and stop the mysql database server daemon
Jun 19 09:01:37 servername psa_service[675]: Loaded: loaded (/etc/init.d/mysql)
Jun 19 09:01:37 servername psa_service[675]: Process: 685 ExecStart=/etc/init.d/mysql start (code=exited, status=1/FAILURE)
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:04 servername.fuyonet.net mysqld[1222]: 170619 9:01:04 [Note] InnoDB: Reading tablespace information from the .ibd files...
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]: 0 processes alive and '/usr/bin/mysqladmin --defaults-file=/etc/mysql/debian.cnf ping' resulted in
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]: [61B blob data]
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]: error: 'Can't connect to local MySQL server through socket '/var/run/mysqld/mysqld.sock' (2 "No such file o
r directory")'
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]: Check that mysqld is running and that the socket: '/var/run/mysqld/mysqld.sock' exists!
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net /etc/init.d/mysql[2038]:
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net mysql[685]: Starting MariaDB database server: mysqld . . . . . . . . . . . . . . . . . . . . . . . . . . . . . . failed!
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net systemd[1]: mysql.service: control process exited, code=exited status=1
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net systemd[1]: Failed to start LSB: Start and stop the mysql database server daemon.
Jun 19 09:01:37 servername psa_service[675]: Jun 19 09:01:30 servername.fuyonet.net systemd[1]: Unit mysql.service entered failed state.
Jun 19 09:01:37 servername psa_service[675]: Some problems are found during start service mysql(see log file: /var/log/plesk/psa_service.log)
Jun 19 09:01:41 servername mysqld: 170619 9:01:41 [Note] InnoDB: Restoring possible half-written data pages
Jun 19 09:01:41 servername mysqld: 170619 9:01:41 [Note] InnoDB: from the doublewrite buffer...
Jun 19 09:01:41 servername mysqld: InnoDB: Doing recovery: scanned up to log sequence number 851333700329
Jun 19 09:01:53 servername mysqld: InnoDB: 11 transaction(s) which must be rolled back or cleaned up
Jun 19 09:01:53 servername mysqld: InnoDB: in total 10 row operations to undo
Jun 19 09:01:53 servername mysqld: InnoDB: Trx id counter is 2935632384
Jun 19 09:01:53 servername mysqld: 170619 9:01:53 [Note] InnoDB: Starting an apply batch of log records to the database...
Jun 19 09:01:53 servername mysqld: InnoDB: Progress in percent: 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63
64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99
Jun 19 09:01:53 servername mysqld: InnoDB: Apply batch completed
Jun 19 09:01:54 servername mysqld: InnoDB: Starting in background the rollback of uncommitted transactions
Jun 19 09:01:54 servername mysqld: 2017-06-19 09:01:54 7f5bd73fe700 InnoDB: Rolling back trx with id 2935631800, 1 rows to undo
Jun 19 09:01:54 servername mysqld: 170619 9:01:54 [Note] InnoDB: 128 rollback segment(s) are active.
Jun 19 09:01:54 servername mysqld: 170619 9:01:54 [Note] InnoDB: Waiting for purge to start
Jun 19 09:01:54 servername mysqld: 170619 9:01:54 [Note] InnoDB: Percona XtraDB (Experts in Database Performance Management) 5.6.35-80.0 started; log sequence number 851333700329
Jun 19 09:01:56 servername mysqld: 170619 9:01:56 [Note] Plugin 'FEEDBACK' is disabled.
Jun 19 09:01:56 servername mysqld: 170619 9:01:56 [Note] Server socket created on IP: '::'.
Jun 19 09:01:57 servername mysqld: 170619 9:01:57 [Warning] 'proxies_priv' entry '@% root@debian8plesk12tpl' ignored in --skip-name-resolve mode.
Jun 19 09:01:59 servername mysqld: 170619 9:01:59 [Note] /usr/sbin/mysqld: ready for connections.
Jun 19 09:01:59 servername mysqld: Version: '10.0.30-MariaDB-0+deb8u2' socket: '/var/run/mysqld/mysqld.sock' port: 3306 (Debian)
