2026-03-13T18:37:58.884Z,1773427078.884 [Supervisor](DEBUG): Initializing supervisor.
2026-03-13T18:37:58.889Z,1773427078.889 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-03-13T18:37:58.889Z,1773427078.889 [SyncHandler](INFO): Protected caller Thread ID is 695
2026-03-13T18:37:58.890Z,1773427078.890 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-03-13T18:37:58.891Z,1773427078.891 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-03-13T18:37:58.891Z,1773427078.891 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 696
2026-03-13T18:37:58.895Z,1773427078.895 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-03-13T18:37:58.914Z,1773427078.914 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-03-13T18:37:58.915Z,1773427078.915 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-03-13T18:37:58.915Z,1773427078.915 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 697
2026-03-13T18:37:58.919Z,1773427078.919 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-03-13T18:37:58.920Z,1773427078.920 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-03-13T18:37:58.921Z,1773427078.921 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 698
2026-03-13T18:37:58.923Z,1773427078.923 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-03-13T18:37:58.924Z,1773427078.924 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-03-13T18:37:58.924Z,1773427078.924 [logger ThreadHandler](INFO): Protected caller Thread ID is 699
2026-03-13T18:37:58.927Z,1773427078.927 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-03-13T18:37:58.928Z,1773427078.928 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-03-13T18:37:58.930Z,1773427078.930 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-03-13T18:37:59.209Z,1773427079.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-03-13T18:37:59.209Z,1773427079.209 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-03-13T18:37:59.309Z,1773427079.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-03-13T18:37:59.310Z,1773427079.310 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-03-13T18:37:59.824Z,1773427079.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-03-13T18:37:59.824Z,1773427079.824 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-03-13T18:38:00.165Z,1773427080.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-03-13T18:38:00.166Z,1773427080.166 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-03-13T18:38:00.273Z,1773427080.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-03-13T18:38:00.273Z,1773427080.273 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-03-13T18:38:00.681Z,1773427080.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-03-13T18:38:00.682Z,1773427080.682 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-03-13T18:38:00.920Z,1773427080.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-03-13T18:38:00.921Z,1773427080.921 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-03-13T18:38:01.457Z,1773427081.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-03-13T18:38:01.458Z,1773427081.458 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-03-13T18:38:01.605Z,1773427081.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-03-13T18:38:01.605Z,1773427081.605 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-03-13T18:38:01.694Z,1773427081.694 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-03-13T18:38:02.530Z,1773427082.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-03-13T18:38:02.530Z,1773427082.530 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-03-13T18:38:02.920Z,1773427082.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-03-13T18:38:02.921Z,1773427082.921 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-03-13T18:38:03.158Z,1773427083.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-03-13T18:38:03.159Z,1773427083.159 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-03-13T18:38:03.608Z,1773427083.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-03-13T18:38:03.608Z,1773427083.608 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-03-13T18:38:03.962Z,1773427083.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-03-13T18:38:03.963Z,1773427083.963 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-03-13T18:38:04.349Z,1773427084.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-03-13T18:38:04.350Z,1773427084.350 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2026-03-13T18:38:04.351Z,1773427084.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2026-03-13T18:38:04.447Z,1773427084.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2026-03-13T18:38:04.557Z,1773427084.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2026-03-13T18:38:04.650Z,1773427084.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2026-03-13T18:38:04.757Z,1773427084.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2026-03-13T18:38:04.859Z,1773427084.859 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2026-03-13T18:38:04.980Z,1773427084.980 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2026-03-13T18:38:05.139Z,1773427085.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2026-03-13T18:38:05.285Z,1773427085.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2026-03-13T18:38:05.381Z,1773427085.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2026-03-13T18:38:05.657Z,1773427085.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-03-13T18:38:05.658Z,1773427085.658 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2026-03-13T18:38:05.659Z,1773427085.659 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-03-13T18:38:05.666Z,1773427085.666 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-03-13T18:38:05.722Z,1773427085.722 [VerticalControl](DEBUG): Construct VerticalControl.
2026-03-13T18:38:05.781Z,1773427085.781 [VerticalControl] Loaded
2026-03-13T18:38:05.781Z,1773427085.781 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-03-13T18:38:05.784Z,1773427085.784 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-03-13T18:38:05.830Z,1773427085.830 [HorizontalControl] Loaded
2026-03-13T18:38:05.831Z,1773427085.831 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-03-13T18:38:05.833Z,1773427085.833 [SpeedControl](DEBUG): Construct SpeedControl.
2026-03-13T18:38:05.836Z,1773427085.836 [SpeedControl] Loaded
2026-03-13T18:38:05.836Z,1773427085.836 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-03-13T18:38:05.839Z,1773427085.839 [LoopControl](DEBUG): Construct LoopControl.
2026-03-13T18:38:05.839Z,1773427085.839 [LoopControl] Loaded
2026-03-13T18:38:05.840Z,1773427085.840 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-03-13T18:38:05.840Z,1773427085.840 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-03-13T18:38:05.840Z,1773427085.840 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-03-13T18:38:05.851Z,1773427085.851 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-03-13T18:38:05.851Z,1773427085.851 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-03-13T18:38:06.034Z,1773427086.034 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-03-13T18:38:06.034Z,1773427086.034 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-03-13T18:38:06.199Z,1773427086.199 [BuoyancyServo] Loaded
2026-03-13T18:38:06.199Z,1773427086.199 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2026-03-13T18:38:06.222Z,1773427086.222 [ElevatorServo] Loaded
2026-03-13T18:38:06.222Z,1773427086.222 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-03-13T18:38:06.243Z,1773427086.243 [MassServo] Loaded
2026-03-13T18:38:06.243Z,1773427086.243 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2026-03-13T18:38:06.263Z,1773427086.263 [RudderServo] Loaded
2026-03-13T18:38:06.263Z,1773427086.263 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-03-13T18:38:06.279Z,1773427086.279 [ThrusterHE] Loaded
2026-03-13T18:38:06.280Z,1773427086.280 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-03-13T18:38:06.280Z,1773427086.280 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-03-13T18:38:06.281Z,1773427086.281 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-03-13T18:38:06.395Z,1773427086.395 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-03-13T18:38:06.395Z,1773427086.395 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-03-13T18:38:06.408Z,1773427086.408 [NavChart] Loaded
2026-03-13T18:38:06.409Z,1773427086.409 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-03-13T18:38:06.414Z,1773427086.414 [UniversalFixResidualReporter] Loaded
2026-03-13T18:38:06.414Z,1773427086.414 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-03-13T18:38:06.415Z,1773427086.415 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-03-13T18:38:06.415Z,1773427086.415 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-03-13T18:38:06.477Z,1773427086.477 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-03-13T18:38:06.477Z,1773427086.477 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-03-13T18:38:06.969Z,1773427086.969 [AHRS_M2] Loaded
2026-03-13T18:38:06.969Z,1773427086.969 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2026-03-13T18:38:07.807Z,1773427087.807 [BPC1] Loaded
2026-03-13T18:38:07.807Z,1773427087.807 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-03-13T18:38:07.907Z,1773427087.907 [DAT] Loaded
2026-03-13T18:38:07.907Z,1773427087.907 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2026-03-13T18:38:07.909Z,1773427087.909 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094D4E0
2026-03-13T18:38:07.909Z,1773427087.909 [DAT ThreadHandler](INFO): Protected caller Thread ID is 781
2026-03-13T18:38:07.982Z,1773427087.982 [DataOverHttps] Loaded
2026-03-13T18:38:07.982Z,1773427087.982 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-03-13T18:38:07.983Z,1773427087.983 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097D4E0
2026-03-13T18:38:07.984Z,1773427087.984 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 782
2026-03-13T18:38:08.007Z,1773427088.007 [Depth_Keller] Loaded
2026-03-13T18:38:08.007Z,1773427088.007 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-03-13T18:38:08.012Z,1773427088.012 [DropWeight] Loaded
2026-03-13T18:38:08.013Z,1773427088.013 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2026-03-13T18:38:08.077Z,1773427088.077 [NAL9602] Loaded
2026-03-13T18:38:08.077Z,1773427088.077 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-03-13T18:38:08.107Z,1773427088.107 [Onboard] Loaded
2026-03-13T18:38:08.107Z,1773427088.107 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-03-13T18:38:08.108Z,1773427088.108 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AD4E0
2026-03-13T18:38:08.108Z,1773427088.108 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 783
2026-03-13T18:38:08.122Z,1773427088.122 [Power24vConverter] Loaded
2026-03-13T18:38:08.123Z,1773427088.123 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-03-13T18:38:08.139Z,1773427088.139 [Radio_Surface] Loaded
2026-03-13T18:38:08.139Z,1773427088.139 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-03-13T18:38:08.140Z,1773427088.140 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2026-03-13T18:38:08.141Z,1773427088.141 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 784
2026-03-13T18:38:08.156Z,1773427088.156 [Sonardyne_Nano] Loaded
2026-03-13T18:38:08.156Z,1773427088.156 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-03-13T18:38:08.157Z,1773427088.157 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-03-13T18:38:08.157Z,1773427088.157 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-03-13T18:38:08.383Z,1773427088.383 [DepthRateCalculator] Loaded
2026-03-13T18:38:08.383Z,1773427088.383 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-03-13T18:38:08.403Z,1773427088.403 [PitchRateCalculator] Loaded
2026-03-13T18:38:08.404Z,1773427088.404 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-03-13T18:38:08.412Z,1773427088.412 [SpeedCalculator] Loaded
2026-03-13T18:38:08.413Z,1773427088.413 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-03-13T18:38:08.417Z,1773427088.417 [YawRateCalculator] Loaded
2026-03-13T18:38:08.417Z,1773427088.417 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-03-13T18:38:08.436Z,1773427088.436 [ElevatorOffsetCalculator] Loaded
2026-03-13T18:38:08.436Z,1773427088.436 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-03-13T18:38:08.436Z,1773427088.436 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-03-13T18:38:08.437Z,1773427088.437 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-03-13T18:38:08.829Z,1773427088.829 [CANONSampler] Loaded
2026-03-13T18:38:08.830Z,1773427088.830 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2026-03-13T18:38:08.891Z,1773427088.891 [CTD_Seabird] Loaded
2026-03-13T18:38:08.891Z,1773427088.891 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-03-13T18:38:08.892Z,1773427088.892 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A9B4E0
2026-03-13T18:38:08.893Z,1773427088.893 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 785
2026-03-13T18:38:08.915Z,1773427088.915 [PAR_Licor] Loaded
2026-03-13T18:38:08.915Z,1773427088.915 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-03-13T18:38:08.959Z,1773427088.959 [WetLabsBB2FL] Loaded
2026-03-13T18:38:08.959Z,1773427088.959 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-03-13T18:38:08.960Z,1773427088.960 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0
2026-03-13T18:38:08.960Z,1773427088.960 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 786
2026-03-13T18:38:08.961Z,1773427088.961 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-03-13T18:38:08.962Z,1773427088.962 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-03-13T18:38:08.992Z,1773427088.992 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-03-13T18:38:08.992Z,1773427088.992 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-03-13T18:38:09.808Z,1773427089.808 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-03-13T18:38:09.809Z,1773427089.809 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-03-13T18:38:09.975Z,1773427089.975 [SBIT](DEBUG): Construct Startup Built In Test.
2026-03-13T18:38:09.984Z,1773427089.984 [SBIT] Loaded
2026-03-13T18:38:09.984Z,1773427089.984 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-03-13T18:38:09.987Z,1773427089.987 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-03-13T18:38:09.999Z,1773427089.999 [IBIT] Loaded
2026-03-13T18:38:09.000Z,1773427090.000 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-03-13T18:38:10.005Z,1773427090.005 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-03-13T18:38:10.083Z,1773427090.083 [CBIT] Loaded
2026-03-13T18:38:10.083Z,1773427090.083 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-03-13T18:38:10.115Z,1773427090.115 [GFScanner] Loaded
2026-03-13T18:38:10.115Z,1773427090.115 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-03-13T18:38:10.116Z,1773427090.116 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-03-13T18:38:10.116Z,1773427090.116 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-03-13T18:38:10.258Z,1773427090.258 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-03-13T18:38:10.264Z,1773427090.264 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-03-13T18:38:10.267Z,1773427090.267 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-03-13T18:38:10.278Z,1773427090.278 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-03-13T18:38:10.279Z,1773427090.279 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2026-03-13T18:38:10.279Z,1773427090.279 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 787
2026-03-13T18:38:10.283Z,1773427090.283 [Supervisor](INFO): Main Thread ID is 537
2026-03-13T18:38:10.283Z,1773427090.283 [Supervisor](DEBUG): Running supervisor.
2026-03-13T18:38:10.284Z,1773427090.284 [CommandExec ThreadHandler](INFO): Handler Thread ID is 788
2026-03-13T18:38:10.285Z,1773427090.285 [CommandExec](INFO): Initializing the command executive.
2026-03-13T18:38:10.286Z,1773427090.286 [CommandLine ThreadHandler](INFO): Handler Thread ID is 789
2026-03-13T18:38:10.289Z,1773427090.289 [controlThread ThreadHandler](INFO): Handler Thread ID is 790
2026-03-13T18:38:10.289Z,1773427090.289 [controlThread](DEBUG): Initializing ControlThread
2026-03-13T18:38:10.290Z,1773427090.290 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-03-13T18:38:10.292Z,1773427090.292 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-03-13T18:38:10.292Z,1773427090.292 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-03-13T18:38:10.293Z,1773427090.293 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-03-13T18:38:10.295Z,1773427090.295 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-03-13T18:38:10.295Z,1773427090.295 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-03-13T18:38:10.298Z,1773427090.298 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-03-13T18:38:10.299Z,1773427090.299 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-03-13T18:38:10.299Z,1773427090.299 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-03-13T18:38:10.299Z,1773427090.299 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-03-13T18:38:10.300Z,1773427090.300 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-03-13T18:38:10.304Z,1773427090.304 [SBIT](INFO): Initialize SBIT Component.
2026-03-13T18:38:10.305Z,1773427090.305 [SBIT](IMPORTANT): git: 2026-02-09
2026-03-13T18:38:10.305Z,1773427090.305 [SBIT](INFO): git hash: 56326f8721d2fb7c55295416e529d5a8c354a561
2026-03-13T18:38:10.305Z,1773427090.305 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-03-13T18:38:10.306Z,1773427090.306 [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
2026-03-13T18:38:10.307Z,1773427090.307 [SBIT](INFO): Beginning SBIT in 36.000000 seconds.
2026-03-13T18:38:10.308Z,1773427090.308 [IBIT](INFO): Initialize IBIT Component.
2026-03-13T18:38:10.309Z,1773427090.309 [CBIT](DEBUG): Initialize CBIT Component.
2026-03-13T18:38:10.310Z,1773427090.310 [logger ThreadHandler](INFO): Handler Thread ID is 792
2026-03-13T18:38:10.320Z,1773427090.320 [CBIT](DEBUG): Initialized mux pins.
2026-03-13T18:38:10.320Z,1773427090.320 [CBIT](DEBUG): Initializing the watchdog timer.
2026-03-13T18:38:10.328Z,1773427090.328 [DAT ThreadHandler](INFO): Handler Thread ID is 793
2026-03-13T18:38:10.329Z,1773427090.329 [DAT](INFO): Powering up
2026-03-13T18:38:10.329Z,1773427090.329 [DAT](DEBUG): Initializing DAT.
2026-03-13T18:38:10.333Z,1773427090.333 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 797
2026-03-13T18:38:10.335Z,1773427090.335 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-03-13T18:38:10.344Z,1773427090.344 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-03-13T18:38:10.344Z,1773427090.344 [CBIT](DEBUG): Initializing heartbeat.
2026-03-13T18:38:10.345Z,1773427090.345 [Onboard ThreadHandler](INFO): Handler Thread ID is 798
2026-03-13T18:38:10.365Z,1773427090.365 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 799
2026-03-13T18:38:10.380Z,1773427090.380 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 800
2026-03-13T18:38:10.381Z,1773427090.381 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-13T18:38:10.385Z,1773427090.385 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 803
2026-03-13T18:38:10.389Z,1773427090.389 [WetLabsBB2FL](INFO): Powering up
2026-03-13T18:38:10.390Z,1773427090.390 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 805
2026-03-13T18:38:10.393Z,1773427090.393 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2026-03-13T18:38:10.393Z,1773427090.393 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2026-03-13T18:38:10.393Z,1773427090.393 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2026-03-13T18:38:10.394Z,1773427090.394 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2026-03-13T18:38:10.394Z,1773427090.394 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2026-03-13T18:38:10.394Z,1773427090.394 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2026-03-13T18:38:10.394Z,1773427090.394 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2026-03-13T18:38:10.394Z,1773427090.394 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2026-03-13T18:38:10.416Z,1773427090.416 [CBIT](DEBUG): Deactivating emergency mode.
2026-03-13T18:38:10.456Z,1773427090.456 [CBIT](DEBUG): Backplane powered.
2026-03-13T18:38:10.456Z,1773427090.456 [GFScanner](DEBUG): Initializing GFScanner
2026-03-13T18:38:10.456Z,1773427090.456 [GFScanner](DEBUG): Deactivating GF circuits.
2026-03-13T18:38:10.461Z,1773427090.461 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-03-13T18:38:10.462Z,1773427090.462 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-03-13T18:38:10.462Z,1773427090.462 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-03-13T18:38:10.463Z,1773427090.463 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-03-13T18:38:10.528Z,1773427090.528 [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>

2026-03-13T18:38:10.544Z,1773427090.544 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-13T18:38:10.598Z,1773427090.598 [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
    }
  }
}


