MySQL Performance Monitor upgrade to v0.9.2

The mpm cache file is automatically loaded from the mpm agent to the Zabbix service at every run. After the load it is automatically shrinked/deleted again. So there is no need to reload or delete this file.>/p>

In some rare case (with older releases) mpm got a hick-up with too big cache files and then it makes sense to completely delete them.

For your automatic deployment with puppet is should not affect the system at all. Can you elaborate your concerns a bit more?

Hi, Here is an error I got : 28991:2014-05-06 15:24:38.940 - ERR : Can’t locate object method “new” via package “Digest::SHA1” (perhaps you forgot to load “Digest::SHA1”?) at /usr/local/mysql_performance_monitor/lib/InnoDbStatus.pm line 54.

And when I try to install : sudo apt-get install libdigest-sha-perl libdigest-sha1-perl"

None are available.

With Cpan I have this error : openssl-version.c:2:30: fatal error: openssl/opensslv.h: No such file or directory compilation terminated. Failed to build and link a simple executable using OpenSSL No ‘Makefile’ created NANIS/Crypt-SSLeay-0.72.tar.gz /usr/bin/perl Makefile.PL INSTALLDIRS=site – NOT OK Running make test Make had some problems, won’t test Running make install Make had some problems, won’t install Could not read metadata file. Falling back to other methods to determine prerequisites

So my MPM is alive but nothing is retrieved ?

Thanks Oli.

Hello

oh, I see. The installation advice for Ubuntu/Debian has a bug. libdigest-sha1-perl does not exits (any more?). I fixed the installation docu.

But apt-get install libdigest-sha-perl should definitely work.

Oli

Hello,

I still have the same error in the log even after installed libdigest-sha-perl “15116:2014-05-07 10:20:38.922 - ERR : Can’t locate object method “new” via package “Digest::SHA1” (perhaps you forgot to load “Digest::SHA1”?) at /usr/local/mysql_performance_monitor/lib/InnoDbStatus.pm line 54.”

I was looking to the Latest Data on Zabbix and noticed that the lastest data was at 01:30 AM. At this time, I’m in my bed so I didn’t touch it. It seems to work according to the log file. I only found that error : 10243:2014-05-07 11:42:48.960 - ERR : Load of cache file failed. rc=1301

So I deleted the cache file in /tmp/, works for a few minutes and it’s dead again.

Obyis,

Try to load “Digest::SHA1” instead of “Digest::SHA” at the beginning of “InnoDbStatus.pm” file by replacing “use Digest::SHA” with “use Digest::SHA1”. It should work after that.

