Hi,
I'm trying to implement a custom UsersRepository to integrate with our
currents software assets, which includes a security model (user and role
based access, with daos, based on hibernate),
I got everything running but when I tried to send emails they don't arribe
to the destination I mean james user mailbox.
package com.get.security.james;
import com.get.security.User;
import com.get.security.UserDao;
import org.apache.james.lifecycle.api.LogEnabled;
import org.apache.james.user.api.UsersRepository;
import org.apache.james.user.api.UsersRepositoryException;
import org.apache.james.user.api.model.User;
import org.slf4j.Logger;
import org.slf4j.LoggerFactory;
import org.springframework.security.authentication.encoding.PasswordEncoder;
import
org.springframework.security.authentication.encoding.ShaPasswordEncoder;
import javax.annotation.Resource;
import java.util.Iterator;
/**
* @author Larry Ruiz, 1/18/11
*/
public class UsersRepositoryImpl implements UsersRepository, LogEnabled {
private Logger logger =
LoggerFactory.getLogger(UsersRepositoryImpl.class);
private PasswordEncoder passwordEncoder = new ShaPasswordEncoder();
private UserDao userDao;
@Resource
public void setUserDao(UserDao userDao) {
this.userDao = userDao;
}
public void addUser(String name, String password) throws
UsersRepositoryException {
logger.debug("addUser called");
throw new UnsupportedOperationException();
}
public User getUserByName(String name) throws UsersRepositoryException {
logger.info("getUser... with name {}", name);
User u = userDao.findPorUsername(name);
if (u != null) {
return new UserImpl(u);
}
return null;
}
public void updateUser(User user) throws UsersRepositoryException {
logger.debug("updateUser called");
throw new UnsupportedOperationException();
}
public void removeUser(String name) throws UsersRepositoryException {
logger.debug("removeUser called");
throw new UnsupportedOperationException();
}
public boolean contains(String name) throws UsersRepositoryException {
logger.info("contains user {}", name);
return userDao.findPorUsername(name) != null;
}
public boolean test(String name, String password) throws
UsersRepositoryException {
logger.info("test... name {} y pass {}", name, password);
org.apache.james.user.api.model.User u = getUserByName(name);
if (u != null) {
return u.verifyPassword(password);
}
return false;
}
public int countUsers() throws UsersRepositoryException {
return userDao.getCount();
}
public Iterator<String> list() throws UsersRepositoryException {
logger.debug("list call");
return null;
}
public boolean supportVirtualHosting() throws UsersRepositoryException {
logger.debug("asked for virtualHosting support");
return false;
}
public void setLog(Logger logger) {
this.logger = logger;
}
class UserImpl implements org.apache.james.user.api.model.User {
User user;
public UserImpl(User user) {
this.user = user;
}
public String getUserName() {
return user.getUsername();
}
public boolean verifyPassword(String password) {
password = passwordEncoder.encodePassword(password, null);
return user.getPassword().equals(password);
}
public boolean setPassword(String s) {
throw new UnsupportedOperationException();
}
}
}
these the log when sending the email
jvm 1 | INFO 19:41:50,994 | james.imapserver | ID=864035458 Connection
established from localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:41:51,047 | james.imapserver | ID=864035458 Got <tag>:
E00000
jvm 1 | DEBUG 19:41:51,047 | james.imapserver | ID=864035458 Got
<command>: CAPABILITY
jvm 1 | DEBUG 19:41:51,055 | james.imapserver | ID=864035458 No mailbox
selected
jvm 1 | INFO 19:42:03,607 | james.smtpserver | ID=734973521 Connection
established from localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:03,615 | james.smtpserver | ID=734973521
org.apache.james.smtpserver.JamesWelcomeMessageHandler disconnect=false
jvm 1 | DEBUG 19:42:03,625 | james.smtpserver | ID=734973521
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: EHLO [IPv6:::1]
jvm 1 | DEBUG 19:42:03,625 | james.smtpserver | ID=734973521 Lookup
command handler for command: EHLO
jvm 1 | DEBUG 19:42:03,626 | james.smtpserver | ID=734973521
org.apache.james.protocols.smtp.core.esmtp.EhloCmdHandler: 250 [127.0.1.1
Hello [IPv6:::1] (localhost [0:0:0:0:0:0:0:1]), AUTH LOGIN PLAIN, AUTH=LOGIN
PLAIN, PIPELINING, ENHANCEDSTATUSCODES, 8BITMIME]
jvm 1 | DEBUG 19:42:03,631 | james.smtpserver | ID=734973521
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:03,633 | james.smtpserver | ID=734973521
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: QUIT
jvm 1 | DEBUG 19:42:03,633 | james.smtpserver | ID=734973521 Lookup
command handler for command: QUIT
jvm 1 | DEBUG 19:42:03,635 | james.smtpserver | ID=734973521
org.apache.james.protocols.smtp.core.QuitCmdHandler: 221 [2.0.0 127.0.1.1
Service closing transmission channel]
jvm 1 | INFO 19:42:03,636 | james.smtpserver | ID=734973521 Connection
closed for localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:03,637 | james.smtpserver | ID=734973521
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | INFO 19:42:23,661 | james.smtpserver | ID=1598618431 Connection
established from localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:23,661 | james.smtpserver | ID=1598618431
org.apache.james.smtpserver.JamesWelcomeMessageHandler disconnect=false
jvm 1 | DEBUG 19:42:23,667 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: EHLO [IPv6:::1]
jvm 1 | DEBUG 19:42:23,667 | james.smtpserver | ID=1598618431 Lookup
command handler for command: EHLO
jvm 1 | DEBUG 19:42:23,667 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.esmtp.EhloCmdHandler: 250 [127.0.1.1
Hello [IPv6:::1] (localhost [0:0:0:0:0:0:0:1]), AUTH LOGIN PLAIN, AUTH=LOGIN
PLAIN, PIPELINING, ENHANCEDSTATUSCODES, 8BITMIME]
jvm 1 | DEBUG 19:42:23,671 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:23,673 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: AUTH LOGIN
jvm 1 | DEBUG 19:42:23,673 | james.smtpserver | ID=1598618431 Lookup
command handler for command: AUTH
jvm 1 | DEBUG 19:42:23,676 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.esmtp.AuthCmdHandler: 334
[VXNlcm5hbWU6]
jvm 1 | DEBUG 19:42:23,676 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:23,685 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.smtpserver.UsersRepositoryAuthHook@3ebd106
jvm 1 | INFO 19:42:23,687 | james.usersrepository | test... con nombre
jperez y pass jperez
jvm 1 | INFO 19:42:23,688 | james.usersrepository | getUser... con
nombre jperez
jvm 1 | DEBUG 19:42:23,980 | james.smtpserver | ID=1598618431 AUTH method
LOGIN succeeded
jvm 1 | DEBUG 19:42:23,982 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: MAIL FROM:<test@localhost>
jvm 1 | DEBUG 19:42:23,983 | james.smtpserver | ID=1598618431 Lookup
command handler for command: MAIL
jvm 1 | DEBUG 19:42:23,983 | james.smtpserver | ID=1598618431
org.apache.james.smtpserver.JamesMailCmdHandler: 250 [2.1.0 Sender
<test@localhost> OK]
jvm 1 | DEBUG 19:42:23,983 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:23,985 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: RCPT TO:<acme@localhost>
jvm 1 | DEBUG 19:42:23,985 | james.smtpserver | ID=1598618431 Lookup
command handler for command: RCPT
jvm 1 | DEBUG 19:42:23,985 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.smtpserver.fastfail.ValidRcptHandler
jvm 1 | DEBUG 19:42:23,987 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | DEBUG 19:42:23,988 | james.smtpserver | ID=1598618431
org.apache.james.smtpserver.fastfail.ValidRcptHandler: result=8 (DECLINED)
jvm 1 | DEBUG 19:42:23,988 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | DEBUG 19:42:23,988 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.smtpserver.AuthRequiredToRelayRcptHook
jvm 1 | DEBUG 19:42:23,988 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | DEBUG 19:42:23,988 | james.smtpserver | ID=1598618431
org.apache.james.smtpserver.AuthRequiredToRelayRcptHook: result=8 (DECLINED)
jvm 1 | DEBUG 19:42:23,988 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | DEBUG 19:42:23,989 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.smtpserver.SenderAuthIdentifyVerificationRcptHook
jvm 1 | DEBUG 19:42:23,989 | james.usersrepository | asked for
virtualHosting support
jvm 1 | DEBUG 19:42:23,989 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | INFO 19:42:23,989 | james.smtpserver | ID=1598618431
org.apache.james.smtpserver.SenderAuthIdentifyVerificationRcptHook: result=2
(DENY)
jvm 1 | DEBUG 19:42:23,989 | james.smtpserver | ID=1598618431 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | INFO 19:42:23,990 | james.smtpserver | ID=1598618431
org.apache.james.smtpserver.JamesRcptCmdHandler: 503 [5.7.1 Incorrect
Authentication for Specified Email Address]
jvm 1 | DEBUG 19:42:23,990 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:23,992 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: QUIT
jvm 1 | DEBUG 19:42:23,992 | james.smtpserver | ID=1598618431 Lookup
command handler for command: QUIT
jvm 1 | DEBUG 19:42:23,992 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.QuitCmdHandler: 221 [2.0.0 127.0.1.1
Service closing transmission channel]
jvm 1 | INFO 19:42:23,994 | james.smtpserver | ID=1598618431 Connection
closed for localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:23,994 | james.smtpserver | ID=1598618431
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | INFO 19:42:23,996 | james.smtpserver | ID=361725735 Connection
established from localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:23,997 | james.smtpserver | ID=361725735
org.apache.james.smtpserver.JamesWelcomeMessageHandler disconnect=false
jvm 1 | DEBUG 19:42:24,002 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: EHLO [IPv6:::1]
jvm 1 | DEBUG 19:42:24,003 | james.smtpserver | ID=361725735 Lookup
command handler for command: EHLO
jvm 1 | DEBUG 19:42:24,003 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.esmtp.EhloCmdHandler: 250 [127.0.1.1
Hello [IPv6:::1] (localhost [0:0:0:0:0:0:0:1]), AUTH LOGIN PLAIN, AUTH=LOGIN
PLAIN, PIPELINING, ENHANCEDSTATUSCODES, 8BITMIME]
jvm 1 | DEBUG 19:42:24,007 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,011 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: AUTH PLAIN AGFjbWUAYWNtZQ==
jvm 1 | DEBUG 19:42:24,011 | james.smtpserver | ID=361725735 Lookup
command handler for command: AUTH
jvm 1 | DEBUG 19:42:24,012 | james.smtpserver | ID=361725735 executing
hook org.apache.james.smtpserver.UsersRepositoryAuthHook@3ebd106
jvm 1 | INFO 19:42:24,012 | james.usersrepository | test... con nombre
acme y pass acme
jvm 1 | INFO 19:42:24,012 | james.usersrepository | getUser... con
nombre acme
jvm 1 | DEBUG 19:42:24,041 | james.smtpserver | ID=361725735 AUTH method
PLAIN succeeded
jvm 1 | DEBUG 19:42:24,042 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.esmtp.AuthCmdHandler: 235
[Authentication Successful]
jvm 1 | DEBUG 19:42:24,042 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,043 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: MAIL FROM:<acme@localhost>
jvm 1 | DEBUG 19:42:24,044 | james.smtpserver | ID=361725735 Lookup
command handler for command: MAIL
jvm 1 | DEBUG 19:42:24,044 | james.smtpserver | ID=361725735
org.apache.james.smtpserver.JamesMailCmdHandler: 250 [2.1.0 Sender
<acme@localhost> OK]
jvm 1 | DEBUG 19:42:24,044 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,046 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: RCPT TO:<jperez@localhost>
jvm 1 | DEBUG 19:42:24,046 | james.smtpserver | ID=361725735 Lookup
command handler for command: RCPT
jvm 1 | DEBUG 19:42:24,046 | james.smtpserver | ID=361725735 executing
hook org.apache.james.smtpserver.fastfail.ValidRcptHandler
jvm 1 | DEBUG 19:42:24,050 | james.smtpserver | ID=361725735 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | DEBUG 19:42:24,051 | james.smtpserver | ID=361725735
org.apache.james.smtpserver.fastfail.ValidRcptHandler: result=8 (DECLINED)
jvm 1 | DEBUG 19:42:24,051 | james.smtpserver | ID=361725735 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | DEBUG 19:42:24,051 | james.smtpserver | ID=361725735 executing
hook org.apache.james.smtpserver.AuthRequiredToRelayRcptHook
jvm 1 | DEBUG 19:42:24,051 | james.smtpserver | ID=361725735 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | DEBUG 19:42:24,051 | james.smtpserver | ID=361725735
org.apache.james.smtpserver.AuthRequiredToRelayRcptHook: result=8 (DECLINED)
jvm 1 | DEBUG 19:42:24,051 | james.smtpserver | ID=361725735 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | DEBUG 19:42:24,051 | james.smtpserver | ID=361725735 executing
hook org.apache.james.smtpserver.SenderAuthIdentifyVerificationRcptHook
jvm 1 | DEBUG 19:42:24,052 | james.usersrepository | asked for
virtualHosting support
jvm 1 | DEBUG 19:42:24,054 | james.smtpserver | ID=361725735 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | INFO 19:42:24,054 | james.smtpserver | ID=361725735
org.apache.james.smtpserver.SenderAuthIdentifyVerificationRcptHook: result=2
(DENY)
jvm 1 | DEBUG 19:42:24,054 | james.smtpserver | ID=361725735 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | INFO 19:42:24,054 | james.smtpserver | ID=361725735
org.apache.james.smtpserver.JamesRcptCmdHandler: 503 [5.7.1 Incorrect
Authentication for Specified Email Address]
jvm 1 | DEBUG 19:42:24,054 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,056 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: QUIT
jvm 1 | DEBUG 19:42:24,056 | james.smtpserver | ID=361725735 Lookup
command handler for command: QUIT
jvm 1 | DEBUG 19:42:24,056 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.QuitCmdHandler: 221 [2.0.0 127.0.1.1
Service closing transmission channel]
jvm 1 | INFO 19:42:24,058 | james.smtpserver | ID=361725735 Connection
closed for localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:24,058 | james.smtpserver | ID=361725735
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | INFO 19:42:24,060 | james.smtpserver | ID=962705842 Connection
established from localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:24,060 | james.smtpserver | ID=962705842
org.apache.james.smtpserver.JamesWelcomeMessageHandler disconnect=false
jvm 1 | DEBUG 19:42:24,063 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: EHLO [IPv6:::1]
jvm 1 | DEBUG 19:42:24,063 | james.smtpserver | ID=962705842 Lookup
command handler for command: EHLO
jvm 1 | DEBUG 19:42:24,064 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.esmtp.EhloCmdHandler: 250 [127.0.1.1
Hello [IPv6:::1] (localhost [0:0:0:0:0:0:0:1]), AUTH LOGIN PLAIN, AUTH=LOGIN
PLAIN, PIPELINING, ENHANCEDSTATUSCODES, 8BITMIME]
jvm 1 | DEBUG 19:42:24,064 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,069 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: AUTH PLAIN AGFjbWUAYWNtZQ==
jvm 1 | DEBUG 19:42:24,069 | james.smtpserver | ID=962705842 Lookup
command handler for command: AUTH
jvm 1 | DEBUG 19:42:24,070 | james.smtpserver | ID=962705842 executing
hook org.apache.james.smtpserver.UsersRepositoryAuthHook@3ebd106
jvm 1 | INFO 19:42:24,070 | james.usersrepository | test... con nombre
acme y pass acme
jvm 1 | INFO 19:42:24,070 | james.usersrepository | getUser... con
nombre acme
jvm 1 | DEBUG 19:42:24,074 | james.smtpserver | ID=962705842 AUTH method
PLAIN succeeded
jvm 1 | DEBUG 19:42:24,075 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.esmtp.AuthCmdHandler: 235
[Authentication Successful]
jvm 1 | DEBUG 19:42:24,075 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,077 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: MAIL FROM:<acme@localhost>
jvm 1 | DEBUG 19:42:24,077 | james.smtpserver | ID=962705842 Lookup
command handler for command: MAIL
jvm 1 | DEBUG 19:42:24,077 | james.smtpserver | ID=962705842
org.apache.james.smtpserver.JamesMailCmdHandler: 250 [2.1.0 Sender
<acme@localhost> OK]
jvm 1 | DEBUG 19:42:24,078 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,079 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: RCPT TO:<jperez@localhost>
jvm 1 | DEBUG 19:42:24,080 | james.smtpserver | ID=962705842 Lookup
command handler for command: RCPT
jvm 1 | DEBUG 19:42:24,080 | james.smtpserver | ID=962705842 executing
hook org.apache.james.smtpserver.fastfail.ValidRcptHandler
jvm 1 | DEBUG 19:42:24,082 | james.smtpserver | ID=962705842 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | DEBUG 19:42:24,083 | james.smtpserver | ID=962705842
org.apache.james.smtpserver.fastfail.ValidRcptHandler: result=8 (DECLINED)
jvm 1 | DEBUG 19:42:24,083 | james.smtpserver | ID=962705842 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | DEBUG 19:42:24,083 | james.smtpserver | ID=962705842 executing
hook org.apache.james.smtpserver.AuthRequiredToRelayRcptHook
jvm 1 | DEBUG 19:42:24,083 | james.smtpserver | ID=962705842 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | DEBUG 19:42:24,083 | james.smtpserver | ID=962705842
org.apache.james.smtpserver.AuthRequiredToRelayRcptHook: result=8 (DECLINED)
jvm 1 | DEBUG 19:42:24,083 | james.smtpserver | ID=962705842 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | DEBUG 19:42:24,084 | james.smtpserver | ID=962705842 executing
hook org.apache.james.smtpserver.SenderAuthIdentifyVerificationRcptHook
jvm 1 | DEBUG 19:42:24,084 | james.usersrepository | asked for
virtualHosting support
jvm 1 | DEBUG 19:42:24,086 | james.smtpserver | ID=962705842 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | INFO 19:42:24,086 | james.smtpserver | ID=962705842
org.apache.james.smtpserver.SenderAuthIdentifyVerificationRcptHook: result=2
(DENY)
jvm 1 | DEBUG 19:42:24,086 | james.smtpserver | ID=962705842 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | INFO 19:42:24,086 | james.smtpserver | ID=962705842
org.apache.james.smtpserver.JamesRcptCmdHandler: 503 [5.7.1 Incorrect
Authentication for Specified Email Address]
jvm 1 | DEBUG 19:42:24,087 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,088 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: QUIT
jvm 1 | DEBUG 19:42:24,089 | james.smtpserver | ID=962705842 Lookup
command handler for command: QUIT
jvm 1 | DEBUG 19:42:24,089 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.QuitCmdHandler: 221 [2.0.0 127.0.1.1
Service closing transmission channel]
jvm 1 | INFO 19:42:24,090 | james.smtpserver | ID=962705842 Connection
closed for localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:24,090 | james.smtpserver | ID=962705842
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | INFO 19:42:24,093 | james.smtpserver | ID=1269579719 Connection
established from localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:24,094 | james.smtpserver | ID=1269579719
org.apache.james.smtpserver.JamesWelcomeMessageHandler disconnect=false
jvm 1 | DEBUG 19:42:24,097 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: EHLO [IPv6:::1]
jvm 1 | DEBUG 19:42:24,097 | james.smtpserver | ID=1269579719 Lookup
command handler for command: EHLO
jvm 1 | DEBUG 19:42:24,097 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.esmtp.EhloCmdHandler: 250 [127.0.1.1
Hello [IPv6:::1] (localhost [0:0:0:0:0:0:0:1]), AUTH LOGIN PLAIN, AUTH=LOGIN
PLAIN, PIPELINING, ENHANCEDSTATUSCODES, 8BITMIME]
jvm 1 | DEBUG 19:42:24,098 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,100 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: AUTH LOGIN
jvm 1 | DEBUG 19:42:24,100 | james.smtpserver | ID=1269579719 Lookup
command handler for command: AUTH
jvm 1 | DEBUG 19:42:24,100 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.esmtp.AuthCmdHandler: 334
[VXNlcm5hbWU6]
jvm 1 | DEBUG 19:42:24,101 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,101 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.smtpserver.UsersRepositoryAuthHook@3ebd106
jvm 1 | INFO 19:42:24,102 | james.usersrepository | test... con nombre
jperez y pass jperez
jvm 1 | INFO 19:42:24,102 | james.usersrepository | getUser... con
nombre jperez
jvm 1 | DEBUG 19:42:24,106 | james.smtpserver | ID=1269579719 AUTH method
LOGIN succeeded
jvm 1 | DEBUG 19:42:24,108 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: MAIL FROM:<jperez@localhost>
jvm 1 | DEBUG 19:42:24,109 | james.smtpserver | ID=1269579719 Lookup
command handler for command: MAIL
jvm 1 | DEBUG 19:42:24,109 | james.smtpserver | ID=1269579719
org.apache.james.smtpserver.JamesMailCmdHandler: 250 [2.1.0 Sender
<jperez@localhost> OK]
jvm 1 | DEBUG 19:42:24,109 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,111 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: RCPT TO:<acme@localhost>
jvm 1 | DEBUG 19:42:24,112 | james.smtpserver | ID=1269579719 Lookup
command handler for command: RCPT
jvm 1 | DEBUG 19:42:24,112 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.smtpserver.fastfail.ValidRcptHandler
jvm 1 | DEBUG 19:42:24,114 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | DEBUG 19:42:24,114 | james.smtpserver | ID=1269579719
org.apache.james.smtpserver.fastfail.ValidRcptHandler: result=8 (DECLINED)
jvm 1 | DEBUG 19:42:24,114 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | DEBUG 19:42:24,114 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.smtpserver.AuthRequiredToRelayRcptHook
jvm 1 | DEBUG 19:42:24,115 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | DEBUG 19:42:24,115 | james.smtpserver | ID=1269579719
org.apache.james.smtpserver.AuthRequiredToRelayRcptHook: result=8 (DECLINED)
jvm 1 | DEBUG 19:42:24,115 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | DEBUG 19:42:24,115 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.smtpserver.SenderAuthIdentifyVerificationRcptHook
jvm 1 | DEBUG 19:42:24,115 | james.usersrepository | asked for
virtualHosting support
jvm 1 | DEBUG 19:42:24,117 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.protocols.smtp.core.log.HookResultLogger@53f0d7b0
jvm 1 | INFO 19:42:24,118 | james.smtpserver | ID=1269579719
org.apache.james.smtpserver.SenderAuthIdentifyVerificationRcptHook: result=2
(DENY)
jvm 1 | DEBUG 19:42:24,118 | james.smtpserver | ID=1269579719 executing
hook org.apache.james.smtpserver.jmx.HookResultJMXMonitor@68e08edd
jvm 1 | INFO 19:42:24,118 | james.smtpserver | ID=1269579719
org.apache.james.smtpserver.JamesRcptCmdHandler: 503 [5.7.1 Incorrect
Authentication for Specified Email Address]
jvm 1 | DEBUG 19:42:24,120 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
jvm 1 | DEBUG 19:42:24,122 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
received: QUIT
jvm 1 | DEBUG 19:42:24,122 | james.smtpserver | ID=1269579719 Lookup
command handler for command: QUIT
jvm 1 | DEBUG 19:42:24,122 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.QuitCmdHandler: 221 [2.0.0 127.0.1.1
Service closing transmission channel]
jvm 1 | INFO 19:42:24,124 | james.smtpserver | ID=1269579719 Connection
closed for localhost (0:0:0:0:0:0:0:1)
jvm 1 | DEBUG 19:42:24,125 | james.smtpserver | ID=1269579719
org.apache.james.protocols.smtp.core.SMTPCommandDispatcherLineHandler
disconnect=false
here it says "DEBUG 19:42:24,106 | james.smtpserver | ID=1269579719 AUTH
method LOGIN succeeded"
and then "org.apache.james.smtpserver.JamesRcptCmdHandler: 503 [5.7.1
Incorrect Authentication for Specified Email Address]"
an another question, I dont know if it is related, what is the implication
of not supporting VirtualHosting.
so I don't know what is wrong with my implementation
regards,
Larry Ruiz
