2025-04-08T20:32:34.300Z,1744144354.300 [Supervisor](DEBUG): Initializing supervisor.
2025-04-08T20:32:34.304Z,1744144354.304 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-04-08T20:32:34.305Z,1744144354.305 [SyncHandler](INFO): Protected caller Thread ID is 829
2025-04-08T20:32:34.305Z,1744144354.305 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-04-08T20:32:34.306Z,1744144354.306 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-04-08T20:32:34.307Z,1744144354.307 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 830
2025-04-08T20:32:34.311Z,1744144354.311 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-04-08T20:32:34.331Z,1744144354.331 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-04-08T20:32:34.332Z,1744144354.332 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-04-08T20:32:34.332Z,1744144354.332 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 831
2025-04-08T20:32:34.336Z,1744144354.336 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-04-08T20:32:34.337Z,1744144354.337 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-04-08T20:32:34.338Z,1744144354.338 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 832
2025-04-08T20:32:34.340Z,1744144354.340 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-04-08T20:32:34.341Z,1744144354.341 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-04-08T20:32:34.341Z,1744144354.341 [logger ThreadHandler](INFO): Protected caller Thread ID is 833
2025-04-08T20:32:34.345Z,1744144354.345 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-04-08T20:32:34.346Z,1744144354.346 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-04-08T20:32:34.351Z,1744144354.351 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-04-08T20:32:34.457Z,1744144354.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-04-08T20:32:34.459Z,1744144354.459 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-04-08T20:32:34.695Z,1744144354.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-04-08T20:32:34.697Z,1744144354.697 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-04-08T20:32:34.972Z,1744144354.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-04-08T20:32:34.974Z,1744144354.974 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-04-08T20:32:35.091Z,1744144355.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-04-08T20:32:35.093Z,1744144355.093 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-04-08T20:32:35.878Z,1744144355.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-04-08T20:32:35.879Z,1744144355.879 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-04-08T20:32:36.401Z,1744144356.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-04-08T20:32:36.401Z,1744144356.401 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-04-08T20:32:36.971Z,1744144356.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-04-08T20:32:36.972Z,1744144356.972 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-04-08T20:32:37.404Z,1744144357.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-04-08T20:32:37.406Z,1744144357.406 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-04-08T20:32:38.214Z,1744144358.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-04-08T20:32:38.215Z,1744144358.215 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-04-08T20:32:38.616Z,1744144358.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-04-08T20:32:38.617Z,1744144358.617 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-04-08T20:32:38.854Z,1744144358.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-04-08T20:32:38.856Z,1744144358.856 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-04-08T20:32:39.008Z,1744144359.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-04-08T20:32:39.008Z,1744144359.008 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-04-08T20:32:39.101Z,1744144359.101 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-04-08T20:32:39.192Z,1744144359.192 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-04-08T20:32:39.394Z,1744144359.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-04-08T20:32:39.396Z,1744144359.396 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-04-08T20:32:39.652Z,1744144359.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-04-08T20:32:39.654Z,1744144359.654 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-04-08T20:32:39.656Z,1744144359.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-04-08T20:32:39.751Z,1744144359.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-04-08T20:32:40.028Z,1744144360.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-04-08T20:32:40.029Z,1744144360.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-04-08T20:32:40.123Z,1744144360.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-04-08T20:32:40.303Z,1744144360.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-04-08T20:32:40.413Z,1744144360.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-04-08T20:32:40.562Z,1744144360.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-04-08T20:32:40.685Z,1744144360.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-04-08T20:32:40.789Z,1744144360.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-04-08T20:32:40.959Z,1744144360.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-04-08T20:32:41.066Z,1744144361.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-04-08T20:32:41.162Z,1744144361.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-04-08T20:32:41.270Z,1744144361.270 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-04-08T20:32:41.366Z,1744144361.366 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-04-08T20:32:41.390Z,1744144361.390 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-04-08T20:32:41.609Z,1744144361.609 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-04-08T20:32:41.610Z,1744144361.610 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-04-08T20:32:41.807Z,1744144361.807 [BuoyancyServo] Loaded
2025-04-08T20:32:41.807Z,1744144361.807 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-04-08T20:32:41.830Z,1744144361.830 [ElevatorServo] Loaded
2025-04-08T20:32:41.830Z,1744144361.830 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-04-08T20:32:41.853Z,1744144361.853 [MassServo] Loaded
2025-04-08T20:32:41.853Z,1744144361.853 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-04-08T20:32:41.875Z,1744144361.875 [RudderServo] Loaded
2025-04-08T20:32:41.875Z,1744144361.875 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-04-08T20:32:41.893Z,1744144361.893 [ThrusterHE] Loaded
2025-04-08T20:32:41.894Z,1744144361.894 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-04-08T20:32:41.894Z,1744144361.894 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-04-08T20:32:41.896Z,1744144361.896 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-04-08T20:32:42.094Z,1744144362.094 [SBIT](DEBUG): Construct Startup Built In Test.
2025-04-08T20:32:42.109Z,1744144362.109 [SBIT] Loaded
2025-04-08T20:32:42.110Z,1744144362.110 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-04-08T20:32:42.112Z,1744144362.112 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-04-08T20:32:42.133Z,1744144362.133 [IBIT] Loaded
2025-04-08T20:32:42.134Z,1744144362.134 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-04-08T20:32:42.139Z,1744144362.139 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-04-08T20:32:42.331Z,1744144362.331 [CBIT] Loaded
2025-04-08T20:32:42.331Z,1744144362.331 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-04-08T20:32:42.362Z,1744144362.362 [GFScanner] Loaded
2025-04-08T20:32:42.363Z,1744144362.363 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-04-08T20:32:42.363Z,1744144362.363 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-04-08T20:32:42.364Z,1744144362.364 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-04-08T20:32:42.578Z,1744144362.578 [CTD_Seabird] Loaded
2025-04-08T20:32:42.578Z,1744144362.578 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-04-08T20:32:42.579Z,1744144362.579 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406834E0
2025-04-08T20:32:42.580Z,1744144362.580 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 921
2025-04-08T20:32:42.615Z,1744144362.615 [ESPComponent] Loaded
2025-04-08T20:32:42.615Z,1744144362.615 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-04-08T20:32:42.636Z,1744144362.636 [PAR_Licor] Loaded
2025-04-08T20:32:42.637Z,1744144362.637 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-04-08T20:32:42.682Z,1744144362.682 [WetLabsBB2FL] Loaded
2025-04-08T20:32:42.682Z,1744144362.682 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-04-08T20:32:42.683Z,1744144362.683 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B34E0
2025-04-08T20:32:42.684Z,1744144362.684 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 922
2025-04-08T20:32:42.684Z,1744144362.684 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-04-08T20:32:42.686Z,1744144362.686 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-04-08T20:32:42.758Z,1744144362.758 [DepthRateCalculator] Loaded
2025-04-08T20:32:42.758Z,1744144362.758 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-04-08T20:32:42.764Z,1744144362.764 [PitchRateCalculator] Loaded
2025-04-08T20:32:42.764Z,1744144362.764 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-04-08T20:32:42.775Z,1744144362.775 [SpeedCalculator] Loaded
2025-04-08T20:32:42.775Z,1744144362.775 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-04-08T20:32:42.780Z,1744144362.780 [YawRateCalculator] Loaded
2025-04-08T20:32:42.780Z,1744144362.780 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-04-08T20:32:42.800Z,1744144362.800 [ElevatorOffsetCalculator] Loaded
2025-04-08T20:32:42.800Z,1744144362.800 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-04-08T20:32:42.801Z,1744144362.801 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-04-08T20:32:42.801Z,1744144362.801 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-04-08T20:32:42.970Z,1744144362.970 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-04-08T20:32:42.971Z,1744144362.971 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-04-08T20:32:43.019Z,1744144363.019 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-04-08T20:32:43.020Z,1744144363.020 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-04-08T20:32:43.221Z,1744144363.221 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-04-08T20:32:43.221Z,1744144363.221 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-04-08T20:32:43.273Z,1744144363.273 [NavChart] Loaded
2025-04-08T20:32:43.274Z,1744144363.274 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-04-08T20:32:43.279Z,1744144363.279 [UniversalFixResidualReporter] Loaded
2025-04-08T20:32:43.280Z,1744144363.280 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-04-08T20:32:43.280Z,1744144363.280 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-04-08T20:32:43.281Z,1744144363.281 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-04-08T20:32:44.760Z,1744144364.760 [AHRS_M2] Loaded
2025-04-08T20:32:44.761Z,1744144364.761 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-04-08T20:32:45.015Z,1744144365.015 [BackseatComponent] Loaded
2025-04-08T20:32:45.015Z,1744144365.015 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-04-08T20:32:45.016Z,1744144365.016 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2025-04-08T20:32:45.017Z,1744144365.017 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2025-04-08T20:32:45.020Z,1744144365.020 [LcmUniversalReporter] Loaded
2025-04-08T20:32:45.020Z,1744144365.020 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-04-08T20:32:45.750Z,1744144365.750 [BPC1] Loaded
2025-04-08T20:32:45.751Z,1744144365.751 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-04-08T20:32:45.847Z,1744144365.847 [DAT] Loaded
2025-04-08T20:32:45.848Z,1744144365.848 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-04-08T20:32:45.849Z,1744144365.849 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2025-04-08T20:32:45.849Z,1744144365.849 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924
2025-04-08T20:32:45.991Z,1744144365.991 [DataOverHttps] Loaded
2025-04-08T20:32:45.991Z,1744144365.991 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-04-08T20:32:45.992Z,1744144365.992 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2025-04-08T20:32:45.992Z,1744144365.992 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2025-04-08T20:32:46.016Z,1744144366.016 [Depth_Keller] Loaded
2025-04-08T20:32:46.016Z,1744144366.016 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-04-08T20:32:46.021Z,1744144366.021 [DropWeight] Loaded
2025-04-08T20:32:46.021Z,1744144366.021 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-04-08T20:32:46.083Z,1744144366.083 [NAL9602] Loaded
2025-04-08T20:32:46.084Z,1744144366.084 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-04-08T20:32:46.110Z,1744144366.110 [Onboard] Loaded
2025-04-08T20:32:46.110Z,1744144366.110 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-04-08T20:32:46.111Z,1744144366.111 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0
2025-04-08T20:32:46.111Z,1744144366.111 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2025-04-08T20:32:46.117Z,1744144366.117 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2025-04-08T20:32:46.131Z,1744144366.131 [PowerOnly] Loaded
2025-04-08T20:32:46.132Z,1744144366.132 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-04-08T20:32:46.146Z,1744144366.146 [Power24vConverter] Loaded
2025-04-08T20:32:46.146Z,1744144366.146 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-04-08T20:32:46.163Z,1744144366.163 [Radio_Surface] Loaded
2025-04-08T20:32:46.163Z,1744144366.163 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-04-08T20:32:46.164Z,1744144366.164 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B3F4E0
2025-04-08T20:32:46.164Z,1744144366.164 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2025-04-08T20:32:46.180Z,1744144366.180 [Sonardyne_Nano] Loaded
2025-04-08T20:32:46.180Z,1744144366.180 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-04-08T20:32:46.181Z,1744144366.181 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-04-08T20:32:46.181Z,1744144366.181 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-04-08T20:32:46.269Z,1744144366.269 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-04-08T20:32:46.270Z,1744144366.270 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-04-08T20:32:46.342Z,1744144366.342 [VerticalControl](DEBUG): Construct VerticalControl.
2025-04-08T20:32:46.395Z,1744144366.395 [VerticalControl] Loaded
2025-04-08T20:32:46.395Z,1744144366.395 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-04-08T20:32:46.398Z,1744144366.398 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-04-08T20:32:46.442Z,1744144366.442 [HorizontalControl] Loaded
2025-04-08T20:32:46.443Z,1744144366.443 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-04-08T20:32:46.445Z,1744144366.445 [SpeedControl](DEBUG): Construct SpeedControl.
2025-04-08T20:32:46.447Z,1744144366.447 [SpeedControl] Loaded
2025-04-08T20:32:46.447Z,1744144366.447 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-04-08T20:32:46.450Z,1744144366.450 [LoopControl](DEBUG): Construct LoopControl.
2025-04-08T20:32:46.450Z,1744144366.450 [LoopControl] Loaded
2025-04-08T20:32:46.451Z,1744144366.451 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-04-08T20:32:46.451Z,1744144366.451 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-04-08T20:32:46.452Z,1744144366.452 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-04-08T20:32:46.468Z,1744144366.468 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-04-08T20:32:46.468Z,1744144366.468 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-04-08T20:32:46.880Z,1744144366.880 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-04-08T20:32:46.887Z,1744144366.887 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-04-08T20:32:46.890Z,1744144366.890 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-04-08T20:32:46.901Z,1744144366.901 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-04-08T20:32:46.902Z,1744144366.902 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-04-08T20:32:46.902Z,1744144366.902 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2025-04-08T20:32:46.907Z,1744144366.907 [Supervisor](INFO): Main Thread ID is 822
2025-04-08T20:32:46.907Z,1744144366.907 [Supervisor](DEBUG): Running supervisor.
2025-04-08T20:32:46.907Z,1744144366.907 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2025-04-08T20:32:46.908Z,1744144366.908 [CommandExec](INFO): Initializing the command executive.
2025-04-08T20:32:46.910Z,1744144366.910 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2025-04-08T20:32:46.912Z,1744144366.912 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2025-04-08T20:32:46.912Z,1744144366.912 [controlThread](DEBUG): Initializing ControlThread
2025-04-08T20:32:46.914Z,1744144366.914 [SBIT](INFO): Initialize SBIT Component.
2025-04-08T20:32:46.914Z,1744144366.914 [SBIT](IMPORTANT): git: 2025-03-26-1-g1815535cc
2025-04-08T20:32:46.914Z,1744144366.914 [SBIT](INFO): git hash: 1815535cca105e464bdb7831f6233b5043f0283f
2025-04-08T20:32:46.915Z,1744144366.915 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-04-08T20:32:46.916Z,1744144366.916 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-04-08T20:32:46.917Z,1744144366.917 [SBIT](INFO): Beginning SBIT in 69.000000 seconds.
2025-04-08T20:32:46.918Z,1744144366.918 [IBIT](INFO): Initialize IBIT Component.
2025-04-08T20:32:46.919Z,1744144366.919 [CBIT](DEBUG): Initialize CBIT Component.
2025-04-08T20:32:46.919Z,1744144366.919 [logger ThreadHandler](INFO): Handler Thread ID is 932
2025-04-08T20:32:46.933Z,1744144366.933 [CBIT](DEBUG): Initialized mux pins.
2025-04-08T20:32:46.933Z,1744144366.933 [CBIT](DEBUG): Initializing the watchdog timer.
2025-04-08T20:32:46.945Z,1744144366.945 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 933
2025-04-08T20:32:46.946Z,1744144366.946 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-08T20:32:46.951Z,1744144366.951 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 935
2025-04-08T20:32:46.953Z,1744144366.953 [WetLabsBB2FL](INFO): Powering up
2025-04-08T20:32:46.955Z,1744144366.955 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937
2025-04-08T20:32:46.957Z,1744144366.957 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-04-08T20:32:46.958Z,1744144366.958 [CBIT](DEBUG): Initializing heartbeat.
2025-04-08T20:32:46.971Z,1744144366.971 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2025-04-08T20:32:46.972Z,1744144366.972 [DAT](INFO): Powering up
2025-04-08T20:32:46.972Z,1744144366.972 [DAT](DEBUG): Initializing DAT.
2025-04-08T20:32:46.974Z,1744144366.974 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 940
2025-04-08T20:32:46.976Z,1744144366.976 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-04-08T20:32:46.986Z,1744144366.986 [Onboard ThreadHandler](INFO): Handler Thread ID is 941
2025-04-08T20:32:47.003Z,1744144367.003 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 942
2025-04-08T20:32:47.021Z,1744144367.021 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2025-04-08T20:32:47.029Z,1744144367.029 [CBIT](DEBUG): Deactivating emergency mode.
2025-04-08T20:32:47.030Z,1744144367.030 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-04-08T20:32:47.030Z,1744144367.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-04-08T20:32:47.030Z,1744144367.030 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-04-08T20:32:47.030Z,1744144367.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-04-08T20:32:47.031Z,1744144367.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-04-08T20:32:47.031Z,1744144367.031 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-04-08T20:32:47.031Z,1744144367.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-04-08T20:32:47.031Z,1744144367.031 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-04-08T20:32:47.069Z,1744144367.069 [CBIT](DEBUG): Backplane powered.
2025-04-08T20:32:47.069Z,1744144367.069 [GFScanner](DEBUG): Initializing GFScanner
2025-04-08T20:32:47.070Z,1744144367.070 [GFScanner](DEBUG): Deactivating GF circuits.
2025-04-08T20:32:47.077Z,1744144367.077 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-04-08T20:32:47.078Z,1744144367.078 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-04-08T20:32:47.078Z,1744144367.078 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-04-08T20:32:47.078Z,1744144367.078 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-04-08T20:32:47.079Z,1744144367.079 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-04-08T20:32:47.080Z,1744144367.080 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-04-08T20:32:47.080Z,1744144367.080 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-04-08T20:32:47.084Z,1744144367.084 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-04-08T20:32:47.089Z,1744144367.089 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-04-08T20:32:47.090Z,1744144367.090 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-04-08T20:32:47.091Z,1744144367.091 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-04-08T20:32:47.091Z,1744144367.091 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-04-08T20:32:47.092Z,1744144367.092 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-04-08T20:32:47.092Z,1744144367.092 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-04-08T20:32:47.110Z,1744144367.110 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-04-08T20:32:47.200Z,1744144367.200 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-04-08T20:32:47.217Z,1744144367.217 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-08T20:32:47.229Z,1744144367.229 [DAT](INFO): Using elevation to construct direction to contact in vehicle frame.
2025-04-08T20:32:47.242Z,1744144367.242 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

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

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


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

