1. Forum
  2. FidoNet
  3. SYNC SYSOPS

  • qnet-http doesn't send REP packets

    From A C@1:103/705 to GitLab issue in main/sbbs on Sun Dec 5 09:55:37 2021
    open https://gitlab.synchro.net/main/sbbs/-/issues/310

    As the title says, packets can be retrieved by the calling system but it doesn't appear any attempt to upload REP packets occurs.When trying to utilize qnet-http, a session starts with something like:Dec 3 12:49:13 home synchronet: evnt QNET Updating /sbbs/data/DOVEMOD.REPDec 3 12:49:13 home synchronet: evnt QNET Packing NetMail for DOVEMODDec 3 12:49:13 home synchronet: evnt QNET Packed 0 NetMail messagesPlus whatever subs need to send messages.The call looks like:Dec 3 12:49:13 home synchronet: evnt QNET Call-out: DOVEMODDec 3 12:49:13 home synchronet: evnt QNET QNET-HTTP 1.3 invoked with options: -s DOVEMOD dove-mod.isurf.ca passwdDec 3 12:49:13 home synchronet: evnt QNET Getting /sbbs/data/DOVEMOD.qwkDec 3 12:49:16 home synchronet: evnt QNET Received 6040 bytesDec 3 12:49:16 home synchronet: evnt QNET Call-out to: DOVEMOD returned 0And that's it.On the server/hub side:Dec 03 12:49:13 [synchronet] web 0044 HTTP connection accepted from: 208.87.97.51 port 58876Dec 03 12:49:13 [synchronet] web 0044 Request: GET /qwk.ssjs HTTP/1.0Dec 03 12:49:13 [synchronet] web 0044 QWK Packet Handler (qwk.ssjs) 1.2 Dec 03 12:49:13 [synchronet] web 0044 Requesting creation of QWK packet: /home/sbbs/data/file/0003.qwk Dec 03 12:49:15 [synchronet] evnt packQWK QWK pack semaphore signaled: /home/sbbs/data/pack0003.nowDec 03 12:49:15 [synchronet] evnt packQWK <BSMNTQQ> Packing QWK Message PacketDec 03 12:49:15 [synchronet] evnt packQWK <BSMNTQQ> scanned 23 sub-boards for new messagesDec 03 12:49:15 [synchronet] evnt packQWK <BSMNTQQ> packed 11 messages (10.2K bytes) in 1 seconds (11 msgs/sec)Dec 03 12:49:15 [synchronet] evnt packQWK <BSMNTQQ> libarchive created /home/sbbs/data/file/0003.qwk from 8 filesDec 03 12:49:15 [synchronet] evnt packQWK <BSMNTQQ> Packing completed: /home/sbbs/data/file/0003.qwkDec 03 12:49:15 [synchronet] web 0044 Sending file: /home/sbbs/temp/SBBS_SSJS.9920.44.html (6040 bytes)Dec 03 12:49:15 [synchronet] web 0044 Sent file: /home/sbbs/temp/SBBS_SSJS.9920.44.html (6040 bytes, 6040 cps)Dec 03 1

    2:49:16 [synchronet] web 0044 HTTP connection accepted from: 208.87.97.51 port 58896Dec 03 12:49:16 [synchronet] web 0044 Request: POST /qwk.ssjs?received=6040 HTTP/1.0Dec 03 12:49:16 [synchronet] web 0044 QWK Packet Handler (qwk.ssjs) 1.2 Dec 03 12:49:16 [synchronet] web 0044 query: {"received":["6040"]} Dec 03 12:49:16 [synchronet] web 0044 Received confirmation of successful QWK packet receipt Dec 03 12:49:16 [synchronet] web 0044 Session thread terminated (1 clients, 4 threads remain, 996 served)Dec 03 12:49:17 [synchronet] web 0044 Session thread terminated (0 clients, 2 threads remain, 996 served)Perhaps better information would be retrieved with debug log sessions, and if nobody else has any qnet-http debug logs, I could certainly work at generating some.
    --- SBBSecho 3.14-Linux
    * Origin: Vertrauen - [vert/cvs/bbs].synchro.net (1:103/705)
  • From Deon George@1:103/705 to GitLab note in main/sbbs on Wed Jan 25 02:17:10 2023
    https://gitlab.synchro.net/main/sbbs/-/issues/310#note_3142

    Hey Rob,I’ve been doing some testing with qnet-http between my two systems ALTTEST/ALTERANT, and I don’t see any issue as seen in https://gitlab.synchro.net/main/sbbs/-/issues/310ALTERANT is 3.19 (fb4e4ce96)ALTTEST is 3.20 (cadada4df)As an example, I posted a message in General on ALTTEST```synchronet: term Node 1 <deon> posted on 0072:DOVE-Net General```And then called ALTERANT```ALTTEST:synchronet: evnt QNET Packing Sub-board...synchronet: evnt QNET Packed: 0 of 1synchronet: evnt QNET libarchive created /opt/sbbs/data/ALTERANT.REP from 3 filessynchronet: evnt QNET Call-out: ALTERANTsynchronet: evnt QNET Executing external: *qnet-http ALTERANT alterant.dege.lan TEST1234synchronet: evnt QNET QNET-HTTP 1.3 invoked with options: ALTERANT alterant.dege.lan TEST1234synchronet: evnt QNET Sending /opt/sbbs/data/ALTERANT.repsynchronet: evnt QNET Getting /opt/sbbs/data/ALTERANT.qwksynchronet: evnt QNET !JavaScript /opt/sbbs/exec/load/http.js line 146: Error: Unable to read statussynchronet: evnt QNET Call-out to: ALTERANT returned 0ALTERANT:Jan 25 21:03:57 d-11-1 synchronet: web 0101 HTTP connection accepted from: 10.1.3.21 port 46172Jan 25 21:03:57 d-11-1 synchronet: web 0101 HTTP [10.1.3.21] Request: POST /qwk.ssjs HTTP/1.0Jan 25 21:03:57 d-11-1 synchronet: web 0101 QWK Packet Handler (qwk.ssjs) 1.2Jan 25 21:03:57 d-11-1 synchronet: web 0101 query: {"PK\u0003\u0004\u0014":[""]}Jan 25 21:03:57 d-11-1 synchronet: web 0101 received REP packet: 1072 bytesJan 25 21:03:57 d-11-1 synchronet: web 0101 Sending file: /opt/sbbs/temp/SBBS_SSJS.20.101.html (0 bytes)Jan 25 21:03:57 d-11-1 synchronet: web 0102 HTTP connection accepted from: 10.1.3.21 port 46174Jan 25 21:03:57 d-11-1 synchronet: web 0102 HTTP [10.1.3.21] Request: GET /qwk.ssjs HTTP/1.0Jan 25 21:03:58 d-11-1 synchronet: web 0102 QWK Packet Handler (qwk.ssjs) 1.2Jan 25 21:03:58 d-11-1 synchronet: web 0102 Requesting creation of QWK packet: /opt/sbbs/data/file/0028.qwkJan 25 21:03:58 d-11-1 synchronet: web 0101 HTTP [10.1.3.21] Session thread terminate

    d (1 clients, 4 threads remain, 50 served)Jan 25 21:04:15 d-11-1 synchronet: srvc 0103 ActiveUser-UDP connection accepted from: 216.96.52.22 port 46516Jan 25 21:04:15 d-11-1 synchronet: srvc 0103 ActiveUser-UDP service thread terminated (1 clients remain, 1 total, 943 served)Jan 25 21:04:33 d-11-1 synchronet: evnt unpackREP <ALTTEST> posted QWK message on 0011:DOVE-net GeneralJan 25 21:04:33 d-11-1 synchronet: evnt unpackREP <ALTTEST> Unpacking completed: /opt/sbbs/data/file/0028.rep (1 msgs, 0 errors, 0 dupes)Jan 25 21:04:33 d-11-1 synchronet: evnt packQWK QWK pack semaphore signaled: /opt/sbbs/data/pack0028.nowJan 25 21:04:33 d-11-1 synchronet: evnt packQWK <ALTTEST> Packing QWK Message PacketJan 25 21:04:33 d-11-1 synchronet: evnt packQWK <ALTTEST> scanned 50 sub-boards for new messagesJan 25 21:04:33 d-11-1 synchronet: evnt packQWK <ALTTEST> packed 4 messages (5.6K bytes) in 1 seconds (4 msgs/sec)Jan 25 21:04:33 d-11-1 synchronet: evnt packQWK <ALTTEST> libarchive created /opt/sbbs/data/file/0028.qwk from 3 filesJan 25 21:04:33 d-11-1 synchronet: evnt packQWK <ALTTEST> Packing completed: /opt/sbbs/data/file/0028.qwkJan 25 21:04:33 d-11-1 synchronet: web 0102 Sending file: /opt/sbbs/temp/SBBS_SSJS.20.102.html (2359 bytes)Jan 25 21:04:33 d-11-1 synchronet: web 0102 Sent file: /opt/sbbs/temp/SBBS_SSJS.20.102.html (2359 bytes, 2359 cps)Jan 25 21:04:33 d-11-1 synchronet: web 0102 Unable to send to peer```The message made it to ALTERANT, in the General echo, which in time will make it to VERT.The only thing I’ve noticed is timeout issues (as captured above) - ALTTEST gives up waiting for ALTERANT to produce a QWK packet, but on a subsequent poll, the packet is collected immediately:```ALTTEST:synchronet: evnt QNET Call-out: ALTERANTsynchronet: evnt QNET Executing external: *qnet-http ALTERANT alterant.dege.lan TEST1234synchronet: evnt QNET QNET-HTTP 1.3 invoked with options: ALTERANT alterant.dege.lan TEST1234synchronet: evnt QNET Getting /opt/sbbs/data/ALTERANT.qwksynchronet: evnt QNET Received 2359 bytessyn

    chronet: evnt QNET Call-out to: ALTERANT returned 0synchronet: evnt QNET Inbound QWK Packet detected: /opt/sbbs/data/ALTERANT.qwksynchronet: evnt QNET libarchive extracted 3 files from /opt/sbbs/data/ALTERANT.qwksynchronet: evnt QNET Reading /opt/sbbs/temp/event/HEADERS.DATsynchronet: evnt QNET Reading /opt/sbbs/temp/event/VOTING.DATsynchronet: evnt QNET Importing QWK Network Packet: /opt/sbbs/data/ALTERANT.qwksynchronet: evnt QNET Importing QWK messages from ALTERANT into 0010:Private PrivateNet Testing Areasynchronet: evnt QNET Imported 4 QWK messages in 1 seconds (4 msgs/sec)synchronet: evnt QNET Finished Importing QWK Network Packet from ALTERANT: (4 msgs) in 1 seconds (4 msgs/sec), 0 errors, 0 dupesALTERANT:Jan 25 21:05:49 d-11-1 synchronet: web 0101 HTTP connection accepted from: 10.1.3.21 port 46624Jan 25 21:05:49 d-11-1 synchronet: web 0101 HTTP [10.1.3.21] Request: GET /qwk.ssjs HTTP/1.0Jan 25 21:05:49 d-11-1 synchronet: web 0101 QWK Packet Handler (qwk.ssjs) 1.2Jan 25 21:05:49 d-11-1 synchronet: web 0101 Sending file: /opt/sbbs/temp/SBBS_SSJS.20.101.html (2359 bytes)Jan 25 21:05:49 d-11-1 synchronet: web 0101 Sent file: /opt/sbbs/temp/SBBS_SSJS.20.101.html (2359 bytes, 2359 cps)Jan 25 21:05:49 d-11-1 synchronet: web 0102 HTTP connection accepted from: 10.1.3.21 port 46626Jan 25 21:05:49 d-11-1 synchronet: web 0102 HTTP [10.1.3.21] Request: POST /qwk.ssjs?received=2359 HTTP/1.0Jan 25 21:05:49 d-11-1 synchronet: web 0102 QWK Packet Handler (qwk.ssjs) 1.2Jan 25 21:05:49 d-11-1 synchronet: web 0102 query: {"received":["2359"]}Jan 25 21:05:49 d-11-1 synchronet: web 0102 Received confirmation of successful QWK packet receipt```So there doesnt seem to be an issue?
    --- SBBSecho 3.20-Linux
    * Origin: Vertrauen - [vert/cvs/bbs].synchro.net (1:103/705)