Closed wb8tyw closed 11 months ago
It is currently very hard to fix issues with setting radio properties, so can not add an option for select which xon/xoff algorithm to use for testing and getting data.
tested from Win10 with IC2820 to other Win with 0.3.9 + ID5100:
log on my side
0/25/2023 18:34:31:INFO:SessionManager:incoming:Received frame for station `!'
10/25/2023 18:34:31:INFO:SessionManager:incoming: Received block 1:0 for session `rpc'
10/25/2023 18:34:31:INFO:RPCSession:__job_state: Job state: complete for 1: {'Hello.txt': '40 B (2023-04-19 10:18:16)', 'HelloIZ2LXI.txt': '54 B (2023-04-19 09:40:53)', 'newemail.wav': '6 KB (2023-06-13 10:
34:32)', 'Red Alert.wav': '9 KB (2023-06-13 10:35:03)', 'Sonar_pings.wav': '79 KB (2023-06-13 09:37:16)', 'switch.c': '178 B (2023-10-07 15:09:25)'}
10/25/2023 18:34:31:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 2.0 sec before transmitting
10/25/2023 18:34:33:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:34:33:INFO:Utils.log_exception:----------------
10/25/2023 18:34:33:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:34:33:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:34:34:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:34:34:INFO:Utils.log_exception:----------------
10/25/2023 18:34:34:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:34:35:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:34:35:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:34:35:INFO:Utils.log_exception:----------------
10/25/2023 18:34:35:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:34:37:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:34:38:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:34:38:INFO:Utils.log_exception:----------------
10/25/2023 18:34:38:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:34:41:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:34:41:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:34:41:INFO:Utils.log_exception:----------------
10/25/2023 18:34:41:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:34:45:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:34:45:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:34:45:INFO:Utils.log_exception:----------------
10/25/2023 18:34:45:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:34:50:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:34:51:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:34:51:INFO:Utils.log_exception:----------------
10/25/2023 18:34:51:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:34:57:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:34:57:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:34:57:INFO:Utils.log_exception:----------------
10/25/2023 18:34:57:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:35:04:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:35:05:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:35:05:INFO:Utils.log_exception:----------------
10/25/2023 18:35:05:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:35:13:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:35:13:INFO:Utils.log_exception:-- Exception: --
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 769, in write
self._serial.write(buf)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 441, in write
self._write(data)
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/comm.py", line 413, in _write
serial.Serial.write(self, data[pos:pos+chunk])
File "C:/msys64/mingw64/lib/python3.11/site-packages/serial/serialwin32.py", line 325, in write
raise SerialTimeoutException('Write timeout')
serial.serialutil.SerialTimeoutException: Write timeout
10/25/2023 18:35:13:INFO:Utils.log_exception:----------------
10/25/2023 18:35:13:INFO:Transporter:__send: [SERIAL COM4@9600] Data path IO error: Failed to write to serial port [SERIAL COM4@9600] Write timeout
10/25/2023 18:35:22:INFO:Transporter:__send: [SERIAL COM4@9600] Attempting reconnect...
10/25/2023 18:35:22:INFO:Transporter:worker: [SERIAL COM4@9600] Exception while sending frames
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/transport.py", line 457, in worker
self.send_frames()
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/transport.py", line 377, in send_frames
self.__send(frame.get_packed())
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/transport.py", line 205, in __send
raise DataPathNotConnectedError("Unable to reconnect %s" % self.pipe)
d_rats.dratsexception.DataPathNotConnectedError: Unable to reconnect [SERIAL COM4@9600]
10/25/2023 18:35:22:INFO:Transporter:__worker: [SERIAL COM4@9600] Waiting for reconnection...
Missed turning off the driver xon/xoff code. The software xon/xoff pretty much does nothing when that is on.
At some point we need to find a way to capture a data stream from radios where driver xon/xoff is not working to find out why packets are being dropped.
My guess is that the radio is may be sending an xoff when it goes into transmit mode and an xon when it completes, and it ignores all characters in between.
Driver xon/xoff usually expects that the xoff is sent as a warning before characters are discarded.
Tested RF file transfer between:
Results:
Mac Acting as server to share file:
10/26/2023 18:37:08:INFO:Transporter:send_frames: [SERIAL /dev/tty.usbserial-1140@9600] Waiting 1.4 sec before transmitting
10/26/2023 18:37:10:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:37:10:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:37:16:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:38:02:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:38:02:INFO:SessionManager:incoming: Received block 0:5 for session `chat'
10/26/2023 18:38:18:INFO:Transporter:send_frames: [SERIAL /dev/tty.usbserial-1140@9600] Waiting 1.9 sec before transmitting
10/26/2023 18:38:23:INFO:Transporter:send_frames: [SERIAL /dev/tty.usbserial-1140@9600] Waiting 1.9 sec before transmitting
10/26/2023 18:38:35:INFO:SessionManager:incoming:Received frame for station `!'
RPC: _decode_rpccall jobtype, args RPCFileListJob
10/26/2023 18:38:36:INFO:SessionManager:incoming: Received block 1:0 for session `rpc'
10/26/2023 18:38:36:INFO:RPCSession:__job_state: Job state: complete for 1: {'newemail.wav': '6 KB (2023-06-13 09:55:20)', 'Red Alert.wav': '9 KB (2023-06-13 09:53:25)', 'HelloIZ2LXI.txt': '54 B (2023-04-21 18:44:07)', 'switch.c': '178 B (2023-10-25 19:40:17)', 'Sonar_pings.wav': '79 KB (2023-06-13 10:40:08)', 'Email Exercise PART 1.txt': '726 B (2023-07-06 19:10:17)', 'Hello.txt': '40 B (2023-07-09 09:50:51)', 'ding.wav': '78 KB (2023-10-08 18:59:36)'}
10/26/2023 18:38:36:INFO:Transporter:send_frames: [SERIAL /dev/tty.usbserial-1140@9600] Waiting 1.4 sec before transmitting
10/26/2023 18:38:38:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:38:38:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:38:38:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x11'`
10/26/2023 18:38:38:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:38:43:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
Log collected at Windows with D-Rats 0.3.9:
![image](https://github.com/ham-radio-software/D-Rats/assets/7696314/1c553942-4a11-418d-908a-4e59b805f17d)
![image](https://github.com/ham-radio-software/D-Rats/assets/7696314/c3fd546b-c287-47ad-a666-6c1783c72382)
Retrieving file from Windo10 0.3.9 to Mac with 0.4.
10/26/2023 18:43:13:INFO:RPCSession:Sending job `File list request' to None
10/26/2023 18:43:13:INFO:RPCSession:Job sent
10/26/2023 18:43:14:INFO:Transporter:send_frames: [SERIAL /dev/tty.usbserial-1140@9600] Waiting 1.3 sec before transmitting
10/26/2023 18:43:22:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:43:27:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:43:44:INFO:RPCSession:worker: Cancelling job 1 due to timeout
10/26/2023 18:43:44:INFO:RemoteFileV:_file_list_cb : Incomplete job
10/26/2023 18:43:49:INFO:RPCSession:Sending job `File list request' to None
10/26/2023 18:43:49:INFO:RPCSession:Job sent
10/26/2023 18:43:49:INFO:Transporter:send_frames: [SERIAL /dev/tty.usbserial-1140@9600] Waiting 1.1 sec before transmitting
10/26/2023 18:43:51:INFO:RPCSession:Sending job `File list request' to None
10/26/2023 18:43:51:INFO:RPCSession:Job sent
10/26/2023 18:43:51:INFO:Transporter:send_frames: [SERIAL /dev/tty.usbserial-1140@9600] Waiting 1.6 sec before transmitting
10/26/2023 18:44:00:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
file transfer between win10 + 0.4.1.dev20-gda8b99d-dirty and wind10 0.3.9 via VHF
10/26/2023 18:48:52:INFO:MainApp:start_comms: Starting port ports_1 (COM4)
10/26/2023 18:48:52:INFO:SWFSerial:Software XON/XOFF control initialized
10/26/2023 18:48:52:INFO:MainApp:_refresh_comms: Re-Starting ports_2
10/26/2023 18:48:52:INFO:MainApp:Starting Comms
10/26/2023 18:48:52:INFO:MainApp:_refresh_comms: Re-Starting ports_3
10/26/2023 18:48:52:INFO:MainApp:Starting Comms
10/26/2023 18:48:52:INFO:MainApp:_refresh_comms: Re-Starting ports_4
10/26/2023 18:48:52:INFO:MainApp:Starting Comms
10/26/2023 18:48:52:INFO:MainApp:_refresh_comms: Re-Starting ports_5
10/26/2023 18:48:52:INFO:MainApp:Starting Comms
10/26/2023 18:48:52:INFO:MainApp:_refresh_comms: Re-Starting ports_6
10/26/2023 18:48:52:INFO:MainApp:Starting Comms
10/26/2023 18:48:52:INFO:MainApp:_refresh_comms: Re-Starting ports_7
10/26/2023 18:48:52:INFO:MainApp:Starting Comms
10/26/2023 18:48:52:INFO:MainApp:_refresh_comms: Path [NET alabama.ratflector.com:9000-IZ2LXI-W] for
port net:alabama.ratflector.com:9000 no longer needed
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Ports expected to be already started:
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: COM4
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Checking all Ports from config:
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: portid ports_0
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Port alabama.ratflector.com not started
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: portid ports_1
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Port COM4 already started!
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: portid ports_2
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Port ideapad500 not started
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: portid ports_3
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Port k3pdr.dstargateway.org not started
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: portid ports_4
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Port locla IP not started
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: portid ports_5
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Port macbook not started
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: portid ports_6
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Port RAT not started
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: portid ports_7
10/26/2023 18:48:52:INFO:MainApp:check_comms_status: Port StTammany not started
10/26/2023 18:48:52:INFO:MainApp:_refresh_gps : GPS: False on @4800
10/26/2023 18:48:52:INFO:MainApp:_static_gps: Configuring the Static position: 45.800430,9.428440
10/26/2023 18:48:52:INFO:MainApp:_static_gps: Mapserver not active: False, call: IZ2LXI-W
10/26/2023 18:48:52:INFO:StaticGPSSource:StaticGPSPosition: SCONOSCIUTO relazione 45.8004,9.4284@0 f
t at 18:48:52
10/26/2023 18:48:52:INFO:MainApp:_refresh_map: reconfigure Mapwindow with new map
10/26/2023 18:48:52:INFO:MainApp:_refresh_map: reconfigured mapurl to: http://tile.thunderforest.com
/landscape/
10/26/2023 18:48:52:INFO:MainApp:_refresh_map: reconfigured mapkey to: ?apikey=5a1a4a79354244a38707d
83969fd88a1
10/26/2023 18:48:52:INFO:MainApp:_refresh_lang: Setting language to: Italian
10/26/2023 18:48:52:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Hello.txt'
10/26/2023 18:48:52:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/HelloIZ2LXI.txt'
10/26/2023 18:48:52:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/newemail.wav'
10/26/2023 18:48:52:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Red Alert.wav'
10/26/2023 18:48:52:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Sonar_pings.wav'
10/26/2023 18:48:52:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/switch.c'
10/26/2023 18:49:00:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.8 sec before transmit
ting
10/26/2023 18:49:26:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:49:26:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:49:33:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.5 sec before transmit
ting
10/26/2023 18:49:37:INFO:StationsList:_menu_handler: requesting position to all known stations
10/26/2023 18:49:37:INFO:RPCSession:Sending job `Position Request' to CQCQCQ
10/26/2023 18:49:37:INFO:RPCSession:Job sent
10/26/2023 18:49:37:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 0.7 sec before transmit
ting
10/26/2023 18:49:38:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:49:38:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:49:41:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:49:41:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRC'
10/26/2023 18:49:41:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCB'
10/26/2023 18:49:41:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,'
10/26/2023 18:49:41:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,I'
10/26/2023 18:49:41:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FO'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APR'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRA'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,D'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DS'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/1649'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h45'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.8'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/00'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/009'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/00922.2'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/00922.23'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/00922.23E>/A'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/00922.23E>/A='
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/00922.23E>/A=0008'
10/26/2023 18:49:42:INFO:Transporter:_match_gps: [SERIAL COM4@9600] Didn't match:
b'$$CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/00922.23E>/A=00088'
10/26/2023 18:49:42:INFO:Transporter:_parse_gps: [SERIAL COM4@9600] Found GPS string: bytearray(b'$$
CRCBEE9,IZ2FOW>APRATS,DSTAR*:/164941h4539.85N/00922.23E>/A=000885\r')
10/26/2023 18:49:42:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:49:42:INFO:MainApp:__incoming_gps_fix: Export to external mapserver not active: False
10/26/2023 18:49:42:INFO:StaticGPSSource:StaticGPSPosition: IZ2FOW relazione 45.6642,9.3705@269 ft a
t 18:49:42
10/26/2023 18:49:47:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:50:06:INFO:RPCSession:Sending job `File list request' to None
10/26/2023 18:50:06:INFO:RPCSession:Job sent
10/26/2023 18:50:06:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 2.0 sec before transmit
ting
10/26/2023 18:50:08:INFO:RPCSession:worker: Cancelling job 1 due to timeout
10/26/2023 18:50:11:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:50:12:INFO:SessionManager:incoming: Received block 2:1 for session `rpc'
10/26/2023 18:51:40:INFO:StationsList:_menu_handler: executing ping
10/26/2023 18:51:40:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.4 sec before transmit
ting
10/26/2023 18:51:44:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:51:45:INFO:SessionManager:incoming: Received block 0:2 for session `chat'
10/26/2023 18:51:46:INFO:SessionManager:incoming: Received block 0:5 for session `chat'
10/26/2023 18:51:54:INFO:RPCSession:Sending job `File list request' to None
10/26/2023 18:51:54:INFO:RPCSession:Job sent
10/26/2023 18:51:54:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.5 sec before transmit
ting
10/26/2023 18:51:59:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:52:00:INFO:SessionManager:incoming: Received block 3:1 for session `rpc'
10/26/2023 18:52:09:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Hello.txt'
10/26/2023 18:52:09:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/HelloIZ2LXI.txt'
10/26/2023 18:52:09:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/newemail.wav'
10/26/2023 18:52:09:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Red Alert.wav'
10/26/2023 18:52:09:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Sonar_pings.wav'
10/26/2023 18:52:09:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/switch.c'
10/26/2023 18:52:23:INFO:RPCSession:Sending job `Request file switch.c' to None
10/26/2023 18:52:23:INFO:RPCSession:Job sent
10/26/2023 18:52:23:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.4 sec before transmit
ting
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'['`
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'S'`
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'"'`
10/26/2023 18:52:24:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'>'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'7'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xc8'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x08'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'!'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'!'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'['`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'E'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:25:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'['`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'S'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xdd'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x02'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'E'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'A'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x17'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'I'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'Z'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'2'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'F'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'W'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'I'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'Z'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'2'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'L'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'X'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'I'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'-'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'W'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'x'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xda'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x0b'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\n'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'p'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'v'`
10/26/2023 18:52:26:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xcb'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xcc'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'I'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xf5'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xc9'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b','`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'.'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xf1'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xca'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x92'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x05'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b')'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xbf'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x05'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b':'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'['`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'E'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:52:27:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:27:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:52:28:INFO:SessionManager:incoming: Received block 1:0 for session `rpc'
10/26/2023 18:52:28:INFO:RPCSession:__job_state: Job state: complete for 1: {'Hello.txt': '40 B (202
3-04-19 10:18:16)', 'HelloIZ2LXI.txt': '54 B (2023-04-19 09:40:53)', 'newemail.wav': '6 KB (2023-06-
13 10:34:32)', 'Red Alert.wav': '9 KB (2023-06-13 10:35:03)', 'Sonar_pings.wav': '79 KB (2023-06-13
09:37:16)', 'switch.c': '178 B (2023-10-07 15:09:25)'}
10/26/2023 18:52:28:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 0.5 sec before transmit
ting
10/26/2023 18:52:29:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:52:29:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:29:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x11'`
10/26/2023 18:52:29:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:31:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:52:31:INFO:ControlSession:ctl_new: New session 4 from remote
10/26/2023 18:52:31:INFO:ControlSession:ctl_new: sending ACK for session request for 4
10/26/2023 18:52:31:INFO:ControlSession:ctl_new: Got type: <class 'd_rats.sessions.file.FileTransfer
Session'>
10/26/2023 18:52:31:INFO:StatefulSession:queue_next: New limit is 4 (8/4), queueing 4
10/26/2023 18:52:31:INFO:StatefulSession:XFER STATUS:
10/26/2023 18:52:31:INFO:StatefulSession:worker: Session loop (None:switch.c)
10/26/2023 18:52:31:INFO:SessionManager:incoming: Received block 0:8 for session `control'
10/26/2023 18:52:31:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:52:31:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.5 sec before transmit
ting
10/26/2023 18:52:31:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:52:31:INFO:StatefulSession:queue_next: New limit is 4 (8/4), queueing 4
10/26/2023 18:52:31:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:31:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:52:31:INFO:SessionCoordinator:New session (in) of type: <class 'd_rats.sessions.file.F
ileTransferSession'>
10/26/2023 18:52:31:INFO:MainApp:Session Started In: [SESSION 4]: FileTransfer
10/26/2023 18:52:31:INFO:MainApp:Session Started In: [SESSION 4]: Trasferimento file di switch.c avv
iato con IZ2FOW
10/26/2023 18:52:31:INFO:MainApp:Session Started In: [SESSION 4]: In attesa avvio trasferimento [00%
]
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xdd'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x04'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x02'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x82'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'%'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\r'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'I'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'Z'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'2'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'F'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'W'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'I'`
10/26/2023 18:52:33:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'Z'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'2'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'L'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'X'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'I'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'-'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'W'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'x'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xda'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'+'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'J'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x96'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xf7'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\xf7'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x06'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x05'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'Q'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x8f'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'['`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'E'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:52:34:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:35:INFO:SessionManager:incoming: Received block 4:1 for session `rpc'
10/26/2023 18:52:38:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:52:39:INFO:SessionManager:incoming: Received block 0:4 for session `switch.c'
10/26/2023 18:52:39:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:52:39:INFO:StatefulSession:queue_next: New limit is 4 (8/4), queueing 4
10/26/2023 18:52:39:INFO:StatefulSession:recv_blocks: Got block 0
10/26/2023 18:52:39:INFO:StatefulSession:recv_blocks: Waiting OOO blocks: [0]
10/26/2023 18:52:39:INFO:StatefulSession:recv_blocks: Queuing now in-order block 0: DDT2+: 0:4:4 IZ2
FOW->IZ2LXI-W (b'\x8a\x00\x00\x00switch.c'...[12])
10/26/2023 18:52:39:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:39:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:52:39:INFO:MainApp:Session Started In: [SESSION 4]: In attesa avvio trasferimento [00%
]
10/26/2023 18:52:39:INFO:MainApp:Session Started In: [SESSION 4]: File in ricezione switch.c della d
imensione 138 [00%]
10/26/2023 18:52:39:INFO:StatefulSession:queue_next: New limit is 4 (8/4), queueing 4
10/26/2023 18:52:39:INFO:MainApp:Session Started In: [SESSION 4]: File in ricezione switch.c della d
imensione 138 [00%]
10/26/2023 18:52:39:INFO:StatefulSession:queue_next : Queuing 0 for send (4)
10/26/2023 18:52:39:INFO:MainApp:Session Started In: [SESSION 4]: File in ricezione switch.c della d
imensione 138 [00%]
10/26/2023 18:52:39:INFO:StatefulSession:write: Waiting for block 0 ACK to be received
10/26/2023 18:52:39:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:52:39:INFO:MainApp:Session Started In: [SESSION 4]: File in ricezione switch.c della d
imensione 138 [00%]
10/26/2023 18:52:39:INFO:StatefulSession:queue_next: New limit is 4 (8/4), queueing 3
10/26/2023 18:52:39:INFO:StatefulSession:send_blocks: Sending 0
10/26/2023 18:52:39:INFO:SessionManager:incoming: Received block 0:5 for session `switch.c'
10/26/2023 18:52:39:INFO:StatefulSession:send_reqack: Requesting ACK of blocks [0]
10/26/2023 18:52:39:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.6 sec before transmit
ting
10/26/2023 18:52:39:INFO:StatefulSession:send_blocks: Waiting for block to be sent
10/26/2023 18:52:41:INFO:StatefulSession:write: Block 0 is sent, waiting for ack
10/26/2023 18:52:41:INFO:StatefulSession:update_xmt: Average transmit rate: 155 bps
10/26/2023 18:52:41:INFO:StatefulSession:write: 0 No ACK received (probably canceled)
10/26/2023 18:52:41:INFO:StatefulSession:send_blocks: Block sent after: 1.714888
10/26/2023 18:52:41:INFO:MainApp:Session Started In: [SESSION 4]: File in ricezione switch.c della d
imensione 138 [00%] 0.00 B/s (Totale 2 B)
10/26/2023 18:52:41:INFO:StatefulSession:recv_blocks: Sending ACK for block 0
10/26/2023 18:52:41:INFO:MainApp:Session Started In: [SESSION 4]: In attesa primo blocco [00%] 0.00
B/s (Totale 2 B)
10/26/2023 18:52:41:INFO:StatefulSession:send_ack: sending ACK for blocks [0] ({'': b'\x00'})
10/26/2023 18:52:41:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:52:42:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:42:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:42:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:52:42:INFO:StatefulSession:is_timeout: ## Timeout for 35 bytes @ 80 bps: 12.0 sec
10/26/2023 18:52:42:INFO:StatefulSession:is_timeout : ## Remaining: 9.7 sec
10/26/2023 18:52:42:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:42:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:52:42:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x11'`
10/26/2023 18:52:42:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:52:43:INFO:StatefulSession:is_timeout: ## Timeout for 35 bytes @ 80 bps: 12.0 sec
10/26/2023 18:52:43:INFO:StatefulSession:is_timeout : ## Remaining: 8.5 sec
10/26/2023 18:52:43:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:43:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:52:44:INFO:StatefulSession:is_timeout: ## Timeout for 35 bytes @ 80 bps: 12.0 sec
10/26/2023 18:52:44:INFO:StatefulSession:is_timeout : ## Remaining: 7.4 sec
10/26/2023 18:52:44:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:44:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:52:45:INFO:StatefulSession:is_timeout: ## Timeout for 35 bytes @ 80 bps: 12.0 sec
10/26/2023 18:52:45:INFO:StatefulSession:is_timeout : ## Remaining: 6.4 sec
10/26/2023 18:52:45:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:45:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:52:45:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:52:46:INFO:StatefulSession:is_timeout: ## Timeout for 35 bytes @ 80 bps: 12.0 sec
10/26/2023 18:52:46:INFO:StatefulSession:is_timeout : ## Remaining: 5.3 sec
10/26/2023 18:52:46:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:46:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:52:46:INFO:SessionManager:incoming: Received block 0:1 for session `switch.c'
10/26/2023 18:52:46:INFO:StatefulSession:is_timeout: ## Timeout for 35 bytes @ 80 bps: 12.0 sec
10/26/2023 18:52:46:INFO:StatefulSession:is_timeout : ## Remaining: 5.1 sec
10/26/2023 18:52:46:INFO:StatefulSession:recv_blocks: Acked blocks: [0] (/1)
10/26/2023 18:52:46:INFO:StatefulSession:recv_blocks: This was not a full ACK
10/26/2023 18:52:46:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:46:INFO:MainApp:Session Started In: [SESSION 4]: In attesa primo blocco [00%] 0.00
B/s (Totale 2 B)
10/26/2023 18:52:46:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:52:51:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:52:51:INFO:SessionManager:incoming: Received block 0:5 for session `switch.c'
10/26/2023 18:52:51:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:52:51:INFO:StatefulSession:queue_next: New limit is 4 (8/4), queueing 4
10/26/2023 18:52:51:INFO:StatefulSession:recv_blocks: Sending ACK for block 0
10/26/2023 18:52:51:INFO:StatefulSession:send_ack: sending ACK for blocks [0] ({'': b'\x00'})
10/26/2023 18:52:51:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:52:51:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:52:52:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 2.0 sec before transmit
ting
10/26/2023 18:52:58:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:53:00:INFO:SessionManager:incoming: Received block 1:4 for session `switch.c'
10/26/2023 18:53:00:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:53:00:INFO:StatefulSession:queue_next: New limit is 4 (8/4), queueing 4
10/26/2023 18:53:00:INFO:StatefulSession:recv_blocks: Got block 1
10/26/2023 18:53:00:INFO:StatefulSession:recv_blocks: Waiting OOO blocks: [1]
10/26/2023 18:53:00:INFO:StatefulSession:recv_blocks: Queuing now in-order block 1: DDT2+: 1:4:4 IZ2
FOW->IZ2LXI-W (b'x\xdaU\xcd\xc1\n\xc20\x0c\x06\xe0{ \xef\x10&B\x07\xd2\xb1y\xb3\xea\xbd\x8fQ\xdb\x8e
\x05j+\xb6\xc3\x83\xf8\xeevl\x1e\xfc\x0f!$_\xc8\x8e\xa3\r\xb3\xf3t\xce\xc5q\x92\xd3\x15\x01\xe1n8\x8
a\x16\xe1\x8d@5\x1c\x0biU{M\x17\xea\xd5"\xf2\x8b\x8b\x9d\x84^\x915\xd9S\x7fZ\xf5/\x8fg\xbd\x1bE\xb3?
:j\x0e\xa4[\xb5\xc1\xa1\xc2\xae\xa3[H\xd6&\x1a\x10\xa4\x94\x08\xce\x8ff\x0e\xe5o\xe7\x98\xb61\xc2gy[
\xcb\x17(e+S'...[138])
10/26/2023 18:53:00:INFO:MainApp:Session Started In: [SESSION 4]: In attesa primo blocco [100%] 6.84
B/s (Totale 2 B)
10/26/2023 18:53:00:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:53:00:INFO:MainApp:Session Started In: [SESSION 4]: Ricezione [100%] 6.82 B/s (Totale
2 B)
10/26/2023 18:53:00:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:53:00:INFO:SessionManager:incoming: Received block 0:5 for session `switch.c'
10/26/2023 18:53:00:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:53:00:INFO:StatefulSession:queue_next: New limit is 4 (8/4), queueing 4
10/26/2023 18:53:00:INFO:StatefulSession:recv_blocks: Sending ACK for block 1
10/26/2023 18:53:00:INFO:StatefulSession:send_ack: sending ACK for blocks [1] ({'': b'\x01'})
10/26/2023 18:53:00:INFO:StatefulSession:worker: Session loop (4:switch.c)
10/26/2023 18:53:00:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:53:00:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.8 sec before transmit
ting
10/26/2023 18:53:07:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:53:08:INFO:StatefulSession:close: Got close request, joining thread...
10/26/2023 18:53:08:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:53:08:INFO:StatefulSession:recv_file: SESSION IS CLOSED
10/26/2023 18:53:08:INFO:StatefulSession:close: Thread is done, continuing with close
10/26/2023 18:53:08:INFO:SessionManager:incoming: Received block 0:1 for session `control'
10/26/2023 18:53:08:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.5 sec before transmit
ting
10/26/2023 18:53:08:INFO:MainApp:Session Started In: [SESSION 4]: Ricezione [129%] 6.25 B/s (Totale
2 B)
10/26/2023 18:53:08:INFO:MainApp:Session Started In: [SESSION 4]: Ricezione [100%] 6.25 B/s (Totale
2 B)
10/26/2023 18:53:08:INFO:MainApp:Session Started In: [SESSION 4]: Completa [100%] 6.25 B/s (Totale 2
B)
10/26/2023 18:53:08:INFO:MainApp:Session Started In: [SESSION 4]: Trasferimento Completato
10/26/2023 18:53:08:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Hello.txt'
10/26/2023 18:53:08:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/HelloIZ2LXI.txt'
10/26/2023 18:53:08:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/newemail.wav'
10/26/2023 18:53:08:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Red Alert.wav'
10/26/2023 18:53:08:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Sonar_pings.wav'
10/26/2023 18:53:08:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/switch.c'
10/26/2023 18:53:13:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:53:14:INFO:ControlSession:ctl_end: Session 4 ended but not registered
Traceback (most recent call last):
File "C:/msys64/home/mauri/D-Rats-PR-268/d_rats/sessions/control.py", line 123, in ctl_end
session = self._sm.sessions[ident]
~~~~~~~~~~~~~~~~~^^^^^^^
KeyError: 4
10/26/2023 18:53:14:INFO:SessionManager:incoming: Received block 0:1 for session `control'
10/26/2023 18:53:52:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:53:52:INFO:SessionManager:incoming: Received block 2:0 for session `rpc'
10/26/2023 18:53:52:INFO:RPCSession:__job_state: Job state: complete for 2: {'Hello.txt': '40 B (202
3-04-19 10:18:16)', 'HelloIZ2LXI.txt': '54 B (2023-04-19 09:40:53)', 'newemail.wav': '6 KB (2023-06-
13 10:34:32)', 'Red Alert.wav': '9 KB (2023-06-13 10:35:03)', 'Sonar_pings.wav': '79 KB (2023-06-13
09:37:16)', 'switch.c': '178 B (2023-10-26 18:53:08)'}
10/26/2023 18:53:52:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.7 sec before transmit
ting
10/26/2023 18:53:54:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:53:54:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:53:54:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x11'`
10/26/2023 18:53:54:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:54:00:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:54:38:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:54:39:INFO:SessionManager:incoming: Received block 3:0 for session `rpc'
10/26/2023 18:54:39:INFO:RPCSession:__job_state: Job state: complete for 3: {'Hello.txt': '40 B (202
3-04-19 10:18:16)', 'HelloIZ2LXI.txt': '54 B (2023-04-19 09:40:53)', 'newemail.wav': '6 KB (2023-06-
13 10:34:32)', 'Red Alert.wav': '9 KB (2023-06-13 10:35:03)', 'Sonar_pings.wav': '79 KB (2023-06-13
09:37:16)', 'switch.c': '178 B (2023-10-26 18:53:08)'}
10/26/2023 18:54:39:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.8 sec before transmit
ting
10/26/2023 18:54:41:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:54:41:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:54:41:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x11'`
10/26/2023 18:54:41:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:54:47:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:56:30:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:56:30:INFO:DDT2EncodedFrame:unpack: Checksum failed: 36331 != 12603
10/26/2023 18:56:30:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:56:31:INFO:SessionManager:incoming: Received block 0:5 for session `chat'
10/26/2023 18:56:56:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Hello.txt'
10/26/2023 18:56:56:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/HelloIZ2LXI.txt'
10/26/2023 18:56:56:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/newemail.wav'
10/26/2023 18:56:56:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Red Alert.wav'
10/26/2023 18:56:56:INFO:LocalFileV:refresh: Adding local file `C:\Users\mauri\Desktop\D-RATS Shared
/Sonar_pings.wav'
10/26/2023 18:57:01:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 2.0 sec before transmit
ting
10/26/2023 18:57:14:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.8 sec before transmit
ting
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'['`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'E'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'['`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'S'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'"'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:57:16:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x01'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x82'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x7f'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'='`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'@'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x05'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'I'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'~'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'c'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'i'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'a'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'o'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'3'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'['`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'E'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'O'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'B'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b']'`
10/26/2023 18:57:17:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:57:18:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:57:18:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:57:53:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:57:54:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:58:09:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:58:10:INFO:SessionManager:incoming: Received block 1:0 for session `rpc'
10/26/2023 18:58:10:INFO:RPCSession:__job_state: Job state: complete for 1: {'Hello.txt': '40 B (2023-04-19 10:18:16)', 'HelloIZ2LXI.txt': '54 B (2023-04-19 09:40:53)'
, 'newemail.wav': '6 KB (2023-06-13 10:34:32)', 'Red Alert.wav': '9 KB (2023-06-13 10:35:03)', 'Sonar_pings.wav': '79 KB (2023-06-13 09:37:16)'}
10/26/2023 18:58:10:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.9 sec before transmitting
10/26/2023 18:58:12:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:58:12:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:58:19:INFO:SessionManager:incoming: Received block 0:0 for session `chat'
10/26/2023 18:58:36:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:58:37:INFO:RPCActionSet:RPC_file_pll: Remote requested C:\Users\mauri\Desktop\D-RATS Shared/newemail.wav
10/26/2023 18:58:37:INFO:SessionCoordinator:send_file: Outgoing files: ['C:\\Users\\mauri\\Desktop\\D-RATS Shared/newemail.wav']
10/26/2023 18:58:37:INFO:SessionCoordinator:send_file: Started Session
10/26/2023 18:58:37:INFO:StatefulSession:queue_next: New limit is 4 (4/8), queueing 4
10/26/2023 18:58:37:INFO:StatefulSession:XFER STATUS:
10/26/2023 18:58:37:INFO:StatefulSession:worker: Session loop (None:newemail.wav)
10/26/2023 18:58:37:INFO:SessionManager:incoming: Received block 2:0 for session `rpc'
10/26/2023 18:58:37:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:58:37:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.7 sec before transmitting
10/26/2023 18:58:37:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:58:37:INFO:StatefulSession:queue_next: New limit is 4 (4/8), queueing 4
10/26/2023 18:58:37:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:37:INFO:StatefulSession:worker: Deep sleep
10/26/2023 18:58:37:INFO:RPCSession:__job_state: Job state: complete for 2: {'rc': 'OK'}
10/26/2023 18:58:37:INFO:SessionCoordinator:New session (out) of type: <class 'd_rats.sessions.file.FileTransferSession'>
10/26/2023 18:58:37:INFO:MainApp:Session Started In: [SESSION 5]: FileTransfer
10/26/2023 18:58:37:INFO:MainApp:Session Started In: [SESSION 5]: Trasferimento file di newemail.wav avviato con IZ2FOW
10/26/2023 18:58:37:INFO:StatefulSession:write: Waiting for session to open
10/26/2023 18:58:39:INFO:ControlSession:new_session: Sent request, blocking...
10/26/2023 18:58:39:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:58:39:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:58:42:INFO:StatefulSession:write: Waiting for session to open
10/26/2023 18:58:42:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:58:43:INFO:ControlSession:ctl_ack: Signaled waiting session thread (l=5 r=4)
10/26/2023 18:58:43:INFO:SessionManager:incoming: Received block 0:2 for session `control'
10/26/2023 18:58:43:INFO:ControlSession:new_session: Established session 5:4
10/26/2023 18:58:43:INFO:StatefulSession:worker: Awoke from deep sleep to some data
10/26/2023 18:58:43:INFO:StatefulSession:queue_next: New limit is 4 (4/8), queueing 4
10/26/2023 18:58:43:INFO:StatefulSession:queue_next: New limit is 4 (4/8), queueing 4
10/26/2023 18:58:43:INFO:StatefulSession:queue_next : Queuing 0 for send (4)
10/26/2023 18:58:43:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:43:INFO:StatefulSession:write: Waiting for block 0 ACK to be received
10/26/2023 18:58:43:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:43:INFO:StatefulSession:queue_next: New limit is 4 (4/8), queueing 3
10/26/2023 18:58:43:INFO:StatefulSession:send_blocks: Sending 0
10/26/2023 18:58:43:INFO:StatefulSession:send_reqack: Requesting ACK of blocks [0]
10/26/2023 18:58:43:INFO:StatefulSession:send_blocks: Waiting for block to be sent
10/26/2023 18:58:43:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.9 sec before transmitting
10/26/2023 18:58:45:INFO:StatefulSession:write: Block 0 is sent, waiting for ack
10/26/2023 18:58:45:INFO:StatefulSession:update_xmt: Average transmit rate: 192 bps
10/26/2023 18:58:45:INFO:StatefulSession:write: 0 No ACK received (probably canceled)
10/26/2023 18:58:45:INFO:StatefulSession:send_file: Waiting for start
10/26/2023 18:58:45:INFO:StatefulSession:send_blocks: Block sent after: 2.283210
10/26/2023 18:58:45:INFO:MainApp:Session Started In: [SESSION 5]: [00%] (Totale 16 B)
10/26/2023 18:58:45:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:45:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:45:INFO:MainApp:Session Started In: [SESSION 5]: In attesa di risposta [00%] (Totale 16 B)
10/26/2023 18:58:45:INFO:SWFSerial:is_xon: Aiee! Read a non-XOFF char: `b'\x13'`
10/26/2023 18:58:45:INFO:SWFSerial:is_xon: Assuming IXANY behavior
10/26/2023 18:58:46:INFO:StatefulSession:send_file: Waiting for start
10/26/2023 18:58:46:INFO:MainApp:Session Started In: [SESSION 5]: In attesa di risposta [00%] (Totale 16 B)
10/26/2023 18:58:46:INFO:StatefulSession:send_file: Waiting for start
10/26/2023 18:58:46:INFO:MainApp:Session Started In: [SESSION 5]: In attesa di risposta [00%] (Totale 16 B)
10/26/2023 18:58:46:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:46:INFO:StatefulSession:is_timeout : ## Remaining: 8.7 sec
10/26/2023 18:58:46:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:46:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:47:INFO:StatefulSession:send_file: Waiting for start
10/26/2023 18:58:47:INFO:MainApp:Session Started In: [SESSION 5]: In attesa di risposta [00%] (Totale 16 B)
10/26/2023 18:58:47:INFO:StatefulSession:send_file: Waiting for start
10/26/2023 18:58:47:INFO:MainApp:Session Started In: [SESSION 5]: In attesa di risposta [00%] (Totale 16 B)
10/26/2023 18:58:47:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:47:INFO:StatefulSession:is_timeout : ## Remaining: 7.7 sec
10/26/2023 18:58:47:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:47:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:48:INFO:StatefulSession:send_file: Waiting for start
10/26/2023 18:58:48:INFO:MainApp:Session Started In: [SESSION 5]: In attesa di risposta [00%] (Totale 16 B)
10/26/2023 18:58:48:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:58:48:INFO:StatefulSession:send_file: Waiting for start
10/26/2023 18:58:48:INFO:MainApp:Session Started In: [SESSION 5]: In attesa di risposta [00%] (Totale 16 B)
10/26/2023 18:58:48:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:48:INFO:StatefulSession:is_timeout : ## Remaining: 6.7 sec
10/26/2023 18:58:48:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:48:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:49:INFO:SessionManager:incoming: Received block 0:4 for session `newemail.wav'
10/26/2023 18:58:49:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:49:INFO:StatefulSession:is_timeout : ## Remaining: 6.4 sec
10/26/2023 18:58:49:INFO:StatefulSession:recv_blocks: Got block 0
10/26/2023 18:58:49:INFO:StatefulSession:recv_blocks: Waiting OOO blocks: [0]
10/26/2023 18:58:49:INFO:StatefulSession:recv_blocks: Queuing now in-order block 0: DDT2+: 0:5:4 IZ2FOW->IZ2LXI-W (b'OK'...[2])
10/26/2023 18:58:49:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:49:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:49:INFO:MainApp:Session Started In: [SESSION 5]: In attesa di risposta [00%] (Totale 16 B)
10/26/2023 18:58:49:INFO:StatefulSession:send_file: Waiting for start
10/26/2023 18:58:49:INFO:MainApp:Session Started In: [SESSION 5]: Negoziazione effettuata [00%] (Totale 16 B)
10/26/2023 18:58:49:INFO:StatefulSession:queue_next: New limit is 4 (4/8), queueing 3
10/26/2023 18:58:49:INFO:MainApp:Session Started In: [SESSION 5]: Negoziazione effettuata [00%] (Totale 16 B)
10/26/2023 18:58:49:INFO:StatefulSession:queue_next : Queuing 1 for send (3)
10/26/2023 18:58:49:INFO:MainApp:Session Started In: [SESSION 5]: Negoziazione effettuata [00%] (Totale 16 B)
10/26/2023 18:58:49:INFO:StatefulSession:queue_next : Queuing 2 for send (3)
10/26/2023 18:58:49:INFO:MainApp:Session Started In: [SESSION 5]: Sending [00%] (Totale 16 B)
10/26/2023 18:58:49:INFO:StatefulSession:queue_next : Queuing 3 for send (3)
10/26/2023 18:58:49:INFO:StatefulSession:write: Waiting for block 1 ACK to be received
10/26/2023 18:58:49:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:49:INFO:StatefulSession:is_timeout : ## Remaining: 6.2 sec
10/26/2023 18:58:49:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:49:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:49:INFO:SessionManager:incoming: Received block 0:5 for session `newemail.wav'
10/26/2023 18:58:49:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:49:INFO:StatefulSession:is_timeout : ## Remaining: 6.0 sec
10/26/2023 18:58:49:INFO:StatefulSession:recv_blocks: Sending ACK for block 0
10/26/2023 18:58:49:INFO:StatefulSession:send_ack: sending ACK for blocks [0] ({'': b'\x00'})
10/26/2023 18:58:49:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:49:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:49:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.6 sec before transmitting
10/26/2023 18:58:50:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:50:INFO:StatefulSession:is_timeout : ## Remaining: 4.9 sec
10/26/2023 18:58:50:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:50:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:51:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:51:INFO:StatefulSession:is_timeout : ## Remaining: 3.9 sec
10/26/2023 18:58:51:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:51:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:52:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:52:INFO:StatefulSession:is_timeout : ## Remaining: 2.9 sec
10/26/2023 18:58:52:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:52:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:53:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:53:INFO:StatefulSession:is_timeout : ## Remaining: 1.9 sec
10/26/2023 18:58:53:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:53:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:54:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:54:INFO:StatefulSession:is_timeout : ## Remaining: 0.9 sec
10/26/2023 18:58:54:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:54:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:55:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:55:INFO:StatefulSession:is_timeout : ## Remaining: -0.1 sec
10/26/2023 18:58:55:INFO:StatefulSession:queue_next: New limit is 4 (4/8), queueing 0
10/26/2023 18:58:55:INFO:StatefulSession:send_blocks: Didn't get last ack, asking again
10/26/2023 18:58:55:INFO:StatefulSession:send_reqack: Requesting ACK of blocks [0]
10/26/2023 18:58:55:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:55:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:55:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.3 sec before transmitting
10/26/2023 18:58:56:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:56:INFO:StatefulSession:is_timeout : ## Remaining: -1.1 sec
10/26/2023 18:58:56:INFO:StatefulSession:is_timeout: ## Waiting for ACK, timeout in 6
10/26/2023 18:58:56:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:56:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:57:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:57:INFO:StatefulSession:is_timeout : ## Remaining: -2.1 sec
10/26/2023 18:58:57:INFO:StatefulSession:is_timeout: ## Waiting for ACK, timeout in 5
10/26/2023 18:58:57:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:57:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:58:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:58:INFO:StatefulSession:is_timeout : ## Remaining: -3.1 sec
10/26/2023 18:58:58:INFO:StatefulSession:is_timeout: ## Waiting for ACK, timeout in 4
10/26/2023 18:58:58:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:58:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:58:59:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:58:59:INFO:StatefulSession:is_timeout : ## Remaining: -4.1 sec
10/26/2023 18:58:59:INFO:StatefulSession:is_timeout: ## Waiting for ACK, timeout in 3
10/26/2023 18:58:59:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:58:59:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:59:00:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:59:00:INFO:StatefulSession:is_timeout : ## Remaining: -5.1 sec
10/26/2023 18:59:00:INFO:StatefulSession:is_timeout: ## Waiting for ACK, timeout in 2
10/26/2023 18:59:00:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:59:00:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:59:00:INFO:SessionManager:incoming:Received frame for station `!'
10/26/2023 18:59:01:INFO:SessionManager:incoming: Received block 0:1 for session `newemail.wav'
10/26/2023 18:59:01:INFO:StatefulSession:is_timeout: ## Timeout for 49 bytes @ 80 bps: 12.0 sec
10/26/2023 18:59:01:INFO:StatefulSession:is_timeout : ## Remaining: -5.8 sec
10/26/2023 18:59:01:INFO:StatefulSession:is_timeout: ## Waiting for ACK, timeout in 2
10/26/2023 18:59:01:INFO:StatefulSession:recv_blocks: Acked blocks: [0] (/4)
10/26/2023 18:59:01:INFO:StatefulSession:recv_blocks: Block 1 outstanding, No ACK received
10/26/2023 18:59:01:INFO:StatefulSession:recv_blocks: Block 2 outstanding, No ACK received
10/26/2023 18:59:01:INFO:StatefulSession:recv_blocks: Block 3 outstanding, No ACK received
10/26/2023 18:59:01:INFO:StatefulSession:recv_blocks: This will ACK every block
10/26/2023 18:59:01:INFO:StatefulSession:worker: Session loop (5:newemail.wav)
10/26/2023 18:59:01:INFO:StatefulSession:worker: Outstanding data, short sleep
10/26/2023 18:59:01:INFO:MainApp:Session Started In: [SESSION 5]: Sending [00%] 1.34 B/s (Totale 16 B)
10/26/2023 18:59:02:INFO:StatefulSession:queue_next: New limit is 4 (4/8), queueing 1
10/26/2023 18:59:02:INFO:StatefulSession:queue_next : Queuing 4 for send (1)
10/26/2023 18:59:02:INFO:StatefulSession:send_blocks: Sending 1
10/26/2023 18:59:02:INFO:StatefulSession:send_blocks: Sending 2
10/26/2023 18:59:02:INFO:Transporter:send_frames: [SERIAL COM4@9600] Waiting 1.5 sec before transmitting
Brief recap:
0.4.x: on both Win and Mac
0.3.x
Look for this in the log messages:
Aiee! Read a non-XOFF
....
For 0.4 + this patch, this is recovering from a previous data lost condition.
When it shows up on 0.3 version or in any 0.4 version with out this patch, you have lost data, the transfer in progress is broken and what ever you were trying to receive will be lost.
This patch recovers the data and allows the transfer to to proceed, but is only present in systems with this patch. Any system with out this patch is going to eventually fail when it is trying to receive a large amount of data.
This pull request fixes it so that d-rats does not lose data received from the radio that d-rats used to discard with the 'Aiee' message written to the console. Not sure that message was showing up on Windows systems, especially the 0.3 branch.
With this patch, the send file on stations work for me, and the files section to connect, get a directory list and then download a file also works. But both d-rats versions were running with this patch. . This issue will also affect AX.25 TNC connections.
Not sure what is causing that error in the 0.3.9 log report. The data loss bug is present in that version and can cause the first byte of a packet to be tossed, and that will cause a packet to be sent to the rpc handler that is missing the first byte.
I have no way to debut the 0.3.9 version any more.
This first byte in packet being randomly discarded could also be the reasons that people are reporting errors with ping responses over radio links on the mailing list.
Before PR 254 was merged, D-Rats serial port behavior was:
Current Master with PR-254 merged:
With this pull request:
In my tests using a serial port between two D-Rats system with no radio, I can easily reproduce the issue of the "iee!" message showing up in a file transfer. I have not been able to reproduce any data loss from the radio writing data to the D-Rats program, so it looks like D-Rats is unlikely to have this issue as the radio sends data a lot slower than a cross over cable.
The "iee!" issue may not show up as often on older PCs or older D-Rats simply because of timing differences, but the bug is still present in the older D-Rats.
In File transfer tab - after connecting to a remote station, clicking on "upload" nothing happens (no upload, no lines in console, no errors shown) shall I open an issue ?
Are both systems running the D-Rats with this PR?
Anything older than this PR can discard data on connection through a radio port, causing this problem as documented above. The current master for some reason works with a null modem cable, but not with a real radio, and anything older than that can randomly discard characters.
I think that SNIFF and RAW go into the event log not the console log, I am not sure on that.
If both systems are running D-Rats with this PR, then more work is needed on this PR, and we need to try to get dumps of the data being sent and received on both sides. Hopefully the RAW and SNIFF settings in D-Rats can capture the data so that we can find out that the actual issue.
For the cases were it is the current master on both radios or with this PR as one of them, it would also be be useful to have the same RAW and SNIFF settings on and captures from event logs of those to find out why the driver.
If the existing RAW and SNIFF settings are not good enough to get the data we need, then that would be the next thing to fix in this PR so that we can get at least one side of the transfer as seen from one radio.
If either radio is older than master or this PR, then we know that it will randomly just discard packets. The data dump should show that happening.
This would also help get an some actual documentation of the protocol that is used for file transfers. I have not been able to figure that out from reading the source code.
about the "upload": I did tested between my Win10 PR268 and other pc with 0.3.10 on internet (alabama ratflector)
just redone tha "upload" test with PR268 on my mac and my win10 via alabama ratflector: pressing upload there is no effect at all
both mac and win10 have sniff and raw enabled.
it appears nothing in console, nothing in log window, no errors. Just it seems that the "upload" button is not configured to do anything at all.
on the other end "download" button makes what it is expected
Hi: I have a MMDVM Hot Spot I experimented in the past for D-Star setup. Tests were successful using a Kenwood TMv71 with the MMDVM. Maybe I can try to test D-Rats RF from ICOM 9700 to the Hot Spot. I think the Hot Spot is still functional. Might try test in the weekend but I need instructions. Other than that I don't have additional D-Star (DV) capable radios or close and capable D-Star - D-Rats Ham Radio operator.
73'
Edfel KP4AJ
Send file from station panel works. This should be using common code to upload file code which does not work as reported. I have duplicated this issue locally.
Previous commit to master to stop creating the file with the name of the remote station broke the upload code. That same parameter was being used for two different types of data. Pushing a quick fix for that for now, will need to clean it up more later.
Temporarily added --sdebug parameter to d-rats.py until we can re-write the config section to allow specifying a debug log on a per port basis.
This will apend to a serial.txt file all activity to or from a radio via the serial port to allow debugging future issues.
In testing, if the "Aiee" message shows up in the console log for any version of d-rats other than one for this PR, it means that due to a bug in those versions the data transfer is lost, and there is nothing that we can do to make that work except upgrade those d-rats clients to this version.
With this PR version, the "Aiee" message is just informational, the data is not lost.
Now we need to setup a set of pages in the Wiki to with sample logs for various communications with various radios.
Testing between my PR268 on windows and on Mac both connected via Alabama ratflector: inconsistent results
With Mac I was able to connect to a third CT1EDG and upload a file, so it seemed ok, but I think It sent the file to a wrong counterpart
With windows (IZ2LXI-W) I was trying to connect to my Mac (Iz2lxi-M) , but I was getting either no response in acceptable time or a list of files which are not on my Mac, eg:
This is my Windows connected to my Mac - look first at the list of the files in the left area and compare with the picture below. The list on the right by no means shows the files on my Mac (IZ2LXI-M)
This is my Mac, when connected to CT1EDG,... the file lists on the righe is the one on my Windows PC :
altought i can confirm that file upload now pushes files, I think that they are not connecting to machine selected in the combo list
This pull request only fixes random character discards between the d-rats client running it and the local serial port. I did not put in packet capture for diagnosing problems with Ratflectors.
The upload button got broken with the previous commit that removed creating the unused directory with the remote host as its name, as that same property was used hold the remote hostname. There is now a separate property for the remote host name.
Diagnosing issues with the Ratflector transfers is going to have to be beyond the scope of this PR. One of the problems with diagnosing that is that we do not have a protocol description and state diagrams for any of the background data transfers that are in D-Rats, and we do not have a good way to collect the debug data.
What should be working now and with a tool to help diagnose communications radio to radio, with no ratflector and the file transfers in both direction.
D-Rats uses half duplex communications for all links including ethernet. This means that if something sends a ratflector or a d-rats client more data than can buffer for read over the internet, some data will be lost, so it is quite possible that we will have some problems until it can be re-written to be full duplex which is a major task.
Found more places where a directory path was being used where the station name property should have been used.
file list is now working- i will try to test transfer via RF later tonight iif some friend with D-Rats capability shows up
The driver XON/XOFF appears to be losing characters when used with actual radios.
Reverting back to software XON/XOFF and fixed the case where software xon/xoff was discarding characters when the driver receive buffer was empty at the start of a read and something other than XON/XOFF was read from the radio.
d_rats/comm.py: Revert back to using software xon/xoff. Fix software xon/xoff to not discard characters.
d_rats/config.py: Only enable edit and remove buttons when an existing radio port is selected to prevent a crash.
d_rats/keyedlistwidget.py: Expose the treeview object as a property.