[Box Backup] Box Deadlock Issue during Sync
Matt Brown
boxbackup@fluffy.co.uk
Wed, 25 Jun 2008 10:33:11 +0100
On 25 Jun 2008, at 09:57, Matt Brown wrote:
> Hi Chris,
>
> It would appear the deadlock issue has returned .... :(
>
> Anything you can suggest ?
>
> On the client using strace gives the following:
*Updated*
lstat64("/etc/box", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
time([1214383394]) = 1214383394
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1323, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1323, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1323, ...}) = 0
send(3, "<182>Jun 25 09:43:14 bbackupd[12"..., 66, MSG_NOSIGNAL) = 66
open("/etc/box", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY) = 6
fstat64(6, {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
fcntl64(6, F_SETFD, FD_CLOEXEC) = 0
getdents64(6, /* 4 entries */, 4096) = 120
lstat64("/etc/box/bbackupd.conf", {st_mode=S_IFREG|0644,
st_size=5609, ...}) = 0
lstat64("/etc/box/bbackupd", {st_mode=S_IFDIR|0700,
st_size=4096, ...}) = 0
getdents64(6, /* 0 entries */, 4096) = 0
close(6) = 0
write(5, "\27\3\1\0 \300\325\304\325\340\331r\"P\342o\376\310I
\256"..., 74) = 74
read(5, 0x8139d38, 5) = -1 EAGAIN (Resource
temporarily unavailable)
gettimeofday({1214383394, 686955}, NULL) = 0
gettimeofday({1214383394, 687015}, NULL) = 0
poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 899999) = 1
read(5, "\27\3\1\0 ", 5) = 5
read(5, "\325\'\264\241\1\204^\335\306\2216\276\213\215]\0\315I"...,
32) = 32
read(5, "\27\3\1\0 ", 5) = 5
read(5, "=\256\257\234\16xk1\200\333j\234\26\27K\356\30\370\307"...,
32) = 32
read(5, 0x8139d38, 5) = -1 EAGAIN (Resource
temporarily unavailable)
gettimeofday({1214383394, 830827}, NULL) = 0
gettimeofday({1214383394, 830901}, NULL) = 0
poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 900000) = 1
read(5, "\27\3\1\0 ", 5) = 5
read(5, "U\362Go\316C\245U\21\177b\5S(\320,\35Ul\272>\\\227y\247"...,
32) = 32
read(5, "\27\3\1\0 ", 5) = 5
read(5, "q\214/\3559\273\243ac\274T\207v\305\314\230*\262\351U
\220"..., 32) = 32
read(5, "\27\3\1\0 ", 5) = 5
read(5, "-\22\270\216[4K\24\216>\256I\243\317\237\t\3\16W<.\250"...,
32) = 32
read(5, "\27\3\1\0\240", 5) = 5
read(5, "\355\244\244\5\37\34\203\304\277\357\303mH\0c\255\16\325"...,
160) = 160
lstat64("/etc/box", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
llistxattr("/etc/box", 0x81eef88, 1000) = 0
lstat64("/etc/box/bbackupd.conf", {st_mode=S_IFREG|0644,
st_size=5609, ...}) = 0
llistxattr("/etc/box/bbackupd.conf", 0x81eef88, 1000) = 0
time([1214383394]) = 1214383394
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1323, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1323, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1323, ...}) = 0
send(3, "<182>Jun 25 09:43:14 bbackupd[12"..., 85, MSG_NOSIGNAL) = 85
lstat64("/etc/box/bbackupd.conf", {st_mode=S_IFREG|0644,
st_size=5609, ...}) = 0
llistxattr("/etc/box/bbackupd.conf", 0x81efb50, 1000) = 0
open("/etc/box/bbackupd.conf", O_RDONLY|O_LARGEFILE) = 6
fstat64(6, {st_mode=S_IFREG|0644, st_size=5609, ...}) = 0
_llseek(6, 0, [0], SEEK_CUR) = 0
gettimeofday({1214383394, 928735}, NULL) = 0
write(5, "\27\3\1\0 8\211\247\247l\7\315`\211_\207\220\357P
\271\207"..., 122) = 122
write(5, "\27\3\1\0 ?/\1\376e\16\267j\25\264-t\214K
\235\337\247\251"..., 74) = 74
brk(0x8221000) = 0x8221000
read(6, "\nStoreHostname = pegasus.3aitsup"..., 4096) = 4096
gettimeofday({1214383394, 929203}, NULL) = 0
brk(0x8251000) = 0x8251000
brk(0x8241000) = 0x8241000
brk(0x8231000) = 0x8231000
brk(0x8221000) = 0x8221000
read(6, "3 file in particular.\n# \n# In ge"..., 1513) = 1513
gettimeofday({1214383394, 930094}, NULL) = 0
brk(0x8251000) = 0x8251000
brk(0x8241000) = 0x8241000
brk(0x8231000) = 0x8231000
brk(0x8221000) = 0x8221000
write(5, "\27\3\1\0 Mh\367\346\3569\243\321\304\347\230g\232\267"...,
2762) = 2762
write(5, "\27\3\1\0 \251\233\351\262\371\217\304\33\36\25\257\f
\211"..., 154) = 154
write(5, "\27\3\1\0 \372\315d\215\2667\205E\347R\362\371\363\324"...,
74) = 74
write(5, "\27\3\1\0 \342\7\200\227O\371\370\331&
\23\246\365\371\241"..., 74) = 74
brk(0x8211000) = 0x8211000
read(5, 0x8139d38, 5) = -1 EAGAIN (Resource
temporarily unavailable)
gettimeofday({1214383394, 931450}, NULL) = 0
gettimeofday({1214383394, 931512}, NULL) = 0
poll([{fd=5, events=POLLIN}], 1, 900000) = -1 EINTR (Interrupted
system call)
--- SIGALRM (Alarm clock) @ 0 (0) ---
sigreturn() = ? (mask now [])
gettimeofday({1214383407, 153771}, NULL) = 0
poll([{fd=5, events=POLLIN}], 1, 887778) = 0
open("/dev/tty", O_RDWR|O_NONBLOCK|O_NOCTTY) = -1 ENXIO (No such
device or address)
writev(2, [{"*** glibc detected *** ", 23}, {"double free or
corruption (!prev"..., 33}, {": 0x", 4}, {"081e2e20", 8}, {" ***\n",
5}], 5) = 73
rt_sigprocmask(SIG_UNBLOCK, [ABRT], NULL, 8) = 0
tgkill(12452, 12452, SIGABRT) = 0
--- SIGABRT (Aborted) @ 0 (0) ---
Process 12452 detached
> On the server we see:
>
> Jun 25 09:43:14 ingfieldfs bbackupd[12452]: Uploading complete
> file: /etc/box/bbackupd.conf
>
> The backup fails to continue - the sync stops and hangs until it is
> closed forcibly.
>
> Regards
>
> Matt
> _______________________________________________
> boxbackup mailing list
> boxbackup@fluffy.co.uk
> http://lists.warhead.org.uk/mailman/listinfo/boxbackup