2025-04-05T17:22:16.684Z [DEBUG] [core] running mail hooks 2025-04-05T17:22:16.684Z [DEBUG] [core] running mail hook in known-senders plugin 2025-04-05T17:22:16.684Z [DEBUG] [core] hook=mail plugin=known-senders function=is_authenticated params= retval=CONT msg="" 2025-04-05T17:22:16.684Z [DEBUG] [core] running mail hook in guard plugin 2025-04-05T17:22:16.685Z [DEBUG] [core] hook=mail plugin=guard function=hook_mail params= retval=CONT msg="" 2025-04-05T17:22:16.685Z [DEBUG] [core] running mail hook in log plugin 2025-04-05T17:22:16.685Z [PROTOCOL] [core] C: MAIL FROM: state=1 2025-04-05T17:22:16.685Z [DEBUG] [core] hook=mail plugin=log function=hook_mail params= retval=CONT msg="" 2025-04-05T17:22:16.685Z [DEBUG] [core] running mail hook in rcpt_database plugin 2025-04-05T17:22:16.692Z [DEBUG] [core] hook=mail plugin=rcpt_database function=is_remote_sender params= retval=CONT msg="" 2025-04-05T17:22:16.692Z [DEBUG] [core] running mail hook in dovecot_quota plugin 2025-04-05T17:22:16.692Z [DEBUG] [core] hook=mail plugin=dovecot_quota function=hook_mail params= retval=CONT msg="" 2025-04-05T17:22:16.693Z [NOTICE] [core] sender code=CONT msg="" 2025-04-05T17:22:16.693Z [PROTOCOL] [core] S: 250 sender OK 2025-04-05T17:22:16.694Z [PROTOCOL] [core] C: RCPT TO: state=1 2025-04-05T17:22:16.695Z [DEBUG] [core] running rcpt hooks 2025-04-05T17:22:16.695Z [DEBUG] [core] running rcpt hook in block_bad_connections plugin 2025-04-05T17:22:16.695Z [DEBUG] [core] hook=rcpt plugin=block_bad_connections function=hook_rcpt params= retval=CONT msg="" 2025-04-05T17:22:16.695Z [DEBUG] [core] running rcpt hook in rcpt_database plugin 2025-04-05T17:22:16.698Z [DEBUG] [rcpt_database] remote delivery domain (relaying) 2025-04-05T17:22:16.698Z [INFO] [core] hook=rcpt plugin=rcpt_database function=database_rcpt params= retval=OK msg="" 2025-04-05T17:22:16.699Z [DEBUG] [core] running rcpt_ok hooks 2025-04-05T17:22:16.699Z [DEBUG] [core] running rcpt_ok hook in known-senders plugin 2025-04-05T17:22:16.699Z [DEBUG] [core] hook=rcpt_ok plugin=known-senders function=check_recipient params="" retval=CONT msg="" 2025-04-05T17:22:16.699Z [DEBUG] [core] running rcpt_ok hook in block_bad_connections plugin 2025-04-05T17:22:16.700Z [DEBUG] [core] hook=rcpt_ok plugin=block_bad_connections function=hook_rcpt_ok params="" retval=CONT msg="" 2025-04-05T17:22:16.700Z [NOTICE] [core] recipient code=OK msg="" sender=bea@bgmcd.org 2025-04-05T17:22:16.700Z [PROTOCOL] [core] S: 250 recipient OK 2025-04-05T17:22:16.701Z [PROTOCOL] [core] C: DATA state=1 2025-04-05T17:22:16.702Z [DEBUG] [core] running data hooks 2025-04-05T17:22:16.703Z [DEBUG] [core] running data hook in limits plugin 2025-04-05T17:22:16.725Z [DEBUG] [core] hook=data plugin=limits function=hook_data params="" retval=CONT msg="" 2025-04-05T17:22:16.728Z [DEBUG] [core] running data hook in attachment plugin 2025-04-05T17:22:16.728Z [DEBUG] [core] hook=data plugin=attachment function=hook_data params="" retval=CONT msg="" 2025-04-05T17:22:16.728Z [DEBUG] [core] running data hook in clamd plugin 2025-04-05T17:22:16.728Z [DEBUG] [core] hook=data plugin=clamd function=hook_data params="" retval=CONT msg="" 2025-04-05T17:22:16.729Z [PROTOCOL] [core] S: 354 go ahead, make my day 2025-04-05T17:22:16.753Z [DEBUG] [core] running data_post hooks 2025-04-05T17:22:16.754Z [DEBUG] [core] running data_post hook in known-senders plugin 2025-04-05T17:22:16.754Z [DEBUG] [core] hook=data_post plugin=known-senders function=is_dkim_authenticated params="" retval=CONT msg="" 2025-04-05T17:22:16.754Z [DEBUG] [core] running data_post hook in rspamd plugin 2025-04-05T17:22:16.755Z [DEBUG] [core] hook=data_post plugin=rspamd function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:22:16.755Z [DEBUG] [core] running data_post hook in rcpt_database plugin 2025-04-05T17:22:16.755Z [DEBUG] [core] hook=data_post plugin=rcpt_database function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:22:16.755Z [DEBUG] [core] running data_post hook in srs plugin 2025-04-05T17:22:16.756Z [DEBUG] [srs] SRS not applied (relaying: true, remote_source: false, remote_destination: true) 2025-04-05T17:22:16.756Z [DEBUG] [core] hook=data_post plugin=srs function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:22:16.756Z [DEBUG] [core] running data_post hook in dovecot_quota plugin 2025-04-05T17:22:16.756Z [DEBUG] [dovecot_quota] localhost is removed from quota, skipping 2025-04-05T17:22:16.757Z [DEBUG] [core] hook=data_post plugin=dovecot_quota function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:22:16.757Z [DEBUG] [core] running data_post hook in attachment plugin 2025-04-05T17:22:16.757Z [DEBUG] [core] hook=data_post plugin=attachment function=wait_for_attachment_hooks params="" retval=CONT msg="" 2025-04-05T17:22:16.757Z [DEBUG] [core] running data_post hook in attachment plugin 2025-04-05T17:22:16.757Z [DEBUG] [attachment] found content type: text/plain 2025-04-05T17:22:16.757Z [DEBUG] [core] hook=data_post plugin=attachment function=check_attachments params="" retval=CONT msg="" 2025-04-05T17:22:16.758Z [DEBUG] [core] running data_post hook in clamd plugin 2025-04-05T17:22:16.758Z [DEBUG] [core] hook=data_post plugin=clamd function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:22:16.758Z [DEBUG] [core] running data_post hook in strict_from plugin 2025-04-05T17:22:16.763Z [DEBUG] [core] hook=data_post plugin=strict_from function=from_check params="" retval=CONT msg="" 2025-04-05T17:22:16.763Z [DEBUG] [core] running data_post hook in inspection plugin 2025-04-05T17:22:16.763Z [DEBUG] [core] hook=data_post plugin=inspection function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:22:16.763Z [DEBUG] [core] running data_post hook in geoip plugin 2025-04-05T17:22:16.795Z [DEBUG] [core] hook=data_post plugin=geoip function=add_headers params="" retval=CONT msg="" 2025-04-05T17:22:16.796Z [NOTICE] [core] message mid=<7e88937977cc45ad745519b9ab8b232a@bgmcd.org> size=304 rcpts=1/0/0 delay=0.042 code=CONT msg="" 2025-04-05T17:22:16.796Z [DEBUG] [core] running queue_outbound hooks 2025-04-05T17:22:16.796Z [DEBUG] [core] running queue_outbound hook in outbound_parameters plugin 2025-04-05T17:22:16.804Z [DEBUG] [core] hook=queue_outbound plugin=outbound_parameters function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T17:22:16.804Z [DEBUG] [core] running queue_outbound hook in discard plugin 2025-04-05T17:22:16.804Z [DEBUG] [core] hook=queue_outbound plugin=discard function=discard params="" retval=CONT msg="" 2025-04-05T17:22:16.804Z [DEBUG] [core] running queue_outbound hook in privacy plugin 2025-04-05T17:22:16.806Z [DEBUG] [privacy] hiding IP from header Received: "from localhost (ip6-localhost [127.0.0.1])\r\n\tby bgmcd.org (Haraka) with ESMTPSA id 10FFC576-1849-48CB-809C-2B693344DA04.1\r\n\tenvelope-from \r\n\ttls TLS_AES_256_GCM_SHA384 (authenticated bits=0);\r\n\tSat, 05 Apr 2025 17:22:16 +0000\n" 2025-04-05T17:22:16.806Z [DEBUG] [core] hook=queue_outbound plugin=privacy function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T17:22:16.806Z [DEBUG] [core] running queue_outbound hook in mailauth/dkim_sign plugin 2025-04-05T17:22:16.808Z [DEBUG] [mailauth/dkim_sign] using first From header sender, using domain: bgmcd.org, original: bgmcd.org 2025-04-05T17:22:16.836Z [ERROR] [mailauth/dkim_sign] error: Error: ENOENT: no such file or directory, stat '/opt/haraka-submission/config/dkim/bgmcd.org' 2025-04-05T17:22:16.836Z [DEBUG] [mailauth/dkim_sign] domain: bgmcd.org, selector: undefined, private_key: undefined 2025-04-05T17:22:16.836Z [ERROR] [mailauth/dkim_sign] missing domain, selector or private_key 2025-04-05T17:22:16.837Z [DEBUG] [core] hook=queue_outbound plugin=mailauth/dkim_sign function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T17:22:16.837Z [DEBUG] [core] running queue_outbound hook in mailauth/arc plugin 2025-04-05T17:22:16.837Z [DEBUG] [core] hook=queue_outbound plugin=mailauth/arc function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T17:22:16.837Z [NOTICE] [core] queue code=CONT msg="Message Queued (10FFC576-1849-48CB-809C-2B693344DA04.1)" 2025-04-05T17:22:16.838Z [DEBUG] [mailauth/dkim_sign] using first From header sender, using domain: bgmcd.org, original: bgmcd.org 2025-04-05T17:22:16.850Z [DEBUG] [core] running queue_ok hooks 2025-04-05T17:22:16.850Z [DEBUG] [core] running queue_ok hook in known-senders plugin 2025-04-05T17:22:16.862Z [INFO] [known-senders] saved bgmcd.org : ik.me : 1 2025-04-05T17:22:16.862Z [DEBUG] [core] hook=queue_ok plugin=known-senders function=update_sender params="Message Queued (10FFC576-1849-48CB-809C-2B693344DA04.1)" retval=CONT msg="" 2025-04-05T17:22:16.862Z [DEBUG] [core] running queue_ok hook in stats plugin 2025-04-05T17:22:16.881Z [DEBUG] [core] hook=queue_ok plugin=stats function=hook_queue_ok params="Message Queued (10FFC576-1849-48CB-809C-2B693344DA04.1)" retval=CONT msg="" 2025-04-05T17:22:16.881Z [DEBUG] [core] running queue_ok hook in limits plugin 2025-04-05T17:22:16.883Z [DEBUG] [limits] increasing counters ["bea@bgmcd.org","@bgmcd.org"]: 1 2025-04-05T17:22:16.888Z [DEBUG] [core] hook=queue_ok plugin=limits function=hook_queue_ok params="Message Queued (10FFC576-1849-48CB-809C-2B693344DA04.1)" retval=CONT msg="" 2025-04-05T17:22:16.888Z [DEBUG] [core] running queue_ok hook in watch plugin 2025-04-05T17:22:16.889Z [DEBUG] [core] hook=queue_ok plugin=watch function=queue_ok params="Message Queued (10FFC576-1849-48CB-809C-2B693344DA04.1)" retval=CONT msg="" 2025-04-05T17:22:16.889Z [NOTICE] [core] queue code=OK msg="Message Queued (10FFC576-1849-48CB-809C-2B693344DA04.1)" 2025-04-05T17:22:16.889Z [PROTOCOL] [core] S: 250 Message Queued (10FFC576-1849-48CB-809C-2B693344DA04.1) 2025-04-05T17:22:16.889Z [DEBUG] [core] running reset_transaction hooks 2025-04-05T17:22:16.889Z [DEBUG] [core] running reset_transaction hook in stats plugin 2025-04-05T17:22:16.897Z [DEBUG] [core] hook=reset_transaction plugin=stats function=hook_reset_transaction params="" retval=CONT msg="" 2025-04-05T17:22:16.897Z [DEBUG] [core] running reset_transaction hook in log plugin 2025-04-05T17:22:16.899Z [DEBUG] [core] hook=reset_transaction plugin=log function=hook_reset_transaction params="" retval=CONT msg="" 2025-04-05T17:22:16.900Z [PROTOCOL] [core] C: QUIT state=1 2025-04-05T17:22:16.900Z [DEBUG] [core] running quit hooks 2025-04-05T17:22:16.900Z [PROTOCOL] [core] S: 221 bgmcd.org closing connection. Have a jolly good day. 2025-04-05T17:22:16.901Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.901Z [DEBUG] [core] running disconnect hooks 2025-04-05T17:22:16.901Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.901Z [DEBUG] [core] running disconnect hook in stats plugin 2025-04-05T17:22:16.905Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.905Z [DEBUG] [core] hook=disconnect plugin=stats function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T17:22:16.905Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.905Z [DEBUG] [core] running disconnect hook in block_bad_connections plugin 2025-04-05T17:22:16.906Z [DEBUG] [core] Authenticated, skipping... 2025-04-05T17:22:16.906Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.906Z [DEBUG] [core] hook=disconnect plugin=block_bad_connections function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T17:22:16.906Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.906Z [DEBUG] [core] running disconnect hook in log plugin 2025-04-05T17:22:16.906Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.906Z [DEBUG] [core] hook=disconnect plugin=log function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T17:22:16.906Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.906Z [DEBUG] [core] running disconnect hook in tls plugin 2025-04-05T17:22:16.907Z [DEBUG] [core] client has disconnected 2025-04-05T17:22:16.907Z [DEBUG] [core] hook=disconnect plugin=tls function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T17:22:16.907Z [NOTICE] [core] disconnect ip=127.0.0.1 rdns=ip6-localhost helo=localhost relay=Y early=N esmtp=Y tls=Y pipe=N errors=0 txns=1 rcpts=1/0/0 msgs=1/0/0 bytes=304 lr="" time=0.65