Hello poppers,
I am suddenly having a strange problem. No one can fetch mail.
I rebuilt with debugging enabled, but saw nothing surprising. A truss
shows this: the forked popper always crashes after unlinking the .lock
file and I cannot figure out why. Any clues?
Brian
-------- -------- -------- -------- -------- -------- -------- --------
11218: psargs: /usr/pkg/qpopper/sbin/popper 110 -l1 -f /etc/mail/qpopper.config
11218: poll(0xFFFFFFFF7FFFBC10, 1, -1) (sleeping...)
11218: poll(0xFFFFFFFF7FFFBC10, 1, -1) = 1
11218: accept(3, 0xFFFFFFFF7FFFFD80, 0xFFFFFFFF7FFFBD48, 1) = 5
11218: fcntl(5, F_GETFL, 0x00000000) = 130
11218: fstat(5, 0xFFFFFFFF7FFFBA40) = 0
11218: getsockopt(5, 65535, 8192, 0xFFFFFFFF7FFFBB78, 0xFFFFFFFF7FFFBB70, 0) = 0
11218: fstat(5, 0xFFFFFFFF7FFFBA40) = 0
11218: getsockopt(5, 65535, 8192, 0xFFFFFFFF7FFFBB78, 0xFFFFFFFF7FFFBB74, 0) = 0
11218: setsockopt(5, 65535, 8192, 0xFFFFFFFF7FFFBB78, 4, 0) = 0
11218: fcntl(5, F_SETFL, 0x00000002) = 0
11218: fork() = 11791
11791: fork() (returning as child ...) = 11218
11218: close(5) = 0
11791: sigaction(SIGCLD, 0xFFFFFFFF7FFFBA70, 0xFFFFFFFF7FFFBB40) = 0
11791: sigaction(SIGTERM, 0xFFFFFFFF7FFFBA70, 0xFFFFFFFF7FFFBB40) = 0
11791: sigaction(SIGHUP, 0xFFFFFFFF7FFFBA70, 0xFFFFFFFF7FFFBB40) = 0
11791: close(3) = 0
11791: fcntl(5, F_DUP2FD, 0x00000000) = 0
11791: fcntl(5, F_DUP2FD, 0x00000001) = 1
11791: fcntl(5, F_DUP2FD, 0x00000002) = 2
11791: close(5) = 0
11791: time() = 1031247744
11791: umask(07) = 022
11791: sigaction(SIGHUP, 0xFFFFFFFF7FFFA230, 0xFFFFFFFF7FFFA300) = 0
11791: sigaction(SIGPIPE, 0xFFFFFFFF7FFFA230, 0xFFFFFFFF7FFFA300) = 0
11791: fstat(4, 0xFFFFFFFF7FFFA190) = 0
11791: open("/etc/mail/qpopper.config", O_RDONLY) = 3
11791: fstat(3, 0xFFFFFFFF7FFF9BE0) = 0
11791: ioctl(3, TCGETA, 0xFFFFFFFF7FFF9B1C) Err#25 ENOTTY
11791: read(3, " # - - - - - - - - - - -".., 8192) = 8192
11791: read(3, " s s l ' u s e d w i".., 8192) = 8192
11791: read(3, " e s . Q p o p p e r".., 8192) = 4076
11791: fstat(4, 0xFFFFFFFF7FFF9BA0) = 0
11791: close(4) = 0
11791: fstat(-1, 0xFFFFFFFF7FFF9BA0) Err#9 EBADF
11791: read(3, 0x10016B144, 8192) = 0
11791: uname(0xFFFFFFFF7FFF9D08) = 1
11791: brk(0x10016D8E0) = 0
11791: brk(0x1001718E0) = 0
11791: open("/etc/netconfig", O_RDONLY) = 4
11791: fstat(4, 0xFFFFFFFF7FFF9910) = 0
11791: ioctl(4, TCGETA, 0xFFFFFFFF7FFF984C) Err#25 ENOTTY
11791: read(4, " # p r a g m a i d e n".., 8192) = 1239
11791: read(4, 0x10016DD14, 8192) = 0
11791: lseek(4, 0, SEEK_CUR) = 1239
11791: lseek(4, 0, SEEK_SET) = 0
11791: read(4, " # p r a g m a i d e n".., 8192) = 1239
11791: read(4, 0x10016DD14, 8192) = 0
11791: lseek(4, 0, SEEK_CUR) = 1239
11791: close(4) = 0
11791: open("/dev/udp", O_RDONLY) = 4
11791: ioctl(4, 0xC00C6982, 0xFFFFFFFF7FFF9EF4) = 0
11791: close(4) = 0
11791: open("/etc/.name_service_door", O_RDONLY) = 4
11791: fcntl(4, F_SETFD, 0x00000001) = 0
11791: door_info(4, 0xFFFFFFFF7E3BF5C0) = 0
11791: door_call(4, 0xFFFFFFFF7FFF7E28) = 0
11791: getpeername(0, 0xFFFFFFFF7FFFA320, 0xFFFFFFFF7FFFA2E4, 1) = 0
11791: brk(0x1001718E0) = 0
11791: brk(0x1001758E0) = 0
11791: brk(0x1001758E0) = 0
11791: brk(0x1001798E0) = 0
11791: brk(0x1001798E0) = 0
11791: brk(0x10017D8E0) = 0
11791: brk(0x10017D8E0) = 0
11791: brk(0x1001818E0) = 0
11791: brk(0x1001818E0) = 0
11791: brk(0x1001858E0) = 0
11791: time() = 1031247744
11791: open("/tmp/qpopper.junk.junk", O_RDWR|O_CREAT, 01156000) = 5
11791: fcntl(5, F_FREESP, 0xFFFFFFFF7FFF80B0) = 0
11791: read(5, "\0\0\0\0\0\0\0\0\0\0\0\0".., 4098) = 4098
11791: close(5) = 0
11791: unlink("/tmp/qpopper.junk") Err#2 ENOENT
11791: brk(0x1001858E0) = 0
11791: brk(0x1001898E0) = 0
11791: open("/etc/ssl/certs/cert.pem", O_RDONLY) = 5
11791: fstat(5, 0xFFFFFFFF7FFF7910) = 0
11791: ioctl(5, TCGETA, 0xFFFFFFFF7FFF784C) Err#25 ENOTTY
11791: read(5, " - - - - - B E G I N R".., 8192) = 2020
11791: lseek(5, 0xFFFFFFFFFFFFFF64, SEEK_CUR) = 1864
11791: close(5) = 0
11791: open("/etc/ssl/certs/cert.pem", O_RDONLY) = 5
11791: fstat(5, 0xFFFFFFFF7FFF7510) = 0
11791: ioctl(5, TCGETA, 0xFFFFFFFF7FFF744C) Err#25 ENOTTY
11791: read(5, " - - - - - B E G I N R".., 8192) = 2020
11791: getpid() = 11791 [11218]
11791: lseek(5, 0xFFFFFFFFFFFFFB93, SEEK_CUR) = 887
11791: close(5) = 0
11791: ioctl(0, TCGETA, 0xFFFFFFFF7FFF994C) Err#22 EINVAL
11791: fstat(0, 0xFFFFFFFF7FFF9A10) = 0
11791: write(0, " + O K Q p o p p e r ".., 57) = 57
11791: sigaction(SIGALRM, 0xFFFFFFFF7FFFA140, 0xFFFFFFFF7FFFA210) = 0
11791: alarm(120) = 0
11218: poll(0xFFFFFFFF7FFFBC10, 1, -1) (sleeping...)
11791: read(0, 0xFFFFFFFF7FFFAB84, 2048) (sleeping...)
11791: read(0, " u s e r g u e s t\r\n", 2048) = 12
11791: alarm(0) = 117
11791: sigaction(SIGALRM, 0xFFFFFFFF7FFFA140, 0xFFFFFFFF7FFFA210) = 0
11791: door_info(4, 0xFFFFFFFF7FFF9CA8) = 0
11791: door_call(4, 0xFFFFFFFF7FFF9C78) = 0
11791: write(0, " + O K P a s s w o r d".., 34) = 34
11791: sigaction(SIGALRM, 0xFFFFFFFF7FFFA140, 0xFFFFFFFF7FFFA210) = 0
11791: alarm(120) = 0
11791: read(0, 0xFFFFFFFF7FFFAB84, 2048) (sleeping...)
11791: read(0, " p a s s g a s t\r\n", 2048) = 11
11791: alarm(0) = 119
11791: sigaction(SIGALRM, 0xFFFFFFFF7FFFA140, 0xFFFFFFFF7FFFA210) = 0
11791: time() = 1031247749
11791: open("/etc/mail/qpopper-users.deny", O_RDONLY) = 5
11791: fstat(5, 0xFFFFFFFF7FFFA010) = 0
11791: brk(0x1001898E0) = 0
11791: brk(0x10018D8E0) = 0
11791: ioctl(5, TCGETA, 0xFFFFFFFF7FFF9F4C) Err#25 ENOTTY
11791: read(5, 0x1001881B4, 8192) = 0
11791: lseek(5, 0, SEEK_CUR) = 0
11791: close(5) = 0
11791: open("/etc/nsswitch.conf", O_RDONLY) = 5
11791: fstat(5, 0xFFFFFFFF7FFF9BA0) = 0
11791: ioctl(5, TCGETA, 0xFFFFFFFF7FFF9ADC) Err#25 ENOTTY
11791: read(5, " #\n # / e t c / n s s".., 8192) = 661
11791: read(5, 0x100188A24, 8192) = 0
11791: lseek(5, 0, SEEK_CUR) = 661
11791: close(5) = 0
11791: open("/usr/lib/64/nss_files.so.1", O_RDONLY) = 5
11791: fstat(5, 0xFFFFFFFF7FFF94D0) = 0
11791: mmap(0x00000000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE, 5, 0) =
0xFFFFFFFF7E000000
11791: mmap(0x00000000, 1081344, PROT_READ|PROT_EXEC, MAP_PRIVATE, 5, 0) =
0xFFFFFFFF7D600000
11791: mmap(0xFFFFFFFF7D706000, 3690, PROT_READ|PROT_WRITE|PROT_EXEC,
MAP_PRIVATE|MAP_FIXED, 5, 24576) = 0xFFFFFFFF7D706000
11791: munmap(0xFFFFFFFF7D606000, 1048576) = 0
11791: memcntl(0xFFFFFFFF7D600000, 10472, MC_ADVISE, MADV_WILLNEED, 0, 0) = 0
11791: close(5) = 0
11791: munmap(0xFFFFFFFF7E000000, 8192) = 0
11791: open("/etc/shadow", O_RDONLY) = 5
11791: fstat(5, 0xFFFFFFFF7FFF9BC0) = 0
11791: ioctl(5, TCGETA, 0xFFFFFFFF7FFF9AFC) Err#25 ENOTTY
11791: read(5, " r o o t : d j y R w E w".., 8192) = 2101
11791: lseek(5, 0xFFFFFFFFFFFFF945, SEEK_CUR) = 378
11791: close(5) = 0
11791: time() = 1031247749
11791: munmap(0xFFFFFFFF7D706000, 3690) = 0
11791: munmap(0xFFFFFFFF7D600000, 24518) = 0
11791: stat("/var/mail", 0xFFFFFFFF7FFFA1D0) = 0
11791: setgid(6) = 0
11791: setgroups(1, 0xFFFFFFFF7FFFBAF4) = 0
11791: setuid(205) = 0
11791: open("/var/mail/.guest.pop", O_RDWR|O_CREAT, 0660) = 5
11791: fstat(5, 0xFFFFFFFF7FFFA1D0) = 0
11791: fcntl(5, F_SETLK, 0xFFFFFFFF7FFF9B80) = 0
11791: stat("/var/mail/.guest.pop", 0xFFFFFFFF7FFF9D50) = 0
11791: fstat(5, 0xFFFFFFFF7FFF9CD0) = 0
11791: lstat("/var/mail/guest.lock", 0xFFFFFFFF7FFF9B40) Err#2 ENOENT
11791: open("/var/mail/guest.lock", O_WRONLY|O_CREAT|O_EXCL, 0600) = 6
11791: lstat("/var/mail/guest.lock", 0xFFFFFFFF7FFF9B40) = 0
11791: fcntl(6, F_SETLK, 0xFFFFFFFF7FFF98E0) = 0
11791: getpid() = 11791 [11218]
11791: uname(0xFFFFFFFF7FFF9428) = 1
11791: write(6, " 1 1 7 9 1 @ m a r k . b".., 18) = 18
11791: fcntl(6, F_SETLKW, 0xFFFFFFFF7FFF98E0) = 0
11791: close(6) = 0
11791: time() = 1031247749
11791: open("/var/mail/guest", O_RDWR) Err#2 ENOENT
11791: unlink("/var/mail/.guest.cache") Err#2 ENOENT
11791: lseek(5, 0, SEEK_CUR) = 0
11791: lseek(5, 0, SEEK_END) = 0
11791: lseek(5, 0, SEEK_CUR) = 0
11791: unlink("/var/mail/guest.lock") = 0
11791: Incurred fault #6, FLTBOUNDS %pc = 0x100031150
11791: siginfo: SIGSEGV SEGV_MAPERR addr=0x01010584
11791: Received signal #11, SIGSEGV [default]
11791: siginfo: SIGSEGV SEGV_MAPERR addr=0x01010584
11791: *** process killed ***
