• Probs

    From Paul Hayton@3:770/100 to All on Sun Nov 10 12:39:52 2019
    Hi guys

    I am seeing this when trying to send Fido files to a BinkIT system

    - 10 Nov 11:54:58 [22732] incoming from 76.188.66.199 (41720)
    + 10 Nov 11:54:58 [4032] incoming session with
    cpe-76-188-66-199.neo.res.rr.com [76.188.66.199]
    - 10 Nov 11:54:58 [4032] OPT CRYPT
    + 10 Nov 11:54:58 [4032] Remote requests CRYPT mode
    - 10 Nov 11:54:58 [4032] SYS The New Frontier ][ BBS
    - 10 Nov 11:54:58 [4032] ZYZ Charles Stephenson
    - 10 Nov 11:54:58 [4032] LOC Akron, Ohio
    - 10 Nov 11:54:58 [4032] NDL BINKIT, MODEM, LINUX, 115200, LD, HST
    - 10 Nov 11:54:58 [4032] TIME Sat Nov 09 2019 17:55:11 GMT-0500 (EST)
    - 10 Nov 11:54:58 [4032] VER BinkIT/2.27,JSBinkP/1.121,sbbs3.17c/Linux binkp/1.1
    + 10 Nov 11:54:58 [4032] addr: 1:226/17@fidonet
    + 10 Nov 11:54:58 [4032] addr: 64:500/13@cnet (n/a or busy)
    + 10 Nov 11:54:58 [4032] addr: 411:411/38@combatnt (n/a or busy)
    + 10 Nov 11:54:58 [4032] addr: 40:300/1@cybernet (n/a or busy)
    + 10 Nov 11:54:58 [4032] addr: 40:300/0@cybernet (n/a or busy)
    + 10 Nov 11:54:58 [4032] addr: 40:100/22@cybernet (n/a or busy)
    + 10 Nov 11:54:58 [4032] addr: 40:100/109@cybernet (n/a or busy)
    + 10 Nov 11:54:58 [4032] addr: 901:1/17@dixienet (n/a or busy)
    + 10 Nov 11:54:58 [4032] addr: 44:100/22@dorenet (n/a or busy)
    + 10 Nov 11:54:58 [4032] addr: 21:2/105@fsxnet
    + 10 Nov 11:54:58 [4032] pwd protected session (MD5)
    - 10 Nov 11:54:58 [4032] session in CRYPT mode
    + 10 Nov 11:54:58 [4032] sending C:\HUB\ECHOMAIL\OUT\fidonet.001\604F012D.WE1 as 604F012D.WE1 (951586)
    + 10 Nov 11:54:58 [4032] 604F012D.WE1 skipped by remote
    ? 10 Nov 11:54:58 [4032] M_FILE: cannot parse args
    + 10 Nov 11:54:59 [4032] done (from 1:226/17@fidonet, failed, S/R: 0/0 (0/0 bytes))
    10 Nov 11:54:59 [4032] session closed, quitting...

    I am running

    Binkd 1.1a-99 (Jun 25 2018 22:56:55/Win32)
    Compilation flags: msvc, static, zlib, bzlib2, perl, https, ntlm, amiga_4d_outbound, bwlim, ipv6, af_force.
    Facilities: fts5004 ipv6

    an earlier outgoing session from a few days prior had the same results

    + 06 Nov 20:13:07 [28676] call to 1:226/17@fidonet
    06 Nov 20:13:07 [28676] trying frontierbbs.net [76.188.66.199]:24556...
    06 Nov 20:13:07 [28676] connected
    + 06 Nov 20:13:07 [28676] outgoing session with frontierbbs.net:24556 [76.188.66.199]
    - 06 Nov 20:13:08 [28676] OPT CRAM-MD5-27e6d656ce986940549144f91278d25f CRYPT
    + 06 Nov 20:13:08 [28676] Remote requests MD mode
    + 06 Nov 20:13:08 [28676] Remote requests CRYPT mode
    - 06 Nov 20:13:08 [28676] SYS The New Frontier ][ BBS
    - 06 Nov 20:13:08 [28676] ZYZ Charles Stephenson
    - 06 Nov 20:13:08 [28676] LOC Akron, Ohio
    - 06 Nov 20:13:08 [28676] NDL 115200,TCP,BINKP
    - 06 Nov 20:13:09 [28676] TIME Wed Nov 06 2019 02:13:15 GMT-0500 (EST)
    - 06 Nov 20:13:09 [28676] VER BinkIT/2.27,JSBinkP/1.121,sbbs3.17c/Linux binkp/1.1
    + 06 Nov 20:13:09 [28676] addr: 1:226/17@fidonet
    + 06 Nov 20:13:09 [28676] addr: 21:2/105@fsxnet
    + 06 Nov 20:13:09 [28676] addr: 40:300/0@cybernet (n/a or busy)
    + 06 Nov 20:13:09 [28676] addr: 40:300/1@cybernet (n/a or busy)
    + 06 Nov 20:13:09 [28676] addr: 44:100/22@dorenet (n/a or busy)
    + 06 Nov 20:13:09 [28676] addr: 901:1/17@dixienet (n/a or busy)
    + 06 Nov 20:13:09 [28676] addr: 40:100/109@cybernet (n/a or busy)
    + 06 Nov 20:13:09 [28676] addr: 64:500/13@cnet (n/a or busy)
    + 06 Nov 20:13:09 [28676] addr: 411:411/38@combatnt (n/a or busy)
    + 06 Nov 20:13:09 [28676] addr: 40:100/22@cybernet (n/a or busy)
    + 06 Nov 20:13:10 [28676] pwd protected session (MD5)
    - 06 Nov 20:13:10 [28676] session in CRYPT mode
    + 06 Nov 20:13:10 [28676] sending
    C:\HUB\ECHOMAIL\OUT\fidonet.001\604F012D.WE0 as 604F012D.WE0 (1990)
    ? 06 Nov 20:13:10 [28676] M_FILE: cannot parse args
    + 06 Nov 20:13:10 [28676] done (to 1:226/17@fidonet, failed, S/R: 0/0 (0/0 bytes))
    06 Nov 20:13:10 [28676] restoring poll with `C' flavour
    06 Nov 20:13:11 [28676] session closed, quitting...

    Any ideas?

    I have asked Charles to email me his logs to see what his system is showing.

    --- Mystic BBS v1.12 A43 2019/03/03 (Windows/32)
    * Origin: Agency BBS | Dunedin, New Zealand | agency.bbs.nz (3:770/100)
  • From Paul Hayton@3:770/100 to All on Sun Nov 10 16:17:32 2019
    On 10 Nov 2019 at 12:39p, Paul Hayton pondered and said...

    Hi guys

    I am seeing this when trying to send Fido files to a BinkIT system

    - 10 Nov 11:54:58 [22732] incoming from 76.188.66.199 (41720)
    + 10 Nov 11:54:58 [4032] incoming session with

    I have received these logs from the BinkIT system

    Running outbox dir for (3:770/1@fidonet): /var/opt/EchoMail/FidoNet/SBBS/Outbound/
    Attempting callout for 3:770/1@fidonet, outbox files: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/,/var/opt/EchoMail/FidoNet /SBBS/Outbound/00e500c3.cut,/var/opt/EchoMail/FidoNet/SBBS/Outbound/012b01aa.cu t,/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dbffa71.pkt,/var/opt/EchoMail/FidoNe t/SBBS/Outbound/5dbffa72.pkt,/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc283e5.p kt,/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc283e6.pkt,/var/opt/EchoMail/FidoN et/SBBS/Outbound/5dc3b89c.pkt,/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc3b89d. pkt,/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc487bb.pkt,/var/opt/EchoMail/Fido Net/SBBS/Outbound/5dc65aaf.pkt,/var/opt/EchoMail/FidoNet/SBBS/Outbound/fde00010 .TH0
    JSBinkP/1.121 callout to 3:770/1@fidonet started
    connecting to 3:770/1@fidonet at agency.bbs.nz
    Connecting to 3:770/1@fidonet at agency.bbs.nz:24554
    Connection to agency.bbs.nz:24554 successful
    Sending M_NUL command args: OPT CRYPT
    Sent M_NUL command
    Sending M_NUL command args: SYS The New Frontier ][ BBS
    Sent M_NUL command
    Sending M_NUL command args: ZYZ Charles Stephenson
    Sent M_NUL command
    Sending M_NUL command args: LOC Akron, Ohio
    Sent M_NUL command
    Sending M_NUL command args: NDL BINKIT, MODEM, LINUX, 115200, LD, HST
    Sent M_NUL command
    Sending M_NUL command args: TIME Sat Nov 09 2019 21:33:49 GMT-0500 (EST)
    Sent M_NUL command
    Sending M_NUL command args: VER BinkIT/2.27,JSBinkP/1.121,sbbs3.17c/Linux binkp/1.1
    Sent M_NUL command
    Sending M_ADR command args: 1:226/17@fidonet 64:500/13@cnet
    411:411/38@combatnt 40:300/1@cybernet 40:300/0@cybernet 40:100/22@cybernet 40:100/109@cybernet 901:1/17@dixienet 44:100/22@dorenet 21:2/105@fsxnet
    Sent M_ADR command
    Got M_NUL command args: OPT CRAM-MD5-57f7147383adbe97a1380f8d908cf2ee
    Got M_NUL command args: SYS Agency HUB
    Got M_NUL command args: ZYZ Paul Hayton
    Got M_NUL command args: LOC Dunedin, New Zealand
    Got M_NUL command args: NDL 115200,TCP,BINKP
    Got M_NUL command args: TIME Sun, 10 Nov 2019 15:33:36 +1300
    Got M_NUL command args: VER binkd/1.1a-99/Win32 binkp/1.1
    Peer version: binkd/1.1a-99/Win32 binkp/1.1
    Got M_ADR command args: 3:57/0@fidonet 3:770/1@fidonet 3:770/0@fidonet 3:772/1@fidonet 3:772/0@fidonet 21:1/1@fsxnet 39:970/0@amiganet 46:3/103@agoranet
    Sending M_PWD command args: CRAM-MD5-e7b8e17f72bf1e4fd5a586e0d1705552
    Sent M_PWD command
    Got M_NUL command args: TRF 0 986069
    Got M_NUL command args: OPT EXTCMD CRYPT GZ BZ2
    Will encrypt session.
    Got M_OK command args: secure
    Authentication successful: secure
    Unconfigured address 3:57/0@fidonet
    Unconfigured address 3:770/0@fidonet
    Unconfigured address 3:772/1@fidonet
    Unconfigured address 3:772/0@fidonet
    Unconfigured address 21:1/1@fsxnet
    Unconfigured address 39:970/0@amiganet
    Unconfigured address 46:3/103@agoranet
    Adding outbound files for 3:770/1@fidonet
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/' as ''
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e500c3.cut
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/00e500c3.cut' as
    '00e500c3.cut'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/012b01aa.cut
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/012b01aa.cut' as
    '012b01aa.cut'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/5dbffa71.pkt
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dbffa71.pkt' as
    '5dbffa71.pkt'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/5dbffa72.pkt
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dbffa72.pkt' as
    '5dbffa72.pkt'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc283e5.pkt
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc283e5.pkt' as
    '5dc283e5.pkt'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc283e6.pkt
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc283e6.pkt' as
    '5dc283e6.pkt'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc3b89c.pkt
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc3b89c.pkt' as
    '5dc3b89c.pkt'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc3b89d.pkt
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc3b89d.pkt' as
    '5dc3b89d.pkt'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc487bb.pkt
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc487bb.pkt' as
    '5dc487bb.pkt'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc65aaf.pkt
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc65aaf.pkt' as
    '5dc65aaf.pkt'
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/fde00010.TH0
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/fde00010.TH0' as
    'fde00010.TH0'
    Initializing crypt keys.
    Got M_FILE command args: 604F012D.WE1 986069 1573351762 0
    Inbound file already exists: /var/opt/SBBS/temp/604F012D.WE1
    Sending M_SKIP command args: 604F012D.WE1 986069 1573351762
    Sent M_SKIP command
    Sending file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/ (4.0KB) Sending M_FILE command args: 4096 1573351165 0
    Sent M_FILE command
    Sending 0 bytes of data
    Sending 0 bytes of data
    Sending 0 bytes of data

    [snip]

    lots of the sending 0 bytes of data lines

    [snip]

    Sending 0 bytes of data
    Got data frame length 4096
    Data packet outside of file!
    Sending 0 bytes of data
    Got data frame length 4096
    Data packet outside of file!

    [snip]

    more sending 0 bytes of data

    [snip]

    Sending 0 bytes of data
    Sending 0 bytes of data
    Got M_EOB command args:
    We got an M_EOB, but there are still 1 files pending M_GOT
    Sending 0 bytes of data
    Got M_ERR command args: M_FILE: cannot parse args
    BinkP got fatal error 'M_FILE: cannot parse args' from remote: 3:57/0@fidonet,3:770/1@fidonet,3:770/0@fidonet,3:772/1@fidonet,3:772/0@fidonet, 21:1/1@fsxnet,39:970/0@amiganet,46:3/103@agoranet
    Sending 0 bytes of data
    Send failure
    Error in recv() of first byte of packet header

    [snip]




    so his end sends

    Sending M_FILE command args: 4096 1573351165 0
    Sent M_FILE command
    Sending 0 bytes of data

    then we see

    Got data frame length 4096
    Data packet outside of file!
    Sending 0 bytes of data
    Got data frame length 4096
    Data packet outside of file!

    and then it cans out at the end reporting

    Got M_EOB command args:
    We got an M_EOB, but there are still 1 files pending M_GOT
    Sending 0 bytes of data
    Got M_ERR command args: M_FILE: cannot parse args
    BinkP got fatal error 'M_FILE: cannot parse args' from remote: 3:57/0@fidonet,3:770/1@fidonet,3:770/0@fidonet,3:772/1@fidonet,3:772/0@fidonet, 21:1/1@fsxnet,39:970/0@amiganet,46:3/103@agoranet
    Sending 0 bytes of data
    Send failure
    Error in recv() of first byte of packet header


    Any ideas?

    --- Mystic BBS v1.12 A43 2019/03/03 (Windows/32)
    * Origin: Agency BBS | Dunedin, New Zealand | agency.bbs.nz (3:770/100)
  • From Oli@2:280/464.47 to Paul Hayton on Sun Nov 10 08:50:16 2019
    Adding outbound files for 3:770/1@fidonet
    outbox file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/
    Adding '/var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/' as ''
    ^^^
    Got M_FILE command args: 604F012D.WE1 986069 1573351762 0
    Inbound file already exists: /var/opt/SBBS/temp/604F012D.WE1
    Sending M_SKIP command args: 604F012D.WE1 986069 1573351762
    Sent M_SKIP command
    Sending file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/ (4.0KB) Sending M_FILE command args: 4096 1573351165 0 Sent M_FILE
    ^^

    command Sending 0 bytes of data Sending 0 bytes of data Sending 0
    bytes of data

    Any ideas?

    Yes! It tries to send a file with an empty filename. The M_FILE command should be

    M_FILE 12345678.ABC 4096 1573351165 0 Sent M_FILE
    and not
    M_FILE 4096 1573351165 0 Sent M_FILE



    --- GoldED+/LNX 1.1.5-b20180707
    * Origin: * nigirO (2:280/464.47)
  • From Oli@2:280/464.47 to Paul Hayton on Sun Nov 10 09:02:13 2019
    I have received these logs from the BinkIT system

    Running outbox dir for (3:770/1@fidonet): /var/opt/EchoMail/FidoNet/SBBS/Outbound/
    Attempting callout for 3:770/1@fidonet, outbox files: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/,/var/opt/EchoMai l/FidoNet /SBBS/Outbound/00e500c3.cut,/var/opt/EchoMail/FidoNet/SBBS/O utbound/012b01aa.cu t,/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dbffa71 .pkt,/var/opt/EchoMail/FidoNe t/SBBS/Outbound/5dbffa72.pkt,/var/opt/Ec hoMail/FidoNet/SBBS/Outbound/5dc283e5.p kt,/var/opt/EchoMail/FidoNet/S BBS/Outbound/5dc283e6.pkt,/var/opt/EchoMail/FidoN et/SBBS/Outbound/5dc 3b89c.pkt,/var/opt/EchoMail/FidoNet/SBBS/Outbound/5dc3b89d. pkt,/var/o pt/EchoMail/FidoNet/SBBS/Outbound/5dc487bb.pkt,/var/opt/EchoMail/Fido Net/SBBS/Outbound/5dc65aaf.pkt,/var/opt/EchoMail/FidoNet/SBBS/Outbound /fde00010 .TH0

    I never installed synchronet, but "outbox" does not sound right for a directory that looks like a binkley-style outbound. One file is for 229/195, another for 299/426. I think it is a combination of a configuration problem and BinkIT choking on sub directories in an outbox dir.

    --- GoldED+/LNX 1.1.5-b20180707
    * Origin: * nigirO (2:280/464.47)
  • From Dumas Walker@1:2320/105 to PAUL HAYTON on Sun Nov 10 09:30:00 2019
    Initializing crypt keys.
    Got M_FILE command args: 604F012D.WE1 986069 1573351762 0
    Inbound file already exists: /var/opt/SBBS/temp/604F012D.WE1
    Sending M_SKIP command args: 604F012D.WE1 986069 1573351762
    Sent M_SKIP command
    Sending file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/ (4.0KB) Sending M_FILE command args: 4096 1573351165 0
    Sent M_FILE command
    Sending 0 bytes of data
    Sending 0 bytes of data
    Sending 0 bytes of data

    It looks to me like the file you are trying to send (604F012D.WE1) already exists. Looks like the bad M_FILE might be because his side is attempting
    to send a directory rather than a file. So, the filename arg is missing
    from the M_FILE command line:

    Got M_FILE command args: 604F012D.WE1 986069 1573351762 0

    Contains a file name in the first arg.

    Sending file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/ (4.0KB)

    No file name at the end of this path statement...

    Sending M_FILE command args: 4096 1573351165 0

    Contains a space in the first arg.

    Sounds like maybe an issue with his version of BinkIT, or maybe his
    pathname is too long?

    Mike


    * SLMR 2.1a * "Silence. Music's original alternative. Roots-grunge!"
    --- SBBSecho 3.07-Linux
    * Origin: capitolcityonline.net * Telnet/SSH:2022/HTTP (1:2320/105)
  • From Paul Hayton@3:770/100 to Oli on Sun Nov 17 10:03:03 2019
    On 10 Nov 2019 at 08:50a, Oli pondered and said...

    Any ideas?

    Yes! It tries to send a file with an empty filename. The M_FILE command should be

    M_FILE 12345678.ABC 4096 1573351165 0 Sent M_FILE
    and not
    M_FILE 4096 1573351165 0 Sent M_FILE


    belated thanks for this info Oli - appreciated :)

    --- Mystic BBS v1.12 A43 2019/03/03 (Windows/32)
    * Origin: Agency BBS | Dunedin, New Zealand | agency.bbs.nz (3:770/100)
  • From Paul Hayton@3:770/100 to Dumas Walker on Sun Nov 17 10:04:29 2019
    On 10 Nov 2019 at 09:30a, Dumas Walker pondered and said...

    It looks to me like the file you are trying to send (604F012D.WE1)
    already exists. Looks like the bad M_FILE might be because his side is attempting to send a directory rather than a file. So, the filename arg is missing from the M_FILE command line:

    Got M_FILE command args: 604F012D.WE1 986069 1573351762 0

    Contains a file name in the first arg.

    Sending file: /var/opt/EchoMail/FidoNet/SBBS/Outbound/00e20011.pnt/
    (4.0

    No file name at the end of this path statement...

    Sending M_FILE command args: 4096 1573351165 0

    Contains a space in the first arg.

    Sounds like maybe an issue with his version of BinkIT, or maybe his pathname is too long?


    Thanks Mike. I have contacted Charles on the back of your helpful feedback
    (and Oli's!) to let him know. I've suggested he enlist some help from someone who knows more about BinkIT than I do, or perhaps look to move to BinkD instead.

    Appreciate the feedback and info guys

    Best, Paul

    --- Mystic BBS v1.12 A43 2019/03/03 (Windows/32)
    * Origin: Agency BBS | Dunedin, New Zealand | agency.bbs.nz (3:770/100)
  • From Paul Quinn@3:640/1384.125 to Paul Hayton on Sun Nov 17 08:16:10 2019
    Hi! Paul,

    On 11/17/2019 07:04 AM, you wrote to Dumas Walker:

    who knows more about BinkIT than I do, or perhaps look to move to BinkD instead.

    I know nothing of BinkIT. But I know a little more about binkD.

    However I just need to say that my simple last-century brain is signalling that maybe, just maybe, someone has a filebox aimed at a BSO outbound directory. This is a gross error state. OTOH it may be, just maybe, not actually be the root problem.

    :)

    Cheers,
    Paul.

    --- Mozilla/5.0 (X11; Linux i686; rv:31.0) Gecko/20100101 Thunderbird/31.4.0
    * Origin: Cogito sumere potum alterum. (3:640/1384.125)
  • From Dumas Walker@1:2320/105 to PAUL QUINN on Sun Nov 17 11:12:00 2019
    I know nothing of BinkIT. But I know a little more about binkD.

    However I just need to say that my simple last-century brain is signalling
    tha
    maybe, just maybe, someone has a filebox aimed at a BSO outbound directory. This is a gross error state. OTOH it may be, just maybe, not actually be
    the
    root problem.

    I think maybe Oli mentioned that also, and I am inclined to agree with you both. That path looks suspicious. :)

    Mike


    * SLMR 2.1a * Hey, how 'bout a fandango ?!?
    --- SBBSecho 3.07-Linux
    * Origin: capitolcityonline.net * Telnet/SSH:2022/HTTP (1:2320/105)