Still can't duplicate locally.
The first conn goes cmdlog: '220:EHLO:250-:STARTTLS:220:EHLO:250-:MAIL|:RCPT|:BDAT:QUIT:250:451:503-:221'
The second: 22:48:10 25726 will pipeline QUIT 22:48:10 25726 dkim signing direct-mode 22:48:10 25726 DKIM >> Body data for hash, canonicalized >>>>>>>>>>>>>>>>>>>>>>>>>>>> 22:48:10 25726 this{SP}is{SP}a{SP}test{CR}{LF} 22:48:10 25726 DKIM <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 22:48:10 25726 DKIM: finish bodyhash sha256/simple/-1 len 16 22:48:10 25726 DKIM: no signatures 22:48:10 25726 DKIM: no signatures to use 22:48:10 25726 ARC: sign for test.ex 22:48:10 25726 LOG: MAIN 22:48:10 25726 ARC: no Authentication-Results header for signing 22:48:10 25726 SMTP+> BDAT 332 LAST 22:48:10 25726 cmd buf flush 86 bytes (more expected) 22:48:10 25726 gnutls_record_cork(session=0x5590af3a31a0) 22:48:10 25726 tls_write(0x5590af989bb8, 86, more) 22:48:10 25726 gnutls_record_send(session=0x5590af3a31a0, buffer=0x5590af989bb8, left=86) 22:48:10 25726 outbytes=86 22:48:10 25726 cannot use sendfile for body: spoolfile not wireformat 22:48:10 25726 writing data block fd=6 size=332 timeout=300 (more expected) 22:48:10 25726 tls_write(0x5590af38a048, 332, more) 22:48:10 25726 gnutls_record_send(session=0x5590af3a31a0, buffer=0x5590af38a048, left=332) 22:48:10 25726 outbytes=332 22:48:10 25726 SMTP>> QUIT 22:48:10 25726 cmd buf flush 6 bytes 22:48:10 25726 tls_write(0x5590af989bb8, 6) 22:48:10 25726 gnutls_record_send(session=0x5590af3a31a0, buffer=0x5590af989bb8, left=6) 22:48:10 25726 outbytes=6 22:48:10 25726 gnutls_record_uncork(session=0x5590af3a31a0) 22:48:10 25726 GnuTLS<2>: FIPS140-2 context is not set 22:48:10 25726 sync_responses expect mail 22:48:10 25726 Calling gnutls_record_recv(session=0x5590af3a31a0, buffer=0x5590af988bb8, len=4096) 22:48:10 25726 GnuTLS<2>: FIPS140-2 context is not set 22:48:10 25726 read response data: size=114 22:48:10 25726 SMTP<< 250 OK 22:48:10 25726 sync_responses expect rcpt for <email address hidden> 22:48:10 25726 SMTP<< 250 Accepted 22:48:10 25726 SMTP<< 250- 332 byte chunk, total 332 22:48:10 25726 250 OK id=1npzcQ-0006h2-32 22:48:10 25726 S:journalling <email address hidden> 22:48:10 25726 ok=1 send_quit=0 send_rset=0 continue_more=0 yield=0 first_address is NULL 22:48:10 25726 SMTP<< 221 test.ex closing connection 22:48:10 25726 Calling gnutls_record_recv(session=0x5590af3a31a0, buffer=0x5590af988bb8, len=4096) 22:48:10 25726 GnuTLS<2>: FIPS140-2 context is not set 22:48:10 25726 GnuTLS<3>: ASSERT: record.c[_gnutls_recv_in_buffers]:1589 22:48:10 25726 Got TLS_EOF 22:48:10 25726 tls_close(): shutting down TLS (with response-wait) 22:48:10 25726 tls_write((nil), 0) 22:48:10 25726 GnuTLS<3>: ASSERT: buffers.c[_gnutls_io_write_flush]:696 22:48:10 25726 GnuTLS<2>: FIPS140-2 context is not set 22:48:10 25726 SMTP(close)>> 22:48:10 25726 cmdlog: '220:EHLO:250-:STARTTLS:220:EHLO:250-:MAIL|:RCPT|:BDAT:QUIT:250:250:250-:221' 22:48:10 25726 set_process_info: 25726 delivering 1npzcP-0006gi-0q: just tried 127.0.0.1 [127.0.0.1]:1225 for <email address hidden>: result OK 22:48:10 25726 Leaving gsmtp transport
Still can't duplicate locally.
The first conn goes 250-:STARTTLS: 220:EHLO: 250-:MAIL| :RCPT|: BDAT:QUIT: 250:451: 503-:221'
cmdlog: '220:EHLO:
The second: >>>>>>> >>>>>>> >>>>>>> is{SP}a{ SP}test{ CR}{LF} <<<<<<< <<<<<<< <<<<<<< <<<<<<< <<<<<<< <<<<<<< <<<<<<< <<<<<<< < Results header for signing record_ cork(session= 0x5590af3a31a0) 0x5590af989bb8, 86, more) record_ send(session= 0x5590af3a31a0, buffer= 0x5590af989bb8, left=86) 0x5590af38a048, 332, more) record_ send(session= 0x5590af3a31a0, buffer= 0x5590af38a048, left=332) 0x5590af989bb8, 6) record_ send(session= 0x5590af3a31a0, buffer= 0x5590af989bb8, left=6) record_ uncork( session= 0x5590af3a31a0) record_ recv(session= 0x5590af3a31a0, buffer= 0x5590af988bb8, len=4096) record_ recv(session= 0x5590af3a31a0, buffer= 0x5590af988bb8, len=4096) c[_gnutls_ recv_in_ buffers] :1589 c[_gnutls_ io_write_ flush]: 696 250-:STARTTLS: 220:EHLO: 250-:MAIL| :RCPT|: BDAT:QUIT: 250:250: 250-:221'
22:48:10 25726 will pipeline QUIT
22:48:10 25726 dkim signing direct-mode
22:48:10 25726 DKIM >> Body data for hash, canonicalized >>>>>>>
22:48:10 25726 this{SP}
22:48:10 25726 DKIM <<<<<<<
22:48:10 25726 DKIM: finish bodyhash sha256/simple/-1 len 16
22:48:10 25726 DKIM: no signatures
22:48:10 25726 DKIM: no signatures to use
22:48:10 25726 ARC: sign for test.ex
22:48:10 25726 LOG: MAIN
22:48:10 25726 ARC: no Authentication-
22:48:10 25726 SMTP+> BDAT 332 LAST
22:48:10 25726 cmd buf flush 86 bytes (more expected)
22:48:10 25726 gnutls_
22:48:10 25726 tls_write(
22:48:10 25726 gnutls_
22:48:10 25726 outbytes=86
22:48:10 25726 cannot use sendfile for body: spoolfile not wireformat
22:48:10 25726 writing data block fd=6 size=332 timeout=300 (more expected)
22:48:10 25726 tls_write(
22:48:10 25726 gnutls_
22:48:10 25726 outbytes=332
22:48:10 25726 SMTP>> QUIT
22:48:10 25726 cmd buf flush 6 bytes
22:48:10 25726 tls_write(
22:48:10 25726 gnutls_
22:48:10 25726 outbytes=6
22:48:10 25726 gnutls_
22:48:10 25726 GnuTLS<2>: FIPS140-2 context is not set
22:48:10 25726 sync_responses expect mail
22:48:10 25726 Calling gnutls_
22:48:10 25726 GnuTLS<2>: FIPS140-2 context is not set
22:48:10 25726 read response data: size=114
22:48:10 25726 SMTP<< 250 OK
22:48:10 25726 sync_responses expect rcpt for <email address hidden>
22:48:10 25726 SMTP<< 250 Accepted
22:48:10 25726 SMTP<< 250- 332 byte chunk, total 332
22:48:10 25726 250 OK id=1npzcQ-0006h2-32
22:48:10 25726 S:journalling <email address hidden>
22:48:10 25726 ok=1 send_quit=0 send_rset=0 continue_more=0 yield=0 first_address is NULL
22:48:10 25726 SMTP<< 221 test.ex closing connection
22:48:10 25726 Calling gnutls_
22:48:10 25726 GnuTLS<2>: FIPS140-2 context is not set
22:48:10 25726 GnuTLS<3>: ASSERT: record.
22:48:10 25726 Got TLS_EOF
22:48:10 25726 tls_close(): shutting down TLS (with response-wait)
22:48:10 25726 tls_write((nil), 0)
22:48:10 25726 GnuTLS<3>: ASSERT: buffers.
22:48:10 25726 GnuTLS<2>: FIPS140-2 context is not set
22:48:10 25726 SMTP(close)>>
22:48:10 25726 cmdlog: '220:EHLO:
22:48:10 25726 set_process_info: 25726 delivering 1npzcP-0006gi-0q: just tried 127.0.0.1 [127.0.0.1]:1225 for <email address hidden>: result OK
22:48:10 25726 Leaving gsmtp transport