Re: error to get netmail
By: Fernando Toledo to Fidonet.HOTDOGED on Thu Apr 09 2020 00:33:11

Mira Carlos, Este era el reporte que envie hace un tiempo:

(sorry the long message and my poor english)

Hi all i write this message to Synchornet support area and now here too.

I try to detect an error that hotdoged won't receive netmail in some case.

I wish if hotdoged author can add debug setting to log file for
importing mesage stage, to help me to find the issue

Thanks!

-------------------------
My settings:

I have 2 setup points at my sbbsecho.ini (same settings for each point, obviously changed the name/password/comment)

4:902/26.3 and 4:902/26.10

Ex:

[node:4:902/26.3@fidonet]
Name = Fernando Toledo

Comment = movil

Archive = ZIP

PacketType = 2+

PacketPwd = **
AreaFix = true

AreaFixPwd = **
SessionPwd = **
TicFilePwd = **
Inbox =

Outbox =

Passive = false

Direct = false

Notify = false

Keys =

Status = Hold

LocalAddress = 4:902/26

GroupHub =

BinkpHost =

BinkpPort = 24554

BinkpPoll = false

BinkpPlainAuthOnly = false

BinkpAllowPlainAuth = true

BinkpAllowPlainText = true

BinkpTLS = false

BinkpSourceAddress =

I have openxp5 as client for 4:902/26.3 and HotDogEd for 4:902/26.10

My bbs node is 4:902/26 and both client connect via binkIT to it

cases:

1) send/receive netmail between points (from .3 to .10 or vice versa) = working

2) send netmails from points to sbbs node = working

3) send from 4:902/26 to point 4:902/26.3 = working

4) echomails to both points = working

5) send netmail from 4:902/26 to point 4:902/26.10 = FAIL
HotdogtEd do not show the netmail.

Logs:

creating netmail to .10 from the bbs node (4:902/26)

Apr 8 23:27:54 cpe-001e8ccf4132 synchronet: term Node 1 <Ragnarok> '?slyedit /sbbs/node1/MSGTMP DCT' returned 0
Apr 8 23:27:54 cpe-001e8ccf4132 synchronet: term Node 1 <Ragnarok>
NetMail subject: prueba 1
Apr 8 23:27:54 cpe-001e8ccf4132 synchronet: term Node 1 <Ragnarok> sent NetMail to FERNANDO TOLEDO# (4:902/26.10)

sbbecho sems to make the .10 package

2020-04-08 23:28:50 Configured: 1 archivers, 14 linked-nodes, 3 echolists 2020-04-08 23:28:50 NetMail directory: /sbbs/fido/netmail/
2020-04-08 23:28:50 Secure Inbound directory: /sbbs/fido/inbound/
2020-04-08 23:28:50 Non-secure Inbound directory: /sbbs/fido/inbound.nosecure/
2020-04-08 23:28:50 Outbound (BSO root) directory: /sbbs/fido/outbound/ 2020-04-08 23:28:50 Read 213 areas from ../data/areas.bbs
2020-04-08 23:28:50 Read 0 areas from ../data/badareas.lst
2020-04-08 23:28:50 Read 292 echo statistics from ../data/echostats.ini 2020-04-08 23:28:50 Created NetMail (1.msg) from Fernando Toledo
(4:902/26) to FERNANDO TOLEDO# (4:902/26.10), attr: 0381, subject: prueba 1 2020-04-08 23:28:50 Packing NetMail (1.msg) from Fernando Toledo
(4:902/26) to FERNANDO TOLEDO# (4:902/26.10), attr: 0381, subject: prueba 1 2020-04-08 23:28:50 Node (4:902/26.10) successfully locked via: /sbbs/fido/outbound/0386001a.pnt/0000000a.bsy
2020-04-08 23:28:50 Adding NetMail (1.msg) to new packet for
4:902/26.10: /sbbs/fido/outbound/0386001a.pnt/0000000a.hut
2020-04-08 23:28:50 Deleting /sbbs/fido/netmail/1.msg (from line 5327) 2020-04-08 23:28:50 Touching outgoing semfile: ../data/binkout.now 2020-04-08 23:28:50 Writing 0 areas to ../data/badareas.lst
2020-04-08 23:28:50 Deleting
/sbbs/fido/outbound/0386001a.pnt/0000000a.bsy (from line 3032)
2020-04-08 23:28:50 Deleting /sbbs/ctrl/sbbsecho.bsy (from line 3038) 2020-04-08 23:28:50 SBBSecho (PID 14027) exiting with error level 0, NetMail(0 imported, 1 exported, 1 packed)

i use python-ftn tool to see packet detail, sems fine

root@cpe-001e8ccf4132:/tmp# ftn-scanpkt --debug 0000000a.hut
2020-04-08 23:31:18 app:DEBUG [14779] set umask to 0022.
2020-04-08 23:31:18 app:DEBUG [14779] reading config from /etc/ftn/ftn.cfg 2020-04-08 23:31:18 app:DEBUG [14779] finished generic setup
2020-04-08 23:31:18 message:DEBUG [14779] parsing a message
2020-04-08 23:31:18 message:DEBUG [14779] looking for FMPT
2020-04-08 23:31:18 message:DEBUG [14779] looking for TOPT
2020-04-08 23:31:18 message:DEBUG [14779] set destPoint = 10 ====================================================================== 0000000a.hut: 4:902/26 -> 4:902/26.10 @ 2020-04-08 23:28:50 ======================================================================