This seems to be a bug in v0.9.2 which happens when you have deadlock error messages in SHOW ENGINE INNODB STATUS output. (bug #139).

Will be fixed in v0.9.3

Workaround: fix lines 51 and 64 in lib/InnoDbStatus.pm as follows:

-    $sha1 = Digest::SHA1->new;
+    $sha1 = Digest::SHA->new;

or as described in Abdel-Mawlas comment above.

Hello, Thanks, it works well with thoses fixes.

Unfortunately, I discovered that the error : “ERR : Load of cache file failed. rc=1301” is coming with the module “Master”. When I remove it, there are no more errors. So the MPM is stucked because of the module Master. For the moment, it’s running without that module.

Hello, I don’t know if you received my previous post => Module Master stucks the MPM.

The MPM is sometimes unstable. It works for a full day and then no new data. I have to launch it manually : “sudo ./FromDualMySQLagent.pl etc/FromDualMySQLagent.conf”

Hello Obyis

Hmm... In the master module nothing has changed since v0.9.1 if I searched correctly...
It looks like the problem is located in sending the cache file.
Can you please enabled Debug = 4 and send us the output when the error happens?

Thanks,
Oli

5698:2014-05-15 13:55:33.212 - INFO: FromDual Performance Monitor for MySQL (0.9.2) run started. 5698:2014-05-15 13:55:33.226 - INFO: FromDualMySQLagent::setAgentLock 5698:2014-05-15 13:55:33.227 - INFO: Read configuration from /usr/local/mysql_performance_monitor/etc/FromDualMySQLagent.conf 5698:2014-05-15 13:55:33.227 - INFO: Sections found: default, hostnameX 5698:2014-05-15 13:55:33.227 - INFO: Reading default section 5698:2014-05-15 13:55:33.227 - DBG : ZabbixServer - ipAddrX 5698:2014-05-15 13:55:33.227 - DBG : Modules - process mysql myisam innodb 5698:2014-05-15 13:55:33.227 - DBG : LogFile - /var/log/zabbix/FromDualMySQLagent.log 5698:2014-05-15 13:55:33.227 - DBG : Type - mysqld 5698:2014-05-15 13:55:33.227 - DBG : Password - ************ 5698:2014-05-15 13:55:33.227 - DBG : Disabled - false 5698:2014-05-15 13:55:33.227 - DBG : Username - db_user 5698:2014-05-15 13:55:33.227 - DBG : Debug - 4 5698:2014-05-15 13:55:33.227 - DBG : TimeShift - 0 5698:2014-05-15 13:55:33.227 - DBG : Socket - /var/lib/mysql/mysql.sock 5698:2014-05-15 13:55:33.227 - DBG : CacheFileBase - /tmp/FromDualAgentCache 5698:2014-05-15 13:55:33.227 - DBG : AgentLockFile - /tmp/FromDualMySQLagent.lock 5698:2014-05-15 13:55:33.227 - DBG : MysqlPort - 3306 5698:2014-05-15 13:55:33.228 - DBG : Hash - 5698:2014-05-15 13:55:33.228 - DBG : ZabbixServerPort - 10051 5698:2014-05-15 13:55:33.228 - DBG : Methode - https 5698:2014-05-15 13:55:33.228 - DBG : ClusterLog - /var/lib/mysql-cluster/ndb_1_cluster.log 5698:2014-05-15 13:55:33.228 - DBG : MaaS - off 5698:2014-05-15 13:55:33.228 - DBG : PidFile - 5698:2014-05-15 13:55:33.228 - DBG : Url - https://support.fromdual.com/maas/receiver.php 5698:2014-05-15 13:55:33.233 - DBG : MysqlHost - 127.0.0.1 5698:2014-05-15 13:55:33.233 - INFO: Processing section default now 5698:2014-05-15 13:55:33.233 - INFO: Processing section hostnameX now 5698:2014-05-15 13:55:33.234 - DBG : The defaults parameters of this configuration file are: 5698:2014-05-15 13:55:33.234 - DBG : ZabbixServer - ipAddrX 5698:2014-05-15 13:55:33.234 - DBG : Modules - process mysql myisam innodb 5698:2014-05-15 13:55:33.234 - DBG : LogFile - /var/log/zabbix/FromDualMySQLagent.log 5698:2014-05-15 13:55:33.234 - DBG : Type - mysqld 5698:2014-05-15 13:55:33.234 - DBG : Password - ************ 5698:2014-05-15 13:55:33.234 - DBG : Disabled - false 5698:2014-05-15 13:55:33.234 - DBG : Username - db_user 5698:2014-05-15 13:55:33.234 - DBG : Debug - 4 5698:2014-05-15 13:55:33.234 - DBG : TimeShift - 0 5698:2014-05-15 13:55:33.234 - DBG : Socket - /var/lib/mysql/mysql.sock 5698:2014-05-15 13:55:33.234 - DBG : MysqlPort - 3306 5698:2014-05-15 13:55:33.234 - DBG : AgentLockFile - /tmp/FromDualMySQLagent.lock 5698:2014-05-15 13:55:33.234 - DBG : CacheFileBase - /tmp/FromDualAgentCache 5698:2014-05-15 13:55:33.235 - DBG : Hash - 5698:2014-05-15 13:55:33.235 - DBG : ZabbixServerPort - 10051 5698:2014-05-15 13:55:33.235 - DBG : Methode - https 5698:2014-05-15 13:55:33.235 - DBG : ClusterLog - /var/lib/mysql-cluster/ndb_1_cluster.log 5698:2014-05-15 13:55:33.235 - DBG : MaaS - off 5698:2014-05-15 13:55:33.235 - DBG : PidFile - 5698:2014-05-15 13:55:33.235 - DBG : Url - https://support.fromdual.com/maas/receiver.php 5698:2014-05-15 13:55:33.235 - DBG : MysqlHost - 127.0.0.1 5698:2014-05-15 13:55:33.235 - DBG : The parameters of section hostnameX are: 5698:2014-05-15 13:55:33.235 - DBG : MysqlPort - 3306 5698:2014-05-15 13:55:33.235 - DBG : Type - mysqld 5698:2014-05-15 13:55:33.235 - DBG : Modules - mpm server process mysql innodb slave 5698:2014-05-15 13:55:33.235 - DBG : The combined (default + section) parameters for section hostnameX are: 5698:2014-05-15 13:55:33.235 - DBG : ZabbixServer - ipAddrX 5698:2014-05-15 13:55:33.236 - DBG : Modules - mpm server process mysql innodb slave 5698:2014-05-15 13:55:33.236 - DBG : LogFile - /var/log/zabbix/FromDualMySQLagent.log 5698:2014-05-15 13:55:33.236 - DBG : Type - mysqld 5698:2014-05-15 13:55:33.236 - DBG : Password - ************ 5698:2014-05-15 13:55:33.236 - DBG : Disabled - false 5698:2014-05-15 13:55:33.236 - DBG : Username - db_user 5698:2014-05-15 13:55:33.236 - DBG : Debug - 4 5698:2014-05-15 13:55:33.236 - DBG : TimeShift - 0 5698:2014-05-15 13:55:33.236 - DBG : Socket - /var/lib/mysql/mysql.sock 5698:2014-05-15 13:55:33.236 - DBG : CacheFile - /tmp/FromDualAgentCache.hostnameX.cache 5698:2014-05-15 13:55:33.236 - DBG : MysqlPort - 3306 5698:2014-05-15 13:55:33.236 - DBG : AgentLockFile - /tmp/FromDualMySQLagent.lock 5698:2014-05-15 13:55:33.236 - DBG : CacheFileBase - /tmp/FromDualAgentCache 5698:2014-05-15 13:55:33.236 - DBG : Hostname - hostnameX 5698:2014-05-15 13:55:33.236 - DBG : Hash - 5698:2014-05-15 13:55:33.236 - DBG : ZabbixServerPort - 10051 5698:2014-05-15 13:55:33.236 - DBG : Methode - https 5698:2014-05-15 13:55:33.237 - DBG : ClusterLog - /var/lib/mysql-cluster/ndb_1_cluster.log 5698:2014-05-15 13:55:33.237 - DBG : MaaS - off 5698:2014-05-15 13:55:33.237 - DBG : PidFile - 5698:2014-05-15 13:55:33.237 - DBG : Url - https://support.fromdual.com/maas/receiver.php 5698:2014-05-15 13:55:33.237 - DBG : MysqlHost - 127.0.0.1 5698:2014-05-15 13:55:33.237 - INFO: Modules for section hostnameX: mpm server process mysql innodb slave 5698:2014-05-15 13:55:33.237 - INFO: Processing module mpm for section hostnameX now… 5698:2014-05-15 13:55:33.324 - INFO: FromDualMySQLmpm::processMpmInformation 5698:2014-05-15 13:55:33.325 - INFO: sendData::sendData 5698:2014-05-15 13:55:33.325 - INFO: writeDataToCacheFile::writeDataToCacheFile 5698:2014-05-15 13:55:33.325 - DBG : hostnameX FromDual.MySQL.mpm.mpm_version 1400154933 0.9.2 5698:2014-05-15 13:55:33.325 - DBG : hostnameX FromDual.MySQL.mpm.mpm_mr_version 1400154933 000902 5698:2014-05-15 13:55:33.325 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 5698:2014-05-15 13:55:33.325 - DBG : Check connection to zabbix server. 5698:2014-05-15 13:55:33.326 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [5701]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000025”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000025” sent: 1; skipped: 0; total: 1 5698:2014-05-15 13:55:33.345 - DBG : (ret=512). 5698:2014-05-15 13:55:33.345 - DBG : Partial success (ret=512 / rc=0).

5698:2014-05-15 13:55:33.345 - DBG : Connection to zabbix server seems OK. 5698:2014-05-15 13:55:33.345 - INFO: sendCachedData::sendCachedData 5698:2014-05-15 13:55:33.345 - DBG : Sending cache file to zabbix server. 5698:2014-05-15 13:55:33.345 - DBG : Cache file found. 5698:2014-05-15 13:55:33.346 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [5704]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 2; total: 2; seconds spent: 0.000040”}] info from server: “processed: 0; failed: 2; total: 2; seconds spent: 0.000040” sent: 2; skipped: 0; total: 2 5698:2014-05-15 13:55:33.355 - DBG : (ret=512 / rc=1301). 5698:2014-05-15 13:55:33.355 - INFO: FromDualMySQLagent::clearCacheFile 5698:2014-05-15 13:55:33.356 - INFO: Processing module mpm for secion hostnameX finished. 5698:2014-05-15 13:55:33.356 - INFO: Processing module server for section hostnameX now… 5698:2014-05-15 13:55:33.367 - INFO: FromDualMySQLserver::processServerInformation 5698:2014-05-15 13:55:33.367 - INFO: FromDualMySQLserver::getDiskStatsLinux 5698:2014-05-15 13:55:33.369 - INFO: FromDualMySQLserver::getMemoryInformationLinux 5698:2014-05-15 13:55:33.369 - INFO: FromDualMySQLserver::getDiskSpaceInformationLinux 5698:2014-05-15 13:55:33.432 - INFO: FromDualMySQLserver::getCpuInformationLinux 5698:2014-05-15 13:55:34.433 - INFO: FromDualMySQLserver::getCpuInformationLinux 5698:2014-05-15 13:55:34.434 - INFO: FromDualMySQLserver::getNetworkInformationLinux 5698:2014-05-15 13:55:34.435 - INFO: sendData::sendData 5698:2014-05-15 13:55:34.435 - INFO: writeDataToCacheFile::writeDataToCacheFile —————- DATA TRUNCATE FOR PRIVACY ————— 5698:2014-05-15 13:55:34.481 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 5698:2014-05-15 13:55:34.481 - DBG : Check connection to zabbix server. 5698:2014-05-15 13:55:34.484 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [5714]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000025”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000025” sent: 1; skipped: 0; total: 1 5698:2014-05-15 13:55:34.493 - DBG : (ret=512). 5698:2014-05-15 13:55:34.493 - DBG : Partial success (ret=512 / rc=0).