2026-03-13T18:38:10.598Z,1773427090.598 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-03-13T18:38:10.598Z,1773427090.598 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-03-13T18:38:10.599Z,1773427090.599 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-03-13T18:38:10.768Z,1773427090.768 [Radio_Surface](INFO): Powering up
2026-03-13T18:38:10.919Z,1773427090.919 [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>

2026-03-13T18:38:10.942Z,1773427090.942 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-03-13T18:38:10.943Z,1773427090.943 [Default:A.Wait](DEBUG): Construct Wait.
2026-03-13T18:38:10.961Z,1773427090.961 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-13T18:38:10.987Z,1773427090.987 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-03-13T18:38:11.014Z,1773427091.014 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-03-13T18:38:11.036Z,1773427091.036 [Default:E.Execute](DEBUG): Construct Execute.
2026-03-13T18:38:11.039Z,1773427091.039 [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"
  }
}


2026-03-13T18:38:11.043Z,1773427091.043 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2026-03-13T18:38:11.056Z,1773427091.056 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2026-03-13T18:38:11.112Z,1773427091.112 [Depth_Keller](INFO): Initializing.
2026-03-13T18:38:11.114Z,1773427091.114 [Power24vConverter](INFO): Powering up.
2026-03-13T18:38:11.114Z,1773427091.114 [Sonardyne_Nano](INFO): Initializing.
2026-03-13T18:38:11.132Z,1773427091.132 [CANONSampler](INFO): Powering down
2026-03-13T18:38:11.160Z,1773427091.160 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-03-13T18:38:11.186Z,1773427091.186 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2026-03-13T18:38:11.192Z,1773427091.192 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2026-03-13T18:38:11.193Z,1773427091.193 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-03-13T18:38:11.200Z,1773427091.200 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-03-13T18:38:11.201Z,1773427091.201 [MassServo](DEBUG): Initializing EZServoServo.
2026-03-13T18:38:11.208Z,1773427091.208 [MassServo](DEBUG): Initializing MassServo.
2026-03-13T18:38:11.209Z,1773427091.209 [RudderServo](DEBUG): Initializing EZServoServo.
2026-03-13T18:38:11.216Z,1773427091.216 [RudderServo](DEBUG): Initializing RudderServo.
2026-03-13T18:38:11.217Z,1773427091.217 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-13T18:38:11.224Z,1773427091.224 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-13T18:38:12.000Z,1773427093.000 [WetLabsBB2FL](INFO): Powering down
2026-03-13T18:38:13.074Z,1773427093.074 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2026-03-13T18:38:14.361Z,1773427094.361 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2026-03-13T18:38:16.949Z,1773427096.949 [ThrusterHE](ERROR): Zero Speed Commanded.
2026-03-13T18:38:22.173Z,1773427102.173 [DAT](INFO): DAT read: 

