2025-04-05T18:02:38.627Z [DEBUG] [core] running mail hooks 2025-04-05T18:02:38.628Z [DEBUG] [core] running mail hook in known-senders plugin 2025-04-05T18:02:38.628Z [DEBUG] [core] hook=mail plugin=known-senders function=is_authenticated params= retval=CONT msg="" 2025-04-05T18:02:38.628Z [DEBUG] [core] running mail hook in guard plugin 2025-04-05T18:02:38.628Z [DEBUG] [core] hook=mail plugin=guard function=hook_mail params= retval=CONT msg="" 2025-04-05T18:02:38.628Z [DEBUG] [core] running mail hook in log plugin 2025-04-05T18:02:38.628Z [PROTOCOL] [core] C: MAIL FROM: state=1 2025-04-05T18:02:38.628Z [DEBUG] [core] hook=mail plugin=log function=hook_mail params= retval=CONT msg="" 2025-04-05T18:02:38.629Z [DEBUG] [core] running mail hook in rcpt_database plugin 2025-04-05T18:02:38.635Z [DEBUG] [core] pipeline: RCPT TO:\r 2025-04-05T18:02:38.642Z [DEBUG] [core] hook=mail plugin=rcpt_database function=is_remote_sender params= retval=CONT msg="" 2025-04-05T18:02:38.642Z [DEBUG] [core] running mail hook in dovecot_quota plugin 2025-04-05T18:02:38.643Z [DEBUG] [core] hook=mail plugin=dovecot_quota function=hook_mail params= retval=CONT msg="" 2025-04-05T18:02:38.643Z [NOTICE] [core] sender code=CONT msg="" 2025-04-05T18:02:38.643Z [PROTOCOL] [core] S: 250 sender OK 2025-04-05T18:02:38.644Z [PROTOCOL] [core] C: RCPT TO: state=1 2025-04-05T18:02:38.646Z [DEBUG] [core] running rcpt hooks 2025-04-05T18:02:38.646Z [DEBUG] [core] running rcpt hook in block_bad_connections plugin 2025-04-05T18:02:38.646Z [DEBUG] [core] hook=rcpt plugin=block_bad_connections function=hook_rcpt params= retval=CONT msg="" 2025-04-05T18:02:38.646Z [DEBUG] [core] running rcpt hook in rcpt_database plugin 2025-04-05T18:02:38.647Z [DEBUG] [core] pipeline: DATA\r 2025-04-05T18:02:38.652Z [INFO] [rcpt_database] ok, user found in user database 2025-04-05T18:02:38.660Z [INFO] [core] hook=rcpt plugin=rcpt_database function=database_rcpt params= retval=OK msg="" 2025-04-05T18:02:38.661Z [DEBUG] [core] running rcpt_ok hooks 2025-04-05T18:02:38.661Z [DEBUG] [core] running rcpt_ok hook in known-senders plugin 2025-04-05T18:02:38.662Z [DEBUG] [core] hook=rcpt_ok plugin=known-senders function=check_recipient params="" retval=CONT msg="" 2025-04-05T18:02:38.662Z [DEBUG] [core] running rcpt_ok hook in block_bad_connections plugin 2025-04-05T18:02:38.663Z [DEBUG] [core] hook=rcpt_ok plugin=block_bad_connections function=hook_rcpt_ok params="" retval=CONT msg="" 2025-04-05T18:02:38.663Z [NOTICE] [core] recipient code=OK msg="" sender=postmaster+letsencrypt@bgmcd.org 2025-04-05T18:02:38.663Z [PROTOCOL] [core] S: 250 recipient OK 2025-04-05T18:02:38.664Z [PROTOCOL] [core] C: DATA state=1 2025-04-05T18:02:38.666Z [DEBUG] [core] running data hooks 2025-04-05T18:02:38.667Z [DEBUG] [core] running data hook in limits plugin 2025-04-05T18:02:38.667Z [DEBUG] [limits] Non-relaying IP or localhost, skipping... 2025-04-05T18:02:38.667Z [DEBUG] [core] hook=data plugin=limits function=hook_data params="" retval=CONT msg="" 2025-04-05T18:02:38.667Z [DEBUG] [core] running data hook in attachment plugin 2025-04-05T18:02:38.668Z [DEBUG] [core] hook=data plugin=attachment function=hook_data params="" retval=CONT msg="" 2025-04-05T18:02:38.668Z [PROTOCOL] [core] S: 354 go ahead, make my day 2025-04-05T18:02:38.675Z [DEBUG] [core] Authentication-Results moved to Original-Authentication-Results 2025-04-05T18:02:38.678Z [DEBUG] [core] running data_post hooks 2025-04-05T18:02:38.678Z [DEBUG] [core] running data_post hook in known-senders plugin 2025-04-05T18:02:38.678Z [DEBUG] [core] hook=data_post plugin=known-senders function=is_dkim_authenticated params="" retval=CONT msg="" 2025-04-05T18:02:38.678Z [DEBUG] [core] running data_post hook in rspamd plugin 2025-04-05T18:02:38.679Z [DEBUG] [core] hook=data_post plugin=rspamd function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.679Z [DEBUG] [core] running data_post hook in rcpt_database plugin 2025-04-05T18:02:38.679Z [DEBUG] [core] hook=data_post plugin=rcpt_database function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.679Z [DEBUG] [core] running data_post hook in srs plugin 2025-04-05T18:02:38.681Z [DEBUG] [srs] SRS not applied (relaying: true, remote_source: false, remote_destination: undefined) 2025-04-05T18:02:38.682Z [DEBUG] [core] hook=data_post plugin=srs function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.682Z [DEBUG] [core] running data_post hook in dovecot_quota plugin 2025-04-05T18:02:38.682Z [DEBUG] [dovecot_quota] localhost is removed from quota, skipping 2025-04-05T18:02:38.682Z [DEBUG] [core] hook=data_post plugin=dovecot_quota function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.682Z [DEBUG] [core] running data_post hook in attachment plugin 2025-04-05T18:02:38.682Z [DEBUG] [core] hook=data_post plugin=attachment function=wait_for_attachment_hooks params="" retval=CONT msg="" 2025-04-05T18:02:38.682Z [DEBUG] [core] running data_post hook in attachment plugin 2025-04-05T18:02:38.683Z [DEBUG] [attachment] found content type: text/html 2025-04-05T18:02:38.683Z [DEBUG] [core] hook=data_post plugin=attachment function=check_attachments params="" retval=CONT msg="" 2025-04-05T18:02:38.683Z [DEBUG] [core] running data_post hook in strict_from plugin 2025-04-05T18:02:38.683Z [DEBUG] [strict_from] skiping non-authenticated user 2025-04-05T18:02:38.684Z [DEBUG] [core] hook=data_post plugin=strict_from function=from_check params="" retval=CONT msg="" 2025-04-05T18:02:38.684Z [DEBUG] [core] running data_post hook in inspection plugin 2025-04-05T18:02:38.684Z [DEBUG] [core] hook=data_post plugin=inspection function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.684Z [DEBUG] [core] running data_post hook in geoip plugin 2025-04-05T18:02:38.697Z [DEBUG] [core] hook=data_post plugin=geoip function=add_headers params="" retval=CONT msg="" 2025-04-05T18:02:38.698Z [NOTICE] [core] message mid=<7f76518a2f4920713b9b96e591ef0471@bgmcd.org> size=5218 rcpts=1/0/0 delay=0.02 code=CONT msg="" 2025-04-05T18:02:38.698Z [DEBUG] [core] running queue_outbound hooks 2025-04-05T18:02:38.698Z [DEBUG] [core] running queue_outbound hook in outbound_parameters plugin 2025-04-05T18:02:38.707Z [DEBUG] [core] hook=queue_outbound plugin=outbound_parameters function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T18:02:38.707Z [DEBUG] [core] running queue_outbound hook in discard plugin 2025-04-05T18:02:38.707Z [DEBUG] [core] hook=queue_outbound plugin=discard function=discard params="" retval=CONT msg="" 2025-04-05T18:02:38.707Z [DEBUG] [core] running queue_outbound hook in privacy plugin 2025-04-05T18:02:38.715Z [DEBUG] [privacy] target inside mailserver, ignored 2025-04-05T18:02:38.715Z [DEBUG] [core] hook=queue_outbound plugin=privacy function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T18:02:38.715Z [DEBUG] [core] running queue_outbound hook in mailauth/dkim_sign plugin 2025-04-05T18:02:38.716Z [DEBUG] [mailauth/dkim_sign] forwarding, using domain: bgmcd.org 2025-04-05T18:02:38.717Z [ERROR] [mailauth/dkim_sign] error: Error: ENOENT: no such file or directory, stat '/opt/haraka-submission/config/dkim/bgmcd.org' 2025-04-05T18:02:38.717Z [DEBUG] [mailauth/dkim_sign] domain: bgmcd.org, selector: undefined, private_key: undefined 2025-04-05T18:02:38.717Z [ERROR] [mailauth/dkim_sign] missing domain, selector or private_key 2025-04-05T18:02:38.717Z [DEBUG] [core] hook=queue_outbound plugin=mailauth/dkim_sign function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T18:02:38.717Z [DEBUG] [core] running queue_outbound hook in mailauth/arc plugin 2025-04-05T18:02:38.717Z [DEBUG] [core] hook=queue_outbound plugin=mailauth/arc function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T18:02:38.718Z [NOTICE] [core] queue code=CONT msg="Message Queued (73F85AE8-15B8-407C-864E-2AE09FCA0705.1)" 2025-04-05T18:02:38.718Z [DEBUG] [mailauth/dkim_sign] forwarding, using domain: bgmcd.org 2025-04-05T18:02:38.727Z [DEBUG] [core] running queue_ok hooks 2025-04-05T18:02:38.727Z [DEBUG] [core] running queue_ok hook in known-senders plugin 2025-04-05T18:02:38.740Z [INFO] [known-senders] saved bgmcd.org : bgmcd.org : 1 2025-04-05T18:02:38.740Z [DEBUG] [core] hook=queue_ok plugin=known-senders function=update_sender params="Message Queued (73F85AE8-15B8-407C-864E-2AE09FCA0705.1)" retval=CONT msg="" 2025-04-05T18:02:38.740Z [DEBUG] [core] running queue_ok hook in stats plugin 2025-04-05T18:02:38.751Z [DEBUG] [core] hook=queue_ok plugin=stats function=hook_queue_ok params="Message Queued (73F85AE8-15B8-407C-864E-2AE09FCA0705.1)" retval=CONT msg="" 2025-04-05T18:02:38.751Z [DEBUG] [core] running queue_ok hook in limits plugin 2025-04-05T18:02:38.751Z [DEBUG] [limits] increasing counters ["127.0.0.1/32"]: 1 2025-04-05T18:02:38.755Z [DEBUG] [core] hook=queue_ok plugin=limits function=hook_queue_ok params="Message Queued (73F85AE8-15B8-407C-864E-2AE09FCA0705.1)" retval=CONT msg="" 2025-04-05T18:02:38.755Z [DEBUG] [core] running queue_ok hook in watch plugin 2025-04-05T18:02:38.756Z [DEBUG] [core] hook=queue_ok plugin=watch function=queue_ok params="Message Queued (73F85AE8-15B8-407C-864E-2AE09FCA0705.1)" retval=CONT msg="" 2025-04-05T18:02:38.756Z [NOTICE] [core] queue code=OK msg="Message Queued (73F85AE8-15B8-407C-864E-2AE09FCA0705.1)" 2025-04-05T18:02:38.756Z [PROTOCOL] [core] S: 250 Message Queued (73F85AE8-15B8-407C-864E-2AE09FCA0705.1) 2025-04-05T18:02:38.756Z [DEBUG] [core] running reset_transaction hooks 2025-04-05T18:02:38.756Z [DEBUG] [core] running reset_transaction hook in stats plugin 2025-04-05T18:02:38.763Z [DEBUG] [core] hook=reset_transaction plugin=stats function=hook_reset_transaction params="" retval=CONT msg="" 2025-04-05T18:02:38.763Z [DEBUG] [core] running reset_transaction hook in log plugin 2025-04-05T18:02:38.764Z [DEBUG] [core] hook=reset_transaction plugin=log function=hook_reset_transaction params="" retval=CONT msg="" 2025-04-05T18:02:38.765Z [PROTOCOL] [core] C: QUIT state=1 2025-04-05T18:02:38.765Z [DEBUG] [core] running quit hooks 2025-04-05T18:02:38.765Z [PROTOCOL] [core] S: 221 bgmcd.org closing connection. Have a jolly good day. 2025-04-05T18:02:38.765Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.765Z [DEBUG] [core] running disconnect hooks 2025-04-05T18:02:38.765Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.765Z [DEBUG] [core] running disconnect hook in stats plugin 2025-04-05T18:02:38.779Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.779Z [DEBUG] [core] hook=disconnect plugin=stats function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T18:02:38.779Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.779Z [DEBUG] [core] running disconnect hook in block_bad_connections plugin 2025-04-05T18:02:38.779Z [DEBUG] [core] Blacklisted/Whitelisted, skipping... 2025-04-05T18:02:38.779Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.780Z [DEBUG] [core] hook=disconnect plugin=block_bad_connections function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T18:02:38.780Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.780Z [DEBUG] [core] running disconnect hook in log plugin 2025-04-05T18:02:38.780Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.780Z [DEBUG] [core] hook=disconnect plugin=log function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T18:02:38.780Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.780Z [DEBUG] [core] running disconnect hook in tls plugin 2025-04-05T18:02:38.780Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.780Z [DEBUG] [core] hook=disconnect plugin=tls function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T18:02:38.780Z [NOTICE] [core] disconnect ip=127.0.0.1 rdns=ip6-localhost helo=bgmcd.org relay=Y early=N esmtp=Y tls=N pipe=Y errors=0 txns=1 rcpts=1/0/0 msgs=1/0/0 bytes=5218 lr="" time=0.175