Bug #80717 Inconsistend logging of InnoDB memcache plugin

• Submitted: 13 Mar 2016 10:01
• Modified: 14 Mar 2016 13:01
• Reporter: Daniël van Eeden (OCA)
• Status: Verified
• Category: MySQL Server: Memcached
• Severity: S3 (Non-critical)
• Version: 5.7.10, 5.7.11
• OS: Any
• CPU Architecture: Any
• Tags: innodb, logging, Memcache

[13 Mar 2016 10:01] Daniël van Eeden

Description:
Some log lines from the memcache plugin seems to be:
- Not using the generic logging API (no timestamp and log level in the message)
- Sometimes 'InnoDB MEMCACHED' is used. Sometimes 'InnoDB_Memcached'.

How to repeat:
The output of grep -i memcache on the error log:
InnoDB MEMCACHED: Memcached uses atomic increment 
2016-03-13T09:08:58.525897Z 2 [Note] Shutting down plugin 'daemon_memcached'
InnoDB MEMCACHED: Memcached uses atomic increment 
InnoDB MEMCACHED: Memcached uses atomic increment 
InnoDB MEMCACHED: Memcached uses atomic increment 
2016-03-13T09:40:49.742761Z 3 [Note] Shutting down plugin 'daemon_memcached'
InnoDB MEMCACHED: Memcached uses atomic increment 
 InnoDB_Memcached: failed to locate value column foo as specified by config table 
2016-03-13T09:41:59.180153Z 3 [Note] Shutting down plugin 'daemon_memcached'
 InnoDB_Memcached: Memcached plugin is still initializing. Can't shut down it.
InnoDB MEMCACHED: Memcached uses atomic increment 
2016-03-13T09:44:25.921765Z 3 [Note] Shutting down plugin 'daemon_memcached'
InnoDB MEMCACHED: Memcached uses atomic increment 

Suggested fix:
Make logging more consistend.
- Ensure log lines have timestamps etc.
- Ensure all lines use the same name for the memcache module

[14 Mar 2016 13:01] MySQL Verification Team

Hello Daniël,

Thank you for the report.

Thanks,
Umesh

[14 Mar 2016 13:02] MySQL Verification Team

-- 5.7.11
- Install/uninstall couple of times and then observe error log

[umshastr@hod03]/export/umesh/server/binaries/mysql-5.7.11: grep -i memcache  80716/log.err
InnoDB MEMCACHED: Memcached uses atomic increment
 InnoDB_Memcached: table 'test/demo_test' cannot be mapped since it contains virtual columns.
2016-03-14T12:58:21.740598Z 5 [Note] Shutting down plugin 'daemon_memcached'
 InnoDB_Memcached: Memcached plugin is still initializing. Can't shut down it.
InnoDB MEMCACHED: Memcached uses atomic increment
 InnoDB_Memcached: table 'test/demo_test' cannot be mapped since it contains virtual columns.