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.REP
Dec 3 12:49:13 home synchronet: evnt QNET Packing NetMail for DOVEMOD
Dec 3 12:49:13 home synchronet: evnt QNET Packed 0 NetMail messages

Plus whatever subs need to send messages.

The call looks like:

Dec 3 12:49:13 home synchronet: evnt QNET Call-out: DOVEMOD
Dec 3 12:49:13 home synchronet: evnt QNET QNET-HTTP 1.3 invoked with options: -s DOVEMOD dove-mod.isurf.ca passwd
Dec 3 12:49:13 home synchronet: evnt QNET Getting /sbbs/data/DOVEMOD.qwk
Dec 3 12:49:16 home synchronet: evnt QNET Received 6040 bytes
Dec 3 12:49:16 home synchronet: evnt QNET Call-out to: DOVEMOD returned 0

And 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 58876
Dec 03 12:49:13 [synchronet] web 0044 Request: GET /qwk.ssjs HTTP/1.0
Dec 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.now
Dec 03 12:49:15 [synchronet] evnt packQWK <BSMNTQQ> Packing QWK Message Packet Dec 03 12:49:15 [synchronet] evnt packQWK <BSMNTQQ> scanned 23 sub-boards for new messages
Dec 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 files
Dec 03 12:49:15 [synchronet] evnt packQWK <BSMNTQQ> Packing completed: /home/sbbs/data/file/0003.qwk
Dec 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 12:49:16 [synchronet] web 0044 HTTP connection accepted from: 208.87.97.51 port 58896
Dec 03 12:49:16 [synchronet] web 0044 Request: POST /qwk.ssjs?received=6040 HTTP/1.0
Dec 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.

---
þ Synchronet þ Vertrauen þ Home of Synchronet þ [vert/cvs/bbs].synchro.net

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/310

ALTERANT 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 1
synchronet: evnt QNET libarchive created /opt/sbbs/data/ALTERANT.REP from 3 files
synchronet: evnt QNET Call-out: ALTERANT
synchronet: evnt QNET Executing external: *qnet-http ALTERANT alterant.dege.lan TEST1234
synchronet: evnt QNET QNET-HTTP 1.3 invoked with options: ALTERANT alterant.dege.lan TEST1234
synchronet: evnt QNET Sending /opt/sbbs/data/ALTERANT.rep
synchronet: evnt QNET Getting /opt/sbbs/data/ALTERANT.qwk
synchronet: evnt QNET !JavaScript /opt/sbbs/exec/load/http.js line 146: Error: Unable to read status
synchronet: evnt QNET Call-out to: ALTERANT returned 0

ALTERANT:
Jan 25 21:03:57 d-11-1 synchronet: web 0101 HTTP connection accepted from: 10.1.3.21 port 46172
Jan 25 21:03:57 d-11-1 synchronet: web 0101 HTTP [10.1.3.21] Request: POST /qwk.ssjs HTTP/1.0
Jan 25 21:03:57 d-11-1 synchronet: web 0101 QWK Packet Handler (qwk.ssjs) 1.2 Jan 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 bytes Jan 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 46174
Jan 25 21:03:57 d-11-1 synchronet: web 0102 HTTP [10.1.3.21] Request: GET /qwk.ssjs HTTP/1.0
Jan 25 21:03:58 d-11-1 synchronet: web 0102 QWK Packet Handler (qwk.ssjs) 1.2 Jan 25 21:03:58 d-11-1 synchronet: web 0102 Requesting creation of QWK packet: /opt/sbbs/data/file/0028.qwk
Jan 25 21:03:58 d-11-1 synchronet: web 0101 HTTP [10.1.3.21] Session thread terminated (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 46516
Jan 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 General
Jan 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.now
Jan 25 21:04:33 d-11-1 synchronet: evnt packQWK <ALTTEST> Packing QWK Message Packet
Jan 25 21:04:33 d-11-1 synchronet: evnt packQWK <ALTTEST> scanned 50 sub-boards for new messages
Jan 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 files
Jan 25 21:04:33 d-11-1 synchronet: evnt packQWK <ALTTEST> Packing completed: /opt/sbbs/data/file/0028.qwk
Jan 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: ALTERANT
synchronet: evnt QNET Executing external: *qnet-http ALTERANT alterant.dege.lan TEST1234
synchronet: evnt QNET QNET-HTTP 1.3 invoked with options: ALTERANT alterant.dege.lan TEST1234
synchronet: evnt QNET Getting /opt/sbbs/data/ALTERANT.qwk
synchronet: evnt QNET Received 2359 bytes
synchronet: evnt QNET Call-out to: ALTERANT returned 0
synchronet: evnt QNET Inbound QWK Packet detected: /opt/sbbs/data/ALTERANT.qwk synchronet: evnt QNET libarchive extracted 3 files from /opt/sbbs/data/ALTERANT.qwk
synchronet: evnt QNET Reading /opt/sbbs/temp/event/HEADERS.DAT
synchronet: evnt QNET Reading /opt/sbbs/temp/event/VOTING.DAT
synchronet: evnt QNET Importing QWK Network Packet: /opt/sbbs/data/ALTERANT.qwk synchronet: evnt QNET Importing QWK messages from ALTERANT into 0010:Private PrivateNet Testing Area
synchronet: 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 dupes

ALTERANT:
Jan 25 21:05:49 d-11-1 synchronet: web 0101 HTTP connection accepted from: 10.1.3.21 port 46624
Jan 25 21:05:49 d-11-1 synchronet: web 0101 HTTP [10.1.3.21] Request: GET /qwk.ssjs HTTP/1.0
Jan 25 21:05:49 d-11-1 synchronet: web 0101 QWK Packet Handler (qwk.ssjs) 1.2 Jan 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 46626
Jan 25 21:05:49 d-11-1 synchronet: web 0102 HTTP [10.1.3.21] Request: POST /qwk.ssjs?received=2359 HTTP/1.0
Jan 25 21:05:49 d-11-1 synchronet: web 0102 QWK Packet Handler (qwk.ssjs) 1.2 Jan 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?

---
þ Synchronet þ Vertrauen þ Home of Synchronet þ [vert/cvs/bbs].synchro.net