Re: IDLE not working for one account only

Neil Price <[email protected]>
Newsgroups gmane.mail.imap.cyrus
Message-ID <[email protected]> 
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
>   [email protected]
>
>
>
>
> ----
> 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

----
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
imapd.strace (text/plain, 8.6 KB) 
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 ...>
lmpx.com only provides a reader for public news (NNTP) servers. It is not affiliated with the servers or forums shown here and is not responsible for the content of articles, which is written by their respective authors.