Skip to content
Snippets Groups Projects
New look: Off

BinkIT dropping connections to Irex

    • Closed Issue created by Lance Vavricka @altere

    Having problems with IRex, there's some posts in Dove-NET Synchronet Discussion on this from myself and my hub Marc Lewis. From my original post, I created a new pastebin that doesn't expire so soon: https://pastebin.com/5ytzz6PD

    I have to sometimes email my HUB so he can clear something out before I start receiving echomail & netmail from him again.

    I initially thought a timeout but can't find anything indicating so on my end.

    Could also be a bug with IRex but appears at least 1 other node is having the same issue from his post below, either way, hoping something can be done to play nice.

    From Marc Lewis to All on Sync Discussion: Why is it that Syncrhonet's linux based BinkP always seems to drop it's inbound connection, ungracefully at that? Here's a couple of examples from my BinkP log:

    -o-o-o-o-o-o-o-o-CUT-o-o-o-o-o-o-o-o-o-

    • 20:37:00 (10) Sent to 1:106/633: 0122FDB4.MOB (2151 bytes) ? 20:37:00 (10) BinkP error: remote dropped connection. =-=-=-=
    • 09:38:19 (9) Sent to 1:106/987: TK426867.TIC (2013 bytes) ? 09:38:19 (9) BinkP error: remote dropped connection. =-=-=-=
    • 09:52:19 (9) Sent to 1:106/987: 0122FC52.SU1 (143743 bytes) ? 09:52:20 (9) BinkP error: remote dropped connection. =-=-=-=
    • 00:02:03 (10) Sent to 1:106/633: 0122FDB4.MO0 (567 bytes) ? 00:02:03 (10) BinkP error: remote dropped connection. -o-o-o-o-o-o-o-o-CUT-o-o-o-o-o-o-o-o-o-

    Here's an example of a normal close-out of a BinkP session from a couple non-Synchronet Nodes:

    -o-o-o-o-o-o-o-o-CUT-o-o-o-o-o-o-o-o-o-

    • 00:05:07 (8) Sent to 1:124/5016: 0110EC95.MO0 (15247 bytes)
    • 00:05:07 (8) Connection closed. =-=-=-=
    • 01:00:23 (8) Sent to 1:387/20: 00090019.MO0 (848 bytes)
    • 01:00:23 (8) Connection closed. -o-o-o-o-o-o-o-o-CUT-o-o-o-o-o-o-o-o-o-
    Edited

    Designs

    Child items ...

    • Activity

    • Lance Vavricka changed title from BinkIT dropping connections to to BinkIT dropping connections to Irex

      changed title from BinkIT dropping connections to to BinkIT dropping connections to Irex

    • Deucе
      Deucе @Deuce ·
      Maintainer

      In that log, binkit would have sent an M_GOT for 0122FDB4.MO0 or there would have been an additional error logged. DEBUG level logging on your side could confirm this, as could a packet capture of a session.

      One theory is that the remote detects the socket is closed and doesn't parse all the data it received before the FIN. Another possibility is the remote is requiring more M_EOB messages and that it's not properly parsing the M_GOT at all.

    • Lance Vavricka
      Lance Vavricka @altere ·
      Author

      Here's debugging enabled, didn't receive M_EOB: https://pastebin.com/yhpJDSZm

      Ragnarok reported same issue on Dove-NET with Peer version: Internet Rex 2.29 Win32 (binkp 1.1)

      Edited by Deucе
    • Deucе
      Deucе @Deuce ·
      Maintainer

      From the spec:

       4.1 Protocol identification string
 ----------------------------------

   In session setup stage both sides sends M_NUL frame like this:

      M_NUL "VER mailer version binkp/1.1"

   where "mailer version" is mailer identification string, usually
   mailer name and version, in free form, and "binkp/1.1" is the
   protocol identification string, case-incencitive. 
   Mailer identification string MAY have and SHOULD consist only
   characters in the ASCII codes range 32-126 (" ".."~").

   Example:

     M_NUL "VER binkd/0.9.5a/FreeBSD binkp/1.1"

   Version identification frame MUST be send and may be received 
   before autentification ends (before sending of M_PWD frame by 
   originating side and M_OK by answering side). Otherwise mailer
   MUST fallback to binkp 1.0.

      He's sending:

      M_NUL "VER Internet Rex 2.67 beta 1a OS/2 (binkp/1.1)"

      And expecting it to enable binkp/1.1. It doesn't. Those parentheses need to be removed to enable binkp/1.1 mode.

    • Rob Swindell
      Rob Swindell @rswindell ·
      Owner

      And so BinkIT falls-back to Binkp/1.0 mode? And that's not compatible in some way?

    • Lance Vavricka
      Lance Vavricka @altere ·
      Author

      Since Irex is no longer supported, the only hope for us using BinkIT is a fix/workaround is put in for Irex connections. Either way, if BinkIT is reverting to binkp/1.0 then I still don't understand why the connection would be timing out and causing the Irex end to have a socket error.

    • Rob Swindell closed with commit d1566e7d

      closed with commit d1566e7d

    • Deucе
      Deucе @Deuce ·
      Maintainer

      The hope is that people who use Irex will move to something supported and not broken. The original idea of these tools was to be a reference that will break when the other end is wrong to help highlight the old broken software people are using. Adding in hacks so things that violate the spec "work" just makes it impossible for new software to innovate in the FTN space.

      Anyway, @rswindell has your back it seems. :face_palm:

    • Rob Swindell mentioned in commit d1566e7d

      mentioned in commit d1566e7d

      • Fernando Toledo
        Fernando Toledo @Ragnarok ·

        I connect to my boss (4:90/1) that have irex, i notice that the binkit log have timeouts on transfers:

        Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Attempting poll for node 4:90/1@fidonet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL JSBinkP/1.123 callout to 4:90/1@fidonet started
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL connecting to 4:90/1@fidonet at momiabbs.no-ip.info
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Connecting to 4:90/1@fidonet at momiabbs.no-ip.info:24554
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Connection to momiabbs.no-ip.info:24554 successful
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: OPT CRYPT
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: SYS Dock Sud BBS
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: ZYZ Ragnarok
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: LOC Dock Sud, Bs As, Argentina
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: NDL 115200,TCP,BINKP
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: TIME Thu Nov 26 2020 06:29:48 GMT-0300
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: VER BinkIT/2.39,JSBinkP/1.123,sbbs3.18c/Linux binkp/1.1
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_ADR command args: 4:902/26@fidonet 21:2/151@fsxnet 666:1/1@latinet 618:500/45@micronet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_ADR command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: OPT MB CRAM-MD5-064a8d3cd48b6ced44d568717a9e3d38
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: SYS Momia BBS
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: ZYZ Manuel Adorni
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: LOC La Plata, Buenos Aires, ARG
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: NDL ALL
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: TIME 2020/11/26 06:41:16 -5:00
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: VER Internet Rex 2.29 Win32 (binkp/1.1)
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Peer version: Internet Rex 2.29 Win32 (binkp/1.1)
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_ADR command args:  4:90/1.0@fidonet 4:4/0.0@fidonet 4:90/0.0@fidonet 4:902/0.0@fidonet 4:902/27.0@fidonet 4:902/100.0@fidonet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_PWD command args: CRAM-MD5-xxxxxxxxxxxxx
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_PWD command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_OK command args:
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Authentication successful:
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:4/0@fidonet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:90/0@fidonet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/0@fidonet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/27@fidonet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/100@fidonet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Adding outbound files for 4:90/1@fidonet (object)
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_EOB command args:
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_EOB command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got M_FILE command args: FCD4FFE7.THR 2110 1606389914 0
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Receiving file: /sbbs/temp/event/FCD4FFE7.THR (2.1KB)
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Got data frame length 2110
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Received file: /sbbs/temp/event/FCD4FFE7.THR (2.1KB)
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Moving '/sbbs/temp/event/FCD4FFE7.THR' to '/sbbs/fido/inbound/FCD4FFE7.THR'.
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sending M_GOT command args: FCD4FFE7.THR 2110 1606389914
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Sent M_GOT command
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Timed out receiving packet data from remote: 4:90/1@fidonet,4:4/0@fidonet,4:90/0@fidonet,4:902/0@fidonet,4:902/27@fidonet,4:902/100@fidonet
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Unlocking /sbbs/fido/outbound/005a0001.bsy.
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL poll_node 21:2/100@fsxnet, outbound_dir: /sbbs/fido/outbound
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Locking /sbbs/fido/outbound.015/00020064.bsy
Nov 26 06:29:48 scarlet synchronet: evnt BINKPOLL Lock successful.
        Edited by Fernando Toledo
      • Rob Swindell
        Rob Swindell @rswindell ·
        Owner

        Is this finding with or without the latest binkit.js and binkp.js updates?

      • Lance Vavricka
        Lance Vavricka @altere ·
        Author

        I had one drop connection earlier this morning (with latest changes) but didn't have debugging enabled still. I've since turned it back on and it hasn't happened again, to my knowledge.

      • Fernando Toledo
        Fernando Toledo @Ragnarok ·

        @rswindell i just update and the timeout still show:

        Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Attempting callout for 4:90/1@fidonet, file: /sbbs/fido/outbound/005a0001.out
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL JSBinkP/1.123 callout to 4:90/1@fidonet started
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL connecting to 4:90/1@fidonet at momiabbs.no-ip.info
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Connecting to 4:90/1@fidonet at momiabbs.no-ip.info:24554
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Connection to momiabbs.no-ip.info:24554 successful
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: OPT CRYPT
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: SYS Dock Sud BBS
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: ZYZ Ragnarok
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: LOC Dock Sud, Bs As, Argentina
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: NDL 115200,TCP,BINKP
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: TIME Thu Nov 26 2020 23:20:17 GMT-0300
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: VER BinkIT/2.39,JSBinkP/1.123,sbbs3.18c/Linux binkp/1.1
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_ADR command args: 4:902/26@fidonet 21:2/151@fsxnet 666:1/1@latinet 618:500/45@micronet
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_ADR command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: OPT MB CRAM-MD5-xxxxxxxx
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: SYS Momia BBS
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: ZYZ Manuel Adorni
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: LOC La Plata, Buenos Aires, ARG
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: NDL ALL
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: TIME 2020/11/26 23:31:47 -5:00
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: VER Internet Rex 2.29 Win32 (binkp/1.1)
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Peer version: Internet Rex 2.29 Win32
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Peer ended their VER with " (binkp/1.1)" instead of the required " binkp/1.1", but we're assuming binkp 1.1 anyway
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Parsed BinkP version: 1.1
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_ADR command args:  4:90/1.0@fidonet 4:4/0.0@fidonet 4:90/0.0@fidonet 4:902/0.0@fidonet 4:902/27.0@fidonet 4:902/100.0@fidonet
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_PWD command args: CRAM-MD5-xxxxxxxxxx
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_PWD command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_OK command args:
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Authentication successful:
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:4/0@fidonet
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:90/0@fidonet
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/0@fidonet
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/27@fidonet
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/100@fidonet
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Adding outbound files for 4:90/1@fidonet (object)
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Adding '/sbbs/fido/outbound/005a0001.out' as 'cmi0zjh4.pkt'
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending file: /sbbs/fido/outbound/005a0001.out (0.4KB)
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_FILE command args: cmi0zjh4.pkt 359 1606443498 0
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_FILE command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending 359 bytes of data
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent file: /sbbs/fido/outbound/005a0001.out (0.4KB)
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_EOB command args:
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_EOB command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got M_FILE command args: AQL9MG80.TIC 522 1606443566 0
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Receiving file: /sbbs/temp/event/AQL9MG80.TIC (0.5KB)
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Got data frame length 522
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Received file: /sbbs/temp/event/AQL9MG80.TIC (0.5KB)
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Moving '/sbbs/temp/event/AQL9MG80.TIC' to '/sbbs/fido/inbound/AQL9MG80.TIC'.
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sending M_GOT command args: AQL9MG80.TIC 522 1606443566
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Sent M_GOT command
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Timed out receiving packet data from remote: 4:90/1@fidonet,4:4/0@fidonet,4:90/0@fidonet,4:902/0@fidonet,4:902/27@fidonet,4:902/100@fidonet
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Unlocking /sbbs/fido/outbound/005a0001.bsy.
Nov 26 23:20:17 scarlet synchronet: evnt BINKPOLL Locking /sbbs/fido/outbound/03860019.bsy
        Edited by Fernando Toledo
      • Please register or sign in to reply
    • Rob Swindell reopened

      reopened

    • Deucе closed with commit d218c8de

      closed with commit d218c8de

    • Deucе
      Deucе @Deuce ·
      Maintainer

      If this fixes the problem, it would be nice if someone could attach a debug log of it working, thanks!

    • Deucе mentioned in commit d218c8de

      mentioned in commit d218c8de

    • Fernando Toledo
      Fernando Toledo @Ragnarok ·

      good you catch it!

      Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Attempting poll for node 4:90/1@fidonet
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL JSBinkP/2 callout to 4:90/1@fidonet started
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL connecting to 4:90/1@fidonet at momiabbs.no-ip.info
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Connecting to 4:90/1@fidonet at momiabbs.no-ip.info:24554
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Connection to momiabbs.no-ip.info:24554 successful
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: OPT CRYPT
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: SYS Dock Sud BBS
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: ZYZ Ragnarok
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: LOC Dock Sud, Bs As, Argentina
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: NDL 115200,TCP,BINKP
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: TIME Fri Nov 27 2020 11:40:25 GMT-0300
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_NUL command args: VER BinkIT/2.4,JSBinkP/2,sbbs3.18c/Linux binkp/1.1
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_NUL command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_ADR command args: 4:902/26@fidonet 21:2/151@fsxnet 666:1/1@latinet 618:500/45@micronet
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_ADR command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: OPT MB CRAM-MD5-xxxxxxxxxxxxxxxxxxxxxx
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: SYS Momia BBS
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: ZYZ Manuel Adorni
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: LOC La Plata, Buenos Aires, ARG
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: NDL ALL
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: TIME 2020/11/27 11:51:55 -5:00
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_NUL command args: VER Internet Rex 2.29 Win32 (binkp/1.1)
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Peer version: Internet Rex 2.29 Win32
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Peer ended their VER with " (binkp/1.1)" instead of the required " binkp/1.1", but we're assuming binkp 1.1 anyway
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Parsed BinkP version: 1.1
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_ADR command args:  4:90/1.0@fidonet 4:4/0.0@fidonet 4:90/0.0@fidonet 4:902/0.0@fidonet 4:902/27.0@fidonet 4:902/100.0@fidonet
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_PWD command args: CRAM-MD5-xxxxxxxxxxxx
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_PWD command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_OK command args:
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Authentication successful:
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:4/0@fidonet
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:90/0@fidonet
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/0@fidonet
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/27@fidonet
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Unconfigured address 4:902/100@fidonet
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Adding outbound files for 4:90/1@fidonet (object)
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_EOB command args:
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_EOB command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_FILE command args: FCD4FFE7.FR3 12483 1606494454 0
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Receiving file: /sbbs/temp/event/FCD4FFE7.FR3 (12.2KB)
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got data frame length 4096
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got data frame length 4096
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got data frame length 4096
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got data frame length 195
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Received file: /sbbs/temp/event/FCD4FFE7.FR3 (12.2KB)
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Moving '/sbbs/temp/event/FCD4FFE7.FR3' to '/sbbs/fido/inbound/FCD4FFE7.FR3'.
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sending M_GOT command args: FCD4FFE7.FR3 12483 1606494454
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Sent M_GOT command
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Remote illegally sent a Data packet with data length of zero.  This isn't even allowed in protocol 1.0.
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got data frame length 0
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Data packet outside of file!
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Got M_EOB command args:
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Unlocking /sbbs/fido/outbound/005a0001.bsy.
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL poll_node 21:2/100@fsxnet, outbound_dir: /sbbs/fido/outbound
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Locking /sbbs/fido/outbound.015/00020064.bsy
Nov 27 11:40:25 scarlet synchronet: evnt BINKPOLL Lock successful.
    • Deucе
      Deucе @Deuce ·
      Maintainer

      Thanks for the log!

    Please register or sign in to reply