2025-04-08T20:32:47.559Z,1744144367.559 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-04-08T20:32:47.560Z,1744144367.560 [Default:A.Wait](DEBUG): Construct Wait.
2025-04-08T20:32:47.562Z,1744144367.562 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-08T20:32:47.618Z,1744144367.618 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-04-08T20:32:47.621Z,1744144367.621 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-04-08T20:32:47.639Z,1744144367.639 [Default:E.Execute](DEBUG): Construct Execute.
2025-04-08T20:32:47.642Z,1744144367.642 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

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

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

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

          set command = "Burn on"
        }

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

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

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

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-04-08T20:32:47.661Z,1744144367.661 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-08T20:32:47.702Z,1744144367.702 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-08T20:32:47.846Z,1744144367.846 [Depth_Keller](INFO): Initializing.
2025-04-08T20:32:47.848Z,1744144367.848 [Power24vConverter](INFO): Powering up.
2025-04-08T20:32:47.848Z,1744144367.848 [Sonardyne_Nano](INFO): Initializing.
2025-04-08T20:32:47.881Z,1744144367.881 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-04-08T20:32:47.919Z,1744144367.919 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-08T20:32:47.925Z,1744144367.925 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-08T20:32:47.926Z,1744144367.926 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-08T20:32:47.933Z,1744144367.933 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-08T20:32:47.934Z,1744144367.934 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-08T20:32:47.941Z,1744144367.941 [MassServo](DEBUG): Initializing MassServo.
2025-04-08T20:32:47.942Z,1744144367.942 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-08T20:32:47.950Z,1744144367.950 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-08T20:32:47.951Z,1744144367.951 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-08T20:32:47.958Z,1744144367.958 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-08T20:32:48.108Z,1744144368.108 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-04-08T20:32:48.109Z,1744144368.109 [DropWeight] Hardware Fault, FailCount= 1
2025-04-08T20:32:48.109Z,1744144368.109 [DropWeight](ERROR): Hardware Fault
2025-04-08T20:32:48.124Z,1744144368.124 [CommandExec](FAULT): Scheduling is paused
2025-04-08T20:32:48.124Z,1744144368.124 [CBIT](INFO): Critical error at 20250408T203248
2025-04-08T20:32:48.124Z,1744144368.124 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-04-08T20:32:48.131Z,1744144368.131 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-04-08T20:32:48.132Z,1744144368.132 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-04-08T20:32:48.502Z,1744144368.502 [CBIT](INFO): Critical error at 20250408T203248
2025-04-08T20:32:49.565Z,1744144369.565 [WetLabsBB2FL](INFO): Powering down
2025-04-08T20:32:50.099Z,1744144370.099 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-04-08T20:32:51.338Z,1744144371.338 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-04-08T20:32:53.566Z,1744144373.566 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-04-08T20:32:59.070Z,1744144379.070 [DAT](INFO): DAT read: 

