2025-04-05T17:31:00.558Z [DEBUG] [core] running mail hooks 2025-04-05T17:31:00.558Z [DEBUG] [core] running mail hook in known-senders plugin 2025-04-05T17:31:00.558Z [DEBUG] [core] hook=mail plugin=known-senders function=is_authenticated params= retval=CONT msg="" 2025-04-05T17:31:00.559Z [DEBUG] [core] running mail hook in guard plugin 2025-04-05T17:31:00.560Z [DEBUG] [core] hook=mail plugin=guard function=hook_mail params= retval=CONT msg="" 2025-04-05T17:31:00.560Z [DEBUG] [core] running mail hook in log plugin 2025-04-05T17:31:00.560Z [PROTOCOL] [core] C: MAIL FROM: state=1 2025-04-05T17:31:00.560Z [DEBUG] [core] hook=mail plugin=log function=hook_mail params= retval=CONT msg="" 2025-04-05T17:31:00.560Z [DEBUG] [core] running mail hook in rcpt_database plugin 2025-04-05T17:31:00.564Z [DEBUG] [core] hook=mail plugin=rcpt_database function=is_remote_sender params= retval=CONT msg="" 2025-04-05T17:31:00.564Z [DEBUG] [core] running mail hook in dovecot_quota plugin 2025-04-05T17:31:00.564Z [DEBUG] [core] hook=mail plugin=dovecot_quota function=hook_mail params= retval=CONT msg="" 2025-04-05T17:31:00.565Z [NOTICE] [core] sender code=CONT msg="" 2025-04-05T17:31:00.565Z [PROTOCOL] [core] S: 250 sender OK 2025-04-05T17:31:00.566Z [PROTOCOL] [core] C: RCPT TO: state=1 2025-04-05T17:31:00.567Z [DEBUG] [core] running rcpt hooks 2025-04-05T17:31:00.567Z [DEBUG] [core] running rcpt hook in block_bad_connections plugin 2025-04-05T17:31:00.568Z [DEBUG] [core] hook=rcpt plugin=block_bad_connections function=hook_rcpt params= retval=CONT msg="" 2025-04-05T17:31:00.568Z [DEBUG] [core] running rcpt hook in rcpt_database plugin 2025-04-05T17:31:00.570Z [DEBUG] [rcpt_database] remote delivery domain (relaying) 2025-04-05T17:31:00.570Z [INFO] [core] hook=rcpt plugin=rcpt_database function=database_rcpt params= retval=OK msg="" 2025-04-05T17:31:00.570Z [DEBUG] [core] running rcpt_ok hooks 2025-04-05T17:31:00.571Z [DEBUG] [core] running rcpt_ok hook in known-senders plugin 2025-04-05T17:31:00.571Z [DEBUG] [core] hook=rcpt_ok plugin=known-senders function=check_recipient params="" retval=CONT msg="" 2025-04-05T17:31:00.571Z [DEBUG] [core] running rcpt_ok hook in block_bad_connections plugin 2025-04-05T17:31:00.572Z [DEBUG] [core] hook=rcpt_ok plugin=block_bad_connections function=hook_rcpt_ok params="" retval=CONT msg="" 2025-04-05T17:31:00.572Z [NOTICE] [core] recipient code=OK msg="" sender=bea@bgmcd.org 2025-04-05T17:31:00.572Z [PROTOCOL] [core] S: 250 recipient OK 2025-04-05T17:31:00.573Z [PROTOCOL] [core] C: DATA state=1 2025-04-05T17:31:00.574Z [DEBUG] [core] running data hooks 2025-04-05T17:31:00.575Z [DEBUG] [core] running data hook in limits plugin 2025-04-05T17:31:00.582Z [DEBUG] [core] hook=data plugin=limits function=hook_data params="" retval=CONT msg="" 2025-04-05T17:31:00.582Z [DEBUG] [core] running data hook in attachment plugin 2025-04-05T17:31:00.582Z [DEBUG] [core] hook=data plugin=attachment function=hook_data params="" retval=CONT msg="" 2025-04-05T17:31:00.582Z [DEBUG] [core] running data hook in clamd plugin 2025-04-05T17:31:00.583Z [DEBUG] [core] hook=data plugin=clamd function=hook_data params="" retval=CONT msg="" 2025-04-05T17:31:00.583Z [PROTOCOL] [core] S: 354 go ahead, make my day 2025-04-05T17:31:00.596Z [DEBUG] [core] running data_post hooks 2025-04-05T17:31:00.596Z [DEBUG] [core] running data_post hook in known-senders plugin 2025-04-05T17:31:00.596Z [DEBUG] [core] hook=data_post plugin=known-senders function=is_dkim_authenticated params="" retval=CONT msg="" 2025-04-05T17:31:00.596Z [DEBUG] [core] running data_post hook in rspamd plugin 2025-04-05T17:31:00.597Z [DEBUG] [core] hook=data_post plugin=rspamd function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:31:00.597Z [DEBUG] [core] running data_post hook in rcpt_database plugin 2025-04-05T17:31:00.597Z [DEBUG] [core] hook=data_post plugin=rcpt_database function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:31:00.597Z [DEBUG] [core] running data_post hook in srs plugin 2025-04-05T17:31:00.597Z [DEBUG] [srs] SRS not applied (relaying: true, remote_source: false, remote_destination: true) 2025-04-05T17:31:00.597Z [DEBUG] [core] hook=data_post plugin=srs function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:31:00.597Z [DEBUG] [core] running data_post hook in dovecot_quota plugin 2025-04-05T17:31:00.597Z [DEBUG] [dovecot_quota] localhost is removed from quota, skipping 2025-04-05T17:31:00.597Z [DEBUG] [core] hook=data_post plugin=dovecot_quota function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:31:00.597Z [DEBUG] [core] running data_post hook in attachment plugin 2025-04-05T17:31:00.598Z [DEBUG] [core] hook=data_post plugin=attachment function=wait_for_attachment_hooks params="" retval=CONT msg="" 2025-04-05T17:31:00.598Z [DEBUG] [core] running data_post hook in attachment plugin 2025-04-05T17:31:00.598Z [DEBUG] [core] hook=data_post plugin=attachment function=check_attachments params="" retval=CONT msg="" 2025-04-05T17:31:00.598Z [DEBUG] [core] running data_post hook in clamd plugin 2025-04-05T17:31:00.598Z [DEBUG] [core] hook=data_post plugin=clamd function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:31:00.598Z [DEBUG] [core] running data_post hook in strict_from plugin 2025-04-05T17:31:00.599Z [DEBUG] [core] hook=data_post plugin=strict_from function=from_check params="" retval=CONT msg="" 2025-04-05T17:31:00.600Z [DEBUG] [core] running data_post hook in inspection plugin 2025-04-05T17:31:00.601Z [DEBUG] [core] hook=data_post plugin=inspection function=hook_data_post params="" retval=CONT msg="" 2025-04-05T17:31:00.601Z [DEBUG] [core] running data_post hook in geoip plugin 2025-04-05T17:31:00.601Z [DEBUG] [core] hook=data_post plugin=geoip function=add_headers params="" retval=CONT msg="" 2025-04-05T17:31:00.601Z [NOTICE] [core] message mid= size=207 rcpts=1/0/0 delay=0.006 code=CONT msg="" 2025-04-05T17:31:00.601Z [DEBUG] [core] running queue_outbound hooks 2025-04-05T17:31:00.602Z [DEBUG] [core] running queue_outbound hook in outbound_parameters plugin 2025-04-05T17:31:00.607Z [DEBUG] [core] hook=queue_outbound plugin=outbound_parameters function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T17:31:00.607Z [DEBUG] [core] running queue_outbound hook in discard plugin 2025-04-05T17:31:00.607Z [DEBUG] [core] hook=queue_outbound plugin=discard function=discard params="" retval=CONT msg="" 2025-04-05T17:31:00.607Z [DEBUG] [core] running queue_outbound hook in privacy plugin 2025-04-05T17:31:00.610Z [DEBUG] [privacy] hiding IP from header Received: "from localhost (ip6-localhost [127.0.0.1])\r\n\tby bgmcd.org (Haraka) with ESMTPSA id 68B17687-8788-4D53-8C04-A0CF96B345E4.1\r\n\tenvelope-from \r\n\ttls TLS_AES_256_GCM_SHA384 (authenticated bits=0);\r\n\tSat, 05 Apr 2025 17:31:00 +0000\n" 2025-04-05T17:31:00.610Z [DEBUG] [core] hook=queue_outbound plugin=privacy function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T17:31:00.610Z [DEBUG] [core] running queue_outbound hook in mailauth/dkim_sign plugin 2025-04-05T17:31:00.611Z [DEBUG] [mailauth/dkim_sign] using first From header sender, using domain: bgmcd.org, original: bgmcd.org 2025-04-05T17:31:00.618Z [ERROR] [mailauth/dkim_sign] error: Error: ENOENT: no such file or directory, stat '/opt/haraka-submission/config/dkim/bgmcd.org' 2025-04-05T17:31:00.618Z [DEBUG] [mailauth/dkim_sign] domain: bgmcd.org, selector: undefined, private_key: undefined 2025-04-05T17:31:00.618Z [ERROR] [mailauth/dkim_sign] missing domain, selector or private_key 2025-04-05T17:31:00.618Z [DEBUG] [core] hook=queue_outbound plugin=mailauth/dkim_sign function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T17:31:00.618Z [DEBUG] [core] running queue_outbound hook in mailauth/arc plugin 2025-04-05T17:31:00.618Z [DEBUG] [core] hook=queue_outbound plugin=mailauth/arc function=hook_queue_outbound params="" retval=CONT msg="" 2025-04-05T17:31:00.618Z [NOTICE] [core] queue code=CONT msg="Message Queued (68B17687-8788-4D53-8C04-A0CF96B345E4.1)" 2025-04-05T17:31:00.619Z [DEBUG] [mailauth/dkim_sign] using first From header sender, using domain: bgmcd.org, original: bgmcd.org 2025-04-05T17:31:00.625Z [DEBUG] [core] running queue_ok hooks 2025-04-05T17:31:00.625Z [DEBUG] [core] running queue_ok hook in known-senders plugin 2025-04-05T17:31:00.628Z [INFO] [known-senders] saved bgmcd.org : gmail.com : 1 2025-04-05T17:31:00.628Z [DEBUG] [core] hook=queue_ok plugin=known-senders function=update_sender params="Message Queued (68B17687-8788-4D53-8C04-A0CF96B345E4.1)" retval=CONT msg="" 2025-04-05T17:31:00.628Z [DEBUG] [core] running queue_ok hook in stats plugin 2025-04-05T17:31:00.638Z [DEBUG] [core] hook=queue_ok plugin=stats function=hook_queue_ok params="Message Queued (68B17687-8788-4D53-8C04-A0CF96B345E4.1)" retval=CONT msg="" 2025-04-05T17:31:00.638Z [DEBUG] [core] running queue_ok hook in limits plugin 2025-04-05T17:31:00.638Z [DEBUG] [limits] increasing counters ["bea@bgmcd.org","@bgmcd.org"]: 1 2025-04-05T17:31:00.640Z [DEBUG] [core] hook=queue_ok plugin=limits function=hook_queue_ok params="Message Queued (68B17687-8788-4D53-8C04-A0CF96B345E4.1)" retval=CONT msg="" 2025-04-05T17:31:00.640Z [DEBUG] [core] running queue_ok hook in watch plugin 2025-04-05T17:31:00.640Z [DEBUG] [core] hook=queue_ok plugin=watch function=queue_ok params="Message Queued (68B17687-8788-4D53-8C04-A0CF96B345E4.1)" retval=CONT msg="" 2025-04-05T17:31:00.640Z [NOTICE] [core] queue code=OK msg="Message Queued (68B17687-8788-4D53-8C04-A0CF96B345E4.1)" 2025-04-05T17:31:00.640Z [PROTOCOL] [core] S: 250 Message Queued (68B17687-8788-4D53-8C04-A0CF96B345E4.1) 2025-04-05T17:31:00.640Z [DEBUG] [core] running reset_transaction hooks 2025-04-05T17:31:00.640Z [DEBUG] [core] running reset_transaction hook in stats plugin 2025-04-05T17:31:00.647Z [DEBUG] [core] hook=reset_transaction plugin=stats function=hook_reset_transaction params="" retval=CONT msg="" 2025-04-05T17:31:00.648Z [DEBUG] [core] running reset_transaction hook in log plugin 2025-04-05T17:31:00.648Z [DEBUG] [core] hook=reset_transaction plugin=log function=hook_reset_transaction params="" retval=CONT msg="" 2025-04-05T17:31:00.651Z [PROTOCOL] [core] C: QUIT state=1 2025-04-05T17:31:00.651Z [DEBUG] [core] running quit hooks 2025-04-05T17:31:00.651Z [PROTOCOL] [core] S: 221 bgmcd.org closing connection. Have a jolly good day. 2025-04-05T17:31:00.652Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.652Z [DEBUG] [core] running disconnect hooks 2025-04-05T17:31:00.652Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.652Z [DEBUG] [core] running disconnect hook in stats plugin 2025-04-05T17:31:00.656Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.656Z [DEBUG] [core] hook=disconnect plugin=stats function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T17:31:00.656Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.656Z [DEBUG] [core] running disconnect hook in block_bad_connections plugin 2025-04-05T17:31:00.656Z [DEBUG] [core] Authenticated, skipping... 2025-04-05T17:31:00.656Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.656Z [DEBUG] [core] hook=disconnect plugin=block_bad_connections function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T17:31:00.656Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.656Z [DEBUG] [core] running disconnect hook in log plugin 2025-04-05T17:31:00.656Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.656Z [DEBUG] [core] hook=disconnect plugin=log function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T17:31:00.656Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.656Z [DEBUG] [core] running disconnect hook in tls plugin 2025-04-05T17:31:00.657Z [DEBUG] [core] client has disconnected 2025-04-05T17:31:00.657Z [DEBUG] [core] hook=disconnect plugin=tls function=hook_disconnect params="" retval=CONT msg="" 2025-04-05T17:31:00.657Z [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=207 lr="" time=0.262