Can't start MariaDB after today's update

benzene · July 23, 2015, 9:39pm

Here is the log:

Jul 23 21:33:35 unix mysql-systemd-helper[5350]: 150723 21:33:35 [Note] /usr/sbin/mysqld (mysqld 10.0.20-MariaDB) starting as process 5369 ...
Jul 23 21:33:58 unix sudo[5442]: claus : TTY=pts/1 ; PWD=/home/claus ; USER=root ; COMMAND=/usr/bin/systemctl status mysql.service
Jul 23 21:33:58 unix unknown[5442]: <audit-1123> pid=5442 uid=1000 auid=1000 ses=1 msg='cwd="/home/claus" cmd=73797374656D63746C20737461747573206D7973716C2E73657276696365 terminal=pts/1 res=success'
Jul 23 21:33:58 unix unknown[5442]: <audit-1110> pid=5442 uid=0 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Jul 23 21:33:58 unix sudo[5442]: pam_unix(sudo:session): session opened for user root by claus(uid=0)
Jul 23 21:33:58 unix unknown[5442]: <audit-1105> pid=5442 uid=0 auid=1000 ses=1 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_umask,pam_systemd,pam_env acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Jul 23 21:33:58 unix sudo[5442]: pam_unix(sudo:session): session closed for user root
Jul 23 21:33:58 unix unknown[5442]: <audit-1106> pid=5442 uid=0 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_umask,pam_systemd,pam_env acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Jul 23 21:33:58 unix unknown[5442]: <audit-1104> pid=5442 uid=0 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Jul 23 21:34:32 unix sudo[5554]: claus : TTY=pts/1 ; PWD=/home/claus ; USER=root ; COMMAND=/usr/bin/systemctl start mysql.service -l
Jul 23 21:34:32 unix unknown[5554]: <audit-1123> pid=5554 uid=1000 auid=1000 ses=1 msg='cwd="/home/claus" cmd=73797374656D63746C207374617274206D7973716C2E73657276696365202D6C terminal=pts/1 res=success'
Jul 23 21:34:32 unix unknown[5554]: <audit-1110> pid=5554 uid=0 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Jul 23 21:34:32 unix sudo[5554]: pam_unix(sudo:session): session opened for user root by claus(uid=0)
Jul 23 21:34:32 unix unknown[5554]: <audit-1105> pid=5554 uid=0 auid=1000 ses=1 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_umask,pam_systemd,pam_env acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Jul 23 21:34:32 unix polkitd[895]: Registered Authentication Agent for unix-process:5556:70487 (system bus name :1.92 [/usr/bin/pkttyagent --notify-fd 5 --fallback], object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8)
Jul 23 21:34:37 unix mysql-systemd-helper[5350]: MySQL is still dead
Jul 23 21:34:37 unix mysql-systemd-helper[5350]: MySQL didn't start, can't continue
Jul 23 21:34:37 unix systemd[1]: mysql.service: control process exited, code=exited status=1
Jul 23 21:34:37 unix systemd[1]: Failed to start MySQL server.
-- Subject: Unit mysql.service has failed
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit mysql.service has failed.
-- 
-- The result is failed.
Jul 23 21:34:37 unix systemd[1]: Unit mysql.service entered failed state.
Jul 23 21:34:37 unix systemd[1]: mysql.service failed.
Jul 23 21:34:37 unix unknown[1]: <audit-1130> pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=mysql comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Jul 23 21:34:37 unix polkitd[895]: Unregistered Authentication Agent for unix-process:5556:70487 (system bus name :1.92, object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8) (disconnected from bus)
Jul 23 21:34:37 unix sudo[5554]: pam_unix(sudo:session): session closed for user root
Jul 23 21:34:37 unix unknown[5554]: <audit-1106> pid=5554 uid=0 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_umask,pam_systemd,pam_env acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Jul 23 21:34:37 unix unknown[5554]: <audit-1104> pid=5554 uid=0 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Jul 23 21:34:37 unix systemd[1]: mysql.service holdoff time over, scheduling restart.
Jul 23 21:34:37 unix unknown[1]: <audit-1130> pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=mysql comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jul 23 21:34:37 unix unknown[1]: <audit-1131> pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=mysql comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jul 23 21:34:37 unix systemd[1]: Starting MySQL server...
-- Subject: Unit mysql.service has begun start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit mysql.service has begun starting up.
Jul 23 21:34:37 unix mysql-systemd-helper[5589]: Checking MySQL configuration for obsolete options...
Jul 23 21:34:37 unix mysql-systemd-helper[5589]: Trying to run upgrade of MySQL databases...
Jul 23 21:34:37 unix mysql-systemd-helper[5589]: Stale files from previous upgrade detected, cleaned them up
Jul 23 21:34:37 unix mysql-systemd-helper[5589]: Running protected MySQL...
Jul 23 21:34:37 unix mysql-systemd-helper[5589]: Waiting for MySQL to start
Jul 23 21:34:37 unix mysql-systemd-helper[5589]: 150723 21:34:37 [Warning] option 'group_concat_max_len': unsigned value 0 adjusted to 4
Jul 23 21:34:37 unix mysql-systemd-helper[5589]: 150723 21:34:37 [Note] /usr/sbin/mysqld (mysqld 10.0.20-MariaDB) starting as process 5609 ...

Ideas?

benzene · July 24, 2015, 8:10pm

Finally got this one kinda sorted. I don’t know what changed in MariaDB, but something did, and it totally hosed itself. Removing and re-installing didn’t enable me to start MariaDB either, and deleting /var/lib/mysql didn’t help either. I did eventually manage to start the mysqld as root: “sudo /usr/sbin/mysqld &” and after this I could stop and then re-start mysql normally using “systemctl start mysql.service”. Then I imported the databases again. The users were of course also hosed, and Webmin 1.760 threw a bunch of errors when creating the new users. I’m not convinced all is well.

benzene · July 25, 2015, 3:04pm

Some further digging seems to indicate that the command to start MariaDB:
systemctl start mysql.service
…and the command used by the Yast Services Manager no longer are correct for the Tumbleweed version of MariaDB.

Instead one can to start it like so:
sudo /usr/sbin/mysqld -u mysql &

Can someone confirm that this is a general issue?