2026-03-13T18:38:22.174Z,1773427102.174 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2026-03-13T18:38:23.689Z,1773427103.689 [DAT](INFO): DAT read: MF Frequency Band 5120 bandwidth

2026-03-13T18:38:23.691Z,1773427103.691 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.19.1

2026-03-13T18:38:23.692Z,1773427103.692 [DAT](INFO): DAT read: Mar 13 2026 18:38:19 

2026-03-13T18:38:24.441Z,1773427104.441 [DAT](INFO): DAT read: Features enabled [Bearing]

2026-03-13T18:38:24.442Z,1773427104.442 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2026-03-13T18:38:24.443Z,1773427104.443 [DAT](INFO): commRate: 1200
2026-03-13T18:38:26.508Z,1773427106.508 [DAT](INFO): entering command mode
2026-03-13T18:38:26.708Z,1773427106.708 [DAT](INFO): DAT read: 

2026-03-13T18:38:26.709Z,1773427106.709 [DAT](INFO): DAT read: user:1>

2026-03-13T18:38:26.709Z,1773427106.709 [DAT](INFO): setting verbose to 3
2026-03-13T18:38:26.961Z,1773427106.961 [DAT](INFO): DAT read: user:1>

2026-03-13T18:38:26.962Z,1773427106.962 [DAT](INFO): DAT read: Verbose         | 3           

