• Re: BinkP SSL

    From Avon@21:1/101 to alter ego on Mon Mar 2 12:22:08 2020
    On 01 Mar 2020 at 10:37p, alter ego pondered and said...

    FYI, I update Hub 3 to the latest synchronet, and SSL polled you:

    Connection to agency.bbs.nz:24553 successful
    Negotiating TLS
    ...

    It was a success :)

    I'll setup Hub 3 to receive inbound TLS - and send the details.

    Sounds good thanks. I hope to be able to test this is around 7 hours time
    when I am home for the evening.

    --- Mystic BBS v1.12 A46 2020/02/29 (Windows/32)
    * Origin: Agency BBS | Dunedin, New Zealand | agency.bbs.nz (21:1/101)
  • From Avon@21:1/101 to alter ego on Mon Mar 2 12:22:59 2020
    On 01 Mar 2020 at 10:53p, alter ego pondered and said...

    I'll setup Hub 3 to receive inbound TLS - and send the details.

    OK, hub 3 is on alterant.leenooks.net:24556 (synchronet windows), and my BBS 2/116 is on alterant.leenooks.net:24553 (synchronet linux).

    Thanks, will test tonight my time.

    --- Mystic BBS v1.12 A46 2020/02/29 (Windows/32)
    * Origin: Agency BBS | Dunedin, New Zealand | agency.bbs.nz (21:1/101)
  • From Avon@21:1/101 to g00r00 on Wed Mar 4 22:31:29 2020
    Not sure what's up or why but there seems to be some problems at NET 4 accepting incoming SSL connects from NET 1

    Here's what I see at 1/100

    --------------------- POLL v1.12 A46 2020/03/02 Wed, Mar 04 2020 (loglevel 2) + 2020.03.04 21:44:15 Poll BINKP node via address lookup: 21:4/100
    + 2020.03.04 21:44:15 Queued 5 files (3,871,119 bytes) for 21:4/100
    + 2020.03.04 21:44:15 1-Polling 21:4/100 on slot 1 via BINKP
    + 2020.03.04 21:44:15 1-Connecting to bbs.castlerockbbs.com on port 24553
    + 2020.03.04 21:44:17 1-Connected by IPV4 SSL to 184.155.113.241
    + 2020.03.04 21:44:17 1-System fsxHUB Niba [NET4]
    + 2020.03.04 21:44:17 1-SysOp Black Panther
    + 2020.03.04 21:44:17 1-Info TIME Wed, 04 Mar 2020 01:44:29 -0700
    + 2020.03.04 21:44:17 1-Mailer Mystic/1.12A46 binkp/1.0
    + 2020.03.04 21:44:17 1-Info BUILD 2020/03/02 15:31:32 Windows/32
    + 2020.03.04 21:44:18 1-Sending: fffd0000.tup (1,938,261 bytes)
    + 2020.03.04 21:44:18 1-Remote Queue: 59 files 9,097,342 bytes
    + 2020.03.04 21:44:18 1-Resuming file at offset 0
    + 2020.03.04 21:44:18 1-Connection lost
    + 2020.03.04 21:44:18 1-Session ended (0 sent, 0 rcvd, 0 skip)
    + 2020.03.04 21:44:20 Polled 1 systems

    Here's what the NET 4 HUB MIS looks like

    + 2020.03.04 01:44:29 BINKP Received connection; Negotiating (for up to 30 seconds)
    + 2020.03.04 01:44:29 BINKP > Connect on slot 1/20 SSL (219.89.83.33)
    + 2020.03.04 01:44:29 BINKP 1-HostName 219-89-83-33.adsl.xtra.co.nz
    + 2020.03.04 01:44:29 BINKP 1-Country New Zealand (NZ)
    + 2020.03.04 01:44:30 BINKP 1-System fsxHUB Risa [NET1]
    + 2020.03.04 01:44:30 BINKP 1-SysOp Avon
    + 2020.03.04 01:44:30 BINKP 1-Info TIME Wed, 04 Mar 2020 21:44:17 +1300
    + 2020.03.04 01:44:30 BINKP 1-Mailer Mystic/1.12A46 binkp/1.0
    + 2020.03.04 01:44:30 BINKP 1-Info BUILD 2020/03/02 15:31:32 Windows/32
    + 2020.03.04 01:44:30 BINKP 1-Authenticating 21:1/100@fsxnet by CRAM-MD5
    + 2020.03.04 01:44:30 BINKP 1-Queued 59 files for 21:1/100@fsxnet
    + 2020.03.04 01:44:30 BINKP 1-Sending: 01ee38f4.pkt (27,266 bytes)
    + 2020.03.04 01:44:31 BINKP 1-Remote Queue: 5 files 3,871,119 bytes
    + 2020.03.04 01:44:31 BINKP 1-Resuming file at offset 16384
    + 2020.03.04 01:44:40 MANAGER Server shutdown received from console
    + 2020.03.04 01:44:40 MANAGER Shutdown: EVENT
    + 2020.03.04 01:44:40 MANAGER Shutdown: BINKP
    + 2020.03.04 01:44:40 MANAGER Waiting for servers to stop (up to 30 seconds)
    + 2020.03.04 01:44:40 EVENT Event system stopped
    + 2020.03.04 01:45:02 BINKP 1-Session timeout
    + 2020.03.04 01:45:02 BINKP 1-Session ended (0 sent, 0 rcvd, 0 skip)
    + 2020.03.04 01:45:02 MANAGER Shutdown complete

    I shut the server down just to grab that log.

    I did also remove the ssl.cert from both HUBs DATA dir and let them auto
    create again in the hope that might be it. But it did not seem to do anything.

    But 1/100 can poll 2/100 fine

    --------------------- POLL v1.12 A46 2020/03/02 Wed, Mar 04 2020 (loglevel 2) + 2020.03.04 21:47:11 Poll BINKP node via address lookup: 21:2/100
    + 2020.03.04 21:47:11 1-Polling 21:2/100 on slot 1 via BINKP
    + 2020.03.04 21:47:11 1-Connecting to error404bbs.ddns.net on port 24553
    + 2020.03.04 21:47:14 1-Connected by IPV4 SSL to 71.74.66.9
    + 2020.03.04 21:47:14 1-System fsxNet [Net2] Tholian
    + 2020.03.04 21:47:14 1-SysOp Todd Zieman - Solaris
    + 2020.03.04 21:47:14 1-Info TIME Wed, 04 Mar 2020 03:47:21 -0500
    + 2020.03.04 21:47:14 1-Mailer Mystic/1.12A46 binkp/1.0
    + 2020.03.04 21:47:14 1-Info BUILD 2020/03/02 15:32:58 Windows/64
    + 2020.03.04 21:47:15 1-Remote Queue: 0 files 0 bytes
    + 2020.03.04 21:47:15 1-Session ended (0 sent, 0 rcvd, 0 skip)
    + 2020.03.04 21:47:16 Polled 1 systems

    and 3/100 also

    --------------------- POLL v1.12 A46 2020/03/02 Wed, Mar 04 2020 (loglevel 2) + 2020.03.04 21:47:43 Poll BINKP node via address lookup: 21:3/100
    + 2020.03.04 21:47:43 1-Polling 21:3/100 on slot 1 via BINKP
    + 2020.03.04 21:47:43 1-Connecting to alterant.leenooks.net on port 24556
    + 2020.03.04 21:47:44 1-Connected by IPV4 SSL to 101.186.5.106
    + 2020.03.04 21:47:44 1-System W7-1-1
    + 2020.03.04 21:47:44 1-SysOp alterego
    + 2020.03.04 21:47:44 1-Location Parkdale VIC
    + 2020.03.04 21:47:44 1-Info NDL 115200,TCP,BINKP
    + 2020.03.04 21:47:44 1-Info TIME Wed Mar 04 2020 19:47:51 GMT+1100 (AUS Eastern Daylight Time)
    + 2020.03.04 21:47:44 1-Mailer BinkIT/2.28,JSBinkP/1.122,sbbs3.17c/Win32 binkp/1.1
    + 2020.03.04 21:47:45 1-Session ended (0 sent, 0 rcvd, 0 skip)
    + 2020.03.04 21:47:45 Polled 1 systems

    Looks like Dan is running NET 4 on Win 7 64bit OS but is running The Mystic
    32 bit system. Don't know if that's anything to do with it. Only cl32.dll is
    in the Mystic dir but it is only 32 bit Mystic running.

    v1.12 A46 Windows/32 Compiled 2020/03/02 15:31:21

    I did some polls at NET 4 of NET 1 and 2 the logs are

    --------------------- POLL v1.12 A46 2020/03/02 Wed, Mar 04 2020 (loglevel 2) + 2020.03.04 01:51:12 Poll BINKP node via address lookup: 21:1/100
    + 2020.03.04 01:51:12 Queued 59 files (9,097,342 bytes) for 21:1/100
    + 2020.03.04 01:51:12 1-Polling 21:1/100 on slot 1 via BINKP
    + 2020.03.04 01:51:13 1-Connecting to agency.bbs.nz on port 24553
    + 2020.03.04 01:51:14 1-Connected by IPV4 SSL to 219.89.83.33
    + 2020.03.04 01:51:15 1-System fsxHUB Risa [NET1]
    + 2020.03.04 01:51:15 1-SysOp Avon
    + 2020.03.04 01:51:15 1-Info TIME Wed, 04 Mar 2020 21:51:01 +1300
    + 2020.03.04 01:51:15 1-Mailer Mystic/1.12A46 binkp/1.0
    + 2020.03.04 01:51:15 1-Info BUILD 2020/03/02 15:31:32 Windows/32
    + 2020.03.04 01:51:15 1-Sending: 01ee38f4.pkt (27,266 bytes)
    + 2020.03.04 01:51:16 1-Remote Queue: 5 files 3,871,119 bytes
    + 2020.03.04 01:51:16 1-Resuming file at offset 16384
    + 2020.03.04 01:51:16 1-Connection lost
    + 2020.03.04 01:51:16 1-Session ended (0 sent, 0 rcvd, 0 skip)
    + 2020.03.04 01:51:17 Polled 1 systems

    --------------------- POLL v1.12 A46 2020/03/02 Wed, Mar 04 2020 (loglevel 2) + 2020.03.04 01:51:24 Poll BINKP node via address lookup: 21:2/100
    + 2020.03.04 01:51:24 Queued 3 files (2,793 bytes) for 21:2/100
    + 2020.03.04 01:51:24 1-Polling 21:2/100 on slot 1 via BINKP
    + 2020.03.04 01:51:24 1-Connecting to error404bbs.ddns.net on port 24553
    + 2020.03.04 01:51:25 1-Connected by IPV4 SSL to 71.74.66.9
    + 2020.03.04 01:51:25 1-System fsxNet [Net2] Tholian
    + 2020.03.04 01:51:25 1-SysOp Todd Zieman - Solaris
    + 2020.03.04 01:51:25 1-Info TIME Wed, 04 Mar 2020 03:51:19 -0500
    + 2020.03.04 01:51:25 1-Mailer Mystic/1.12A46 binkp/1.0
    + 2020.03.04 01:51:25 1-Info BUILD 2020/03/02 15:32:58 Windows/64
    + 2020.03.04 01:51:25 1-Sending: 02484271.pkt (735 bytes)
    + 2020.03.04 01:51:25 1-Remote Queue: 0 files 0 bytes
    + 2020.03.04 01:51:56 1-Session timeout
    + 2020.03.04 01:51:56 1-Session ended (0 sent, 0 rcvd, 0 skip)
    + 2020.03.04 01:51:57 Polled 1 systems

    Last night I think things were polling OK to those two HUBs but there were issues polling Deon at NET 3 so I reverted to non SSL BinkP for his HUB

    I think for now I will revert polling by NET 4 to NET 1 and 2 to use standard BinkP until we can suss why this is happening.

    [time passes]

    tried a poll to 21:1/100 from 1/400 polling std port and saw timeout issues still.

    --------------------- POLL v1.12 A46 2020/03/02 Wed, Mar 04 2020 (loglevel 2) + 2020.03.04 01:56:54 Poll BINKP node via address lookup: 21:1/100
    + 2020.03.04 01:56:54 Queued 59 files (9,097,342 bytes) for 21:1/100
    + 2020.03.04 01:56:54 1-Polling 21:1/100 on slot 1 via BINKP
    + 2020.03.04 01:56:54 1-Connecting to agency.bbs.nz on port 24556
    + 2020.03.04 01:56:54 1-Connected by IPV4 to 219.89.83.33
    + 2020.03.04 01:56:57 1-System fsxHUB Risa [NET1]
    + 2020.03.04 01:56:57 1-SysOp Avon
    + 2020.03.04 01:56:57 1-Info TIME Wed, 04 Mar 2020 21:56:43 +1300
    + 2020.03.04 01:56:57 1-Mailer Mystic/1.12A46 binkp/1.0
    + 2020.03.04 01:56:57 1-Info BUILD 2020/03/02 15:31:32 Windows/32
    + 2020.03.04 01:56:57 1-Sending: 01ee38f4.pkt (27,266 bytes)
    + 2020.03.04 01:56:57 1-Remote Queue: 5 files 3,871,119 bytes
    + 2020.03.04 01:56:57 1-Resuming file at offset 16384
    + 2020.03.04 01:57:29 1-Session timeout
    + 2020.03.04 01:57:29 1-Session ended (0 sent, 0 rcvd, 0 skip)
    + 2020.03.04 01:57:30 Polled 1 systems

    [time passes]

    I tried Fidopoll from 4/100 to 1/100 and got some success

    Mar 04 02:04:52 Scanning 21:1/100
    Mar 04 02:04:52 Queued 59 files (9097342 bytes) to 21:1/100
    Mar 04 02:04:52 Polling BINKP node 21:1/100 (Avon) by IPV4
    Mar 04 02:04:52 Connecting to agency.bbs.nz:24556 port 24556
    Mar 04 02:04:53 Connected IPV4 to 219.89.83.33
    Mar 04 02:04:56 System fsxHUB Risa [NET1]
    Mar 04 02:04:56 SysOp Avon
    Mar 04 02:04:56 Info TIME Wed, 04 Mar 2020 22:04:42 +1300
    Mar 04 02:04:56 Mailer Mystic/1.12A46 binkp/1.0
    Mar 04 02:04:56 Info BUILD 2020/03/02 15:31:32 Windows/32
    Mar 04 02:04:56 Sending: 01ee38f4.pkt (27,266 bytes)
    Mar 04 02:04:56 Remote Queue: 5 files 3,871,119 bytes
    Mar 04 02:04:56 Receiving: fffd0000.tup (1,938,261 bytes)
    Mar 04 02:04:58 Sending: 01f24102.pkt (3,142 bytes)
    Mar 04 02:04:59 Sending: 01f2548e.pkt (4,785 bytes)
    Mar 04 02:05:00 Sending: 0206180a.pkt (881 bytes)
    Mar 04 02:05:01 Sending: 020623c9.pkt (974 bytes)
    Mar 04 02:05:02 Sending: 020b2d1c.pkt (1,161 bytes)
    Mar 04 02:05:02 Sending: 020d502d.pkt (1,470 bytes)
    Mar 04 02:05:03 Sending: 020f9655.pkt (1,605 bytes)
    Mar 04 02:05:04 Sending: 020ff425.pkt (1,320 bytes)
    Mar 04 02:05:05 Sending: 0212ab8e.pkt (732 bytes)
    Mar 04 02:05:06 Sending: 0217a14a.pkt (1,597 bytes)
    Mar 04 02:05:07 Sending: 02256319.pkt (6,091 bytes)
    Mar 04 02:05:08 Sending: 02257166.pkt (525,573 bytes)
    Mar 04 02:05:17 Sending: 02257253.pkt (525,988 bytes)
    Mar 04 02:05:26 Sending: 022572b5.pkt (527,465 bytes)
    Mar 04 02:05:26 Receiving: fffd0000.tuq (1,190,918 bytes)
    Mar 04 02:11:20 FIDOPOLL Version 1.12 A46 2020/03/02

    Then subsequently another mis poll 21:1/100 from 1/400 seemed to send the
    rest of the files. These were mostly the big dupe dump from the other day and
    a loop of that dump that had gone 1/100 to 2/100 and back to 4/100 then
    finally heading back to 1/100 which thankfully (I think) saw them as dupes
    this time and stopped most.

    So perhaps it was the file sizes trying to be sent leading to the timeouts in SSL and BinkP standard?

    I think the same issue (whatever it is) is now happening with NET 2 trying to poll NET 4 with a bunch of files via SSL

    4/100 mis logs

    + 2020.03.04 02:29:19 BINKP Received connection; Negotiating (for up to 30 seconds)
    + 2020.03.04 02:29:19 BINKP > Connect on slot 1/20 SSL (71.74.66.9)
    + 2020.03.04 02:29:19 BINKP 1-HostName cpe-71-74-66-9.insight.res.rr.com
    + 2020.03.04 02:29:19 BINKP 1-Country United States of America (US)
    + 2020.03.04 02:29:20 BINKP 1-System fsxNet [Net2] Tholian
    + 2020.03.04 02:29:20 BINKP 1-SysOp Todd Zieman - Solaris
    + 2020.03.04 02:29:20 BINKP 1-Info TIME Wed, 04 Mar 2020 04:29:14 -0500
    + 2020.03.04 02:29:20 BINKP 1-Mailer Mystic/1.12A46 binkp/1.0
    + 2020.03.04 02:29:20 BINKP 1-Info BUILD 2020/03/02 15:32:58 Windows/64
    + 2020.03.04 02:29:20 BINKP 1-Authenticating 21:2/100@fsxnet by CRAM-MD5
    + 2020.03.04 02:29:20 BINKP 1-Queued 29 files for 21:2/100@fsxnet
    + 2020.03.04 02:29:20 BINKP 1-Sending: 02484271.pkt (735 bytes)
    + 2020.03.04 02:29:20 BINKP 1-Remote Queue: 1 files 2,580 bytes
    + 2020.03.04 02:29:20 BINKP 1-Renaming file fffe0000.we5 to fffe0000.1.we5
    + 2020.03.04 02:29:20 BINKP 1-Resuming file at offset 0
    + 2020.03.04 02:29:50 BINKP 1-Session timeout
    + 2020.03.04 02:29:50 BINKP 1-Session ended (0 sent, 0 rcvd, 0 skip)

    Bed time for me, sorry about the long message...

    Oh while I think of it.. you know how you show port numbers below?

    02:25:35 3-Sending: 026f0f53.pkt (347,739 bytes)
    02:25:36 2-Sending: 026e63ad.pkt (535,783 bytes)
    02:25:37 3-Session ended (10 sent, 0 rcvd, 0 skip)
    02:25:45 2-Sending: 026e6403.pkt (526,282 bytes)
    02:25:54 2-Sending: 026e645e.pkt (526,837 bytes)
    02:26:03 2-Sending: 026e64c1.pkt (525,397 bytes)
    02:26:14 2-Sending: 026e6534.pkt (561,778 bytes)

    could you add that here also either before system or between system and sysop? it would be helpful to see that.

    ────────────────────────────────────────────────────────────────────────────
    ─ System ────────────────── SysOp ────────────────────────── Address/Domain
    fsxNet HUB (NET3) Deon George 21:3/100@fsxnet


    Best, Paul

    --- Mystic BBS v1.12 A46 2020/02/29 (Windows/32)
    * Origin: Agency BBS | Dunedin, New Zealand | agency.bbs.nz (21:1/101)