As a follow up I checked my logs and I see on NetMail sending I get a call out fail even though the message seems to have made it out.
Re: Sending mail to areafix
By: Digital Man to Khronos on Sat Jun 06 2026 13:07:51
[callout failure: 123:0/1@hobbyspc]
oper = Morningstarr
AKAs = 123:0/1@hobbyspc
caps = 115200,TCP,BINKP
vers = BinkIT/2.41,JSBinkP/4,sbbs3.20d/Win32
host = hobbyspc.synchro.net
port = 24554
info.sys = Hobby Space
info.loc = Mt. Pleasant Nc
info.time = Sat Jun 06 2026 16:59:57 GMT-0400 (Eastern Daylight Time)
localtime = Jun 6 2026 16:59:58
sent_files = /home/sbbs/sbbs/fido/outbound.07b/00000001.dut
This is from the binkstats.ini for the fails
Re: Sending mail to areafix
By: Digital Man to Khronos on Sat Jun 06 2026 15:50:03
Here are the log messages I ahve.
Jun 6 19:09:56 cwshack synchronet: evnt BINKOUT Connecting to 123:0/1@hobbyspc at hobbyspc.synchro.net:24554
Jun 6 19:09:57 cwshack synchronet: evnt BINKOUT Will encrypt session.
Jun 6 19:09:57 cwshack synchronet: evnt BINKOUT Peer version: BinkIT/2.41,JSBinkP/4,sbbs3.20d/Win32
Jun 6 19:09:57 cwshack synchronet: evnt BINKOUT Authentication successful: secure
Jun 6 19:09:57 cwshack synchronet: evnt BINKOUT Sending file: /home/sbbs/sbbs/fido/outbound.07b/00000001.dut (0.4KB)
Jun 6 19:09:57 cwshack synchronet: evnt BINKOUT Sent file: /home/sbbs/sbbs/fido/outbound.07b/00000001.dut (0.4KB)
Jun 6 19:09:57 cwshack synchronet: evnt BINKOUT Deleted file: /home/sbbs/sbbs/fido/outbound.07b/00000001.dut
Jun 6 19:09:57 cwshack synchronet: evnt BINKOUT Timed event: '?binkit' returned 0
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP [173.185.85.205] Connection accepted on 192.168.1.103 port 24554 from port 57911
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP BinkIT/2.42 invoked with options:
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP JSBinkP/5 inbound connection from 173.185.85.205:57911
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP Will encrypt session. Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP Peer version: BinkIT/2.41,JSBinkP/4,sbbs3.20d/Win32
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP Remote addresses: 123:0/1@hobbyspc
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP Inbound session for: 123:0/1@hobbyspc
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP CRAM-MD5 password match for 123:0/1@hobbyspc
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP Receiving file: /sbbs/temp/x9b6c2gj.pkt (0.4KB)
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP Received file: /sbbs/temp/x9b6c2gj.pkt (0.4KB)
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP Moving '/sbbs/temp/x9b6c2gj.pkt' to '../fido/inbound/x9b6c2gj.pkt'.
Jun 6 19:10:01 cwshack synchronet: srvc 0050 BINKP [173.185.85.205] JavaScript service thread terminated (0 clients remain, 0 total, 10 served)
Khronos wrote to Digital Man <=-
Re: Sending mail to areafix
By: Digital Man to Khronos on Sat Jun 06 2026 16:28:04
What's weird about this is these show that everything is good but the
sats file shows a failed connection on call outs sometimes. Not just
this peer all of my FTN peers.
Khronos wrote to Digital Man <=-
Re: Sending mail to areafix
By: Digital Man to Khronos on Sat Jun 06 2026 16:28:04
What's weird about this is these show that everything is good but the sats file shows a failed connection on call outs sometimes. Not just this peer all of my FTN peers.
Digital Man, jumping in here because I noticed something. I wasn't super-aware of the 'binkstats.ini' file, but went and looked at mine, and.... I also see a *LOT* of "callout failure" entries - in fact it is
the vast majority of them. Some say success, and I see no pattern on who/when that happens with. But it seems to be in error, as pretty
much every one of those callouts are in fact successful. I think
something is amiss with how that file is recording things, just wanted
to mention it.
Naturally, just after sending the above I need to send a followup: I did notice a pattern now after looking more at 'binkstats.ini'. What I saw was that when my system calls out to a MYSTIC board, I get a "callout success" entry - but calling a system using BINKIT or BINKD, I get the "callout failure" entry. Every single time. Got to be something to that...
Digital Man wrote to Gamgee <=-
Re: Re: Sending mail to areafix
By: Gamgee to Digital Man on Sun Jun 07 2026 08:18 am
Naturally, just after sending the above I need to send a followup: I did notice a pattern now after looking more at 'binkstats.ini'. What I saw was that when my system calls out to a MYSTIC board, I get a "callout success" entry - but calling a system using BINKIT or BINKD, I get the "callout failure" entry. Every single time. Got to be something to that...
The corresponding log output for that BinkIt session should provide the reason.
But that's just it - there *is* no reason. The callout is a complete success, but it's entered in 'binkstats.ini' as a "callout failure".
Digital Man wrote to Gamgee <=-
But that's just it - there *is* no reason. The callout is a complete success, but it's entered in 'binkstats.ini' as a "callout failure".
You and Khronos were both right - it was a bug in BinkIT, not your configs, and no mail was ever actually lost. It was purely a stats-recording bug.
In a binkp/1.1 session (what you get with Synchronet, binkd, and most modern mailers) the two sides exchange a pair of M_EOB ("end of batch") frames to close out. When your side reached the end by *sending* that final EOB, the peer would close the connection first, and BinkIT would then try to send one more EOB onto the already-closed socket. That harmless send failure was being treated as a failed session - so a fully-successful callout got logged as "[callout failure]" in binkstats.ini, with the sent file(s) still listed, which is exactly why
it looked so contradictory.
binkp/1.0 peers (Mystic) send only a single EOB each and close differently, so those were always recorded correctly - matching the MYSTIC-works / SBBS-fails pattern you spotted.
Fixed in git just now: a closing-EOB send failure is ignored once all
of your sent files have been acknowledged. You'll see correct
accounting (and JSBinkP/6 in the vers= field) after your next update + recycle.
Thanks to you both for the clear reports and log snippets - they made
this an easy one to pin down.
But that's just it - there *is* no reason. The callout is a complete success, but it's entered in 'binkstats.ini' as a "callout failure".
You and Khronos were both right - it was a bug in BinkIT, not your
configs, and no mail was ever actually lost. It was purely a stats-recording bug.
In a binkp/1.1 session (what you get with Synchronet, binkd, and most
modern mailers) the two sides exchange a pair of M_EOB ("end of batch") frames to close out. When your side reached the end by *sending* that
final EOB, the peer would close the connection first, and BinkIT would
then try to send one more EOB onto the already-closed socket. That
harmless send failure was being treated as a failed session - so a fully-successful callout got logged as "[callout failure]" in
binkstats.ini, with the sent file(s) still listed, which is exactly why
it looked so contradictory.
binkp/1.0 peers (Mystic) send only a single EOB each and close
differently, so those were always recorded correctly - matching the MYSTIC-works / SBBS-fails pattern you spotted.
Fixed in git just now: a closing-EOB send failure is ignored once all
of your sent files have been acknowledged. You'll see correct accounting (and JSBinkP/6 in the vers= field) after your next update + recycle.
Thanks to you both for the clear reports and log snippets - they made
this an easy one to pin down.
Re: Re: Sending mail to areafix
By: Digital Man to Gamgee on Sun Jun 07 2026 06:42 pm
But that's just it - there *is* no reason. The callout is a complete success, but it's entered in 'binkstats.ini' as a "callout failure".
You and Khronos were both right - it was a bug in BinkIT, not your configs, and no mail was ever actually lost. It was purely a stats-recording bug.
In a binkp/1.1 session (what you get with Synchronet, binkd, and most modern mailers) the two sides exchange a pair of M_EOB ("end of batch") frames to close out. When your side reached the end by *sending* that final EOB, the peer would close the connection first, and BinkIT would then try to send one more EOB onto the already-closed socket. That harmless send failure was being treated as a failed session - so a fully-successful callout got logged as "[callout failure]" in binkstats.ini, with the sent file(s) still listed, which is exactly why it looked so contradictory.
binkp/1.0 peers (Mystic) send only a single EOB each and close differently, so those were always recorded correctly - matching the MYSTIC-works / SBBS-fails pattern you spotted.
Fixed in git just now: a closing-EOB send failure is ignored once all
of your sent files have been acknowledged. You'll see correct accounting (and JSBinkP/6 in the vers= field) after your next update + recycle.
Thanks to you both for the clear reports and log snippets - they made this an easy one to pin down.
Digital Man - an update on this... It's actually not related to the above context, but I'm now noticing in my logs a "warning" line that wasn't there before the above fix. I'm now using "master/135f08f0f" and seeing the below in logs after every remote system connection here (I'm a Fidonet hub):
Jun 13 09:27:00 palantir synchronet: srvc 0013 BINKP [157.245.114.161] Connection accepted on 192.168.254.70 port 24554 from port 59274
Jun 13 09:27:01 palantir synchronet: srvc 0013 BINKP BinkIT/2.42 invoked with options:
Jun 13 09:27:01 palantir synchronet: srvc 0013 BINKP JSBinkP/6 inbound connection from 157.245.114.161:59274
Jun 13 09:27:01 palantir synchronet: srvc 0013 BINKP Will encrypt session. Jun 13 09:27:01 palantir synchronet: srvc 0013 BINKP Peer version: BinkIT/2.42,JSBinkP/4,sbbs3.21a/Linux
Jun 13 09:27:01 palantir synchronet: srvc 0013 BINKP Remote addresses: 1:135/260@fidonet
Jun 13 09:27:01 palantir synchronet: srvc 0013 BINKP Inbound session for: 1:135/260@fidonet
Jun 13 09:27:01 palantir synchronet: srvc 0013 BINKP CRAM-MD5 password match for 1:135/260@fidonet
Jun 13 09:27:02 palantir synchronet: srvc 0013 BINKP !JavaScript warning /sbbs/exec/binkit.js line 1268: Disconnected
Jun 13 09:27:02 palantir synchronet: srvc 0013 BINKP [157.245.114.161] JavaScript service thread terminated (0 clients remain, 0 total, 68 served)
Hopefully the long lines didn't make it impossible to read, but the line I'm talking about is the "!JavaScript warning... line 1268: Disconnected".
That didn't used to be there, and now happens on every single inbound poll/connection, whether they transfer any mail packets or not.
Understood that it's "just a warning" but seems odd... Thanks.
Apparently binkit.js is EXITING at that failure and therefore... the
very last line of binkit.js which is "touch_semaphores();" never gets
called.
| Sysop: | DaiTengu |
|---|---|
| Location: | Appleton, WI |
| Users: | 1,123 |
| Nodes: | 10 (0 / 10) |
| Uptime: | 45:20:44 |
| Calls: | 14,369 |
| Calls today: | 1 |
| Files: | 186,376 |
| D/L today: |
7,690 files (2,489M bytes) |
| Messages: | 2,538,831 |