Bug reports

Bug:

VarAC Cluster breaks after receiving <LHR> (Last Heard Peaking request) with multiband peeking ON.

Note: I did NOT test LHR with multiband peeking off, and it may also be broken.

Scope:

Spotted in VarAC V11.2.0

Symptoms:

1. EMAIL GATE turns orange (it is supposed to remain blue) in the non-sender nodes.

2. Datastream messages window - discontinuity, some messages disappear or are not displayed after <LHR>.

3. A Vmail was LOST - after being received and confirmed with <SMR>, and parked! The Vmail disappeared from parked vmails and was lost.

4. The VarAC Cluster does NOT recover until closing ALL instances of VarAC and restarting them!

Analysis:

The log indicates SQLite database errors:

1. 02/06/2025 22:12:04 - LHR tag identified (Last Heard Request) 02/06/2025 22:12:04 - Error: processing Request last heard callsign peeking request 02/06/2025 22:12:04 - Value was either too large or too small for an Int32. at System.Convert.ToInt32(Int64 value) at System.Int64.System.IConvertible.ToInt32(IFormatProvider provider) at System.Convert.ToInt32(Object value) at VarAC.VarAC.<connect_varahf_modem>b__126_2() 02/06/2025 22:12:11 - Error: updating instance keepalive in SQLite:database is locked database is locked at System.Data.SQLite.SQLite3.Step(SQLiteStatement stmt) at System.Data.SQLite.SQLiteDataReader.NextResult() at System.Data.SQLite.SQLiteDataReader..ctor(SQLiteCommand cmd, CommandBehavior behave) at System.Data.SQLite.SQLiteCommand.ExecuteReader(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery() at VarAC.VarAC.update_instance_keepalive(Int32 instance_id) 02/06/2025 22:12:11 - Error: updating instance keepalive in SQLite:database is locked database is locked at System.Data.SQLite.SQLite3.Step(SQLiteStatement stmt) at System.Data.SQLite.SQLiteDataReader.NextResult() at System.Data.SQLite.SQLiteDataReader..ctor(SQLiteCommand cmd, CommandBehavior behave) at System.Data.SQLite.SQLiteCommand.ExecuteReader(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery() at VarAC.VarAC.update_instance_keepalive(Int32 instance_id) 02/06/2025 22:12:15 - Error: Writing to SQLite datastream table :database is locked database is locked at System.Data.SQLite.SQLite3.Step(SQLiteStatement stmt) at System.Data.SQLite.SQLiteDataReader.NextResult() at System.Data.SQLite.SQLiteDataReader..ctor(SQLiteCommand cmd, CommandBehavior behave) at System.Data.SQLite.SQLiteCommand.ExecuteReader(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery() at VarAC.VarAC.write_datastream_log_to_db(String qso_guid, Int32 datastream_entry_type_id, String callsign, String entry, DateTime creation_time, Int32 chat_id, Int32 reply_on_chat_id, String file_path) 02/06/2025 22:12:27 - Error: Writing to cqframe table:database is locked database is locked at System.Data.SQLite.SQLite3.Step(SQLiteStatement stmt) at System.Data.SQLite.SQLiteDataReader.NextResult() at System.Data.SQLite.SQLiteDataReader..ctor(SQLiteCommand cmd, CommandBehavior behave) at System.Data.SQLite.SQLiteCommand.ExecuteReader(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery() at VarAC.VarAC.write_last_heard_to_db(DateTime cqframe_time, Int32 cqframe_type_id, Int64 frequency, String bandwidth, String from_callsign, Int32 snr, String slot, String data, String locator, Boolean is_emcomm, Boolean is_email_gateway) 02/06/2025 22:12:28 - PSKReporter: Logged successfully (4Z1AC) 02/06/2025 22:12:28 - PSKReporter: Sending accumulated records 02/06/2025 22:12:28 - Error: Writing to SQLite qso_snr_report table :database is locked database is locked at System.Data.SQLite.SQLite3.Step(SQLiteStatement stmt) at System.Data.SQLite.SQLiteDataReader.NextResult() at System.Data.SQLite.SQLiteDataReader..ctor(SQLiteCommand cmd, CommandBehavior behave) at System.Data.SQLite.SQLiteCommand.ExecuteReader(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery() at VarAC.VarAC.write_snr_to_db(String qso_guid, Int32 snr_direction, Int32 snr, DateTime creation_time) 02/06/2025 22:12:29 - Error: Writing to SQLite datastream table :database is locked database is locked at System.Data.SQLite.SQLite3.Step(SQLiteStatement stmt) at System.Data.SQLite.SQLiteDataReader.NextResult() at System.Data.SQLite.SQLiteDataReader..ctor(SQLiteCommand cmd, CommandBehavior behave) at System.Data.SQLite.SQLiteCommand.ExecuteReader(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery() at VarAC.VarAC.write_datastream_log_to_db(String qso_guid, Int32 datastream_entry_type_id, String callsign, String entry, DateTime creation_time, Int32 chat_id, Int32 reply_on_chat_id, String file_path) 02/06/2025 22:12:32 - Disable PTT status set to false 02/06/2025 22:12:32 - Setting disable ptt status to false 02/06/2025 22:13:00 - Closing Rig comport 02/06/2025 22:13:00 - Closing GPS comport 02/06/2025 22:13:00 - Closing Rig TCP port 02/06/2025 22:13:22 - Debug mode disabled 02/06/2025 22:13:22 - Instance number: 20 02/06/2025 22:13:23 - Error: updating instance email gateway status in SQLite:database is locked database is locked at System.Data.SQLite.SQLite3.Step(SQLiteStatement stmt) at System.Data.SQLite.SQLiteDataReader.NextResult() at System.Data.SQLite.SQLiteDataReader..ctor(SQLiteCommand cmd, CommandBehavior behave) at System.Data.SQLite.SQLiteCommand.ExecuteReader(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(CommandBehavior behavior) at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery() at VarAC.VarAC.update_instance_email_gateway_status(Int32 instance_id, Boolean email_gateway_active, Boolean email_gateway_sender_node) 02/06/2025 22:13:23 - Setting unattended links ON (load) ...

Recommendation:

1. Fix the bugs indicated in the VarAC log, and fully test that Last Heard Peeking works in all cases.

2. Add additional sanity checks and error handling. I suggest: A. Display errors in the GUI and offer to send feedback/bug report to the developers. B. Then recover the cluster and all instances and the SQLite database to a sane and safe state, or instruct the end user how to do this.

Screen Capture of the Bug, Showing the Broken VarAC Cluster: