[Pkg-exim4-users] How to get around "Must issue a STARTTLS command first"

Norbert Preining preining at logic.at
Wed Aug 4 08:33:26 UTC 2010


Hi Marc,
(once more, this time with log file included as list server rejected)

On Mi, 04 Aug 2010, Marc Haber wrote:
> You could try reproducing the issue with gnutls-cli-debug and see
> whether this gives any insight. I'm beginning  to run out of clues;

Insight not much to me:
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298



Full log follows at the end

Best wishes

Norbert
------------------------------------------------------------------------
Norbert Preining            preining@{jaist.ac.jp, logic.at, debian.org}
JAIST, Japan                                 TeX Live & Debian Developer
DSA: 0x09C5B094   fp: 14DF 2E6C 0307 BE6D AD76  A9C0 D2BF 4AA3 09C5 B094
------------------------------------------------------------------------
MOFFAT (n. tailoring term)
That part of your coat which is designed to be sat on by the person
next of you on the bus.
			--- Douglas Adams, The Meaning of Liff



|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_ARCFOUR_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_ARCFOUR_MD5
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_DSS_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_DSS_ARCFOUR_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_RSA_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Removing ciphersuite: ANON_DH_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Removing ciphersuite: ANON_DH_ARCFOUR_MD5
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_EXPORT_ARCFOUR_40_MD5
|<3>| HSK[0xd24f10]: CLIENT HELLO was send [57 bytes]
|<6>| BUF[HSK]: Peeked 0 bytes of Data
|<6>| BUF[HSK]: Emptied buffer
|<4>| REC[0xd24f10]: Sending Packet[0] Handshake(22) with length: 57
|<2>| ASSERT: gnutls_cipher.c:204
|<7>| WRITE: Will write 62 bytes to 0x4.
|<7>| WRITE: wrote 62 bytes to 0x4. Left 0 bytes. Total 62 bytes.
|<7>| 0000 - 16 03 02 00 39 01 00 00 35 03 02 4c 59 13 d1 a4 
|<7>| 0001 - 95 6d 7a 18 96 56 0e 2a 5d 98 07 5f 43 0d 76 47 
|<7>| 0002 - ad 5a 13 ff 94 1b 41 3f 33 b3 80 00 00 0e 00 0a 
|<7>| 0003 - 00 05 00 04 00 13 00 66 00 16 00 03 01 00 
|<4>| REC[0xd24f10]: Sent Packet[1] Handshake(22) with length: 62
|<7>| READ: Got 5 bytes from 0x4
|<7>| READ: read 5 bytes from 0x4
|<7>| 0000 - 32 32 30 20 6a 
|<7>| RB: Have 0 bytes into buffer. Adding 5 bytes.
|<7>| RB: Requested 5 bytes
|<2>| ASSERT: gnutls_record.c:507
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298
|<7>| READ: Got 37 bytes from 0x4
|<7>| READ: Got 0 bytes from 0x4
|<7>| READ: read 37 bytes from 0x4
|<7>| 0000 - 61 69 73 74 2e 61 63 2e 6a 70 20 45 53 4d 54 50 
|<7>| 0001 - 20 6d 61 69 6c 20 73 65 72 76 69 63 65 20 72 65 
|<7>| 0002 - 61 64 79 0d 0a 
|<7>| RB: Have 5 bytes into buffer. Adding 37 bytes.
|<7>| RB: Requested 8303 bytes
|<2>| ASSERT: gnutls_buffers.c:608
|<2>| ASSERT: gnutls_buffers.c:1032
|<2>| ASSERT: gnutls_handshake.c:1045
|<2>| ASSERT: gnutls_record.c:507
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298
|<7>| READ: Got 0 bytes from 0x4
|<7>| READ: read 0 bytes from 0x4
|<7>| 0000 - 
|<2>| ASSERT: gnutls_buffers.c:599
|<2>| ASSERT: gnutls_record.c:976
|<2>| ASSERT: gnutls_buffers.c:1032
|<2>| ASSERT: gnutls_handshake.c:1045
|<2>| ASSERT: gnutls_handshake.c:2364
|<6>| BUF[HSK]: Cleared Data from buffer
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_ARCFOUR_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_ARCFOUR_MD5
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_DSS_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_DSS_ARCFOUR_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_RSA_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Removing ciphersuite: ANON_DH_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Removing ciphersuite: ANON_DH_ARCFOUR_MD5
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_EXPORT_ARCFOUR_40_MD5
|<3>| HSK[0xd24f10]: CLIENT HELLO was send [57 bytes]
|<6>| BUF[HSK]: Peeked 0 bytes of Data
|<6>| BUF[HSK]: Emptied buffer
|<4>| REC[0xd24f10]: Sending Packet[0] Handshake(22) with length: 57
|<2>| ASSERT: gnutls_cipher.c:204
|<7>| WRITE: Will write 62 bytes to 0x4.
|<7>| WRITE: wrote 62 bytes to 0x4. Left 0 bytes. Total 62 bytes.
|<7>| 0000 - 16 03 02 00 39 01 00 00 35 03 02 4c 59 13 d1 c9 
|<7>| 0001 - 45 c1 89 82 d9 63 11 cf 58 db 77 6e e6 68 47 26 
|<7>| 0002 - 25 cf f2 87 bf f2 8b 83 f9 6b f3 00 00 0e 00 0a 
|<7>| 0003 - 00 05 00 04 00 13 00 66 00 16 00 03 01 00 
|<4>| REC[0xd24f10]: Sent Packet[1] Handshake(22) with length: 62
|<7>| READ: Got 5 bytes from 0x4
|<7>| READ: read 5 bytes from 0x4
|<7>| 0000 - 32 32 30 20 6a 
|<7>| RB: Have 0 bytes into buffer. Adding 5 bytes.
|<7>| RB: Requested 5 bytes
|<2>| ASSERT: gnutls_record.c:507
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298
|<7>| READ: Got 37 bytes from 0x4
|<7>| READ: Got 0 bytes from 0x4
|<7>| READ: read 37 bytes from 0x4
|<7>| 0000 - 61 69 73 74 2e 61 63 2e 6a 70 20 45 53 4d 54 50 
|<7>| 0001 - 20 6d 61 69 6c 20 73 65 72 76 69 63 65 20 72 65 
|<7>| 0002 - 61 64 79 0d 0a 
|<7>| RB: Have 5 bytes into buffer. Adding 37 bytes.
|<7>| RB: Requested 8303 bytes
|<2>| ASSERT: gnutls_buffers.c:608
|<2>| ASSERT: gnutls_buffers.c:1032
|<2>| ASSERT: gnutls_handshake.c:1045
|<2>| ASSERT: gnutls_record.c:507
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298
|<7>| READ: Got 0 bytes from 0x4
|<7>| READ: read 0 bytes from 0x4
|<7>| 0000 - 
|<2>| ASSERT: gnutls_buffers.c:599
|<2>| ASSERT: gnutls_record.c:976
|<2>| ASSERT: gnutls_buffers.c:1032
|<2>| ASSERT: gnutls_handshake.c:1045
|<2>| ASSERT: gnutls_handshake.c:2364
|<6>| BUF[HSK]: Cleared Data from buffer
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_ARCFOUR_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_ARCFOUR_MD5
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_DSS_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_DSS_ARCFOUR_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_RSA_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Removing ciphersuite: ANON_DH_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Removing ciphersuite: ANON_DH_ARCFOUR_MD5
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_EXPORT_ARCFOUR_40_MD5
|<3>| HSK[0xd24f10]: CLIENT HELLO was send [57 bytes]
|<6>| BUF[HSK]: Peeked 0 bytes of Data
|<6>| BUF[HSK]: Emptied buffer
|<4>| REC[0xd24f10]: Sending Packet[0] Handshake(22) with length: 57
|<2>| ASSERT: gnutls_cipher.c:204
|<7>| WRITE: Will write 62 bytes to 0x4.
|<7>| WRITE: wrote 62 bytes to 0x4. Left 0 bytes. Total 62 bytes.
|<7>| 0000 - 16 03 01 00 39 01 00 00 35 03 01 4c 59 13 d1 df 
|<7>| 0001 - 6a 7f 40 60 b7 33 02 b0 ce 7a 39 96 95 74 80 82 
|<7>| 0002 - b4 c4 97 b0 7f d8 89 19 f3 a4 b1 00 00 0e 00 0a 
|<7>| 0003 - 00 05 00 04 00 13 00 66 00 16 00 03 01 00 
|<4>| REC[0xd24f10]: Sent Packet[1] Handshake(22) with length: 62
|<7>| READ: Got 5 bytes from 0x4
|<7>| READ: read 5 bytes from 0x4
|<7>| 0000 - 32 32 30 20 6a 
|<7>| RB: Have 0 bytes into buffer. Adding 5 bytes.
|<7>| RB: Requested 5 bytes
|<2>| ASSERT: gnutls_record.c:507
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298
|<7>| READ: Got 37 bytes from 0x4
|<7>| READ: Got 0 bytes from 0x4
|<7>| READ: read 37 bytes from 0x4
|<7>| 0000 - 61 69 73 74 2e 61 63 2e 6a 70 20 45 53 4d 54 50 
|<7>| 0001 - 20 6d 61 69 6c 20 73 65 72 76 69 63 65 20 72 65 
|<7>| 0002 - 61 64 79 0d 0a 
|<7>| RB: Have 5 bytes into buffer. Adding 37 bytes.
|<7>| RB: Requested 8303 bytes
|<2>| ASSERT: gnutls_buffers.c:608
|<2>| ASSERT: gnutls_buffers.c:1032
|<2>| ASSERT: gnutls_handshake.c:1045
|<2>| ASSERT: gnutls_record.c:507
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298
|<7>| READ: Got 0 bytes from 0x4
|<7>| READ: read 0 bytes from 0x4
|<7>| 0000 - 
|<2>| ASSERT: gnutls_buffers.c:599
|<2>| ASSERT: gnutls_record.c:976
|<2>| ASSERT: gnutls_buffers.c:1032
|<2>| ASSERT: gnutls_handshake.c:1045
|<2>| ASSERT: gnutls_handshake.c:2364
|<6>| BUF[HSK]: Cleared Data from buffer
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_ARCFOUR_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_ARCFOUR_MD5
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_DSS_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Keeping ciphersuite: DHE_RSA_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Removing ciphersuite: ANON_DH_3DES_EDE_CBC_SHA1
|<3>| HSK[0xd24f10]: Removing ciphersuite: ANON_DH_ARCFOUR_MD5
|<3>| HSK[0xd24f10]: Keeping ciphersuite: RSA_EXPORT_ARCFOUR_40_MD5
|<3>| HSK[0xd24f10]: CLIENT HELLO was send [55 bytes]
|<6>| BUF[HSK]: Peeked 0 bytes of Data
|<6>| BUF[HSK]: Emptied buffer
|<4>| REC[0xd24f10]: Sending Packet[0] Handshake(22) with length: 55
|<2>| ASSERT: gnutls_cipher.c:204
|<7>| WRITE: Will write 60 bytes to 0x4.
|<7>| WRITE: wrote 60 bytes to 0x4. Left 0 bytes. Total 60 bytes.
|<7>| 0000 - 16 03 00 00 37 01 00 00 33 03 00 4c 59 13 d1 01 
|<7>| 0001 - af ea a7 3a 9c 6b c0 ea 06 bb f8 c2 91 91 28 61 
|<7>| 0002 - 0d a2 3a d9 9c 20 87 64 93 3a 58 00 00 0c 00 0a 
|<7>| 0003 - 00 05 00 04 00 13 00 16 00 03 01 00 
|<4>| REC[0xd24f10]: Sent Packet[1] Handshake(22) with length: 60
|<7>| READ: Got 5 bytes from 0x4
|<7>| READ: read 5 bytes from 0x4
|<7>| 0000 - 32 32 30 20 6a 
|<7>| RB: Have 0 bytes into buffer. Adding 5 bytes.
|<7>| RB: Requested 5 bytes
|<2>| ASSERT: gnutls_record.c:507
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298
|<7>| READ: Got 37 bytes from 0x4
|<7>| READ: Got 0 bytes from 0x4
|<7>| READ: read 37 bytes from 0x4
|<7>| 0000 - 61 69 73 74 2e 61 63 2e 6a 70 20 45 53 4d 54 50 
|<7>| 0001 - 20 6d 61 69 6c 20 73 65 72 76 69 63 65 20 72 65 
|<7>| 0002 - 61 64 79 0d 0a 
|<7>| RB: Have 5 bytes into buffer. Adding 37 bytes.
|<7>| RB: Requested 8303 bytes
|<2>| ASSERT: gnutls_buffers.c:608
|<2>| ASSERT: gnutls_buffers.c:1032
|<2>| ASSERT: gnutls_handshake.c:1045
|<2>| ASSERT: gnutls_record.c:507
|<4>| REC[0xd24f10]: Expected Packet[0] Handshake(22) with length: 1
|<4>| REC[0xd24f10]: Received Packet[0] Unknown Packet(50) with length: 8298
|<7>| READ: Got 0 bytes from 0x4
|<7>| READ: read 0 bytes from 0x4
|<7>| 0000 - 
|<2>| ASSERT: gnutls_buffers.c:599
|<2>| ASSERT: gnutls_record.c:976
|<2>| ASSERT: gnutls_buffers.c:1032
|<2>| ASSERT: gnutls_handshake.c:1045
|<2>| ASSERT: gnutls_handshake.c:2364
|<6>| BUF[HSK]: Cleared Data from buffer

Server does not support any of SSL 3.0, TLS 1.0 and TLS 1.1
Resolving 'smtp.jaist.ac.jp'...
Connecting to '150.65.19.12:587'...
Checking for TLS 1.1 support... no
Checking fallback from TLS 1.1 to... failed
Checking for TLS 1.0 support... no
Checking for SSL 3.0 support... no



More information about the Pkg-exim4-users mailing list