Loading...

Type: Bug
Resolution: Unresolved
Priority: Major
Fix Version/s: None
Affects Version/s: None
Component/s: Test Suite
Labels:
- good-first-issue

[QE] How to address?:
---
[QE] Why QE missed?:
---

MailTesterServlet tests that we are able to send and read email messages using Apache James docker image.

We have got the following failure on the CI. The trace shows the message was sent, but it failed when it was read:

&amp#27;[0m&amp#27;[0m00:11:23,770 INFO  [org.jboss.as.server] (management-handler-thread - 1) WFLYSRV0010: Deployed "aab391a4-c6f0-48a3-854d-9fe07b9025b2.war" (runtime-name : "aab391a4-c6f0-48a3-854d-9fe07b9025b2.war")
&amp#27;[0m&amp#27;[0m00:11:23,812 INFO  [stdout] (default task-1) DEBUG: Jakarta Mail version 2.1.3
&amp#27;[0m&amp#27;[0m00:11:23,814 INFO  [stdout] (default task-1) DEBUG: URL jar:file:/opt/buildAgent/work/e8e0dd9c7c4ba60/full/preview/dist/target/wildfly-preview-32.0.0.Beta1-SNAPSHOT/modules/system/layers/base/org/eclipse/angus/mail/main/angus-mail-2.0.3.jar!/META-INF/javamail.providers
&amp#27;[0m&amp#27;[0m00:11:23,814 INFO  [stdout] (default task-1) DEBUG: successfully loaded resource: jar:file:/opt/buildAgent/work/e8e0dd9c7c4ba60/full/preview/dist/target/wildfly-preview-32.0.0.Beta1-SNAPSHOT/modules/system/layers/base/org/eclipse/angus/mail/main/angus-mail-2.0.3.jar!/META-INF/javamail.providers
&amp#27;[0m&amp#27;[0m00:11:23,815 INFO  [stdout] (default task-1) DEBUG: successfully loaded resource: /META-INF/javamail.default.providers
&amp#27;[0m&amp#27;[0m00:11:23,815 INFO  [stdout] (default task-1) DEBUG: Tables of loaded providers
&amp#27;[0m&amp#27;[0m00:11:23,815 INFO  [stdout] (default task-1) DEBUG: Providers Listed By Class Name: {org.eclipse.angus.mail.imap.IMAPStore=jakarta.mail.Provider[STORE,imap,org.eclipse.angus.mail.imap.IMAPStore,Oracle], org.eclipse.angus.mail.smtp.SMTPTransport=jakarta.mail.Provider[TRANSPORT,smtp,org.eclipse.angus.mail.smtp.SMTPTranspo
rt,Oracle], org.eclipse.angus.mail.pop3.POP3Store=jakarta.mail.Provider[STORE,pop3,org.eclipse.angus.mail.pop3.POP3Store,Oracle], org.eclipse.angus.mail.pop3.POP3SSLStore=jakarta.mail.Provider[STORE,pop3s,org.eclipse.angus.mail.pop3.POP3SSLStore,Oracle], org.eclipse.angus.mail.smtp.SMTPSSLTransport=jakarta.mail.Provider[TRANSPORT,smtps,org.e
clipse.angus.mail.smtp.SMTPSSLTransport,Oracle], org.eclipse.angus.mail.imap.IMAPSSLStore=jakarta.mail.Provider[STORE,imaps,org.eclipse.angus.mail.imap.IMAPSSLStore,Oracle]}
&amp#27;[0m&amp#27;[0m00:11:23,815 INFO  [stdout] (default task-1) DEBUG: Providers Listed By Protocol: {imap=jakarta.mail.Provider[STORE,imap,org.eclipse.angus.mail.imap.IMAPStore,Oracle], smtp=jakarta.mail.Provider[TRANSPORT,smtp,org.eclipse.angus.mail.smtp.SMTPTransport,Oracle], pop3=jakarta.mail.Provider[STORE,pop3,org.eclipse.angus.mail
.pop3.POP3Store,Oracle], imaps=jakarta.mail.Provider[STORE,imaps,org.eclipse.angus.mail.imap.IMAPSSLStore,Oracle], smtps=jakarta.mail.Provider[TRANSPORT,smtps,org.eclipse.angus.mail.smtp.SMTPSSLTransport,Oracle], pop3s=jakarta.mail.Provider[STORE,pop3s,org.eclipse.angus.mail.pop3.POP3SSLStore,Oracle]}
&amp#27;[0m&amp#27;[0m00:11:23,816 INFO  [stdout] (default task-1) DEBUG: successfully loaded resource: /META-INF/javamail.default.address.map
&amp#27;[0m&amp#27;[0m00:11:23,816 INFO  [stdout] (default task-1) DEBUG: URL jar:file:/opt/buildAgent/work/e8e0dd9c7c4ba60/full/preview/dist/target/wildfly-preview-32.0.0.Beta1-SNAPSHOT/modules/system/layers/base/org/eclipse/angus/mail/main/angus-mail-2.0.3.jar!/META-INF/javamail.address.map
&amp#27;[0m&amp#27;[0m00:11:23,816 INFO  [stdout] (default task-1) DEBUG: successfully loaded resource: jar:file:/opt/buildAgent/work/e8e0dd9c7c4ba60/full/preview/dist/target/wildfly-preview-32.0.0.Beta1-SNAPSHOT/modules/system/layers/base/org/eclipse/angus/mail/main/angus-mail-2.0.3.jar!/META-INF/javamail.address.map
&amp#27;[0m&amp#27;[0m00:11:23,897 INFO  [stdout] (default task-1) DEBUG: getProvider() returning jakarta.mail.Provider[TRANSPORT,smtp,org.eclipse.angus.mail.smtp.SMTPTransport,Oracle]
&amp#27;[0m&amp#27;[0m00:11:23,909 INFO  [stdout] (default task-1) DEBUG SMTP: need username and password for authentication
&amp#27;[0m&amp#27;[0m00:11:23,909 INFO  [stdout] (default task-1) DEBUG SMTP: protocolConnect returning false, host=localhost, user=user01@james.local, password=<null>
&amp#27;[0m&amp#27;[0m00:11:23,910 INFO  [stdout] (default task-1) DEBUG SMTP: useEhlo true, useAuth true
&amp#27;[0m&amp#27;[0m00:11:23,910 INFO  [stdout] (default task-1) DEBUG SMTP: trying to connect to host "localhost", port 33140, isSSL false
&amp#27;[0m&amp#27;[0m00:11:24,233 INFO  [stdout] (default task-1) 220 Apache JAMES awesome SMTP Server
&amp#27;[0m&amp#27;[0m00:11:24,234 INFO  [stdout] (default task-1) DEBUG SMTP: connected to host "localhost", port: 33140
&amp#27;[0m&amp#27;[0m00:11:24,235 INFO  [stdout] (default task-1) EHLO build209-centos.wildfly.int
&amp#27;[0m&amp#27;[0m00:11:24,308 INFO  [stdout] (default task-1) 250-781a050c1379 Hello build209-centos.wildfly.int [172.17.0.1])
&amp#27;[0m&amp#27;[0m00:11:24,308 INFO  [stdout] (default task-1) 250-PIPELINING
&amp#27;[0m&amp#27;[0m00:11:24,308 INFO  [stdout] (default task-1) 250-ENHANCEDSTATUSCODES
&amp#27;[0m&amp#27;[0m00:11:24,308 INFO  [stdout] (default task-1) 250-8BITMIME
&amp#27;[0m&amp#27;[0m00:11:24,308 INFO  [stdout] (default task-1) 250 STARTTLS
&amp#27;[0m&amp#27;[0m00:11:24,309 INFO  [stdout] (default task-1) DEBUG SMTP: Found extension "PIPELINING", arg ""
&amp#27;[0m&amp#27;[0m00:11:24,309 INFO  [stdout] (default task-1) DEBUG SMTP: Found extension "ENHANCEDSTATUSCODES", arg ""
&amp#27;[0m&amp#27;[0m00:11:24,309 INFO  [stdout] (default task-1) DEBUG SMTP: Found extension "8BITMIME", arg ""
&amp#27;[0m&amp#27;[0m00:11:24,309 INFO  [stdout] (default task-1) DEBUG SMTP: Found extension "STARTTLS", arg ""
&amp#27;[0m&amp#27;[0m00:11:24,310 INFO  [stdout] (default task-1) DEBUG SMTP: use8bit false
&amp#27;[0m&amp#27;[0m00:11:24,310 INFO  [stdout] (default task-1) MAIL FROM:<user01@james.local>
&amp#27;[0m&amp#27;[0m00:11:24,321 INFO  [stdout] (default task-1) 250 2.1.0 Sender <user01@james.local> OK
&amp#27;[0m&amp#27;[0m00:11:24,321 INFO  [stdout] (default task-1) RCPT TO:<user02@james.local>
&amp#27;[0m&amp#27;[0m00:11:24,342 INFO  [stdout] (default task-1) 250 2.1.5 Recipient <user02@james.local> OK
&amp#27;[0m&amp#27;[0m00:11:24,343 INFO  [stdout] (default task-1) DEBUG SMTP: Verified Addresses
&amp#27;[0m&amp#27;[0m00:11:24,343 INFO  [stdout] (default task-1) DEBUG SMTP:   user02@james.local
&amp#27;[0m&amp#27;[0m00:11:24,343 INFO  [stdout] (default task-1) DATA
&amp#27;[0m&amp#27;[0m00:11:24,402 INFO  [stdout] (default task-1) 354 Ok Send data ending with <CRLF>.<CRLF>
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) Date: Thu, 4 Apr 2024 00:11:23 +0000 (UTC)
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) From: user01@james.local
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) To: user02@james.local
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) Message-ID: <1614930444.0.1712189483895@build209-centos.wildfly.int>
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) Subject: test email subject
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) MIME-Version: 1.0
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) Content-Type: text/plain; charset=us-ascii
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) Content-Transfer-Encoding: 7bit
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) 
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) This is the content of an email sent from user01 to user02
&amp#27;[0m&amp#27;[0m00:11:24,405 INFO  [stdout] (default task-1) .
&amp#27;[0m&amp#27;[0m00:11:24,849 INFO  [stdout] (default task-1) 250 2.6.0 Message received
&amp#27;[0m&amp#27;[0m00:11:24,849 INFO  [stdout] (default task-1) DEBUG SMTP: message successfully delivered to mail server
&amp#27;[0m&amp#27;[0m00:11:24,849 INFO  [stdout] (default task-1) QUIT
&amp#27;[0m&amp#27;[0m00:11:24,871 INFO  [stdout] (default task-1) 221 2.0.0 781a050c1379 Service closing transmission channel
&amp#27;[0m&amp#27;[0m00:11:25,874 INFO  [stdout] (default task-1) DEBUG: getProvider() returning jakarta.mail.Provider[STORE,pop3,org.eclipse.angus.mail.pop3.POP3Store,Oracle]
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.rsetbeforequit: false
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.disabletop: false
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.forgettopheaders: false
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.cachewriteto: false
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.filecache.enable: false
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.keepmessagecontent: false
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.starttls.enable: false
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.starttls.required: false
&amp#27;[0m&amp#27;[0m00:11:25,880 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.finalizecleanclose: false
&amp#27;[0m&amp#27;[0m00:11:25,883 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.apop.enable: false
&amp#27;[0m&amp#27;[0m00:11:25,883 INFO  [stdout] (default task-1) DEBUG POP3: mail.pop3.disablecapa: false
&amp#27;[0m&amp#27;[0m00:11:25,883 INFO  [stdout] (default task-1) DEBUG POP3: connecting to host "localhost", port 33139, isSSL false
&amp#27;[0m&amp#27;[0m00:11:25,950 INFO  [stdout] (default task-1) +OK <c863ecee.1712189485936@781a050c1379> POP3 server (JAMES POP3 Server ) ready 
&amp#27;[0m&amp#27;[0m00:11:25,951 INFO  [stdout] (default task-1) CAPA
&amp#27;[0m&amp#27;[0m00:11:26,083 INFO  [stdout] (default task-1) +OK Capability list follows
&amp#27;[0m&amp#27;[0m00:11:26,083 INFO  [stdout] (default task-1) PIPELINING
&amp#27;[0m&amp#27;[0m00:11:26,083 INFO  [stdout] (default task-1) USER
&amp#27;[0m&amp#27;[0m00:11:26,083 INFO  [stdout] (default task-1) STLS
&amp#27;[0m&amp#27;[0m00:11:26,083 INFO  [stdout] (default task-1) .
&amp#27;[0m&amp#27;[0m00:11:26,084 INFO  [stdout] (default task-1) DEBUG POP3: PIPELINING enabled
&amp#27;[0m&amp#27;[0m00:11:26,087 INFO  [stdout] (default task-1) DEBUG POP3: server doesn't support TOP, disabling it
&amp#27;[0m&amp#27;[0m00:11:26,087 INFO  [stdout] (default task-1) DEBUG POP3: Attempt to authenticate using mechanisms: LOGIN PLAIN NTLM XOAUTH2 
&amp#27;[0m&amp#27;[0m00:11:26,087 INFO  [stdout] (default task-1) DEBUG POP3: Using mechanism LOGIN
&amp#27;[0m&amp#27;[0m00:11:26,087 INFO  [stdout] (default task-1) DEBUG POP3: authentication command trace suppressed
&amp#27;[0m&amp#27;[0m00:11:26,614 INFO  [stdout] (default task-1) DEBUG POP3: authentication command succeeded
&amp#27;[0m&amp#27;[0m00:11:26,617 INFO  [stdout] (default task-1) STAT
&amp#27;[0m&amp#27;[0m00:11:26,654 INFO  [stdout] (default task-1) +OK 0 0
&amp#27;[0m&amp#27;[0m00:11:26,655 INFO  [stdout] (default task-1) NOOP
&amp#27;[0m&amp#27;[0m00:11:26,662 INFO  [stdout] (default task-1) +OK
&amp#27;[0m&amp#27;[31m00:11:26,662 ERROR [io.undertow.request] (default task-1) UT005023: Exception handling request to /aab391a4-c6f0-48a3-854d-9fe07b9025b2//mail_test: jakarta.servlet.ServletException: no message was found when reading using pop3
        at deployment.aab391a4-c6f0-48a3-854d-9fe07b9025b2.war//org.jboss.as.test.integration.mail.basic.MailTesterServlet.doGet(MailTesterServlet.java:63)
        at jakarta.servlet.api@6.0.0//jakarta.servlet.http.HttpServlet.service(HttpServlet.java:527)
        at jakarta.servlet.api@6.0.0//jakarta.servlet.http.HttpServlet.service(HttpServlet.java:614)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.ServletHandler.handleRequest(ServletHandler.java:74)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.ServletChain$1.handleRequest(ServletChain.java:68)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36)
        at org.wildfly.security.elytron-web.undertow-server@4.1.0.Final//org.wildfly.elytron.web.undertow.server.ElytronRunAsHandler.lambda$handleRequest$1(ElytronRunAsHandler.java:68)
        at org.wildfly.security.elytron-base@2.4.0.CR1//org.wildfly.security.auth.server.FlexibleIdentityAssociation.runAsFunctionEx(FlexibleIdentityAssociation.java:103)
        at org.wildfly.security.elytron-base@2.4.0.CR1//org.wildfly.security.auth.server.Scoped.runAsFunctionEx(Scoped.java:161)
        at org.wildfly.security.elytron-base@2.4.0.CR1//org.wildfly.security.auth.server.Scoped.runAs(Scoped.java:73)
        at org.wildfly.security.elytron-web.undertow-server@4.1.0.Final//org.wildfly.elytron.web.undertow.server.ElytronRunAsHandler.handleRequest(ElytronRunAsHandler.java:67)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.RedirectDirHandler.handleRequest(RedirectDirHandler.java:68)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.security.SSLInformationAssociationHandler.handleRequest(SSLInformationAssociationHandler.java:117)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.security.ServletAuthenticationCallHandler.handleRequest(ServletAuthenticationCallHandler.java:57)
        at io.undertow.core@2.3.12.Final//io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
        at io.undertow.core@2.3.12.Final//io.undertow.security.handlers.AbstractConfidentialityHandler.handleRequest(AbstractConfidentialityHandler.java:46)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.security.ServletConfidentialityConstraintHandler.handleRequest(ServletConfidentialityConstraintHandler.java:64)
        at io.undertow.core@2.3.12.Final//io.undertow.security.handlers.AbstractSecurityContextAssociationHandler.handleRequest(AbstractSecurityContextAssociationHandler.java:43)
        at org.wildfly.security.elytron-web.undertow-server-servlet@4.1.0.Final//org.wildfly.elytron.web.undertow.server.servlet.CleanUpHandler.handleRequest(CleanUpHandler.java:38)
        at io.undertow.core@2.3.12.Final//io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
        at org.wildfly.extension.undertow@32.0.0.Beta1-SNAPSHOT//org.wildfly.extension.undertow.security.jacc.JACCContextIdHandler.handleRequest(JACCContextIdHandler.java:44)
        at io.undertow.core@2.3.12.Final//io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
        at org.wildfly.extension.undertow@32.0.0.Beta1-SNAPSHOT//org.wildfly.extension.undertow.deployment.GlobalRequestControllerHandler.handleRequest(GlobalRequestControllerHandler.java:51)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.SendErrorPageHandler.handleRequest(SendErrorPageHandler.java:52)
        at io.undertow.core@2.3.12.Final//io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:276)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:132)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
        at org.wildfly.extension.undertow@32.0.0.Beta1-SNAPSHOT//org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1421)
        at org.wildfly.extension.undertow@32.0.0.Beta1-SNAPSHOT//org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1421)
        at org.wildfly.extension.undertow@32.0.0.Beta1-SNAPSHOT//org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1421)
        at org.wildfly.extension.undertow@32.0.0.Beta1-SNAPSHOT//org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1421)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:256)
        at io.undertow.servlet@2.3.12.Final//io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:101)
        at io.undertow.core@2.3.12.Final//io.undertow.server.Connectors.executeRootHandler(Connectors.java:393)
        at io.undertow.core@2.3.12.Final//io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:859)
        at org.jboss.threads@2.4.0.Final//org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
        at org.jboss.threads@2.4.0.Final//org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1990)
        at org.jboss.threads@2.4.0.Final//org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1486)
        at org.jboss.threads@2.4.0.Final//org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1348)
        at org.jboss.xnio@3.8.13.Final//org.xnio.XnioWorker$WorkerThreadFactory$1$1.run(XnioWorker.java:1282)
        at java.base/java.lang.Thread.run(Thread.java:840)

This is likely due to the poor handling to give time to the Mail Server to process the message, right now we are just waiting for one second. See https://github.com/wildfly/wildfly/blob/2ad58ff7c22add994016c09c4385fdaab62a6c02/testsuite/integration/basic/src/test/java/org/jboss/as/test/integration/mail/basic/MailTesterServlet.java#L50-L51

The read should be done in a loop with a time out with a try and repeat strategy to give more time to the mail server to process the message.

Details

Description

Attachments

Activity

People

Dates