You are here

Home

MySQL Performance Monitor upgrade to v0.9.2

Mon, 2014-05-05 19:18 — oli

I'm going to deploy the new version of MPM on all my servers and i was wondering if there was a way to automatically delete or reload the cache file FromDualAgentCache.hostname.cache when we upgrade to the new version?

I'm using Puppet to deploy the new version but I can't find a way to delete this file once, otherwise it will erase it every 30 minutes which is not good for the system.

Taxonomy upgrade extras: 
mpm upgrade

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?

olicomment Mon, 2014-05-05 19:24

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.

Obyiscomment Tue, 2014-05-06 15:38

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

olicomment Wed, 2014-05-07 07:02

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.

Obyiscomment Wed, 2014-05-07 12:09

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.

abdel-mawlacomment Thu, 2014-05-08 10:50

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.

olicomment Thu, 2014-05-08 20:18

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.

Obyiscomment Mon, 2014-05-12 10:38

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"
Obyiscomment Thu, 2014-05-15 09:53

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

olicomment Thu, 2014-05-15 10:53

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).

Obyiscomment Thu, 2014-05-15 14:17

It looks like you did not enable the master module: INFO: Modules for section hostnameX: mpm server process mysql innodb slave
olicomment Thu, 2014-05-15 14:29

Hello, This debug is when the MPM stops after some hours of work. I'll send the debug for the module master after.
Obyiscomment Thu, 2014-05-15 14:52

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?
olicomment Thu, 2014-05-15 16:21

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

Obyiscomment Thu, 2014-05-15 16:55

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).

Obyiscomment Tue, 2014-05-20 11:10

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
olicomment Tue, 2014-05-20 21:34

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

Obyiscomment Wed, 2014-05-21 11:10

Thanks, got it:

hostnameX FromDual.MySQL.master.Binlog_ignore_filter 1400661892
olicomment Wed, 2014-05-21 12:42

So what's the fix to apply ?
Thanks !

Obyiscomment Wed, 2014-05-21 14:16

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... :-(

olicomment Sat, 2014-05-24 13:06

It is done: v0.9.3.

olicomment Sat, 2014-05-24 15:25

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).

Obyiscomment Wed, 2014-05-28 16:53

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...
olicomment Wed, 2014-05-28 16:58

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

Obyiscomment Tue, 2014-06-03 10:14

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.
olicomment Tue, 2014-06-03 16:04

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 ?

Obyiscomment Tue, 2014-06-03 16:35

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

Obyiscomment Thu, 2014-06-12 14:59