5698:2014-05-15 13:55:34.493 - DBG : Connection to zabbix server seems OK. 5698:2014-05-15 13:55:34.493 - INFO: sendCachedData::sendCachedData 5698:2014-05-15 13:55:34.493 - DBG : Sending cache file to zabbix server. 5698:2014-05-15 13:55:34.493 - DBG : Cache file found. 5698:2014-05-15 13:55:34.494 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [5717]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 27; failed: 223; total: 250; seconds spent: 0.003408”}] info from server: “processed: 27; failed: 223; total: 250; seconds spent: 0.003408” zabbix_sender [5718]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 7; failed: 75; total: 82; seconds spent: 0.001617”}] info from server: “processed: 7; failed: 75; total: 82; seconds spent: 0.001617” sent: 332; skipped: 0; total: 332 5698:2014-05-15 13:55:34.512 - DBG : (ret=512 / rc=1301). 5698:2014-05-15 13:55:34.512 - INFO: FromDualMySQLagent::clearCacheFile 5698:2014-05-15 13:55:34.513 - INFO: Processing module server for secion hostnameX finished. 5698:2014-05-15 13:55:34.513 - INFO: Processing module process for section hostnameX now… 5698:2014-05-15 13:55:34.560 - INFO: FromDualMySQLprocess::processProcessInformation 5698:2014-05-15 13:55:34.561 - ERR : PID file is not defined or empty. 5698:2014-05-15 13:55:34.561 - INFO: Processing module process for secion hostnameX finished. 5698:2014-05-15 13:55:34.561 - INFO: Processing module mysql for section hostnameX now… 5698:2014-05-15 13:55:34.570 - INFO: FromDualMySQLmysql::processMysqlInformation 5698:2014-05-15 13:55:34.861 - INFO: FromDualMySQLagent::getGlobalVariables —————- DATA TRUNCATE FOR PRIVACY ————— 5698:2014-05-15 13:55:35.097 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 5698:2014-05-15 13:55:35.097 - DBG : Check connection to zabbix server. 5698:2014-05-15 13:55:35.098 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [5722]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000026”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000026” sent: 1; skipped: 0; total: 1 5698:2014-05-15 13:55:35.106 - DBG : (ret=512). 5698:2014-05-15 13:55:35.106 - DBG : Partial success (ret=512 / rc=0).

