2025-04-05T18:02:38.771Z [DEBUG] [core] running mail hooks 2025-04-05T18:02:38.772Z [DEBUG] [core] running mail hook in known-senders plugin 2025-04-05T18:02:38.772Z [DEBUG] [core] hook=mail plugin=known-senders function=is_authenticated params= retval=CONT msg="" 2025-04-05T18:02:38.772Z [DEBUG] [core] running mail hook in guard plugin 2025-04-05T18:02:38.772Z [DEBUG] [core] hook=mail plugin=guard function=hook_mail params= retval=CONT msg="" 2025-04-05T18:02:38.772Z [DEBUG] [core] running mail hook in log plugin 2025-04-05T18:02:38.772Z [PROTOCOL] [core] C: MAIL FROM: state=1 2025-04-05T18:02:38.772Z [DEBUG] [core] hook=mail plugin=log function=hook_mail params= retval=CONT msg="" 2025-04-05T18:02:38.772Z [DEBUG] [core] running mail hook in rcpt_database plugin 2025-04-05T18:02:38.772Z [DEBUG] [core] pipeline: RCPT TO:\r 2025-04-05T18:02:38.781Z [DEBUG] [core] hook=mail plugin=rcpt_database function=is_remote_sender params= retval=CONT msg="" 2025-04-05T18:02:38.781Z [DEBUG] [core] running mail hook in dovecot_quota plugin 2025-04-05T18:02:38.781Z [DEBUG] [core] hook=mail plugin=dovecot_quota function=hook_mail params= retval=CONT msg="" 2025-04-05T18:02:38.781Z [NOTICE] [core] sender code=CONT msg="" 2025-04-05T18:02:38.781Z [PROTOCOL] [core] S: 250 sender OK 2025-04-05T18:02:38.781Z [PROTOCOL] [core] C: RCPT TO: state=1 2025-04-05T18:02:38.783Z [DEBUG] [core] running rcpt hooks 2025-04-05T18:02:38.784Z [DEBUG] [core] running rcpt hook in block_bad_connections plugin 2025-04-05T18:02:38.784Z [DEBUG] [core] hook=rcpt plugin=block_bad_connections function=hook_rcpt params= retval=CONT msg="" 2025-04-05T18:02:38.784Z [DEBUG] [core] running rcpt hook in rcpt_database plugin 2025-04-05T18:02:38.784Z [DEBUG] [core] pipeline: DATA\r 2025-04-05T18:02:38.790Z [DEBUG] [rcpt_database] remote delivery domain (relaying) 2025-04-05T18:02:38.790Z [INFO] [core] hook=rcpt plugin=rcpt_database function=database_rcpt params= retval=OK msg="" 2025-04-05T18:02:38.790Z [DEBUG] [core] running rcpt_ok hooks 2025-04-05T18:02:38.790Z [DEBUG] [core] running rcpt_ok hook in known-senders plugin 2025-04-05T18:02:38.790Z [DEBUG] [core] hook=rcpt_ok plugin=known-senders function=check_recipient params="" retval=CONT msg="" 2025-04-05T18:02:38.790Z [DEBUG] [core] running rcpt_ok hook in block_bad_connections plugin 2025-04-05T18:02:38.792Z [DEBUG] [core] hook=rcpt_ok plugin=block_bad_connections function=hook_rcpt_ok params="" retval=CONT msg="" 2025-04-05T18:02:38.792Z [NOTICE] [core] recipient code=OK msg="" sender=postmaster+letsencrypt@bgmcd.org 2025-04-05T18:02:38.792Z [PROTOCOL] [core] S: 250 recipient OK 2025-04-05T18:02:38.792Z [PROTOCOL] [core] C: DATA state=1 2025-04-05T18:02:38.793Z [DEBUG] [core] running data hooks 2025-04-05T18:02:38.793Z [DEBUG] [core] running data hook in limits plugin 2025-04-05T18:02:38.793Z [DEBUG] [limits] Non-relaying IP or localhost, skipping... 2025-04-05T18:02:38.793Z [DEBUG] [core] hook=data plugin=limits function=hook_data params="" retval=CONT msg="" 2025-04-05T18:02:38.793Z [DEBUG] [core] running data hook in attachment plugin 2025-04-05T18:02:38.793Z [DEBUG] [core] hook=data plugin=attachment function=hook_data params="" retval=CONT msg="" 2025-04-05T18:02:38.793Z [PROTOCOL] [core] S: 354 go ahead, make my day 2025-04-05T18:02:38.801Z [DEBUG] [core] Authentication-Results moved to Original-Authentication-Results 2025-04-05T18:02:38.804Z [DEBUG] [core] running data_post hooks 2025-04-05T18:02:38.804Z [DEBUG] [core] running data_post hook in known-senders plugin 2025-04-05T18:02:38.805Z [DEBUG] [core] hook=data_post plugin=known-senders function=is_dkim_authenticated params="" retval=CONT msg="" 2025-04-05T18:02:38.805Z [DEBUG] [core] running data_post hook in rspamd plugin 2025-04-05T18:02:38.806Z [DEBUG] [core] hook=data_post plugin=rspamd function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.806Z [DEBUG] [core] running data_post hook in rcpt_database plugin 2025-04-05T18:02:38.806Z [DEBUG] [core] hook=data_post plugin=rcpt_database function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.806Z [DEBUG] [core] running data_post hook in srs plugin 2025-04-05T18:02:38.807Z [DEBUG] [srs] SRS not applied (relaying: true, remote_source: false, remote_destination: true) 2025-04-05T18:02:38.807Z [DEBUG] [core] hook=data_post plugin=srs function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.807Z [DEBUG] [core] running data_post hook in dovecot_quota plugin 2025-04-05T18:02:38.807Z [DEBUG] [dovecot_quota] localhost is removed from quota, skipping 2025-04-05T18:02:38.808Z [DEBUG] [core] hook=data_post plugin=dovecot_quota function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.808Z [DEBUG] [core] running data_post hook in attachment plugin 2025-04-05T18:02:38.808Z [DEBUG] [core] hook=data_post plugin=attachment function=wait_for_attachment_hooks params="" retval=CONT msg="" 2025-04-05T18:02:38.808Z [DEBUG] [core] running data_post hook in attachment plugin 2025-04-05T18:02:38.808Z [DEBUG] [attachment] found content type: text/html 2025-04-05T18:02:38.808Z [DEBUG] [core] hook=data_post plugin=attachment function=check_attachments params="" retval=CONT msg="" 2025-04-05T18:02:38.809Z [DEBUG] [core] running data_post hook in strict_from plugin 2025-04-05T18:02:38.809Z [DEBUG] [strict_from] skiping non-authenticated user 2025-04-05T18:02:38.809Z [DEBUG] [core] hook=data_post plugin=strict_from function=from_check params="" retval=CONT msg="" 2025-04-05T18:02:38.809Z [DEBUG] [core] running data_post hook in inspection plugin 2025-04-05T18:02:38.809Z [DEBUG] [core] hook=data_post plugin=inspection function=hook_data_post params="" retval=CONT msg="" 2025-04-05T18:02:38.809Z [DEBUG] [core] running data_post hook in geoip plugin 2025-04-05T18:02:38.810Z [DEBUG] [core] hook=data_post plugin=geoip function=add_headers params="" retval=CONT msg="" 2025-04-05T18:02:38.810Z [NOTICE] [core] message mid=<7f76518a2f4920713b9b96e591ef0471@bgmcd.org> size=5218 rcpts=1/0/0 delay=0.007 code=CONT msg="" 2025-04-05T18:02:38.810Z [DEBUG] [core] running queue_outbound hooks 2025-04-05T18:02:38.810Z [DEBUG] [core] running queue_outbound hook in outbound_parameters plugin 2025-04-05T18:02:38.816Z [DEBUG] [core] hook=queue_outbound plugin=outbound_parameters function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T18:02:38.816Z [DEBUG] [core] running queue_outbound hook in discard plugin 2025-04-05T18:02:38.816Z [DEBUG] [core] hook=queue_outbound plugin=discard function=discard params="" retval=CONT msg="" 2025-04-05T18:02:38.816Z [DEBUG] [core] running queue_outbound hook in privacy plugin 2025-04-05T18:02:38.824Z [DEBUG] [privacy] removing header "x-sieve: ["Pigeonhole Sieve 0.5.21.1 (49005e73)\n"]" 2025-04-05T18:02:38.826Z [DEBUG] [privacy] removing header "x-sieve-redirected-from: ["bea@bgmcd.org\n"]" 2025-04-05T18:02:38.827Z [DEBUG] [privacy] hiding IP from header Received: "from bgmcd.org (ip6-localhost [127.0.0.1])\r\n\tby bgmcd.org (Haraka) with ESMTP id E6AA32FD-BC55-4F35-8CEF-C3645977CC5E.1\r\n\tenvelope-from ;\r\n\tSat, 05 Apr 2025 18:02:38 +0000\n" 2025-04-05T18:02:38.827Z [DEBUG] [privacy] hiding IP from header Received: "from [127.0.0.1] (ip6-localhost [::1])\n\tby bgmcd.org (Haraka) with ESMTPS id 2C522607-5B41-409B-B320-B7EE348E6776.1\n\tenvelope-from \n\ttls TLS_AES_256_GCM_SHA384;\n\tSat, 05 Apr 2025 18:02:34 +0000\n" 2025-04-05T18:02:38.827Z [DEBUG] [core] hook=queue_outbound plugin=privacy function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T18:02:38.827Z [DEBUG] [core] running queue_outbound hook in mailauth/dkim_sign plugin 2025-04-05T18:02:38.831Z [DEBUG] [mailauth/dkim_sign] using first From header sender, using domain: bgmcd.org, original: bgmcd.org 2025-04-05T18:02:38.832Z [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.832Z [DEBUG] [mailauth/dkim_sign] domain: bgmcd.org, selector: undefined, private_key: undefined 2025-04-05T18:02:38.832Z [ERROR] [mailauth/dkim_sign] missing domain, selector or private_key 2025-04-05T18:02:38.832Z [DEBUG] [core] hook=queue_outbound plugin=mailauth/dkim_sign function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T18:02:38.832Z [DEBUG] [core] running queue_outbound hook in mailauth/arc plugin 2025-04-05T18:02:38.832Z [DEBUG] [core] hook=queue_outbound plugin=mailauth/arc function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T18:02:38.832Z [NOTICE] [core] queue code=CONT msg="Message Queued (E6AA32FD-BC55-4F35-8CEF-C3645977CC5E.1)" 2025-04-05T18:02:38.833Z [DEBUG] [mailauth/dkim_sign] using first From header sender, using domain: bgmcd.org, original: bgmcd.org 2025-04-05T18:02:38.843Z [DEBUG] [core] running queue_ok hooks 2025-04-05T18:02:38.843Z [DEBUG] [core] running queue_ok hook in known-senders plugin 2025-04-05T18:02:38.845Z [INFO] [known-senders] saved bgmcd.org : ik.me : 3 2025-04-05T18:02:38.845Z [DEBUG] [core] hook=queue_ok plugin=known-senders function=update_sender params="Message Queued (E6AA32FD-BC55-4F35-8CEF-C3645977CC5E.1)" retval=CONT msg="" 2025-04-05T18:02:38.845Z [DEBUG] [core] running queue_ok hook in stats plugin 2025-04-05T18:02:38.849Z [DEBUG] [core] hook=queue_ok plugin=stats function=hook_queue_ok params="Message Queued (E6AA32FD-BC55-4F35-8CEF-C3645977CC5E.1)" retval=CONT msg="" 2025-04-05T18:02:38.849Z [DEBUG] [core] running queue_ok hook in limits plugin 2025-04-05T18:02:38.849Z [DEBUG] [limits] increasing counters ["127.0.0.1/32"]: 1 2025-04-05T18:02:38.855Z [DEBUG] [core] hook=queue_ok plugin=limits function=hook_queue_ok params="Message Queued (E6AA32FD-BC55-4F35-8CEF-C3645977CC5E.1)" retval=CONT msg="" 2025-04-05T18:02:38.855Z [DEBUG] [core] running queue_ok hook in watch plugin 2025-04-05T18:02:38.855Z [DEBUG] [core] hook=queue_ok plugin=watch function=queue_ok params="Message Queued (E6AA32FD-BC55-4F35-8CEF-C3645977CC5E.1)" retval=CONT msg="" 2025-04-05T18:02:38.856Z [NOTICE] [core] queue code=OK msg="Message Queued (E6AA32FD-BC55-4F35-8CEF-C3645977CC5E.1)" 2025-04-05T18:02:38.856Z [PROTOCOL] [core] S: 250 Message Queued (E6AA32FD-BC55-4F35-8CEF-C3645977CC5E.1) 2025-04-05T18:02:38.856Z [DEBUG] [core] running reset_transaction hooks 2025-04-05T18:02:38.857Z [DEBUG] [core] running reset_transaction hook in stats plugin 2025-04-05T18:02:38.860Z [DEBUG] [core] hook=reset_transaction plugin=stats function=hook_reset_transaction params="" retval=CONT msg="" 2025-04-05T18:02:38.860Z [DEBUG] [core] running reset_transaction hook in log plugin 2025-04-05T18:02:38.860Z [DEBUG] [core] hook=reset_transaction plugin=log function=hook_reset_transaction params="" retval=CONT msg="" 2025-04-05T18:02:38.861Z [PROTOCOL] [core] C: QUIT state=1 2025-04-05T18:02:38.861Z [DEBUG] [core] running quit hooks 2025-04-05T18:02:38.861Z [PROTOCOL] [core] S: 221 bgmcd.org closing connection. Have a jolly good day. 2025-04-05T18:02:38.862Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.862Z [DEBUG] [core] running disconnect hooks 2025-04-05T18:02:38.862Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.862Z [DEBUG] [core] running disconnect hook in stats plugin 2025-04-05T18:02:38.863Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.863Z [DEBUG] [core] hook=disconnect plugin=stats function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T18:02:38.863Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.863Z [DEBUG] [core] running disconnect hook in block_bad_connections plugin 2025-04-05T18:02:38.863Z [DEBUG] [core] Blacklisted/Whitelisted, skipping... 2025-04-05T18:02:38.863Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.863Z [DEBUG] [core] hook=disconnect plugin=block_bad_connections function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T18:02:38.863Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.863Z [DEBUG] [core] running disconnect hook in log plugin 2025-04-05T18:02:38.864Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.864Z [DEBUG] [core] hook=disconnect plugin=log function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T18:02:38.864Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.864Z [DEBUG] [core] running disconnect hook in tls plugin 2025-04-05T18:02:38.864Z [DEBUG] [core] client has disconnected 2025-04-05T18:02:38.864Z [DEBUG] [core] hook=disconnect plugin=tls function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T18:02:38.864Z [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.106