v2.3.15 IMAP connection hangs

Rosenbaum, Larry M. rosenbaumlm at ornl.gov
Tue Sep 15 14:35:48 EDT 2009


> From: Bron Gondwana [mailto:brong at fastmail.fm]
> 
>...
> That's kind of pointless, sorry.  I really need a trace of the imapd
> process, since that's what's locking up.

Here's another trace of an imapd process:

ornl71# truss -f -p 23237
23237:  accept(4, 0x00000000, 0x00000000, 1) (sleeping...)

(at this point I used Telnet to connect to port 143)

23237:  accept(4, 0x00000000, 0x00000000, 1)            = 10
23237:  fcntl(9, F_SETLKW, 0xFFBFF290)                  = 0
23237:  alarm(0)                                        = 0
23237:  getpid()                                        = 23237 [17305]
23237:  write(3, "\0\0\002\0\0 ZC5", 8)                 = 8
23237:  fstat(5, 0xFFBFEFC8)                            = 0
23237:  time()                                          = 1253039206
23237:  getpid()                                        = 23237 [17305]
23237:  putmsg(5, 0xFFBFE680, 0xFFBFE674, 0)            = 0
23237:  open("/var/run/syslog_door", O_RDONLY)          = 11
23237:  door_info(11, 0xFFBFE5B8)                       = 0
23237:  getpid()                                        = 23237 [17305]
23237:  door_call(11, 0xFFBFE5A0)                       = 0
23237:  close(11)                                       = 0
23237:  fcntl(10, F_DUP2FD, 0x00000000)                 = 0
23237:  fcntl(10, F_DUP2FD, 0x00000001)                 = 1
23237:  fcntl(10, F_DUP2FD, 0x00000002)                 = 2
23237:  close(10)                                       = 0
23237:  getpid()                                        = 23237 [17305]
23237:  write(3, "\0\0\003\0\0 ZC5", 8)                 = 8
23237:  getpeername(0, 0xFFBFF010, 0xFFBFEB84, 1)       = 0
23237:  open("/etc/netconfig", O_RDONLY|O_LARGEFILE)    = 10
23237:  fcntl(10, F_DUPFD, 0x00000100)                  Err#22 EINVAL
23237:  read(10, " # p r a g m a   i d e n".., 1024)    = 1024
23237:  read(10, " t s           t p i _ c".., 1024)    = 215
23237:  read(10, 0x00136728, 1024)                      = 0
23237:  lseek(10, 0, SEEK_SET)                          = 0
23237:  read(10, " # p r a g m a   i d e n".., 1024)    = 1024
23237:  read(10, " t s           t p i _ c".., 1024)    = 215
23237:  read(10, 0x00136728, 1024)                      = 0
23237:  close(10)                                       = 0
23237:  open("/dev/udp", O_RDONLY)                      = 10
23237:  ioctl(10, 0xC00C6982, 0xFFBFE8FC)               = 0
23237:  close(10)                                       = 0
23237:  open64("/var/run/name_service_door", O_RDONLY)  = 10
23237:  fcntl(10, F_SETFD, 0x00000001)                  = 0
23237:  door_info(10, 0xFEEC26C0)                       = 0
23237:  door_call(10, 0xFFBFC868)                       = 0
23237:  door_info(10, 0xFFBFC800)                       = 0
23237:  door_call(10, 0xFFBFC7E8)                       = 0
23237:  getsockname(0, 0xFFBFF110, 0xFFBFEB84, 1)       = 0
23237:  brk(0x0013D190)                                 = 0
23237:  brk(0x0013F190)                                 = 0
23237:  getpid()                                        = 23237 [17305]
23237:  open("/usr/local/cyrus/data/proc/23237", O_RDWR|O_CREAT|O_TRUNC, 0666) = 11
23237:  llseek(11, 0, SEEK_CUR)                         = 0
23237:  llseek(11, 0, SEEK_SET)                         = 0
23237:  fstat64(11, 0xFFBFE950)                         = 0
23237:  fstat64(11, 0xFFBFE7F8)                         = 0
23237:  ioctl(11, TCGETA, 0xFFBFE8DC)                   Err#25 ENOTTY
23237:  write(11, " e m a i l . o r n l . g".., 29)     = 29
23237:  llseek(11, 0, SEEK_CUR)                         = 29
23237:  fcntl(11, F_FREESP, 0xFFBFEA84)                 = 0
23237:  time()                                          = 1253039206
23237:  open("/usr/local/cyrus/data/msg/motd", O_RDONLY) Err#2 ENOENT
23237:  write(1, " *   O K   [ C A P A B I".., 126)     = 126
23237:  time()                                          = 1253039206
23237:  poll(0xFFBFB860, 1, 1800000)    (sleeping...)

(at this time I typed "0 logout" at the Telnet session)

23237:  poll(0xFFBFB860, 1, 1800000)                    = 1
23237:  time()                                          = 1253039218
23237:  time()                                          = 1253039218
23237:  poll(0xFFBFC858, 1, 1788000)                    = 1
23237:  time()                                          = 1253039218
23237:  time()                                          = 1253039218
23237:  read(0, " 0   l o g o u t\r\n", 4096)           = 10
23237:  time()                                          = 1253039218
23237:  poll(0xFFBFC858, 1, 1800000)    (sleeping...)

(At this point the trace paused, and the Telnet session got no response.
I aborted the Telnet session and the trace continued as follows:)

23237:  poll(0xFFBFC858, 1, 1800000)                    = 1
23237:  time()                                          = 1253039249
23237:  time()                                          = 1253039249
23237:  read(0, 0x00135688, 4096)                       = 0
23237:  llseek(11, 0, SEEK_CUR)                         = 29
23237:  close(11)                                       = 0
23237:  unlink("/usr/local/cyrus/data/proc/23237")      = 0
23237:  open("/dev/null", O_RDWR)                       = 11
23237:  shutdown(0, 0, 1)                               = 0
23237:  fcntl(11, F_DUP2FD, 0x00000000)                 = 0
23237:  shutdown(1, 0, 1)                               = 0
23237:  fcntl(11, F_DUP2FD, 0x00000001)                 = 1
23237:  shutdown(2, 0, 1)                               = 0
23237:  fcntl(11, F_DUP2FD, 0x00000002)                 = 2
23237:  close(11)                                       = 0
23237:  getpid()                                        = 23237 [17305]
23237:  write(3, "\0\0\001\0\0 ZC5", 8)                 = 8
23237:  sigaction(SIGALRM, 0xFFBFF170, 0x00000000)      = 0
23237:  sigaction(SIGHUP, 0xFFBFF170, 0x00000000)       = 0
23237:  sigaction(SIGINT, 0xFFBFF170, 0x00000000)       = 0
23237:  sigaction(SIGQUIT, 0xFFBFF170, 0x00000000)      = 0
23237:  alarm(114)                                      = 0
23237:  fcntl(9, F_SETLKW, 0xFFBFF3B8)  (sleeping...)

So what was it waiting for?

Thanks,
Larry
-------------- next part --------------
A non-text attachment was scrubbed...
Name: imapd.conf
Type: application/octet-stream
Size: 1077 bytes
Desc: imapd.conf
Url : http://lists.andrew.cmu.edu/pipermail/info-cyrus/attachments/20090915/759e1ecb/attachment.obj 


More information about the Info-cyrus mailing list