Hi,
I do not use the sieve plug-in.
I've attached a the strace log of a single process with an error. I've
obfuscated the user's name with <user>.
Just for reference this log was created using the following commands (as
root):
strace -tf dovecot -F 2> ~/dovecot.strace
grep 'pid 6477' /root/dovecot.strace > dovecot.6477.strace
If you would like the full unedited dovecot.strace log I can send you
that. However, it is 11MB and I'd prefer not to post the user
information it contains on the public mailing list.
Also, this may be nothing, but when I ran the command above with sudo,
(instead of su'ing) the problem did not seem to occur. I ran it for a
good half hour with no errors, and when I switched to su I got a couple
errors rather quickly. Although, that may have just been a coincidence.
Thanks,
Logan
Fabio Tranchitella wrote:
Hello,
* 2008-03-19 18:54, Logan Gunthorpe wrote:
Package: dovecot-common
Version: 1.0.rc15-2etch4
Severity: important
I began receiving the following log messages for numerous users after
upgrading to the most recent stable version of dovecot and changing the
configuration files to use mail_priviliged_group instead of
mail_extra_groups.
Mar 18 20:31:13 porter dovecot: POP3(logang): open(/var/mail/logang, O_CREAT)
failed: Permission denied
Mar 18 20:51:46 porter dovecot: IMAP(logang): open(/var/mail/logang, O_CREAT)
failed: Permission denied
The mail_priviliged_group is used for writing the lock file, but it seems
that the problem lies somewhere else and this is why adding the mail group
added to mail_priviliged_group doesn't fix it.
I don't see any obvious reason why you get these messages. Do you use the
sieve plug-in? Also, can you strace some of the processes to understand
what is happening?
Thanks,
[pid 6477] 14:29:44 dup2(42, 0 <unfinished ...>
[pid 6477] 14:29:44 <... dup2 resumed> ) = 0
[pid 6477] 14:29:44 dup2(42, 1 <unfinished ...>
[pid 6477] 14:29:44 <... dup2 resumed> ) = 1
[pid 6477] 14:29:44 dup2(45, 2 <unfinished ...>
[pid 6477] 14:29:44 <... dup2 resumed> ) = 2
[pid 6477] 14:29:44 fcntl64(0, F_GETFD <unfinished ...>
[pid 6477] 14:29:44 <... fcntl64 resumed> ) = 0
[pid 6477] 14:29:44 fcntl64(0, F_SETFD, 0) = 0
[pid 6477] 14:29:44 fcntl64(1, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(1, F_SETFD, 0) = 0
[pid 6477] 14:29:44 fcntl64(2, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(2, F_SETFD, 0) = 0
[pid 6477] 14:29:44 setrlimit(RLIMIT_DATA, {rlim_cur=262144*1024,
rlim_max=262144*1024}) = 0
[pid 6477] 14:29:44 setrlimit(RLIMIT_AS, {rlim_cur=262144*1024,
rlim_max=262144*1024}) = 0
[pid 6477] 14:29:44 setresgid32(-1, 100, -1) = 0
[pid 6477] 14:29:44 setresuid32(-1, 2190, -1) = 0
[pid 6477] 14:29:44 chdir("/home/<user>") = 0
[pid 6477] 14:29:44 setresuid32(-1, 0, -1) = 0
[pid 6477] 14:29:44 umask(077) = 077
[pid 6477] 14:29:44 close(11) = 0
[pid 6477] 14:29:44 execve("/usr/lib/dovecot/pop3", ["pop3"], [/* 37 vars */])
= 0
[pid 6477] 14:29:44 uname({sys="Linux", node="porter", ...}) = 0
[pid 6477] 14:29:44 brk(0) = 0x80c5000
[pid 6477] 14:29:44 fcntl64(0, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(1, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(2, F_GETFD) = 0
[pid 6477] 14:29:44 access("/etc/suid-debug", F_OK) = -1 ENOENT (No such file
or directory)
[pid 6477] 14:29:44 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such
file or directory)
[pid 6477] 14:29:44 mmap2(NULL, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7fc9000
[pid 6477] 14:29:44 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such
file or directory)
[pid 6477] 14:29:44 open("/etc/ld.so.cache", O_RDONLY) = 3
[pid 6477] 14:29:44 fstat64(3, {st_mode=S_IFREG|0644, st_size=25539, ...}) = 0
[pid 6477] 14:29:44 mmap2(NULL, 25539, PROT_READ, MAP_PRIVATE, 3, 0) =
0xb7fc2000
[pid 6477] 14:29:44 close(3) = 0
[pid 6477] 14:29:44 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such
file or directory)
[pid 6477] 14:29:44 open("/lib/tls/libdl.so.2", O_RDONLY) = 3
[pid 6477] 14:29:44 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\20\f\0"..., 512) = 512
[pid 6477] 14:29:44 fstat64(3, {st_mode=S_IFREG|0644, st_size=9592, ...}) = 0
[pid 6477] 14:29:44 mmap2(NULL, 12404, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7fbe000
[pid 6477] 14:29:44 mmap2(0xb7fc0000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1) = 0xb7fc0000
[pid 6477] 14:29:44 close(3) = 0
[pid 6477] 14:29:44 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such
file or directory)
[pid 6477] 14:29:44 open("/lib/tls/libc.so.6", O_RDONLY) = 3
[pid 6477] 14:29:44 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\240O\1"..., 512) = 512
[pid 6477] 14:29:44 fstat64(3, {st_mode=S_IFREG|0644, st_size=1245488, ...}) = 0
[pid 6477] 14:29:44 mmap2(NULL, 1251484, PROT_READ|PROT_EXEC,
MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7e8c000
[pid 6477] 14:29:44 mmap2(0xb7fb4000, 28672, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x128) = 0xb7fb4000
[pid 6477] 14:29:44 mmap2(0xb7fbb000, 10396, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7fbb000
[pid 6477] 14:29:44 close(3) = 0
[pid 6477] 14:29:44 mmap2(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7e8b000
[pid 6477] 14:29:44 mmap2(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7e8a000
[pid 6477] 14:29:44 mprotect(0xb7fb4000, 20480, PROT_READ) = 0
[pid 6477] 14:29:44 set_thread_area({entry_number:-1 -> 6,
base_addr:0xb7e8a6c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0,
limit_in_pages:1, seg_not_present:0, useable:1}) = 0
[pid 6477] 14:29:44 munmap(0xb7fc2000, 25539) = 0
[pid 6477] 14:29:44 time(NULL) = 1205958584
[pid 6477] 14:29:44 brk(0) = 0x80c5000
[pid 6477] 14:29:44 brk(0x80ee000) = 0x80ee000
[pid 6477] 14:29:44 uname({sys="Linux", node="porter", ...}) = 0
[pid 6477] 14:29:44 getpid() = 6477
[pid 6477] 14:29:44 open("/dev/urandom", O_RDONLY|O_LARGEFILE) = 3
[pid 6477] 14:29:44 read(3, "\7\f\243Y", 4) = 4
[pid 6477] 14:29:44 fcntl64(3, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(3, F_SETFD, FD_CLOEXEC) = 0
[pid 6477] 14:29:44 geteuid32() = 0
[pid 6477] 14:29:44 getegid32() = 100
[pid 6477] 14:29:44 getgid32() = 0
[pid 6477] 14:29:44 setresgid32(100, 100, 8) = 0
[pid 6477] 14:29:44 open("/proc/sys/kernel/ngroups_max", O_RDONLY) = 4
[pid 6477] 14:29:44 read(4, "65536\n", 31) = 6
[pid 6477] 14:29:44 close(4) = 0
[pid 6477] 14:29:44 socket(PF_FILE, SOCK_STREAM, 0) = 4
[pid 6477] 14:29:44 fcntl64(4, F_GETFL) = 0x2 (flags O_RDWR)
[pid 6477] 14:29:44 fcntl64(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[pid 6477] 14:29:44 connect(4, {sa_family=AF_FILE,
path="/var/run/nscd/socket"}, 110) = 0
[pid 6477] 14:29:44 poll([{fd=4, events=POLLOUT|POLLERR|POLLHUP,
revents=POLLOUT}], 1, 5000) = 1
[pid 6477] 14:29:44 writev(4, [{"\2\0\0\0\f\0\0\0\6\0\0\0", 12}, {"group\0",
6}], 2) = 18
[pid 6477] 14:29:44 poll([{fd=4, events=POLLIN|POLLERR|POLLHUP,
revents=POLLIN|POLLHUP}], 1, 5000) = 1
[pid 6477] 14:29:44 recvmsg(4, {msg_name(0)=NULL, msg_iov(1)=[{"group\0", 6}],
msg_controllen=16, {cmsg_len=16, cmsg_level=SOL_SOCKET, cmsg_type=SCM_RIGHTS,
{5}}, msg_flags=0}, MSG_NOSIGNAL) = 6
[pid 6477] 14:29:44 fstat64(5, {st_mode=S_IFREG|0600, st_size=217016, ...}) = 0
[pid 6477] 14:29:44 pread64(5,
"\1\0\0\0h\0\0\0\226\v\0\0\1\0\0\0\0004\314G\0\0\0\0\323"..., 104, 0) = 104
[pid 6477] 14:29:44 mmap2(NULL, 217016, PROT_READ, MAP_SHARED, 5, 0) =
0xb7e55000
[pid 6477] 14:29:44 close(5) = 0
[pid 6477] 14:29:44 close(4) = 0
[pid 6477] 14:29:44 setgroups32(7, [10135, 10134, 10095, 10024, 10037, 10056,
100]) = 0
[pid 6477] 14:29:44 getgroups32(0, NULL) = 7
[pid 6477] 14:29:44 getgroups32(7, [100, 10024, 10037, 10056, 10095, 10134,
10135]) = 7
[pid 6477] 14:29:44 setuid32(2190) = 0
[pid 6477] 14:29:44 setuid32(0) = -1 EPERM (Operation not permitted)
[pid 6477] 14:29:44 getgid32() = 100
[pid 6477] 14:29:44 getegid32() = 100
[pid 6477] 14:29:44 setgid32(0) = -1 EPERM (Operation not permitted)
[pid 6477] 14:29:44 gettimeofday({1205958584, 794317}, {360, 0}) = 0
[pid 6477] 14:29:44 pipe([4, 5]) = 0
[pid 6477] 14:29:44 fcntl64(4, F_GETFL) = 0 (flags O_RDONLY)
[pid 6477] 14:29:44 fcntl64(4, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
[pid 6477] 14:29:44 fcntl64(5, F_GETFL) = 0x1 (flags O_WRONLY)
[pid 6477] 14:29:44 fcntl64(5, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
[pid 6477] 14:29:44 fcntl64(4, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(4, F_SETFD, FD_CLOEXEC) = 0
[pid 6477] 14:29:44 fcntl64(5, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(5, F_SETFD, FD_CLOEXEC) = 0
[pid 6477] 14:29:44 rt_sigaction(SIGIO, {SIG_IGN}, {SIG_IGN}, 8) = 0
[pid 6477] 14:29:44 rt_sigaction(SIGRT_2, {0x80ac2a0, [],
SA_RESTART|SA_NOMASK|SA_SIGINFO}, NULL, 8) = 0
[pid 6477] 14:29:44 rt_sigaction(SIGINT, {0x80ad120, [], 0}, NULL, 8) = 0
[pid 6477] 14:29:44 pipe([6, 7]) = 0
[pid 6477] 14:29:44 fcntl64(6, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(6, F_SETFD, FD_CLOEXEC) = 0
[pid 6477] 14:29:44 fcntl64(7, F_GETFD) = 0
[pid 6477] 14:29:44 fcntl64(7, F_SETFD, FD_CLOEXEC) = 0
[pid 6477] 14:29:44 rt_sigaction(SIGTERM, {0x80ad120, [], 0}, NULL, 8) = 0
[pid 6477] 14:29:44 rt_sigaction(SIGPIPE, {SIG_IGN}, NULL, 8) = 0
[pid 6477] 14:29:44 rt_sigaction(SIGALRM, {0x80acc20, [], 0}, NULL, 8) = 0
[pid 6477] 14:29:44 lstat64("/home/<user>/.mail", {st_mode=S_IFDIR|0700,
st_size=4096, ...}) = 0
[pid 6477] 14:29:44 fcntl64(0, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK)
[pid 6477] 14:29:44 fcntl64(0, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[pid 6477] 14:29:44 fcntl64(1, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK)
[pid 6477] 14:29:44 fcntl64(1, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[pid 6477] 14:29:44 fstat64(0, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0
[pid 6477] 14:29:44 _llseek(1, 0, 0xbfc0acf0, SEEK_CUR) = -1 ESPIPE (Illegal
seek)
[pid 6477] 14:29:44 getsockname(1, {sa_family=AF_FILE, path="é·"}, [2]) = 0
[pid 6477] 14:29:44 open("/var/mail/<user>",
O_RDWR|O_CREAT|O_EXCL|O_LARGEFILE, 0660) = -1 EACCES (Permission denied)
[pid 6477] 14:29:44 write(2, "\1Eopen(/var/mail/<user>, O_CREAT) "..., 58) = 58
[pid 6477] 14:29:44 open("/etc/localtime", O_RDONLY <unfinished ...>
[pid 6477] 14:29:44 <... open resumed> ) = 8
[pid 6477] 14:29:44 fstat64(8, <unfinished ...>
[pid 6477] 14:29:44 <... fstat64 resumed> {st_mode=S_IFREG|0644, st_size=874,
...}) = 0
[pid 6477] 14:29:44 fstat64(8, <unfinished ...>
[pid 6477] 14:29:44 <... fstat64 resumed> {st_mode=S_IFREG|0644, st_size=874,
...}) = 0
[pid 6477] 14:29:44 mmap2(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid 6477] 14:29:44 <... mmap2 resumed> ) = 0xb7fc8000
[pid 6477] 14:29:44 read(8, <unfinished ...>
[pid 6477] 14:29:44 <... read resumed>
"TZif\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\5\0\0\0\5\0"..., 4096) = 874
[pid 6477] 14:29:44 close(8 <unfinished ...>
[pid 6477] 14:29:44 <... close resumed> ) = 0
[pid 6477] 14:29:44 munmap(0xb7fc8000, 4096 <unfinished ...>
[pid 6477] 14:29:44 <... munmap resumed> ) = 0
[pid 6477] 14:29:44 stat64("/etc/localtime", <unfinished ...>
[pid 6477] 14:29:44 <... stat64 resumed> {st_mode=S_IFREG|0644, st_size=874,
...}) = 0
[pid 6477] 14:29:44 mkdir("/home/<user>/.mail/.imap/INBOX", 0770 <unfinished
...>
[pid 6477] 14:29:44 <... mkdir resumed> ) = -1 EEXIST (File exists)
[pid 6477] 14:29:44 stat64("/home/<user>/.mail/.imap/INBOX",
{st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
[pid 6477] 14:29:44 time(NULL) = 1205958584
[pid 6477] 14:29:44 open("/home/<user>/.mail/.imap/INBOX/dovecot.index",
O_RDWR|O_LARGEFILE) = 8
[pid 6477] 14:29:44 alarm(120) = 0
[pid 6477] 14:29:44 fcntl64(8, F_SETLKW64, {type=F_RDLCK, whence=SEEK_SET,
start=0, len=0}, 0xbfc0aa54) = 0
[pid 6477] 14:29:44 alarm(0) = 120
[pid 6477] 14:29:44 pread64(8,
"\7\0x\0\310\0\0\0(\0\0\0\1\0\0\0\332\367tF\0\0\0\0004\257"..., 512, 0) = 440
[pid 6477] 14:29:44 pread64(8,
"\32\1\0\0\10\0\0\0005\2\0\0\0\0\0\0004\20\0\0\264\310\36"..., 240, 200) = 240
[pid 6477] 14:29:44 open("/home/<user>/.mail/.imap/INBOX/dovecot.index.log",
O_RDWR|O_LARGEFILE) = 9
[pid 6477] 14:29:44 fstat64(9, {st_mode=S_IFREG|0600, st_size=110580, ...}) = 0
[pid 6477] 14:29:44 pread64(9,
"\1\0\30\0\332\367tF\1\0\0\0\0\0\0\0\0\0\0\0\332\367tF", 24, 0) = 24
[pid 6477] 14:29:44 fcntl64(8, F_SETLK64, {type=F_UNLCK, whence=SEEK_SET,
start=0, len=0}, 0xbfc0aab4) = 0
[pid 6477] 14:29:44 access("/var/mail/<user>", R_OK|W_OK) = 0
[pid 6477] 14:29:44 stat64("/var/mail/<user>", {st_mode=S_IFREG|0640,
st_size=1077178, ...}) = 0
[pid 6477] 14:29:44 alarm(120) = 0
[pid 6477] 14:29:44 fcntl64(9, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET,
start=0, len=0}, 0xbfc0aa24) = 0
[pid 6477] 14:29:44 alarm(0) = 120
[pid 6477] 14:29:44 stat64("/home/<user>/.mail/.imap/INBOX/dovecot.index.log",
{st_mode=S_IFREG|0600, st_size=110580, ...}) = 0
[pid 6477] 14:29:44 pread64(9, "", 1024, 110580) = 0
[pid 6477] 14:29:44 fcntl64(9, F_SETLK64, {type=F_UNLCK, whence=SEEK_SET,
start=0, len=0}, 0xbfc0aad4) = 0
[pid 6477] 14:29:44 open("/home/<user>/.mail/.imap/INBOX/dovecot.index.cache",
O_RDWR|O_LARGEFILE) = 10
[pid 6477] 14:29:44 mmap2(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7fc8000
[pid 6477] 14:29:44 pread64(10,
"\1\10\0\0\332\367tF\17\245uF\0\0\0\0\0\0\0\0\350\20\0\0"..., 4096, 0) = 4096
[pid 6477] 14:29:44 pread64(10,
"\1\10\0\0\332\367tF\17\245uF\0\0\0\0\0\0\0\0\350\20\0\0"..., 4096, 0) = 4096
[pid 6477] 14:29:44 mremap(0xb7fc8000, 4096, 8192, MREMAP_MAYMOVE) = 0xb7fc6000
[pid 6477] 14:29:44 pread64(10,
"\3\0\0\0\35&\0\0\0\0\0\0\0\0\0\0\24\0\0\0\3\0\0\0_\t\0"..., 4096, 4096) = 4096
[pid 6477] 14:29:44 gettimeofday({1205958584, 802947}, NULL) = 0
[pid 6477] 14:29:44 poll( <unfinished ...>
[pid 6477] 14:29:44 <... poll resumed> [{fd=6,
events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=2,
events=POLLERR|POLLHUP|POLLNVAL}, {fd=0,
events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL, revents=POLLIN}], 3, 9991) = 1
[pid 6477] 14:29:44 gettimeofday( <unfinished ...>
[pid 6477] 14:29:44 <... gettimeofday resumed> {1205958584, 810768}, {360, 0})
= 0
[pid 6477] 14:29:44 read(0, <unfinished ...>
[pid 6477] 14:29:44 <... read resumed> "STAT\r\n", 2048) = 6
[pid 6477] 14:29:44 setsockopt(1, SOL_TCP, TCP_CORK, [1], 4) = -1 EOPNOTSUPP
(Operation not supported)
[pid 6477] 14:29:44 write(1, "+OK 6 1091187\r\n", 15) = 15
[pid 6477] 14:29:44 gettimeofday( <unfinished ...>
[pid 6477] 14:29:44 <... gettimeofday resumed> {1205958584, 811229}, NULL) = 0
[pid 6477] 14:29:44 poll( <unfinished ...>
[pid 6477] 14:29:44 <... poll resumed> [{fd=6,
events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=2,
events=POLLERR|POLLHUP|POLLNVAL}, {fd=0,
events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL, revents=POLLIN}], 3, 9982) = 1
[pid 6477] 14:29:44 gettimeofday( <unfinished ...>
[pid 6477] 14:29:44 <... gettimeofday resumed> {1205958584, 833455}, {360, 0})
= 0
[pid 6477] 14:29:44 read(0, <unfinished ...>
[pid 6477] 14:29:44 <... read resumed> "UIDL\r\n", 2042) = 6
[pid 6477] 14:29:44 write(1, "+OK\r\n1 0000011a45edaf34\r\n2 00000"..., 128) =
128
[pid 6477] 14:29:44 gettimeofday( <unfinished ...>
[pid 6477] 14:29:44 <... gettimeofday resumed> {1205958584, 833925}, NULL) = 0
[pid 6477] 14:29:44 poll( <unfinished ...>
[pid 6477] 14:29:44 <... poll resumed> [{fd=6,
events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=2,
events=POLLERR|POLLHUP|POLLNVAL}, {fd=0,
events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL, revents=POLLIN}], 3, 9960) = 1
[pid 6477] 14:29:44 gettimeofday( <unfinished ...>
[pid 6477] 14:29:44 <... gettimeofday resumed> {1205958584, 856608}, {360, 0})
= 0
[pid 6477] 14:29:44 read(0, <unfinished ...>
[pid 6477] 14:29:44 <... read resumed> "LIST\r\n", 2036) = 6
[pid 6477] 14:29:44 write(1, "+OK 6 messages:\r\n1 41338\r\n2 3161"..., 75
<unfinished ...>
[pid 6477] 14:29:44 <... write resumed> ) = 75
[pid 6477] 14:29:44 gettimeofday( <unfinished ...>
[pid 6477] 14:29:44 <... gettimeofday resumed> {1205958584, 856990}, NULL) = 0
[pid 6477] 14:29:44 poll( <unfinished ...>
[pid 6477] 14:29:45 <... poll resumed> [{fd=6,
events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL}, {fd=2,
events=POLLERR|POLLHUP|POLLNVAL}, {fd=0,
events=POLLIN|POLLPRI|POLLERR|POLLHUP|POLLNVAL, revents=POLLIN}], 3, 9937) = 1
[pid 6477] 14:29:45 gettimeofday( <unfinished ...>
[pid 6477] 14:29:45 <... gettimeofday resumed> {1205958585, 9477}, {360, 0}) = 0
[pid 6477] 14:29:45 read(0, <unfinished ...>
[pid 6477] 14:29:45 <... read resumed> "QUIT\r\n", 2030) = 6
[pid 6477] 14:29:45 stat64("/var/mail/<user>", {st_mode=S_IFREG|0640,
st_size=1077178, ...}) = 0
[pid 6477] 14:29:45 alarm(120) = 0
[pid 6477] 14:29:45 fcntl64(9, F_SETLKW64, {type=F_WRLCK, whence=SEEK_SET,
start=0, len=0}, 0xbfc0a9d4) = 0
[pid 6477] 14:29:45 alarm(0) = 120
[pid 6477] 14:29:45 stat64("/home/<user>/.mail/.imap/INBOX/dovecot.index.log",
{st_mode=S_IFREG|0600, st_size=110580, ...}) = 0
[pid 6477] 14:29:45 pread64(9, "", 1024, 110580) = 0
[pid 6477] 14:29:45 fcntl64(9, F_SETLK64, {type=F_UNLCK, whence=SEEK_SET,
start=0, len=0}, 0xbfc0aa14) = 0
[pid 6477] 14:29:45 write(2, "\1IDisconnected: Logged out top=0"..., 68) = 68
[pid 6477] 14:29:45 write(1, "+OK Logging out.\r\n", 18 <unfinished ...>
[pid 6477] 14:29:45 <... write resumed> ) = 18
[pid 6477] 14:29:45 gettimeofday( <unfinished ...>
[pid 6477] 14:29:45 <... gettimeofday resumed> {1205958585, 10987}, NULL) = 0
[pid 6477] 14:29:45 close(9 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 munmap(0xb7fc6000, 8192 <unfinished ...>
[pid 6477] 14:29:45 <... munmap resumed> ) = 0
[pid 6477] 14:29:45 close(10 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 close(8 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 close(0 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 close(1 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 close(3 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 close(6 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 close(7 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 rt_sigaction(SIGRT_2, {SIG_IGN}, <unfinished ...>
[pid 6477] 14:29:45 <... rt_sigaction resumed> {0x80ac2a0, [],
SA_RESTART|SA_NOMASK|SA_SIGINFO}, 8) = 0
[pid 6477] 14:29:45 close(4 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 close(5 <unfinished ...>
[pid 6477] 14:29:45 <... close resumed> ) = 0
[pid 6477] 14:29:45 exit_group(0) = ?