5698:2014-05-15 13:55:35.107 - DBG : Connection to zabbix server seems OK. 5698:2014-05-15 13:55:35.107 - INFO: sendCachedData::sendCachedData 5698:2014-05-15 13:55:35.107 - DBG : Sending cache file to zabbix server. 5698:2014-05-15 13:55:35.107 - DBG : Cache file found. 5698:2014-05-15 13:55:35.107 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [5725]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 12; failed: 21; total: 33; seconds spent: 0.000484”}] info from server: “processed: 12; failed: 21; total: 33; seconds spent: 0.000484” sent: 33; skipped: 0; total: 33 5698:2014-05-15 13:55:35.116 - DBG : (ret=512 / rc=1301). 5698:2014-05-15 13:55:35.116 - INFO: FromDualMySQLagent::clearCacheFile 5698:2014-05-15 13:55:35.116 - INFO: sendData::sendData 5698:2014-05-15 13:55:35.116 - INFO: writeDataToCacheFile::writeDataToCacheFile —————- DATA TRUNCATE FOR PRIVACY ————— 5698:2014-05-15 13:55:35.128 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 5698:2014-05-15 13:55:35.128 - DBG : Check connection to zabbix server. 5698:2014-05-15 13:55:35.129 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [5728]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000032”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000032” sent: 1; skipped: 0; total: 1 5698:2014-05-15 13:55:35.137 - DBG : (ret=512). 5698:2014-05-15 13:55:35.137 - DBG : Partial success (ret=512 / rc=0).

5698:2014-05-15 13:55:35.137 - DBG : Connection to zabbix server seems OK. 5698:2014-05-15 13:55:35.137 - INFO: sendCachedData::sendCachedData 5698:2014-05-15 13:55:35.137 - DBG : Sending cache file to zabbix server. 5698:2014-05-15 13:55:35.137 - DBG : Cache file found. 5698:2014-05-15 13:55:35.138 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [5731]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 51; failed: 20; total: 71; seconds spent: 0.000991”}] info from server: “processed: 51; failed: 20; total: 71; seconds spent: 0.000991” sent: 71; skipped: 0; total: 71 5698:2014-05-15 13:55:35.146 - DBG : (ret=512 / rc=1301). 5698:2014-05-15 13:55:35.147 - INFO: FromDualMySQLagent::clearCacheFile 5698:2014-05-15 13:55:35.148 - INFO: Processing module mysql for secion hostnameX finished. 5698:2014-05-15 13:55:35.148 - INFO: Processing module innodb for section hostnameX now… 5698:2014-05-15 13:55:35.294 - INFO: FromDualMySQLagent::removeAgentLock 5698:2014-05-15 13:55:35.295 - INFO: FromDual Performance Monitor for MySQL run finshed (rc=1).

It looks like you did not enable the master module: INFO: Modules for section hostnameX: mpm server process mysql innodb slave

Hello, This debug is when the MPM stops after some hours of work. I’ll send the debug for the module master after.

I see… The only part in the code where exit code 1 can be reached is the signal TERM trap. Either somebody killed the process or mpm killed a long running former mpm process automatically. How do you start mpm agent?

Thanks to a UserParameter I guess:

UserParameter=FromDual.MySQL.check,/usr/local/mysql_performance_monitor/FromDualMySQL agent.pl /usr/local/mysql_performance_monitor/etc/FromDualMySQLagent.conf

Hello,

Here is the log when I’m adding the module master. My latest data are at 10:40 on Zabbix UI.

This line could be interesting :

4971:2014-05-20 10:50:18.155 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [4998]: Warning: [line 5] ‘Key value’ required Sending failed.

– FULL LOG –