2026-03-13T18:38:26.962Z,1773427106.962 [DAT](INFO): set verbose to 3
2026-03-13T18:38:26.962Z,1773427106.962 [DAT](INFO): setting DatVerbose to 27440
2026-03-13T18:38:27.213Z,1773427107.213 [DAT](INFO): DAT read: user:2>

2026-03-13T18:38:27.213Z,1773427107.213 [DAT](INFO): DAT read: DatVerbose      | 27440       

2026-03-13T18:38:27.214Z,1773427107.214 [DAT](INFO): set DatVerbose to 27440
2026-03-13T18:38:27.214Z,1773427107.214 [DAT](INFO): setting transmit power to 8
2026-03-13T18:38:27.465Z,1773427107.465 [DAT](INFO): DAT read: user:3>

2026-03-13T18:38:27.465Z,1773427107.465 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2026-03-13T18:38:27.466Z,1773427107.466 [DAT](INFO): set transmit power to 8
2026-03-13T18:38:27.466Z,1773427107.466 [DAT](INFO): setting local address to 8
2026-03-13T18:38:27.717Z,1773427107.717 [DAT](INFO): DAT read: user:4>

2026-03-13T18:38:27.717Z,1773427107.717 [DAT](INFO): DAT read: LocalAddr       | 8           