2025-04-08T20:32:59.071Z,1744144379.071 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-04-08T20:33:00.834Z,1744144380.834 [DAT](INFO): DAT read: MF Frequency Band

2025-04-08T20:33:00.835Z,1744144380.835 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-04-08T20:33:00.836Z,1744144380.836 [DAT](INFO): DAT read: Apr  8 2025 20:29:46 

2025-04-08T20:33:02.094Z,1744144382.094 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-04-08T20:33:02.095Z,1744144382.095 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-04-08T20:33:02.095Z,1744144382.095 [DAT](INFO): commRate: 1200
2025-04-08T20:33:04.161Z,1744144384.161 [DAT](INFO): entering command mode
2025-04-08T20:33:04.362Z,1744144384.362 [DAT](INFO): DAT read: 

2025-04-08T20:33:04.362Z,1744144384.362 [DAT](INFO): DAT read: user:1>

2025-04-08T20:33:04.363Z,1744144384.363 [DAT](INFO): setting verbose to 3
2025-04-08T20:33:04.614Z,1744144384.614 [DAT](INFO): DAT read: user:1>

2025-04-08T20:33:04.615Z,1744144384.615 [DAT](INFO): DAT read: Verbose         | 3           

2025-04-08T20:33:04.615Z,1744144384.615 [DAT](INFO): set verbose to 3
2025-04-08T20:33:04.615Z,1744144384.615 [DAT](INFO): setting DatVerbose to 27440
2025-04-08T20:33:04.866Z,1744144384.866 [DAT](INFO): DAT read: user:2>

2025-04-08T20:33:04.867Z,1744144384.867 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-04-08T20:33:04.867Z,1744144384.867 [DAT](INFO): set DatVerbose to 27440
2025-04-08T20:33:04.867Z,1744144384.867 [DAT](INFO): setting transmit power to 8
2025-04-08T20:33:05.118Z,1744144385.118 [DAT](INFO): DAT read: user:3>

2025-04-08T20:33:05.119Z,1744144385.119 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-04-08T20:33:05.119Z,1744144385.119 [DAT](INFO): set transmit power to 8
2025-04-08T20:33:05.119Z,1744144385.119 [DAT](INFO): setting local address to 5
2025-04-08T20:33:05.370Z,1744144385.370 [DAT](INFO): DAT read: user:4>

2025-04-08T20:33:05.371Z,1744144385.371 [DAT](INFO): DAT read: LocalAddr       | 5           

2025-04-08T20:33:05.371Z,1744144385.371 [DAT](INFO): set local address to 5
2025-04-08T20:33:05.372Z,1744144385.372 [DAT](INFO): Setting time to: 20:33:5 And date to:4/8/2025
2025-04-08T20:33:05.622Z,1744144385.622 [DAT](INFO): DAT read: user:5>

2025-04-08T20:33:05.622Z,1744144385.622 [DAT](INFO): DAT read: Tue Apr 8, 2025  20:33:05  

2025-04-08T20:33:05.623Z,1744144385.623 [DAT](INFO): Local DAT time set to Tue Apr 8, 2025  20:33:05  

2025-04-08T20:33:16.371Z,1744144396.371 [NAL9602](INFO): Powering up NAL9602
2025-04-08T20:33:27.271Z,1744144407.271 [NAL9602](INFO): NAL9602 initialized
2025-04-08T20:33:56.386Z,1744144436.386 [SBIT](IMPORTANT): Beginning Startup BIT
2025-04-08T20:33:56.391Z,1744144436.391 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:34:00.952Z,1744144440.952 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007476
CHAN A1 (24V): 0.000204
CHAN A2 (12V): -0.005337
CHAN A3 (5V): -0.002569
CHAN B0 (3.3V): -0.001313
CHAN B1 (3.15aV): -0.000920
CHAN B2 (3.15bV): -0.001096
CHAN B3 (GND): -0.000385
OPEN: 0.006657
Full Scale: +/- 1 mA
2025-04-08T20:34:49.847Z,1744144489.847 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004731
2025-04-08T20:34:50.587Z,1744144490.587 [SBIT](IMPORTANT): SBIT PASSED
2025-04-08T20:34:50.588Z,1744144490.588 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-04-08T20:34:50.589Z,1744144490.589 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;

2025-04-08T20:34:50.589Z,1744144490.589 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;

2025-04-08T20:34:50.589Z,1744144490.589 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2025-04-08T20:34:50.589Z,1744144490.589 [SBIT](IMPORTANT): BuoyancyServo.loadAtStartup=1 bool;

2025-04-08T20:34:50.589Z,1744144490.589 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;

2025-04-08T20:34:50.589Z,1744144490.589 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=1 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): MassServo.loadAtStartup=1 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): NAL9602.loadAtStartup=1 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2025-04-08T20:34:50.590Z,1744144490.590 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): Radio_Surface.loadAtStartup=1 bool;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): RudderServo.loadAtStartup=1 bool;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): ThrusterHE.loadAtStartup=1 bool;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=209.04 cubic_centimeter;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): VerticalControl.kdDepth=0.4 radian_per_second_per_meter;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): VerticalControl.kpDepth=0.1 radian_per_meter;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): VerticalControl.massDefault=12.4082 millimeter;

2025-04-08T20:34:50.591Z,1744144490.591 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool;

