On Sat, Sep 07, 2019 at 11:53:58AM +0200, Ede Wolf wrote:
> > > So it is a binary, thats useful information. Having specified /opt/smtpd
> > > as prefix during ./configure, it is located here:
> > > 
> > > /opt/smptd/libexec/opensmtpd/mail.lmtp
> > > 
> > 
> > Throw it in /usr/local/libexec/smtpd/ and see what happens. May be a build 
> > tools bug.
> > 
> 
> Excellent idea, however, the error stays the same. No change, despite
> copying the whole opensmtpd folder to /usr/local/libexec
> 
> 
> Even strace does not reveal the path it is looking for:
> 
> 
> expand: 0x56284c3f4338: expand_insert() called for
> address:m...@example.com[parent=(nil), rule=(nil)]
> expand: 0x56284c3f4338: inserted node 0x56284c3f6030
> expand: lka_expand: address: m...@example.com [depth=0]
> lookup: match "37.120.186.114" as NETADDR in table static:<anyhost> -> true
> lookup: match "example.com" as DOMAIN in table static:<dynamic:0> -> true
> rule #1 matched: match from any for domain <dynamic:0> action deliver
> lookup: lookup "m...@example.com" as ALIAS in table static:vusers -> none
> lookup: lookup "mary" as ALIAS in table static:vusers -> none
> lookup: lookup "@example.com" as ALIAS in table static:vusers -> none
> lookup: lookup "@" as ALIAS in table static:vusers -> "lmtpd"
> expand: 0x56284c3f3b10: expand_insert() called for
> username:lmtpd[parent=(nil), rule=(nil)]
> expand: 0x56284c3f3b10: inserted node 0x56284c3f6590
> expand: 0x56284c3f4338: expand_insert() called for
> username:lmtpd[parent=0x56284c3f6030, rule=0x56284c403e50,
> dispatcher=0x56284c405750]
> expand: 0x56284c3f4338: inserted node 0x56284c3f6af0
> expand: 0x56284c3f3b10: clearing expand tree
> expand: 0x56284c3f3b10: freeing expand tree
> debug: aliases_virtual_get: '@example.com' resolved to 1 nodes
> expand: lka_expand: username: lmtpd [depth=1, sameuser=0]
> lookup: lookup "lmtpd@" as ALIAS in table static:vusers -> none
> lookup: lookup "lmtpd" as ALIAS in table static:vusers -> none
> lookup: lookup "@" as ALIAS in table static:vusers -> "lmtpd"
> expand: 0x56284c3ed110: expand_insert() called for
> username:lmtpd[parent=(nil), rule=(nil)]
> expand: 0x56284c3ed110: inserted node 0x56284c3f6590
> expand: 0x56284c3f4338: expand_insert() called for
> username:lmtpd[parent=0x56284c3f6af0, rule=0x56284c403e50,
> dispatcher=0x56284c405750]
> expand: 0x56284c3f4338: setting sameuser = 1
> expand: 0x56284c3f4338: inserted node 0x56284c3f7050
> expand: 0x56284c3ed110: clearing expand tree
> expand: 0x56284c3ed110: freeing expand tree
> debug: aliases_virtual_get: '@' resolved to 1 nodes
> expand: lka_expand: username: lmtpd [depth=2, sameuser=1]
> lookup: lookup "lmtpd" as USERINFO in table getpwnam:<getpwnam> ->
> "115:115:/opt/smptd/var/lmtpd"
> [{EPOLLIN, {u32=6, u64=6}}], 32, -1) = 1
> epoll_ctl(3, EPOLL_CTL_DEL, 6, 0x7ffeb16e607c) = 0
> socket(AF_INET, SOCK_DGRAM, IPPROTO_IP) = 12
> socket(AF_INET, SOCK_DGRAM, IPPROTO_IP) = 13
> close(12)                               = 0
> close(13)                               = 0
> recvmsg(6, {msg_name=NULL, msg_namelen=0, 
> msg_iov=[{iov_base="4\0\0\0(\21\0\0\0\0\0\0\237\25\0\0[\300\213\3725\333\374!\0lmtpd\0\0"...,
> iov_len=65535}], msg_iovlen=1, msg_controllen=0, msg_flags=0}, 0) = 4392
> stat("/opt/smptd/var/lmtpd", {st_mode=S_IFDIR
> openat(AT_FDCWD, "/opt/smptd/var/lmtpd/.forward", O_RDONLY
> epoll_ctl(3, EPOLL_CTL_ADD, 6, {EPOLLIN
> epoll_ctl(3, EPOLL_CTL_DEL, 6, 0x7ffeb16e5fdc) = 0
> epoll_ctl(3, EPOLL_CTL_ADD, 6, {EPOLLIN
> epoll_wait(3, [{EPOLLOUT, {u32=6, u64=6}}], 32, -1) = 1
> epoll_ctl(3, EPOLL_CTL_DEL, 6, 0x7ffeb16e607c) = 0
> sendmsg(6, {msg_name=NULL, msg_namelen=0, 
> msg_iov=[{iov_base="4\0\0\0(\21\0\0\0\0\0\0\234\25\0\0[\300\213\3725\333\374!\1lmtpd\0\0"...,
> iov_len=4392}], msg_iovlen=1, msg_controllen=0, msg_flags=0}, 0) = 4392
> epoll_ctl(3, EPOLL_CTL_ADD, 6, {EPOLLIN, {u32=6, u64=6}}expand: no forward
> for user lmtpd, just deliver
> ) = 0
> epoll_wait(3, expand: 0x56284c3f4338: clearing expand tree
> smtp: 0x564267537b60: fd 14 from queue
> smtp: 0x564267537b60: message fd 14
> smtp: 0x564267537b60: message begin
> debug: 0x564267537b60: end of message, error=0
> 21fcdb35fa8bc05b smtp message msgid=22c2f515 size=245 nrcpt=1 proto=ESMTP
> 21fcdb35fa8bc05b smtp envelope evpid=22c2f5151c4decec
> from=<sen...@nospam.net> to=<m...@example.com>
> debug: scheduler: evp:22c2f5151c4decec scheduled (mda)
> mda: new user 21fcdb36b331cade for "<getpwnam>:lmtpd" delivering as "lmtpd"
> debug: lka: userinfo <getpwnam>:lmtpd
> lookup: lookup "lmtpd" as USERINFO in table getpwnam:<getpwnam> ->
> "115:115:/opt/smptd/var/lmtpd"
> debug: mda: new session 21fcdb37f01f7374 for user "<getpwnam>:lmtpd" evpid
> 22c2f5151c4decec
> debug: mda: no more envelope for "<getpwnam>:lmtpd"
> debug: mda: got message fd 14 for session 21fcdb37f01f7374 evpid
> 22c2f5151c4decec
> debug: mda: querying mda fd for session 21fcdb37f01f7374 evpid
> 22c2f5151c4decec
> [{EPOLLIN, {u32=7, u64=7}}], 32, -1) = 1
> epoll_ctl(3, EPOLL_CTL_DEL, 7, 0x7ffeb16e607c) = 0
> socket(AF_INET, SOCK_DGRAM, IPPROTO_IP) = 12
> socket(AF_INET, SOCK_DGRAM, IPPROTO_IP) = 13
> close(12)                               = 0
> close(13)                               = 0
> recvmsg(7, {msg_name=NULL, msg_namelen=0, 
> msg_iov=[{iov_base="O\0\0\0($\0\0\0\0\0\0\240\25\0\0ts\37\3607\333\374!\10$\0\0\0\0\0\0"...,
> iov_len=65535}], msg_iovlen=1, msg_controllen=0, msg_flags=0}, 0) = 9256
> write(2, "debug: smtpd: forking mda for se"..., 71debug: smtpd: forking mda
> for session 21fcdb37f01f7374: lmtpd as lmtpd
> ) = 71
> openat(AT_FDCWD, "/var/db/passwd.db", O_RDONLY
> (No such file or directory)
> openat(AT_FDCWD, "/etc/passwd", O_RDONLY
> lseek(12, 0, SEEK_CUR)                  = 0
> fstat(12, {st_mode=S_IFREG
> read(12, "root:x:0:0:root:/root:/bin/bash\n"..., 4096) = 1284
> close(12)                               = 0
> pipe([12, 13])                          = 0
> getpid()                                = 5532
> openat(AT_FDCWD, "/tmp/smtpd.out.XXXXXUS9f5n", O_RDWR
> 0600) = 14
> unlink("/tmp/smtpd.out.XXXXXUS9f5n")    = 0
> clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID
> child_tidptr=0x7f4488d1aa10) = 5540
> close(12)                               = 0
> epoll_ctl(3, EPOLL_CTL_ADD, 7, {EPOLLIN
> epoll_ctl(3, EPOLL_CTL_DEL, 7, 0x7ffeb16e5fdc) = 0
> epoll_ctl(3, EPOLL_CTL_ADD, 7, {EPOLLIN
> epoll_wait(3, [{EPOLLOUT, {u32=7, u64=7}}], 32, -1) = 1
> epoll_ctl(3, EPOLL_CTL_DEL, 7, 0x7ffeb16e607c) = 0
> sendmsg(7, {msg_name=NULL, msg_namelen=0,
> msg_iov=[{iov_base="O\0\0\0\30\0\1\0\0\0\0\0\234\25\0\0ts\37\3607\333\374!",
> iov_len=24}], msg_iovlen=1, msg_control=[{cmsg_len=20,
> cmsg_level=SOL_SOCKET, cmsg_type=SCM_RIGHTS, cmsg_data=[13]}],
> msg_controllen=24, msg_flags=0}, 0) = 24
> close(13)                               = 0
> epoll_ctl(3, EPOLL_CTL_ADD, 7, {EPOLLIN, {u32=7, u64=7}}) = 0
> epoll_wait(3, debug: mda: got mda fd 15 for session 21fcdb37f01f7374 evpid
> 22c2f5151c4decec
> debug: mda: end-of-file for session 21fcdb37f01f7374 evpid 22c2f5151c4decec
> debug: mda: all data sent for session 21fcdb37f01f7374 evpid
> 22c2f5151c4decec
> 0x564fc589b930, 32, -1)   = -1 EINTR (Interrupted system call)
> --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5540, si_uid=115,
> si_status=75, si_utime=0, si_stime=0} ---
> write(11, "\21", 1)                     = 1
> rt_sigreturn({mask=[]})                 = -1 EINTR (Interrupted system call)
> epoll_wait(3, [{EPOLLIN, {u32=10, u64=10}}], 32, -1) = 1
> read(10, "\21", 1024)                   = 1
> read(10, 0x7f4488f9d160, 1024)          = -1 EAGAIN (Resource temporarily
> unavailable)
> wait4(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 75}], WNOHANG, NULL) = 5540
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^

Looks like lmtpd isn't running.

> write(2, "debug: smtpd: mda process done f"..., 79debug: smtpd: mda process
> done for session 21fcdb37f01f7374: exited abnormally
> ) = 79
> epoll_ctl(3, EPOLL_CTL_DEL, 7, 0x7ffeb16e5fcc) = 0
> epoll_ctl(3, EPOLL_CTL_ADD, 7, {EPOLLIN
> wait4(-1, 0x7ffeb16e60f4, WNOHANG, NULL) = 0
> epoll_wait(3, [{EPOLLOUT, {u32=7, u64=7}}], 32, -1) = 1
> epoll_ctl(3, EPOLL_CTL_DEL, 7, 0x7ffeb16e607c) = 0
> sendmsg(7, {msg_name=NULL, msg_namelen=0, 
> msg_iov=[{iov_base="N\0\0\0003\0\1\0\0\0\0\0\234\25\0\0ts\37\3607\333\374!\1\0\0\0K\0\0\0"...,
> iov_len=51}], msg_iovlen=1, msg_control=[{cmsg_len=20,
> cmsg_level=SOL_SOCKET, cmsg_type=SCM_RIGHTS, cmsg_data=[14]}],
> msg_controllen=24, msg_flags=0}, 0) = 51
> close(14)                               = 0
> epoll_ctl(3, EPOLL_CTL_ADD, 7, {EPOLLIN, {u32=7, u64=7}}) = 0
> epoll_wait(3, 21fcdb36b331cade mda delivery evpid=22c2f5151c4decec
> from=<sen...@nospam.net> to=<m...@example.com> rcpt=<m...@example.com>
> user=lmtpd delay=14s result=TempFail stat=Error (temporary failure:
> "mail.lmtp: No such file or directoryconnect")
> debug: mda: session 21fcdb37f01f7374 done
> debug: mda: user "lmtpd" becomes runnable
> debug: mda: all done for user "<getpwnam>:lmtpd"
> 
> 
> 
> 
> 

Reply via email to