2026-03-13T18:38:27.718Z,1773427107.718 [DAT](INFO): set local address to 8
2026-03-13T18:38:27.719Z,1773427107.719 [DAT](INFO): Setting time to: 18:38:27 And date to:3/13/2026
2026-03-13T18:38:27.969Z,1773427107.969 [DAT](INFO): DAT read: user:5>

2026-03-13T18:38:27.969Z,1773427107.969 [DAT](INFO): DAT read: Fri Mar 13, 2026  18:38:27  

2026-03-13T18:38:27.970Z,1773427107.970 [DAT](INFO): Local DAT time set to Fri Mar 13, 2026  18:38:27  

2026-03-13T18:38:33.521Z,1773427113.521 [CommandExec](IMPORTANT): got command strobe off
2026-03-13T18:38:33.521Z,1773427113.521 [CommandExec](IMPORTANT): Deactivating strobe
2026-03-13T18:38:38.129Z,1773427118.129 [NAL9602](INFO): Powering up NAL9602
2026-03-13T18:38:47.062Z,1773427127.062 [SBIT](IMPORTANT): Beginning Startup BIT
2026-03-13T18:38:47.070Z,1773427127.070 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-13T18:38:49.110Z,1773427129.110 [NAL9602](INFO): NAL9602 initialized
2026-03-13T18:38:51.620Z,1773427131.620 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006920
CHAN A1 (24V): 0.008743
CHAN A2 (12V): 0.001528
CHAN A3 (5V): -0.000771
CHAN B0 (3.3V): 0.000815
CHAN B1 (3.15aV): 0.000355
CHAN B2 (3.15bV): 0.000192
CHAN B3 (GND): 0.000258
OPEN: 0.006532
Full Scale: +/- 1 mA
2026-03-13T18:39:32.910Z,1773427172.910 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 100
2026-03-13T18:39:32.910Z,1773427172.910 [CommandExec](FAULT): Incomplete syntax. Try: help maintain
2026-03-13T18:39:41.277Z,1773427181.277 [SBIT](IMPORTANT): SBIT PASSED
2026-03-13T18:39:41.277Z,1773427181.277 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2026-03-13T18:39:41.278Z,1773427181.278 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count;

