Reading SMTP Module Forwarding entries in the RetainServer Log

  • 7023440
  • 12-Oct-2018
  • 12-Oct-2018

Environment

Retain 4

Situation

When looking for details about SMTP Module Forwarding in the RetainServer log, you can search for "Forwarder" in the log to find lines relating to that process.

Note: Retain only forwards new items it archives. Previously archives items are not forwarded again.

Resolution

When Retain finds items to forward it will prepare a job which will take place at the top of the next hour. Shown in the log as:
10:50:19, 597[StartupThreadProcess] [DEBUG] StandardStartupListener: PROCESSINFO scheduler - DEBUG :Schedule SmtpForward Job
10:50:19, 597[StartupThreadProcess] [TRACE] ModuleForwarder: Next fire time: Fri Oct 12 11:00:00 MDT 2018

When a forwarding job begins you will find the following lines in the log:
11:00:00, 083[RTSQuartzScheduler_QuartzSchedulerThread] [DEBUG] SimpleJobFactory: Producing instance of Job 'MODULEFORWARDGroup.MODULEFORWARDER', class=com.gwava.smtp.ModuleForwarder
11:00:00, 083[RTSQuartzScheduler_Worker-2] [DEBUG] JobRunShell: Calling execute on job MODULEFORWARDGroup.MODULEFORWARDER
11:00:00, 083[RTSQuartzScheduler_Worker-2] [INFO ] ModuleForwarder: Starting module forwarding job.
11:00:00, 098[RTSQuartzScheduler_Worker-2] [DEBUG] ModuleForwarder: Checking for SMTP forwarding (mid=-999).

Each item that is forwarded will be recorded in the log following this example:
11:00:00, 223[RTSQuartzScheduler_Worker-2] [TRACE] SMTPPostArchiveListener: Starting up SMTP consumer thread.
11:00:00, 223[SMTP-Consumer] [TRACE] SMTPPostArchiveListener: SMTP consumer thread running.
11:00:00, 223[SMTP-Consumer] [TRACE] SMTPPostArchiveListener: SMTP Consumer running for message 240581
11:00:00, 223[SMTP-Consumer] [DEBUG] SMTPPostArchiveListener: Queue Element SMTP params :SMTPParams [from=retain140@mf.com, mailServer=151.155.183.142, mailProtocol=unencrypted, mailPort=25, recipients=heimdal@sf.gwava.net, smtpUser=, messageMaxSize=0]
11:00:00, 223[SMTP-Consumer] [INFO ] SMTPPostArchiveListener: Wait until the message is processed to send
11:00:00, 239[SMTP-Mail-Forwarder-0] [TRACE] AbstractForwardManager: Returning EmailMessage object
11:00:00, 239[SMTP-Mail-Forwarder-0] [INFO ] AbstractForwardManager: =====================================================
11:00:00, 239[SMTP-Mail-Forwarder-0] [INFO ] AbstractForwardManager: Forwarding message: 240581 over SMTP starts.
11:00:00, 239[SMTP-Mail-Forwarder-0] [TRACE] AbstractForwardManager: Generating MIME mail for communication.
11:00:00, 255[SMTP-Mail-Forwarder-0] [TRACE] EmailSystemCommunicationMail: Constructing message with ID: 240581 and Subject: Acid burns holes in wool cloth. 1651478533434
11:00:00, 255[SMTP-Mail-Forwarder-0] [TRACE] EmailSystemCommunicationMail: Adding attachments to message -240581
11:00:00, 255[SMTP-Mail-Forwarder-0] [DEBUG] EmailSystemCommunicationMail: Adding doc: Acid burns holes in wool cloth. 1651478533434.txt
11:00:00, 395[SMTP-Mail-Forwarder-0] [TRACE] PartitionCache: Def Partition Path: PARTITION:id=0;name=default;path=\Retain\archive;bt=1539363600;et=0
11:00:00, 427[SMTP-Mail-Forwarder-0] [TRACE] PartitionCache: Def Partition Path: PARTITION:id=0;name=default;path=\Retain\archive;bt=1539363600;et=0
11:00:00, 427[SMTP-Mail-Forwarder-0] [DEBUG] EmailSystemCommunicationMail: Adding doc: Acid burns holes in wool cloth. 1651478533434.txt completes.
11:00:00, 427[SMTP-Mail-Forwarder-0] [DEBUG] EmailSystemCommunicationMail: Adding doc: Mime.822
11:00:00, 427[SMTP-Mail-Forwarder-0] [DEBUG] EmailSystemCommunicationMail: Skipping previous mime file attachment.
11:00:00, 427[SMTP-Mail-Forwarder-0] [DEBUG] EmailSystemCommunicationMail: NOT Adding multipartrelated
11:00:00, 427[SMTP-Mail-Forwarder-0] [DEBUG] EmailSystemCommunicationMail: NOT Adding multipartalt
11:00:00, 427[SMTP-Mail-Forwarder-0] [DEBUG] EmailSystemCommunicationMail:  Adding single alt part
11:00:00, 489[SMTP-Mail-Forwarder-0] [TRACE] EmailSystemCommunicationMail: Message construction complete for message -240581
11:00:00, 489[SMTP-Mail-Forwarder-0] [TRACE] AbstractForwardManager: Sending email message: heimdal@sf.gwava.net
11:00:00, 802[SMTP-Mail-Forwarder-0] [INFO ] AbstractForwardManager: Forwarding message: 240581 over SMTP completes.
11:00:00, 802[SMTP-Mail-Forwarder-0] [INFO ] AbstractForwardManager: =====================================================
11:00:00, 817[SMTP-Mail-Forwarder-0] [INFO ] SMTPPostArchiveListener: Notify the queue thread

The forwarding job will end with:
11:00:05, 239[RTSQuartzScheduler_Worker-2] [DEBUG] ModuleForwarder: Checking for FTP/FTPS forwarding.
11:00:05, 333[RTSQuartzScheduler_Worker-2] [INFO ] FTPPostArchiveListener: No messages to forward.
11:00:05, 333[RTSQuartzScheduler_Worker-2] [DEBUG] FTPPostArchiveListener: Notifying queue thread.