2025-04-08T20:34:51.010Z,1744144491.010 [MissionManager](IMPORTANT): Started mission Startup
2025-04-08T20:34:51.010Z,1744144491.010 [Startup] Running Loop=1
2025-04-08T20:34:51.010Z,1744144491.010 [Startup](DEBUG): Aggregate::initialize Startup
2025-04-08T20:34:51.010Z,1744144491.010 [Startup:A.GoToSurface] Running Loop=1
2025-04-08T20:34:51.011Z,1744144491.011 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-08T20:34:51.011Z,1744144491.011 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-04-08T20:34:51.012Z,1744144491.012 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-04-08T20:34:51.012Z,1744144491.012 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-04-08T20:34:51.012Z,1744144491.012 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-04-08T20:34:51.012Z,1744144491.012 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-08T20:34:51.013Z,1744144491.013 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-08T20:34:51.015Z,1744144491.015 [Startup:StartupSatComms] Running Loop=1
2025-04-08T20:34:51.015Z,1744144491.015 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-04-08T20:34:51.015Z,1744144491.015 [Startup:StartupSatComms:A] Running Loop=1
2025-04-08T20:34:51.398Z,1744144491.398 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-04-08T20:34:52.603Z,1744144492.603 [NAL9602](INFO): SBD MO Status=2, MOMSN=19218, MT Status=2, MTMSN=0
2025-04-08T20:34:52.604Z,1744144492.604 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:35:40.235Z,1744144540.235 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:35:40.334Z,1744144540.334 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:35:44.732Z,1744144544.732 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006799
CHAN A1 (24V): 0.000076
CHAN A2 (12V): -0.005487
CHAN A3 (5V): -0.002375
CHAN B0 (3.3V): -0.000923
CHAN B1 (3.15aV): -0.000673
CHAN B2 (3.15bV): -0.001005
CHAN B3 (GND): 0.000226
OPEN: 0.006562
Full Scale: +/- 1 mA
2025-04-08T20:35:47.179Z,1744144547.179 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-04-08T20:35:47.179Z,1744144547.179 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-08T20:35:47.197Z,1744144547.197 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-08T20:35:47.594Z,1744144547.594 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-08T20:35:47.595Z,1744144547.595 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-04-08T20:35:48.355Z,1744144548.355 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:35:48.521Z,1744144548.521 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:35:51.182Z,1744144551.182 [Startup:StartupSatComms:A](INFO): Timed out from 2025-04-08T20:34:51.0Z
2025-04-08T20:35:51.182Z,1744144551.182 [Startup:StartupSatComms:A] Stopped
2025-04-08T20:35:51.182Z,1744144551.182 [Startup:StartupSatComms:B] Running Loop=1
2025-04-08T20:35:51.598Z,1744144551.598 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-08T20:35:52.824Z,1744144552.824 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006952
CHAN A1 (24V): -0.000053
CHAN A2 (12V): -0.005326
CHAN A3 (5V): -0.002837
CHAN B0 (3.3V): -0.001034
CHAN B1 (3.15aV): -0.001700
CHAN B2 (3.15bV): -0.000920
CHAN B3 (GND): 0.000159
OPEN: 0.006554
Full Scale: +/- 1 mA
2025-04-08T20:35:55.854Z,1744144555.854 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:35:56.072Z,1744144556.072 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:35:59.477Z,1744144559.477 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250408T201926/Courier0004.lzma
2025-04-08T20:36:00.479Z,1744144560.479 [DataOverHttps](INFO): Moved sent file to Logs/20250408T201926/Courier0004.lzma.bak
2025-04-08T20:36:00.479Z,1744144560.479 [DataOverHttps](INFO): SBD MOMSN=24582625
2025-04-08T20:36:00.556Z,1744144560.556 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007040
CHAN A1 (24V): 0.000300
CHAN A2 (12V): -0.005347
CHAN A3 (5V): -0.002983
CHAN B0 (3.3V): -0.001214
CHAN B1 (3.15aV): -0.000673
CHAN B2 (3.15bV): -0.001231
CHAN B3 (GND): 0.000223
OPEN: 0.006509
Full Scale: +/- 1 mA
2025-04-08T20:36:01.693Z,1744144561.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=19218, MT Status=2, MTMSN=0
2025-04-08T20:36:01.693Z,1744144561.693 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:36:16.220Z,1744144576.220 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250408T202509/Courier0000.lzma
2025-04-08T20:36:17.223Z,1744144577.223 [DataOverHttps](INFO): Moved sent file to Logs/20250408T202509/Courier0000.lzma.bak
2025-04-08T20:36:17.223Z,1744144577.223 [DataOverHttps](INFO): SBD MOMSN=24582632
2025-04-08T20:36:18.660Z,1744144578.660 [NAL9602](INFO): SBD MO Status=0, MOMSN=19218, MT Status=0, MTMSN=0
2025-04-08T20:36:18.660Z,1744144578.660 [NAL9602](INFO): No messages in MT queue
2025-04-08T20:36:20.779Z,1744144580.779 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB4
2025-04-08T20:36:22.708Z,1744144582.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203555.00,A,3648.15919,N,12147.28214,W,0.214,351.13,080425,,,A*71
2025-04-08T20:36:22.712Z,1744144582.712 [NAL9602](INFO): GPS fix at 20250408T203555: (36.802653, -121.788036)
2025-04-08T20:36:23.598Z,1744144583.598 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:36:23.953Z,1744144583.953 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:36:28.389Z,1744144588.389 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.003919
CHAN A1 (24V): 0.010323
CHAN A2 (12V): -0.014948
CHAN A3 (5V): -0.043352
CHAN B0 (3.3V): -0.007974
CHAN B1 (3.15aV): -0.003159
CHAN B2 (3.15bV): -0.002077
CHAN B3 (GND): -0.007914
OPEN: 0.012136
Full Scale: +/- 1 mA
2025-04-08T20:36:31.006Z,1744144591.006 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:36:31.249Z,1744144591.249 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:36:34.847Z,1744144594.847 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20250408T202834/Courier0000.lzma
2025-04-08T20:36:35.680Z,1744144595.680 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.000603
CHAN A1 (24V): 0.006493
CHAN A2 (12V): -0.009095
CHAN A3 (5V): -0.138825
CHAN B0 (3.3V): -0.011242
CHAN B1 (3.15aV): -0.003441
CHAN B2 (3.15bV): -0.002386
CHAN B3 (GND): -0.006298
OPEN: 0.012918
Full Scale: +/- 1 mA
2025-04-08T20:36:35.847Z,1744144595.847 [DataOverHttps](INFO): Moved sent file to Logs/20250408T202834/Courier0000.lzma.bak
2025-04-08T20:36:35.847Z,1744144595.847 [DataOverHttps](INFO): SBD MOMSN=24582640
2025-04-08T20:36:51.425Z,1744144611.425 [Startup:StartupSatComms:B](INFO): Timed out from 2025-04-08T20:35:51.2Z
2025-04-08T20:36:51.425Z,1744144611.425 [Startup:StartupSatComms:B] Stopped
2025-04-08T20:36:51.425Z,1744144611.425 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-04-08T20:36:51.425Z,1744144611.425 [Startup:StartupSatComms] Stopped
2025-04-08T20:36:51.426Z,1744144611.426 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-04-08T20:36:51.427Z,1744144611.427 [Startup](INFO): Completed Startup
2025-04-08T20:36:51.427Z,1744144611.427 [MissionManager](INFO): Startup is completed.
2025-04-08T20:36:51.427Z,1744144611.427 [MissionManager](INFO): Uninitializing Mission Startup
2025-04-08T20:36:51.427Z,1744144611.427 [Startup] Stopped
2025-04-08T20:36:51.427Z,1744144611.427 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-04-08T20:36:51.427Z,1744144611.427 [Startup:A.GoToSurface] Stopped
2025-04-08T20:36:51.427Z,1744144611.427 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-08T20:36:51.805Z,1744144611.805 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20250408T203234/Courier0000.lzma
2025-04-08T20:36:51.818Z,1744144611.818 [MissionManager](IMPORTANT): Started mission Default
2025-04-08T20:36:51.818Z,1744144611.818 [Default] Running Loop=1
2025-04-08T20:36:51.818Z,1744144611.818 [Default](DEBUG): Aggregate::initialize Default
2025-04-08T20:36:51.818Z,1744144611.818 [Default:B.GoToSurface] Running Loop=1
2025-04-08T20:36:51.818Z,1744144611.818 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-08T20:36:51.819Z,1744144611.819 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-04-08T20:36:51.819Z,1744144611.819 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-04-08T20:36:51.819Z,1744144611.819 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-04-08T20:36:51.819Z,1744144611.819 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-04-08T20:36:51.820Z,1744144611.820 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-08T20:36:51.820Z,1744144611.820 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-08T20:36:51.820Z,1744144611.820 [Default:A.Wait] Running Loop=1
2025-04-08T20:36:51.820Z,1744144611.820 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-04-08T20:36:52.807Z,1744144612.807 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0000.lzma.bak
2025-04-08T20:36:52.807Z,1744144612.807 [DataOverHttps](INFO): SBD MOMSN=24582684
2025-04-08T20:36:55.434Z,1744144615.434 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-08T20:37:03.226Z,1744144623.226 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:37:03.583Z,1744144623.583 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:37:05.192Z,1744144625.192 [Default:A.Wait](INFO): Done Waiting.
2025-04-08T20:37:05.193Z,1744144625.193 [Default:A.Wait] Stopped
2025-04-08T20:37:05.193Z,1744144625.193 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T20:37:05.615Z,1744144625.615 [Default:CheckIn] Running Loop=1
2025-04-08T20:37:05.615Z,1744144625.615 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T20:37:05.615Z,1744144625.615 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T20:37:05.995Z,1744144625.995 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-08T20:37:07.603Z,1744144627.603 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203639.00,A,3648.16281,N,12147.27659,W,0.778,351.13,080425,,,A*7C
2025-04-08T20:37:07.605Z,1744144627.605 [NAL9602](INFO): GPS fix at 20250408T203639: (36.802714, -121.787943)
2025-04-08T20:37:07.616Z,1744144627.616 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T20:37:07.616Z,1744144627.616 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T20:37:08.038Z,1744144628.038 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-08T20:37:08.075Z,1744144628.075 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.002074
CHAN A1 (24V): 0.011121
CHAN A2 (12V): -0.015593
CHAN A3 (5V): -0.021574
CHAN B0 (3.3V): -0.008017
CHAN B1 (3.15aV): -0.003577
CHAN B2 (3.15bV): -0.001955
CHAN B3 (GND): -0.006710
OPEN: 0.012275
Full Scale: +/- 1 mA
2025-04-08T20:37:16.471Z,1744144636.471 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250408T203234/Courier0004.lzma
2025-04-08T20:37:17.467Z,1744144637.467 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0004.lzma.bak
2025-04-08T20:37:17.467Z,1744144637.467 [DataOverHttps](INFO): SBD MOMSN=24582689
2025-04-08T20:37:28.224Z,1744144648.224 [NAL9602](INFO): SBD MO Status=2, MOMSN=19219, MT Status=2, MTMSN=0
2025-04-08T20:37:28.224Z,1744144648.224 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:37:32.619Z,1744144652.619 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadB4
2025-04-08T20:37:33.566Z,1744144653.566 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250408T201926/Express0005.lzma
2025-04-08T20:37:34.567Z,1744144654.567 [DataOverHttps](INFO): Moved sent file to Logs/20250408T201926/Express0005.lzma.bak
2025-04-08T20:37:34.567Z,1744144654.567 [DataOverHttps](INFO): SBD MOMSN=24582698
2025-04-08T20:37:41.414Z,1744144661.414 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:37:41.721Z,1744144661.721 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:37:46.143Z,1744144666.143 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007010
CHAN A1 (24V): 0.000200
CHAN A2 (12V): -0.005716
CHAN A3 (5V): -0.003139
CHAN B0 (3.3V): -0.001238
CHAN B1 (3.15aV): -0.001009
CHAN B2 (3.15bV): -0.001306
CHAN B3 (GND): 0.000077
OPEN: 0.006588
Full Scale: +/- 1 mA
2025-04-08T20:37:49.763Z,1744144669.763 [NAL9602](INFO): SBD MO Status=2, MOMSN=19219, MT Status=2, MTMSN=0
2025-04-08T20:37:49.763Z,1744144669.763 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:37:52.377Z,1744144672.377 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250408T202509/Express0001.lzma
2025-04-08T20:37:53.379Z,1744144673.379 [DataOverHttps](INFO): Moved sent file to Logs/20250408T202509/Express0001.lzma.bak
2025-04-08T20:37:53.379Z,1744144673.379 [DataOverHttps](INFO): SBD MOMSN=24582700
2025-04-08T20:37:59.114Z,1744144679.114 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:37:59.141Z,1744144679.141 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:38:03.530Z,1744144683.530 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007358
CHAN A1 (24V): 0.000016
CHAN A2 (12V): -0.005829
CHAN A3 (5V): -0.002628
CHAN B0 (3.3V): -0.001375
CHAN B1 (3.15aV): -0.001514
CHAN B2 (3.15bV): -0.001328
CHAN B3 (GND): 0.000167
OPEN: 0.006482
Full Scale: +/- 1 mA
2025-04-08T20:38:11.196Z,1744144691.196 [NAL9602](INFO): SBD MO Status=2, MOMSN=19219, MT Status=2, MTMSN=0
2025-04-08T20:38:11.196Z,1744144691.196 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:38:11.752Z,1744144691.752 [DataOverHttps](INFO): Sending 1189 bytes from file Logs/20250408T202834/Express0001.lzma
2025-04-08T20:38:12.343Z,1744144692.343 [DataOverHttps](INFO): Moved sent file to Logs/20250408T202834/Express0001.lzma.bak
2025-04-08T20:38:12.343Z,1744144692.343 [DataOverHttps](INFO): SBD MOMSN=24582702
2025-04-08T20:38:28.551Z,1744144708.551 [NAL9602](INFO): SBD MO Status=2, MOMSN=19219, MT Status=2, MTMSN=0
2025-04-08T20:38:28.551Z,1744144708.551 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:38:28.572Z,1744144708.572 [DataOverHttps](INFO): Sending 1151 bytes from file Logs/20250408T203234/Express0001.lzma
2025-04-08T20:38:29.571Z,1744144709.571 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0001.lzma.bak
2025-04-08T20:38:29.571Z,1744144709.571 [DataOverHttps](INFO): SBD MOMSN=24582742
2025-04-08T20:38:45.650Z,1744144725.650 [DataOverHttps](INFO): Sending 764 bytes from file Logs/20250408T203234/Express0005.lzma
2025-04-08T20:38:46.651Z,1744144726.651 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0005.lzma.bak
2025-04-08T20:38:46.651Z,1744144726.651 [DataOverHttps](INFO): SBD MOMSN=24582785
2025-04-08T20:38:48.376Z,1744144728.376 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T20:38:48.376Z,1744144728.376 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T20:38:48.376Z,1744144728.376 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T20:39:05.265Z,1744144745.265 [CommandExec](IMPORTANT): got command gfscan
2025-04-08T20:39:05.360Z,1744144745.360 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-08T20:39:09.855Z,1744144749.855 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007336
CHAN A1 (24V): 0.000324
CHAN A2 (12V): -0.005257
CHAN A3 (5V): -0.002680
CHAN B0 (3.3V): -0.000987
CHAN B1 (3.15aV): -0.000810
CHAN B2 (3.15bV): -0.001203
CHAN B3 (GND): 0.000176
OPEN: 0.006441
Full Scale: +/- 1 mA
2025-04-08T20:39:27.944Z,1744144767.944 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-04-08T20:41:24.711Z,1744144884.711 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-04-08T20:41:24.712Z,1744144884.712 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2025-04-08T20:41:24.720Z,1744144884.720 [BPC1](INFO): Received data from all battery sticks.
2025-04-08T20:42:10.361Z,1744144930.361 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-04-08T20:42:41.064Z,1744144961.064 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-08T20:43:48.955Z,1744145028.955 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-08T20:43:48.955Z,1744145028.955 [Default:CheckIn:C.Wait] Stopped
2025-04-08T20:43:48.955Z,1744145028.955 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T20:43:48.955Z,1744145028.955 [Default:CheckIn:D] Running Loop=1
2025-04-08T20:43:49.360Z,1744145029.360 [Default:CheckIn:D] Stopped
2025-04-08T20:43:49.360Z,1744145029.360 [Default:CheckIn:E] Running Loop=1
2025-04-08T20:43:49.769Z,1744145029.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  6.959026 min 
2025-04-08T20:43:49.769Z,1744145029.769 [Default:CheckIn:E] Stopped
2025-04-08T20:43:49.769Z,1744145029.769 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-08T20:43:49.769Z,1744145029.769 [Default:CheckIn] Stopped
2025-04-08T20:43:49.770Z,1744145029.770 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T20:43:49.770Z,1744145029.770 [Default:CheckIn](INFO): Running loop #2
2025-04-08T20:43:49.770Z,1744145029.770 [Default:CheckIn] Running Loop=2
2025-04-08T20:43:49.770Z,1744145029.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T20:43:49.770Z,1744145029.770 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T20:43:51.775Z,1744145031.775 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204327.00,A,3648.16846,N,12147.28392,W,0.097,351.13,080425,,,A*7B
2025-04-08T20:43:51.777Z,1744145031.777 [NAL9602](INFO): GPS fix at 20250408T204327: (36.802808, -121.788065)
2025-04-08T20:43:51.815Z,1744145031.815 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T20:43:51.815Z,1744145031.815 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T20:44:01.953Z,1744145041.953 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250408T203234/Courier0007.lzma
2025-04-08T20:44:02.955Z,1744145042.955 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0007.lzma.bak
2025-04-08T20:44:02.955Z,1744145042.955 [DataOverHttps](INFO): SBD MOMSN=24582873
2025-04-08T20:44:18.832Z,1744145058.832 [DataOverHttps](INFO): Sending 583 bytes from file Logs/20250408T203234/Express0008.lzma
2025-04-08T20:44:19.827Z,1744145059.827 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0008.lzma.bak
2025-04-08T20:44:19.827Z,1744145059.827 [DataOverHttps](INFO): SBD MOMSN=24582876
2025-04-08T20:44:21.683Z,1744145061.683 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T20:44:21.684Z,1744145061.684 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T20:44:21.684Z,1744145061.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T20:44:22.480Z,1744145062.480 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-04-08T20:44:22.557Z,1744145062.557 [NAL9602](ERROR): received: 
+CSQ:0