4971:2014-05-20 10:50:16.677 - INFO: FromDual Performance Monitor for MySQL (0.9.2) run started. 4971:2014-05-20 10:50:16.677 - INFO: FromDualMySQLagent::setAgentLock 4971:2014-05-20 10:50:16.677 - INFO: Read configuration from /usr/local/mysql_performance_monitor/etc/FromDualMySQLagent.conf 4971:2014-05-20 10:50:16.677 - INFO: Sections found: default, hostnameX 4971:2014-05-20 10:50:16.677 - INFO: Reading default section 4971:2014-05-20 10:50:16.677 - DBG : ZabbixServer - ipAddrX 4971:2014-05-20 10:50:16.677 - DBG : Modules - process mysql myisam innodb 4971:2014-05-20 10:50:16.678 - DBG : LogFile - /var/log/zabbix/FromDualMySQLagent.log 4971:2014-05-20 10:50:16.678 - DBG : Type - mysqld 4971:2014-05-20 10:50:16.678 - DBG : Password - ************ 4971:2014-05-20 10:50:16.678 - DBG : Disabled - false 4971:2014-05-20 10:50:16.678 - DBG : Username - db_user 4971:2014-05-20 10:50:16.678 - DBG : Debug - 4 4971:2014-05-20 10:50:16.678 - DBG : TimeShift - 0 4971:2014-05-20 10:50:16.678 - DBG : Socket - /var/lib/mysql/mysql.sock 4971:2014-05-20 10:50:16.678 - DBG : CacheFileBase - /tmp/FromDualAgentCache 4971:2014-05-20 10:50:16.678 - DBG : AgentLockFile - /tmp/FromDualMySQLagent.lock 4971:2014-05-20 10:50:16.678 - DBG : MysqlPort - 3306 4971:2014-05-20 10:50:16.678 - DBG : Hash - 4971:2014-05-20 10:50:16.679 - DBG : ZabbixServerPort - 10051 4971:2014-05-20 10:50:16.679 - DBG : Methode - https 4971:2014-05-20 10:50:16.679 - DBG : ClusterLog - /var/lib/mysql-cluster/ndb_1_cluster.log 4971:2014-05-20 10:50:16.679 - DBG : MaaS - off 4971:2014-05-20 10:50:16.679 - DBG : PidFile - 4971:2014-05-20 10:50:16.679 - DBG : Url - https://support.fromdual.com/maas/receiver.php 4971:2014-05-20 10:50:16.679 - DBG : MysqlHost - 127.0.0.1 4971:2014-05-20 10:50:16.679 - INFO: Processing section default now 4971:2014-05-20 10:50:16.679 - INFO: Processing section hostnameX now 4971:2014-05-20 10:50:16.679 - DBG : The defaults parameters of this configuration file are: 4971:2014-05-20 10:50:16.680 - DBG : ZabbixServer - ipAddrX 4971:2014-05-20 10:50:16.680 - DBG : Modules - process mysql myisam innodb 4971:2014-05-20 10:50:16.680 - DBG : LogFile - /var/log/zabbix/FromDualMySQLagent.log 4971:2014-05-20 10:50:16.680 - DBG : Type - mysqld 4971:2014-05-20 10:50:16.680 - DBG : Password - ************ 4971:2014-05-20 10:50:16.680 - DBG : Disabled - false 4971:2014-05-20 10:50:16.680 - DBG : Username - db_user 4971:2014-05-20 10:50:16.680 - DBG : Debug - 4 4971:2014-05-20 10:50:16.680 - DBG : TimeShift - 0 4971:2014-05-20 10:50:16.680 - DBG : Socket - /var/lib/mysql/mysql.sock 4971:2014-05-20 10:50:16.680 - DBG : MysqlPort - 3306 4971:2014-05-20 10:50:16.680 - DBG : AgentLockFile - /tmp/FromDualMySQLagent.lock 4971:2014-05-20 10:50:16.680 - DBG : CacheFileBase - /tmp/FromDualAgentCache 4971:2014-05-20 10:50:16.681 - DBG : Hash - 4971:2014-05-20 10:50:16.681 - DBG : ZabbixServerPort - 10051 4971:2014-05-20 10:50:16.681 - DBG : Methode - https 4971:2014-05-20 10:50:16.681 - DBG : ClusterLog - /var/lib/mysql-cluster/ndb_1_cluster.log 4971:2014-05-20 10:50:16.681 - DBG : MaaS - off 4971:2014-05-20 10:50:16.681 - DBG : PidFile - 4971:2014-05-20 10:50:16.681 - DBG : Url - https://support.fromdual.com/maas/receiver.php 4971:2014-05-20 10:50:16.681 - DBG : MysqlHost - 127.0.0.1 4971:2014-05-20 10:50:16.681 - DBG : The parameters of section hostnameX are: 4971:2014-05-20 10:50:16.682 - DBG : MysqlPort - 3306 4971:2014-05-20 10:50:16.682 - DBG : Type - mysqld 4971:2014-05-20 10:50:16.682 - DBG : Modules - mpm server process mysql innodb master 4971:2014-05-20 10:50:16.682 - DBG : The combined (default + section) parameters for section hostnameX are: 4971:2014-05-20 10:50:16.682 - DBG : ZabbixServer - ipAddrX 4971:2014-05-20 10:50:16.682 - DBG : Modules - mpm server process mysql innodb master 4971:2014-05-20 10:50:16.682 - DBG : LogFile - /var/log/zabbix/FromDualMySQLagent.log 4971:2014-05-20 10:50:16.682 - DBG : Type - mysqld 4971:2014-05-20 10:50:16.682 - DBG : Password - ************ 4971:2014-05-20 10:50:16.682 - DBG : Disabled - false 4971:2014-05-20 10:50:16.682 - DBG : Username - db_user 4971:2014-05-20 10:50:16.683 - DBG : Debug - 4 4971:2014-05-20 10:50:16.683 - DBG : TimeShift - 0 4971:2014-05-20 10:50:16.683 - DBG : Socket - /var/lib/mysql/mysql.sock 4971:2014-05-20 10:50:16.683 - DBG : CacheFile - /tmp/FromDualAgentCache.hostnameX.cache 4971:2014-05-20 10:50:16.683 - DBG : MysqlPort - 3306 4971:2014-05-20 10:50:16.683 - DBG : AgentLockFile - /tmp/FromDualMySQLagent.lock 4971:2014-05-20 10:50:16.683 - DBG : CacheFileBase - /tmp/FromDualAgentCache 4971:2014-05-20 10:50:16.683 - DBG : Hostname - hostnameX 4971:2014-05-20 10:50:16.683 - DBG : Hash - 4971:2014-05-20 10:50:16.683 - DBG : ZabbixServerPort - 10051 4971:2014-05-20 10:50:16.683 - DBG : Methode - https 4971:2014-05-20 10:50:16.683 - DBG : ClusterLog - /var/lib/mysql-cluster/ndb_1_cluster.log 4971:2014-05-20 10:50:16.683 - DBG : MaaS - off 4971:2014-05-20 10:50:16.684 - DBG : PidFile - 4971:2014-05-20 10:50:16.684 - DBG : Url - https://support.fromdual.com/maas/receiver.php 4971:2014-05-20 10:50:16.684 - DBG : MysqlHost - 127.0.0.1 4971:2014-05-20 10:50:16.684 - INFO: Modules for section hostnameX: mpm server process mysql innodb master 4971:2014-05-20 10:50:16.684 - INFO: Processing module mpm for section hostnameX now… 4971:2014-05-20 10:50:16.713 - INFO: FromDualMySQLmpm::processMpmInformation 4971:2014-05-20 10:50:16.713 - INFO: sendData::sendData 4971:2014-05-20 10:50:16.713 - INFO: writeDataToCacheFile::writeDataToCacheFile 4971:2014-05-20 10:50:16.713 - DBG : hostnameX FromDual.MySQL.mpm.mpm_version 1400575816 0.9.2 4971:2014-05-20 10:50:16.714 - DBG : hostnameX FromDual.MySQL.mpm.mpm_mr_version 1400575816 000902 4971:2014-05-20 10:50:16.714 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:16.714 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:16.714 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [4974]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000031”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000031” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:16.726 - DBG : (ret=512). 4971:2014-05-20 10:50:16.726 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:16.727 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:16.727 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:16.727 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:16.727 - DBG : Cache file found. 4971:2014-05-20 10:50:16.727 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [4976]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:16.733 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:16.733 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:16.733 - INFO: Processing module mpm for secion hostnameX finished. 4971:2014-05-20 10:50:16.733 - INFO: Processing module server for section hostnameX now… 4971:2014-05-20 10:50:16.740 - INFO: FromDualMySQLserver::processServerInformation 4971:2014-05-20 10:50:16.740 - INFO: FromDualMySQLserver::getDiskStatsLinux 4971:2014-05-20 10:50:16.742 - INFO: FromDualMySQLserver::getMemoryInformationLinux 4971:2014-05-20 10:50:16.743 - INFO: FromDualMySQLserver::getDiskSpaceInformationLinux 4971:2014-05-20 10:50:16.749 - INFO: FromDualMySQLserver::getCpuInformationLinux 4971:2014-05-20 10:50:17.750 - INFO: FromDualMySQLserver::getCpuInformationLinux 4971:2014-05-20 10:50:17.751 - INFO: FromDualMySQLserver::getNetworkInformationLinux 4971:2014-05-20 10:50:17.752 - INFO: sendData::sendData 4971:2014-05-20 10:50:17.752 - INFO: writeDataToCacheFile::writeDataToCacheFile ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:17.809 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:17.809 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:17.810 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [4980]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000026”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000026” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:17.825 - DBG : (ret=512). 4971:2014-05-20 10:50:17.825 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:17.825 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:17.826 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:17.826 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:17.826 - DBG : Cache file found. 4971:2014-05-20 10:50:17.827 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [4982]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:17.832 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:17.832 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:17.833 - INFO: Processing module server for secion hostnameX finished. 4971:2014-05-20 10:50:17.833 - INFO: Processing module process for section hostnameX now… 4971:2014-05-20 10:50:17.842 - INFO: FromDualMySQLprocess::processProcessInformation 4971:2014-05-20 10:50:17.842 - ERR : PID file is not defined or empty. 4971:2014-05-20 10:50:17.842 - INFO: Processing module process for secion hostnameX finished. 4971:2014-05-20 10:50:17.842 - INFO: Processing module mysql for section hostnameX now… 4971:2014-05-20 10:50:17.844 - INFO: FromDualMySQLmysql::processMysqlInformation 4971:2014-05-20 10:50:17.906 - INFO: FromDualMySQLagent::getGlobalVariables 4971:2014-05-20 10:50:17.917 - DBG : Global Variables: ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:17.978 - INFO: sendData::sendData 4971:2014-05-20 10:50:17.978 - INFO: writeDataToCacheFile::writeDataToCacheFile ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:17.983 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:17.983 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:17.984 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [4986]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000022”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000022” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:17.994 - DBG : (ret=512). 4971:2014-05-20 10:50:17.994 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:17.994 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:17.994 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:17.994 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:17.994 - DBG : Cache file found. 4971:2014-05-20 10:50:17.995 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [4988]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:18.001 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:18.001 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:18.001 - INFO: sendData::sendData 4971:2014-05-20 10:50:18.001 - INFO: writeDataToCacheFile::writeDataToCacheFile ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.013 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:18.014 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:18.014 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [4991]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000021”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000021” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:18.024 - DBG : (ret=512). 4971:2014-05-20 10:50:18.024 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:18.024 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:18.024 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:18.024 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:18.024 - DBG : Cache file found. 4971:2014-05-20 10:50:18.025 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [4993]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:18.031 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:18.031 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:18.032 - INFO: Processing module mysql for secion hostnameX finished. 4971:2014-05-20 10:50:18.033 - INFO: Processing module innodb for section hostnameX now… 4971:2014-05-20 10:50:18.059 - INFO: FromDualMySQLinnodb::processInnodbInformation 4971:2014-05-20 10:50:18.061 - INFO: FromDualMySQLagent::getGlobalVariables 4971:2014-05-20 10:50:18.073 - DBG : Global Variables: ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.098 - INFO: FromDualMySQLagent::getGlobalStatus 4971:2014-05-20 10:50:18.108 - DBG : Global Status: ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.133 - INFO: InnoDbStatus::getInnoDbStatus 4971:2014-05-20 10:50:18.135 - INFO: InnoDbStatus::parseInnoDbStatus 4971:2014-05-20 10:50:18.136 - DBG : No Deadlock message. 4971:2014-05-20 10:50:18.136 - DBG : No Deadlock message. 4971:2014-05-20 10:50:18.139 - DBG : InnoDB Status: ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.141 - INFO: sendData::sendData 4971:2014-05-20 10:50:18.141 - INFO: writeDataToCacheFile::writeDataToCacheFile ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.143 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:18.143 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:18.144 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [4996]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000024”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000024” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:18.153 - DBG : (ret=512). 4971:2014-05-20 10:50:18.154 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:18.154 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:18.154 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:18.154 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:18.154 - DBG : Cache file found. 4971:2014-05-20 10:50:18.155 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [4998]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:18.161 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:18.161 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:18.161 - INFO: sendData::sendData 4971:2014-05-20 10:50:18.161 - INFO: writeDataToCacheFile::writeDataToCacheFile ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.166 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:18.166 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:18.167 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [5001]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000021”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000021” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:18.176 - DBG : (ret=512). 4971:2014-05-20 10:50:18.176 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:18.177 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:18.177 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:18.177 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:18.177 - DBG : Cache file found. 4971:2014-05-20 10:50:18.178 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [5003]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:18.184 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:18.184 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:18.184 - INFO: sendData::sendData 4971:2014-05-20 10:50:18.184 - INFO: writeDataToCacheFile::writeDataToCacheFile ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.187 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:18.187 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:18.188 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [5006]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000022”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000022” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:18.198 - DBG : (ret=512). 4971:2014-05-20 10:50:18.198 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:18.198 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:18.198 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:18.198 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:18.198 - DBG : Cache file found. 4971:2014-05-20 10:50:18.199 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [5008]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:18.205 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:18.205 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:18.206 - INFO: Processing module innodb for secion hostnameX finished. 4971:2014-05-20 10:50:18.207 - INFO: Processing module master for section hostnameX now… 4971:2014-05-20 10:50:18.210 - INFO: FromDualMySQLmaster::processMasterInformation 4971:2014-05-20 10:50:18.212 - INFO: FromDualMySQLmaster::getMasterStatus 4971:2014-05-20 10:50:18.212 - DBG : Master status: ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.214 - INFO: FromDualMySQLmaster::getBinaryLogs 4971:2014-05-20 10:50:18.215 - DBG : Master status: ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.216 - INFO: FromDualMySQLmaster::getSlaveCount ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.218 - INFO: FromDualMySQLagent::getGlobalVariables 4971:2014-05-20 10:50:18.229 - DBG : Global Variables: ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.261 - INFO: sendData::sendData 4971:2014-05-20 10:50:18.261 - INFO: writeDataToCacheFile::writeDataToCacheFile ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.262 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:18.262 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:18.263 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [5011]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000023”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000023” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:18.274 - DBG : (ret=512). 4971:2014-05-20 10:50:18.274 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:18.274 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:18.274 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:18.274 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:18.274 - DBG : Cache file found. 4971:2014-05-20 10:50:18.275 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [5013]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:18.281 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:18.281 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:18.281 - INFO: sendData::sendData 4971:2014-05-20 10:50:18.282 - INFO: writeDataToCacheFile::writeDataToCacheFile ———— DATA TRUNCATE PRIVACY ————— 4971:2014-05-20 10:50:18.284 - INFO: FromDualMySQLagent::checkConnectionToZabbixServer 4971:2014-05-20 10:50:18.284 - DBG : Check connection to zabbix server. 4971:2014-05-20 10:50:18.285 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –host ‘hostnameX’ –key FromDual.server.check –value ‘1’ -vv zabbix_sender [5016]: DEBUG: answer [{ “response”:“success”, “info”:“processed: 0; failed: 1; total: 1; seconds spent: 0.000020”}] info from server: “processed: 0; failed: 1; total: 1; seconds spent: 0.000020” sent: 1; skipped: 0; total: 1 4971:2014-05-20 10:50:18.293 - DBG : (ret=512). 4971:2014-05-20 10:50:18.293 - DBG : Partial success (ret=512 / rc=0).