2026-03-13T18:39:41.278Z,1773427181.278 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;

2026-03-13T18:39:41.278Z,1773427181.278 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;

2026-03-13T18:39:41.278Z,1773427181.278 [SBIT](IMPORTANT): Express linearApproximation PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;

2026-03-13T18:39:41.279Z,1773427181.279 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=268.567066 cubic_centimeter;

2026-03-13T18:39:41.279Z,1773427181.279 [SBIT](IMPORTANT): VerticalControl.massDefault=4.114080 millimeter;

2026-03-13T18:39:41.701Z,1773427181.701 [MissionManager](IMPORTANT): Started mission Startup
2026-03-13T18:39:41.701Z,1773427181.701 [Startup] Running Loop=1
2026-03-13T18:39:41.701Z,1773427181.701 [Startup](DEBUG): Aggregate::initialize Startup
2026-03-13T18:39:41.701Z,1773427181.701 [Startup:A.GoToSurface] Running Loop=1
2026-03-13T18:39:41.701Z,1773427181.701 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-13T18:39:41.702Z,1773427181.702 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-13T18:39:41.703Z,1773427181.703 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-13T18:39:41.703Z,1773427181.703 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-13T18:39:41.703Z,1773427181.703 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-13T18:39:41.708Z,1773427181.708 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-13T18:39:41.708Z,1773427181.708 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-13T18:39:41.709Z,1773427181.709 [Startup:StartupSatComms] Running Loop=1
2026-03-13T18:39:41.710Z,1773427181.710 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2026-03-13T18:39:41.710Z,1773427181.710 [Startup:StartupSatComms:A] Running Loop=1
2026-03-13T18:39:42.074Z,1773427182.074 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2026-03-13T18:39:42.575Z,1773427182.575 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 100 revolution_per_minute
2026-03-13T18:39:42.579Z,1773427182.579 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2026-03-13T18:39:42.912Z,1773427182.912 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2026-03-13T18:40:16.721Z,1773427216.721 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 200 revolution_per_minute
2026-03-13T18:40:29.493Z,1773427229.493 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute
2026-03-13T18:40:38.276Z,1773427238.276 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:38.692Z,1773427238.692 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:39.104Z,1773427239.104 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:39.504Z,1773427239.504 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:39.904Z,1773427239.904 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:40.308Z,1773427240.308 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:40.675Z,1773427240.675 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2026-03-13T18:40:40.675Z,1773427240.675 [ThrusterHE](INFO): Powering down
2026-03-13T18:40:41.504Z,1773427241.504 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-13T18:40:41.625Z,1773427241.625 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-13T18:40:41.878Z,1773427241.878 [Startup:StartupSatComms:A](INFO): Timed out from 2026-03-13T18:39:41.7Z
2026-03-13T18:40:41.878Z,1773427241.878 [Startup:StartupSatComms:A] Stopped
2026-03-13T18:40:41.878Z,1773427241.878 [Startup:StartupSatComms:B] Running Loop=1
2026-03-13T18:40:42.290Z,1773427242.290 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-13T18:40:44.761Z,1773427244.761 [CommandExec](IMPORTANT): got command failComponent
2026-03-13T18:40:44.761Z,1773427244.761 [CommandExec](IMPORTANT): Failed components:
2026-03-13T18:40:44.762Z,1773427244.762 [CommandExec](IMPORTANT): No failed Components.
2026-03-13T18:40:53.342Z,1773427253.342 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004546
2026-03-13T18:40:54.901Z,1773427254.901 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:55.292Z,1773427255.292 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:56.948Z,1773427256.948 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:57.364Z,1773427257.364 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:57.771Z,1773427257.771 [ThrusterHE](IMPORTANT): Status lock Detected.
2026-03-13T18:40:58.111Z,1773427258.111 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2026-03-13T18:40:58.111Z,1773427258.111 [ThrusterHE](INFO): Powering down
2026-03-13T18:40:58.926Z,1773427258.926 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-13T18:40:59.041Z,1773427259.041 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-13T18:41:01.715Z,1773427261.715 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260313T182249/Courier0007.lzma
2026-03-13T18:41:02.718Z,1773427262.718 [DataOverHttps](INFO): Moved sent file to Logs/20260313T182249/Courier0007.lzma.bak
2026-03-13T18:41:02.718Z,1773427262.718 [DataOverHttps](INFO): SBD MOMSN=26734702
2026-03-13T18:41:10.505Z,1773427270.505 [CommandExec](IMPORTANT): got command failComponent
2026-03-13T18:41:10.505Z,1773427270.505 [CommandExec](IMPORTANT): Failed components:
2026-03-13T18:41:10.506Z,1773427270.506 [CommandExec](IMPORTANT): No failed Components.
2026-03-13T18:41:10.581Z,1773427270.581 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2026-03-13T18:41:10.582Z,1773427270.582 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-03-13T18:41:10.663Z,1773427270.663 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-03-13T18:41:11.107Z,1773427271.107 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-03-13T18:41:11.107Z,1773427271.107 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2026-03-13T18:41:18.799Z,1773427278.799 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20260313T183758/Courier0000.lzma
2026-03-13T18:41:19.802Z,1773427279.802 [DataOverHttps](INFO): Moved sent file to Logs/20260313T183758/Courier0000.lzma.bak
2026-03-13T18:41:19.802Z,1773427279.802 [DataOverHttps](INFO): SBD MOMSN=26734711
2026-03-13T18:41:20.702Z,1773427280.702 [CommandExec](IMPORTANT): got command maintain clear
2026-03-13T18:41:20.732Z,1773427280.732 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2026-03-13T18:41:28.205Z,1773427288.205 [CommandExec](IMPORTANT): got command restart application
2026-03-13T18:41:29.208Z,1773427289.208 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2026-03-13T18:41:29.208Z,1773427289.208 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:29.208Z,1773427289.208 [CommandExec](INFO): Uninitializing the command executive.
2026-03-13T18:41:29.208Z,1773427289.208 [CommandExec](INFO): Uninitializing the command scheduler.
2026-03-13T18:41:29.209Z,1773427289.209 [CommandExec ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:29.232Z,1773427289.232 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2026-03-13T18:41:29.232Z,1773427289.232 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2026-03-13T18:41:29.233Z,1773427289.233 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:29.233Z,1773427289.233 [NavChartDb](INFO): Join timeout helper Thread ID is 831
2026-03-13T18:41:29.248Z,1773427289.248 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:29.249Z,1773427289.249 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:29.252Z,1773427289.252 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2026-03-13T18:41:29.252Z,1773427289.252 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:29.253Z,1773427289.253 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 832
2026-03-13T18:41:29.588Z,1773427289.588 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:29.588Z,1773427289.588 [WetLabsBB2FL](INFO): Powering down
2026-03-13T18:41:29.589Z,1773427289.589 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:29.608Z,1773427289.608 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2026-03-13T18:41:29.609Z,1773427289.609 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:29.609Z,1773427289.609 [CTD_Seabird](INFO): Join timeout helper Thread ID is 833
2026-03-13T18:41:30.232Z,1773427290.232 [CTD_Seabird](INFO): Powering down
2026-03-13T18:41:30.244Z,1773427290.244 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:30.244Z,1773427290.244 [CTD_Seabird](INFO): Powering down
2026-03-13T18:41:30.256Z,1773427290.256 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:30.273Z,1773427290.273 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2026-03-13T18:41:30.273Z,1773427290.273 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:30.273Z,1773427290.273 [Radio_Surface](INFO): Join timeout helper Thread ID is 835
2026-03-13T18:41:30.473Z,1773427290.473 [Radio_Surface](INFO): Powering down
2026-03-13T18:41:30.477Z,1773427290.477 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:30.478Z,1773427290.478 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:30.492Z,1773427290.492 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2026-03-13T18:41:30.492Z,1773427290.492 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:30.492Z,1773427290.492 [Onboard](INFO): Join timeout helper Thread ID is 836
2026-03-13T18:41:30.504Z,1773427290.504 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:30.504Z,1773427290.504 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:30.513Z,1773427290.513 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2026-03-13T18:41:30.513Z,1773427290.513 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:30.513Z,1773427290.513 [DataOverHttps](INFO): Join timeout helper Thread ID is 837
2026-03-13T18:41:31.124Z,1773427291.124 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:31.125Z,1773427291.125 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.141Z,1773427291.141 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2026-03-13T18:41:31.141Z,1773427291.141 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.141Z,1773427291.141 [DAT](INFO): Join timeout helper Thread ID is 838
2026-03-13T18:41:31.252Z,1773427291.252 [DAT](INFO): Powering down
2026-03-13T18:41:31.325Z,1773427291.325 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:31.325Z,1773427291.325 [DAT](INFO): Powering down
2026-03-13T18:41:31.326Z,1773427291.326 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.341Z,1773427291.341 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2026-03-13T18:41:31.341Z,1773427291.341 [logger ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.341Z,1773427291.341 [logger](INFO): Join timeout helper Thread ID is 839
2026-03-13T18:41:31.352Z,1773427291.352 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:31.353Z,1773427291.353 [logger ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.361Z,1773427291.361 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2026-03-13T18:41:31.361Z,1773427291.361 [CommandLine ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.361Z,1773427291.361 [CommandLine](INFO): Join timeout helper Thread ID is 840
2026-03-13T18:41:31.440Z,1773427291.440 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:31.440Z,1773427291.440 [CommandLine ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.460Z,1773427291.460 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2026-03-13T18:41:31.461Z,1773427291.461 [CommandExec ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.461Z,1773427291.461 [CommandExec](INFO): Join timeout helper Thread ID is 841
2026-03-13T18:41:31.462Z,1773427291.462 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2026-03-13T18:41:31.462Z,1773427291.462 [controlThread ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.463Z,1773427291.463 [controlThread](INFO): Join timeout helper Thread ID is 842
2026-03-13T18:41:31.568Z,1773427291.568 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-13T18:41:31.568Z,1773427291.568 [controlThread](DEBUG): Uninitializing ControlThread
2026-03-13T18:41:31.569Z,1773427291.569 [AHRS_M2](INFO): Powering down
2026-03-13T18:41:31.648Z,1773427291.648 [NAL9602](INFO): Powering down
2026-03-13T18:41:31.649Z,1773427291.649 [Sonardyne_Nano](INFO): Powering down
2026-03-13T18:41:31.856Z,1773427291.856 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2026-03-13T18:41:31.857Z,1773427291.857 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2026-03-13T18:41:31.857Z,1773427291.857 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2026-03-13T18:41:31.858Z,1773427291.858 [MissionManager](INFO): Uninitializing Mission Default
2026-03-13T18:41:31.861Z,1773427291.861 [MissionManager](INFO): Uninitializing Mission Startup
2026-03-13T18:41:31.861Z,1773427291.861 [Startup] Stopped
2026-03-13T18:41:31.861Z,1773427291.861 [Startup](DEBUG): Aggregate::uninitialize Startup
2026-03-13T18:41:31.861Z,1773427291.861 [Startup:A.GoToSurface] Stopped
2026-03-13T18:41:31.861Z,1773427291.861 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-13T18:41:31.861Z,1773427291.861 [Startup:StartupSatComms] Stopped
2026-03-13T18:41:31.861Z,1773427291.861 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2026-03-13T18:41:31.861Z,1773427291.861 [Startup:StartupSatComms:B] Stopped
2026-03-13T18:41:31.863Z,1773427291.863 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2026-03-13T18:41:31.863Z,1773427291.863 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2026-03-13T18:41:31.864Z,1773427291.864 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2026-03-13T18:41:31.864Z,1773427291.864 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2026-03-13T18:41:31.864Z,1773427291.864 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2026-03-13T18:41:31.864Z,1773427291.864 [BuoyancyServo](INFO): Powering down
2026-03-13T18:41:31.876Z,1773427291.876 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2026-03-13T18:41:31.876Z,1773427291.876 [ElevatorServo](INFO): Powering down
2026-03-13T18:41:31.877Z,1773427291.877 [MassServo](DEBUG): Uninitialize Mass Servo.
2026-03-13T18:41:31.877Z,1773427291.877 [MassServo](INFO): Powering down
2026-03-13T18:41:31.878Z,1773427291.878 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2026-03-13T18:41:31.878Z,1773427291.878 [RudderServo](INFO): Powering down
2026-03-13T18:41:31.879Z,1773427291.879 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2026-03-13T18:41:31.879Z,1773427291.879 [ThrusterHE](INFO): Powering down
2026-03-13T18:41:31.880Z,1773427291.880 [SBIT](DEBUG): Uninitialize SBIT Component.
2026-03-13T18:41:31.880Z,1773427291.880 [IBIT](DEBUG): Uninitialize IBIT Component.
2026-03-13T18:41:31.880Z,1773427291.880 [CBIT](DEBUG): Uninitialize CBIT Component.
2026-03-13T18:41:31.880Z,1773427291.880 [CBIT](DEBUG): Powering off loads.
2026-03-13T18:41:31.892Z,1773427291.892 [CBIT](DEBUG): Disabling WDT.
2026-03-13T18:41:31.904Z,1773427291.904 [GFScanner](DEBUG): Uninitialize GFScanner component.
2026-03-13T18:41:31.904Z,1773427291.904 [GFScanner](DEBUG): Opening all GF detection circuits.
2026-03-13T18:41:31.905Z,1773427291.905 [controlThread ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.960Z,1773427291.960 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.963Z,1773427291.963 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.969Z,1773427291.969 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:31.975Z,1773427291.975 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:32.049Z,1773427292.049 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:32.053Z,1773427292.053 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:32.076Z,1773427292.076 [GFScanner](DEBUG): Uninitialize GFScanner component.
2026-03-13T18:41:32.076Z,1773427292.076 [GFScanner](DEBUG): Opening all GF detection circuits.
2026-03-13T18:41:32.097Z,1773427292.097 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-13T18:41:32.164Z,1773427292.164 [logger ThreadHandler](INFO): Thread cancelled.