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
br...@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
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 ...>
----
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