catching up with sync logs

Rudy Gevaert rudy.gevaert at ugent.be
Tue Jun 30 05:41:09 EDT 2009


Hi Bron,

Thanks for the quick reply.

Citeren Bron Gondwana <brong at fastmail.fm>:

> What's happening at the other end?  It looks to me like you're either
> waiting on locks, or the process at the other end has died.
>
> (worst case, some corruption isn't being handled correctly by sync_client,
> and is causing protocol alignment issues, but I suspect locking or death)

(I can also say that the file system check on the replica gave me some  
lost and found files.)

I gave it an other shot and now when I process the 12MB sync log I get:

on the cyrus master server:



open("/mail/mail3/imap/domain/u/ugent.be/t/user/torsten^dhondt/Ongewenste  
e-mail/cyrus.index", O_RDWR) = 11
fstat64(11, {st_mode=S_IFREG|0600, st_size=448, ...}) = 0
mmap2(NULL, 16384, PROT_READ, MAP_SHARED, 11, 0) = 0xb7b88000
open("/mail/mail3/imap/domain/u/ugent.be/t/user/torsten^dhondt/Ongewenste  
e-mail/cyrus.cache", O_RDWR) = 12
fstat64(12, {st_mode=S_IFREG|0600, st_size=8428, ...}) = 0
mmap2(NULL, 24576, PROT_READ, MAP_SHARED, 12, 0) = 0xb7b82000
fstat64(11, {st_mode=S_IFREG|0600, st_size=448, ...}) = 0
fcntl64(3, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0
fstat64(3, {st_mode=S_IFREG|0600, st_size=4025524, ...}) = 0
stat64("/mail/mail3/var/imap/mailboxes.db", {st_mode=S_IFREG|0600,  
st_size=4025524, ...}) = 0
fcntl64(3, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0
write(6,  
"\27\3\1\0p_V\22\0268\256\377\10\360E\257E^\371vV\353\304"..., 117) =  
117
time(NULL)                              = 1246354048
read(6, "\27\3\1\0P", 5)                = 5
read(6, "<\374Ec\224\316\3259(\\\341\232\361\272D\275\30=\214\251"...,  
80) = 80
time([1246354048])                      = 1246354048
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
send(7, "<179>Jun 30 11:27:28 mail3/sync_"..., 160, MSG_NOSIGNAL) = 160
close(9)                                = 0
munmap(0xb7f4a000, 199)                 = 0
close(11)                               = 0
munmap(0xb7b88000, 16384)               = 0
close(12)                               = 0
munmap(0xb7b82000, 24576)               = 0
write(6,  
"\27\3\1\0000\20\241\375A\314R/\230l\362\224e\313\366s\322"..., 53) = 53
time(NULL)                              = 1246354048
read(6, "\27\3\1\0000", 5)              = 5
read(6, "\370\262\322\4\2003\300\213\204\231\322}Dx\324\250\374"..., 48) = 48
time([1246354049])                      = 1246354049
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
send(7, "<182>Jun 30 11:27:29 mail3/sync_"..., 71, MSG_NOSIGNAL) = 71
time([1246354049])                      = 1246354049
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
send(7, "<179>Jun 30 11:27:29 mail3/sync_"..., 79, MSG_NOSIGNAL) = 79
close(8)                                = 0
write(6, "\27\3\1\0000%Yw>\301,\4\241GJ\202\261\352G\225\224\304"..., 53) = 53
time(NULL)                              = 1246354049
read(6, "\27\3\1\0000", 5)              = 5
read(6, "\351\313\255\4\tH\24\375u_\21\206\0\3166\312yS\310\216"..., 48) = 48
time(NULL)                              = 1246354049
select(7, [6], NULL, NULL, {0, 0})      = 0 (Timeout)
time(NULL)                              = 1246354049
shutdown(6, 0 /* receive */)            = 0
close(6)                                = 0
munmap(0xb6345000, 16384)               = 0
close(10)                               = 0
munmap(0xb6477000, 16384)               = 0
close(5)                                = 0
munmap(0xb605b000, 327680)              = 0
close(4)                                = 0
munmap(0xb64c7000, 4038656)             = 0
close(3)                                = 0
munmap(0xb68a1000, 32768)               = 0
munmap(0xb7a5d000, 98304)               = 0
munmap(0xb68a9000, 18563072)            = 0
munmap(0xb7a75000, 663552)              = 0
munmap(0xb7f4b000, 16384)               = 0
exit_group(-1904809439)                 = ?
Process 16586 detached

on the replica I straced the sync server that was handling that sync_client

open("/mail/mail3r/imap/sync./8349.cache", O_RDWR|O_CREAT|O_TRUNC, 0666) = 16
fcntl64(18, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0
close(18)                               = 0
time([1246354049])                      = 1246354049
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1067, ...}) = 0
send(6, "<182>Jun 30 11:27:29 mail3r/sync"..., 64, MSG_NOSIGNAL) = 64
write(1, "\27\3\1\0000\370\262\322\4\2003\300\213\204\231\322}Dx"..., 53) = 53
time(NULL)                              = 1246354049
read(0, "\27\3\1\0000", 5)              = 5
read(0, "%Yw>\301,\4\241GJ\202\261\352G\225\224\304x=\21+R6#z\254"...,  
48) = 48
write(1,  
"\27\3\1\0000\351\313\255\4\tH\24\375u_\21\206\0\3166\312"..., 53) = 53
close(19)                               = 0
munmap(0xb7f85000, 201)                 = 0
close(20)                               = 0
munmap(0xb6464000, 24576)               = 0
close(21)                               = 0
munmap(0xb629a000, 548864)              = 0
close(16)                               = 0
unlink("/mail/mail3r/imap/sync./8349.cache") = 0
rmdir("")                               = -1 ENOENT (No such file or  
directory)
close(15)                               = 0
munmap(0xb6407000, 4096)                = 0
unlink("/mail/mail3r/var/imap/proc/8349") = 0
time(NULL)                              = 1246354049
select(1, [0], NULL, NULL, {0, 0})      = 0 (Timeout)
time(NULL)                              = 1246354049
open("/dev/null", O_RDWR)               = 15
shutdown(0, 0 /* receive */)            = 0
dup2(15, 0)                             = 0
shutdown(1, 0 /* receive */)            = 0
dup2(15, 1)                             = 1
shutdown(2, 0 /* receive */)            = 0
dup2(15, 2)                             = 2
close(15)                               = 0
write(3, "\1\0\0\0\235 \0\0", 8)        = 8
rt_sigaction(SIGALRM, {0x8072c20, [], SA_ONESHOT}, NULL, 8) = 0
rt_sigaction(SIGHUP, {0x8072c20, [], SA_RESTART|SA_ONESHOT}, NULL, 8) = 0
rt_sigaction(SIGINT, {0x8072c20, [], SA_RESTART|SA_ONESHOT}, NULL, 8) = 0
rt_sigaction(SIGQUIT, {0x8072c20, [], SA_RESTART|SA_ONESHOT}, NULL, 8) = 0
alarm(114)                              = 0
fcntl64(14, F_SETLKW, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0})  
= ? ERESTARTSYS (To be restarted)
--- SIGALRM (Alarm clock) @ 0 (0) ---
sigreturn()                             = ? (mask now [])
munmap(0xb6320000, 32768)               = 0
close(22)                               = 0
munmap(0xb6475000, 3735552)             = 0
close(8)                                = 0
munmap(0xb6044000, 262144)              = 0
close(12)                               = 0
munmap(0xb643d000, 16384)               = 0
close(13)                               = 0
munmap(0xb639c000, 106496)              = 0
close(17)                               = 0
munmap(0xb6933000, 32768)               = 0
munmap(0xb7aef000, 98304)               = 0
munmap(0xb693b000, 18563072)            = 0
munmap(0xb7b07000, 663552)              = 0
munmap(0xb7f86000, 16384)               = 0
exit_group(0)                           = ?
Process 8349 detached


Maybe this information is not enough, if you tell me what you need I  
can provide :)

-- 
-- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --
Rudy Gevaert          Rudy.Gevaert at UGent.be          tel:+32 9 264 4734
Directie ICT, afd. Infrastructuur  Direction ICT, Infrastructure dept.
Groep Systemen                     Systems group
Universiteit Gent                  Ghent University
Krijgslaan 281, gebouw S9, 9000 Gent, Belgie               www.UGent.be
-- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- --




More information about the Info-cyrus mailing list