Topic: [SOLVED] problem with pre-backup event and mysql dump

[Stefano]

Hi all


on a server of mine I'm testing a custom backup script that before creating the tgz file makes a call to pre-backup event.


script is run by cron using root user.


in /var/log/messages I read:

Code: [Select]

Nov 16 03:39:06 server esmith::event[23531]: Processing event: pre-backup
Nov 16 03:39:06 server esmith::event[23531]: Running event handler: /etc/e-smith/events/actions/generic_template_expand
Nov 16 03:39:07 server esmith::event[23531]: expanding /etc/dar/DailyBackup.dcf
Nov 16 03:39:07 server esmith::event[23531]: generic_template_expand=action|Event|pre-backup|Action|generic_template_expand|Start|1447641546 872136|End|1447641547 609218|Elapsed|0.737082
Nov 16 03:39:07 server esmith::event[23531]: Running event handler: /etc/e-smith/events/pre-backup/S10mysql-delete-dumped-tables
Nov 16 03:39:07 server esmith::event[23531]: S10mysql-delete-dumped-tables=action|Event|pre-backup|Action|S10mysql-delete-dumped-tables|Start|1447641547 609782|End|1447641547 613067|Elapsed|0.003285
Nov 16 03:39:07 server esmith::event[23531]: Running event handler: /etc/e-smith/events/pre-backup/S20mysql-dump-tables
Nov 16 03:39:07 server esmith::event[23531]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
Nov 16 03:39:07 server esmith::event[23531]: S20mysql-dump-tables=action|Event|pre-backup|Action|S20mysql-dump-tables|Start|1447641547 613568|End|1447641547 947942|Elapsed|0.334374
Nov 16 03:39:07 server esmith::event[23531]: Running event handler: /etc/e-smith/events/pre-backup/S30ldap-dump
Nov 16 03:39:08 server esmith::event[23531]: 564941cc bdb_monitor_db_open: monitoring disabled; configure monitor database to enable
Nov 16 03:39:08 server esmith::event[23531]: S30ldap-dump=action|Event|pre-backup|Action|S30ldap-dump|Start|1447641547 948369|End|1447641548 282694|Elapsed|0.334325
Nov 16 03:39:08 server esmith::event[23531]: Running event handler: /etc/e-smith/events/pre-backup/S50rewind-tape
Nov 16 03:39:08 server esmith::event[23531]: S50rewind-tape=action|Event|pre-backup|Action|S50rewind-tape|Start|1447641548 283179|End|1447641548 343599|Elapsed|0.06042


but, on the same server:

Code: [Select]

[root@server ~]# mysql
Welcome to the MySQL monitor.  Commands end with ; or \g.
Your MySQL connection id is 709
Server version: 5.1.73 Source distribution
Copyright (c) 2000, 2013, Oracle and/or its affiliates. All rights reserved.
Oracle is a registered trademark of Oracle Corporation and/or its
affiliates. Other names may be trademarks of their respective
owners.
Type 'help;' or '\h' for help. Type '\c' to clear the current input statement.
mysql>
running manually a "signal-event pre-backup" has no errors:

Code: [Select]

Nov 16 18:08:47 server esmith::event[4561]: Processing event: pre-backup
Nov 16 18:08:47 server esmith::event[4561]: Running event handler: /etc/e-smith/events/actions/generic_template_expand
Nov 16 18:08:47 server esmith::event[4561]: expanding /etc/dar/DailyBackup.dcf
Nov 16 18:08:48 server esmith::event[4561]: generic_template_expand=action|Event|pre-backup|Action|generic_template_expand|Start|1447693727 757342|End|1447693728 21605|Elapsed|0.264263
Nov 16 18:08:48 server esmith::event[4561]: Running event handler: /etc/e-smith/events/pre-backup/S10mysql-delete-dumped-tables
Nov 16 18:08:48 server esmith::event[4561]: S10mysql-delete-dumped-tables=action|Event|pre-backup|Action|S10mysql-delete-dumped-tables|Start|1447693728 22051|End|1447693728 25366|Elapsed|0.003315
Nov 16 18:08:48 server esmith::event[4561]: Running event handler: /etc/e-smith/events/pre-backup/S20mysql-dump-tables
Nov 16 18:08:48 server esmith::event[4561]: S20mysql-dump-tables=action|Event|pre-backup|Action|S20mysql-dump-tables|Start|1447693728 25830|End|1447693728 982171|Elapsed|0.956341
Nov 16 18:08:48 server esmith::event[4561]: Running event handler: /etc/e-smith/events/pre-backup/S30ldap-dump
Nov 16 18:08:49 server esmith::event[4561]: 564a0da1 bdb_monitor_db_open: monitoring disabled; configure monitor database to enable
Nov 16 18:08:49 server esmith::event[4561]: S30ldap-dump=action|Event|pre-backup|Action|S30ldap-dump|Start|1447693728 982570|End|1447693729 373287|Elapsed|0.390717
Nov 16 18:08:49 server esmith::event[4561]: Running event handler: /etc/e-smith/events/pre-backup/S50rewind-tape
Nov 16 18:08:49 server esmith::event[4561]: S50rewind-tape=action|Event|pre-backup|Action|S50rewind-tape|Start|1447693729 373687|End|1447693729 434057|Elapsed|0.06037
the relevant part of my script:

Code: [Select]

# metto nel log qualche info
echo $MSG
NOW=`date`
echo "Inizio operazione backup: $NOW"
echo "operazione pre-backup"
$SIGNAL pre-backup
with $SIGNAL defined as

Code: [Select]

SIGNAL="/sbin/e-smith/signal-event"


this script is a prototype, that runs after usual backupwk script (3 hours later, backupwk is never running when I call my script)
checking all messages* files, I see that the db dump has never succeded

Code: [Select]

[root@server ~]# grep ' Access denied' /var/log/messages*
/var/log/messages:Nov 16 03:39:07 server esmith::event[23531]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
/var/log/messages.20150815011203:Aug 10 03:16:10 server esmith::event[10077]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
/var/log/messages.20150815011203:Aug 11 03:26:40 server esmith::event[22684]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
/var/log/messages.20150815011203:Aug 12 03:06:20 server esmith::event[2645]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
.
.
. (deleted all the missing lines)
.
.
/var/log/messages.20151115011201:Nov 11 03:09:07 server esmith::event[13846]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
/var/log/messages.20151115011201:Nov 12 03:18:08 server esmith::event[3193]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
/var/log/messages.20151115011201:Nov 13 03:35:08 server esmith::event[25116]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
/var/log/messages.20151115011201:Nov 14 03:20:10 server esmith::event[15079]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)
/var/log/messages.20151115011202:Nov 15 03:41:08 server esmith::event[3729]: ERROR 1045 (28000): Access denied for user 'root'@'localhost' (using password: NO)




Any hint?
what can I check?


TIA


S.

[Daniel B.]

Try

Code: [Select]

HOME=/root

At the top of your script. Cron usually use "HOME=/" by default, so ~/.my.cnf isn't found, and no client password is given to MySQL. By specifying the HOME, you'll get the same result as when running it interactively

[Stefano]

Try

Code: [Select]

HOME=/root

At the top of your script. Cron usually use "HOME=/" by default, so ~/.my.cnf isn't found, and no client password is given to MySQL. By specifying the HOME, you'll get the same result as when running it interactively

thank you Dani.. I remember something about it in bugzilla..

I try and let you know

[Stefano]

thank you Dani, it worked perfectly..