I can report the [saitobenkei] solution worked so far for all I have tested it with except for when I tried with Flectra outgoing emails.
I have attached two NethServer email logs from two test below, the first log is the test is with standard port 465 and the second log is from the test with port 1465 which fails to send any emails. I have made a break and added a note where the two test start to be different.
The NethServer administrator receives an error message âUndelivered Mail Returned to Senderâ
Action: failed
Status: 5.3.0
Diagnostic-Code: x-unix; Message content rejected
It looks to be failing at a Rspamd process, but in the history in the Rspamd interface there is no indication of an issue.
Has anyone got any suggestions?
â Below - NethServer email log of email send via port 465 - which is working â
Aug 28 17:05:23 emails postfix/smtpd[26076]: connect from 74.8.233.220.static.exetel.com.au[[removed - my IP address]]
Aug 28 17:05:23 emails rspamd[22678]: <96bc0c>; proxy; proxy_accept_socket: accepted milter connection from /var/run/rspamd/worker-proxy port 0
Aug 28 17:05:23 emails rspamd[22678]: <96bc0c>; milter; rspamd_milter_process_command: got connection from [removed - my IP address]:44392
Aug 28 17:05:23 emails postfix/smtpd[26076]: disconnect from 74.8.233.220.static.exetel.com.au[[removed - my IP address]]
Aug 28 17:05:23 emails rspamd[22678]: <96bc0c>; proxy; proxy_milter_finish_handler: finished milter connection
Aug 28 17:05:49 emails postfix/smtpd[26076]: connect from 74.8.233.220.static.exetel.com.au[[removed - my IP address]]
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; proxy_accept_socket: accepted milter connection from /var/run/rspamd/worker-proxy port 0
Aug 28 17:05:49 emails postfix/smtpd[26076]: 89360D9D44: client=74.8.233.220.static.exetel.com.au[[removed - my IP address]], sasl_method=PLAIN, sasl_username=[removed - email address senting from]
Aug 28 17:05:49 emails rspamd[22678]: ; milter; rspamd_milter_process_command: got connection from [removed - my IP address]:44394
Aug 28 17:05:49 emails postfix/cleanup[26153]: 89360D9D44: message-id=281009409502812.1630134349.256407022476196-flectra-private@aits-ub
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; rspamd_message_parse: loaded message; id: 281009409502812.1630134349.256407022476196-flectra-private@aits-ub; queue-id: <89360D9D44>; size: 1047; checksum:
Aug 28 17:05:49 emails rspamd[22678]: ; lua; settings.lua:363: 281009409502812.1630134349.256407022476196-flectra-private@aits-ub apply static settings authenticated (id = 1937017268); authenticated matched; priority high
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; dkim_symbol_callback: skip DKIM checks for local networks and authorized users
Aug 28 17:05:49 emails rspamd[22678]: ; lua; spf.lua:185: skip SPF checks for local networks and authorized users
Aug 28 17:05:49 emails rspamd[22678]: ; lua; dmarc.lua:596: skip DMARC checks as either SPF or DKIM were not checked
Aug 28 17:05:49 emails rspamd[22678]: ; lua; once_received.lua:99: Skipping once_received for authenticated user or local network
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; lua_task_insert_result_common: symbol insertion issue: unknown symbol GENERIC_REPUTATION; trace: [1]:{/usr/share/rspamd/plugins/reputation.lua:83 - add_symbol_score [Lua]}; [2]:{/usr/share/rspamd/plugins/reputation.lua:645 - continuation_cb [Lua]}; [3]:{/usr/share/rspamd/plugins/reputation.lua:929 - callback [Lua]}; [4]:{/usr/share/rspamd/lualib/lua_redis.lua:1296 - callback [Lua]}; [5]:{/usr/share/rspamd/lualib/lua_redis.lua:917 - [Lua]};
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; rspamd_redis_connected: skip obtaining bayes tokens for BAYES_SPAM of classifier bayes: not enough learns 38; 200 required
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; rspamd_redis_connected: skip obtaining bayes tokens for BAYES_HAM of classifier bayes: not enough learns 32; 200 required
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; rspamd_stat_classifiers_process: skip statistics as SPAM class is missing
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; rspamd_task_write_log: id: 281009409502812.1630134349.256407022476196-flectra-private@aits-ub, qid: <89360D9D44>, ip: [removed - my IP address], user: [removed - email address senting from], from: <bounce@[removed - my domain]>, (default: F (no action): [3.30/20.00] [CTYPE_MIXED_BOGUS(1.00){},MIME_BASE64_TEXT_BOGUS(1.00){},MID_RHS_NOT_FQDN(0.50){},R_PARTS_DIFFER(0.50){100.0%;},FORGED_SENDER(0.30){[removed - email address senting to];bounce@[removed - my domain];},MIME_BASE64_TEXT(0.10){},MIME_GOOD(-0.10){multipart/mixed;multipart/alternative;text/plain;},ASN(0.00){asn:10143, ipnet:220.233.8.0/21, country:AU;},FROM_HAS_DN(0.00){},FROM_NEQ_ENVFROM(0.00){[removed - email address senting to];bounce@[removed - my domain];},GENERIC_REPUTATION(0.00){-0.39521405646944;},HAS_REPLYTO(0.00){[removed - email address senting to];},MIME_TRACE(0.00){0:+;1:+;2:+;3:~;},RCPT_COUNT_ONE(0.00){1;},RCVD_COUNT_ZERO(0.00){0;},REPLYTO_EQ_FROM(0.00){},TAGGED_FROM(0.00){81;},TO_DN_NONE(0.00){},TO_EQ_FROM(0.00){},TO_MATCH_ENVRCPT_ALL(0.00){}]), len: 1047, time: 12.057ms, dns req: 3, digest: , rcpts: <[removed - email address senting to]>, mime_rcpts: <[removed - email address senting to]>, settings_id: authenticated
Aug 28 17:05:49 emails rspamd[22678]: ; proxy; rspamd_protocol_http_reply: regexp statistics: 0 pcre regexps scanned, 3 regexps matched, 174 regexps total, 44 regexps cached, 0B scanned using pcre, 580B scanned total
Aug 28 17:05:49 emails opendkim[26002]: 89360D9D44: DKIM-Signature field added (s=default, d=[removed - my domain])
Aug 28 17:05:49 emails postfix/qmgr[22325]: 89360D9D44: from=<bounce+81@[removed - my domain]>, size=1341, nrcpt=1 (queue active)
â Below here is where the difference is with this successful port 465 test â
Aug 28 17:05:49 emails postfix/smtpd[26076]: disconnect from 74.8.233.220.static.exetel.com.au[[removed - my IP address]]
Aug 28 17:05:49 emails rspamd[22678]: <65f546>; proxy; proxy_milter_finish_handler: finished milter connection
Aug 28 17:05:49 emails dovecot: lmtp(26156): Connect from local
Aug 28 17:05:49 emails dovecot: lmtp([removed - email address senting to]): save: box=INBOX, uid=5785, msgid=281009409502812.1630134349.256407022476196-flectra-private@aits-ub, from=[removed - my name]<[removed - email address senting to]>, subject=12, flags=()
Aug 28 17:05:49 emails dovecot: lmtp([removed - email address senting to]): 6NV2JU3gKWEsZgAAEA1DJQ: sieve: msgid=281009409502812.1630134349.256407022476196-flectra-private@aits-ub: stored mail into mailbox âINBOXâ
Aug 28 17:05:49 emails postfix/lmtp[26155]: 89360D9D44: to=<[removed - email address senting to]>, relay=emails.[removed - my domain][/var/run/dovecot/lmtp], delay=0.1, delays=0.05/0.01/0.02/0.03, dsn=2.0.0, status=sent (250 2.0.0 <[removed - email address senting to]> 6NV2JU3gKWEsZgAAEA1DJQ Saved)
Aug 28 17:05:49 emails dovecot: lmtp(26156): Disconnect from local: Successful quit
Aug 28 17:05:49 emails postfix/qmgr[22325]: 89360D9D44: removed
â Below - NethServer email log of email send via port 1465 - which is not working â
Aug 28 17:06:09 emails rspamd[22679]: ; lua; bayes_expiry.lua:440: finished expiry step 13: 985 items checked, 356 significant (0 made persistent), 7 insignificant (0 ttls set), 0 common (0 discriminated), 622 infrequent (0 ttls set), 1 mean, 1 std
Aug 28 17:06:56 emails postfix/smtpd[26374]: connect from 74.8.233.220.static.exetel.com.au[[removed - my IP address]]
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; proxy_accept_socket: accepted milter connection from /var/run/rspamd/worker-proxy port 0
Aug 28 17:06:56 emails postfix/smtpd[26374]: 47B70D9D44: client=74.8.233.220.static.exetel.com.au[[removed - my IP address]], sasl_method=PLAIN, sasl_username=[removed - email address senting from]
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; milter; rspamd_milter_process_command: got connection from [removed - my IP address]:49402
Aug 28 17:06:56 emails postfix/cleanup[26153]: 47B70D9D44: message-id=812138549935327.1630134415.985342502593994-flectra-private@aits-ub
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; rspamd_message_parse: loaded message; id: 812138549935327.1630134415.985342502593994-flectra-private@aits-ub; queue-id: <47B70D9D44>; size: 1047; checksum: <51de5f5dec2982d6d33bb70747a0078a>
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; lua; settings.lua:363: 812138549935327.1630134415.985342502593994-flectra-private@aits-ub apply static settings authenticated (id = 1937017268); authenticated matched; priority high
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; dkim_symbol_callback: skip DKIM checks for local networks and authorized users
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; lua; spf.lua:185: skip SPF checks for local networks and authorized users
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; lua; dmarc.lua:596: skip DMARC checks as either SPF or DKIM were not checked
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; lua; once_received.lua:99: Skipping once_received for authenticated user or local network
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; lua_task_insert_result_common: symbol insertion issue: unknown symbol GENERIC_REPUTATION; trace: [1]:{/usr/share/rspamd/plugins/reputation.lua:83 - add_symbol_score [Lua]}; [2]:{/usr/share/rspamd/plugins/reputation.lua:645 - continuation_cb [Lua]}; [3]:{/usr/share/rspamd/plugins/reputation.lua:929 - callback [Lua]}; [4]:{/usr/share/rspamd/lualib/lua_redis.lua:1296 - callback [Lua]}; [5]:{/usr/share/rspamd/lualib/lua_redis.lua:917 - [Lua]};
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; rspamd_symcache_finalize_item: slow rule: RSPAMD_EMAILBL(280): 310.35 ms; enable slow timer delay
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; rspamd_redis_connected: skip obtaining bayes tokens for BAYES_SPAM of classifier bayes: not enough learns 38; 200 required
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; rspamd_redis_connected: skip obtaining bayes tokens for BAYES_HAM of classifier bayes: not enough learns 32; 200 required
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; rspamd_stat_classifiers_process: skip statistics as SPAM class is missing
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; rspamd_task_write_log: id: 812138549935327.1630134415.985342502593994-flectra-private@aits-ub, qid: <47B70D9D44>, ip: [removed - my IP address], user: [removed - email address senting from], from: <bounce@[removed - my domain]>, (default: F (no action): [3.30/20.00] [CTYPE_MIXED_BOGUS(1.00){},MIME_BASE64_TEXT_BOGUS(1.00){},MID_RHS_NOT_FQDN(0.50){},R_PARTS_DIFFER(0.50){100.0%;},FORGED_SENDER(0.30){[removed - email address senting to];bounce@[removed - my domain];},MIME_BASE64_TEXT(0.10){},MIME_GOOD(-0.10){multipart/mixed;multipart/alternative;text/plain;},ASN(0.00){asn:10143, ipnet:220.233.8.0/21, country:AU;},FROM_HAS_DN(0.00){},FROM_NEQ_ENVFROM(0.00){[removed - email address senting to];bounce@[removed - my domain];},GENERIC_REPUTATION(0.00){-0.3952000363363;},HAS_REPLYTO(0.00){[removed - email address senting to];},MIME_TRACE(0.00){0:+;1:+;2:+;3:~;},RCPT_COUNT_ONE(0.00){1;},RCVD_COUNT_ZERO(0.00){0;},REPLYTO_EQ_FROM(0.00){},TAGGED_FROM(0.00){82;},TO_DN_NONE(0.00){},TO_EQ_FROM(0.00){},TO_MATCH_ENVRCPT_ALL(0.00){}]), len: 1047, time: 418.323ms, dns req: 3, digest: <51de5f5dec2982d6d33bb70747a0078a>, rcpts: <[removed - email address senting to]>, mime_rcpts: <[removed - email address senting to]>, settings_id: authenticated
Aug 28 17:06:56 emails rspamd[22678]: <77003f>; proxy; rspamd_protocol_http_reply: regexp statistics: 0 pcre regexps scanned, 3 regexps matched, 174 regexps total, 44 regexps cached, 0B scanned using pcre, 580B scanned total
Aug 28 17:06:56 emails opendkim[26002]: 47B70D9D44: DKIM-Signature field added (s=default, d=[removed - my domain])
Aug 28 17:06:56 emails postfix/qmgr[22325]: 47B70D9D44: from=<bounce+82@[removed - my domain]>, size=1341, nrcpt=1 (queue active)
â Below here is where the difference is with the port 465 test that worked â
Aug 28 17:06:56 emails rspamd[22678]: <9f52b3>; proxy; proxy_milter_finish_handler: finished milter connection
Aug 28 17:06:56 emails postfix/smtpd[26374]: disconnect from 74.8.233.220.static.exetel.com.au[[removed - my IP address]]
Aug 28 17:06:56 emails postfix/pipe[26378]: 47B70D9D44: to=<[removed - email address senting to]>, relay=sendandcopy, delay=0.51, delays=0.44/0.01/0/0.06, dsn=5.3.0, status=bounced (service unavailable. Command output: Message content rejected )
Aug 28 17:06:56 emails postfix/cleanup[26153]: C304EDDA79: message-id=<20210828070656.C304EDDA79@emails.[removed - my domain]>
Aug 28 17:06:56 emails postfix/qmgr[22325]: C304EDDA79: from=<>, size=4666, nrcpt=1 (queue active)
Aug 28 17:06:56 emails postfix/bounce[26383]: 47B70D9D44: sender non-delivery notification: C304EDDA79
Aug 28 17:06:56 emails postfix/qmgr[22325]: 47B70D9D44: removed
Aug 28 17:06:56 emails dovecot: lmtp(26384): Connect from local
Aug 28 17:06:56 emails dovecot: lmtp(bounce@[removed - my domain]): Error: qAUNMZDgKWEQZwAAEA1DJQ: sieve: Execution of script /var/lib/nethserver/sieve-scripts/unknown.sieve failed with unsuccessful implicit keep (user logfile /var/lib/nethserver/sieve-scripts/unknown.sieve.log may reveal additional details)
Aug 28 17:06:56 emails dovecot: lmtp(bounce@[removed - my domain]): msgid=<20210828070656.C304EDDA79@emails.[removed - my domain]>: save failed to open mailbox 82: Mailbox doesnât exist: 82
Aug 28 17:06:56 emails dovecot: lmtp(bounce@[removed - my domain]): save: box=INBOX, uid=5786, msgid=<20210828070656.C304EDDA79@emails.[removed - my domain]>, from=MAILER-DAEMON@emails.[removed - my domain] (Mail Delivery System), subject=Undelivered Mail Returned to Sender, flags=()
Aug 28 17:06:56 emails dovecot: lmtp(bounce@[removed - my domain]): msgid=<20210828070656.C304EDDA79@emails.[removed - my domain]>: saved mail to INBOX
Aug 28 17:06:56 emails postfix/lmtp[26155]: C304EDDA79: to=<bounce+82@[removed - my domain]>, relay=emails.[removed - my domain][/var/run/dovecot/lmtp], delay=0.05, delays=0.01/0/0.02/0.03, dsn=2.0.0, status=sent (250 2.0.0 <bounce+82@[removed - my domain]> qAUNMZDgKWEQZwAAEA1DJQ Saved)
Aug 28 17:06:56 emails dovecot: lmtp(26384): Disconnect from local: Successful quit
Aug 28 17:06:56 emails postfix/qmgr[22325]: C304EDDA79: removed