2025-06-20T16:13:07.041Z,1750435987.041 [Supervisor](DEBUG): Initializing supervisor.
2025-06-20T16:13:07.046Z,1750435987.046 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-06-20T16:13:07.046Z,1750435987.046 [SyncHandler](INFO): Protected caller Thread ID is 837
2025-06-20T16:13:07.046Z,1750435987.046 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-06-20T16:13:07.047Z,1750435987.047 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-06-20T16:13:07.048Z,1750435987.048 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2025-06-20T16:13:07.052Z,1750435987.052 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-06-20T16:13:07.072Z,1750435987.072 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-06-20T16:13:07.073Z,1750435987.073 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-06-20T16:13:07.073Z,1750435987.073 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2025-06-20T16:13:07.078Z,1750435987.078 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-06-20T16:13:07.079Z,1750435987.079 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-06-20T16:13:07.079Z,1750435987.079 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2025-06-20T16:13:07.081Z,1750435987.081 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-06-20T16:13:07.082Z,1750435987.082 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-06-20T16:13:07.083Z,1750435987.083 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2025-06-20T16:13:07.087Z,1750435987.087 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-06-20T16:13:07.087Z,1750435987.087 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-06-20T16:13:07.090Z,1750435987.090 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-06-20T16:13:07.315Z,1750435987.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-06-20T16:13:07.315Z,1750435987.315 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-06-20T16:13:07.432Z,1750435987.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-06-20T16:13:07.434Z,1750435987.434 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-06-20T16:13:07.540Z,1750435987.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-06-20T16:13:07.542Z,1750435987.542 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-06-20T16:13:08.094Z,1750435988.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-06-20T16:13:08.096Z,1750435988.096 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-06-20T16:13:08.464Z,1750435988.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-06-20T16:13:08.465Z,1750435988.465 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-06-20T16:13:08.824Z,1750435988.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-06-20T16:13:08.825Z,1750435988.825 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-06-20T16:13:09.034Z,1750435989.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-06-20T16:13:09.035Z,1750435989.035 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-06-20T16:13:09.124Z,1750435989.124 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-06-20T16:13:09.217Z,1750435989.217 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-06-20T16:13:09.738Z,1750435989.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-06-20T16:13:09.739Z,1750435989.739 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-06-20T16:13:10.042Z,1750435990.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-06-20T16:13:10.042Z,1750435990.042 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-06-20T16:13:11.247Z,1750435991.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-06-20T16:13:11.248Z,1750435991.248 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-06-20T16:13:11.498Z,1750435991.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-06-20T16:13:11.500Z,1750435991.500 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-06-20T16:13:11.756Z,1750435991.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-06-20T16:13:11.757Z,1750435991.757 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-06-20T16:13:11.911Z,1750435991.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-06-20T16:13:11.913Z,1750435991.913 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-06-20T16:13:12.830Z,1750435992.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-06-20T16:13:12.832Z,1750435992.832 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2025-06-20T16:13:12.835Z,1750435992.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2025-06-20T16:13:12.930Z,1750435992.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2025-06-20T16:13:13.072Z,1750435993.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2025-06-20T16:13:13.351Z,1750435993.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-06-20T16:13:13.353Z,1750435993.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2025-06-20T16:13:13.492Z,1750435993.492 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2025-06-20T16:13:13.602Z,1750435993.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2025-06-20T16:13:13.708Z,1750435993.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2025-06-20T16:13:13.829Z,1750435993.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2025-06-20T16:13:13.926Z,1750435993.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2025-06-20T16:13:14.023Z,1750435994.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2025-06-20T16:13:14.127Z,1750435994.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2025-06-20T16:13:14.313Z,1750435994.313 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2025-06-20T16:13:14.313Z,1750435994.313 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-06-20T16:13:14.330Z,1750435994.330 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-06-20T16:13:14.403Z,1750435994.403 [DepthRateCalculator] Loaded
2025-06-20T16:13:14.403Z,1750435994.403 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-06-20T16:13:14.409Z,1750435994.409 [PitchRateCalculator] Loaded
2025-06-20T16:13:14.409Z,1750435994.409 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-06-20T16:13:14.420Z,1750435994.420 [SpeedCalculator] Loaded
2025-06-20T16:13:14.420Z,1750435994.420 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-06-20T16:13:14.425Z,1750435994.425 [YawRateCalculator] Loaded
2025-06-20T16:13:14.425Z,1750435994.425 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-06-20T16:13:14.447Z,1750435994.447 [ElevatorOffsetCalculator] Loaded
2025-06-20T16:13:14.447Z,1750435994.447 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-06-20T16:13:14.447Z,1750435994.447 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-06-20T16:13:14.448Z,1750435994.448 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-06-20T16:13:14.496Z,1750435994.496 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-06-20T16:13:14.497Z,1750435994.497 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-06-20T16:13:14.513Z,1750435994.513 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-06-20T16:13:14.513Z,1750435994.513 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-06-20T16:13:15.360Z,1750435995.360 [AHRS_M2] Loaded
2025-06-20T16:13:15.360Z,1750435995.360 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-06-20T16:13:15.613Z,1750435995.613 [BackseatComponent] Loaded
2025-06-20T16:13:15.613Z,1750435995.613 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-06-20T16:13:15.614Z,1750435995.614 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0
2025-06-20T16:13:15.615Z,1750435995.615 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 925
2025-06-20T16:13:15.618Z,1750435995.618 [LcmUniversalReporter] Loaded
2025-06-20T16:13:15.618Z,1750435995.618 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-06-20T16:13:16.557Z,1750435996.557 [BPC1] Loaded
2025-06-20T16:13:16.557Z,1750435996.557 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-06-20T16:13:16.920Z,1750435996.920 [DAT] Loaded
2025-06-20T16:13:16.920Z,1750435996.920 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-06-20T16:13:16.921Z,1750435996.921 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0
2025-06-20T16:13:16.921Z,1750435996.921 [DAT ThreadHandler](INFO): Protected caller Thread ID is 926
2025-06-20T16:13:17.180Z,1750435997.180 [DataOverHttps] Loaded
2025-06-20T16:13:17.180Z,1750435997.180 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-06-20T16:13:17.185Z,1750435997.185 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0
2025-06-20T16:13:17.185Z,1750435997.185 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 927
2025-06-20T16:13:17.241Z,1750435997.241 [Depth_Keller] Loaded
2025-06-20T16:13:17.242Z,1750435997.242 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-06-20T16:13:17.249Z,1750435997.249 [DropWeight] Loaded
2025-06-20T16:13:17.249Z,1750435997.249 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-06-20T16:13:17.270Z,1750435997.270 [MultiRay](INFO): Found secondary power supply at: /dev/loadC7
2025-06-20T16:13:17.270Z,1750435997.270 [MultiRay] Loaded
2025-06-20T16:13:17.271Z,1750435997.271 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2025-06-20T16:13:17.335Z,1750435997.335 [NAL9602] Loaded
2025-06-20T16:13:17.335Z,1750435997.335 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-06-20T16:13:17.365Z,1750435997.365 [Onboard] Loaded
2025-06-20T16:13:17.365Z,1750435997.365 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-06-20T16:13:17.366Z,1750435997.366 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2025-06-20T16:13:17.367Z,1750435997.367 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 928
2025-06-20T16:13:17.373Z,1750435997.373 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-06-20T16:13:17.387Z,1750435997.387 [PowerOnly] Loaded
2025-06-20T16:13:17.387Z,1750435997.387 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-06-20T16:13:17.401Z,1750435997.401 [Power24vConverter] Loaded
2025-06-20T16:13:17.401Z,1750435997.401 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-06-20T16:13:17.418Z,1750435997.418 [Radio_Surface] Loaded
2025-06-20T16:13:17.418Z,1750435997.418 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-06-20T16:13:17.419Z,1750435997.419 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2025-06-20T16:13:17.419Z,1750435997.419 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 929
2025-06-20T16:13:17.420Z,1750435997.420 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-06-20T16:13:17.421Z,1750435997.421 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-06-20T16:13:17.560Z,1750435997.560 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-06-20T16:13:17.561Z,1750435997.561 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-06-20T16:13:17.575Z,1750435997.575 [NavChart] Loaded
2025-06-20T16:13:17.575Z,1750435997.575 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-06-20T16:13:17.581Z,1750435997.581 [UniversalFixResidualReporter] Loaded
2025-06-20T16:13:17.581Z,1750435997.581 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-06-20T16:13:17.582Z,1750435997.582 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-06-20T16:13:17.582Z,1750435997.582 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-06-20T16:13:17.782Z,1750435997.782 [SBIT](DEBUG): Construct Startup Built In Test.
2025-06-20T16:13:17.797Z,1750435997.797 [SBIT] Loaded
2025-06-20T16:13:17.797Z,1750435997.797 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-06-20T16:13:17.800Z,1750435997.800 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-06-20T16:13:17.813Z,1750435997.813 [IBIT] Loaded
2025-06-20T16:13:17.814Z,1750435997.814 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-06-20T16:13:17.819Z,1750435997.819 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-06-20T16:13:17.899Z,1750435997.899 [CBIT] Loaded
2025-06-20T16:13:17.899Z,1750435997.899 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-06-20T16:13:17.931Z,1750435997.931 [GFScanner] Loaded
2025-06-20T16:13:17.931Z,1750435997.931 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-06-20T16:13:17.931Z,1750435997.931 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-06-20T16:13:17.932Z,1750435997.932 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-06-20T16:13:18.151Z,1750435998.151 [CTD_Seabird] Loaded
2025-06-20T16:13:18.151Z,1750435998.151 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-06-20T16:13:18.152Z,1750435998.152 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2025-06-20T16:13:18.153Z,1750435998.153 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 930
2025-06-20T16:13:18.174Z,1750435998.174 [PAR_Licor] Loaded
2025-06-20T16:13:18.174Z,1750435998.174 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-06-20T16:13:18.219Z,1750435998.219 [WetLabsBB2FL] Loaded
2025-06-20T16:13:18.220Z,1750435998.220 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-06-20T16:13:18.221Z,1750435998.221 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2025-06-20T16:13:18.221Z,1750435998.221 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 931
2025-06-20T16:13:18.222Z,1750435998.222 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-06-20T16:13:18.222Z,1750435998.222 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-06-20T16:13:18.393Z,1750435998.393 [BuoyancyServo] Loaded
2025-06-20T16:13:18.393Z,1750435998.393 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-06-20T16:13:18.418Z,1750435998.418 [ElevatorServo] Loaded
2025-06-20T16:13:18.418Z,1750435998.418 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-06-20T16:13:18.441Z,1750435998.441 [MassServo] Loaded
2025-06-20T16:13:18.442Z,1750435998.442 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-06-20T16:13:18.464Z,1750435998.464 [RudderServo] Loaded
2025-06-20T16:13:18.464Z,1750435998.464 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-06-20T16:13:18.482Z,1750435998.482 [ThrusterHE] Loaded
2025-06-20T16:13:18.483Z,1750435998.483 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-06-20T16:13:18.483Z,1750435998.483 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-06-20T16:13:18.484Z,1750435998.484 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-06-20T16:13:18.556Z,1750435998.556 [VerticalControl](DEBUG): Construct VerticalControl.
2025-06-20T16:13:18.609Z,1750435998.609 [VerticalControl] Loaded
2025-06-20T16:13:18.609Z,1750435998.609 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-06-20T16:13:18.612Z,1750435998.612 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-06-20T16:13:18.657Z,1750435998.657 [HorizontalControl] Loaded
2025-06-20T16:13:18.657Z,1750435998.657 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-06-20T16:13:18.659Z,1750435998.659 [SpeedControl](DEBUG): Construct SpeedControl.
2025-06-20T16:13:18.661Z,1750435998.661 [SpeedControl] Loaded
2025-06-20T16:13:18.661Z,1750435998.661 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-06-20T16:13:18.664Z,1750435998.664 [LoopControl](DEBUG): Construct LoopControl.
2025-06-20T16:13:18.665Z,1750435998.665 [LoopControl] Loaded
2025-06-20T16:13:18.665Z,1750435998.665 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-06-20T16:13:18.665Z,1750435998.665 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-06-20T16:13:18.666Z,1750435998.666 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-06-20T16:13:18.841Z,1750435998.841 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-06-20T16:13:18.841Z,1750435998.841 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-06-20T16:13:19.233Z,1750435999.233 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-06-20T16:13:19.234Z,1750435999.234 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-06-20T16:13:19.960Z,1750435999.960 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-06-20T16:13:19.961Z,1750435999.961 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-06-20T16:13:20.049Z,1750436000.049 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-06-20T16:13:20.056Z,1750436000.056 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-06-20T16:13:20.059Z,1750436000.059 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-06-20T16:13:20.070Z,1750436000.070 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-06-20T16:13:20.071Z,1750436000.071 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-06-20T16:13:20.071Z,1750436000.071 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 932
2025-06-20T16:13:20.076Z,1750436000.076 [Supervisor](INFO): Main Thread ID is 829
2025-06-20T16:13:20.076Z,1750436000.076 [Supervisor](DEBUG): Running supervisor.
2025-06-20T16:13:20.077Z,1750436000.077 [CommandExec ThreadHandler](INFO): Handler Thread ID is 933
2025-06-20T16:13:20.077Z,1750436000.077 [CommandExec](INFO): Initializing the command executive.
2025-06-20T16:13:20.079Z,1750436000.079 [CommandLine ThreadHandler](INFO): Handler Thread ID is 934
2025-06-20T16:13:20.081Z,1750436000.081 [controlThread ThreadHandler](INFO): Handler Thread ID is 935
2025-06-20T16:13:20.081Z,1750436000.081 [controlThread](DEBUG): Initializing ControlThread
2025-06-20T16:13:20.082Z,1750436000.082 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-06-20T16:13:20.083Z,1750436000.083 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-06-20T16:13:20.083Z,1750436000.083 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-06-20T16:13:20.083Z,1750436000.083 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-06-20T16:13:20.084Z,1750436000.084 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-06-20T16:13:20.087Z,1750436000.087 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-06-20T16:13:20.088Z,1750436000.088 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-06-20T16:13:20.088Z,1750436000.088 [SBIT](INFO): Initialize SBIT Component.
2025-06-20T16:13:20.088Z,1750436000.088 [SBIT](IMPORTANT): git: 2025-05-12_B
2025-06-20T16:13:20.089Z,1750436000.089 [SBIT](INFO): git hash: 7c5b1c07405cfe7ca09821eb029d166d25d65d57
2025-06-20T16:13:20.089Z,1750436000.089 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-06-20T16:13:20.090Z,1750436000.090 [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-06-20T16:13:20.091Z,1750436000.091 [SBIT](INFO): Beginning SBIT in 122.000000 seconds.
2025-06-20T16:13:20.092Z,1750436000.092 [IBIT](INFO): Initialize IBIT Component.
2025-06-20T16:13:20.092Z,1750436000.092 [CBIT](DEBUG): Initialize CBIT Component.
2025-06-20T16:13:20.093Z,1750436000.093 [logger ThreadHandler](INFO): Handler Thread ID is 936
2025-06-20T16:13:20.105Z,1750436000.105 [CBIT](DEBUG): Initialized mux pins.
2025-06-20T16:13:20.105Z,1750436000.105 [CBIT](DEBUG): Initializing the watchdog timer.
2025-06-20T16:13:20.114Z,1750436000.114 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937
2025-06-20T16:13:20.129Z,1750436000.129 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-06-20T16:13:20.130Z,1750436000.130 [CBIT](DEBUG): Initializing heartbeat.
2025-06-20T16:13:20.130Z,1750436000.130 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2025-06-20T16:13:20.131Z,1750436000.131 [DAT](INFO): Powering up
2025-06-20T16:13:20.131Z,1750436000.131 [DAT](DEBUG): Initializing DAT.
2025-06-20T16:13:20.135Z,1750436000.135 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 940
2025-06-20T16:13:20.136Z,1750436000.136 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-06-20T16:13:20.154Z,1750436000.154 [Onboard ThreadHandler](INFO): Handler Thread ID is 941
2025-06-20T16:13:20.171Z,1750436000.171 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 942
2025-06-20T16:13:20.194Z,1750436000.194 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 943
2025-06-20T16:13:20.194Z,1750436000.194 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-06-20T16:13:20.199Z,1750436000.199 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 945
2025-06-20T16:13:20.201Z,1750436000.201 [CBIT](DEBUG): Deactivating emergency mode.
2025-06-20T16:13:20.202Z,1750436000.202 [WetLabsBB2FL](INFO): Powering up
2025-06-20T16:13:20.203Z,1750436000.203 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 947
2025-06-20T16:13:20.210Z,1750436000.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-06-20T16:13:20.210Z,1750436000.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-06-20T16:13:20.210Z,1750436000.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-06-20T16:13:20.211Z,1750436000.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-06-20T16:13:20.211Z,1750436000.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-06-20T16:13:20.211Z,1750436000.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-06-20T16:13:20.211Z,1750436000.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-06-20T16:13:20.211Z,1750436000.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-06-20T16:13:20.241Z,1750436000.241 [CBIT](DEBUG): Backplane powered.
2025-06-20T16:13:20.242Z,1750436000.242 [GFScanner](DEBUG): Initializing GFScanner
2025-06-20T16:13:20.242Z,1750436000.242 [GFScanner](DEBUG): Deactivating GF circuits.
2025-06-20T16:13:20.251Z,1750436000.251 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-06-20T16:13:20.252Z,1750436000.252 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-06-20T16:13:20.253Z,1750436000.253 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-06-20T16:13:20.254Z,1750436000.254 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-06-20T16:13:20.254Z,1750436000.254 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-06-20T16:13:20.255Z,1750436000.255 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-06-20T16:13:20.255Z,1750436000.255 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-06-20T16:13:20.262Z,1750436000.262 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-06-20T16:13:20.336Z,1750436000.336 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-06-20T16:13:20.340Z,1750436000.340 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-20T16:13:20.365Z,1750436000.365 [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-06-20T16:13:20.378Z,1750436000.378 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-06-20T16:13:20.378Z,1750436000.378 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-06-20T16:13:20.380Z,1750436000.380 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-06-20T16:13:20.578Z,1750436000.578 [Radio_Surface](INFO): Powering up
2025-06-20T16:13:20.610Z,1750436000.610 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-06-20T16:13:20.618Z,1750436000.618 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-06-20T16:13:20.619Z,1750436000.619 [Default:A.Wait](DEBUG): Construct Wait.
2025-06-20T16:13:20.622Z,1750436000.622 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-20T16:13:20.647Z,1750436000.647 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-06-20T16:13:20.662Z,1750436000.662 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-06-20T16:13:20.667Z,1750436000.667 [Default:E.Execute](DEBUG): Construct Execute.
2025-06-20T16:13:20.671Z,1750436000.671 [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-06-20T16:13:20.681Z,1750436000.681 [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-06-20T16:13:20.697Z,1750436000.697 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-06-20T16:13:20.810Z,1750436000.810 [Depth_Keller](INFO): Initializing.
2025-06-20T16:13:20.811Z,1750436000.811 [MultiRay](INFO): Powering up MultiRay Lights
2025-06-20T16:13:20.811Z,1750436000.811 [MultiRay](INFO): Powering up secondary power supply.
2025-06-20T16:13:20.851Z,1750436000.851 [Power24vConverter](INFO): Powering up.
2025-06-20T16:13:20.858Z,1750436000.858 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-20T16:13:20.876Z,1750436000.876 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-06-20T16:13:20.886Z,1750436000.886 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-06-20T16:13:20.887Z,1750436000.887 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-20T16:13:20.894Z,1750436000.894 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-20T16:13:20.894Z,1750436000.894 [MassServo](DEBUG): Initializing EZServoServo.
2025-06-20T16:13:20.906Z,1750436000.906 [MassServo](DEBUG): Initializing MassServo.
2025-06-20T16:13:20.906Z,1750436000.906 [RudderServo](DEBUG): Initializing EZServoServo.
2025-06-20T16:13:20.914Z,1750436000.914 [RudderServo](DEBUG): Initializing RudderServo.
2025-06-20T16:13:20.914Z,1750436000.914 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-06-20T16:13:20.922Z,1750436000.922 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-06-20T16:13:21.902Z,1750436001.902 [MultiRay](INFO): Powering down
2025-06-20T16:13:22.021Z,1750436002.021 [MultiRay](INFO): Powering down LCB2
2025-06-20T16:13:22.809Z,1750436002.809 [WetLabsBB2FL](INFO): Powering down
2025-06-20T16:13:26.474Z,1750436006.474 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-06-20T16:13:32.478Z,1750436012.478 [DAT](INFO): DAT read:
2025-06-20T16:13:32.480Z,1750436012.480 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-06-20T16:13:33.990Z,1750436013.990 [DAT](INFO): DAT read: MF Frequency Band
2025-06-20T16:13:33.991Z,1750436013.991 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-06-20T16:13:33.992Z,1750436013.992 [DAT](INFO): DAT read: Jun 20 2025 16:13:11
2025-06-20T16:13:34.998Z,1750436014.998 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-06-20T16:13:34.000Z,1750436015.000 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-06-20T16:13:34.000Z,1750436015.000 [DAT](INFO): commRate: 800
2025-06-20T16:13:37.065Z,1750436017.065 [DAT](INFO): entering command mode
2025-06-20T16:13:37.266Z,1750436017.266 [DAT](INFO): DAT read:
2025-06-20T16:13:37.267Z,1750436017.267 [DAT](INFO): DAT read: user:1>
2025-06-20T16:13:37.267Z,1750436017.267 [DAT](INFO): setting verbose to 3
2025-06-20T16:13:37.518Z,1750436017.518 [DAT](INFO): DAT read: user:1>
2025-06-20T16:13:37.519Z,1750436017.519 [DAT](INFO): DAT read: Verbose | 3
2025-06-20T16:13:37.519Z,1750436017.519 [DAT](INFO): set verbose to 3
2025-06-20T16:13:37.520Z,1750436017.520 [DAT](INFO): setting DatVerbose to 27440
2025-06-20T16:13:37.770Z,1750436017.770 [DAT](INFO): DAT read: user:2>
2025-06-20T16:13:37.771Z,1750436017.771 [DAT](INFO): DAT read: DatVerbose | 27440
2025-06-20T16:13:37.771Z,1750436017.771 [DAT](INFO): set DatVerbose to 27440
2025-06-20T16:13:37.772Z,1750436017.772 [DAT](INFO): setting transmit power to 8
2025-06-20T16:13:38.022Z,1750436018.022 [DAT](INFO): DAT read: user:3>
2025-06-20T16:13:38.023Z,1750436018.023 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-06-20T16:13:38.023Z,1750436018.023 [DAT](INFO): set transmit power to 8
2025-06-20T16:13:38.023Z,1750436018.023 [DAT](INFO): setting local address to 9
2025-06-20T16:13:38.274Z,1750436018.274 [DAT](INFO): DAT read: user:4>
2025-06-20T16:13:38.275Z,1750436018.275 [DAT](INFO): DAT read: LocalAddr | 9
2025-06-20T16:13:38.275Z,1750436018.275 [DAT](INFO): set local address to 9
2025-06-20T16:13:38.276Z,1750436018.276 [DAT](INFO): Setting time to: 16:13:38 And date to:6/20/2025
2025-06-20T16:13:38.526Z,1750436018.526 [DAT](INFO): DAT read: user:5>
2025-06-20T16:13:38.527Z,1750436018.527 [DAT](INFO): DAT read: Fri Jun 20, 2025 16:13:38
2025-06-20T16:13:38.527Z,1750436018.527 [DAT](INFO): Local DAT time set to Fri Jun 20, 2025 16:13:38
2025-06-20T16:13:47.399Z,1750436027.399 [NAL9602](INFO): Powering up NAL9602
2025-06-20T16:13:53.975Z,1750436033.975 [CommandExec](IMPORTANT): got command strobe off
2025-06-20T16:13:53.975Z,1750436033.975 [CommandExec](IMPORTANT): Deactivating strobe
2025-06-20T16:13:58.311Z,1750436038.311 [NAL9602](INFO): NAL9602 initialized
2025-06-20T16:14:08.274Z,1750436048.274 [CommandExec](IMPORTANT): got command strobe off
2025-06-20T16:14:08.275Z,1750436048.275 [CommandExec](IMPORTANT): Deactivating strobe
2025-06-20T16:15:22.771Z,1750436122.771 [SBIT](IMPORTANT): Beginning Startup BIT
2025-06-20T16:15:22.775Z,1750436122.775 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-20T16:15:27.326Z,1750436127.326 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.017788
CHAN A1 (24V): 0.003194
CHAN A2 (12V): -0.004885
CHAN A3 (5V): -0.003408
CHAN B0 (3.3V): -0.001293
CHAN B1 (3.15aV): -0.001682
CHAN B2 (3.15bV): -0.001717
CHAN B3 (GND): -0.000657
OPEN: 0.004821
Full Scale: +/- 1 mA
2025-06-20T16:16:01.120Z,1750436161.120 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005406
2025-06-20T16:16:16.752Z,1750436176.752 [SBIT](IMPORTANT): SBIT PASSED
2025-06-20T16:16:16.752Z,1750436176.752 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-06-20T16:16:16.753Z,1750436176.753 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;
2025-06-20T16:16:16.753Z,1750436176.753 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2025-06-20T16:16:16.753Z,1750436176.753 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2025-06-20T16:16:16.753Z,1750436176.753 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;
2025-06-20T16:16:16.753Z,1750436176.753 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;
2025-06-20T16:16:16.753Z,1750436176.753 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-1 ampere_hour;
2025-06-20T16:16:16.753Z,1750436176.753 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=10 volt;
2025-06-20T16:16:16.753Z,1750436176.753 [SBIT](IMPORTANT): MultiRay.brightnessWhite=88 none;
2025-06-20T16:16:16.754Z,1750436176.754 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;
2025-06-20T16:16:16.754Z,1750436176.754 [SBIT](IMPORTANT): NAL9602.fastGPSFix=0 bool;
2025-06-20T16:16:16.754Z,1750436176.754 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;
2025-06-20T16:16:16.754Z,1750436176.754 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;
2025-06-20T16:16:16.754Z,1750436176.754 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=210.385350 cubic_centimeter;
2025-06-20T16:16:16.754Z,1750436176.754 [SBIT](IMPORTANT): VerticalControl.massDefault=25.573220 millimeter;
2025-06-20T16:16:17.126Z,1750436177.126 [MissionManager](IMPORTANT): Started mission Startup
2025-06-20T16:16:17.126Z,1750436177.126 [Startup] Running Loop=1
2025-06-20T16:16:17.126Z,1750436177.126 [Startup](DEBUG): Aggregate::initialize Startup
2025-06-20T16:16:17.126Z,1750436177.126 [Startup:A.GoToSurface] Running Loop=1
2025-06-20T16:16:17.126Z,1750436177.126 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-20T16:16:17.127Z,1750436177.127 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-20T16:16:17.128Z,1750436177.128 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-20T16:16:17.128Z,1750436177.128 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-20T16:16:17.128Z,1750436177.128 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-20T16:16:17.129Z,1750436177.129 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-20T16:16:17.129Z,1750436177.129 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-20T16:16:17.131Z,1750436177.131 [Startup:StartupSatComms] Running Loop=1
2025-06-20T16:16:17.131Z,1750436177.131 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-06-20T16:16:17.131Z,1750436177.131 [Startup:StartupSatComms:A] Running Loop=1
2025-06-20T16:16:17.532Z,1750436177.532 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-06-20T16:16:20.352Z,1750436180.352 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-06-20T16:16:20.352Z,1750436180.352 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:16:20.362Z,1750436180.362 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:16:20.781Z,1750436180.781 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:16:20.782Z,1750436180.782 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-06-20T16:16:25.808Z,1750436185.808 [CommandExec](IMPORTANT): got command failComponent
2025-06-20T16:16:25.808Z,1750436185.808 [CommandExec](IMPORTANT): Failed components:
2025-06-20T16:16:25.808Z,1750436185.808 [CommandExec](IMPORTANT): No failed Components.
2025-06-20T16:16:31.852Z,1750436191.852 [CommandExec](IMPORTANT): got command gfscan
2025-06-20T16:16:32.140Z,1750436192.140 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-20T16:16:36.604Z,1750436196.604 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.020272
CHAN A1 (24V): 0.003314
CHAN A2 (12V): -0.005317
CHAN A3 (5V): -0.003079
CHAN B0 (3.3V): -0.001867
CHAN B1 (3.15aV): -0.001514
CHAN B2 (3.15bV): -0.001529
CHAN B3 (GND): -0.001160
OPEN: 0.004693
Full Scale: +/- 1 mA
2025-06-20T16:17:17.322Z,1750436237.322 [Startup:StartupSatComms:A](INFO): Timed out from 2025-06-20T16:16:17.1Z
2025-06-20T16:17:17.322Z,1750436237.322 [Startup:StartupSatComms:A] Stopped
2025-06-20T16:17:17.322Z,1750436237.322 [Startup:StartupSatComms:B] Running Loop=1
2025-06-20T16:17:17.717Z,1750436237.717 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-20T16:17:24.924Z,1750436244.924 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250619T223834/Courier0043.lzma
2025-06-20T16:17:25.925Z,1750436245.925 [DataOverHttps](INFO): Moved sent file to Logs/20250619T223834/Courier0043.lzma.bak
2025-06-20T16:17:25.925Z,1750436245.925 [DataOverHttps](INFO): SBD MOMSN=25231549
2025-06-20T16:17:41.756Z,1750436261.756 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250620T160637/Courier0000.lzma
2025-06-20T16:17:42.781Z,1750436262.781 [DataOverHttps](INFO): Moved sent file to Logs/20250620T160637/Courier0000.lzma.bak
2025-06-20T16:17:42.781Z,1750436262.781 [DataOverHttps](INFO): SBD MOMSN=25231551
2025-06-20T16:17:58.848Z,1750436278.848 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250620T161307/Courier0000.lzma
2025-06-20T16:17:59.853Z,1750436279.853 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Courier0000.lzma.bak
2025-06-20T16:17:59.853Z,1750436279.853 [DataOverHttps](INFO): SBD MOMSN=25231554
2025-06-20T16:18:15.444Z,1750436295.444 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250619T223834/Express0044.lzma
2025-06-20T16:18:16.445Z,1750436296.445 [DataOverHttps](INFO): Moved sent file to Logs/20250619T223834/Express0044.lzma.bak
2025-06-20T16:18:16.445Z,1750436296.445 [DataOverHttps](INFO): SBD MOMSN=25231557
2025-06-20T16:18:17.546Z,1750436297.546 [Startup:StartupSatComms:B](INFO): Timed out from 2025-06-20T16:17:17.3Z
2025-06-20T16:18:17.546Z,1750436297.546 [Startup:StartupSatComms:B] Stopped
2025-06-20T16:18:17.546Z,1750436297.546 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-06-20T16:18:17.546Z,1750436297.546 [Startup:StartupSatComms] Stopped
2025-06-20T16:18:17.546Z,1750436297.546 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-06-20T16:18:17.551Z,1750436297.551 [Startup](INFO): Completed Startup
2025-06-20T16:18:17.552Z,1750436297.552 [MissionManager](INFO): Startup is completed.
2025-06-20T16:18:17.552Z,1750436297.552 [MissionManager](INFO): Uninitializing Mission Startup
2025-06-20T16:18:17.552Z,1750436297.552 [Startup] Stopped
2025-06-20T16:18:17.552Z,1750436297.552 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-06-20T16:18:17.552Z,1750436297.552 [Startup:A.GoToSurface] Stopped
2025-06-20T16:18:17.552Z,1750436297.552 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-20T16:18:17.936Z,1750436297.936 [MissionManager](IMPORTANT): Started mission Default
2025-06-20T16:18:17.937Z,1750436297.937 [Default] Running Loop=1
2025-06-20T16:18:17.937Z,1750436297.937 [Default](DEBUG): Aggregate::initialize Default
2025-06-20T16:18:17.937Z,1750436297.937 [Default:B.GoToSurface] Running Loop=1
2025-06-20T16:18:17.937Z,1750436297.937 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-20T16:18:17.937Z,1750436297.937 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-20T16:18:17.937Z,1750436297.937 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-20T16:18:17.938Z,1750436297.938 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-20T16:18:17.938Z,1750436297.938 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-20T16:18:17.938Z,1750436297.938 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-20T16:18:17.939Z,1750436297.939 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-20T16:18:17.967Z,1750436297.967 [Default:A.Wait] Running Loop=1
2025-06-20T16:18:17.967Z,1750436297.967 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-20T16:18:31.265Z,1750436311.265 [Default:A.Wait](INFO): Done Waiting.
2025-06-20T16:18:31.265Z,1750436311.265 [Default:A.Wait] Stopped
2025-06-20T16:18:31.265Z,1750436311.265 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T16:18:31.677Z,1750436311.677 [Default:CheckIn] Running Loop=1
2025-06-20T16:18:31.677Z,1750436311.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-20T16:18:31.677Z,1750436311.677 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-20T16:18:32.098Z,1750436312.098 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-20T16:18:59.411Z,1750436339.411 [DataOverHttps](INFO): Sending 1171 bytes from file Logs/20250620T160637/Express0001.lzma
2025-06-20T16:19:00.409Z,1750436340.409 [DataOverHttps](INFO): Moved sent file to Logs/20250620T160637/Express0001.lzma.bak
2025-06-20T16:19:00.409Z,1750436340.409 [DataOverHttps](INFO): SBD MOMSN=25231567
2025-06-20T16:19:01.153Z,1750436341.153 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-06-20T16:19:21.360Z,1750436361.360 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-06-20T16:19:21.360Z,1750436361.360 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:19:21.395Z,1750436361.395 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:19:21.768Z,1750436361.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:19:21.769Z,1750436361.769 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-06-20T16:21:40.739Z,1750436500.739 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2025-06-20T16:21:40.742Z,1750436500.742 [BPC1](INFO): Received data from all battery sticks.
2025-06-20T16:22:22.352Z,1750436542.352 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-06-20T16:22:22.352Z,1750436542.352 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:22:22.362Z,1750436542.362 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:22:22.787Z,1750436542.787 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:22:22.787Z,1750436542.787 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-06-20T16:23:31.841Z,1750436611.841 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-20T16:18:31.7Z
2025-06-20T16:23:31.841Z,1750436611.841 [Default:CheckIn:Read_GPS] Stopped
2025-06-20T16:23:31.841Z,1750436611.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-20T16:23:32.253Z,1750436612.253 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-20T16:23:38.971Z,1750436618.971 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250620T161307/Courier0004.lzma
2025-06-20T16:23:39.973Z,1750436619.973 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Courier0004.lzma.bak
2025-06-20T16:23:39.973Z,1750436619.973 [DataOverHttps](INFO): SBD MOMSN=25231623
2025-06-20T16:23:55.610Z,1750436635.610 [DataOverHttps](INFO): Sending 1114 bytes from file Logs/20250620T161307/Express0001.lzma
2025-06-20T16:23:56.609Z,1750436636.609 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Express0001.lzma.bak
2025-06-20T16:23:56.609Z,1750436636.609 [DataOverHttps](INFO): SBD MOMSN=25231625
2025-06-20T16:24:00.917Z,1750436640.917 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-20T16:24:00.917Z,1750436640.917 [NAL9602] Data Fault, FailCount= 1
2025-06-20T16:24:00.917Z,1750436640.917 [NAL9602](ERROR): Data Fault
2025-06-20T16:24:00.986Z,1750436640.986 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-20T16:24:01.320Z,1750436641.320 [NAL9602](INFO): Powering down
2025-06-20T16:24:02.175Z,1750436642.175 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-20T16:24:02.204Z,1750436642.204 [NAL9602] No Fault, FailCount= 1
2025-06-20T16:24:11.179Z,1750436651.179 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20250620T161307/Express0005.lzma
2025-06-20T16:24:12.181Z,1750436652.181 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Express0005.lzma.bak
2025-06-20T16:24:12.181Z,1750436652.181 [DataOverHttps](INFO): SBD MOMSN=25231661
2025-06-20T16:24:13.461Z,1750436653.461 [Default:CheckIn:Read_Iridium] Stopped
2025-06-20T16:24:13.462Z,1750436653.462 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-20T16:24:13.462Z,1750436653.462 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-20T16:24:31.625Z,1750436671.625 [NAL9602](INFO): Powering up NAL9602
2025-06-20T16:24:42.537Z,1750436682.537 [NAL9602](INFO): NAL9602 initialized
2025-06-20T16:25:23.343Z,1750436723.343 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-06-20T16:25:23.343Z,1750436723.343 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:25:23.355Z,1750436723.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:25:23.765Z,1750436723.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:25:23.766Z,1750436723.766 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-06-20T16:28:24.355Z,1750436904.355 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-06-20T16:28:24.355Z,1750436904.355 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:28:24.370Z,1750436904.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:28:24.773Z,1750436904.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:28:24.774Z,1750436904.774 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-06-20T16:29:14.049Z,1750436954.049 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-20T16:29:14.049Z,1750436954.049 [Default:CheckIn:C.Wait] Stopped
2025-06-20T16:29:14.049Z,1750436954.049 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T16:29:14.049Z,1750436954.049 [Default:CheckIn:D] Running Loop=1
2025-06-20T16:29:14.456Z,1750436954.456 [Default:CheckIn:D] Stopped
2025-06-20T16:29:14.456Z,1750436954.456 [Default:CheckIn:E] Running Loop=1
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.941992 min
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn:E] Stopped
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn] Stopped
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn](INFO): Running loop #2
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn] Running Loop=2
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-20T16:29:14.837Z,1750436954.837 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-20T16:31:25.388Z,1750437085.388 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-06-20T16:31:25.388Z,1750437085.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:31:25.399Z,1750437085.399 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:31:25.792Z,1750437085.792 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:31:25.792Z,1750437085.792 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-06-20T16:34:15.049Z,1750437255.049 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-20T16:29:14.8Z
2025-06-20T16:34:15.049Z,1750437255.049 [Default:CheckIn:Read_GPS] Stopped
2025-06-20T16:34:15.049Z,1750437255.049 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-20T16:34:21.822Z,1750437261.822 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250620T161307/Courier0007.lzma
2025-06-20T16:34:22.825Z,1750437262.825 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Courier0007.lzma.bak
2025-06-20T16:34:22.825Z,1750437262.825 [DataOverHttps](INFO): SBD MOMSN=25231677
2025-06-20T16:34:26.387Z,1750437266.387 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-06-20T16:34:26.387Z,1750437266.387 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:34:26.402Z,1750437266.402 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:34:26.804Z,1750437266.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:34:26.805Z,1750437266.805 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-06-20T16:34:38.491Z,1750437278.491 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250620T161307/Express0008.lzma
2025-06-20T16:34:39.493Z,1750437279.493 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Express0008.lzma.bak
2025-06-20T16:34:39.493Z,1750437279.493 [DataOverHttps](INFO): SBD MOMSN=25231679
2025-06-20T16:34:40.925Z,1750437280.925 [Default:CheckIn:Read_Iridium] Stopped
2025-06-20T16:34:40.925Z,1750437280.925 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-20T16:34:40.925Z,1750437280.925 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-20T16:34:44.529Z,1750437284.529 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-20T16:34:44.529Z,1750437284.529 [NAL9602] Data Fault, FailCount= 2
2025-06-20T16:34:44.529Z,1750437284.529 [NAL9602](ERROR): Data Fault
2025-06-20T16:34:44.569Z,1750437284.569 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-20T16:34:44.933Z,1750437284.933 [NAL9602](INFO): Powering down
2025-06-20T16:34:45.805Z,1750437285.805 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-20T16:34:45.805Z,1750437285.805 [NAL9602] No Fault, FailCount= 2
2025-06-20T16:35:15.241Z,1750437315.241 [NAL9602](INFO): Powering up NAL9602
2025-06-20T16:35:26.145Z,1750437326.145 [NAL9602](INFO): NAL9602 initialized
2025-06-20T16:37:27.348Z,1750437447.348 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-06-20T16:37:27.349Z,1750437447.349 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:37:27.383Z,1750437447.383 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:37:27.765Z,1750437447.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:37:27.765Z,1750437447.765 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-06-20T16:39:41.489Z,1750437581.489 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-20T16:39:41.489Z,1750437581.489 [Default:CheckIn:C.Wait] Stopped
2025-06-20T16:39:41.489Z,1750437581.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T16:39:41.489Z,1750437581.489 [Default:CheckIn:D] Running Loop=1
2025-06-20T16:39:41.885Z,1750437581.885 [Default:CheckIn:D] Stopped
2025-06-20T16:39:41.885Z,1750437581.885 [Default:CheckIn:E] Running Loop=1
2025-06-20T16:39:42.320Z,1750437582.320 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.399139 min
2025-06-20T16:39:42.320Z,1750437582.320 [Default:CheckIn:E] Stopped
2025-06-20T16:39:42.320Z,1750437582.320 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-20T16:39:42.320Z,1750437582.320 [Default:CheckIn] Stopped
2025-06-20T16:39:42.320Z,1750437582.320 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-20T16:39:42.321Z,1750437582.321 [Default:CheckIn](INFO): Running loop #3
2025-06-20T16:39:42.321Z,1750437582.321 [Default:CheckIn] Running Loop=3
2025-06-20T16:39:42.321Z,1750437582.321 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-20T16:39:42.321Z,1750437582.321 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-20T16:40:28.344Z,1750437628.344 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-06-20T16:40:28.344Z,1750437628.344 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:40:28.355Z,1750437628.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:40:28.759Z,1750437628.759 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:40:28.760Z,1750437628.760 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-06-20T16:43:29.336Z,1750437809.336 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-06-20T16:43:29.336Z,1750437809.336 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:43:29.346Z,1750437809.346 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:43:29.749Z,1750437809.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:43:29.749Z,1750437809.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-06-20T16:44:42.494Z,1750437882.494 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-20T16:39:42.3Z
2025-06-20T16:44:42.494Z,1750437882.494 [Default:CheckIn:Read_GPS] Stopped
2025-06-20T16:44:42.494Z,1750437882.494 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-20T16:44:49.194Z,1750437889.194 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250620T161307/Courier0010.lzma
2025-06-20T16:44:50.197Z,1750437890.197 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Courier0010.lzma.bak
2025-06-20T16:44:50.197Z,1750437890.197 [DataOverHttps](INFO): SBD MOMSN=25231695
2025-06-20T16:45:05.827Z,1750437905.827 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250620T161307/Express0011.lzma
2025-06-20T16:45:06.829Z,1750437906.829 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Express0011.lzma.bak
2025-06-20T16:45:06.829Z,1750437906.829 [DataOverHttps](INFO): SBD MOMSN=25231697
2025-06-20T16:45:08.317Z,1750437908.317 [Default:CheckIn:Read_Iridium] Stopped
2025-06-20T16:45:08.318Z,1750437908.318 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-20T16:45:08.318Z,1750437908.318 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-20T16:45:28.509Z,1750437928.509 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-20T16:45:28.509Z,1750437928.509 [NAL9602] Data Fault, FailCount= 3
2025-06-20T16:45:28.509Z,1750437928.509 [NAL9602](ERROR): Data Fault
2025-06-20T16:45:28.568Z,1750437928.568 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-20T16:45:28.913Z,1750437928.913 [NAL9602](INFO): Powering down
2025-06-20T16:45:29.745Z,1750437929.745 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-20T16:45:29.745Z,1750437929.745 [NAL9602] No Fault, FailCount= 3
2025-06-20T16:45:59.213Z,1750437959.213 [NAL9602](INFO): Powering up NAL9602
2025-06-20T16:46:10.125Z,1750437970.125 [NAL9602](INFO): NAL9602 initialized
2025-06-20T16:46:30.329Z,1750437990.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-06-20T16:46:30.329Z,1750437990.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:46:30.368Z,1750437990.368 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:46:30.746Z,1750437990.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:46:30.746Z,1750437990.746 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-06-20T16:49:31.320Z,1750438171.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-06-20T16:49:31.320Z,1750438171.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:49:31.359Z,1750438171.359 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:49:31.735Z,1750438171.735 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:49:31.736Z,1750438171.736 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-06-20T16:50:08.901Z,1750438208.901 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-20T16:50:08.901Z,1750438208.901 [Default:CheckIn:C.Wait] Stopped
2025-06-20T16:50:08.901Z,1750438208.901 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T16:50:08.901Z,1750438208.901 [Default:CheckIn:D] Running Loop=1
2025-06-20T16:50:09.297Z,1750438209.297 [Default:CheckIn:D] Stopped
2025-06-20T16:50:09.297Z,1750438209.297 [Default:CheckIn:E] Running Loop=1
2025-06-20T16:50:09.701Z,1750438209.701 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.856012 min
2025-06-20T16:50:09.701Z,1750438209.701 [Default:CheckIn:E] Stopped
2025-06-20T16:50:09.701Z,1750438209.701 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-20T16:50:09.701Z,1750438209.701 [Default:CheckIn] Stopped
2025-06-20T16:50:09.701Z,1750438209.701 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-20T16:50:09.702Z,1750438209.702 [Default:CheckIn](INFO): Running loop #4
2025-06-20T16:50:09.702Z,1750438209.702 [Default:CheckIn] Running Loop=4
2025-06-20T16:50:09.702Z,1750438209.702 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-20T16:50:09.702Z,1750438209.702 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-20T16:52:32.345Z,1750438352.345 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-06-20T16:52:32.345Z,1750438352.345 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:52:32.361Z,1750438352.361 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:52:32.752Z,1750438352.752 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:52:32.753Z,1750438352.753 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-06-20T16:55:09.930Z,1750438509.930 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-20T16:50:09.7Z
2025-06-20T16:55:09.930Z,1750438509.930 [Default:CheckIn:Read_GPS] Stopped
2025-06-20T16:55:09.930Z,1750438509.930 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-20T16:55:16.322Z,1750438516.322 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250620T161307/Courier0013.lzma
2025-06-20T16:55:17.325Z,1750438517.325 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Courier0013.lzma.bak
2025-06-20T16:55:17.325Z,1750438517.325 [DataOverHttps](INFO): SBD MOMSN=25231715
2025-06-20T16:55:33.015Z,1750438533.015 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250620T161307/Express0014.lzma
2025-06-20T16:55:33.328Z,1750438533.328 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-06-20T16:55:33.329Z,1750438533.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:55:33.339Z,1750438533.339 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:55:33.753Z,1750438533.753 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:55:33.753Z,1750438533.753 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-06-20T16:55:34.017Z,1750438534.017 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Express0014.lzma.bak
2025-06-20T16:55:34.017Z,1750438534.017 [DataOverHttps](INFO): SBD MOMSN=25231717
2025-06-20T16:55:35.366Z,1750438535.366 [Default:CheckIn:Read_Iridium] Stopped
2025-06-20T16:55:35.366Z,1750438535.366 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-20T16:55:35.366Z,1750438535.366 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-20T16:56:11.301Z,1750438571.301 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-20T16:56:11.301Z,1750438571.301 [NAL9602] Data Fault, FailCount= 4
2025-06-20T16:56:11.301Z,1750438571.301 [NAL9602](ERROR): Data Fault
2025-06-20T16:56:11.387Z,1750438571.387 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-20T16:56:11.701Z,1750438571.701 [NAL9602](INFO): Powering down
2025-06-20T16:56:12.550Z,1750438572.550 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-20T16:56:12.550Z,1750438572.550 [NAL9602] No Fault, FailCount= 4
2025-06-20T16:56:42.005Z,1750438602.005 [NAL9602](INFO): Powering up NAL9602
2025-06-20T16:56:52.917Z,1750438612.917 [NAL9602](INFO): NAL9602 initialized
2025-06-20T16:58:34.343Z,1750438714.343 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-06-20T16:58:34.343Z,1750438714.343 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T16:58:34.358Z,1750438714.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T16:58:34.802Z,1750438714.802 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T16:58:34.804Z,1750438714.804 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-06-20T17:00:35.942Z,1750438835.942 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-20T17:00:35.942Z,1750438835.942 [Default:CheckIn:C.Wait] Stopped
2025-06-20T17:00:35.942Z,1750438835.942 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:00:35.943Z,1750438835.943 [Default:CheckIn:D] Running Loop=1
2025-06-20T17:00:36.356Z,1750438836.356 [Default:CheckIn:D] Stopped
2025-06-20T17:00:36.356Z,1750438836.356 [Default:CheckIn:E] Running Loop=1
2025-06-20T17:00:36.768Z,1750438836.768 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.306995 min
2025-06-20T17:00:36.769Z,1750438836.769 [Default:CheckIn:E] Stopped
2025-06-20T17:00:36.769Z,1750438836.769 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-20T17:00:36.769Z,1750438836.769 [Default:CheckIn] Stopped
2025-06-20T17:00:36.769Z,1750438836.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-20T17:00:36.769Z,1750438836.769 [Default:CheckIn](INFO): Running loop #5
2025-06-20T17:00:36.769Z,1750438836.769 [Default:CheckIn] Running Loop=5
2025-06-20T17:00:36.769Z,1750438836.769 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-20T17:00:36.769Z,1750438836.769 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-20T17:01:35.344Z,1750438895.344 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-06-20T17:01:35.344Z,1750438895.344 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T17:01:35.382Z,1750438895.382 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T17:01:35.747Z,1750438895.747 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T17:01:35.747Z,1750438895.747 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-06-20T17:04:36.332Z,1750439076.332 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-06-20T17:04:36.332Z,1750439076.332 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T17:04:36.367Z,1750439076.367 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T17:04:36.746Z,1750439076.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T17:04:36.746Z,1750439076.746 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-06-20T17:05:36.937Z,1750439136.937 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-20T17:00:36.8Z
2025-06-20T17:05:36.938Z,1750439136.938 [Default:CheckIn:Read_GPS] Stopped
2025-06-20T17:05:36.938Z,1750439136.938 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-20T17:05:43.658Z,1750439143.658 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250620T161307/Courier0016.lzma
2025-06-20T17:05:44.661Z,1750439144.661 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Courier0016.lzma.bak
2025-06-20T17:05:44.661Z,1750439144.661 [DataOverHttps](INFO): SBD MOMSN=25231734
2025-06-20T17:06:00.491Z,1750439160.491 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250620T161307/Express0017.lzma
2025-06-20T17:06:01.493Z,1750439161.493 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Express0017.lzma.bak
2025-06-20T17:06:01.493Z,1750439161.493 [DataOverHttps](INFO): SBD MOMSN=25231736
2025-06-20T17:06:02.774Z,1750439162.774 [Default:CheckIn:Read_Iridium] Stopped
2025-06-20T17:06:02.774Z,1750439162.774 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-20T17:06:02.774Z,1750439162.774 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:06:54.873Z,1750439214.873 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-20T17:06:54.873Z,1750439214.873 [NAL9602] Data Fault, FailCount= 5
2025-06-20T17:06:54.873Z,1750439214.873 [NAL9602](ERROR): Data Fault
2025-06-20T17:06:54.890Z,1750439214.890 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-20T17:06:54.891Z,1750439214.891 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-06-20T17:06:55.295Z,1750439215.295 [NAL9602](INFO): Powering down
2025-06-20T17:06:55.311Z,1750439215.311 [CommandExec](FAULT): Scheduling is paused
2025-06-20T17:06:55.312Z,1750439215.312 [CBIT](INFO): Critical error at 20250620T170654
2025-06-20T17:06:55.312Z,1750439215.312 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-06-20T17:07:37.308Z,1750439257.308 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-06-20T17:07:37.308Z,1750439257.308 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T17:07:37.319Z,1750439257.319 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T17:07:37.729Z,1750439257.729 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T17:07:37.729Z,1750439257.729 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-06-20T17:08:54.881Z,1750439334.881 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-20T17:08:54.881Z,1750439334.881 [NAL9602] No Fault, FailCount= 5
2025-06-20T17:08:55.279Z,1750439335.279 [NAL9602](INFO): Powering up NAL9602
2025-06-20T17:09:06.172Z,1750439346.172 [NAL9602](INFO): NAL9602 initialized
2025-06-20T17:10:38.329Z,1750439438.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2025-06-20T17:10:38.329Z,1750439438.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T17:10:38.348Z,1750439438.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T17:10:38.737Z,1750439438.737 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T17:10:38.738Z,1750439438.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2025-06-20T17:11:03.368Z,1750439463.368 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-20T17:11:03.368Z,1750439463.368 [Default:CheckIn:C.Wait] Stopped
2025-06-20T17:11:03.368Z,1750439463.368 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:11:03.368Z,1750439463.368 [Default:CheckIn:D] Running Loop=1
2025-06-20T17:11:03.749Z,1750439463.749 [Default:CheckIn:D] Stopped
2025-06-20T17:11:03.749Z,1750439463.749 [Default:CheckIn:E] Running Loop=1
2025-06-20T17:11:04.149Z,1750439464.149 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.763538 min
2025-06-20T17:11:04.149Z,1750439464.149 [Default:CheckIn:E] Stopped
2025-06-20T17:11:04.149Z,1750439464.149 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-20T17:11:04.149Z,1750439464.149 [Default:CheckIn] Stopped
2025-06-20T17:11:04.150Z,1750439464.150 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-20T17:11:04.150Z,1750439464.150 [Default:CheckIn](INFO): Running loop #6
2025-06-20T17:11:04.150Z,1750439464.150 [Default:CheckIn] Running Loop=6
2025-06-20T17:11:04.150Z,1750439464.150 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-20T17:11:04.150Z,1750439464.150 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-20T17:12:39.428Z,1750439559.428 [DataOverHttps](IMPORTANT): SBD MTMSN=20250620T171238
2025-06-20T17:12:46.755Z,1750439566.755 [DataOverHttps](INFO): Received command: run Engineering/multiray_test.tl
2025-06-20T17:12:46.873Z,1750439566.873 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/multiray_test.tl
2025-06-20T17:12:46.874Z,1750439566.874 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/multiray_test.tl
2025-06-20T17:12:46.874Z,1750439566.874 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/multiray_test.tl
2025-06-20T17:12:46.878Z,1750439566.878 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/multiray_test.tl
2025-06-20T17:12:47.110Z,1750439567.110 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/multiray_test.tl translated into:
5
5
0
1
2
2025-06-20T17:12:47.116Z,1750439567.116 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min
2025-06-20T17:12:47.120Z,1750439567.120 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s
2025-06-20T17:12:47.122Z,1750439567.122 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count
2025-06-20T17:12:47.126Z,1750439567.126 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count
2025-06-20T17:12:47.129Z,1750439567.129 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count
2025-06-20T17:12:47.179Z,1750439567.179 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool
2025-06-20T17:12:47.199Z,1750439567.199 [multiray_test:C.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.212Z,1750439567.212 [multiray_test:E.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.224Z,1750439567.224 [multiray_test:G.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.248Z,1750439567.248 [multiray_test:I.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.259Z,1750439567.259 [multiray_test:K.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.262Z,1750439567.262 [multiray_test:M.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.278Z,1750439567.278 [multiray_test:O.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.285Z,1750439567.285 [multiray_test:Q.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.290Z,1750439567.290 [multiray_test:R.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.306Z,1750439567.306 [multiray_test:T.Wait](DEBUG): Construct Wait.
2025-06-20T17:12:47.319Z,1750439567.319 [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 multiray_test {
"""
This mission is designed to run in the lab only. Cycles through MultiRay light modes
"""
arguments {
MissionTimeout = 5 minute
"""
How long to let the mission run.
"""
LightDuration = 5 second
"""
How long to wait before changing light modes
"""
LightMode0 = 0 count
"""
Light mode = 0
"""
LightMode1 = 1 count
"""
Light mode = 1
"""
LightMode2 = 2 count
"""
Light mode = 2
"""
EnabledMultiray = Sensor:MultiRay.loadAtStartup
"""
Automatically set to true if the Multiray is enabled.
"""
}
timeout duration=MissionTimeout
readData strategy="MinError" {
while ( EnabledMultiray )
Sensor:MultiRay.lightModeLog
}
#
#
#
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
}
2025-06-20T17:12:47.321Z,1750439567.321 [CommandExec](IMPORTANT): Running ./Missions/Engineering/multiray_test.tl
2025-06-20T17:12:47.584Z,1750439567.584 [Default] Stopped
2025-06-20T17:12:47.584Z,1750439567.584 [Default](DEBUG): Aggregate::uninitialize Default
2025-06-20T17:12:47.584Z,1750439567.584 [Default:B.GoToSurface] Stopped
2025-06-20T17:12:47.585Z,1750439567.585 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-20T17:12:47.585Z,1750439567.585 [Default:CheckIn] Stopped
2025-06-20T17:12:47.585Z,1750439567.585 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-20T17:12:47.585Z,1750439567.585 [Default:CheckIn:Read_GPS] Stopped
2025-06-20T17:12:47.585Z,1750439567.585 [MissionManager](IMPORTANT): Started mission multiray_test
2025-06-20T17:12:47.585Z,1750439567.585 [multiray_test] Running Loop=1
2025-06-20T17:12:47.585Z,1750439567.585 [multiray_test](DEBUG): Aggregate::initialize multiray_test
2025-06-20T17:12:47.585Z,1750439567.585 [multiray_test:A] Running Loop=1
2025-06-20T17:12:47.586Z,1750439567.586 [multiray_test:B] Running Loop=1
2025-06-20T17:12:47.586Z,1750439567.586 [multiray_test:B] Stopped
2025-06-20T17:12:47.586Z,1750439567.586 [multiray_test:C.Wait] Running Loop=1
2025-06-20T17:12:47.586Z,1750439567.586 [multiray_test:C.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:12:47.586Z,1750439567.586 [multiray_test:A] Running Loop=1
2025-06-20T17:12:47.587Z,1750439567.587 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2025-06-20T17:12:49.186Z,1750439569.186 [MultiRay](IMPORTANT): MultiRay all lights OFF
2025-06-20T17:12:49.588Z,1750439569.588 [MultiRay](INFO): Powering down
2025-06-20T17:12:49.708Z,1750439569.708 [MultiRay](INFO): Powering down LCB2
2025-06-20T17:12:53.258Z,1750439573.258 [multiray_test:C.Wait](INFO): Done Waiting.
2025-06-20T17:12:53.258Z,1750439573.258 [multiray_test:C.Wait] Stopped
2025-06-20T17:12:53.258Z,1750439573.258 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:12:53.258Z,1750439573.258 [multiray_test:D] Running Loop=1
2025-06-20T17:12:53.668Z,1750439573.668 [multiray_test:D] Stopped
2025-06-20T17:12:53.668Z,1750439573.668 [multiray_test:E.Wait] Running Loop=1
2025-06-20T17:12:53.668Z,1750439573.668 [multiray_test:E.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:12:56.072Z,1750439576.072 [MultiRay](IMPORTANT): Multiray red lights ON
2025-06-20T17:12:59.317Z,1750439579.317 [multiray_test:E.Wait](INFO): Done Waiting.
2025-06-20T17:12:59.317Z,1750439579.317 [multiray_test:E.Wait] Stopped
2025-06-20T17:12:59.317Z,1750439579.317 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:12:59.317Z,1750439579.317 [multiray_test:F] Running Loop=1
2025-06-20T17:12:59.722Z,1750439579.722 [multiray_test:F] Stopped
2025-06-20T17:12:59.722Z,1750439579.722 [multiray_test:G.Wait] Running Loop=1
2025-06-20T17:12:59.722Z,1750439579.722 [multiray_test:G.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:13:01.354Z,1750439581.354 [MultiRay](IMPORTANT): MultiRay white lights ON
2025-06-20T17:13:05.377Z,1750439585.377 [multiray_test:G.Wait](INFO): Done Waiting.
2025-06-20T17:13:05.377Z,1750439585.377 [multiray_test:G.Wait] Stopped
2025-06-20T17:13:05.377Z,1750439585.377 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:13:05.378Z,1750439585.378 [multiray_test:H] Running Loop=1
2025-06-20T17:13:05.782Z,1750439585.782 [multiray_test:H] Stopped
2025-06-20T17:13:05.782Z,1750439585.782 [multiray_test:I.Wait] Running Loop=1
2025-06-20T17:13:05.782Z,1750439585.782 [multiray_test:I.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:13:06.581Z,1750439586.581 [MultiRay](IMPORTANT): MultiRay all lights OFF
2025-06-20T17:13:06.984Z,1750439586.984 [MultiRay](INFO): Powering down
2025-06-20T17:13:07.099Z,1750439587.099 [MultiRay](INFO): Powering down LCB2
2025-06-20T17:13:11.438Z,1750439591.438 [multiray_test:I.Wait](INFO): Done Waiting.
2025-06-20T17:13:11.438Z,1750439591.438 [multiray_test:I.Wait] Stopped
2025-06-20T17:13:11.438Z,1750439591.438 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:13:11.438Z,1750439591.438 [multiray_test:J] Running Loop=1
2025-06-20T17:13:11.841Z,1750439591.841 [multiray_test:J] Stopped
2025-06-20T17:13:11.841Z,1750439591.841 [multiray_test:K.Wait] Running Loop=1
2025-06-20T17:13:11.841Z,1750439591.841 [multiray_test:K.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:13:14.262Z,1750439594.262 [MultiRay](IMPORTANT): Multiray red lights ON
2025-06-20T17:13:17.499Z,1750439597.499 [multiray_test:K.Wait](INFO): Done Waiting.
2025-06-20T17:13:17.499Z,1750439597.499 [multiray_test:K.Wait] Stopped
2025-06-20T17:13:17.499Z,1750439597.499 [multiray_test:K.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:13:17.499Z,1750439597.499 [multiray_test:L] Running Loop=1
2025-06-20T17:13:17.940Z,1750439597.940 [multiray_test:L] Stopped
2025-06-20T17:13:17.941Z,1750439597.941 [multiray_test:M.Wait] Running Loop=1
2025-06-20T17:13:17.941Z,1750439597.941 [multiray_test:M.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:13:19.528Z,1750439599.528 [MultiRay](IMPORTANT): MultiRay white lights ON
2025-06-20T17:13:23.595Z,1750439603.595 [multiray_test:M.Wait](INFO): Done Waiting.
2025-06-20T17:13:23.595Z,1750439603.595 [multiray_test:M.Wait] Stopped
2025-06-20T17:13:23.595Z,1750439603.595 [multiray_test:M.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:13:23.595Z,1750439603.595 [multiray_test:N] Running Loop=1
2025-06-20T17:13:23.986Z,1750439603.986 [multiray_test:N] Stopped
2025-06-20T17:13:23.986Z,1750439603.986 [multiray_test:O.Wait] Running Loop=1
2025-06-20T17:13:23.986Z,1750439603.986 [multiray_test:O.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:13:24.780Z,1750439604.780 [MultiRay](IMPORTANT): MultiRay all lights OFF
2025-06-20T17:13:25.188Z,1750439605.188 [MultiRay](INFO): Powering down
2025-06-20T17:13:25.303Z,1750439605.303 [MultiRay](INFO): Powering down LCB2
2025-06-20T17:13:29.661Z,1750439609.661 [multiray_test:O.Wait](INFO): Done Waiting.
2025-06-20T17:13:29.661Z,1750439609.661 [multiray_test:O.Wait] Stopped
2025-06-20T17:13:29.661Z,1750439609.661 [multiray_test:O.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:13:29.662Z,1750439609.662 [multiray_test:P] Running Loop=1
2025-06-20T17:13:30.059Z,1750439610.059 [multiray_test:P] Stopped
2025-06-20T17:13:30.059Z,1750439610.059 [multiray_test:Q.Wait] Running Loop=1
2025-06-20T17:13:30.059Z,1750439610.059 [multiray_test:Q.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:13:32.461Z,1750439612.461 [MultiRay](IMPORTANT): Multiray red lights ON
2025-06-20T17:13:35.733Z,1750439615.733 [multiray_test:Q.Wait](INFO): Done Waiting.
2025-06-20T17:13:35.733Z,1750439615.733 [multiray_test:Q.Wait] Stopped
2025-06-20T17:13:35.733Z,1750439615.733 [multiray_test:Q.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:13:35.733Z,1750439615.733 [multiray_test:U] Running Loop=1
2025-06-20T17:13:36.125Z,1750439616.125 [multiray_test:U] Stopped
2025-06-20T17:13:36.125Z,1750439616.125 [multiray_test:R.Wait] Running Loop=1
2025-06-20T17:13:36.126Z,1750439616.126 [multiray_test:R.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:13:37.713Z,1750439617.713 [MultiRay](IMPORTANT): MultiRay white lights ON
2025-06-20T17:13:39.368Z,1750439619.368 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2025-06-20T17:13:39.368Z,1750439619.368 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T17:13:39.378Z,1750439619.378 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T17:13:39.748Z,1750439619.748 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T17:13:39.749Z,1750439619.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2025-06-20T17:13:41.781Z,1750439621.781 [multiray_test:R.Wait](INFO): Done Waiting.
2025-06-20T17:13:41.781Z,1750439621.781 [multiray_test:R.Wait] Stopped
2025-06-20T17:13:41.781Z,1750439621.781 [multiray_test:R.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:13:41.781Z,1750439621.781 [multiray_test:S] Running Loop=1
2025-06-20T17:13:42.189Z,1750439622.189 [multiray_test:S] Stopped
2025-06-20T17:13:42.189Z,1750439622.189 [multiray_test:T.Wait] Running Loop=1
2025-06-20T17:13:42.189Z,1750439622.189 [multiray_test:T.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:13:42.961Z,1750439622.961 [MultiRay](IMPORTANT): MultiRay all lights OFF
2025-06-20T17:13:43.368Z,1750439623.368 [MultiRay](INFO): Powering down
2025-06-20T17:13:43.483Z,1750439623.483 [MultiRay](INFO): Powering down LCB2
2025-06-20T17:13:47.830Z,1750439627.830 [multiray_test:T.Wait](INFO): Done Waiting.
2025-06-20T17:13:47.830Z,1750439627.830 [multiray_test:T.Wait] Stopped
2025-06-20T17:13:47.830Z,1750439627.830 [multiray_test:T.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:13:47.830Z,1750439627.830 [multiray_test](INFO): Completed multiray_test
2025-06-20T17:13:47.830Z,1750439627.830 [MissionManager](INFO): multiray_test is completed.
2025-06-20T17:13:47.830Z,1750439627.830 [MissionManager](INFO): Uninitializing Mission multiray_test
2025-06-20T17:13:47.830Z,1750439627.830 [multiray_test] Stopped
2025-06-20T17:13:47.830Z,1750439627.830 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test
2025-06-20T17:13:47.831Z,1750439627.831 [multiray_test:A] Stopped
2025-06-20T17:13:48.247Z,1750439628.247 [MissionManager](IMPORTANT): Started mission Default
2025-06-20T17:13:48.247Z,1750439628.247 [Default] Running Loop=1
2025-06-20T17:13:48.247Z,1750439628.247 [Default](DEBUG): Aggregate::initialize Default
2025-06-20T17:13:48.247Z,1750439628.247 [Default:B.GoToSurface] Running Loop=1
2025-06-20T17:13:48.247Z,1750439628.247 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-20T17:13:48.248Z,1750439628.248 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-20T17:13:48.248Z,1750439628.248 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-20T17:13:48.248Z,1750439628.248 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-20T17:13:48.248Z,1750439628.248 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-20T17:13:48.249Z,1750439628.249 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-20T17:13:48.249Z,1750439628.249 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-20T17:13:48.249Z,1750439628.249 [Default:A.Wait] Running Loop=1
2025-06-20T17:13:48.250Z,1750439628.250 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:14:01.558Z,1750439641.558 [Default:A.Wait](INFO): Done Waiting.
2025-06-20T17:14:01.558Z,1750439641.558 [Default:A.Wait] Stopped
2025-06-20T17:14:01.558Z,1750439641.558 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:14:01.961Z,1750439641.961 [Default:CheckIn] Running Loop=1
2025-06-20T17:14:01.961Z,1750439641.961 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-20T17:14:01.961Z,1750439641.961 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-20T17:15:30.014Z,1750439730.014 [CommandExec](IMPORTANT): got command stop
2025-06-20T17:15:30.014Z,1750439730.014 [CommandExec](IMPORTANT): Scheduling is paused
2025-06-20T17:15:30.014Z,1750439730.014 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-06-20T17:16:40.327Z,1750439800.327 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2025-06-20T17:16:40.327Z,1750439800.327 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T17:16:40.367Z,1750439800.367 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T17:16:40.741Z,1750439800.741 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T17:16:40.742Z,1750439800.742 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2025-06-20T17:16:50.825Z,1750439810.825 [CommandExec](IMPORTANT): got command schedule resume
2025-06-20T17:16:50.825Z,1750439810.825 [CommandExec](IMPORTANT): Scheduling is resumed
2025-06-20T17:16:54.152Z,1750439814.152 [CommandExec](IMPORTANT): got command show stack
2025-06-20T17:16:54.152Z,1750439814.152 [CommandExec](IMPORTANT): Behavior Stack:
2025-06-20T17:16:54.153Z,1750439814.153 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2025-06-20T17:16:54.153Z,1750439814.153 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2025-06-20T17:19:02.133Z,1750439942.133 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-20T17:14:01.0Z
2025-06-20T17:19:02.133Z,1750439942.133 [Default:CheckIn:Read_GPS] Stopped
2025-06-20T17:19:02.133Z,1750439942.133 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-20T17:19:08.585Z,1750439948.585 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-20T17:19:08.585Z,1750439948.585 [NAL9602] Data Fault, FailCount= 1
2025-06-20T17:19:08.585Z,1750439948.585 [NAL9602](ERROR): Data Fault
2025-06-20T17:19:08.657Z,1750439948.657 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-20T17:19:08.998Z,1750439948.998 [NAL9602](INFO): Powering down
2025-06-20T17:19:09.819Z,1750439949.819 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250620T161307/Courier0019.lzma
2025-06-20T17:19:09.822Z,1750439949.822 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-20T17:19:09.822Z,1750439949.822 [NAL9602] No Fault, FailCount= 1
2025-06-20T17:19:10.821Z,1750439950.821 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Courier0019.lzma.bak
2025-06-20T17:19:10.821Z,1750439950.821 [DataOverHttps](INFO): SBD MOMSN=25231769
2025-06-20T17:19:26.359Z,1750439966.359 [DataOverHttps](INFO): Sending 567 bytes from file Logs/20250620T161307/Express0020.lzma
2025-06-20T17:19:27.357Z,1750439967.357 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Express0020.lzma.bak
2025-06-20T17:19:27.357Z,1750439967.357 [DataOverHttps](INFO): SBD MOMSN=25231772
2025-06-20T17:19:39.301Z,1750439979.301 [NAL9602](INFO): Powering up NAL9602
2025-06-20T17:19:41.332Z,1750439981.332 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2025-06-20T17:19:41.332Z,1750439981.332 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-20T17:19:41.355Z,1750439981.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-20T17:19:41.761Z,1750439981.761 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-20T17:19:41.761Z,1750439981.761 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2025-06-20T17:19:43.318Z,1750439983.318 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250620T161307/Express0023.lzma
2025-06-20T17:19:44.321Z,1750439984.321 [DataOverHttps](INFO): Moved sent file to Logs/20250620T161307/Express0023.lzma.bak
2025-06-20T17:19:44.321Z,1750439984.321 [DataOverHttps](INFO): SBD MOMSN=25231800
2025-06-20T17:19:45.780Z,1750439985.780 [Default:CheckIn:Read_Iridium] Stopped
2025-06-20T17:19:45.781Z,1750439985.781 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-20T17:19:45.781Z,1750439985.781 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-20T17:19:50.201Z,1750439990.201 [NAL9602](INFO): NAL9602 initialized
2025-06-20T17:21:40.005Z,1750440100.005 [CommandExec](IMPORTANT): got command quit
2025-06-20T17:21:41.007Z,1750440101.007 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:41.007Z,1750440101.007 [CommandExec](INFO): Uninitializing the command executive.
2025-06-20T17:21:41.007Z,1750440101.007 [CommandExec](INFO): Uninitializing the command scheduler.
2025-06-20T17:21:41.008Z,1750440101.008 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:41.120Z,1750440101.120 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-06-20T17:21:41.121Z,1750440101.121 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-06-20T17:21:41.121Z,1750440101.121 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:41.122Z,1750440101.122 [NavChartDb](INFO): Join timeout helper Thread ID is 1020
2025-06-20T17:21:41.404Z,1750440101.404 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:41.404Z,1750440101.404 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:41.423Z,1750440101.423 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-06-20T17:21:41.423Z,1750440101.423 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:41.423Z,1750440101.423 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1021
2025-06-20T17:21:41.795Z,1750440101.795 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:41.795Z,1750440101.795 [WetLabsBB2FL](INFO): Powering down
2025-06-20T17:21:41.796Z,1750440101.796 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:41.803Z,1750440101.803 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-06-20T17:21:41.803Z,1750440101.803 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:41.804Z,1750440101.804 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1022
2025-06-20T17:21:42.703Z,1750440102.703 [CTD_Seabird](INFO): Powering down
2025-06-20T17:21:42.715Z,1750440102.715 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:42.716Z,1750440102.716 [CTD_Seabird](INFO): Powering down
2025-06-20T17:21:42.728Z,1750440102.728 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:42.747Z,1750440102.747 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-06-20T17:21:42.747Z,1750440102.747 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:42.748Z,1750440102.748 [Radio_Surface](INFO): Join timeout helper Thread ID is 1023
2025-06-20T17:21:43.063Z,1750440103.063 [Radio_Surface](INFO): Powering down
2025-06-20T17:21:43.064Z,1750440103.064 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:43.064Z,1750440103.064 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:43.079Z,1750440103.079 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-06-20T17:21:43.079Z,1750440103.079 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:43.079Z,1750440103.079 [Onboard](INFO): Join timeout helper Thread ID is 1024
2025-06-20T17:21:43.975Z,1750440103.975 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-20T17:21:45.935Z,1750440105.935 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:45.935Z,1750440105.935 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:45.944Z,1750440105.944 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-06-20T17:21:45.944Z,1750440105.944 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:45.944Z,1750440105.944 [DataOverHttps](INFO): Join timeout helper Thread ID is 1025
2025-06-20T17:21:45.983Z,1750440105.983 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:45.984Z,1750440105.984 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.004Z,1750440106.004 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-06-20T17:21:46.004Z,1750440106.004 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.004Z,1750440106.004 [DAT](INFO): Join timeout helper Thread ID is 1026
2025-06-20T17:21:46.063Z,1750440106.063 [DAT](INFO): Powering down
2025-06-20T17:21:46.132Z,1750440106.132 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:46.132Z,1750440106.132 [DAT](INFO): Powering down
2025-06-20T17:21:46.133Z,1750440106.133 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.148Z,1750440106.148 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-06-20T17:21:46.148Z,1750440106.148 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.148Z,1750440106.148 [BackseatComponent](INFO): Join timeout helper Thread ID is 1027
2025-06-20T17:21:46.300Z,1750440106.300 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:46.301Z,1750440106.301 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.312Z,1750440106.312 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-06-20T17:21:46.312Z,1750440106.312 [logger ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.312Z,1750440106.312 [logger](INFO): Join timeout helper Thread ID is 1028
2025-06-20T17:21:46.315Z,1750440106.315 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:46.315Z,1750440106.315 [logger ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.332Z,1750440106.332 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-06-20T17:21:46.332Z,1750440106.332 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.332Z,1750440106.332 [CommandLine](INFO): Join timeout helper Thread ID is 1029
2025-06-20T17:21:46.427Z,1750440106.427 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:46.427Z,1750440106.427 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.432Z,1750440106.432 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-06-20T17:21:46.432Z,1750440106.432 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.432Z,1750440106.432 [CommandExec](INFO): Join timeout helper Thread ID is 1030
2025-06-20T17:21:46.433Z,1750440106.433 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-06-20T17:21:46.433Z,1750440106.433 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.434Z,1750440106.434 [controlThread](INFO): Join timeout helper Thread ID is 1031
2025-06-20T17:21:46.539Z,1750440106.539 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-20T17:21:46.539Z,1750440106.539 [controlThread](DEBUG): Uninitializing ControlThread
2025-06-20T17:21:46.540Z,1750440106.540 [AHRS_M2](INFO): Powering down
2025-06-20T17:21:46.619Z,1750440106.619 [MultiRay](INFO): Powering down LCB2
2025-06-20T17:21:46.620Z,1750440106.620 [MultiRay](INFO): Powering down.
2025-06-20T17:21:46.621Z,1750440106.621 [NAL9602](INFO): Powering down
2025-06-20T17:21:46.623Z,1750440106.623 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-06-20T17:21:46.624Z,1750440106.624 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-06-20T17:21:46.624Z,1750440106.624 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-06-20T17:21:46.625Z,1750440106.625 [MissionManager](INFO): Uninitializing Mission Default
2025-06-20T17:21:46.625Z,1750440106.625 [Default] Stopped
2025-06-20T17:21:46.625Z,1750440106.625 [Default](DEBUG): Aggregate::uninitialize Default
2025-06-20T17:21:46.625Z,1750440106.625 [Default:B.GoToSurface] Stopped
2025-06-20T17:21:46.625Z,1750440106.625 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-20T17:21:46.625Z,1750440106.625 [Default:CheckIn] Stopped
2025-06-20T17:21:46.625Z,1750440106.625 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-20T17:21:46.625Z,1750440106.625 [Default:CheckIn:C.Wait] Stopped
2025-06-20T17:21:46.625Z,1750440106.625 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-20T17:21:46.629Z,1750440106.629 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-06-20T17:21:46.629Z,1750440106.629 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-06-20T17:21:46.629Z,1750440106.629 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-06-20T17:21:46.630Z,1750440106.630 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-06-20T17:21:46.630Z,1750440106.630 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-06-20T17:21:46.630Z,1750440106.630 [BuoyancyServo](INFO): Powering down
2025-06-20T17:21:46.643Z,1750440106.643 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-20T17:21:46.643Z,1750440106.643 [ElevatorServo](INFO): Powering down
2025-06-20T17:21:46.644Z,1750440106.644 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-06-20T17:21:46.644Z,1750440106.644 [MassServo](INFO): Powering down
2025-06-20T17:21:46.645Z,1750440106.645 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-06-20T17:21:46.645Z,1750440106.645 [RudderServo](INFO): Powering down
2025-06-20T17:21:46.646Z,1750440106.646 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-06-20T17:21:46.646Z,1750440106.646 [ThrusterHE](INFO): Powering down
2025-06-20T17:21:46.647Z,1750440106.647 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-06-20T17:21:46.647Z,1750440106.647 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-06-20T17:21:46.648Z,1750440106.648 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-06-20T17:21:46.648Z,1750440106.648 [CBIT](DEBUG): Powering off loads.
2025-06-20T17:21:46.659Z,1750440106.659 [CBIT](DEBUG): Disabling WDT.
2025-06-20T17:21:46.671Z,1750440106.671 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-06-20T17:21:46.671Z,1750440106.671 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-06-20T17:21:46.672Z,1750440106.672 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.705Z,1750440106.705 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.709Z,1750440106.709 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.716Z,1750440106.716 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.722Z,1750440106.722 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.764Z,1750440106.764 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.798Z,1750440106.798 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-06-20T17:21:46.798Z,1750440106.798 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-06-20T17:21:46.814Z,1750440106.814 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.818Z,1750440106.818 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.872Z,1750440106.872 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-20T17:21:46.921Z,1750440106.921 [logger ThreadHandler](INFO): Thread cancelled.