[debian-mysql] Bug#845648: Bug#845648: mention how to deal with Pre-4.1 password hash found. It is deprecated and will be removed in a future release. Please upgrade it to a new format warning

Tue Nov 29 00:40:23 UTC 2016

Now I got as far as

echo "ALTER USER 'wikiuser'@'localhost.localdomain' IDENTIFIED BY '-[censored]-';"|mysql --user=root --password=-[censored]-; sleep 1
mysql: [Warning] Using a password on the command line interface can be insecure.
ERROR 1396 (HY000) at line 1: Operation ALTER USER failed for 'wikiuser'@'localhost.localdomain'
echo "ALTER USER 'jidanni'@'%' IDENTIFIED BY '-[censored]-';"|mysql --user=root --password=-[censored]-; sleep 1
mysql: [Warning] Using a password on the command line interface can be insecure.
ERROR 1396 (HY000) at line 1: Operation ALTER USER failed for 'jidanni'@'%'

By the way nothing is written to /var/log/mysql*(/*) these days.

But I noticed these:

# journalctl -f&
# service mysql start
11月 29 08:34:42 jidanni2 systemd[1]: Accepted new private connection.
11月 29 08:34:42 jidanni2 systemd[1]: Got message type=method_call sender=n/a destination=org.freedesktop.systemd1 object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=GetUnit cookie=1 reply_cookie=0 error=n/a
11月 29 08:34:42 jidanni2 systemd[1]: Sent message type=method_return sender=n/a destination=n/a object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
11月 29 08:34:42 jidanni2 systemd[1]: Got message type=method_call sender=n/a destination=org.freedesktop.systemd1 object=/org/freedesktop/systemd1/unit/multi_2duser_2etarget interface=org.freedesktop.DBus.Properties member=Get cookie=2 reply_cookie=0 error=n/a
11月 29 08:34:42 jidanni2 systemd[1]: Sent message type=method_return sender=n/a destination=n/a object=n/a interface=n/a member=n/a cookie=2 reply_cookie=2 error=n/a
11月 29 08:34:42 jidanni2 systemd[1]: Got disconnect on private connection.
11月 29 08:34:42 jidanni2 systemd[1]: Accepted new private connection.
11月 29 08:34:42 jidanni2 systemd[1]: Got message type=method_call sender=n/a destination=org.freedesktop.systemd1 object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=ListUnitFilesByPatterns cookie=1 reply_cookie=0 error=n/a
11月 29 08:34:43 jidanni2 systemd[1]: Sent message type=method_return sender=n/a destination=n/a object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
11月 29 08:34:43 jidanni2 systemd[1]: Got disconnect on private connection.
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitRemoved cookie=7043 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitRemoved cookie=7045 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=UnitRemoved cookie=7047 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/unit/mysql_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7049 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/unit/mysql_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7050 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/job/919 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7051 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd[26148]: mysql.service: Executing: /usr/share/mysql/mysql-systemd-start pre
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/unit/mysql_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7052 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/unit/mysql_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7053 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/unit/mysql_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7054 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/unit/mysql_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7055 reply_cookie=0 error=n/a
11月 29 08:34:44 jidanni2 mysqld[26154]: mysql.service: Executing: /usr/sbin/mysqld
11月 29 08:34:44 jidanni2 systemd[26155]: mysql.service: Executing: /usr/share/mysql/mysql-systemd-start post
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:44.817309Z 0 [Warning] Changed limits: max_open_files: 1024 (requested 5000)
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:44.817460Z 0 [Warning] Changed limits: table_open_cache: 431 (requested 2000)
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:45.420414Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:45.509925Z 0 [Note] /usr/sbin/mysqld (mysqld 5.7.16-1) starting as process 26154 ...
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.200143Z 0 [Note] InnoDB: PUNCH HOLE support available
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.201177Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.201213Z 0 [Note] InnoDB: Uses event mutexes
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.201229Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.201243Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.8
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.201256Z 0 [Note] InnoDB: Using Linux native AIO
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.205018Z 0 [Note] InnoDB: Number of pools: 1
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.252485Z 0 [Note] InnoDB: Not using CPU crc32 instructions
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.260041Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.303954Z 0 [Note] InnoDB: Completed initialization of buffer pool
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.343236Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
11月 29 08:34:46 jidanni2 mysqld[26154]: 2016-11-29T00:34:46.593941Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
11月 29 08:34:51 jidanni2 mysqld[26154]: 2016-11-29T00:34:51.036238Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
11月 29 08:34:51 jidanni2 mysqld[26154]: 2016-11-29T00:34:51.037478Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
11月 29 08:34:52 jidanni2 mysqld[26154]: 2016-11-29T00:34:52.290256Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
11月 29 08:34:52 jidanni2 mysqld[26154]: 2016-11-29T00:34:52.294465Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
11月 29 08:34:52 jidanni2 mysqld[26154]: 2016-11-29T00:34:52.295370Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
11月 29 08:34:52 jidanni2 mysqld[26154]: 2016-11-29T00:34:52.322230Z 0 [Note] InnoDB: Waiting for purge to start
11月 29 08:34:52 jidanni2 mysqld[26154]: 2016-11-29T00:34:52.374434Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 6031ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
11月 29 08:34:52 jidanni2 mysqld[26154]: 2016-11-29T00:34:52.395456Z 0 [Note] InnoDB: 5.7.16 started; log sequence number 2173454271
11月 29 08:34:52 jidanni2 mysqld[26154]: 2016-11-29T00:34:52.396344Z 0 [Note] Plugin 'FEDERATED' is disabled.
11月 29 08:34:52 jidanni2 mysqld[26154]: 2016-11-29T00:34:52.399655Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.088030Z 0 [ERROR] Function 'archive' already exists
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.088095Z 0 [Warning] Couldn't load plugin named 'archive' with soname 'ha_archive.so'.
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.088117Z 0 [ERROR] Function 'blackhole' already exists
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.088128Z 0 [Warning] Couldn't load plugin named 'blackhole' with soname 'ha_blackhole.so'.
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.088141Z 0 [ERROR] Function 'federated' already exists
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.088150Z 0 [Warning] Couldn't load plugin named 'federated' with soname 'ha_federated.so'.
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.088163Z 0 [ERROR] Function 'innodb' already exists
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.088173Z 0 [Warning] Couldn't load plugin named 'innodb' with soname 'ha_innodb.so'.
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.470552Z 0 [Warning] Failed to set up SSL because of the following SSL library error: SSL context is not usable without certificate and private key
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.476766Z 0 [Note] Server hostname (bind-address): '*'; port: 3306
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.477545Z 0 [Note] IPv6 is available.
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.478022Z 0 [Note]   - '::' resolves to '::';
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.478588Z 0 [Note] Server socket created on IP: '::'.
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.505960Z 0 [Note] InnoDB: Buffer pool(s) load completed at 161129  8:34:53
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.851444Z 0 [Warning] User entry 'jidanni'@'%' has an empty plugin value. The user will be ignored and no one can login with this user anymore.
11月 29 08:34:53 jidanni2 mysqld[26154]: 2016-11-29T00:34:53.851519Z 0 [Warning] User entry 'wikiuser'@'localhost.localdomain' has an empty plugin value. The user will be ignored and no one can login with this user anymore.
08:34 mysql# 11月 29 08:34:54 jidanni2 mysqld[26154]: 2016-11-29T00:34:54.426922Z 0 [Note] Event Scheduler: Loaded 0 events
11月 29 08:34:54 jidanni2 mysqld[26154]: 2016-11-29T00:34:54.428394Z 0 [Note] /usr/sbin/mysqld: ready for connections.
11月 29 08:34:54 jidanni2 mysqld[26154]: Version: '5.7.16-1'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  (Debian)
11月 29 08:34:54 jidanni2 mysqld[26154]: 2016-11-29T00:34:54.453553Z 2 [Note] Access denied for user 'root'@'localhost' (using password: NO)
11月 29 08:34:54 jidanni2 systemd[1]: Received SIGCHLD from PID 26155 (mysql-systemd-s).
11月 29 08:34:54 jidanni2 systemd[1]: Child 26155 (mysql-systemd-s) died (code=exited, status=0/SUCCESS)
11月 29 08:34:54 jidanni2 systemd[1]: mysql.service: Child 26155 belongs to mysql.service
11月 29 08:34:54 jidanni2 systemd[1]: mysql.service: Control process exited, code=exited status=0
11月 29 08:34:54 jidanni2 systemd[1]: mysql.service: Got final SIGCHLD for state start-post.
11月 29 08:34:54 jidanni2 systemd[1]: mysql.service: Changed start-post -> running
11月 29 08:34:54 jidanni2 systemd[1]: mysql.service: Job mysql.service/start finished, result=done
11月 29 08:34:54 jidanni2 systemd[1]: Started MySQL Community Server.
11月 29 08:34:54 jidanni2 systemd[1]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=12 reply_cookie=0 error=n/a
11月 29 08:34:54 jidanni2 systemd[1]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=7058 reply_cookie=0 error=n/a
11月 29 08:34:54 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=7058 reply_cookie=0 error=n/a
11月 29 08:34:54 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/unit/mysql_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7059 reply_cookie=0 error=n/a
11月 29 08:34:54 jidanni2 systemd-logind[414]: Got message type=signal sender=:1.0 destination=n/a object=/org/freedesktop/systemd1/unit/mysql_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7060 reply_cookie=0 error=n/a

In which we see several other problems too.