2025-04-05T18:02:33.619Z [NOTICE] [core] connect ip=::1 port=53558 local_ip=::1 local_port=25
2025-04-05T18:02:33.621Z [DEBUG] [core] running connect_init hooks
2025-04-05T18:02:33.622Z [DEBUG] [core] running connect_init hook in guard plugin
2025-04-05T18:02:33.643Z [DEBUG] [core] hook=connect_init plugin=guard function=hook_connect_init params="" retval=CONT msg=""
2025-04-05T18:02:33.644Z [DEBUG] [core] running connect_init hook in karma plugin
2025-04-05T18:02:33.645Z [DEBUG] [karma] skipping
2025-04-05T18:02:33.645Z [DEBUG] [core] hook=connect_init plugin=karma function=results_init params="" retval=CONT msg=""
2025-04-05T18:02:33.645Z [DEBUG] [core] running connect_init hook in karma plugin
2025-04-05T18:02:33.645Z [DEBUG] [core] hook=connect_init plugin=karma function=ip_history_from_redis params="" retval=CONT msg=""
2025-04-05T18:02:33.646Z [DEBUG] [core] running connect_init hook in early_talker plugin
2025-04-05T18:02:33.648Z [DEBUG] [core] hook=connect_init plugin=early_talker function=early_talker params="" retval=CONT msg=""
2025-04-05T18:02:33.648Z [DEBUG] [core] running connect_init hook in fcrdns plugin
2025-04-05T18:02:33.648Z [DEBUG] [core] hook=connect_init plugin=fcrdns function=initialize_fcrdns params="" retval=CONT msg=""
2025-04-05T18:02:33.649Z [DEBUG] [core] running connect_init hook in relay plugin
2025-04-05T18:02:33.649Z [DEBUG] [relay] checking ::1 in relay_acl_allow
2025-04-05T18:02:33.649Z [DEBUG] [relay] checking if ::1 is in 127.0.0.1/24
2025-04-05T18:02:33.649Z [DEBUG] [relay] checking if ::1 is in 172.17.0.4/32
2025-04-05T18:02:33.650Z [DEBUG] [core] hook=connect_init plugin=relay function=acl params="" retval=CONT msg=""
2025-04-05T18:02:33.650Z [DEBUG] [core] running connect_init_respond
2025-04-05T18:02:33.650Z [DEBUG] [core] running lookup_rdns hooks
2025-04-05T18:02:33.650Z [DEBUG] [core] running lookup_rdns hook in p0f plugin
2025-04-05T18:02:33.650Z [DEBUG] [core] hook=lookup_rdns plugin=p0f function=query_p0f params="" retval=CONT msg=""
2025-04-05T18:02:33.650Z [DEBUG] [core] running lookup_rdns hook in fcrdns plugin
2025-04-05T18:02:33.651Z [DEBUG] [core] hook=lookup_rdns plugin=fcrdns function=do_dns_lookups params="" retval=CONT msg=""
2025-04-05T18:02:33.651Z [DEBUG] [core] running lookup_rdns hook in uribl plugin
2025-04-05T18:02:33.655Z [DEBUG] [uribl] (rdns) found 2 items for lookup
2025-04-05T18:02:33.655Z [DEBUG] [uribl] (rdns) checking: ip6-loopback
2025-04-05T18:02:33.655Z [DEBUG] [uribl] (rdns) checking: ip6-localhost
2025-04-05T18:02:33.658Z [DEBUG] [core] hook=lookup_rdns plugin=uribl function=lookup_remote_ip params="" retval=CONT msg=""
2025-04-05T18:02:33.659Z [DEBUG] [core] running lookup_rdns hook in asn plugin
2025-04-05T18:02:33.660Z [DEBUG] [core] hook=lookup_rdns plugin=asn function=lookup_via_dns params="" retval=CONT msg=""
2025-04-05T18:02:33.660Z [DEBUG] [core] running connect hooks
2025-04-05T18:02:33.661Z [DEBUG] [core] running connect hook in guard plugin
2025-04-05T18:02:33.661Z [DEBUG] [core] hook=connect plugin=guard function=hook_connect params="" retval=CONT msg=""
2025-04-05T18:02:33.661Z [DEBUG] [core] running connect hook in karma plugin
2025-04-05T18:02:33.661Z [DEBUG] [core] hook=connect plugin=karma function=hook_connect params="" retval=CONT msg=""
2025-04-05T18:02:33.661Z [DEBUG] [core] running connect hook in dns-list plugin
2025-04-05T18:02:33.662Z [INFO] [dns-list] skip:private: ::1
2025-04-05T18:02:33.662Z [DEBUG] [core] hook=connect plugin=dns-list function=onConnect params="" retval=CONT msg=""
2025-04-05T18:02:33.662Z [DEBUG] [core] running connect hook in relay plugin
2025-04-05T18:02:33.662Z [DEBUG] [core] hook=connect plugin=relay function=pass_relaying params="" retval=CONT msg=""
2025-04-05T18:02:33.662Z [DEBUG] [core] running connect hook in geoip plugin
2025-04-05T18:02:33.663Z [DEBUG] [core] hook=connect plugin=geoip function=lookup_maxmind params="" retval=CONT msg=""
2025-04-05T18:02:33.666Z [PROTOCOL] [core] S: 220 bgmcd.org ESMTP Haraka ready
2025-04-05T18:02:33.671Z [PROTOCOL] [core] C: EHLO [127.0.0.1] state=1
2025-04-05T18:02:33.674Z [DEBUG] [core] running ehlo hooks
2025-04-05T18:02:33.674Z [DEBUG] [core] running ehlo hook in hello_block plugin
2025-04-05T18:02:33.675Z [DEBUG] [core] hook=ehlo plugin=hello_block function=check_ymlf params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.675Z [DEBUG] [core] running ehlo hook in karma plugin
2025-04-05T18:02:33.675Z [DEBUG] [core] hook=ehlo plugin=karma function=hook_ehlo params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.675Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.675Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.675Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.676Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=init params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.676Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.676Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=match_re params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.676Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.676Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=bare_ip params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.676Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.677Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=dynamic params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.677Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.677Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=big_company params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.677Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.678Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=valid_hostname params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.678Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.678Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=rdns_match params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.678Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.679Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=forward_dns params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.679Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.679Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=host_mismatch params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.679Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.680Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=literal_mismatch params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.680Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.680Z [INFO] [helo.checks] helo_host: [127.0.0.1], skip:proto_mismatch(private), match_re(private), bare_ip(private), dynamic(private), big_company(private), valid_hostname(private), rdns_match(private), forward_dns(private), host_mismatch(private), literal_mismatch(private)
2025-04-05T18:02:33.680Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=emit_log params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.680Z [DEBUG] [core] running ehlo hook in mailauth/verify plugin
2025-04-05T18:02:33.680Z [DEBUG] [core] hook=ehlo plugin=mailauth/verify function=mailauth_helo params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.680Z [DEBUG] [core] running ehlo hook in uribl plugin
2025-04-05T18:02:33.689Z [DEBUG] [uribl] (helo) found 1 items for lookup
2025-04-05T18:02:33.691Z [DEBUG] [uribl] (helo) checking: 127.0.0.1
2025-04-05T18:02:33.698Z [DEBUG] [core] hook=ehlo plugin=uribl function=lookup_ehlo params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.699Z [DEBUG] [core] running capabilities hooks
2025-04-05T18:02:33.699Z [DEBUG] [core] running capabilities hook in auth/poste plugin
2025-04-05T18:02:33.699Z [DEBUG] [core] hook=capabilities plugin=auth/poste function=hook_capabilities params="" retval=CONT msg=""
2025-04-05T18:02:33.699Z [DEBUG] [core] running capabilities hook in status_http plugin
2025-04-05T18:02:33.699Z [DEBUG] [core] hook=capabilities plugin=status_http function=hook_capabilities params="" retval=CONT msg=""
2025-04-05T18:02:33.700Z [DEBUG] [core] running capabilities hook in tls plugin
2025-04-05T18:02:33.710Z [DEBUG] [core] hook=capabilities plugin=tls function=advertise_starttls params="" retval=CONT msg=""
2025-04-05T18:02:33.710Z [PROTOCOL] [core] S: 250-bgmcd.org Hello ip6-localhost [::1], , Poste.io is at your service
2025-04-05T18:02:33.711Z [PROTOCOL] [core] S: 250-PIPELINING
2025-04-05T18:02:33.711Z [PROTOCOL] [core] S: 250-8BITMIME
2025-04-05T18:02:33.711Z [PROTOCOL] [core] S: 250-SMTPUTF8
2025-04-05T18:02:33.711Z [PROTOCOL] [core] S: 250-SIZE 26214400
2025-04-05T18:02:33.711Z [PROTOCOL] [core] S: 250-STATUS
2025-04-05T18:02:33.711Z [PROTOCOL] [core] S: 250 STARTTLS
2025-04-05T18:02:33.716Z [PROTOCOL] [core] C: STARTTLS state=1
2025-04-05T18:02:33.717Z [DEBUG] [core] running unrecognized_command hooks
2025-04-05T18:02:33.717Z [DEBUG] [core] running unrecognized_command hook in auth/poste plugin
2025-04-05T18:02:33.717Z [DEBUG] [core] hook=unrecognized_command plugin=auth/poste function=hook_unrecognized_command params=STARTTLS retval=CONT msg=""
2025-04-05T18:02:33.717Z [DEBUG] [core] running unrecognized_command hook in status_http plugin
2025-04-05T18:02:33.718Z [DEBUG] [core] hook=unrecognized_command plugin=status_http function=hook_unrecognized_command params=STARTTLS retval=CONT msg=""
2025-04-05T18:02:33.718Z [DEBUG] [core] running unrecognized_command hook in karma plugin
2025-04-05T18:02:33.718Z [DEBUG] [core] hook=unrecognized_command plugin=karma function=hook_unrecognized_command params=STARTTLS retval=CONT msg=""
2025-04-05T18:02:33.718Z [DEBUG] [core] running unrecognized_command hook in tls plugin
2025-04-05T18:02:33.718Z [PROTOCOL] [core] S: 220 Go ahead.
2025-04-05T18:02:33.767Z [INFO] [tls] secured: cipher=TLS_AES_256_GCM_SHA384 version=TLSv1.3 verified=false
2025-04-05T18:02:33.767Z [INFO] [core] hook=unrecognized_command plugin=tls function=upgrade_connection params=STARTTLS retval=OK msg=""
2025-04-05T18:02:33.769Z [PROTOCOL] [core] C: EHLO [127.0.0.1] state=1
2025-04-05T18:02:33.769Z [DEBUG] [core] running ehlo hooks
2025-04-05T18:02:33.769Z [DEBUG] [core] running ehlo hook in hello_block plugin
2025-04-05T18:02:33.769Z [DEBUG] [core] hook=ehlo plugin=hello_block function=check_ymlf params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.769Z [DEBUG] [core] running ehlo hook in karma plugin
2025-04-05T18:02:33.769Z [DEBUG] [core] hook=ehlo plugin=karma function=hook_ehlo params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.769Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.770Z [DEBUG] [helo.checks] SKIPPING: proto_mismatch
2025-04-05T18:02:33.770Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.770Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.770Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=init params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.770Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.770Z [DEBUG] [helo.checks] SKIPPING: match_re
2025-04-05T18:02:33.770Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=match_re params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.770Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.770Z [DEBUG] [helo.checks] SKIPPING: bare_ip
2025-04-05T18:02:33.770Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=bare_ip params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.771Z [DEBUG] [helo.checks] SKIPPING: dynamic
2025-04-05T18:02:33.771Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=dynamic params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.771Z [DEBUG] [helo.checks] SKIPPING: big_company
2025-04-05T18:02:33.771Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=big_company params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.771Z [DEBUG] [helo.checks] SKIPPING: valid_hostname
2025-04-05T18:02:33.771Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=valid_hostname params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.771Z [DEBUG] [helo.checks] SKIPPING: rdns_match
2025-04-05T18:02:33.771Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=rdns_match params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.771Z [DEBUG] [helo.checks] SKIPPING: forward_dns
2025-04-05T18:02:33.771Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=forward_dns params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.771Z [DEBUG] [helo.checks] SKIPPING: host_mismatch
2025-04-05T18:02:33.771Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=host_mismatch params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.771Z [DEBUG] [helo.checks] SKIPPING: literal_mismatch
2025-04-05T18:02:33.771Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=literal_mismatch params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in helo.checks plugin
2025-04-05T18:02:33.771Z [INFO] [helo.checks] helo_host: [127.0.0.1], skip:proto_mismatch(private), match_re(private), bare_ip(private), dynamic(private), big_company(private), valid_hostname(private), rdns_match(private), forward_dns(private), host_mismatch(private), literal_mismatch(private)
2025-04-05T18:02:33.771Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=emit_log params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.771Z [DEBUG] [core] running ehlo hook in mailauth/verify plugin
2025-04-05T18:02:33.772Z [DEBUG] [core] hook=ehlo plugin=mailauth/verify function=mailauth_helo params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.772Z [DEBUG] [core] running ehlo hook in uribl plugin
2025-04-05T18:02:33.774Z [DEBUG] [uribl] (helo) found 1 items for lookup
2025-04-05T18:02:33.775Z [DEBUG] [uribl] (helo) checking: 127.0.0.1
2025-04-05T18:02:33.775Z [DEBUG] [core] hook=ehlo plugin=uribl function=lookup_ehlo params=[127.0.0.1] retval=CONT msg=""
2025-04-05T18:02:33.775Z [DEBUG] [core] running capabilities hooks
2025-04-05T18:02:33.775Z [DEBUG] [core] running capabilities hook in auth/poste plugin
2025-04-05T18:02:33.775Z [DEBUG] [core] hook=capabilities plugin=auth/poste function=hook_capabilities params="" retval=CONT msg=""
2025-04-05T18:02:33.775Z [DEBUG] [core] running capabilities hook in status_http plugin
2025-04-05T18:02:33.776Z [DEBUG] [core] hook=capabilities plugin=status_http function=hook_capabilities params="" retval=CONT msg=""
2025-04-05T18:02:33.776Z [DEBUG] [core] running capabilities hook in tls plugin
2025-04-05T18:02:33.776Z [DEBUG] [core] hook=capabilities plugin=tls function=advertise_starttls params="" retval=CONT msg=""
2025-04-05T18:02:33.776Z [PROTOCOL] [core] S: 250-bgmcd.org Hello ip6-localhost [::1], , Poste.io is at your service
2025-04-05T18:02:33.776Z [PROTOCOL] [core] S: 250-PIPELINING
2025-04-05T18:02:33.776Z [PROTOCOL] [core] S: 250-8BITMIME
2025-04-05T18:02:33.776Z [PROTOCOL] [core] S: 250-SMTPUTF8
2025-04-05T18:02:33.776Z [PROTOCOL] [core] S: 250-SIZE 26214400
2025-04-05T18:02:33.776Z [PROTOCOL] [core] S: 250-AUTH PLAIN LOGIN
2025-04-05T18:02:33.776Z [PROTOCOL] [core] S: 250 STATUS
2025-04-05T18:02:33.819Z [PROTOCOL] [core] C: MAIL FROM:<postmaster+letsencrypt@bgmcd.org> state=1
2025-04-05T18:02:34.013Z [TX] [core] #2C522607-5B41-409B-B320-B7EE348E6776.1