4971:2014-05-20 10:50:18.293 - DBG : Connection to zabbix server seems OK. 4971:2014-05-20 10:50:18.293 - INFO: sendCachedData::sendCachedData 4971:2014-05-20 10:50:18.293 - DBG : Sending cache file to zabbix server. 4971:2014-05-20 10:50:18.294 - DBG : Cache file found. 4971:2014-05-20 10:50:18.294 - DBG : /usr/local/zabbix/bin/zabbix_sender –zabbix-server ipAddrX –port 10051 –input-file /tmp/FromDualAgentCache.hostnameX.cache –with-timestamps -vv zabbix_sender [5018]: Warning: [line 5] ‘Key value’ required Sending failed. 4971:2014-05-20 10:50:18.300 - DBG : (ret=256 / rc=1301). 4971:2014-05-20 10:50:18.300 - ERR : Load of cache file failed. rc=1301 4971:2014-05-20 10:50:18.301 - INFO: Processing module master for secion hostnameX finished. 4971:2014-05-20 10:50:18.301 - INFO: FromDualMySQLagent::removeAgentLock 4971:2014-05-20 10:50:18.301 - INFO: FromDual Performance Monitor for MySQL run finshed (rc=0).

I think I see what went wrong. Can you please send me the first 10 lines of the file /tmp/FromDualAgentCache.hostnameX.cache:

