IDLE not working for one account only

Neil Price nprice at gibb.co.za
Wed Jun 6 06:45:32 EDT 2018 

I'm grateful for your help.

Attached is an imapd strace of the bad account going into idle, a 
message being sent to it, and it leaving idle again.


On 05/06/2018 17:11, Bron Gondwana wrote:
> The timeouts are fine - that's just a select timeout so it can do cleanup.
>
> recvfrom(14, "\2\0\0\0\0\0\0\0user.elangenhoven\0", 1032, 0, {sa_family=AF_UNIX, sun_path="/var/lib/cyrus/socket/idle.31167"}, [110->35]) = 26
> sendto(14, "\2\0\0\0\0\0\0\0user.elangenhoven\0", 26, MSG_DONTWAIT, {sa_family=AF_UNIX, sun_path="/var/lib/cyrus/socket/idle.30947"}, 110) = 26
> That's a push from the lmtpd (PID 31167) for delivery, followed by a push to the imapd (PID 30947) saying that the mailbox has been touched!
> Sorry to be a pain, but can you do it again and get an strace of the imapd as well - the one which is in idle.
> Thanks,
> Bron.
>
>
> On Wed, Jun 6, 2018, at 00:53, Neil Price wrote:
>>
>> I've attached an strace of idled. It shows the bad user client 
>> (elangenhoven) going into idle and a message being sent to it.You can 
>> see the message being sent but there seems to be no reaction to it 
>> arriving.
>>
>> There are a bunch of timeouts there....?
>>
>>
>> On 05/06/2018 15:42, Bron Gondwana wrote:
>>> Damn.  I guess I'm going to have to ask for an strace next!  Of the 
>>> idled process probably.
>>>
>>> Idle changed a bunch between 2.5 and 3.0, so I don't know how much 
>>> I'll be able to help :(  Definitely an strace of the idled during 
>>> both the start of the IDLE command and the delivery will help see 
>>> what's happening.
>>>
>>> Bron.
>>>
>>>
>>
>> ----
>> Cyrus Home Page: http://www.cyrusimap.org/
>> List Archives/Info: http://lists.andrew.cmu.edu/pipermail/info-cyrus/
>> To Unsubscribe:
>> https://lists.andrew.cmu.edu/mailman/listinfo/info-cyrus
>>
>> Email had 1 attachment:
>>
>>  *
>>     |strace.txt|
>>       12k (text/plain)
>>
>
> --
>   Bron Gondwana, CEO, FastMail Pty Ltd
>   brong at fastmailteam.com
>
>
>
>
> ----
> Cyrus Home Page: http://www.cyrusimap.org/
> List Archives/Info: http://lists.andrew.cmu.edu/pipermail/info-cyrus/
> To Unsubscribe:
> https://lists.andrew.cmu.edu/mailman/listinfo/info-cyrus

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.andrew.cmu.edu/pipermail/info-cyrus/attachments/20180606/36695353/attachment-0001.html>
-------------- next part --------------
strace: Process 10379 attached
pselect6(1, [0], NULL, NULL, {tv_sec=1730, tv_nsec=561738279}, {[], 8}) = 1 (in [0], left {tv_sec=1723, tv_nsec=44475135})
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [INT QUIT ALRM TERM CHLD], [], 8) = 0
pselect6(1, [0], NULL, NULL, {tv_sec=1723, tv_nsec=0}, {[], 8}) = 1 (in [0], left {tv_sec=1722, tv_nsec=999994923})
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
read(0, "a04 idle\r\n", 4096)           = 10
getpid()                                = 10379
open("/run/cyrus/proc/10379.new", O_RDWR|O_CREAT|O_TRUNC, 0666) = 19
fstat(19, {st_mode=S_IFREG|0600, st_size=0, ...}) = 0
write(19, "imap\tlocalhost [127.0.0.1]\telang"..., 63) = 63
close(19)                               = 0
rename("/run/cyrus/proc/10379.new", "/run/cyrus/proc/10379") = 0
write(1, "+ idling\r\n", 10)            = 10
open("/run/cyrus/lock/e/user/elangenhoven.lock", O_RDWR|O_CREAT|O_TRUNC, 0666) = 19
fcntl(19, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
fcntl(12, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
fstat(12, {st_mode=S_IFREG|0600, st_size=46688, ...}) = 0
stat("/var/lib/cyrus/mailboxes.db", {st_mode=S_IFREG|0600, st_size=46688, ...}) = 0
fcntl(12, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", O_RDWR) = 20
fstat(20, {st_mode=S_IFREG|0600, st_size=229456, ...}) = 0
mmap(NULL, 245760, PROT_READ, MAP_SHARED, 20, 0) = 0x7f8473107000
fcntl(20, F_SETLKW, {l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
stat("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", {st_mode=S_IFREG|0600, st_size=229456, ...}) = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.header", O_RDONLY) = 21
fstat(21, {st_mode=S_IFREG|0600, st_size=266, ...}) = 0
mmap(NULL, 266, PROT_READ, MAP_SHARED, 21, 0) = 0x7f847320b000
munmap(0x7f847320b000, 266)             = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.expunge", O_RDWR) = -1 ENOENT (No such file or directory)
fcntl(20, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
sendto(14, "\0\0\0\0\0\0\0\0user.elangenhoven\0", 26, MSG_DONTWAIT, {sa_family=AF_UNIX, sun_path="/var/run/cyrus/socket/idle"}, 110) = 26
fcntl(20, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
stat("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", {st_mode=S_IFREG|0600, st_size=229456, ...}) = 0
close(21)                               = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.header", O_RDONLY) = 21
fstat(21, {st_mode=S_IFREG|0600, st_size=266, ...}) = 0
mmap(NULL, 266, PROT_READ, MAP_SHARED, 21, 0) = 0x7f847320b000
munmap(0x7f847320b000, 266)             = 0
fcntl(20, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
close(21)                               = 0
close(20)                               = 0
munmap(0x7f8473107000, 245760)          = 0
close(19)                               = 0
rt_sigprocmask(SIG_BLOCK, [INT QUIT ALRM TERM CHLD], [], 8) = 0
pselect6(15, [0 14], NULL, NULL, {tv_sec=60, tv_nsec=0}, {[], 8}) = 1 (in [14], left {tv_sec=42, tv_nsec=206449687})
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
recvfrom(14, "\2\0\0\0\0\0\0\0user.elangenhoven\0", 1032, 0, {sa_family=AF_UNIX, sun_path="/var/run/cyrus/socket/idle"}, [110->29]) = 26
open("/run/cyrus/lock/e/user/elangenhoven.lock", O_RDWR|O_CREAT|O_TRUNC, 0666) = 19
fcntl(19, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
fcntl(12, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
fstat(12, {st_mode=S_IFREG|0600, st_size=46688, ...}) = 0
stat("/var/lib/cyrus/mailboxes.db", {st_mode=S_IFREG|0600, st_size=46688, ...}) = 0
fcntl(12, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", O_RDWR) = 20
fstat(20, {st_mode=S_IFREG|0600, st_size=229516, ...}) = 0
mmap(NULL, 245760, PROT_READ, MAP_SHARED, 20, 0) = 0x7f8473107000
fcntl(20, F_SETLKW, {l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
stat("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", {st_mode=S_IFREG|0600, st_size=229516, ...}) = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.header", O_RDONLY) = 21
fstat(21, {st_mode=S_IFREG|0600, st_size=266, ...}) = 0
mmap(NULL, 266, PROT_READ, MAP_SHARED, 21, 0) = 0x7f847320b000
munmap(0x7f847320b000, 266)             = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.expunge", O_RDWR) = -1 ENOENT (No such file or directory)
fcntl(20, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
fcntl(20, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
stat("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", {st_mode=S_IFREG|0600, st_size=229516, ...}) = 0
close(21)                               = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.header", O_RDONLY) = 21
fstat(21, {st_mode=S_IFREG|0600, st_size=266, ...}) = 0
mmap(NULL, 266, PROT_READ, MAP_SHARED, 21, 0) = 0x7f847320b000
munmap(0x7f847320b000, 266)             = 0
fcntl(20, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
close(21)                               = 0
close(20)                               = 0
munmap(0x7f8473107000, 245760)          = 0
close(19)                               = 0
rt_sigprocmask(SIG_BLOCK, [INT QUIT ALRM TERM CHLD], [], 8) = 0
pselect6(15, [0 14], NULL, NULL, {tv_sec=60, tv_nsec=0}, {[], 8}) = 1 (in [0], left {tv_sec=50, tv_nsec=488030526})
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [INT QUIT ALRM TERM CHLD], [], 8) = 0
pselect6(1, [0], NULL, NULL, {tv_sec=1773, tv_nsec=0}, {[], 8}) = 1 (in [0], left {tv_sec=1772, tv_nsec=999995184})
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
read(0, "done\r\n", 4096)               = 6
sendto(14, "\1\0\0\0\0\0\0\0user.elangenhoven\0", 26, MSG_DONTWAIT, {sa_family=AF_UNIX, sun_path="/var/run/cyrus/socket/idle"}, 110) = 26
open("/run/cyrus/lock/e/user/elangenhoven.lock", O_RDWR|O_CREAT|O_TRUNC, 0666) = 19
fcntl(19, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
fcntl(12, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
fstat(12, {st_mode=S_IFREG|0600, st_size=46688, ...}) = 0
stat("/var/lib/cyrus/mailboxes.db", {st_mode=S_IFREG|0600, st_size=46688, ...}) = 0
fcntl(12, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", O_RDWR) = 20
fstat(20, {st_mode=S_IFREG|0600, st_size=229516, ...}) = 0
mmap(NULL, 245760, PROT_READ, MAP_SHARED, 20, 0) = 0x7f8473107000
fcntl(20, F_SETLKW, {l_type=F_WRLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
stat("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", {st_mode=S_IFREG|0600, st_size=229516, ...}) = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.header", O_RDONLY) = 21
fstat(21, {st_mode=S_IFREG|0600, st_size=266, ...}) = 0
mmap(NULL, 266, PROT_READ, MAP_SHARED, 21, 0) = 0x7f847320b000
munmap(0x7f847320b000, 266)             = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.expunge", O_RDWR) = -1 ENOENT (No such file or directory)
fcntl(20, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
fcntl(20, F_SETLKW, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
stat("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.index", {st_mode=S_IFREG|0600, st_size=229516, ...}) = 0
close(21)                               = 0
open("/var/spool/cyrus/mail/e/user/elangenhoven/cyrus.header", O_RDONLY) = 21
fstat(21, {st_mode=S_IFREG|0600, st_size=266, ...}) = 0
mmap(NULL, 266, PROT_READ, MAP_SHARED, 21, 0) = 0x7f847320b000
munmap(0x7f847320b000, 266)             = 0
fcntl(20, F_SETLKW, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=0, l_len=0}) = 0
close(21)                               = 0
close(20)                               = 0
munmap(0x7f8473107000, 245760)          = 0
close(19)                               = 0
write(1, "a04 OK Completed\r\n", 18)    = 18
getpid()                                = 10379
open("/run/cyrus/proc/10379.new", O_RDWR|O_CREAT|O_TRUNC, 0666) = 19
fstat(19, {st_mode=S_IFREG|0600, st_size=0, ...}) = 0
write(19, "imap\tlocalhost [127.0.0.1]\telang"..., 59) = 59
close(19)                               = 0
rename("/run/cyrus/proc/10379.new", "/run/cyrus/proc/10379") = 0
open("/var/lib/cyrus/msg/shutdown", O_RDONLY) = -1 ENOENT (No such file or directory)
stat("/var/lib/cyrus/user_deny.db", {st_mode=S_IFREG|0600, st_size=0, ...}) = 0
rt_sigprocmask(SIG_BLOCK, [INT QUIT ALRM TERM CHLD], [], 8) = 0
pselect6(1, [0], NULL, NULL, {tv_sec=1800, tv_nsec=0}, {[], 8}strace: Process 10379 detached
 <detached ...>

More information about the Info-cyrus mailing list