2025-04-25T19:13:52.737Z,1745608432.737 [Supervisor](DEBUG): Initializing supervisor.
2025-04-25T19:13:52.741Z,1745608432.741 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-04-25T19:13:52.742Z,1745608432.742 [SyncHandler](INFO): Protected caller Thread ID is 4239
2025-04-25T19:13:52.742Z,1745608432.742 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-04-25T19:13:52.743Z,1745608432.743 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-04-25T19:13:52.744Z,1745608432.744 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4240
2025-04-25T19:13:52.748Z,1745608432.748 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-04-25T19:13:52.769Z,1745608432.769 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-04-25T19:13:52.770Z,1745608432.770 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-04-25T19:13:52.770Z,1745608432.770 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4241
2025-04-25T19:13:52.775Z,1745608432.775 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-04-25T19:13:52.776Z,1745608432.776 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-04-25T19:13:52.776Z,1745608432.776 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4242
2025-04-25T19:13:52.779Z,1745608432.779 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-04-25T19:13:52.779Z,1745608432.779 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-04-25T19:13:52.780Z,1745608432.780 [logger ThreadHandler](INFO): Protected caller Thread ID is 4243
2025-04-25T19:13:52.784Z,1745608432.784 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-04-25T19:13:52.784Z,1745608432.784 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-04-25T19:13:52.786Z,1745608432.786 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-04-25T19:13:53.011Z,1745608433.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-04-25T19:13:53.012Z,1745608433.012 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-04-25T19:13:53.130Z,1745608433.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-04-25T19:13:53.131Z,1745608433.131 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-04-25T19:13:53.238Z,1745608433.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-04-25T19:13:53.238Z,1745608433.238 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-04-25T19:13:53.744Z,1745608433.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-04-25T19:13:53.745Z,1745608433.745 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-04-25T19:13:54.112Z,1745608434.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-04-25T19:13:54.113Z,1745608434.113 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-04-25T19:13:54.476Z,1745608434.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-04-25T19:13:54.477Z,1745608434.477 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-04-25T19:13:54.680Z,1745608434.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-04-25T19:13:54.681Z,1745608434.681 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-04-25T19:13:54.771Z,1745608434.771 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-04-25T19:13:54.864Z,1745608434.864 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-04-25T19:13:55.298Z,1745608435.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-04-25T19:13:55.299Z,1745608435.299 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-04-25T19:13:55.607Z,1745608435.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-04-25T19:13:55.608Z,1745608435.608 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-04-25T19:13:56.188Z,1745608436.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-04-25T19:13:56.188Z,1745608436.188 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-04-25T19:13:56.447Z,1745608436.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-04-25T19:13:56.447Z,1745608436.447 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-04-25T19:13:56.707Z,1745608436.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-04-25T19:13:56.708Z,1745608436.708 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-04-25T19:13:56.863Z,1745608436.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-04-25T19:13:56.864Z,1745608436.864 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-04-25T19:13:57.887Z,1745608437.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-04-25T19:13:57.889Z,1745608437.889 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2025-04-25T19:13:57.890Z,1745608437.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2025-04-25T19:13:58.228Z,1745608438.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2025-04-25T19:13:58.541Z,1745608438.541 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2025-04-25T19:13:58.969Z,1745608438.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-04-25T19:13:58.969Z,1745608438.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2025-04-25T19:13:59.106Z,1745608439.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2025-04-25T19:13:59.215Z,1745608439.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2025-04-25T19:13:59.321Z,1745608439.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2025-04-25T19:13:59.441Z,1745608439.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2025-04-25T19:13:59.536Z,1745608439.536 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2025-04-25T19:13:59.634Z,1745608439.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2025-04-25T19:13:59.737Z,1745608439.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2025-04-25T19:13:59.921Z,1745608439.921 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2025-04-25T19:13:59.921Z,1745608439.921 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-04-25T19:13:59.932Z,1745608439.932 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-04-25T19:13:59.984Z,1745608439.984 [DepthRateCalculator] Loaded
2025-04-25T19:13:59.984Z,1745608439.984 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-04-25T19:13:59.989Z,1745608439.989 [PitchRateCalculator] Loaded
2025-04-25T19:13:59.990Z,1745608439.990 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-04-25T19:14:00.000Z,1745608440.000 [SpeedCalculator] Loaded
2025-04-25T19:14:00.000Z,1745608440.000 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-04-25T19:14:00.005Z,1745608440.005 [YawRateCalculator] Loaded
2025-04-25T19:14:00.006Z,1745608440.006 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-04-25T19:14:00.027Z,1745608440.027 [ElevatorOffsetCalculator] Loaded
2025-04-25T19:14:00.027Z,1745608440.027 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-04-25T19:14:00.028Z,1745608440.028 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-04-25T19:14:00.028Z,1745608440.028 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-04-25T19:14:00.057Z,1745608440.057 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-04-25T19:14:00.058Z,1745608440.058 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-04-25T19:14:00.068Z,1745608440.068 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-04-25T19:14:00.069Z,1745608440.069 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-04-25T19:14:00.544Z,1745608440.544 [AHRS_M2] Loaded
2025-04-25T19:14:00.544Z,1745608440.544 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-04-25T19:14:00.583Z,1745608440.583 [BackseatComponent] Loaded
2025-04-25T19:14:00.583Z,1745608440.583 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-04-25T19:14:00.584Z,1745608440.584 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0
2025-04-25T19:14:00.585Z,1745608440.585 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4326
2025-04-25T19:14:00.587Z,1745608440.587 [LcmUniversalReporter] Loaded
2025-04-25T19:14:00.588Z,1745608440.588 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-04-25T19:14:01.380Z,1745608441.380 [BPC1] Loaded
2025-04-25T19:14:01.381Z,1745608441.381 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-04-25T19:14:01.477Z,1745608441.477 [DAT] Loaded
2025-04-25T19:14:01.477Z,1745608441.477 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-04-25T19:14:01.479Z,1745608441.479 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0
2025-04-25T19:14:01.479Z,1745608441.479 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4327
2025-04-25T19:14:01.553Z,1745608441.553 [DataOverHttps] Loaded
2025-04-25T19:14:01.553Z,1745608441.553 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-04-25T19:14:01.555Z,1745608441.555 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0
2025-04-25T19:14:01.555Z,1745608441.555 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4328
2025-04-25T19:14:01.578Z,1745608441.578 [Depth_Keller] Loaded
2025-04-25T19:14:01.578Z,1745608441.578 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-04-25T19:14:01.583Z,1745608441.583 [DropWeight] Loaded
2025-04-25T19:14:01.583Z,1745608441.583 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-04-25T19:14:01.604Z,1745608441.604 [MultiRay](INFO): Found secondary power supply at: /dev/loadC7
2025-04-25T19:14:01.605Z,1745608441.605 [MultiRay] Loaded
2025-04-25T19:14:01.605Z,1745608441.605 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2025-04-25T19:14:01.670Z,1745608441.670 [NAL9602] Loaded
2025-04-25T19:14:01.670Z,1745608441.670 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-04-25T19:14:01.700Z,1745608441.700 [Onboard] Loaded
2025-04-25T19:14:01.700Z,1745608441.700 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-04-25T19:14:01.701Z,1745608441.701 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2025-04-25T19:14:01.702Z,1745608441.702 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4329
2025-04-25T19:14:01.708Z,1745608441.708 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-04-25T19:14:01.721Z,1745608441.721 [PowerOnly] Loaded
2025-04-25T19:14:01.721Z,1745608441.721 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-04-25T19:14:01.735Z,1745608441.735 [Power24vConverter] Loaded
2025-04-25T19:14:01.735Z,1745608441.735 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-04-25T19:14:01.753Z,1745608441.753 [Radio_Surface] Loaded
2025-04-25T19:14:01.753Z,1745608441.753 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-04-25T19:14:01.754Z,1745608441.754 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2025-04-25T19:14:01.754Z,1745608441.754 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4330
2025-04-25T19:14:01.755Z,1745608441.755 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-04-25T19:14:01.756Z,1745608441.756 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-04-25T19:14:01.868Z,1745608441.868 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-04-25T19:14:01.868Z,1745608441.868 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-04-25T19:14:01.882Z,1745608441.882 [NavChart] Loaded
2025-04-25T19:14:01.883Z,1745608441.883 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-04-25T19:14:01.888Z,1745608441.888 [UniversalFixResidualReporter] Loaded
2025-04-25T19:14:01.889Z,1745608441.889 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-04-25T19:14:01.889Z,1745608441.889 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-04-25T19:14:01.890Z,1745608441.890 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-04-25T19:14:02.051Z,1745608442.051 [SBIT](DEBUG): Construct Startup Built In Test.
2025-04-25T19:14:02.065Z,1745608442.065 [SBIT] Loaded
2025-04-25T19:14:02.065Z,1745608442.065 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-04-25T19:14:02.068Z,1745608442.068 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-04-25T19:14:02.081Z,1745608442.081 [IBIT] Loaded
2025-04-25T19:14:02.081Z,1745608442.081 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-04-25T19:14:02.086Z,1745608442.086 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-04-25T19:14:02.167Z,1745608442.167 [CBIT] Loaded
2025-04-25T19:14:02.167Z,1745608442.167 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-04-25T19:14:02.198Z,1745608442.198 [GFScanner] Loaded
2025-04-25T19:14:02.199Z,1745608442.199 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-04-25T19:14:02.199Z,1745608442.199 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-04-25T19:14:02.200Z,1745608442.200 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-04-25T19:14:02.367Z,1745608442.367 [CTD_Seabird] Loaded
2025-04-25T19:14:02.367Z,1745608442.367 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-04-25T19:14:02.368Z,1745608442.368 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2025-04-25T19:14:02.368Z,1745608442.368 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4331
2025-04-25T19:14:02.392Z,1745608442.392 [PAR_Licor] Loaded
2025-04-25T19:14:02.392Z,1745608442.392 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-04-25T19:14:02.438Z,1745608442.438 [WetLabsBB2FL] Loaded
2025-04-25T19:14:02.438Z,1745608442.438 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-04-25T19:14:02.439Z,1745608442.439 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2025-04-25T19:14:02.439Z,1745608442.439 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4332
2025-04-25T19:14:02.440Z,1745608442.440 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-04-25T19:14:02.441Z,1745608442.441 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-04-25T19:14:02.578Z,1745608442.578 [BuoyancyServo] Loaded
2025-04-25T19:14:02.578Z,1745608442.578 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-04-25T19:14:02.603Z,1745608442.603 [ElevatorServo] Loaded
2025-04-25T19:14:02.604Z,1745608442.604 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-04-25T19:14:02.627Z,1745608442.627 [MassServo] Loaded
2025-04-25T19:14:02.627Z,1745608442.627 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-04-25T19:14:02.650Z,1745608442.650 [RudderServo] Loaded
2025-04-25T19:14:02.650Z,1745608442.650 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-04-25T19:14:02.669Z,1745608442.669 [ThrusterHE] Loaded
2025-04-25T19:14:02.669Z,1745608442.669 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-04-25T19:14:02.669Z,1745608442.669 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-04-25T19:14:02.670Z,1745608442.670 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-04-25T19:14:02.724Z,1745608442.724 [VerticalControl](DEBUG): Construct VerticalControl.
2025-04-25T19:14:02.777Z,1745608442.777 [VerticalControl] Loaded
2025-04-25T19:14:02.777Z,1745608442.777 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-04-25T19:14:02.780Z,1745608442.780 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-04-25T19:14:02.824Z,1745608442.824 [HorizontalControl] Loaded
2025-04-25T19:14:02.824Z,1745608442.824 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-04-25T19:14:02.827Z,1745608442.827 [SpeedControl](DEBUG): Construct SpeedControl.
2025-04-25T19:14:02.828Z,1745608442.828 [SpeedControl] Loaded
2025-04-25T19:14:02.829Z,1745608442.829 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-04-25T19:14:02.832Z,1745608442.832 [LoopControl](DEBUG): Construct LoopControl.
2025-04-25T19:14:02.832Z,1745608442.832 [LoopControl] Loaded
2025-04-25T19:14:02.832Z,1745608442.832 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-04-25T19:14:02.833Z,1745608442.833 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-04-25T19:14:02.833Z,1745608442.833 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-04-25T19:14:03.204Z,1745608443.204 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-04-25T19:14:03.204Z,1745608443.204 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-04-25T19:14:03.498Z,1745608443.498 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-04-25T19:14:03.498Z,1745608443.498 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-04-25T19:14:03.843Z,1745608443.843 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-04-25T19:14:03.843Z,1745608443.843 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-04-25T19:14:03.902Z,1745608443.902 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-04-25T19:14:03.909Z,1745608443.909 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-04-25T19:14:03.912Z,1745608443.912 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-04-25T19:14:03.923Z,1745608443.923 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-04-25T19:14:03.924Z,1745608443.924 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-04-25T19:14:03.925Z,1745608443.925 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4333
2025-04-25T19:14:03.929Z,1745608443.929 [Supervisor](INFO): Main Thread ID is 4238
2025-04-25T19:14:03.929Z,1745608443.929 [Supervisor](DEBUG): Running supervisor.
2025-04-25T19:14:03.930Z,1745608443.930 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4334
2025-04-25T19:14:03.930Z,1745608443.930 [CommandExec](INFO): Initializing the command executive.
2025-04-25T19:14:03.932Z,1745608443.932 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4335
2025-04-25T19:14:03.934Z,1745608443.934 [controlThread ThreadHandler](INFO): Handler Thread ID is 4336
2025-04-25T19:14:03.935Z,1745608443.935 [controlThread](DEBUG): Initializing ControlThread
2025-04-25T19:14:03.935Z,1745608443.935 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-04-25T19:14:03.936Z,1745608443.936 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-04-25T19:14:03.936Z,1745608443.936 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-04-25T19:14:03.936Z,1745608443.936 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-04-25T19:14:03.937Z,1745608443.937 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-04-25T19:14:03.941Z,1745608443.941 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-04-25T19:14:03.942Z,1745608443.942 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-04-25T19:14:03.942Z,1745608443.942 [SBIT](INFO): Initialize SBIT Component.
2025-04-25T19:14:03.942Z,1745608443.942 [SBIT](IMPORTANT): git: 2025-04-24
2025-04-25T19:14:03.943Z,1745608443.943 [SBIT](INFO): git hash: ed8b2699e10264a13705e958152987274a27a0ee
2025-04-25T19:14:03.943Z,1745608443.943 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-04-25T19:14:03.944Z,1745608443.944 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-04-25T19:14:03.945Z,1745608443.945 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2025-04-25T19:14:03.946Z,1745608443.946 [IBIT](INFO): Initialize IBIT Component.
2025-04-25T19:14:03.947Z,1745608443.947 [CBIT](DEBUG): Initialize CBIT Component.
2025-04-25T19:14:03.948Z,1745608443.948 [logger ThreadHandler](INFO): Handler Thread ID is 4337
2025-04-25T19:14:03.961Z,1745608443.961 [CBIT](DEBUG): Initialized mux pins.
2025-04-25T19:14:03.961Z,1745608443.961 [CBIT](DEBUG): Initializing the watchdog timer.
2025-04-25T19:14:03.974Z,1745608443.974 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4338
2025-04-25T19:14:03.985Z,1745608443.985 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-04-25T19:14:03.985Z,1745608443.985 [CBIT](DEBUG): Initializing heartbeat.
2025-04-25T19:14:03.986Z,1745608443.986 [DAT ThreadHandler](INFO): Handler Thread ID is 4339
2025-04-25T19:14:03.987Z,1745608443.987 [DAT](INFO): Powering up
2025-04-25T19:14:03.987Z,1745608443.987 [DAT](DEBUG): Initializing DAT.
2025-04-25T19:14:03.990Z,1745608443.990 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4341
2025-04-25T19:14:03.992Z,1745608443.992 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-04-25T19:14:04.013Z,1745608444.013 [Onboard ThreadHandler](INFO): Handler Thread ID is 4342
2025-04-25T19:14:04.031Z,1745608444.031 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4343
2025-04-25T19:14:04.051Z,1745608444.051 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4344
2025-04-25T19:14:04.051Z,1745608444.051 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-25T19:14:04.054Z,1745608444.054 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4346
2025-04-25T19:14:04.057Z,1745608444.057 [CBIT](DEBUG): Deactivating emergency mode.
2025-04-25T19:14:04.058Z,1745608444.058 [WetLabsBB2FL](INFO): Powering up
2025-04-25T19:14:04.059Z,1745608444.059 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4348
2025-04-25T19:14:04.062Z,1745608444.062 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-04-25T19:14:04.062Z,1745608444.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-04-25T19:14:04.062Z,1745608444.062 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-04-25T19:14:04.062Z,1745608444.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-04-25T19:14:04.062Z,1745608444.062 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-04-25T19:14:04.063Z,1745608444.063 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-04-25T19:14:04.063Z,1745608444.063 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-04-25T19:14:04.063Z,1745608444.063 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-04-25T19:14:04.097Z,1745608444.097 [CBIT](DEBUG): Backplane powered.
2025-04-25T19:14:04.097Z,1745608444.097 [GFScanner](DEBUG): Initializing GFScanner
2025-04-25T19:14:04.098Z,1745608444.098 [GFScanner](DEBUG): Deactivating GF circuits.
2025-04-25T19:14:04.107Z,1745608444.107 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-04-25T19:14:04.108Z,1745608444.108 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-04-25T19:14:04.109Z,1745608444.109 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-04-25T19:14:04.110Z,1745608444.110 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-04-25T19:14:04.110Z,1745608444.110 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-04-25T19:14:04.111Z,1745608444.111 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-04-25T19:14:04.111Z,1745608444.111 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-04-25T19:14:04.112Z,1745608444.112 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-04-25T19:14:04.175Z,1745608444.175 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-04-25T19:14:04.192Z,1745608444.192 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-25T19:14:04.216Z,1745608444.216 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Startup {
  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


2025-04-25T19:14:04.217Z,1745608444.217 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-04-25T19:14:04.217Z,1745608444.217 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-04-25T19:14:04.218Z,1745608444.218 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-04-25T19:14:04.437Z,1745608444.437 [Radio_Surface](INFO): Powering up
2025-04-25T19:14:04.439Z,1745608444.439 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-04-25T19:14:04.461Z,1745608444.461 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-04-25T19:14:04.463Z,1745608444.463 [Default:A.Wait](DEBUG): Construct Wait.
2025-04-25T19:14:04.465Z,1745608444.465 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-25T19:14:04.490Z,1745608444.490 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-04-25T19:14:04.493Z,1745608444.493 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-04-25T19:14:04.499Z,1745608444.499 [Default:E.Execute](DEBUG): Construct Execute.
2025-04-25T19:14:04.505Z,1745608444.505 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

  behavior Guidance:Wait {
    """
    Wait a moment to see if the scheduler starts a new mission before
    starting to actually run Default.
    """

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P4H {
        behavior Guidance:Execute {
          run in sequence

          set command = "Burn on"
        }

        syslog critical "Dropped weight due to communications timeout."
      }

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

    syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
  }

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-04-25T19:14:04.529Z,1745608444.529 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-25T19:14:04.537Z,1745608444.537 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-25T19:14:04.586Z,1745608444.586 [Depth_Keller](INFO): Initializing.
2025-04-25T19:14:04.587Z,1745608444.587 [MultiRay](INFO): Powering up MultiRay Lights
2025-04-25T19:14:04.587Z,1745608444.587 [MultiRay](INFO): Powering up secondary power supply.
2025-04-25T19:14:04.631Z,1745608444.631 [Power24vConverter](INFO): Powering up.
2025-04-25T19:14:04.650Z,1745608444.650 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-04-25T19:14:04.673Z,1745608444.673 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-25T19:14:04.677Z,1745608444.677 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-25T19:14:04.678Z,1745608444.678 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-25T19:14:04.685Z,1745608444.685 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-25T19:14:04.687Z,1745608444.687 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-25T19:14:04.694Z,1745608444.694 [MassServo](DEBUG): Initializing MassServo.
2025-04-25T19:14:04.694Z,1745608444.694 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-25T19:14:04.701Z,1745608444.701 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-25T19:14:04.702Z,1745608444.702 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-25T19:14:04.709Z,1745608444.709 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-25T19:14:05.750Z,1745608445.750 [MultiRay](INFO): Powering down
2025-04-25T19:14:05.869Z,1745608445.869 [MultiRay](INFO): Powering down LCB2
2025-04-25T19:14:06.681Z,1745608446.681 [WetLabsBB2FL](INFO): Powering down
2025-04-25T19:14:09.354Z,1745608449.354 [CommandExec](IMPORTANT): got command failComponent
2025-04-25T19:14:09.354Z,1745608449.354 [CommandExec](IMPORTANT): Failed components:
2025-04-25T19:14:09.354Z,1745608449.354 [CommandExec](IMPORTANT): No failed Components.
2025-04-25T19:14:10.514Z,1745608450.514 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-04-25T19:14:11.081Z,1745608451.081 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-04-25T19:14:13.674Z,1745608453.674 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005605
2025-04-25T19:14:16.590Z,1745608456.590 [DAT](INFO): DAT read: 

2025-04-25T19:14:16.591Z,1745608456.591 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-04-25T19:14:18.102Z,1745608458.102 [DAT](INFO): DAT read: MF Frequency Band

2025-04-25T19:14:18.104Z,1745608458.104 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-04-25T19:14:18.104Z,1745608458.104 [DAT](INFO): DAT read: Apr 24 2025 23:40:07 

2025-04-25T19:14:19.111Z,1745608459.111 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-04-25T19:14:19.112Z,1745608459.112 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-04-25T19:14:19.112Z,1745608459.112 [DAT](INFO): commRate: 800
2025-04-25T19:14:21.177Z,1745608461.177 [DAT](INFO): entering command mode
2025-04-25T19:14:21.378Z,1745608461.378 [DAT](INFO): DAT read: 

2025-04-25T19:14:21.378Z,1745608461.378 [DAT](INFO): DAT read: user:1>

2025-04-25T19:14:21.379Z,1745608461.379 [DAT](INFO): setting verbose to 3
2025-04-25T19:14:21.630Z,1745608461.630 [DAT](INFO): DAT read: user:1>

2025-04-25T19:14:21.631Z,1745608461.631 [DAT](INFO): DAT read: Verbose         | 3           

2025-04-25T19:14:21.631Z,1745608461.631 [DAT](INFO): set verbose to 3
2025-04-25T19:14:21.632Z,1745608461.632 [DAT](INFO): setting DatVerbose to 27440
2025-04-25T19:14:21.882Z,1745608461.882 [DAT](INFO): DAT read: user:2>

2025-04-25T19:14:21.883Z,1745608461.883 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-04-25T19:14:21.883Z,1745608461.883 [DAT](INFO): set DatVerbose to 27440
2025-04-25T19:14:21.883Z,1745608461.883 [DAT](INFO): setting transmit power to 8
2025-04-25T19:14:22.134Z,1745608462.134 [DAT](INFO): DAT read: user:3>

2025-04-25T19:14:22.135Z,1745608462.135 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-04-25T19:14:22.135Z,1745608462.135 [DAT](INFO): set transmit power to 8
2025-04-25T19:14:22.135Z,1745608462.135 [DAT](INFO): setting local address to 9
2025-04-25T19:14:22.386Z,1745608462.386 [DAT](INFO): DAT read: user:4>

2025-04-25T19:14:22.387Z,1745608462.387 [DAT](INFO): DAT read: LocalAddr       | 9           

2025-04-25T19:14:22.387Z,1745608462.387 [DAT](INFO): set local address to 9
2025-04-25T19:14:22.388Z,1745608462.388 [DAT](INFO): Setting time to: 19:14:22 And date to:4/25/2025
2025-04-25T19:14:22.639Z,1745608462.639 [DAT](INFO): DAT read: user:5>

2025-04-25T19:14:22.640Z,1745608462.640 [DAT](INFO): DAT read: Fri Apr 25, 2025  19:14:22  

2025-04-25T19:14:22.640Z,1745608462.640 [DAT](INFO): Local DAT time set to Fri Apr 25, 2025  19:14:22  

2025-04-25T19:14:31.703Z,1745608471.703 [NAL9602](INFO): Powering up NAL9602
2025-04-25T19:14:32.523Z,1745608472.523 [SBIT](IMPORTANT): Beginning Startup BIT
2025-04-25T19:14:32.528Z,1745608472.528 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-25T19:14:37.039Z,1745608477.039 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006652
CHAN A1 (24V): 0.000631
CHAN A2 (12V): -0.005488
CHAN A3 (5V): -0.002661
CHAN B0 (3.3V): -0.001040
CHAN B1 (3.15aV): -0.001017
CHAN B2 (3.15bV): -0.001647
CHAN B3 (GND): -0.000091
OPEN: 0.004959
Full Scale: +/- 1 mA
2025-04-25T19:14:42.615Z,1745608482.615 [NAL9602](INFO): NAL9602 initialized
2025-04-25T19:15:08.886Z,1745608508.886 [CommandExec](IMPORTANT): got command failComponent
2025-04-25T19:15:08.886Z,1745608508.886 [CommandExec](IMPORTANT): Failed components:
2025-04-25T19:15:08.886Z,1745608508.886 [CommandExec](IMPORTANT): No failed Components.
2025-04-25T19:15:19.323Z,1745608519.323 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2025-04-25T19:15:19.324Z,1745608519.324 [CommandExec](IMPORTANT): Onboard.Pressure 9.928683 psi
2025-04-25T19:15:26.740Z,1745608526.740 [SBIT](IMPORTANT): SBIT PASSED
2025-04-25T19:15:26.740Z,1745608526.740 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-04-25T19:15:26.741Z,1745608526.741 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;

2025-04-25T19:15:26.742Z,1745608526.742 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-04-25T19:15:26.742Z,1745608526.742 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;

2025-04-25T19:15:26.742Z,1745608526.742 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;

2025-04-25T19:15:26.742Z,1745608526.742 [SBIT](IMPORTANT): MultiRay.brightnessWhite=100 none;

2025-04-25T19:15:26.793Z,1745608526.793 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;

2025-04-25T19:15:26.794Z,1745608526.794 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;

2025-04-25T19:15:26.794Z,1745608526.794 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2025-04-25T19:15:26.794Z,1745608526.794 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=297.568711 cubic_centimeter;

2025-04-25T19:15:26.794Z,1745608526.794 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;

2025-04-25T19:15:27.144Z,1745608527.144 [MissionManager](IMPORTANT): Started mission Startup
2025-04-25T19:15:27.145Z,1745608527.145 [Startup] Running Loop=1
2025-04-25T19:15:27.145Z,1745608527.145 [Startup](DEBUG): Aggregate::initialize Startup
2025-04-25T19:15:27.145Z,1745608527.145 [Startup:A.GoToSurface] Running Loop=1
2025-04-25T19:15:27.145Z,1745608527.145 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-25T19:15:27.146Z,1745608527.146 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-04-25T19:15:27.146Z,1745608527.146 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-04-25T19:15:27.147Z,1745608527.147 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-04-25T19:15:27.147Z,1745608527.147 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-04-25T19:15:27.147Z,1745608527.147 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-25T19:15:27.148Z,1745608527.148 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-25T19:15:27.149Z,1745608527.149 [Startup:StartupSatComms] Running Loop=1
2025-04-25T19:15:27.150Z,1745608527.150 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-04-25T19:15:27.150Z,1745608527.150 [Startup:StartupSatComms:A] Running Loop=1
2025-04-25T19:15:27.568Z,1745608527.568 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-04-25T19:15:29.497Z,1745608529.497 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2025-04-25T19:15:29.498Z,1745608529.498 [CommandExec](IMPORTANT): Onboard.Humidity 10.413869 %
2025-04-25T19:15:33.554Z,1745608533.554 [CommandExec](IMPORTANT): got command failComponent
2025-04-25T19:15:33.554Z,1745608533.554 [CommandExec](IMPORTANT): Failed components:
2025-04-25T19:15:33.554Z,1745608533.554 [CommandExec](IMPORTANT): No failed Components.
2025-04-25T19:16:27.336Z,1745608587.336 [Startup:StartupSatComms:A](INFO): Timed out from 2025-04-25T19:15:27.1Z
2025-04-25T19:16:27.336Z,1745608587.336 [Startup:StartupSatComms:A] Stopped
2025-04-25T19:16:27.336Z,1745608587.336 [Startup:StartupSatComms:B] Running Loop=1
2025-04-25T19:16:27.749Z,1745608587.749 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-25T19:16:33.317Z,1745608593.317 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250424T230941/Courier0007.lzma
2025-04-25T19:16:34.271Z,1745608594.271 [DataOverHttps](INFO): Moved sent file to Logs/20250424T230941/Courier0007.lzma.bak
2025-04-25T19:16:34.271Z,1745608594.271 [DataOverHttps](INFO): SBD MOMSN=24731724
2025-04-25T19:16:45.258Z,1745608605.258 [CommandExec](IMPORTANT): got command configSet list
2025-04-25T19:16:45.258Z,1745608605.258 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-04-25T19:16:45.259Z,1745608605.259 [CommandExec](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;

2025-04-25T19:16:45.260Z,1745608605.260 [CommandExec](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-04-25T19:16:45.261Z,1745608605.261 [CommandExec](IMPORTANT): GFScanner.loadAtStartup=1 bool;

2025-04-25T19:16:45.262Z,1745608605.262 [CommandExec](IMPORTANT): GFScanner.simulateHardware=0 bool;

2025-04-25T19:16:45.262Z,1745608605.262 [CommandExec](IMPORTANT): MultiRay.brightnessWhite=100 none;

2025-04-25T19:16:45.263Z,1745608605.263 [CommandExec](IMPORTANT): MultiRay.loadAtStartup=1 bool;

2025-04-25T19:16:45.264Z,1745608605.264 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup=1 bool;

2025-04-25T19:16:45.264Z,1745608605.264 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2025-04-25T19:16:45.265Z,1745608605.265 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=297.568711 cubic_centimeter;

2025-04-25T19:16:45.266Z,1745608605.266 [CommandExec](IMPORTANT): Waterlinked.loadAtStartup=0 bool;

2025-04-25T19:16:49.969Z,1745608609.969 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250425T191352/Courier0000.lzma
2025-04-25T19:16:50.971Z,1745608610.971 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Courier0000.lzma.bak
2025-04-25T19:16:50.971Z,1745608610.971 [DataOverHttps](INFO): SBD MOMSN=24731726
2025-04-25T19:17:04.102Z,1745608624.102 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-04-25T19:17:04.102Z,1745608624.102 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:17:04.117Z,1745608624.117 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:17:04.532Z,1745608624.532 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:17:04.532Z,1745608624.532 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-04-25T19:17:06.818Z,1745608626.818 [DataOverHttps](INFO): Sending 419 bytes from file Logs/20250424T230941/Express0008.lzma
2025-04-25T19:17:07.819Z,1745608627.819 [DataOverHttps](INFO): Moved sent file to Logs/20250424T230941/Express0008.lzma.bak
2025-04-25T19:17:07.819Z,1745608627.819 [DataOverHttps](INFO): SBD MOMSN=24731729
2025-04-25T19:17:23.851Z,1745608643.851 [DataOverHttps](INFO): Sending 1031 bytes from file Logs/20250425T191352/Express0001.lzma
2025-04-25T19:17:24.851Z,1745608644.851 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Express0001.lzma.bak
2025-04-25T19:17:24.851Z,1745608644.851 [DataOverHttps](INFO): SBD MOMSN=24731749
2025-04-25T19:17:26.323Z,1745608646.323 [Startup:StartupSatComms:B] Stopped
2025-04-25T19:17:26.323Z,1745608646.323 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-04-25T19:17:26.323Z,1745608646.323 [Startup:StartupSatComms] Stopped
2025-04-25T19:17:26.323Z,1745608646.323 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-04-25T19:17:26.324Z,1745608646.324 [Startup](INFO): Completed Startup
2025-04-25T19:17:26.325Z,1745608646.325 [MissionManager](INFO): Startup is completed.
2025-04-25T19:17:26.325Z,1745608646.325 [MissionManager](INFO): Uninitializing Mission Startup
2025-04-25T19:17:26.325Z,1745608646.325 [Startup] Stopped
2025-04-25T19:17:26.325Z,1745608646.325 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-04-25T19:17:26.325Z,1745608646.325 [Startup:A.GoToSurface] Stopped
2025-04-25T19:17:26.325Z,1745608646.325 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-25T19:17:26.735Z,1745608646.735 [MissionManager](IMPORTANT): Started mission Default
2025-04-25T19:17:26.735Z,1745608646.735 [Default] Running Loop=1
2025-04-25T19:17:26.735Z,1745608646.735 [Default](DEBUG): Aggregate::initialize Default
2025-04-25T19:17:26.735Z,1745608646.735 [Default:B.GoToSurface] Running Loop=1
2025-04-25T19:17:26.736Z,1745608646.736 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-25T19:17:26.736Z,1745608646.736 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-04-25T19:17:26.736Z,1745608646.736 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-04-25T19:17:26.736Z,1745608646.736 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-04-25T19:17:26.737Z,1745608646.737 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-04-25T19:17:26.738Z,1745608646.738 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-25T19:17:26.739Z,1745608646.739 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-25T19:17:26.739Z,1745608646.739 [Default:A.Wait] Running Loop=1
2025-04-25T19:17:26.739Z,1745608646.739 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-04-25T19:17:40.055Z,1745608660.055 [Default:A.Wait](INFO): Done Waiting.
2025-04-25T19:17:40.055Z,1745608660.055 [Default:A.Wait] Stopped
2025-04-25T19:17:40.056Z,1745608660.056 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T19:17:40.459Z,1745608660.459 [Default:CheckIn] Running Loop=1
2025-04-25T19:17:40.459Z,1745608660.459 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-25T19:17:40.459Z,1745608660.459 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-25T19:17:40.868Z,1745608660.868 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-25T19:18:02.135Z,1745608682.135 [CommandExec](IMPORTANT): got command strobe off
2025-04-25T19:18:02.135Z,1745608682.135 [CommandExec](IMPORTANT): Deactivating strobe
2025-04-25T19:19:12.964Z,1745608752.964 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-04-25T19:19:45.822Z,1745608785.822 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-04-25T19:20:05.221Z,1745608805.221 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-04-25T19:20:05.221Z,1745608805.221 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:20:05.232Z,1745608805.232 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:20:05.634Z,1745608805.634 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:20:05.634Z,1745608805.634 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-04-25T19:21:26.417Z,1745608886.417 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2025-04-25T19:21:26.433Z,1745608886.433 [BPC1](INFO): Received data from all battery sticks.
2025-04-25T19:22:40.796Z,1745608960.796 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-25T19:17:40.5Z
2025-04-25T19:22:40.796Z,1745608960.796 [Default:CheckIn:Read_GPS] Stopped
2025-04-25T19:22:40.796Z,1745608960.796 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-25T19:22:41.167Z,1745608961.167 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-25T19:22:48.444Z,1745608968.444 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250425T191352/Courier0004.lzma
2025-04-25T19:22:49.446Z,1745608969.446 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Courier0004.lzma.bak
2025-04-25T19:22:49.446Z,1745608969.446 [DataOverHttps](INFO): SBD MOMSN=24731794
2025-04-25T19:23:05.145Z,1745608985.145 [DataOverHttps](INFO): Sending 548 bytes from file Logs/20250425T191352/Express0005.lzma
2025-04-25T19:23:06.146Z,1745608986.146 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Express0005.lzma.bak
2025-04-25T19:23:06.146Z,1745608986.146 [DataOverHttps](INFO): SBD MOMSN=24731799
2025-04-25T19:23:06.209Z,1745608986.209 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-04-25T19:23:06.209Z,1745608986.209 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:23:06.236Z,1745608986.236 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:23:06.630Z,1745608986.630 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:23:06.630Z,1745608986.630 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-04-25T19:23:07.439Z,1745608987.439 [Default:CheckIn:Read_Iridium] Stopped
2025-04-25T19:23:07.439Z,1745608987.439 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-25T19:23:07.439Z,1745608987.439 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-25T19:24:45.994Z,1745609085.994 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-04-25T19:24:45.994Z,1745609085.994 [NAL9602] Data Fault, FailCount= 1
2025-04-25T19:24:45.994Z,1745609085.994 [NAL9602](ERROR): Data Fault
2025-04-25T19:24:46.011Z,1745609086.011 [CBIT](ERROR): Data Fault in component: NAL9602
2025-04-25T19:24:46.399Z,1745609086.399 [NAL9602](INFO): Powering down
2025-04-25T19:24:47.222Z,1745609087.222 [CBIT](INFO): Clearing failed state for component NAL9602
2025-04-25T19:24:47.222Z,1745609087.222 [NAL9602] No Fault, FailCount= 1
2025-04-25T19:25:16.692Z,1745609116.692 [NAL9602](INFO): Powering up NAL9602
2025-04-25T19:25:27.606Z,1745609127.606 [NAL9602](INFO): NAL9602 initialized
2025-04-25T19:26:07.236Z,1745609167.236 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-04-25T19:26:07.236Z,1745609167.236 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:26:07.260Z,1745609167.260 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:26:07.666Z,1745609167.666 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:26:07.666Z,1745609167.666 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-04-25T19:28:08.002Z,1745609288.002 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-25T19:28:08.002Z,1745609288.002 [Default:CheckIn:C.Wait] Stopped
2025-04-25T19:28:08.002Z,1745609288.002 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T19:28:08.002Z,1745609288.002 [Default:CheckIn:D] Running Loop=1
2025-04-25T19:28:08.405Z,1745609288.405 [Default:CheckIn:D] Stopped
2025-04-25T19:28:08.405Z,1745609288.405 [Default:CheckIn:E] Running Loop=1
2025-04-25T19:28:08.835Z,1745609288.835 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.694500 min 
2025-04-25T19:28:08.835Z,1745609288.835 [Default:CheckIn:E] Stopped
2025-04-25T19:28:08.835Z,1745609288.835 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-25T19:28:08.835Z,1745609288.835 [Default:CheckIn] Stopped
2025-04-25T19:28:08.835Z,1745609288.835 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T19:28:08.835Z,1745609288.835 [Default:CheckIn](INFO): Running loop #2
2025-04-25T19:28:08.835Z,1745609288.835 [Default:CheckIn] Running Loop=2
2025-04-25T19:28:08.836Z,1745609288.836 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-25T19:28:08.836Z,1745609288.836 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-25T19:29:08.217Z,1745609348.217 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-04-25T19:29:08.217Z,1745609348.217 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:29:08.228Z,1745609348.228 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:29:08.643Z,1745609348.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:29:08.643Z,1745609348.643 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-04-25T19:32:09.287Z,1745609529.287 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-04-25T19:32:09.287Z,1745609529.287 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:32:09.298Z,1745609529.298 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:32:09.708Z,1745609529.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:32:09.709Z,1745609529.709 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-04-25T19:33:09.072Z,1745609589.072 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-25T19:28:08.8Z
2025-04-25T19:33:09.072Z,1745609589.072 [Default:CheckIn:Read_GPS] Stopped
2025-04-25T19:33:09.072Z,1745609589.072 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-25T19:33:15.918Z,1745609595.918 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250425T191352/Courier0007.lzma
2025-04-25T19:33:16.921Z,1745609596.921 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Courier0007.lzma.bak
2025-04-25T19:33:16.921Z,1745609596.921 [DataOverHttps](INFO): SBD MOMSN=24731847
2025-04-25T19:33:32.570Z,1745609612.570 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250425T191352/Express0008.lzma
2025-04-25T19:33:33.573Z,1745609613.573 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Express0008.lzma.bak
2025-04-25T19:33:33.573Z,1745609613.573 [DataOverHttps](INFO): SBD MOMSN=24731849
2025-04-25T19:33:34.929Z,1745609614.929 [Default:CheckIn:Read_Iridium] Stopped
2025-04-25T19:33:34.929Z,1745609614.929 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-25T19:33:34.929Z,1745609614.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-25T19:35:10.309Z,1745609710.309 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-04-25T19:35:10.309Z,1745609710.309 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:35:10.320Z,1745609710.320 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:35:10.713Z,1745609710.713 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:35:10.713Z,1745609710.713 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-04-25T19:35:28.854Z,1745609728.854 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-04-25T19:35:28.854Z,1745609728.854 [NAL9602] Data Fault, FailCount= 2
2025-04-25T19:35:28.854Z,1745609728.854 [NAL9602](ERROR): Data Fault
2025-04-25T19:35:28.899Z,1745609728.899 [CBIT](ERROR): Data Fault in component: NAL9602
2025-04-25T19:35:29.256Z,1745609729.256 [NAL9602](INFO): Powering down
2025-04-25T19:35:30.087Z,1745609730.087 [CBIT](INFO): Clearing failed state for component NAL9602
2025-04-25T19:35:30.087Z,1745609730.087 [NAL9602] No Fault, FailCount= 2
2025-04-25T19:35:59.552Z,1745609759.552 [NAL9602](INFO): Powering up NAL9602
2025-04-25T19:36:10.464Z,1745609770.464 [NAL9602](INFO): NAL9602 initialized
2025-04-25T19:38:11.128Z,1745609891.128 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-04-25T19:38:11.128Z,1745609891.128 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:38:11.139Z,1745609891.139 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:38:11.542Z,1745609891.542 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:38:11.542Z,1745609891.542 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-04-25T19:38:35.348Z,1745609915.348 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-25T19:38:35.348Z,1745609915.348 [Default:CheckIn:C.Wait] Stopped
2025-04-25T19:38:35.348Z,1745609915.348 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T19:38:35.348Z,1745609915.348 [Default:CheckIn:D] Running Loop=1
2025-04-25T19:38:35.769Z,1745609915.769 [Default:CheckIn:D] Stopped
2025-04-25T19:38:35.769Z,1745609915.769 [Default:CheckIn:E] Running Loop=1
2025-04-25T19:38:36.178Z,1745609916.178 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  21.150562 min 
2025-04-25T19:38:36.178Z,1745609916.178 [Default:CheckIn:E] Stopped
2025-04-25T19:38:36.179Z,1745609916.179 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-25T19:38:36.179Z,1745609916.179 [Default:CheckIn] Stopped
2025-04-25T19:38:36.179Z,1745609916.179 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T19:38:36.179Z,1745609916.179 [Default:CheckIn](INFO): Running loop #3
2025-04-25T19:38:36.179Z,1745609916.179 [Default:CheckIn] Running Loop=3
2025-04-25T19:38:36.179Z,1745609916.179 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-25T19:38:36.179Z,1745609916.179 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-25T19:41:12.089Z,1745610072.089 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-04-25T19:41:12.089Z,1745610072.089 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:41:12.100Z,1745610072.100 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:41:12.522Z,1745610072.522 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:41:12.523Z,1745610072.523 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-04-25T19:43:36.317Z,1745610216.317 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-25T19:38:36.2Z
2025-04-25T19:43:36.317Z,1745610216.317 [Default:CheckIn:Read_GPS] Stopped
2025-04-25T19:43:36.317Z,1745610216.317 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-25T19:43:43.483Z,1745610223.483 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250425T191352/Courier0010.lzma
2025-04-25T19:43:44.485Z,1745610224.485 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Courier0010.lzma.bak
2025-04-25T19:43:44.485Z,1745610224.485 [DataOverHttps](INFO): SBD MOMSN=24731920
2025-04-25T19:44:00.092Z,1745610240.092 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250425T191352/Express0011.lzma
2025-04-25T19:44:01.093Z,1745610241.093 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Express0011.lzma.bak
2025-04-25T19:44:01.094Z,1745610241.094 [DataOverHttps](INFO): SBD MOMSN=24731922
2025-04-25T19:44:02.597Z,1745610242.597 [Default:CheckIn:Read_Iridium] Stopped
2025-04-25T19:44:02.597Z,1745610242.597 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-25T19:44:02.597Z,1745610242.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-25T19:44:13.113Z,1745610253.113 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-04-25T19:44:13.113Z,1745610253.113 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:44:13.123Z,1745610253.123 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:44:13.511Z,1745610253.511 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:44:13.511Z,1745610253.511 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-04-25T19:46:12.657Z,1745610372.657 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-04-25T19:46:12.657Z,1745610372.657 [NAL9602] Data Fault, FailCount= 3
2025-04-25T19:46:12.657Z,1745610372.657 [NAL9602](ERROR): Data Fault
2025-04-25T19:46:12.675Z,1745610372.675 [CBIT](ERROR): Data Fault in component: NAL9602
2025-04-25T19:46:13.062Z,1745610373.062 [NAL9602](INFO): Powering down
2025-04-25T19:46:13.904Z,1745610373.904 [CBIT](INFO): Clearing failed state for component NAL9602
2025-04-25T19:46:13.904Z,1745610373.904 [NAL9602] No Fault, FailCount= 3
2025-04-25T19:46:43.358Z,1745610403.358 [NAL9602](INFO): Powering up NAL9602
2025-04-25T19:46:54.266Z,1745610414.266 [NAL9602](INFO): NAL9602 initialized
2025-04-25T19:47:13.906Z,1745610433.906 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-04-25T19:47:13.906Z,1745610433.906 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:47:13.917Z,1745610433.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:47:14.317Z,1745610434.317 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:47:14.317Z,1745610434.317 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-04-25T19:49:02.990Z,1745610542.990 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-25T19:49:02.990Z,1745610542.990 [Default:CheckIn:C.Wait] Stopped
2025-04-25T19:49:02.990Z,1745610542.990 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T19:49:02.990Z,1745610542.990 [Default:CheckIn:D] Running Loop=1
2025-04-25T19:49:03.415Z,1745610543.415 [Default:CheckIn:D] Stopped
2025-04-25T19:49:03.415Z,1745610543.415 [Default:CheckIn:E] Running Loop=1
2025-04-25T19:49:03.807Z,1745610543.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  31.611324 min 
2025-04-25T19:49:03.807Z,1745610543.807 [Default:CheckIn:E] Stopped
2025-04-25T19:49:03.807Z,1745610543.807 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-25T19:49:03.807Z,1745610543.807 [Default:CheckIn] Stopped
2025-04-25T19:49:03.807Z,1745610543.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T19:49:03.808Z,1745610543.808 [Default:CheckIn](INFO): Running loop #4
2025-04-25T19:49:03.808Z,1745610543.808 [Default:CheckIn] Running Loop=4
2025-04-25T19:49:03.808Z,1745610543.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-25T19:49:03.808Z,1745610543.808 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-25T19:50:14.893Z,1745610614.893 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-04-25T19:50:14.893Z,1745610614.893 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:50:14.904Z,1745610614.904 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:50:15.312Z,1745610615.312 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:50:15.312Z,1745610615.312 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-04-25T19:53:15.890Z,1745610795.890 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-04-25T19:53:15.891Z,1745610795.891 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:53:15.902Z,1745610795.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:53:16.320Z,1745610796.320 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:53:16.320Z,1745610796.320 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-04-25T19:54:03.959Z,1745610843.959 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-25T19:49:03.8Z
2025-04-25T19:54:03.959Z,1745610843.959 [Default:CheckIn:Read_GPS] Stopped
2025-04-25T19:54:03.959Z,1745610843.959 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-25T19:54:11.180Z,1745610851.180 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250425T191352/Courier0013.lzma
2025-04-25T19:54:12.182Z,1745610852.182 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Courier0013.lzma.bak
2025-04-25T19:54:12.182Z,1745610852.182 [DataOverHttps](INFO): SBD MOMSN=24731958
2025-04-25T19:54:27.868Z,1745610867.868 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250425T191352/Express0014.lzma
2025-04-25T19:54:28.870Z,1745610868.870 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Express0014.lzma.bak
2025-04-25T19:54:28.871Z,1745610868.871 [DataOverHttps](INFO): SBD MOMSN=24731960
2025-04-25T19:54:30.243Z,1745610870.243 [Default:CheckIn:Read_Iridium] Stopped
2025-04-25T19:54:30.243Z,1745610870.243 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-25T19:54:30.243Z,1745610870.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-25T19:56:16.877Z,1745610976.877 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-04-25T19:56:16.877Z,1745610976.877 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:56:16.888Z,1745610976.888 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:56:17.299Z,1745610977.299 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:56:17.299Z,1745610977.299 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-04-25T19:56:56.458Z,1745611016.458 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-04-25T19:56:56.458Z,1745611016.458 [NAL9602] Data Fault, FailCount= 4
2025-04-25T19:56:56.458Z,1745611016.458 [NAL9602](ERROR): Data Fault
2025-04-25T19:56:56.496Z,1745611016.496 [CBIT](ERROR): Data Fault in component: NAL9602
2025-04-25T19:56:56.862Z,1745611016.862 [NAL9602](INFO): Powering down
2025-04-25T19:56:57.691Z,1745611017.691 [CBIT](INFO): Clearing failed state for component NAL9602
2025-04-25T19:56:57.691Z,1745611017.691 [NAL9602] No Fault, FailCount= 4
2025-04-25T19:57:11.669Z,1745611031.669 [CommandExec](IMPORTANT): got command failComponent
2025-04-25T19:57:11.669Z,1745611031.669 [CommandExec](IMPORTANT): Failed components:
2025-04-25T19:57:11.670Z,1745611031.670 [CommandExec](IMPORTANT): No failed Components.
2025-04-25T19:57:27.166Z,1745611047.166 [NAL9602](INFO): Powering up NAL9602
2025-04-25T19:57:29.705Z,1745611049.705 [CommandExec](IMPORTANT): got command gfscan
2025-04-25T19:57:30.050Z,1745611050.050 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-25T19:57:34.467Z,1745611054.467 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006520
CHAN A1 (24V): 0.000716
CHAN A2 (12V): -0.005614
CHAN A3 (5V): -0.002973
CHAN B0 (3.3V): -0.001249
CHAN B1 (3.15aV): -0.000891
CHAN B2 (3.15bV): -0.001455
CHAN B3 (GND): -0.000274
OPEN: 0.004806
Full Scale: +/- 1 mA
2025-04-25T19:57:38.074Z,1745611058.074 [NAL9602](INFO): NAL9602 initialized
2025-04-25T19:59:17.886Z,1745611157.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-04-25T19:59:17.886Z,1745611157.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T19:59:17.933Z,1745611157.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T19:59:18.311Z,1745611158.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T19:59:18.311Z,1745611158.311 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-04-25T19:59:30.819Z,1745611170.819 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-25T19:59:30.819Z,1745611170.819 [Default:CheckIn:C.Wait] Stopped
2025-04-25T19:59:30.819Z,1745611170.819 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T19:59:30.819Z,1745611170.819 [Default:CheckIn:D] Running Loop=1
2025-04-25T19:59:31.212Z,1745611171.212 [Default:CheckIn:D] Stopped
2025-04-25T19:59:31.213Z,1745611171.213 [Default:CheckIn:E] Running Loop=1
2025-04-25T19:59:31.607Z,1745611171.607 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  42.074613 min 
2025-04-25T19:59:31.607Z,1745611171.607 [Default:CheckIn:E] Stopped
2025-04-25T19:59:31.607Z,1745611171.607 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-25T19:59:31.607Z,1745611171.607 [Default:CheckIn] Stopped
2025-04-25T19:59:31.607Z,1745611171.607 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T19:59:31.607Z,1745611171.607 [Default:CheckIn](INFO): Running loop #5
2025-04-25T19:59:31.608Z,1745611171.608 [Default:CheckIn] Running Loop=5
2025-04-25T19:59:31.608Z,1745611171.608 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-25T19:59:31.608Z,1745611171.608 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-25T20:02:18.916Z,1745611338.916 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-04-25T20:02:18.916Z,1745611338.916 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T20:02:18.977Z,1745611338.977 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T20:02:19.311Z,1745611339.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T20:02:19.311Z,1745611339.311 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-04-25T20:04:31.802Z,1745611471.802 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-25T19:59:31.6Z
2025-04-25T20:04:31.802Z,1745611471.802 [Default:CheckIn:Read_GPS] Stopped
2025-04-25T20:04:31.802Z,1745611471.802 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-25T20:04:39.036Z,1745611479.036 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250425T191352/Courier0016.lzma
2025-04-25T20:04:40.038Z,1745611480.038 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Courier0016.lzma.bak
2025-04-25T20:04:40.038Z,1745611480.038 [DataOverHttps](INFO): SBD MOMSN=24731999
2025-04-25T20:04:57.165Z,1745611497.165 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20250425T191352/Express0017.lzma
2025-04-25T20:04:58.166Z,1745611498.166 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Express0017.lzma.bak
2025-04-25T20:04:58.166Z,1745611498.166 [DataOverHttps](INFO): SBD MOMSN=24732001
2025-04-25T20:04:59.694Z,1745611499.694 [Default:CheckIn:Read_Iridium] Stopped
2025-04-25T20:04:59.695Z,1745611499.695 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-25T20:04:59.695Z,1745611499.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-25T20:05:19.872Z,1745611519.872 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-04-25T20:05:19.872Z,1745611519.872 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T20:05:19.884Z,1745611519.884 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T20:05:20.290Z,1745611520.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T20:05:20.290Z,1745611520.290 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-04-25T20:06:13.445Z,1745611573.445 [CommandExec](IMPORTANT): got command failComponent
2025-04-25T20:06:13.445Z,1745611573.445 [CommandExec](IMPORTANT): Failed components:
2025-04-25T20:06:13.445Z,1745611573.445 [CommandExec](IMPORTANT): No failed Components.
2025-04-25T20:07:40.454Z,1745611660.454 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-04-25T20:07:40.454Z,1745611660.454 [NAL9602] Data Fault, FailCount= 5
2025-04-25T20:07:40.454Z,1745611660.454 [NAL9602](ERROR): Data Fault
2025-04-25T20:07:40.472Z,1745611660.472 [CBIT](ERROR): Data Fault in component: NAL9602
2025-04-25T20:07:40.472Z,1745611660.472 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-04-25T20:07:40.866Z,1745611660.866 [NAL9602](INFO): Powering down
2025-04-25T20:07:40.881Z,1745611660.881 [CommandExec](FAULT): Scheduling is paused
2025-04-25T20:07:40.882Z,1745611660.882 [CBIT](INFO): Critical error at 20250425T200740
2025-04-25T20:07:40.882Z,1745611660.882 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-04-25T20:08:20.861Z,1745611700.861 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-04-25T20:08:20.861Z,1745611700.861 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T20:08:20.872Z,1745611700.872 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T20:08:21.275Z,1745611701.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T20:08:21.275Z,1745611701.275 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-04-25T20:09:40.466Z,1745611780.466 [CBIT](INFO): Clearing failed state for component NAL9602
2025-04-25T20:09:40.466Z,1745611780.466 [NAL9602] No Fault, FailCount= 5
2025-04-25T20:09:40.850Z,1745611780.850 [NAL9602](INFO): Powering up NAL9602
2025-04-25T20:09:51.758Z,1745611791.758 [NAL9602](INFO): NAL9602 initialized
2025-04-25T20:10:00.250Z,1745611800.250 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-25T20:10:00.250Z,1745611800.250 [Default:CheckIn:C.Wait] Stopped
2025-04-25T20:10:00.250Z,1745611800.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T20:10:00.251Z,1745611800.251 [Default:CheckIn:D] Running Loop=1
2025-04-25T20:10:00.663Z,1745611800.663 [Default:CheckIn:D] Stopped
2025-04-25T20:10:00.663Z,1745611800.663 [Default:CheckIn:E] Running Loop=1
2025-04-25T20:10:01.087Z,1745611801.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  52.565458 min 
2025-04-25T20:10:01.087Z,1745611801.087 [Default:CheckIn:E] Stopped
2025-04-25T20:10:01.087Z,1745611801.087 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-25T20:10:01.087Z,1745611801.087 [Default:CheckIn] Stopped
2025-04-25T20:10:01.087Z,1745611801.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T20:10:01.087Z,1745611801.087 [Default:CheckIn](INFO): Running loop #6
2025-04-25T20:10:01.087Z,1745611801.087 [Default:CheckIn] Running Loop=6
2025-04-25T20:10:01.088Z,1745611801.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-25T20:10:01.088Z,1745611801.088 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-25T20:11:21.905Z,1745611881.905 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2025-04-25T20:11:21.905Z,1745611881.905 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T20:11:21.916Z,1745611881.916 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T20:11:22.359Z,1745611882.359 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T20:11:22.359Z,1745611882.359 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2025-04-25T20:14:22.923Z,1745612062.923 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2025-04-25T20:14:22.923Z,1745612062.923 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T20:14:22.935Z,1745612062.935 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T20:14:23.309Z,1745612063.309 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T20:14:23.309Z,1745612063.309 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2025-04-25T20:15:01.298Z,1745612101.298 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-25T20:10:01.1Z
2025-04-25T20:15:01.298Z,1745612101.298 [Default:CheckIn:Read_GPS] Stopped
2025-04-25T20:15:01.298Z,1745612101.298 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-25T20:15:08.122Z,1745612108.122 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20250425T191352/Courier0019.lzma
2025-04-25T20:15:09.124Z,1745612109.124 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Courier0019.lzma.bak
2025-04-25T20:15:09.124Z,1745612109.124 [DataOverHttps](INFO): SBD MOMSN=24732043
2025-04-25T20:15:24.827Z,1745612124.827 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250425T191352/Express0020.lzma
2025-04-25T20:15:25.828Z,1745612125.828 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Express0020.lzma.bak
2025-04-25T20:15:25.828Z,1745612125.828 [DataOverHttps](INFO): SBD MOMSN=24732046
2025-04-25T20:15:27.148Z,1745612127.148 [Default:CheckIn:Read_Iridium] Stopped
2025-04-25T20:15:27.148Z,1745612127.148 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-25T20:15:27.149Z,1745612127.149 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-25T20:17:23.923Z,1745612243.923 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2025-04-25T20:17:23.923Z,1745612243.923 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T20:17:23.938Z,1745612243.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T20:17:24.348Z,1745612244.348 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T20:17:24.348Z,1745612244.348 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2025-04-25T20:19:54.980Z,1745612394.980 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-04-25T20:19:54.980Z,1745612394.980 [NAL9602] Data Fault, FailCount= 1
2025-04-25T20:19:54.980Z,1745612394.980 [NAL9602](ERROR): Data Fault
2025-04-25T20:19:55.022Z,1745612395.022 [CBIT](ERROR): Data Fault in component: NAL9602
2025-04-25T20:19:55.384Z,1745612395.384 [NAL9602](INFO): Powering down
2025-04-25T20:19:56.205Z,1745612396.205 [CBIT](INFO): Clearing failed state for component NAL9602
2025-04-25T20:19:56.205Z,1745612396.205 [NAL9602] No Fault, FailCount= 1
2025-04-25T20:20:24.883Z,1745612424.883 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2025-04-25T20:20:24.883Z,1745612424.883 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T20:20:24.894Z,1745612424.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T20:20:25.301Z,1745612425.301 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T20:20:25.301Z,1745612425.301 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2025-04-25T20:20:25.684Z,1745612425.684 [NAL9602](INFO): Powering up NAL9602
2025-04-25T20:20:27.712Z,1745612427.712 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-25T20:20:27.712Z,1745612427.712 [Default:CheckIn:C.Wait] Stopped
2025-04-25T20:20:27.712Z,1745612427.712 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T20:20:27.712Z,1745612427.712 [Default:CheckIn:D] Running Loop=1
2025-04-25T20:20:28.116Z,1745612428.116 [Default:CheckIn:D] Stopped
2025-04-25T20:20:28.116Z,1745612428.116 [Default:CheckIn:E] Running Loop=1
2025-04-25T20:20:28.522Z,1745612428.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  63.023002 min 
2025-04-25T20:20:28.522Z,1745612428.522 [Default:CheckIn:E] Stopped
2025-04-25T20:20:28.522Z,1745612428.522 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-25T20:20:28.522Z,1745612428.522 [Default:CheckIn] Stopped
2025-04-25T20:20:28.522Z,1745612428.522 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T20:20:28.522Z,1745612428.522 [Default:CheckIn](INFO): Running loop #7
2025-04-25T20:20:28.522Z,1745612428.522 [Default:CheckIn] Running Loop=7
2025-04-25T20:20:28.523Z,1745612428.523 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-25T20:20:28.523Z,1745612428.523 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-25T20:20:36.592Z,1745612436.592 [NAL9602](INFO): NAL9602 initialized
2025-04-25T20:23:25.904Z,1745612605.904 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2025-04-25T20:23:25.904Z,1745612605.904 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-25T20:23:25.914Z,1745612605.914 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-25T20:23:26.325Z,1745612606.325 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-25T20:23:26.325Z,1745612606.325 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2025-04-25T20:25:28.768Z,1745612728.768 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-25T20:20:28.5Z
2025-04-25T20:25:28.768Z,1745612728.768 [Default:CheckIn:Read_GPS] Stopped
2025-04-25T20:25:28.768Z,1745612728.768 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-25T20:25:38.766Z,1745612738.766 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250425T191352/Courier0022.lzma
2025-04-25T20:25:39.768Z,1745612739.768 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Courier0022.lzma.bak
2025-04-25T20:25:39.769Z,1745612739.769 [DataOverHttps](INFO): SBD MOMSN=24732091
2025-04-25T20:25:56.962Z,1745612756.962 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20250425T191352/Express0023.lzma
2025-04-25T20:25:57.964Z,1745612757.964 [DataOverHttps](INFO): Moved sent file to Logs/20250425T191352/Express0023.lzma.bak
2025-04-25T20:25:57.964Z,1745612757.964 [DataOverHttps](INFO): SBD MOMSN=24732093
2025-04-25T20:25:59.452Z,1745612759.452 [Default:CheckIn:Read_Iridium] Stopped
2025-04-25T20:25:59.452Z,1745612759.452 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-25T20:25:59.452Z,1745612759.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-25T20:26:16.932Z,1745612776.932 [CommandExec](IMPORTANT): got command restart application
2025-04-25T20:26:17.934Z,1745612777.934 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:17.935Z,1745612777.935 [CommandExec](INFO): Uninitializing the command executive.
2025-04-25T20:26:17.935Z,1745612777.935 [CommandExec](INFO): Uninitializing the command scheduler.
2025-04-25T20:26:17.935Z,1745612777.935 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:17.950Z,1745612777.950 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-04-25T20:26:17.950Z,1745612777.950 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-04-25T20:26:17.951Z,1745612777.951 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:17.951Z,1745612777.951 [NavChartDb](INFO): Join timeout helper Thread ID is 4464
2025-04-25T20:26:18.247Z,1745612778.247 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:18.247Z,1745612778.247 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:18.266Z,1745612778.266 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-04-25T20:26:18.266Z,1745612778.266 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:18.267Z,1745612778.267 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4465
2025-04-25T20:26:18.442Z,1745612778.442 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:18.443Z,1745612778.443 [WetLabsBB2FL](INFO): Powering down
2025-04-25T20:26:18.443Z,1745612778.443 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:18.450Z,1745612778.450 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-04-25T20:26:18.451Z,1745612778.451 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:18.451Z,1745612778.451 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4466
2025-04-25T20:26:19.263Z,1745612779.263 [CTD_Seabird](INFO): Powering down
2025-04-25T20:26:19.275Z,1745612779.275 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:19.275Z,1745612779.275 [CTD_Seabird](INFO): Powering down
2025-04-25T20:26:19.286Z,1745612779.286 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:19.287Z,1745612779.287 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-04-25T20:26:19.287Z,1745612779.287 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:19.287Z,1745612779.287 [Radio_Surface](INFO): Join timeout helper Thread ID is 4467
2025-04-25T20:26:19.339Z,1745612779.339 [Radio_Surface](INFO): Powering down
2025-04-25T20:26:19.340Z,1745612779.340 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:19.340Z,1745612779.340 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:19.355Z,1745612779.355 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-04-25T20:26:19.355Z,1745612779.355 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:19.355Z,1745612779.355 [Onboard](INFO): Join timeout helper Thread ID is 4468
2025-04-25T20:26:19.663Z,1745612779.663 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:19.663Z,1745612779.663 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:19.680Z,1745612779.680 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-04-25T20:26:19.680Z,1745612779.680 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:19.681Z,1745612779.681 [DataOverHttps](INFO): Join timeout helper Thread ID is 4469
2025-04-25T20:26:20.310Z,1745612780.310 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:20.311Z,1745612780.311 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.323Z,1745612780.323 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-04-25T20:26:20.323Z,1745612780.323 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.324Z,1745612780.324 [DAT](INFO): Join timeout helper Thread ID is 4470
2025-04-25T20:26:20.437Z,1745612780.437 [DAT](INFO): Powering down
2025-04-25T20:26:20.507Z,1745612780.507 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:20.507Z,1745612780.507 [DAT](INFO): Powering down
2025-04-25T20:26:20.508Z,1745612780.508 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.523Z,1745612780.523 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-04-25T20:26:20.523Z,1745612780.523 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.524Z,1745612780.524 [BackseatComponent](INFO): Join timeout helper Thread ID is 4471
2025-04-25T20:26:20.575Z,1745612780.575 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:20.575Z,1745612780.575 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.587Z,1745612780.587 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-04-25T20:26:20.587Z,1745612780.587 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.588Z,1745612780.588 [logger](INFO): Join timeout helper Thread ID is 4472
2025-04-25T20:26:20.620Z,1745612780.620 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:20.620Z,1745612780.620 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.623Z,1745612780.623 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-04-25T20:26:20.623Z,1745612780.623 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.624Z,1745612780.624 [CommandLine](INFO): Join timeout helper Thread ID is 4473
2025-04-25T20:26:20.698Z,1745612780.698 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:20.699Z,1745612780.699 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.703Z,1745612780.703 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-04-25T20:26:20.703Z,1745612780.703 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.704Z,1745612780.704 [CommandExec](INFO): Join timeout helper Thread ID is 4474
2025-04-25T20:26:20.705Z,1745612780.705 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-04-25T20:26:20.705Z,1745612780.705 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.705Z,1745612780.705 [controlThread](INFO): Join timeout helper Thread ID is 4475
2025-04-25T20:26:20.834Z,1745612780.834 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-25T20:26:20.835Z,1745612780.835 [controlThread](DEBUG): Uninitializing ControlThread
2025-04-25T20:26:20.835Z,1745612780.835 [AHRS_M2](INFO): Powering down
2025-04-25T20:26:20.915Z,1745612780.915 [MultiRay](INFO): Powering down LCB2
2025-04-25T20:26:20.915Z,1745612780.915 [MultiRay](INFO): Powering down.
2025-04-25T20:26:20.916Z,1745612780.916 [NAL9602](INFO): Powering down
2025-04-25T20:26:20.918Z,1745612780.918 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-04-25T20:26:20.919Z,1745612780.919 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-04-25T20:26:20.920Z,1745612780.920 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-04-25T20:26:20.920Z,1745612780.920 [MissionManager](INFO): Uninitializing Mission Default
2025-04-25T20:26:20.920Z,1745612780.920 [Default] Stopped
2025-04-25T20:26:20.920Z,1745612780.920 [Default](DEBUG): Aggregate::uninitialize Default
2025-04-25T20:26:20.921Z,1745612780.921 [Default:B.GoToSurface] Stopped
2025-04-25T20:26:20.921Z,1745612780.921 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-25T20:26:20.921Z,1745612780.921 [Default:CheckIn] Stopped
2025-04-25T20:26:20.921Z,1745612780.921 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T20:26:20.921Z,1745612780.921 [Default:CheckIn:C.Wait] Stopped
2025-04-25T20:26:20.921Z,1745612780.921 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T20:26:20.924Z,1745612780.924 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-04-25T20:26:20.925Z,1745612780.925 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-04-25T20:26:20.925Z,1745612780.925 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-04-25T20:26:20.925Z,1745612780.925 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-04-25T20:26:20.926Z,1745612780.926 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-04-25T20:26:20.926Z,1745612780.926 [BuoyancyServo](INFO): Powering down
2025-04-25T20:26:20.939Z,1745612780.939 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-25T20:26:20.939Z,1745612780.939 [ElevatorServo](INFO): Powering down
2025-04-25T20:26:20.939Z,1745612780.939 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-25T20:26:20.940Z,1745612780.940 [MassServo](INFO): Powering down
2025-04-25T20:26:20.940Z,1745612780.940 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-04-25T20:26:20.941Z,1745612780.941 [RudderServo](INFO): Powering down
2025-04-25T20:26:20.941Z,1745612780.941 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-25T20:26:20.941Z,1745612780.941 [ThrusterHE](INFO): Powering down
2025-04-25T20:26:20.942Z,1745612780.942 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-04-25T20:26:20.943Z,1745612780.943 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-04-25T20:26:20.943Z,1745612780.943 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-04-25T20:26:20.943Z,1745612780.943 [CBIT](DEBUG): Powering off loads.
2025-04-25T20:26:20.954Z,1745612780.954 [CBIT](DEBUG): Disabling WDT.
2025-04-25T20:26:20.966Z,1745612780.966 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-04-25T20:26:20.967Z,1745612780.967 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-04-25T20:26:20.968Z,1745612780.968 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:20.996Z,1745612780.996 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:21.000Z,1745612781.000 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:21.007Z,1745612781.007 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:21.014Z,1745612781.014 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:21.058Z,1745612781.058 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:21.092Z,1745612781.092 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-04-25T20:26:21.093Z,1745612781.093 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-04-25T20:26:21.108Z,1745612781.108 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:21.113Z,1745612781.113 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:21.167Z,1745612781.167 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-25T20:26:21.219Z,1745612781.219 [logger ThreadHandler](INFO): Thread cancelled.