shell> head -n 10 /tmp/FromDualAgentCache.hostnameX.cache

Here are the output of this command: hostnameX FromDual.MySQL.master.Binlog_position 1400661892 232071 hostnameX FromDual.MySQL.master.Slave_count 1400661892 0 hostnameX FromDual.MySQL.master.Binlog_event_count 1400661892 0 hostnameX FromDual.MySQL.master.Binlog_total_size 1400661892 407016903 hostnameX FromDual.MySQL.master.Binlog_ignore_filter 1400661892 hostnameX FromDual.MySQL.master.Binlog_count 1400661892 8 hostnameX FromDual.MySQL.master.Binlog_do_filter 1400661892 tableX hostnameX FromDual.MySQL.master.Binlog_number 1400661892 682 hostnameX FromDual.MySQL.master.Binlog_avg_event_size 1400661892 0 hostnameX FromDual.MySQL.master.Binlog_file 1400661892 mysql-bin.000682

Thanks, got it:

hostnameX FromDual.MySQL.master.Binlog_ignore_filter 1400661892

So what’s the fix to apply ? Thanks !

The following fixes in the file lib/FromDualMySQLmaster.pm should do the job:

-  $$status_ref{'Binlog_file'} = '';
+  $$status_ref{'Binlog_file'} = 'none';