hotdoged call to the node and get the packet:

Apr 8 23:38:32 cpe-001e8ccf4132 synchronet: term Node 2 terminal type: 80x24 DUMB
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP connection accepted from: 192.168.10.30 port 50744
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP JavaScript service thread started
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP BinkIT/2.37 invoked with options:
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP
JSBinkP/1.122 inbound connection from 192.168.10.30:50744
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_NUL command args: OPT CRAM-MD5-0df9046bab0fd111b81c86b82dadc18c CRYPT
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_NUL command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_NUL command args: SYS Point 10
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_NUL command args: SYS Dock Sud BBS
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_NUL command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_NUL command args: ZYZ Ragnarok
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_NUL command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_NUL command args: LOC Dock Sud, Bs As, Argentina
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_NUL command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_NUL command args: NDL 115200,TCP,BINKP
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_NUL command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_NUL command args: TIME Wed Apr 08 2020 23:38:36 GMT-0300
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_NUL command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_NUL command args: VER BinkIT/2.37,JSBinkP/1.122,sbbs3.17c/Linux binkp/1.1 Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_NUL command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_ADR command args: 4:902/26@fidonet 666:1/1@latinet 21:2/151@fsxnet 618:500/45@micronet
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_ADR command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_NUL command args: ZYZ Fernando Toledo
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_NUL command args: LOC Android device
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_NUL command args: NDL 115200,TCP,BINKP
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_NUL command args: VER jNode/Android binkp/1.1
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Peer
version: jNode/Android binkp/1.1
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_NUL command args: TIME Wed, 08 Apr 2020 23:38:35 -0300
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_ADR command args: 4:902/26.10@fidonet
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_PWD command args: CRAM-MD5-XXXXXXXXXXXX
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Remote addresses: 4:902/26.10@fidonet
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Inbound
session for: 4:902/26.10@fidonet
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP CRAM-MD5 password match for 4:902/26.10@fidonet
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Adding
outbound files for 4:902/26.10@fidonet
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Locking /sbbs/fido/outbound/0386001a.pnt/0000000a.bsy
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Lock successful.
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Adding '/sbbs/fido/outbound/0386001a.pnt/0000000a.hut' as 'ef93z3i1.pkt'
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_OK command args: secure
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_OK command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
file: /sbbs/fido/outbound/0386001a.pnt/0000000a.hut (0.5KB)
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_FILE command args: ef93z3i1.pkt 532 1586399330 0
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_FILE command
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending 532 bytes of data
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent file: /sbbs/fido/outbound/0386001a.pnt/0000000a.hut (0.5KB)
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_EOB command args:
Apr 8 23:38:36 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_EOB command
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_EOB command args:
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP We got an M_EOB, but there are still 1 files pending M_GOT
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_EOB command args:
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_EOB command
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_GOT command args: ef93z3i1.pkt 532 1586399330
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Got M_EOB command args:
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sending
M_EOB command args:
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Sent M_EOB command
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Remote host closed socket
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Deleted
file: /sbbs/fido/outbound/0386001a.pnt/0000000a.hut
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP Unlocking /sbbs/fido/outbound/0386001a.pnt/0000000a.bsy.
Apr 8 23:38:37 cpe-001e8ccf4132 synchronet: srvc 0306 BINKP service
thread terminated (0 clients remain, 6 total, 21 served)

the netmail do not show at hotdoged
if i write netmail from the .3 point to .10.. is receive perfect.

global sbbsecho setting:

TempDirectory = ../temp/sbbsecho
ZoneBlindThreshold = 65535
StrictPacketPasswords = true
EchomailNotify = true
AutoAddSubs = true
BsyTimeout = 12H
BsoLockDelay = 10S
BsoLockAttempts = 60
MaxEchomailAge = 60D
MaxNetmailAge = 0S
RelayFilteredMsgs = false
DeleteNetmail = true
DeletePackets = true
IgnoreNetmailDestAddr = false
IgnoreNetmailSentAttr = false
IgnoreNetmailRecvAttr = false
IgnoreNetmailLocalAttr = false
DefaultRecipient = SYSOP
UseFTNDomains = false
OutgoingSemaphore=../data/binkout.now
IgnoreNetmailKillAttr = false
MinFreeDiskSpace = 10M
StripSoftCRs = false
UseOutboxes = true
AutoAddToAreaFile = true
AutoUTF8 = true

I'm test with several configs and i can not detect the failure
Why the netmail is received from point .3 and not from the bbs node?

i'm run out of ideas

=|

ps: i will write to hotdoged author too. i hope that can add some debug
log to helpme to find the issue.

--
Ragnarok

Dock Sud BBS
https://bbs.docksud.com.ar
tw: @docksud
fb: DockSudBBS
--- SBBSecho 3.14-Linux
* Origin: Dock Sud BBS - http://bbs.docksud.com.ar (4:902/26)