[Box Backup-dev] Re:BoxReport.pl (was ChrisMerge_1828)

Matt Brown boxbackup-dev at fluffy.co.uk
Fri Sep 21 22:42:51 BST 2007


Hi Chris,
>>
>> Could you try stracing syslogd while bbackupd is running a backup  
>> (you can force this with bbackupctl force-sync) and check that it  
>> receives the messages, and find out where it writes them to? lsof  
>> on the syslogd process can tell you which file descriptor is  
>> which. For example, on my system:
>>

<snip>

Ok, here we go:

root at orion:~# sudo strace -f -s 128 -e recv,writev -p 6308
Process 6308 attached - interrupt to quit
writev(18, [{"Sep 21 22:33:33", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"sudo:     matt : TTY=pts/1 ; PWD=/home/matt ; USER=root ;  
COMMAND=/usr/bin/strace -f -s 128 -e recv,writev -p 6308", 114},  
{"\n", 1}], 6) = 137
recv(0, "<181>Sep 21 22:33:53 Box Backup[6393]: NOTICE: Using  
configuration file /etc/box/bbackupd.conf", 1022, 0) = 94
writev(1, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup[6393]: NOTICE: Using configuration file /etc/box/ 
bbackupd.conf", 73}, {"\n", 1}], 6) = 96
writev(3, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup[6393]: NOTICE: Using configuration file /etc/box/ 
bbackupd.conf", 73}, {"\n", 1}], 6) = 96
writev(17, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup[6393]: NOTICE: Using configuration file /etc/box/ 
bbackupd.conf", 73}, {"\n", 1}], 6) = 96
writev(18, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup[6393]: NOTICE: Using configuration file /etc/box/ 
bbackupd.conf", 73}, {"\n", 1}], 6) = 96
recv(0, "<182>Sep 21 22:33:53 Box Backup[6393]: Daemon configuration  
summary:\n  AutomaticBackup = false\n  UpdateStoreInterval = 0  
seconds"..., 1022, 0) = 186
writev(1, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup[6393]: Daemon configuration summary:   AutomaticBackup =  
false   UpdateStoreInterval = 0 seconds   MinimumFileAge = 0"...,  
165}, {"\n", 1}], 6) = 188
writev(3, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup[6393]: Daemon configuration summary:   AutomaticBackup =  
false   UpdateStoreInterval = 0 seconds   MinimumFileAge = 0"...,  
165}, {"\n", 1}], 6) = 188
writev(17, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup[6393]: Daemon configuration summary:    
AutomaticBackup = false   UpdateStoreInterval = 0 seconds    
MinimumFileAge = 0"..., 165}, {"\n", 1}], 6) = 188
writev(18, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup[6393]: Daemon configuration summary:    
AutomaticBackup = false   UpdateStoreInterval = 0 seconds    
MinimumFileAge = 0"..., 165}, {"\n", 1}], 6) = 188
recv(0, "<181>Sep 21 22:33:53 Box Backup (bbackupd)[3653]: NOTICE:  
Beginning scan of local files", 1022, 0) = 87
writev(1, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: Beginning scan of local  
files", 66}, {"\n", 1}], 6) = 89
writev(3, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: Beginning scan of local  
files", 66}, {"\n", 1}], 6) = 89
writev(17, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: Beginning scan of local  
files", 66}, {"\n", 1}], 6) = 89
writev(18, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: Beginning scan of local  
files", 66}, {"\n", 1}], 6) = 89
recv(0, "<181>Sep 21 22:33:53 Box Backup (bbackupd)[3653]: NOTICE:  
About to notify administrator about event backup-start, running  
script"..., 1022, 0) = 179
writev(1, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: About to notify administrator  
about event backup-start, running script \'/etc/box/bbackupd/N"...,  
158}, {"\n", 1}], 6) = 181
writev(3, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: About to notify administrator  
about event backup-start, running script \'/etc/box/bbackupd/N"...,  
158}, {"\n", 1}], 6) = 181
writev(17, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: About to notify  
administrator about event backup-start, running script \'/etc/box/ 
bbackupd/N"..., 158}, {"\n", 1}], 6) = 181
writev(18, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: About to notify  
administrator about event backup-start, running script \'/etc/box/ 
bbackupd/N"..., 158}, {"\n", 1}], 6) = 181
recv(0, "<182>Sep 21 22:33:53 Box Backup[6393]: Succeeded.\n", 1022,  
0) = 50
writev(1, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup[6393]: Succeeded. ", 29}, {"\n", 1}], 6) = 52
writev(3, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup[6393]: Succeeded. ", 29}, {"\n", 1}], 6) = 52
writev(17, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup[6393]: Succeeded. ", 29}, {"\n", 1}], 6) = 52
writev(18, [{"Sep 21 22:33:53", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup[6393]: Succeeded. ", 29}, {"\n", 1}], 6) = 52
recv(0, "<180>Sep 21 22:33:59 Box Backup (bbackupd)[3653]: WARNING:  
Ignored directory: /var/lock: is a mount point; create a new  
location"..., 1022, 0) = 154
writev(1, [{"Sep 21 22:33:59", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: WARNING: Ignored directory: /var/lock:  
is a mount point; create a new location if you want to back "...,  
133}, {"\n", 1}], 6) = 156
writev(3, [{"Sep 21 22:33:59", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: WARNING: Ignored directory: /var/lock:  
is a mount point; create a new location if you want to back "...,  
133}, {"\n", 1}], 6) = 156
writev(17, [{"Sep 21 22:33:59", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: WARNING: Ignored directory: /var/ 
lock: is a mount point; create a new location if you want to back  
"..., 133}, {"\n", 1}], 6) = 156
writev(18, [{"Sep 21 22:33:59", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: WARNING: Ignored directory: /var/ 
lock: is a mount point; create a new location if you want to back  
"..., 133}, {"\n", 1}], 6) = 156
recv(0, "<180>Sep 21 22:33:59 Box Backup (bbackupd)[3653]: WARNING:  
Ignored directory: /var/run: is a mount point; create a new location  
"..., 1022, 0) = 153
writev(1, [{"Sep 21 22:33:59", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: WARNING: Ignored directory: /var/run:  
is a mount point; create a new location if you want to back i"...,  
132}, {"\n", 1}], 6) = 155
writev(3, [{"Sep 21 22:33:59", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: WARNING: Ignored directory: /var/run:  
is a mount point; create a new location if you want to back i"...,  
132}, {"\n", 1}], 6) = 155
writev(17, [{"Sep 21 22:33:59", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: WARNING: Ignored directory: /var/ 
run: is a mount point; create a new location if you want to back  
i"..., 132}, {"\n", 1}], 6) = 155
writev(18, [{"Sep 21 22:33:59", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: WARNING: Ignored directory: /var/ 
run: is a mount point; create a new location if you want to back  
i"..., 132}, {"\n", 1}], 6) = 155
recv(0, "<181>Sep 21 22:34:14 Box Backup (bbackupd)[3653]: NOTICE:  
Finished scan of local files", 1022, 0) = 86
writev(1, [{"Sep 21 22:34:14", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: Finished scan of local files",  
65}, {"\n", 1}], 6) = 88
writev(3, [{"Sep 21 22:34:14", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: Finished scan of local files",  
65}, {"\n", 1}], 6) = 88
writev(17, [{"Sep 21 22:34:14", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: Finished scan of local  
files", 65}, {"\n", 1}], 6) = 88
writev(18, [{"Sep 21 22:34:14", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: Finished scan of local  
files", 65}, {"\n", 1}], 6) = 88
recv(0, "<181>Sep 21 22:34:14 Box Backup (bbackupd)[3653]: NOTICE:  
About to notify administrator about event backup-finish, running  
scrip"..., 1022, 0) = 181
writev(1, [{"Sep 21 22:34:14", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: About to notify administrator  
about event backup-finish, running script \'/etc/box/bbackupd/"...,  
160}, {"\n", 1}], 6) = 183
writev(3, [{"Sep 21 22:34:14", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: About to notify administrator  
about event backup-finish, running script \'/etc/box/bbackupd/"...,  
160}, {"\n", 1}], 6) = 183
writev(17, [{"Sep 21 22:34:14", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: About to notify  
administrator about event backup-finish, running script \'/etc/box/ 
bbackupd/"..., 160}, {"\n", 1}], 6) = 183
writev(18, [{"Sep 21 22:34:14", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: About to notify  
administrator about event backup-finish, running script \'/etc/box/ 
bbackupd/"..., 160}, {"\n", 1}], 6) = 183
recv(0, "<181>Sep 21 22:34:15 Box Backup (bbackupd)[3653]: NOTICE:  
File statistics: total file size uploaded 6107715, bytes already on  
se"..., 1022, 0) = 160
writev(1, [{"Sep 21 22:34:15", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: File statistics: total file  
size uploaded 6107715, bytes already on server 6088959, encoded"...,  
139}, {"\n", 1}], 6) = 162
writev(3, [{"Sep 21 22:34:15", 15}, {" ", 1}, {"orion", 5}, {" ", 1},  
{"Box Backup (bbackupd)[3653]: NOTICE: File statistics: total file  
size uploaded 6107715, bytes already on server 6088959, encoded"...,  
139}, {"\n", 1}], 6) = 162
writev(17, [{"Sep 21 22:34:15", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: File statistics: total  
file size uploaded 6107715, bytes already on server 6088959,  
encoded"..., 139}, {"\n", 1}], 6) = 162
writev(18, [{"Sep 21 22:34:15", 15}, {" ", 1}, {"orion", 5}, {" ",  
1}, {"Box Backup (bbackupd)[3653]: NOTICE: File statistics: total  
file size uploaded 6107715, bytes already on server 6088959,  
encoded"..., 139}, {"\n", 1}], 6) = 162


root at orion:~# sudo lsof -p 6308
COMMAND  PID   USER   FD   TYPE     DEVICE    SIZE     NODE NAME
syslogd 6308 syslog  cwd    DIR        3,1    4096        2 /
syslogd 6308 syslog  rtd    DIR        3,1    4096        2 /
syslogd 6308 syslog  txt    REG        3,1   31828  2408453 /sbin/ 
syslogd
syslogd 6308 syslog  mem    REG        0,0                0 [heap]  
(stat: No such file or directory)
syslogd 6308 syslog  mem    REG        3,1  897076  3113112 /usr/lib/ 
libdb-4.3.so
syslogd 6308 syslog  mem    REG        3,1   17104  3130696 /usr/lib/ 
sasl2/libsasldb.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1   27836  3130673 /usr/lib/ 
sasl2/libntlm.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1   12824  3130670 /usr/lib/ 
sasl2/liblogin.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1   12344  3130667 /usr/lib/ 
sasl2/libanonymous.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1   41408  3130661 /usr/lib/ 
sasl2/libotp.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1 1224120  3129491 /usr/lib/ 
i686/cmov/libcrypto.so.0.9.8
syslogd 6308 syslog  mem    REG        3,1   13080  3130664 /usr/lib/ 
sasl2/libplain.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1   41752  3130658 /usr/lib/ 
sasl2/libdigestmd5.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1   10292  3115194 /usr/lib/ 
libgpg-error.so.0.1.4
syslogd 6308 syslog  mem    REG        3,1  310348  3115192 /usr/lib/ 
libgcrypt.so.11.2.1
syslogd 6308 syslog  mem    REG        3,1   77368  3114673 /usr/lib/ 
libz.so.1.2.3
syslogd 6308 syslog  mem    REG        3,1   63656  3115198 /usr/lib/ 
libtasn1.so.2.0.17
syslogd 6308 syslog  mem    REG        3,1   86580 14157141 /lib/tls/ 
i686/cmov/libpthread-2.3.6.so
syslogd 6308 syslog  mem    REG        3,1  426680  3115243 /usr/lib/ 
libgnutls.so.12.3.6
syslogd 6308 syslog  mem    REG        3,1   80876  3115071 /usr/lib/ 
libsasl2.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1   18900 14157129 /lib/tls/ 
i686/cmov/libcrypt-2.3.6.so
syslogd 6308 syslog  mem    REG        3,1   68804 14157142 /lib/tls/ 
i686/cmov/libresolv-2.3.6.so
syslogd 6308 syslog  mem    REG        3,1   77176 14157133 /lib/tls/ 
i686/cmov/libnsl-2.3.6.so
syslogd 6308 syslog  mem    REG        3,1   44884  3115212 /usr/lib/ 
liblber.so.2.0.130
syslogd 6308 syslog  mem    REG        3,1  213792  3115213 /usr/lib/ 
libldap_r.so.2.0.130
syslogd 6308 syslog  mem    REG        3,1   60856 14155821 /lib/ 
libnss_ldap-2.3.6.so
syslogd 6308 syslog  mem    REG        3,1   14584  3130655 /usr/lib/ 
sasl2/libcrammd5.so.2.0.19
syslogd 6308 syslog  mem    REG        3,1   37432 14157136 /lib/tls/ 
i686/cmov/libnss_files-2.3.6.so
syslogd 6308 syslog  mem    REG        3,1 1232784 14157127 /lib/tls/ 
i686/cmov/libc-2.3.6.so
syslogd 6308 syslog  mem    REG        3,1    8204 14157130 /lib/tls/ 
i686/cmov/libdl-2.3.6.so
syslogd 6308 syslog  mem    REG        3,1   86404 14155825 /lib/ 
ld-2.3.6.so
syslogd 6308 syslog    0u  unix 0xd5678380            15096 /dev/log
syslogd 6308 syslog    1w   REG        3,1  959727  8489586 /var/log/box
syslogd 6308 syslog    2w   REG        3,1   13028  8488007 /var/log/ 
auth.log
syslogd 6308 syslog    3w   REG        3,1 2618291  8489590 /var/log/ 
syslog
syslogd 6308 syslog    4w   REG        3,1   34502  8486919 /var/log/ 
daemon.log
syslogd 6308 syslog    5w   REG        3,1   26380  8488549 /var/log/ 
kern.log
syslogd 6308 syslog    6w   REG        3,1    1545  8488739 /var/log/ 
lpr.log
syslogd 6308 syslog    7w   REG        3,1  850475  8488407 /var/log/ 
mail.log
syslogd 6308 syslog    8w   REG        3,1   87722  8488061 /var/log/ 
user.log
syslogd 6308 syslog    9w   REG        3,1       0  8488753 /var/log/ 
uucp.log
syslogd 6308 syslog   10w   REG        3,1  748985  8488669 /var/log/ 
mail.info
syslogd 6308 syslog   11w   REG        3,1  217793  8487451 /var/log/ 
mail.warn
syslogd 6308 syslog   12w   REG        3,1  217793  8488401 /var/log/ 
mail.err
syslogd 6308 syslog   13w   REG        3,1       0  8488774 /var/log/ 
news/news.crit
syslogd 6308 syslog   14w   REG        3,1       0  8488784 /var/log/ 
news/news.err
syslogd 6308 syslog   15w   REG        3,1       0  8488787 /var/log/ 
news/news.notice
syslogd 6308 syslog   16w   REG        3,1 1470769  8487268 /var/log/ 
debug
syslogd 6308 syslog   17w   REG        3,1  995633  8487950 /var/log/ 
messages
syslogd 6308 syslog   18u  FIFO       0,13             8163 /dev/ 
xconsole
syslogd 6308 syslog   19u  IPv4      15100              TCP localhost: 
45157->localhost:ldap (ESTABLISHED)

Regards

Matt



More information about the Boxbackup-dev mailing list