[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