OK219, 2, 0, 0, 0

OK
2025-04-08T20:45:56.647Z,1744145156.647 [NAL9602](INFO): SBD MO Status=2, MOMSN=19219, MT Status=2, MTMSN=0
2025-04-08T20:45:56.648Z,1744145156.648 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:46:18.857Z,1744145178.857 [NAL9602](INFO): SBD MO Status=0, MOMSN=19219, MT Status=0, MTMSN=0
2025-04-08T20:46:18.857Z,1744145178.857 [NAL9602](INFO): No messages in MT queue
2025-04-08T20:46:49.554Z,1744145209.554 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-08T20:47:48.199Z,1744145268.199 [CBIT](INFO): Clearing failed state for component DropWeight
2025-04-08T20:47:48.200Z,1744145268.200 [DropWeight] No Fault, FailCount= 1
2025-04-08T20:49:22.314Z,1744145362.314 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-08T20:49:22.314Z,1744145362.314 [Default:CheckIn:C.Wait] Stopped
2025-04-08T20:49:22.314Z,1744145362.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T20:49:22.314Z,1744145362.314 [Default:CheckIn:D] Running Loop=1
2025-04-08T20:49:22.721Z,1744145362.721 [Default:CheckIn:D] Stopped
2025-04-08T20:49:22.721Z,1744145362.721 [Default:CheckIn:E] Running Loop=1
2025-04-08T20:49:23.131Z,1744145363.131 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  12.515046 min 
2025-04-08T20:49:23.131Z,1744145363.131 [Default:CheckIn:E] Stopped
2025-04-08T20:49:23.131Z,1744145363.131 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-08T20:49:23.131Z,1744145363.131 [Default:CheckIn] Stopped
2025-04-08T20:49:23.131Z,1744145363.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T20:49:23.131Z,1744145363.131 [Default:CheckIn](INFO): Running loop #3
2025-04-08T20:49:23.131Z,1744145363.131 [Default:CheckIn] Running Loop=3
2025-04-08T20:49:23.132Z,1744145363.132 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T20:49:23.132Z,1744145363.132 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T20:49:25.139Z,1744145365.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204900.00,A,3648.16620,N,12147.28374,W,0.467,47.77,080425,,,D*4A
2025-04-08T20:49:25.142Z,1744145365.142 [NAL9602](INFO): GPS fix at 20250408T204900: (36.802770, -121.788062)
2025-04-08T20:49:25.152Z,1744145365.152 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T20:49:25.152Z,1744145365.152 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T20:49:32.625Z,1744145372.625 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250408T203234/Courier0010.lzma
2025-04-08T20:49:33.635Z,1744145373.635 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0010.lzma.bak
2025-04-08T20:49:33.635Z,1744145373.635 [DataOverHttps](INFO): SBD MOMSN=24583040
2025-04-08T20:49:49.537Z,1744145389.537 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250408T203234/Express0011.lzma
2025-04-08T20:49:50.539Z,1744145390.539 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0011.lzma.bak
2025-04-08T20:49:50.539Z,1744145390.539 [DataOverHttps](INFO): SBD MOMSN=24583043
2025-04-08T20:49:52.233Z,1744145392.233 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T20:49:52.234Z,1744145392.234 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T20:49:52.234Z,1744145392.234 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T20:52:40.231Z,1744145560.231 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter
2025-04-08T20:52:40.235Z,1744145560.235 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2025-04-08T20:52:40.356Z,1744145560.356 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-08T20:52:44.498Z,1744145564.498 [CommandExec](IMPORTANT): got command strobe off
2025-04-08T20:52:44.499Z,1744145564.499 [CommandExec](IMPORTANT): Deactivating strobe
2025-04-08T20:53:44.934Z,1744145624.934 [NAL9602](INFO): SBD MO Status=2, MOMSN=19220, MT Status=2, MTMSN=0
2025-04-08T20:53:44.935Z,1744145624.935 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:54:03.925Z,1744145643.925 [NAL9602](INFO): SBD MO Status=2, MOMSN=19220, MT Status=2, MTMSN=0
2025-04-08T20:54:03.926Z,1744145643.926 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:54:22.103Z,1744145662.103 [NAL9602](INFO): SBD MO Status=2, MOMSN=19220, MT Status=2, MTMSN=0
2025-04-08T20:54:22.104Z,1744145662.104 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T20:54:27.360Z,1744145667.360 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-04-08T20:54:52.821Z,1744145692.821 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-08T20:54:52.821Z,1744145692.821 [Default:CheckIn:C.Wait] Stopped
2025-04-08T20:54:52.821Z,1744145692.821 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T20:54:52.821Z,1744145692.821 [Default:CheckIn:D] Running Loop=1
2025-04-08T20:54:53.237Z,1744145693.237 [Default:CheckIn:D] Stopped
2025-04-08T20:54:53.237Z,1744145693.237 [Default:CheckIn:E] Running Loop=1
2025-04-08T20:54:53.623Z,1744145693.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  18.023645 min 
2025-04-08T20:54:53.623Z,1744145693.623 [Default:CheckIn:E] Stopped
2025-04-08T20:54:53.623Z,1744145693.623 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-08T20:54:53.623Z,1744145693.623 [Default:CheckIn] Stopped
2025-04-08T20:54:53.624Z,1744145693.624 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T20:54:53.624Z,1744145693.624 [Default:CheckIn](INFO): Running loop #4
2025-04-08T20:54:53.624Z,1744145693.624 [Default:CheckIn] Running Loop=4
2025-04-08T20:54:53.624Z,1744145693.624 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T20:54:53.624Z,1744145693.624 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T20:54:55.641Z,1744145695.641 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205430.00,A,3648.16985,N,12147.28092,W,1.050,37.56,080425,,,D*4B
2025-04-08T20:54:55.643Z,1744145695.643 [NAL9602](INFO): GPS fix at 20250408T205430: (36.802831, -121.788015)
2025-04-08T20:54:55.654Z,1744145695.654 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T20:54:55.654Z,1744145695.654 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T20:55:03.885Z,1744145703.885 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250408T203234/Courier0013.lzma
2025-04-08T20:55:04.887Z,1744145704.887 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0013.lzma.bak
2025-04-08T20:55:04.887Z,1744145704.887 [DataOverHttps](INFO): SBD MOMSN=24583157
2025-04-08T20:55:20.758Z,1744145720.758 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20250408T203234/Express0014.lzma
2025-04-08T20:55:21.759Z,1744145721.759 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0014.lzma.bak
2025-04-08T20:55:21.759Z,1744145721.759 [DataOverHttps](INFO): SBD MOMSN=24583160
2025-04-08T20:55:23.543Z,1744145723.543 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T20:55:23.543Z,1744145723.543 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T20:55:23.543Z,1744145723.543 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T20:55:28.386Z,1744145728.386 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-08T21:00:24.131Z,1744146024.131 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-08T21:00:24.131Z,1744146024.131 [Default:CheckIn:C.Wait] Stopped
2025-04-08T21:00:24.131Z,1744146024.131 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T21:00:24.131Z,1744146024.131 [Default:CheckIn:D] Running Loop=1
2025-04-08T21:00:24.522Z,1744146024.522 [Default:CheckIn:D] Stopped
2025-04-08T21:00:24.523Z,1744146024.523 [Default:CheckIn:E] Running Loop=1
2025-04-08T21:00:24.924Z,1744146024.924 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  23.545072 min 
2025-04-08T21:00:24.924Z,1744146024.924 [Default:CheckIn:E] Stopped
2025-04-08T21:00:24.925Z,1744146024.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-08T21:00:24.925Z,1744146024.925 [Default:CheckIn] Stopped
2025-04-08T21:00:24.925Z,1744146024.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T21:00:24.925Z,1744146024.925 [Default:CheckIn](INFO): Running loop #5
2025-04-08T21:00:24.925Z,1744146024.925 [Default:CheckIn] Running Loop=5
2025-04-08T21:00:24.925Z,1744146024.925 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T21:00:24.925Z,1744146024.925 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T21:00:26.942Z,1744146026.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210002.00,A,3648.16600,N,12147.28484,W,0.058,224.11,080425,,,D*71
2025-04-08T21:00:26.944Z,1744146026.944 [NAL9602](INFO): GPS fix at 20250408T210002: (36.802767, -121.788081)
2025-04-08T21:00:26.955Z,1744146026.955 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T21:00:26.955Z,1744146026.955 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T21:00:34.293Z,1744146034.293 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250408T203234/Courier0016.lzma
2025-04-08T21:00:35.295Z,1744146035.295 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0016.lzma.bak
2025-04-08T21:00:35.295Z,1744146035.295 [DataOverHttps](INFO): SBD MOMSN=24583258
2025-04-08T21:00:51.165Z,1744146051.165 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250408T203234/Express0017.lzma
2025-04-08T21:00:52.167Z,1744146052.167 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0017.lzma.bak
2025-04-08T21:00:52.167Z,1744146052.167 [DataOverHttps](INFO): SBD MOMSN=24583261
2025-04-08T21:00:54.024Z,1744146054.024 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T21:00:54.024Z,1744146054.024 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T21:00:54.024Z,1744146054.024 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T21:00:57.685Z,1744146057.685 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-04-08T21:00:57.757Z,1744146057.757 [NAL9602](ERROR): received: 
+CSQ:0

