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)

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)

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)

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)

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)

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)

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)

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)

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)