• sbbsecho routing problem with points

    From Ragnarok@VERT/DOCKSUD to DOVE-Net.Synchronet_Discussion on Thu Apr 9 03:54:46 2020
    (sorry the long message and my poor english)

    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.

    ---
    ■ Synchronet ■ Dock Sud BBS TLD 24 HS - bbs.docksud.com.ar