-  $$status_ref{'Binlog_do_filter'} = '';
-  $$status_ref{'Binlog_ignore_filter'} = '';
+  $$status_ref{'Binlog_do_filter'} = 'none';
+  $$status_ref{'Binlog_ignore_filter'} = 'none';

-      $$status_ref{'Binlog_do_filter'} = $ref->{'Binlog_Do_DB'};
-      $$status_ref{'Binlog_ignore_filter'} = $ref->{'Binlog_Ignore_DB'};
+      $$status_ref{'Binlog_do_filter'} = $ref->{'Binlog_Do_DB'} eq '' ? "''" : $ref->{'Binlog_Do_DB'};
+      $$status_ref{'Binlog_ignore_filter'} = $ref->{'Binlog_Ignore_DB'} eq '' ? "''" : $ref->{'Binlog_Ignore_DB'};

I hope we will have a new release soon...

By the way I would say that this bug was introduced in v0.9.1 already, more than 13 months ago... :-(

It is done: v0.9.3.

Hi ! Looks like it’s working for the master module.

Unfortunately, I still have sometimes a lack of data and that new error in logs :
4096:2014-05-28 16:46:49.051 - ERR : got TERM signal. Cleaning up stuff an exit (rc=1).

As mentioned earlier (now with a nicer error message) somebody or something is killing the actual mpm agent job. This is typically a next mpm agent job who has to wait too long for the actual one. The question is more: Why is the first one lasting for so long…

Maybe by postponing the interval between two checks, it could be better ? I noticed it’s happening only on huge databases

An mpm agent run should not be started before the previous has ended. To make this sure we have some internal checks (and a kill). So the interval should be bigger than the duration of a (the longest) run. Typically we run mpm every 10 seconds and that is fine in most of the cases. But our biggest DB is around 250 Gbyte. So I am wondering why your runs take much longer. Should be visible by the timestamps of the log (debug) file where most of the time is spent.

4187:2014-06-03 16:06:51.934 - INFO: FromDual Performance Monitor for MySQL (0.9.3) run started. 4187:2014-06-03 16:06:51.934 - INFO: FromDualMySQLagent::setAgentLock 4187:2014-06-03 16:06:51.934 - INFO: Read configuration from /usr/local/mysql_performance_monitor/etc/FromDualMySQLagent.conf

4187:2014-06-03 16:06:53.350 - ERR : got TERM signal. Cleaning up stuff an exit (rc=1). 4187:2014-06-03 16:06:53.351 - INFO: FromDualMySQLagent::removeAgentLock 4187:2014-06-03 16:06:53.351 - INFO: FromDual Performance Monitor for MySQL run finshed (rc=1).

It’s less than 2 seconds. Sometimes, in our biggest database (150Gb), we have some slow queries ? Maybe it’s blocking the MPM ?

Furthermore, I noticed more than 700 files created BY ZABBIX in /tmp on only masters. They are named like : DeadlockMessage.tmp.17145 & ForeignKeyMessage.tmp.11416