OK220, 2, 0, 0, 0

OK
2025-04-08T21:01:38.068Z,1744146098.068 [NAL9602](INFO): SBD MO Status=2, MOMSN=19220, MT Status=2, MTMSN=0
2025-04-08T21:01:38.068Z,1744146098.068 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T21:03:48.548Z,1744146228.548 [NAL9602](INFO): SBD MO Status=2, MOMSN=19220, MT Status=2, MTMSN=0
2025-04-08T21:03:48.548Z,1744146228.548 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T21:04:19.247Z,1744146259.247 [NAL9602](INFO): SBD MO Status=2, MOMSN=19220, MT Status=2, MTMSN=0
2025-04-08T21:04:19.248Z,1744146259.248 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T21:04:36.638Z,1744146276.638 [NAL9602](INFO): SBD MO Status=0, MOMSN=19220, MT Status=0, MTMSN=0
2025-04-08T21:04:36.639Z,1744146276.639 [NAL9602](INFO): No messages in MT queue
2025-04-08T21:05:07.346Z,1744146307.346 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-08T21:05:54.618Z,1744146354.618 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-08T21:05:54.618Z,1744146354.618 [Default:CheckIn:C.Wait] Stopped
2025-04-08T21:05:54.618Z,1744146354.618 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T21:05:54.618Z,1744146354.618 [Default:CheckIn:D] Running Loop=1
2025-04-08T21:05:55.026Z,1744146355.026 [Default:CheckIn:D] Stopped
2025-04-08T21:05:55.026Z,1744146355.026 [Default:CheckIn:E] Running Loop=1
2025-04-08T21:05:55.428Z,1744146355.428 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  29.053471 min 
2025-04-08T21:05:55.428Z,1744146355.428 [Default:CheckIn:E] Stopped
2025-04-08T21:05:55.429Z,1744146355.429 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-08T21:05:55.429Z,1744146355.429 [Default:CheckIn] Stopped
2025-04-08T21:05:55.429Z,1744146355.429 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T21:05:55.429Z,1744146355.429 [Default:CheckIn](INFO): Running loop #6
2025-04-08T21:05:55.429Z,1744146355.429 [Default:CheckIn] Running Loop=6
2025-04-08T21:05:55.429Z,1744146355.429 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T21:05:55.429Z,1744146355.429 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T21:05:57.439Z,1744146357.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210532.00,A,3648.16279,N,12147.28599,W,0.467,254.78,080425,,,A*75
2025-04-08T21:05:57.441Z,1744146357.441 [NAL9602](INFO): GPS fix at 20250408T210532: (36.802713, -121.788100)
2025-04-08T21:05:57.478Z,1744146357.478 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T21:05:57.478Z,1744146357.478 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T21:06:05.377Z,1744146365.377 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250408T203234/Courier0019.lzma
2025-04-08T21:06:06.379Z,1744146366.379 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0019.lzma.bak
2025-04-08T21:06:06.379Z,1744146366.379 [DataOverHttps](INFO): SBD MOMSN=24583277
2025-04-08T21:06:16.942Z,1744146376.942 [NAL9602](INFO): SBD MO Status=2, MOMSN=19221, MT Status=2, MTMSN=0
2025-04-08T21:06:16.942Z,1744146376.942 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T21:06:22.241Z,1744146382.241 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250408T203234/Express0020.lzma
2025-04-08T21:06:23.243Z,1744146383.243 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0020.lzma.bak
2025-04-08T21:06:23.243Z,1744146383.243 [DataOverHttps](INFO): SBD MOMSN=24583280
2025-04-08T21:06:25.053Z,1744146385.053 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T21:06:25.053Z,1744146385.053 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T21:06:25.053Z,1744146385.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T21:07:08.656Z,1744146428.656 [NAL9602](INFO): SBD MO Status=2, MOMSN=19221, MT Status=2, MTMSN=0
2025-04-08T21:07:08.656Z,1744146428.656 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T21:10:33.913Z,1744146633.913 [NAL9602](INFO): SBD MO Status=2, MOMSN=19221, MT Status=2, MTMSN=0
2025-04-08T21:10:33.914Z,1744146633.914 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T21:11:11.047Z,1744146671.047 [NAL9602](INFO): SBD MO Status=2, MOMSN=19221, MT Status=2, MTMSN=0
2025-04-08T21:11:11.047Z,1744146671.047 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-08T21:11:11.445Z,1744146671.445 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-04-08T21:11:21.687Z,1744146681.687 [CommandExec](IMPORTANT): got command maintain clear
2025-04-08T21:11:21.991Z,1744146681.991 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-08T21:11:25.599Z,1744146685.599 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-08T21:11:25.599Z,1744146685.599 [Default:CheckIn:C.Wait] Stopped
2025-04-08T21:11:25.599Z,1744146685.599 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T21:11:25.599Z,1744146685.599 [Default:CheckIn:D] Running Loop=1
2025-04-08T21:11:26.000Z,1744146686.000 [Default:CheckIn:D] Stopped
2025-04-08T21:11:26.000Z,1744146686.000 [Default:CheckIn:E] Running Loop=1
2025-04-08T21:11:26.411Z,1744146686.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  34.569698 min 
2025-04-08T21:11:26.411Z,1744146686.411 [Default:CheckIn:E] Stopped
2025-04-08T21:11:26.411Z,1744146686.411 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-08T21:11:26.411Z,1744146686.411 [Default:CheckIn] Stopped
2025-04-08T21:11:26.411Z,1744146686.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T21:11:26.411Z,1744146686.411 [Default:CheckIn](INFO): Running loop #7
2025-04-08T21:11:26.411Z,1744146686.411 [Default:CheckIn] Running Loop=7
2025-04-08T21:11:26.411Z,1744146686.411 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T21:11:26.412Z,1744146686.412 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T21:11:28.416Z,1744146688.416 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211103.00,A,3648.16526,N,12147.28273,W,0.136,28.50,080425,,,A*4E
2025-04-08T21:11:28.418Z,1744146688.418 [NAL9602](INFO): GPS fix at 20250408T211103: (36.802754, -121.788045)
2025-04-08T21:11:28.452Z,1744146688.452 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T21:11:28.453Z,1744146688.453 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T21:11:37.003Z,1744146697.003 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250408T203234/Courier0022.lzma
2025-04-08T21:11:37.135Z,1744146697.135 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0022.lzma.bak
2025-04-08T21:11:37.135Z,1744146697.135 [DataOverHttps](INFO): SBD MOMSN=24583339
2025-04-08T21:11:53.107Z,1744146713.107 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20250408T203234/Express0023.lzma
2025-04-08T21:11:54.107Z,1744146714.107 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0023.lzma.bak
2025-04-08T21:11:54.107Z,1744146714.107 [DataOverHttps](INFO): SBD MOMSN=24583342
2025-04-08T21:11:55.926Z,1744146715.926 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T21:11:55.926Z,1744146715.926 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T21:11:55.926Z,1744146715.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T21:12:00.347Z,1744146720.347 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-08T21:14:23.233Z,1744146863.233 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter
2025-04-08T21:14:23.234Z,1744146863.234 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2025-04-08T21:14:23.394Z,1744146863.394 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-08T21:16:37.291Z,1744146997.291 [CommandExec](IMPORTANT): got command maintain clear
2025-04-08T21:16:37.526Z,1744146997.526 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-08T21:16:56.497Z,1744147016.497 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-08T21:16:56.497Z,1744147016.497 [Default:CheckIn:C.Wait] Stopped
2025-04-08T21:16:56.497Z,1744147016.497 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T21:16:56.497Z,1744147016.497 [Default:CheckIn:D] Running Loop=1
2025-04-08T21:16:56.906Z,1744147016.906 [Default:CheckIn:D] Stopped
2025-04-08T21:16:56.906Z,1744147016.906 [Default:CheckIn:E] Running Loop=1
2025-04-08T21:16:57.315Z,1744147017.315 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  40.084798 min 
2025-04-08T21:16:57.315Z,1744147017.315 [Default:CheckIn:E] Stopped
2025-04-08T21:16:57.315Z,1744147017.315 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-08T21:16:57.315Z,1744147017.315 [Default:CheckIn] Stopped
2025-04-08T21:16:57.315Z,1744147017.315 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T21:16:57.315Z,1744147017.315 [Default:CheckIn](INFO): Running loop #8
2025-04-08T21:16:57.315Z,1744147017.315 [Default:CheckIn] Running Loop=8
2025-04-08T21:16:57.315Z,1744147017.315 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T21:16:57.315Z,1744147017.316 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T21:16:59.315Z,1744147019.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211634.00,A,3648.16366,N,12147.29087,W,0.253,37.46,080425,,,A*4E
2025-04-08T21:16:59.317Z,1744147019.317 [NAL9602](INFO): GPS fix at 20250408T211634: (36.802728, -121.788181)
2025-04-08T21:16:59.327Z,1744147019.327 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T21:16:59.327Z,1744147019.327 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T21:17:07.034Z,1744147027.034 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250408T203234/Courier0025.lzma
2025-04-08T21:17:08.035Z,1744147028.035 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0025.lzma.bak
2025-04-08T21:17:08.035Z,1744147028.035 [DataOverHttps](INFO): SBD MOMSN=24583359
2025-04-08T21:17:24.013Z,1744147044.013 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20250408T203234/Express0026.lzma
2025-04-08T21:17:25.016Z,1744147045.016 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0026.lzma.bak
2025-04-08T21:17:25.016Z,1744147045.016 [DataOverHttps](INFO): SBD MOMSN=24583362
2025-04-08T21:17:27.224Z,1744147047.224 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T21:17:27.224Z,1744147047.224 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T21:17:27.225Z,1744147047.225 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T21:17:49.408Z,1744147069.408 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-04-08T21:17:49.409Z,1744147069.409 [DropWeight] Hardware Fault, FailCount= 1
2025-04-08T21:17:49.409Z,1744147069.409 [DropWeight](ERROR): Hardware Fault
2025-04-08T21:17:49.488Z,1744147069.488 [CBIT](INFO): Critical error at 20250408T211749
2025-04-08T21:17:49.491Z,1744147069.491 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-04-08T21:17:49.491Z,1744147069.491 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-04-08T21:17:49.828Z,1744147069.828 [CBIT](INFO): Critical error at 20250408T211749
2025-04-08T21:18:04.667Z,1744147084.667 [CommandExec](IMPORTANT): got command get platform_mass_position
2025-04-08T21:18:04.668Z,1744147084.668 [CommandExec](IMPORTANT): platform_mass_position 0.012408 m
2025-04-08T21:18:31.308Z,1744147111.308 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 12.7 millimeter
2025-04-08T21:18:31.312Z,1744147111.312 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2025-04-08T21:18:31.470Z,1744147111.470 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-08T21:19:09.229Z,1744147149.229 [CommandExec](IMPORTANT): got command get platform_mass_position
2025-04-08T21:19:09.234Z,1744147149.234 [CommandExec](IMPORTANT): platform_mass_position 0.012700 m
2025-04-08T21:19:42.792Z,1744147182.792 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 12.9 millimeter
2025-04-08T21:20:15.070Z,1744147215.070 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 12.3 millimeter
2025-04-08T21:20:46.603Z,1744147246.603 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 12.1 millimeter
2025-04-08T21:21:22.271Z,1744147282.271 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 11 millimeter
2025-04-08T21:21:51.951Z,1744147311.951 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 9 millimeter
2025-04-08T21:22:01.506Z,1744147321.506 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-04-08T21:22:27.804Z,1744147347.804 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-08T21:22:27.805Z,1744147347.805 [Default:CheckIn:C.Wait] Stopped
2025-04-08T21:22:27.805Z,1744147347.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T21:22:27.809Z,1744147347.809 [Default:CheckIn:D] Running Loop=1
2025-04-08T21:22:28.194Z,1744147348.194 [Default:CheckIn:D] Stopped
2025-04-08T21:22:28.194Z,1744147348.194 [Default:CheckIn:E] Running Loop=1
2025-04-08T21:22:28.596Z,1744147348.596 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  45.606270 min 
2025-04-08T21:22:28.596Z,1744147348.596 [Default:CheckIn:E] Stopped
2025-04-08T21:22:28.597Z,1744147348.597 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-08T21:22:28.597Z,1744147348.597 [Default:CheckIn] Stopped
2025-04-08T21:22:28.601Z,1744147348.601 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T21:22:28.601Z,1744147348.601 [Default:CheckIn](INFO): Running loop #9
2025-04-08T21:22:28.601Z,1744147348.601 [Default:CheckIn] Running Loop=9
2025-04-08T21:22:28.601Z,1744147348.601 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-08T21:22:28.601Z,1744147348.601 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-08T21:22:28.776Z,1744147348.776 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 7 millimeter
2025-04-08T21:22:30.591Z,1744147350.591 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212205.00,A,3648.16723,N,12147.28526,W,0.097,59.62,080425,,,A*45
2025-04-08T21:22:30.593Z,1744147350.593 [NAL9602](INFO): GPS fix at 20250408T212205: (36.802787, -121.788088)
2025-04-08T21:22:30.628Z,1744147350.628 [Default:CheckIn:Read_GPS] Stopped
2025-04-08T21:22:30.628Z,1744147350.628 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-08T21:22:37.961Z,1744147357.961 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250408T203234/Courier0028.lzma
2025-04-08T21:22:38.963Z,1744147358.963 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Courier0028.lzma.bak
2025-04-08T21:22:38.963Z,1744147358.963 [DataOverHttps](INFO): SBD MOMSN=24583425
2025-04-08T21:22:52.520Z,1744147372.520 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-04-08T21:22:54.984Z,1744147374.984 [DataOverHttps](INFO): Sending 403 bytes from file Logs/20250408T203234/Express0029.lzma
2025-04-08T21:22:55.983Z,1744147375.983 [DataOverHttps](INFO): Moved sent file to Logs/20250408T203234/Express0029.lzma.bak
2025-04-08T21:22:55.984Z,1744147375.984 [DataOverHttps](INFO): SBD MOMSN=24583430
2025-04-08T21:22:58.269Z,1744147378.269 [Default:CheckIn:Read_Iridium] Stopped
2025-04-08T21:22:58.270Z,1744147378.270 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-08T21:22:58.270Z,1744147378.270 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-08T21:23:01.698Z,1744147381.698 [CommandExec](IMPORTANT): got command configSet DropWeight.loadAtStartup 0 bool persist
2025-04-08T21:23:01.698Z,1744147381.698 [CommandExec](IMPORTANT): configSet DropWeight.loadAtStartup requires a restart to take effect.
2025-04-08T21:23:01.708Z,1744147381.708 [CommandExec](IMPORTANT): got command restart application
2025-04-08T21:23:02.709Z,1744147382.709 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:02.709Z,1744147382.709 [CommandExec](INFO): Uninitializing the command executive.
2025-04-08T21:23:02.709Z,1744147382.709 [CommandExec](INFO): Uninitializing the command scheduler.
2025-04-08T21:23:02.710Z,1744147382.710 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:02.857Z,1744147382.857 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-04-08T21:23:02.857Z,1744147382.857 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-04-08T21:23:02.857Z,1744147382.857 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:02.858Z,1744147382.858 [NavChartDb](INFO): Join timeout helper Thread ID is 1237
2025-04-08T21:23:03.113Z,1744147383.113 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:03.114Z,1744147383.114 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:03.117Z,1744147383.117 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-04-08T21:23:03.117Z,1744147383.117 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:03.118Z,1744147383.118 [Radio_Surface](INFO): Join timeout helper Thread ID is 1238
2025-04-08T21:23:03.440Z,1744147383.440 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-08T21:23:03.505Z,1744147383.505 [Radio_Surface](INFO): Powering down
2025-04-08T21:23:03.506Z,1744147383.506 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:03.506Z,1744147383.506 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:03.509Z,1744147383.509 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-04-08T21:23:03.509Z,1744147383.509 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:03.510Z,1744147383.510 [Onboard](INFO): Join timeout helper Thread ID is 1239
2025-04-08T21:23:04.485Z,1744147384.485 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:04.485Z,1744147384.485 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:04.489Z,1744147384.489 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-04-08T21:23:04.489Z,1744147384.489 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:04.489Z,1744147384.489 [DataOverHttps](INFO): Join timeout helper Thread ID is 1240
2025-04-08T21:23:04.749Z,1744147384.749 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:04.752Z,1744147384.752 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:04.757Z,1744147384.757 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-04-08T21:23:04.757Z,1744147384.757 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:04.757Z,1744147384.757 [DAT](INFO): Join timeout helper Thread ID is 1241
2025-04-08T21:23:04.961Z,1744147384.961 [DAT](INFO): Powering down
2025-04-08T21:23:05.039Z,1744147385.039 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:05.039Z,1744147385.039 [DAT](INFO): Powering down
2025-04-08T21:23:05.040Z,1744147385.040 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.058Z,1744147385.058 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-04-08T21:23:05.058Z,1744147385.058 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.059Z,1744147385.059 [BackseatComponent](INFO): Join timeout helper Thread ID is 1242
2025-04-08T21:23:05.225Z,1744147385.225 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:05.225Z,1744147385.225 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.230Z,1744147385.230 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-04-08T21:23:05.230Z,1744147385.230 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.230Z,1744147385.230 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1243
2025-04-08T21:23:05.361Z,1744147385.361 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:05.361Z,1744147385.361 [WetLabsBB2FL](INFO): Powering down
2025-04-08T21:23:05.362Z,1744147385.362 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.378Z,1744147385.378 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-04-08T21:23:05.378Z,1744147385.378 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.378Z,1744147385.378 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1244
2025-04-08T21:23:05.649Z,1744147385.649 [CTD_Seabird](INFO): Powering down
2025-04-08T21:23:05.661Z,1744147385.661 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:05.661Z,1744147385.661 [CTD_Seabird](INFO): Powering down
2025-04-08T21:23:05.685Z,1744147385.685 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.687Z,1744147385.687 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-04-08T21:23:05.687Z,1744147385.687 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.687Z,1744147385.687 [logger](INFO): Join timeout helper Thread ID is 1245
2025-04-08T21:23:05.722Z,1744147385.722 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:05.722Z,1744147385.722 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.730Z,1744147385.730 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-04-08T21:23:05.730Z,1744147385.730 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.730Z,1744147385.730 [CommandLine](INFO): Join timeout helper Thread ID is 1246
2025-04-08T21:23:05.813Z,1744147385.813 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:05.813Z,1744147385.813 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.830Z,1744147385.830 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-04-08T21:23:05.830Z,1744147385.830 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.830Z,1744147385.830 [CommandExec](INFO): Join timeout helper Thread ID is 1247
2025-04-08T21:23:05.831Z,1744147385.831 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-04-08T21:23:05.831Z,1744147385.831 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:05.832Z,1744147385.832 [controlThread](INFO): Join timeout helper Thread ID is 1248
2025-04-08T21:23:05.833Z,1744147385.833 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-08T21:23:05.833Z,1744147385.833 [controlThread](DEBUG): Uninitializing ControlThread
2025-04-08T21:23:05.835Z,1744147385.835 [AHRS_M2](INFO): Powering down
2025-04-08T21:23:05.913Z,1744147385.913 [NAL9602](INFO): Powering down
2025-04-08T21:23:05.915Z,1744147385.915 [Sonardyne_Nano](INFO): Powering down
2025-04-08T21:23:06.121Z,1744147386.121 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-04-08T21:23:06.122Z,1744147386.122 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-04-08T21:23:06.122Z,1744147386.122 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-04-08T21:23:06.123Z,1744147386.123 [MissionManager](INFO): Uninitializing Mission Default
2025-04-08T21:23:06.123Z,1744147386.123 [Default] Stopped
2025-04-08T21:23:06.123Z,1744147386.123 [Default](DEBUG): Aggregate::uninitialize Default
2025-04-08T21:23:06.123Z,1744147386.123 [Default:B.GoToSurface] Stopped
2025-04-08T21:23:06.123Z,1744147386.123 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-08T21:23:06.123Z,1744147386.123 [Default:CheckIn] Stopped
2025-04-08T21:23:06.123Z,1744147386.123 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-08T21:23:06.123Z,1744147386.123 [Default:CheckIn:C.Wait] Stopped
2025-04-08T21:23:06.124Z,1744147386.124 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-08T21:23:06.127Z,1744147386.127 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-04-08T21:23:06.127Z,1744147386.127 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-04-08T21:23:06.127Z,1744147386.127 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-04-08T21:23:06.127Z,1744147386.127 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-04-08T21:23:06.128Z,1744147386.128 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-04-08T21:23:06.128Z,1744147386.128 [BuoyancyServo](INFO): Powering down
2025-04-08T21:23:06.141Z,1744147386.141 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-08T21:23:06.141Z,1744147386.141 [ElevatorServo](INFO): Powering down
2025-04-08T21:23:06.142Z,1744147386.142 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-08T21:23:06.142Z,1744147386.142 [MassServo](INFO): Powering down
2025-04-08T21:23:06.143Z,1744147386.143 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-04-08T21:23:06.143Z,1744147386.143 [RudderServo](INFO): Powering down
2025-04-08T21:23:06.144Z,1744147386.144 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-08T21:23:06.144Z,1744147386.144 [ThrusterHE](INFO): Powering down
2025-04-08T21:23:06.145Z,1744147386.145 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-04-08T21:23:06.145Z,1744147386.145 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-04-08T21:23:06.146Z,1744147386.146 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-04-08T21:23:06.146Z,1744147386.146 [CBIT](DEBUG): Powering off loads.
2025-04-08T21:23:06.157Z,1744147386.157 [CBIT](DEBUG): Disabling WDT.
2025-04-08T21:23:06.169Z,1744147386.169 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-04-08T21:23:06.169Z,1744147386.169 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-04-08T21:23:06.170Z,1744147386.170 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.192Z,1744147386.192 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-04-08T21:23:06.193Z,1744147386.193 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-04-08T21:23:06.208Z,1744147386.208 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.215Z,1744147386.215 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.248Z,1744147386.248 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.251Z,1744147386.251 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.258Z,1744147386.258 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.265Z,1744147386.265 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.307Z,1744147386.307 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.365Z,1744147386.365 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-08T21:23:06.440Z,1744147386.440 [logger ThreadHandler](INFO): Thread cancelled.