2026-02-09T17:50:24.789Z,1770659424.789 [Supervisor](DEBUG): Initializing supervisor.
2026-02-09T17:50:24.793Z,1770659424.793 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-02-09T17:50:24.793Z,1770659424.793 [SyncHandler](INFO): Protected caller Thread ID is 837
2026-02-09T17:50:24.794Z,1770659424.794 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-02-09T17:50:24.795Z,1770659424.795 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-02-09T17:50:24.795Z,1770659424.795 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2026-02-09T17:50:24.799Z,1770659424.799 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-02-09T17:50:24.818Z,1770659424.818 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-02-09T17:50:24.819Z,1770659424.819 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-02-09T17:50:24.819Z,1770659424.819 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2026-02-09T17:50:24.823Z,1770659424.823 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-02-09T17:50:24.824Z,1770659424.824 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-02-09T17:50:24.825Z,1770659424.825 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2026-02-09T17:50:24.827Z,1770659424.827 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-02-09T17:50:24.828Z,1770659424.828 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-02-09T17:50:24.828Z,1770659424.828 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2026-02-09T17:50:24.832Z,1770659424.832 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-02-09T17:50:24.832Z,1770659424.832 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-02-09T17:50:24.836Z,1770659424.836 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-02-09T17:50:25.095Z,1770659425.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-02-09T17:50:25.097Z,1770659425.097 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-02-09T17:50:25.195Z,1770659425.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-02-09T17:50:25.195Z,1770659425.195 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-02-09T17:50:25.698Z,1770659425.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-02-09T17:50:25.699Z,1770659425.699 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-02-09T17:50:26.031Z,1770659426.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-02-09T17:50:26.033Z,1770659426.033 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-02-09T17:50:26.138Z,1770659426.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-02-09T17:50:26.139Z,1770659426.139 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-02-09T17:50:26.536Z,1770659426.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-02-09T17:50:26.538Z,1770659426.538 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-02-09T17:50:26.772Z,1770659426.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-02-09T17:50:26.774Z,1770659426.774 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-02-09T17:50:27.297Z,1770659427.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-02-09T17:50:27.299Z,1770659427.299 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-02-09T17:50:27.443Z,1770659427.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-02-09T17:50:27.445Z,1770659427.445 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-02-09T17:50:27.534Z,1770659427.534 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-02-09T17:50:29.198Z,1770659429.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-02-09T17:50:29.198Z,1770659429.198 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-02-09T17:50:29.583Z,1770659429.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-02-09T17:50:29.583Z,1770659429.583 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-02-09T17:50:29.768Z,1770659429.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-02-09T17:50:29.770Z,1770659429.770 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-02-09T17:50:29.891Z,1770659429.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-02-09T17:50:29.892Z,1770659429.892 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-02-09T17:50:30.119Z,1770659430.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-02-09T17:50:30.121Z,1770659430.121 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-02-09T17:50:30.377Z,1770659430.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-02-09T17:50:30.379Z,1770659430.379 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2026-02-09T17:50:30.382Z,1770659430.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2026-02-09T17:50:30.477Z,1770659430.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2026-02-09T17:50:30.586Z,1770659430.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2026-02-09T17:50:30.678Z,1770659430.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2026-02-09T17:50:30.784Z,1770659430.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2026-02-09T17:50:30.884Z,1770659430.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2026-02-09T17:50:31.003Z,1770659431.003 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2026-02-09T17:50:31.159Z,1770659431.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2026-02-09T17:50:31.301Z,1770659431.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2026-02-09T17:50:31.399Z,1770659431.399 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2026-02-09T17:50:31.670Z,1770659431.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-02-09T17:50:31.670Z,1770659431.670 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-02-09T17:50:31.683Z,1770659431.683 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-02-09T17:50:31.755Z,1770659431.755 [VerticalControl](DEBUG): Construct VerticalControl.
2026-02-09T17:50:31.815Z,1770659431.815 [VerticalControl] Loaded
2026-02-09T17:50:31.815Z,1770659431.815 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-02-09T17:50:31.818Z,1770659431.818 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-02-09T17:50:31.866Z,1770659431.866 [HorizontalControl] Loaded
2026-02-09T17:50:31.866Z,1770659431.866 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-02-09T17:50:31.869Z,1770659431.869 [SpeedControl](DEBUG): Construct SpeedControl.
2026-02-09T17:50:31.872Z,1770659431.872 [SpeedControl] Loaded
2026-02-09T17:50:31.872Z,1770659431.872 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-02-09T17:50:31.875Z,1770659431.875 [LoopControl](DEBUG): Construct LoopControl.
2026-02-09T17:50:31.875Z,1770659431.875 [LoopControl] Loaded
2026-02-09T17:50:31.875Z,1770659431.875 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-02-09T17:50:31.876Z,1770659431.876 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-02-09T17:50:31.878Z,1770659431.878 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-02-09T17:50:31.893Z,1770659431.893 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-02-09T17:50:31.894Z,1770659431.894 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-02-09T17:50:32.103Z,1770659432.103 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-02-09T17:50:32.104Z,1770659432.104 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-02-09T17:50:32.288Z,1770659432.288 [BuoyancyServo] Loaded
2026-02-09T17:50:32.289Z,1770659432.289 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2026-02-09T17:50:32.310Z,1770659432.310 [ElevatorServo] Loaded
2026-02-09T17:50:32.311Z,1770659432.311 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-02-09T17:50:32.331Z,1770659432.331 [MassServo] Loaded
2026-02-09T17:50:32.332Z,1770659432.332 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2026-02-09T17:50:32.351Z,1770659432.351 [RudderServo] Loaded
2026-02-09T17:50:32.352Z,1770659432.352 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-02-09T17:50:32.369Z,1770659432.369 [ThrusterHE] Loaded
2026-02-09T17:50:32.369Z,1770659432.369 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-02-09T17:50:32.369Z,1770659432.369 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-02-09T17:50:32.370Z,1770659432.370 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-02-09T17:50:32.507Z,1770659432.507 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-02-09T17:50:32.508Z,1770659432.508 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-02-09T17:50:32.521Z,1770659432.521 [NavChart] Loaded
2026-02-09T17:50:32.521Z,1770659432.521 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-02-09T17:50:32.527Z,1770659432.527 [UniversalFixResidualReporter] Loaded
2026-02-09T17:50:32.527Z,1770659432.527 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-02-09T17:50:32.527Z,1770659432.527 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-02-09T17:50:32.528Z,1770659432.528 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-02-09T17:50:32.615Z,1770659432.615 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-02-09T17:50:32.615Z,1770659432.615 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-02-09T17:50:33.436Z,1770659433.436 [AHRS_M2] Loaded
2026-02-09T17:50:33.436Z,1770659433.436 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2026-02-09T17:50:34.751Z,1770659434.751 [BPC1] Loaded
2026-02-09T17:50:34.751Z,1770659434.751 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-02-09T17:50:35.010Z,1770659435.010 [DAT] Loaded
2026-02-09T17:50:35.011Z,1770659435.011 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2026-02-09T17:50:35.012Z,1770659435.012 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094D4E0
2026-02-09T17:50:35.012Z,1770659435.012 [DAT ThreadHandler](INFO): Protected caller Thread ID is 920
2026-02-09T17:50:35.155Z,1770659435.155 [DataOverHttps] Loaded
2026-02-09T17:50:35.155Z,1770659435.155 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-02-09T17:50:35.156Z,1770659435.156 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097D4E0
2026-02-09T17:50:35.156Z,1770659435.156 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 921
2026-02-09T17:50:35.180Z,1770659435.180 [Depth_Keller] Loaded
2026-02-09T17:50:35.180Z,1770659435.180 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-02-09T17:50:35.185Z,1770659435.185 [DropWeight] Loaded
2026-02-09T17:50:35.185Z,1770659435.185 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2026-02-09T17:50:35.248Z,1770659435.248 [NAL9602] Loaded
2026-02-09T17:50:35.248Z,1770659435.248 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-02-09T17:50:35.278Z,1770659435.278 [Onboard] Loaded
2026-02-09T17:50:35.278Z,1770659435.278 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-02-09T17:50:35.279Z,1770659435.279 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AD4E0
2026-02-09T17:50:35.279Z,1770659435.279 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 922
2026-02-09T17:50:35.293Z,1770659435.293 [Power24vConverter] Loaded
2026-02-09T17:50:35.293Z,1770659435.293 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-02-09T17:50:35.309Z,1770659435.309 [Radio_Surface] Loaded
2026-02-09T17:50:35.309Z,1770659435.309 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-02-09T17:50:35.310Z,1770659435.310 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2026-02-09T17:50:35.310Z,1770659435.310 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 923
2026-02-09T17:50:35.326Z,1770659435.326 [Sonardyne_Nano] Loaded
2026-02-09T17:50:35.326Z,1770659435.326 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-02-09T17:50:35.327Z,1770659435.327 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-02-09T17:50:35.327Z,1770659435.327 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-02-09T17:50:35.397Z,1770659435.397 [DepthRateCalculator] Loaded
2026-02-09T17:50:35.398Z,1770659435.398 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-02-09T17:50:35.403Z,1770659435.403 [PitchRateCalculator] Loaded
2026-02-09T17:50:35.403Z,1770659435.403 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-02-09T17:50:35.412Z,1770659435.412 [SpeedCalculator] Loaded
2026-02-09T17:50:35.412Z,1770659435.412 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-02-09T17:50:35.416Z,1770659435.416 [YawRateCalculator] Loaded
2026-02-09T17:50:35.417Z,1770659435.417 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-02-09T17:50:35.435Z,1770659435.435 [ElevatorOffsetCalculator] Loaded
2026-02-09T17:50:35.436Z,1770659435.436 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-02-09T17:50:35.436Z,1770659435.436 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-02-09T17:50:35.437Z,1770659435.437 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-02-09T17:50:35.617Z,1770659435.617 [CANONSampler] Loaded
2026-02-09T17:50:35.617Z,1770659435.617 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2026-02-09T17:50:35.679Z,1770659435.679 [CTD_Seabird] Loaded
2026-02-09T17:50:35.679Z,1770659435.679 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-02-09T17:50:35.680Z,1770659435.680 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A9B4E0
2026-02-09T17:50:35.681Z,1770659435.681 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 924
2026-02-09T17:50:35.703Z,1770659435.703 [PAR_Licor] Loaded
2026-02-09T17:50:35.703Z,1770659435.703 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-02-09T17:50:35.747Z,1770659435.747 [WetLabsBB2FL] Loaded
2026-02-09T17:50:35.747Z,1770659435.747 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-02-09T17:50:35.748Z,1770659435.748 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0
2026-02-09T17:50:35.748Z,1770659435.748 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 925
2026-02-09T17:50:35.749Z,1770659435.749 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-02-09T17:50:35.750Z,1770659435.750 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-02-09T17:50:35.798Z,1770659435.798 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-02-09T17:50:35.799Z,1770659435.799 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-02-09T17:50:36.198Z,1770659436.198 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-02-09T17:50:36.200Z,1770659436.200 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-02-09T17:50:36.391Z,1770659436.391 [SBIT](DEBUG): Construct Startup Built In Test.
2026-02-09T17:50:36.400Z,1770659436.400 [SBIT] Loaded
2026-02-09T17:50:36.400Z,1770659436.400 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-02-09T17:50:36.403Z,1770659436.403 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-02-09T17:50:36.415Z,1770659436.415 [IBIT] Loaded
2026-02-09T17:50:36.416Z,1770659436.416 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-02-09T17:50:36.421Z,1770659436.421 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-02-09T17:50:36.500Z,1770659436.500 [CBIT] Loaded
2026-02-09T17:50:36.501Z,1770659436.501 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-02-09T17:50:36.533Z,1770659436.533 [GFScanner] Loaded
2026-02-09T17:50:36.533Z,1770659436.533 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-02-09T17:50:36.533Z,1770659436.533 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-02-09T17:50:36.534Z,1770659436.534 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-02-09T17:50:36.698Z,1770659436.698 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-02-09T17:50:36.705Z,1770659436.705 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-02-09T17:50:36.707Z,1770659436.707 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-02-09T17:50:36.719Z,1770659436.719 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-02-09T17:50:36.720Z,1770659436.720 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2026-02-09T17:50:36.720Z,1770659436.720 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2026-02-09T17:50:36.724Z,1770659436.724 [Supervisor](INFO): Main Thread ID is 830
2026-02-09T17:50:36.724Z,1770659436.724 [Supervisor](DEBUG): Running supervisor.
2026-02-09T17:50:36.725Z,1770659436.725 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2026-02-09T17:50:36.726Z,1770659436.726 [CommandExec](INFO): Initializing the command executive.
2026-02-09T17:50:36.727Z,1770659436.727 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2026-02-09T17:50:36.729Z,1770659436.729 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2026-02-09T17:50:36.730Z,1770659436.730 [controlThread](DEBUG): Initializing ControlThread
2026-02-09T17:50:36.731Z,1770659436.731 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-02-09T17:50:36.732Z,1770659436.732 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-02-09T17:50:36.733Z,1770659436.733 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-02-09T17:50:36.734Z,1770659436.734 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-02-09T17:50:36.736Z,1770659436.736 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-02-09T17:50:36.736Z,1770659436.736 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-02-09T17:50:36.739Z,1770659436.739 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-02-09T17:50:36.739Z,1770659436.739 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-02-09T17:50:36.740Z,1770659436.740 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-02-09T17:50:36.740Z,1770659436.740 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-02-09T17:50:36.741Z,1770659436.741 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-02-09T17:50:36.745Z,1770659436.745 [SBIT](INFO): Initialize SBIT Component.
2026-02-09T17:50:36.745Z,1770659436.745 [SBIT](IMPORTANT): git: 2026-02-09
2026-02-09T17:50:36.746Z,1770659436.746 [SBIT](INFO): git hash: 56326f8721d2fb7c55295416e529d5a8c354a561
2026-02-09T17:50:36.746Z,1770659436.746 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-02-09T17:50:36.747Z,1770659436.747 [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-02-09T17:50:36.748Z,1770659436.748 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2026-02-09T17:50:36.749Z,1770659436.749 [IBIT](INFO): Initialize IBIT Component.
2026-02-09T17:50:36.750Z,1770659436.750 [CBIT](DEBUG): Initialize CBIT Component.
2026-02-09T17:50:36.750Z,1770659436.750 [logger ThreadHandler](INFO): Handler Thread ID is 930
2026-02-09T17:50:36.761Z,1770659436.761 [CBIT](DEBUG): Initialized mux pins.
2026-02-09T17:50:36.761Z,1770659436.761 [CBIT](DEBUG): Initializing the watchdog timer.
2026-02-09T17:50:36.769Z,1770659436.769 [DAT ThreadHandler](INFO): Handler Thread ID is 931
2026-02-09T17:50:36.770Z,1770659436.770 [DAT](INFO): Powering up
2026-02-09T17:50:36.770Z,1770659436.770 [DAT](DEBUG): Initializing DAT.
2026-02-09T17:50:36.774Z,1770659436.774 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 933
2026-02-09T17:50:36.776Z,1770659436.776 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-02-09T17:50:36.785Z,1770659436.785 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-02-09T17:50:36.785Z,1770659436.785 [CBIT](DEBUG): Initializing heartbeat.
2026-02-09T17:50:36.786Z,1770659436.786 [Onboard ThreadHandler](INFO): Handler Thread ID is 934
2026-02-09T17:50:36.806Z,1770659436.806 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 935
2026-02-09T17:50:36.821Z,1770659436.821 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 936
2026-02-09T17:50:36.822Z,1770659436.822 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-02-09T17:50:36.825Z,1770659436.825 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 938
2026-02-09T17:50:36.830Z,1770659436.830 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2026-02-09T17:50:36.831Z,1770659436.831 [WetLabsBB2FL](INFO): Powering up
2026-02-09T17:50:36.838Z,1770659436.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2026-02-09T17:50:36.839Z,1770659436.839 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2026-02-09T17:50:36.839Z,1770659436.839 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2026-02-09T17:50:36.839Z,1770659436.839 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2026-02-09T17:50:36.839Z,1770659436.839 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2026-02-09T17:50:36.839Z,1770659436.839 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2026-02-09T17:50:36.840Z,1770659436.840 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2026-02-09T17:50:36.840Z,1770659436.840 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2026-02-09T17:50:36.857Z,1770659436.857 [CBIT](DEBUG): Deactivating emergency mode.
2026-02-09T17:50:36.897Z,1770659436.897 [CBIT](DEBUG): Backplane powered.
2026-02-09T17:50:36.897Z,1770659436.897 [GFScanner](DEBUG): Initializing GFScanner
2026-02-09T17:50:36.898Z,1770659436.898 [GFScanner](DEBUG): Deactivating GF circuits.
2026-02-09T17:50:36.902Z,1770659436.902 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-02-09T17:50:36.902Z,1770659436.902 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-02-09T17:50:36.903Z,1770659436.903 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-02-09T17:50:36.908Z,1770659436.908 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-02-09T17:50:36.982Z,1770659436.982 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2026-02-09T17:50:36.986Z,1770659436.986 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-02-09T17:50:37.045Z,1770659437.045 [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-02-09T17:50:37.053Z,1770659437.053 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-02-09T17:50:37.053Z,1770659437.053 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-02-09T17:50:37.057Z,1770659437.057 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-02-09T17:50:37.209Z,1770659437.209 [Radio_Surface](INFO): Powering up
2026-02-09T17:50:37.327Z,1770659437.327 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2026-02-09T17:50:37.361Z,1770659437.361 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-02-09T17:50:37.363Z,1770659437.363 [Default:A.Wait](DEBUG): Construct Wait.
2026-02-09T17:50:37.365Z,1770659437.365 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-02-09T17:50:37.411Z,1770659437.411 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-02-09T17:50:37.413Z,1770659437.413 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-02-09T17:50:37.431Z,1770659437.431 [Default:E.Execute](DEBUG): Construct Execute.
2026-02-09T17:50:37.434Z,1770659437.434 [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-02-09T17:50:37.447Z,1770659437.447 [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-02-09T17:50:37.467Z,1770659437.467 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2026-02-09T17:50:37.617Z,1770659437.617 [Depth_Keller](INFO): Initializing.
2026-02-09T17:50:37.618Z,1770659437.618 [Power24vConverter](INFO): Powering up.
2026-02-09T17:50:37.619Z,1770659437.619 [Sonardyne_Nano](INFO): Initializing.
2026-02-09T17:50:37.633Z,1770659437.633 [CANONSampler](INFO): Powering down
2026-02-09T17:50:37.641Z,1770659437.641 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-02-09T17:50:37.668Z,1770659437.668 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2026-02-09T17:50:37.677Z,1770659437.677 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2026-02-09T17:50:37.678Z,1770659437.678 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-02-09T17:50:37.685Z,1770659437.685 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-02-09T17:50:37.686Z,1770659437.686 [MassServo](DEBUG): Initializing EZServoServo.
2026-02-09T17:50:37.693Z,1770659437.693 [MassServo](DEBUG): Initializing MassServo.
2026-02-09T17:50:37.694Z,1770659437.694 [RudderServo](DEBUG): Initializing EZServoServo.
2026-02-09T17:50:37.701Z,1770659437.701 [RudderServo](DEBUG): Initializing RudderServo.
2026-02-09T17:50:37.702Z,1770659437.702 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-02-09T17:50:37.709Z,1770659437.709 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-02-09T17:50:39.441Z,1770659439.441 [WetLabsBB2FL](INFO): Powering down
2026-02-09T17:50:39.879Z,1770659439.879 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2026-02-09T17:50:41.117Z,1770659441.117 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2026-02-09T17:50:43.342Z,1770659443.342 [ThrusterHE](ERROR): Zero Speed Commanded.
2026-02-09T17:50:48.613Z,1770659448.613 [DAT](INFO): DAT read:
2026-02-09T17:50:48.615Z,1770659448.615 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2026-02-09T17:50:50.126Z,1770659450.126 [DAT](INFO): DAT read: MF Frequency Band
2026-02-09T17:50:50.127Z,1770659450.127 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2026-02-09T17:50:50.127Z,1770659450.127 [DAT](INFO): DAT read: Feb 9 2026 17:50:45
2026-02-09T17:50:51.386Z,1770659451.386 [DAT](INFO): DAT read: Features enabled [Bearing]
2026-02-09T17:50:51.387Z,1770659451.387 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2026-02-09T17:50:51.387Z,1770659451.387 [DAT](INFO): commRate: 1200
2026-02-09T17:50:53.453Z,1770659453.453 [DAT](INFO): entering command mode
2026-02-09T17:50:53.653Z,1770659453.653 [DAT](INFO): DAT read:
2026-02-09T17:50:53.654Z,1770659453.654 [DAT](INFO): DAT read: user:1>
2026-02-09T17:50:53.654Z,1770659453.654 [DAT](INFO): setting verbose to 3
2026-02-09T17:50:53.905Z,1770659453.905 [DAT](INFO): DAT read: user:1>
2026-02-09T17:50:53.906Z,1770659453.906 [DAT](INFO): DAT read: Verbose | 3
2026-02-09T17:50:53.907Z,1770659453.907 [DAT](INFO): set verbose to 3
2026-02-09T17:50:53.907Z,1770659453.907 [DAT](INFO): setting DatVerbose to 27440
2026-02-09T17:50:54.157Z,1770659454.157 [DAT](INFO): DAT read: user:2>
2026-02-09T17:50:54.158Z,1770659454.158 [DAT](INFO): DAT read: DatVerbose | 27440
2026-02-09T17:50:54.158Z,1770659454.158 [DAT](INFO): set DatVerbose to 27440
2026-02-09T17:50:54.159Z,1770659454.159 [DAT](INFO): setting transmit power to 8
2026-02-09T17:50:54.410Z,1770659454.410 [DAT](INFO): DAT read: user:3>
2026-02-09T17:50:54.411Z,1770659454.411 [DAT](INFO): DAT read: TxPower | 8 (Max)
2026-02-09T17:50:54.411Z,1770659454.411 [DAT](INFO): set transmit power to 8
2026-02-09T17:50:54.411Z,1770659454.411 [DAT](INFO): setting local address to 8
2026-02-09T17:50:54.662Z,1770659454.662 [DAT](INFO): DAT read: user:4>
2026-02-09T17:50:54.662Z,1770659454.662 [DAT](INFO): DAT read: LocalAddr | 8
2026-02-09T17:50:54.663Z,1770659454.663 [DAT](INFO): set local address to 8
2026-02-09T17:50:54.664Z,1770659454.664 [DAT](INFO): Setting time to: 17:50:54 And date to:2/9/2026
2026-02-09T17:50:54.913Z,1770659454.913 [DAT](INFO): DAT read: user:5>
2026-02-09T17:50:54.914Z,1770659454.914 [DAT](INFO): DAT read: Mon Feb 9, 2026 17:50:54
2026-02-09T17:50:54.915Z,1770659454.915 [DAT](INFO): Local DAT time set to Mon Feb 9, 2026 17:50:54
2026-02-09T17:51:01.346Z,1770659461.346 [SBIT](IMPORTANT): Beginning Startup BIT
2026-02-09T17:51:01.355Z,1770659461.355 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-02-09T17:51:05.362Z,1770659465.362 [NAL9602](INFO): Powering up NAL9602
2026-02-09T17:51:05.874Z,1770659465.874 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.021524
CHAN A1 (24V): 0.044599
CHAN A2 (12V): 0.012652
CHAN A3 (5V): -0.000542
CHAN B0 (3.3V): 0.000040
CHAN B1 (3.15aV): 0.000578
CHAN B2 (3.15bV): 0.000093
CHAN B3 (GND): -0.004623
OPEN: 0.006942
Full Scale: +/- 1 mA
2026-02-09T17:51:16.278Z,1770659476.278 [NAL9602](INFO): NAL9602 initialized
2026-02-09T17:51:55.079Z,1770659515.079 [SBIT](IMPORTANT): SBIT PASSED
2026-02-09T17:51:55.080Z,1770659515.080 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2026-02-09T17:51:55.080Z,1770659515.080 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count;
2026-02-09T17:51:55.104Z,1770659515.104 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2026-02-09T17:51:55.104Z,1770659515.104 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2026-02-09T17:51:55.104Z,1770659515.104 [SBIT](IMPORTANT): Express linearApproximation PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;
2026-02-09T17:51:55.104Z,1770659515.104 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=282.753401 cubic_centimeter;
2026-02-09T17:51:55.104Z,1770659515.104 [SBIT](IMPORTANT): VerticalControl.massDefault=1.176086 millimeter;
2026-02-09T17:51:55.483Z,1770659515.483 [MissionManager](IMPORTANT): Started mission Startup
2026-02-09T17:51:55.483Z,1770659515.483 [Startup] Running Loop=1
2026-02-09T17:51:55.483Z,1770659515.483 [Startup](DEBUG): Aggregate::initialize Startup
2026-02-09T17:51:55.483Z,1770659515.483 [Startup:A.GoToSurface] Running Loop=1
2026-02-09T17:51:55.484Z,1770659515.484 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-02-09T17:51:55.484Z,1770659515.484 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-02-09T17:51:55.485Z,1770659515.485 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-02-09T17:51:55.485Z,1770659515.485 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-02-09T17:51:55.485Z,1770659515.485 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-02-09T17:51:55.486Z,1770659515.486 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-02-09T17:51:55.486Z,1770659515.486 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-02-09T17:51:55.487Z,1770659515.487 [Startup:StartupSatComms] Running Loop=1
2026-02-09T17:51:55.487Z,1770659515.487 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2026-02-09T17:51:55.488Z,1770659515.488 [Startup:StartupSatComms:A] Running Loop=1
2026-02-09T17:51:55.885Z,1770659515.885 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2026-02-09T17:52:27.788Z,1770659547.788 [NAL9602](INFO): SBD MO Status=2, MOMSN=536, MT Status=2, MTMSN=0
2026-02-09T17:52:27.788Z,1770659547.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T17:52:55.683Z,1770659575.683 [Startup:StartupSatComms:A](INFO): Timed out from 2026-02-09T17:51:55.5Z
2026-02-09T17:52:55.683Z,1770659575.683 [Startup:StartupSatComms:A] Stopped
2026-02-09T17:52:55.683Z,1770659575.683 [Startup:StartupSatComms:B] Running Loop=1
2026-02-09T17:52:56.078Z,1770659576.078 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-02-09T17:52:58.899Z,1770659578.899 [NAL9602](INFO): SBD MO Status=0, MOMSN=536, MT Status=0, MTMSN=0
2026-02-09T17:52:58.899Z,1770659578.899 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:53:00.112Z,1770659580.112 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175258.00,A,3648.15622,N,12147.22292,W,0.097,0.00,090226,,,A*72
2026-02-09T17:53:00.115Z,1770659580.115 [NAL9602](INFO): GPS fix at 20260209T175258: (36.802604, -121.787049)
2026-02-09T17:53:19.543Z,1770659599.543 [NAL9602](INFO): SBD MO Status=2, MOMSN=537, MT Status=2, MTMSN=0
2026-02-09T17:53:19.543Z,1770659599.543 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T17:53:30.095Z,1770659610.095 [NAL9602](INFO): SBD MO Status=1, MOMSN=537, MT Status=0, MTMSN=0
2026-02-09T17:53:30.329Z,1770659610.329 [NAL9602](INFO): Sent 18 bytes from file Logs/20260209T161454/Courier0049.lzma
2026-02-09T17:53:30.329Z,1770659610.329 [NAL9602](INFO): Packets left to send: 0
2026-02-09T17:53:39.835Z,1770659619.835 [NAL9602](INFO): SBD MO Status=1, MOMSN=538, MT Status=0, MTMSN=0
2026-02-09T17:53:39.887Z,1770659619.887 [NAL9602](INFO): Sent 151 bytes from file Logs/20260209T175024/Courier0000.lzma
2026-02-09T17:53:39.887Z,1770659619.887 [NAL9602](INFO): Packets left to send: 0
2026-02-09T17:53:56.054Z,1770659636.054 [Startup:StartupSatComms:B](INFO): Timed out from 2026-02-09T17:52:55.7Z
2026-02-09T17:53:56.055Z,1770659636.055 [Startup:StartupSatComms:B] Stopped
2026-02-09T17:53:56.055Z,1770659636.055 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2026-02-09T17:53:56.055Z,1770659636.055 [Startup:StartupSatComms] Stopped
2026-02-09T17:53:56.055Z,1770659636.055 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2026-02-09T17:53:56.056Z,1770659636.056 [Startup](INFO): Completed Startup
2026-02-09T17:53:56.056Z,1770659636.056 [MissionManager](INFO): Startup is completed.
2026-02-09T17:53:56.058Z,1770659636.058 [MissionManager](INFO): Uninitializing Mission Startup
2026-02-09T17:53:56.059Z,1770659636.059 [Startup] Stopped
2026-02-09T17:53:56.059Z,1770659636.059 [Startup](DEBUG): Aggregate::uninitialize Startup
2026-02-09T17:53:56.060Z,1770659636.060 [Startup:A.GoToSurface] Stopped
2026-02-09T17:53:56.060Z,1770659636.060 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-02-09T17:53:56.432Z,1770659636.432 [NAL9602](INFO): SBD MO Status=2, MOMSN=539, MT Status=2, MTMSN=0
2026-02-09T17:53:56.433Z,1770659636.433 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T17:53:56.443Z,1770659636.443 [MissionManager](IMPORTANT): Started mission Default
2026-02-09T17:53:56.443Z,1770659636.443 [Default] Running Loop=1
2026-02-09T17:53:56.443Z,1770659636.443 [Default](DEBUG): Aggregate::initialize Default
2026-02-09T17:53:56.443Z,1770659636.443 [Default:B.GoToSurface] Running Loop=1
2026-02-09T17:53:56.443Z,1770659636.443 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-02-09T17:53:56.444Z,1770659636.444 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-02-09T17:53:56.444Z,1770659636.444 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-02-09T17:53:56.444Z,1770659636.444 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-02-09T17:53:56.463Z,1770659636.463 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-02-09T17:53:56.464Z,1770659636.464 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-02-09T17:53:56.464Z,1770659636.464 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-02-09T17:53:56.464Z,1770659636.464 [Default:A.Wait] Running Loop=1
2026-02-09T17:53:56.465Z,1770659636.465 [Default:A.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:54:09.783Z,1770659649.783 [Default:A.Wait](INFO): Done Waiting.
2026-02-09T17:54:09.783Z,1770659649.783 [Default:A.Wait] Stopped
2026-02-09T17:54:09.783Z,1770659649.783 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:54:10.190Z,1770659650.190 [Default:CheckIn] Running Loop=1
2026-02-09T17:54:10.191Z,1770659650.191 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:54:10.191Z,1770659650.191 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:54:10.599Z,1770659650.599 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-02-09T17:54:12.188Z,1770659652.188 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175410.00,A,3648.15638,N,12147.22275,W,0.058,0.00,090226,,,A*79
2026-02-09T17:54:12.190Z,1770659652.190 [NAL9602](INFO): GPS fix at 20260209T175410: (36.802606, -121.787046)
2026-02-09T17:54:12.237Z,1770659652.237 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:54:12.237Z,1770659652.237 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:54:12.618Z,1770659652.618 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-02-09T17:54:21.714Z,1770659661.714 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004303
2026-02-09T17:54:28.122Z,1770659668.122 [NAL9602](INFO): SBD MO Status=1, MOMSN=539, MT Status=0, MTMSN=0
2026-02-09T17:54:28.174Z,1770659668.174 [NAL9602](INFO): Sent 222 bytes from file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:54:28.175Z,1770659668.175 [NAL9602](INFO): Packets left to send: 0
2026-02-09T17:54:28.208Z,1770659668.208 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:54:28.208Z,1770659668.208 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:54:28.213Z,1770659668.213 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:54:29.359Z,1770659669.359 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:54:29.359Z,1770659669.359 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:54:37.799Z,1770659677.799 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:54:37.799Z,1770659677.799 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:54:58.723Z,1770659698.723 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:55:08.351Z,1770659708.351 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:55:08.351Z,1770659708.351 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:55:39.135Z,1770659739.135 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:55:39.135Z,1770659739.135 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:56:10.955Z,1770659770.955 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:56:10.955Z,1770659770.955 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:56:40.595Z,1770659800.595 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:56:40.595Z,1770659800.595 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:57:11.015Z,1770659831.015 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:57:11.015Z,1770659831.015 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:57:41.523Z,1770659861.523 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:57:41.523Z,1770659861.523 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:58:14.639Z,1770659894.639 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:58:14.639Z,1770659894.639 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:58:42.255Z,1770659922.255 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:58:42.255Z,1770659922.255 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:59:12.791Z,1770659952.791 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:59:12.791Z,1770659952.791 [DataOverHttps](FAULT): Could not open file Logs/20260209T175024/Courier0004.lzma
2026-02-09T17:59:28.665Z,1770659968.665 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:59:28.665Z,1770659968.665 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:59:28.665Z,1770659968.665 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:59:28.665Z,1770659968.665 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:59:29.128Z,1770659969.128 [Default:CheckIn:D] Stopped
2026-02-09T17:59:29.128Z,1770659969.128 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:59:29.546Z,1770659969.546 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.544743 min
2026-02-09T17:59:29.546Z,1770659969.546 [Default:CheckIn:E] Stopped
2026-02-09T17:59:29.547Z,1770659969.547 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:59:29.547Z,1770659969.547 [Default:CheckIn] Stopped
2026-02-09T17:59:29.547Z,1770659969.547 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:59:29.547Z,1770659969.547 [Default:CheckIn](INFO): Running loop #2
2026-02-09T17:59:29.547Z,1770659969.547 [Default:CheckIn] Running Loop=2
2026-02-09T17:59:29.547Z,1770659969.547 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:59:29.547Z,1770659969.547 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:59:31.473Z,1770659971.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175929.00,A,3648.15574,N,12147.22212,W,0.175,0.00,090226,,,A*7A
2026-02-09T17:59:31.475Z,1770659971.475 [NAL9602](INFO): GPS fix at 20260209T175929: (36.802596, -121.787035)
2026-02-09T17:59:31.486Z,1770659971.486 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:59:31.486Z,1770659971.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:59:39.480Z,1770659979.480 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T175024/Courier0007.lzma
2026-02-09T17:59:40.483Z,1770659980.483 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Courier0007.lzma.bak
2026-02-09T17:59:40.483Z,1770659980.483 [DataOverHttps](INFO): SBD MOMSN=26641807
2026-02-09T17:59:56.396Z,1770659996.396 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20260209T161454/Express0050.lzma
2026-02-09T17:59:56.956Z,1770659996.956 [NAL9602](INFO): SBD MO Status=0, MOMSN=540, MT Status=0, MTMSN=0
2026-02-09T17:59:56.956Z,1770659996.956 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:59:57.399Z,1770659997.399 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0050.lzma.bak
2026-02-09T17:59:57.399Z,1770659997.399 [DataOverHttps](INFO): SBD MOMSN=26641810
2026-02-09T18:00:16.174Z,1770660016.174 [DataOverHttps](INFO): Sending 957 bytes from file Logs/20260209T175024/Express0001.lzma
2026-02-09T18:00:17.175Z,1770660017.175 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0001.lzma.bak
2026-02-09T18:00:17.175Z,1770660017.175 [DataOverHttps](INFO): SBD MOMSN=26641812
2026-02-09T18:00:27.442Z,1770660027.442 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T18:00:33.525Z,1770660033.525 [DataOverHttps](INFO): Sending 349 bytes from file Logs/20260209T175024/Express0005.lzma
2026-02-09T18:00:34.527Z,1770660034.527 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0005.lzma.bak
2026-02-09T18:00:34.527Z,1770660034.527 [DataOverHttps](INFO): SBD MOMSN=26641831
2026-02-09T18:00:41.584Z,1770660041.584 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2026-02-09T18:00:50.521Z,1770660050.521 [DataOverHttps](INFO): Sending 354 bytes from file Logs/20260209T175024/Express0008.lzma
2026-02-09T18:00:51.522Z,1770660051.522 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0008.lzma.bak
2026-02-09T18:00:51.523Z,1770660051.523 [DataOverHttps](INFO): SBD MOMSN=26641836
2026-02-09T18:00:52.904Z,1770660052.904 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T18:00:52.904Z,1770660052.904 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T18:00:52.904Z,1770660052.904 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T18:03:46.211Z,1770660226.211 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2026-02-09T18:05:53.484Z,1770660353.484 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T18:05:53.485Z,1770660353.485 [Default:CheckIn:C.Wait] Stopped
2026-02-09T18:05:53.485Z,1770660353.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T18:05:53.485Z,1770660353.485 [Default:CheckIn:D] Running Loop=1
2026-02-09T18:05:53.900Z,1770660353.900 [Default:CheckIn:D] Stopped
2026-02-09T18:05:53.900Z,1770660353.900 [Default:CheckIn:E] Running Loop=1
2026-02-09T18:05:54.308Z,1770660354.308 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.957614 min
2026-02-09T18:05:54.308Z,1770660354.308 [Default:CheckIn:E] Stopped
2026-02-09T18:05:54.309Z,1770660354.309 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T18:05:54.309Z,1770660354.309 [Default:CheckIn] Stopped
2026-02-09T18:05:54.309Z,1770660354.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T18:05:54.309Z,1770660354.309 [Default:CheckIn](INFO): Running loop #3
2026-02-09T18:05:54.309Z,1770660354.309 [Default:CheckIn] Running Loop=3
2026-02-09T18:05:54.309Z,1770660354.309 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T18:05:54.309Z,1770660354.309 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T18:05:56.305Z,1770660356.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180555.00,A,3648.15497,N,12147.22415,W,0.039,345.51,090226,,,A*75
2026-02-09T18:05:56.307Z,1770660356.307 [NAL9602](INFO): GPS fix at 20260209T180555: (36.802583, -121.787069)
2026-02-09T18:05:56.318Z,1770660356.318 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T18:05:56.318Z,1770660356.318 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T18:06:03.844Z,1770660363.844 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T175024/Courier0010.lzma
2026-02-09T18:06:04.846Z,1770660364.846 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Courier0010.lzma.bak
2026-02-09T18:06:04.847Z,1770660364.847 [DataOverHttps](INFO): SBD MOMSN=26641870
2026-02-09T18:06:07.280Z,1770660367.280 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2026-02-09T18:06:07.294Z,1770660367.294 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 6.
2026-02-09T18:06:07.302Z,1770660367.302 [BPC1](INFO): Received data from all battery sticks.
2026-02-09T18:06:12.523Z,1770660372.523 [NAL9602](INFO): SBD MO Status=0, MOMSN=541, MT Status=0, MTMSN=0
2026-02-09T18:06:12.523Z,1770660372.523 [NAL9602](INFO): No messages in MT queue
2026-02-09T18:06:23.602Z,1770660383.602 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20260209T175024/Express0011.lzma
2026-02-09T18:06:24.602Z,1770660384.602 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0011.lzma.bak
2026-02-09T18:06:24.603Z,1770660384.603 [DataOverHttps](INFO): SBD MOMSN=26641873
2026-02-09T18:06:25.864Z,1770660385.864 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T18:06:25.864Z,1770660385.864 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T18:06:25.864Z,1770660385.864 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T18:06:43.223Z,1770660403.223 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T18:11:26.447Z,1770660686.447 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T18:11:26.447Z,1770660686.447 [Default:CheckIn:C.Wait] Stopped
2026-02-09T18:11:26.447Z,1770660686.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T18:11:26.447Z,1770660686.447 [Default:CheckIn:D] Running Loop=1
2026-02-09T18:11:26.847Z,1770660686.847 [Default:CheckIn:D] Stopped
2026-02-09T18:11:26.847Z,1770660686.847 [Default:CheckIn:E] Running Loop=1
2026-02-09T18:11:27.257Z,1770660687.257 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.506732 min
2026-02-09T18:11:27.257Z,1770660687.257 [Default:CheckIn:E] Stopped
2026-02-09T18:11:27.257Z,1770660687.257 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T18:11:27.257Z,1770660687.257 [Default:CheckIn] Stopped
2026-02-09T18:11:27.257Z,1770660687.257 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T18:11:27.257Z,1770660687.257 [Default:CheckIn](INFO): Running loop #4
2026-02-09T18:11:27.257Z,1770660687.257 [Default:CheckIn] Running Loop=4
2026-02-09T18:11:27.258Z,1770660687.258 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T18:11:27.258Z,1770660687.258 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T18:11:29.263Z,1770660689.263 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181128.00,A,3648.15146,N,12147.21916,W,0.058,108.00,090226,,,A*76
2026-02-09T18:11:29.274Z,1770660689.274 [NAL9602](INFO): GPS fix at 20260209T181128: (36.802524, -121.786986)
2026-02-09T18:11:29.288Z,1770660689.288 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T18:11:29.288Z,1770660689.288 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T18:11:36.212Z,1770660696.212 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20260209T175024/Courier0013.lzma
2026-02-09T18:11:37.214Z,1770660697.214 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Courier0013.lzma.bak
2026-02-09T18:11:37.215Z,1770660697.215 [DataOverHttps](INFO): SBD MOMSN=26641883
2026-02-09T18:11:43.803Z,1770660703.803 [NAL9602](INFO): SBD MO Status=0, MOMSN=542, MT Status=0, MTMSN=0
2026-02-09T18:11:43.803Z,1770660703.803 [NAL9602](INFO): No messages in MT queue
2026-02-09T18:11:53.016Z,1770660713.016 [DataOverHttps](INFO): Sending 291 bytes from file Logs/20260209T175024/Express0014.lzma
2026-02-09T18:11:54.018Z,1770660714.018 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0014.lzma.bak
2026-02-09T18:11:54.019Z,1770660714.019 [DataOverHttps](INFO): SBD MOMSN=26641886
2026-02-09T18:11:55.132Z,1770660715.132 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T18:11:55.133Z,1770660715.133 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T18:11:55.133Z,1770660715.133 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T18:12:14.503Z,1770660734.503 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T18:16:55.695Z,1770661015.695 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T18:16:55.695Z,1770661015.695 [Default:CheckIn:C.Wait] Stopped
2026-02-09T18:16:55.696Z,1770661015.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T18:16:55.696Z,1770661015.696 [Default:CheckIn:D] Running Loop=1
2026-02-09T18:16:56.104Z,1770661016.104 [Default:CheckIn:D] Stopped
2026-02-09T18:16:56.104Z,1770661016.104 [Default:CheckIn:E] Running Loop=1
2026-02-09T18:16:56.521Z,1770661016.521 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.994340 min
2026-02-09T18:16:56.521Z,1770661016.521 [Default:CheckIn:E] Stopped
2026-02-09T18:16:56.521Z,1770661016.521 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T18:16:56.521Z,1770661016.521 [Default:CheckIn] Stopped
2026-02-09T18:16:56.521Z,1770661016.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T18:16:56.522Z,1770661016.522 [Default:CheckIn](INFO): Running loop #5
2026-02-09T18:16:56.522Z,1770661016.522 [Default:CheckIn] Running Loop=5
2026-02-09T18:16:56.522Z,1770661016.522 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T18:16:56.522Z,1770661016.522 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T18:16:58.521Z,1770661018.521 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181657.00,A,3648.14940,N,12147.21693,W,0.039,108.00,090226,,,D*76
2026-02-09T18:16:58.523Z,1770661018.523 [NAL9602](INFO): GPS fix at 20260209T181657: (36.802490, -121.786949)
2026-02-09T18:16:58.553Z,1770661018.553 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T18:16:58.553Z,1770661018.553 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T18:17:05.464Z,1770661025.464 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T175024/Courier0016.lzma
2026-02-09T18:17:06.466Z,1770661026.466 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Courier0016.lzma.bak
2026-02-09T18:17:06.467Z,1770661026.467 [DataOverHttps](INFO): SBD MOMSN=26641896
2026-02-09T18:17:13.467Z,1770661033.467 [NAL9602](INFO): SBD MO Status=0, MOMSN=543, MT Status=0, MTMSN=0
2026-02-09T18:17:13.467Z,1770661033.467 [NAL9602](INFO): No messages in MT queue
2026-02-09T18:17:22.272Z,1770661042.272 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20260209T175024/Express0017.lzma
2026-02-09T18:17:23.274Z,1770661043.274 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0017.lzma.bak
2026-02-09T18:17:23.275Z,1770661043.275 [DataOverHttps](INFO): SBD MOMSN=26641899
2026-02-09T18:17:24.388Z,1770661044.388 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T18:17:24.389Z,1770661044.389 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T18:17:24.389Z,1770661044.389 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T18:17:44.170Z,1770661064.170 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T18:22:24.989Z,1770661344.989 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T18:22:24.989Z,1770661344.989 [Default:CheckIn:C.Wait] Stopped
2026-02-09T18:22:24.989Z,1770661344.989 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T18:22:24.989Z,1770661344.989 [Default:CheckIn:D] Running Loop=1
2026-02-09T18:22:25.401Z,1770661345.401 [Default:CheckIn:D] Stopped
2026-02-09T18:22:25.401Z,1770661345.401 [Default:CheckIn:E] Running Loop=1
2026-02-09T18:22:25.793Z,1770661345.793 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.482631 min
2026-02-09T18:22:25.793Z,1770661345.793 [Default:CheckIn:E] Stopped
2026-02-09T18:22:25.793Z,1770661345.793 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T18:22:25.793Z,1770661345.793 [Default:CheckIn] Stopped
2026-02-09T18:22:25.793Z,1770661345.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T18:22:25.794Z,1770661345.794 [Default:CheckIn](INFO): Running loop #6
2026-02-09T18:22:25.794Z,1770661345.794 [Default:CheckIn] Running Loop=6
2026-02-09T18:22:25.794Z,1770661345.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T18:22:25.794Z,1770661345.794 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T18:22:27.801Z,1770661347.801 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182227.00,A,3648.16925,N,12147.19683,W,2.819,15.95,090226,,,D*44
2026-02-09T18:22:27.803Z,1770661347.803 [NAL9602](INFO): GPS fix at 20260209T182227: (36.802821, -121.786614)
2026-02-09T18:22:27.814Z,1770661347.814 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T18:22:27.815Z,1770661347.815 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T18:22:35.136Z,1770661355.136 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T175024/Courier0019.lzma
2026-02-09T18:22:36.138Z,1770661356.138 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Courier0019.lzma.bak
2026-02-09T18:22:36.139Z,1770661356.139 [DataOverHttps](INFO): SBD MOMSN=26641909
2026-02-09T18:22:45.575Z,1770661365.575 [NAL9602](INFO): SBD MO Status=0, MOMSN=544, MT Status=0, MTMSN=0
2026-02-09T18:22:45.575Z,1770661365.575 [NAL9602](INFO): No messages in MT queue
2026-02-09T18:22:51.976Z,1770661371.976 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20260209T175024/Express0020.lzma
2026-02-09T18:22:52.978Z,1770661372.978 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0020.lzma.bak
2026-02-09T18:22:52.979Z,1770661372.979 [DataOverHttps](INFO): SBD MOMSN=26641912
2026-02-09T18:22:54.067Z,1770661374.067 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T18:22:54.068Z,1770661374.068 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T18:22:54.068Z,1770661374.068 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T18:23:16.282Z,1770661396.282 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T18:27:54.660Z,1770661674.660 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T18:27:54.660Z,1770661674.660 [Default:CheckIn:C.Wait] Stopped
2026-02-09T18:27:54.660Z,1770661674.660 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T18:27:54.660Z,1770661674.660 [Default:CheckIn:D] Running Loop=1
2026-02-09T18:27:55.049Z,1770661675.049 [Default:CheckIn:D] Stopped
2026-02-09T18:27:55.049Z,1770661675.049 [Default:CheckIn:E] Running Loop=1
2026-02-09T18:27:55.452Z,1770661675.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.976770 min
2026-02-09T18:27:55.452Z,1770661675.452 [Default:CheckIn:E] Stopped
2026-02-09T18:27:55.452Z,1770661675.452 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T18:27:55.452Z,1770661675.453 [Default:CheckIn] Stopped
2026-02-09T18:27:55.453Z,1770661675.453 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T18:27:55.453Z,1770661675.453 [Default:CheckIn](INFO): Running loop #7
2026-02-09T18:27:55.453Z,1770661675.453 [Default:CheckIn] Running Loop=7
2026-02-09T18:27:55.453Z,1770661675.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T18:27:55.453Z,1770661675.453 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T18:27:57.461Z,1770661677.461 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182756.00,A,3648.44399,N,12147.32391,W,15.454,235.77,090226,,,D*4D
2026-02-09T18:27:57.467Z,1770661677.467 [NAL9602](INFO): GPS fix at 20260209T182756: (36.807400, -121.788732)
2026-02-09T18:27:57.479Z,1770661677.479 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T18:27:57.479Z,1770661677.479 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T18:28:05.236Z,1770661685.236 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260209T175024/Courier0022.lzma
2026-02-09T18:28:06.238Z,1770661686.238 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Courier0022.lzma.bak
2026-02-09T18:28:06.239Z,1770661686.239 [DataOverHttps](INFO): SBD MOMSN=26641922
2026-02-09T18:28:22.563Z,1770661702.563 [NAL9602](INFO): SBD MO Status=2, MOMSN=545, MT Status=2, MTMSN=0
2026-02-09T18:28:22.563Z,1770661702.563 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T18:28:25.040Z,1770661705.040 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20260209T175024/Express0023.lzma
2026-02-09T18:28:26.042Z,1770661706.042 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0023.lzma.bak
2026-02-09T18:28:26.043Z,1770661706.043 [DataOverHttps](INFO): SBD MOMSN=26641925
2026-02-09T18:28:27.420Z,1770661707.420 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T18:28:27.420Z,1770661707.420 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T18:28:27.420Z,1770661707.420 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T18:28:31.855Z,1770661711.855 [NAL9602](INFO): SBD MO Status=0, MOMSN=545, MT Status=0, MTMSN=0
2026-02-09T18:28:31.855Z,1770661711.855 [NAL9602](INFO): No messages in MT queue
2026-02-09T18:29:02.562Z,1770661742.562 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T18:33:28.023Z,1770662008.023 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T18:33:28.023Z,1770662008.023 [Default:CheckIn:C.Wait] Stopped
2026-02-09T18:33:28.023Z,1770662008.023 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T18:33:28.023Z,1770662008.023 [Default:CheckIn:D] Running Loop=1
2026-02-09T18:33:28.438Z,1770662008.438 [Default:CheckIn:D] Stopped
2026-02-09T18:33:28.438Z,1770662008.438 [Default:CheckIn:E] Running Loop=1
2026-02-09T18:33:28.847Z,1770662008.847 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.533248 min
2026-02-09T18:33:28.847Z,1770662008.847 [Default:CheckIn:E] Stopped
2026-02-09T18:33:28.847Z,1770662008.847 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T18:33:28.847Z,1770662008.847 [Default:CheckIn] Stopped
2026-02-09T18:33:28.847Z,1770662008.847 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T18:33:28.848Z,1770662008.848 [Default:CheckIn](INFO): Running loop #8
2026-02-09T18:33:28.848Z,1770662008.848 [Default:CheckIn] Running Loop=8
2026-02-09T18:33:28.848Z,1770662008.848 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T18:33:28.848Z,1770662008.848 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T18:33:30.847Z,1770662010.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183330.00,A,3647.99016,N,12149.40536,W,16.017,260.35,090226,,,D*45
2026-02-09T18:33:30.850Z,1770662010.850 [NAL9602](INFO): GPS fix at 20260209T183330: (36.799836, -121.823423)
2026-02-09T18:33:30.861Z,1770662010.861 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T18:33:30.861Z,1770662010.861 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T18:33:39.860Z,1770662019.860 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T175024/Courier0025.lzma
2026-02-09T18:33:40.862Z,1770662020.862 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Courier0025.lzma.bak
2026-02-09T18:33:40.863Z,1770662020.863 [DataOverHttps](INFO): SBD MOMSN=26641935
2026-02-09T18:33:42.155Z,1770662022.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=546, MT Status=0, MTMSN=0
2026-02-09T18:33:42.156Z,1770662022.156 [NAL9602](INFO): No messages in MT queue
2026-02-09T18:33:56.726Z,1770662036.726 [DataOverHttps](INFO): Sending 878 bytes from file Logs/20260209T175024/Express0026.lzma
2026-02-09T18:33:57.726Z,1770662037.726 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0026.lzma.bak
2026-02-09T18:33:57.727Z,1770662037.727 [DataOverHttps](INFO): SBD MOMSN=26641938
2026-02-09T18:33:58.816Z,1770662038.816 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T18:33:58.817Z,1770662038.817 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T18:33:58.817Z,1770662038.817 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T18:34:12.857Z,1770662052.857 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T18:36:30.649Z,1770662190.649 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T18:37:01.774Z,1770662221.774 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T18:37:32.905Z,1770662252.905 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T18:38:04.037Z,1770662284.037 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T18:38:59.339Z,1770662339.339 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T18:38:59.339Z,1770662339.339 [Default:CheckIn:C.Wait] Stopped
2026-02-09T18:38:59.339Z,1770662339.339 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T18:38:59.339Z,1770662339.339 [Default:CheckIn:D] Running Loop=1
2026-02-09T18:38:59.743Z,1770662339.743 [Default:CheckIn:D] Stopped
2026-02-09T18:38:59.743Z,1770662339.743 [Default:CheckIn:E] Running Loop=1
2026-02-09T18:39:00.144Z,1770662340.144 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.054997 min
2026-02-09T18:39:00.144Z,1770662340.144 [Default:CheckIn:E] Stopped
2026-02-09T18:39:00.144Z,1770662340.144 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T18:39:00.161Z,1770662340.161 [Default:CheckIn] Stopped
2026-02-09T18:39:00.161Z,1770662340.161 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T18:39:00.161Z,1770662340.161 [Default:CheckIn](INFO): Running loop #9
2026-02-09T18:39:00.161Z,1770662340.161 [Default:CheckIn] Running Loop=9
2026-02-09T18:39:00.161Z,1770662340.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T18:39:00.161Z,1770662340.161 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T18:39:02.151Z,1770662342.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183901.00,A,3647.79287,N,12150.71688,W,0.447,82.64,090226,,,D*49
2026-02-09T18:39:02.153Z,1770662342.153 [NAL9602](INFO): GPS fix at 20260209T183901: (36.796548, -121.845281)
2026-02-09T18:39:02.164Z,1770662342.164 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T18:39:02.164Z,1770662342.164 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T18:39:10.124Z,1770662350.124 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T175024/Courier0028.lzma
2026-02-09T18:39:11.127Z,1770662351.127 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Courier0028.lzma.bak
2026-02-09T18:39:11.127Z,1770662351.127 [DataOverHttps](INFO): SBD MOMSN=26641948
2026-02-09T18:39:26.913Z,1770662366.913 [DataOverHttps](INFO): Sending 899 bytes from file Logs/20260209T175024/Express0029.lzma
2026-02-09T18:39:27.914Z,1770662367.914 [DataOverHttps](INFO): Moved sent file to Logs/20260209T175024/Express0029.lzma.bak
2026-02-09T18:39:27.915Z,1770662367.915 [DataOverHttps](INFO): SBD MOMSN=26641951
2026-02-09T18:39:28.017Z,1770662368.017 [NAL9602](INFO): SBD MO Status=2, MOMSN=547, MT Status=2, MTMSN=0
2026-02-09T18:39:28.017Z,1770662368.017 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T18:39:29.237Z,1770662369.237 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T18:39:29.237Z,1770662369.237 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T18:39:29.237Z,1770662369.237 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T18:40:03.159Z,1770662403.159 [NAL9602](INFO): SBD MO Status=0, MOMSN=547, MT Status=0, MTMSN=0
2026-02-09T18:40:03.159Z,1770662403.159 [NAL9602](INFO): No messages in MT queue
2026-02-09T18:40:33.858Z,1770662433.858 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T18:43:10.238Z,1770662590.238 [DataOverHttps](IMPORTANT): SBD MTMSN=20260209T184309
2026-02-09T18:43:17.697Z,1770662597.697 [DataOverHttps](INFO): Received command: run Maintenance/ballast_and_trim.tl
2026-02-09T18:43:17.793Z,1770662597.793 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/ballast_and_trim.tl
2026-02-09T18:43:17.793Z,1770662597.793 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/ballast_and_trim.tl
2026-02-09T18:43:17.794Z,1770662597.794 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Maintenance/ballast_and_trim.tl
2026-02-09T18:43:17.797Z,1770662597.797 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Maintenance/ballast_and_trim.tl
2026-02-09T18:43:18.709Z,1770662598.709 [DataOverHttps](IMPORTANT): SBD MTMSN=20260209T184317
2026-02-09T18:43:24.055Z,1770662604.055 [MissionManager](DEBUG): TethyslAPI: ./Missions/Maintenance/ballast_and_trim.tl translated into:
90
35
NaN
0.1
1
20
0.4
20
0
10
45
10
0.5
25
98.0
0.25
5
52
2
1
Aborting ballast and trim mission. Target depth Depth1 exceeds the mission's MaxDepth setting (review mission parameters).
stop
Aborting ballast and trim mission. Target depth Depth2 exceeds the mission's MaxDepth setting (review mission parameters).
stop
0
Checking for additional instructions before submerging.
2026-02-09T18:43:24.076Z,1770662604.076 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 90.000000 min
2026-02-09T18:43:24.083Z,1770662604.083 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2026-02-09T18:43:24.090Z,1770662604.090 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 35.000000 m
2026-02-09T18:43:24.097Z,1770662604.097 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2026-02-09T18:43:24.100Z,1770662604.100 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2026-02-09T18:43:24.108Z,1770662604.108 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSpeed = 1.000000 m/s
2026-02-09T18:43:24.147Z,1770662604.147 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthTimeout = 20.000000 min
2026-02-09T18:43:24.154Z,1770662604.154 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthRate = 0.400000 m/s
2026-02-09T18:43:24.162Z,1770662604.162 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachPitchLimit = 20.000000 arcdeg
2026-02-09T18:43:24.181Z,1770662604.181 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSettleTimePreDive = 0.000000 min
2026-02-09T18:43:24.184Z,1770662604.184 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2026-02-09T18:43:24.195Z,1770662604.195 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2026-02-09T18:43:24.226Z,1770662604.226 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2026-02-09T18:43:24.237Z,1770662604.237 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.500000 mm
2026-02-09T18:43:24.240Z,1770662604.240 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 25.000000 cc
2026-02-09T18:43:24.252Z,1770662604.252 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 98.000000 %
2026-02-09T18:43:24.259Z,1770662604.259 [MissionManager](INFO): DefineArg ballast_and_trim.massDeadband = 0.250000 mm
2026-02-09T18:43:24.278Z,1770662604.278 [MissionManager](INFO): DefineArg ballast_and_trim.MinAltitude = 5.000000 m
2026-02-09T18:43:24.289Z,1770662604.289 [MissionManager](INFO): DefineArg ballast_and_trim.MaxDepth = 52.000000 m
2026-02-09T18:43:24.292Z,1770662604.292 [MissionManager](INFO): DefineArg ballast_and_trim.MinOffshore = 2.000000 km
2026-02-09T18:43:24.307Z,1770662604.307 [MissionManager](INFO): DefineArg ballast_and_trim.CheckDepths = 1.000000 bool
2026-02-09T18:43:24.308Z,1770662604.308 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2026-02-09T18:43:24.308Z,1770662604.308 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl
2026-02-09T18:43:24.382Z,1770662604.382 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl
2026-02-09T18:43:29.968Z,1770662609.968 [DataOverHttps](INFO): Received command: restart logs
2026-02-09T18:43:30.771Z,1770662610.771 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into:
NaN
3
100
80
20
5
NaN
NaN
NaN
2.0
1
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
0
1
NaN
NaN
5
0
0
0
0
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
PeakDetectVsDepth
PeakChlAvgProfiles =
. PeakChlDepthAvgProfiles =
EdgeDetectVsDepth
GoDown, ChlPeakThresh, PeakChl, PeakChlDepth, EdgeChl, EdgeChlDepth =
,
,
,
,
,
PeakDetectHorizontal
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
_.planktivore_LM_AvgRois
PeakPlanktivoreLMavgROIAvgProfiles =
. PeakPlanktivoreLMavgROIDepthAvgProfiles =
EdgeDetectVsDepth
_.planktivore_LM_AvgRois
GoDown, PlanktivoreLMavgROIPeakThresh, PeakPlanktivoreLMavgROI, PeakPlanktivoreLMavgROIDepth, EdgePlanktivoreLMavgROI, EdgePlanktivoreLMavgROIDepth =
,
,
,
,
,
PeakDetectHorizontal
PeakDetectVsDepth
_.planktivore_HM_AvgRois
EdgeDetectVsDepth
_.planktivore_HM_AvgRois
GoDown, PlanktivoreHMavgROIPeakThresh, PeakPlanktivoreHMavgROI, PeakPlanktivoreHMavgROIDepth, EdgePlanktivoreHMavgROI, EdgePlanktivoreHMavgROIDepth =
,
,
,
,
,
PeakDetectVsDepth
_.planktivore_diatoms
EdgeDetectVsDepth
_.planktivore_diatoms
GoDown, PlanktivoreDiatomsPeakThresh, PeakPlanktivoreDiatoms, PeakPlanktivoreDiatomsDepth, EdgePlanktivoreDiatoms, EdgePlanktivoreDiatomsDepth =
,
,
,
,
,
PeakDetectVsDepth
_.planktivore_dinoflagellates
EdgeDetectVsDepth
_.planktivore_dinoflagellates
GoDown, PlanktivoreDinoflagellatesPeakThresh, PeakPlanktivoreDinoflagellates, PeakPlanktivoreDinoflagellatesDepth, EdgePlanktivoreDinoflagellates, EdgePlanktivoreDinoflagellatesDepth =
,
,
,
,
,
EdgeDetectVsDepth
2
_.planktivore_LM_AvgRois
_.planktivore_HM_AvgRois
GoDown, PlanktivoreLMavgROIPeakThresh, PeakPlanktivoreLMavgROI, PeakPlanktivoreLMavgROIDepth, EdgePlanktivoreLMavgROI, EdgePlanktivoreLMavgROIDepth =
,
,
,
,
,
PlanktivoreHMavgROIPeakThresh, PeakPlanktivoreHMavgROI, PeakPlanktivoreHMavgROIDepth, EdgePlanktivoreHMavgROI, EdgePlanktivoreHMavgROIDepth =
,
,
,
,
Triggered. PeakChl values on
consecutive yo-yo profiles fall in [
] (NaN bound is ignored).
2026-02-09T18:43:30.862Z,1770662610.862 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2026-02-09T18:43:30.869Z,1770662610.869 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDepthAvgProfilesChlActive = 0 bool
2026-02-09T18:43:30.871Z,1770662610.871 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectChlActive = 0 bool
2026-02-09T18:43:30.876Z,1770662610.876 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2026-02-09T18:43:30.879Z,1770662610.879 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2026-02-09T18:43:30.882Z,1770662610.882 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2026-02-09T18:43:30.885Z,1770662610.885 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2026-02-09T18:43:30.888Z,1770662610.888 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2026-02-09T18:43:30.891Z,1770662610.891 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2026-02-09T18:43:30.914Z,1770662610.914 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2026-02-09T18:43:30.921Z,1770662610.921 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2026-02-09T18:43:30.924Z,1770662610.924 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2026-02-09T18:43:30.931Z,1770662610.931 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2026-02-09T18:43:30.936Z,1770662610.936 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2026-02-09T18:43:30.947Z,1770662610.947 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2026-02-09T18:43:30.950Z,1770662610.950 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectPlanktivoreLMavgROIActive = 0 bool
2026-02-09T18:43:30.957Z,1770662610.957 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDepthAvgProfilesPlanktivoreLMavgROIActive = 0 bool
2026-02-09T18:43:30.960Z,1770662610.960 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectPlanktivoreLMavgROIActive = 0 bool
2026-02-09T18:43:30.967Z,1770662610.967 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectPlanktivoreHMavgROIActive = 0 bool
2026-02-09T18:43:30.978Z,1770662610.978 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectPlanktivoreHMavgROIActive = 0 bool
2026-02-09T18:43:30.985Z,1770662610.985 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectPlanktivoreDiatomsActive = 0 bool
2026-02-09T18:43:30.988Z,1770662610.988 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectPlanktivoreDiatomsActive = 0 bool
2026-02-09T18:43:30.991Z,1770662610.991 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectPlanktivoreDinoflagellatesActive = 0 bool
2026-02-09T18:43:31.010Z,1770662611.010 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectPlanktivoreDinoflagellatesActive = 0 bool
2026-02-09T18:43:31.037Z,1770662611.037 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2026-02-09T18:43:31.040Z,1770662611.040 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2026-02-09T18:43:31.056Z,1770662611.056 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2026-02-09T18:43:31.063Z,1770662611.063 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledRBRTridente = 0 bool
2026-02-09T18:43:31.079Z,1770662611.079 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2026-02-09T18:43:31.087Z,1770662611.087 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2026-02-09T18:43:31.103Z,1770662611.103 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2026-02-09T18:43:31.111Z,1770662611.111 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2026-02-09T18:43:31.130Z,1770662611.130 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2026-02-09T18:43:31.139Z,1770662611.139 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2026-02-09T18:43:31.151Z,1770662611.151 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ShallowBound = nan m
2026-02-09T18:43:31.155Z,1770662611.155 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DeepBound = nan m
2026-02-09T18:43:31.170Z,1770662611.170 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DeepThreshValidLeg = nan m
2026-02-09T18:43:31.185Z,1770662611.185 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2026-02-09T18:43:31.188Z,1770662611.188 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumberOfVariablesEdgeDetect = 1.000000 count
2026-02-09T18:43:31.241Z,1770662611.241 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalAscentChl = nan %
2026-02-09T18:43:31.244Z,1770662611.244 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalAscentPlanktivoreLMavgROI = nan %
2026-02-09T18:43:31.256Z,1770662611.256 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalAscentPlanktivoreHMavgROI = nan %
2026-02-09T18:43:31.271Z,1770662611.271 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalAscentPlanktivoreDiatoms = nan %
2026-02-09T18:43:31.274Z,1770662611.274 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalAscentPlanktivoreDinoflagellates = nan %
2026-02-09T18:43:31.289Z,1770662611.289 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalDescentChl = nan %
2026-02-09T18:43:31.297Z,1770662611.297 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalDescentPlanktivoreLMavgROI = nan %
2026-02-09T18:43:31.300Z,1770662611.300 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalDescentPlanktivoreHMavgROI = nan %
2026-02-09T18:43:31.307Z,1770662611.307 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalDescentPlanktivoreDiatoms = nan %
2026-02-09T18:43:31.314Z,1770662611.314 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVerticalDescentPlanktivoreDinoflagellates = nan %
2026-02-09T18:43:31.330Z,1770662611.330 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlPeakThresh = 0.000000 ug/l
2026-02-09T18:43:31.336Z,1770662611.336 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumberOfLayers = 1.000000 count
2026-02-09T18:43:31.356Z,1770662611.356 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool
2026-02-09T18:43:31.359Z,1770662611.359 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l
2026-02-09T18:43:31.370Z,1770662611.370 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l
2026-02-09T18:43:31.374Z,1770662611.374 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count
2026-02-09T18:43:31.385Z,1770662611.385 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PlanktivoreLMavgROIPeakThresh = 0.000000 count/s
2026-02-09T18:43:31.393Z,1770662611.393 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PlanktivoreHMavgROIPeakThresh = 0.000000 count/s
2026-02-09T18:43:31.396Z,1770662611.396 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PlanktivoreDiatomsPeakThresh = 0.000000 count/ml
2026-02-09T18:43:31.403Z,1770662611.403 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PlanktivoreDinoflagellatesPeakThresh = 0.000000 count/ml
2026-02-09T18:43:31.407Z,1770662611.407 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.GoDown = 1 bool
2026-02-09T18:43:31.429Z,1770662611.429 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l
2026-02-09T18:43:31.437Z,1770662611.437 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m
2026-02-09T18:43:31.440Z,1770662611.440 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC
2026-02-09T18:43:31.447Z,1770662611.447 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2026-02-09T18:43:31.459Z,1770662611.459 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2026-02-09T18:43:31.462Z,1770662611.462 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlAvgProfiles = nan ug/l
2026-02-09T18:43:31.473Z,1770662611.473 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepthAvgProfiles = nan m
2026-02-09T18:43:31.481Z,1770662611.481 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgeChl = nan ug/l
2026-02-09T18:43:31.484Z,1770662611.484 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgeChlDepth = nan m
2026-02-09T18:43:31.492Z,1770662611.492 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l
2026-02-09T18:43:31.500Z,1770662611.500 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m
2026-02-09T18:43:31.503Z,1770662611.503 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2026-02-09T18:43:31.514Z,1770662611.514 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2026-02-09T18:43:31.517Z,1770662611.517 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3
2026-02-09T18:43:31.520Z,1770662611.520 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m
2026-02-09T18:43:31.523Z,1770662611.523 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2026-02-09T18:43:31.545Z,1770662611.545 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2026-02-09T18:43:31.548Z,1770662611.548 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb
2026-02-09T18:43:31.551Z,1770662611.551 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m
2026-02-09T18:43:31.566Z,1770662611.566 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2026-02-09T18:43:31.576Z,1770662611.576 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2026-02-09T18:43:31.579Z,1770662611.579 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2026-02-09T18:43:31.582Z,1770662611.582 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu
2026-02-09T18:43:31.602Z,1770662611.602 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m
2026-02-09T18:43:31.633Z,1770662611.633 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC
2026-02-09T18:43:31.636Z,1770662611.636 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2026-02-09T18:43:31.639Z,1770662611.639 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2026-02-09T18:43:31.660Z,1770662611.659 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROI = nan count/s
2026-02-09T18:43:31.663Z,1770662611.663 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROIDepth = nan m
2026-02-09T18:43:31.682Z,1770662611.682 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROITemperature = nan degC
2026-02-09T18:43:31.689Z,1770662611.689 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROILatitude = nan arcdeg
2026-02-09T18:43:31.697Z,1770662611.697 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROILongitude = nan arcdeg
2026-02-09T18:43:31.700Z,1770662611.700 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROIAvgProfiles = nan count/s
2026-02-09T18:43:31.707Z,1770662611.707 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROIDepthAvgProfiles = nan m
2026-02-09T18:43:31.722Z,1770662611.722 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreLMavgROI = nan count/s
2026-02-09T18:43:31.729Z,1770662611.729 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreLMavgROIDepth = nan m
2026-02-09T18:43:31.737Z,1770662611.737 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROI = nan count/s
2026-02-09T18:43:31.742Z,1770662611.742 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROIDepth = nan m
2026-02-09T18:43:31.745Z,1770662611.745 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROITemperature = nan degC
2026-02-09T18:43:31.753Z,1770662611.753 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROILatitude = nan arcdeg
2026-02-09T18:43:31.756Z,1770662611.756 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROILongitude = nan arcdeg
2026-02-09T18:43:31.763Z,1770662611.763 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreHMavgROI = nan count/s
2026-02-09T18:43:31.767Z,1770662611.767 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreHMavgROIDepth = nan m
2026-02-09T18:43:31.782Z,1770662611.782 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatoms = nan count/ml
2026-02-09T18:43:31.785Z,1770662611.785 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatomsDepth = nan m
2026-02-09T18:43:31.788Z,1770662611.788 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatomsTemperature = nan degC
2026-02-09T18:43:31.796Z,1770662611.796 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatomsLatitude = nan arcdeg
2026-02-09T18:43:31.803Z,1770662611.803 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatomsLongitude = nan arcdeg
2026-02-09T18:43:31.807Z,1770662611.807 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDiatoms = nan count/ml
2026-02-09T18:43:31.828Z,1770662611.828 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDiatomsDepth = nan m
2026-02-09T18:43:31.844Z,1770662611.844 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellates = nan count/ml
2026-02-09T18:43:31.851Z,1770662611.851 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellatesDepth = nan m
2026-02-09T18:43:31.865Z,1770662611.865 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellatesTemperature = nan degC
2026-02-09T18:43:31.869Z,1770662611.869 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellatesLatitude = nan arcdeg
2026-02-09T18:43:31.872Z,1770662611.872 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellatesLongitude = nan arcdeg
2026-02-09T18:43:31.879Z,1770662611.879 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDinoflagellates = nan count/ml
2026-02-09T18:43:31.884Z,1770662611.884 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDinoflagellatesDepth = nan m
2026-02-09T18:43:31.900Z,1770662611.900 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l
2026-02-09T18:43:31.907Z,1770662611.907 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m
2026-02-09T18:43:31.912Z,1770662611.912 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2026-02-09T18:43:31.927Z,1770662611.927 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2026-02-09T18:43:31.953Z,1770662611.953 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2026-02-09T18:43:31.956Z,1770662611.956 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu
2026-02-09T18:43:31.964Z,1770662611.964 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m
2026-02-09T18:43:31.971Z,1770662611.971 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2026-02-09T18:43:31.979Z,1770662611.979 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2026-02-09T18:43:31.982Z,1770662611.982 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2026-02-09T18:43:31.994Z,1770662611.994 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu
2026-02-09T18:43:31.999Z,1770662611.999 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m
2026-02-09T18:43:32.042Z,1770662612.042 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2026-02-09T18:43:32.051Z,1770662612.051 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2026-02-09T18:43:32.067Z,1770662612.067 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2026-02-09T18:43:32.079Z,1770662612.079 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool
2026-02-09T18:43:32.090Z,1770662612.090 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l
2026-02-09T18:43:32.173Z,1770662612.173 [ballast_and_trim:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.215Z,1770662612.215 [ballast_and_trim:Science:EdgeDetectChl:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2026-02-09T18:43:32.268Z,1770662612.268 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2026-02-09T18:43:32.307Z,1770662612.307 [ballast_and_trim:Science:PeakDepthAvgProfilesChl:B.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2026-02-09T18:43:32.334Z,1770662612.334 [ballast_and_trim:Science:PeakDetectNO3:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.370Z,1770662612.370 [ballast_and_trim:Science:PeakDetectOil:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.397Z,1770662612.397 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2026-02-09T18:43:32.465Z,1770662612.465 [ballast_and_trim:Science:PeakDetectFDOM:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.514Z,1770662612.514 [ballast_and_trim:Science:PeakDetectSalinity:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.544Z,1770662612.544 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2026-02-09T18:43:32.575Z,1770662612.575 [ballast_and_trim:Science:PeakDetectPlanktivoreLMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.613Z,1770662612.613 [ballast_and_trim:Science:EdgeDetectPlanktivoreLMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2026-02-09T18:43:32.651Z,1770662612.651 [ballast_and_trim:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI:B.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2026-02-09T18:43:32.675Z,1770662612.675 [ballast_and_trim:Science:PeakDetectPlanktivoreHMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.715Z,1770662612.715 [ballast_and_trim:Science:EdgeDetectPlanktivoreHMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2026-02-09T18:43:32.754Z,1770662612.754 [ballast_and_trim:Science:PeakDetectPlanktivoreDiatoms:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.777Z,1770662612.777 [ballast_and_trim:Science:EdgeDetectPlanktivoreDiatoms:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2026-02-09T18:43:32.873Z,1770662612.873 [ballast_and_trim:Science:PeakDetectPlanktivoreDinoflagellates:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2026-02-09T18:43:32.895Z,1770662612.895 [ballast_and_trim:Science:EdgeDetectPlanktivoreDinoflagellates:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2026-02-09T18:43:32.940Z,1770662612.940 [ballast_and_trim:Science:EdgeDetectPlanktivoreLMavgROIPlanktivoreHMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2026-02-09T18:43:32.990Z,1770662612.990 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2026-02-09T18:43:33.007Z,1770662613.007 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2026-02-09T18:43:33.007Z,1770662613.007 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2026-02-09T18:43:33.013Z,1770662613.013 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2026-02-09T18:43:33.654Z,1770662613.654 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1500
7
30
0
0
0
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2026-02-09T18:43:33.677Z,1770662613.677 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.DiveInterval = 1.000000 h
2026-02-09T18:43:33.680Z,1770662613.680 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.WaitForPitchUp = 10.000000 min
2026-02-09T18:43:33.704Z,1770662613.704 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacePitch = 20.000000 arcdeg
2026-02-09T18:43:33.707Z,1770662613.707 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceDepthRate = nan m/s
2026-02-09T18:43:33.711Z,1770662613.711 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceSpeed = 1.000000 m/s
2026-02-09T18:43:33.722Z,1770662613.722 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacingTimeout = 1500.000000 s
2026-02-09T18:43:33.729Z,1770662613.729 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.GPSTimeout = 7.000000 min
2026-02-09T18:43:33.732Z,1770662613.732 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.CommsTimeout = 30.000000 min
2026-02-09T18:43:33.745Z,1770662613.745 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.DoingCommsMode = 0.000000 count
2026-02-09T18:43:33.748Z,1770662613.748 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.RestoreMode = 0.000000 count
2026-02-09T18:43:33.758Z,1770662613.758 [MissionManager](INFO): DefineOutput ballast_and_trim:NeedComms.IsActive = 0 bool
2026-02-09T18:43:33.769Z,1770662613.769 [MissionManager](INFO): DefineOutput ballast_and_trim:NeedComms.RequiredDiveMode = 0.000000 count
2026-02-09T18:43:33.789Z,1770662613.789 [ballast_and_trim:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface.
2026-02-09T18:43:33.854Z,1770662613.854 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2026-02-09T18:43:33.854Z,1770662613.854 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2026-02-09T18:43:33.855Z,1770662613.855 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2026-02-09T18:43:33.994Z,1770662613.994 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2026-02-09T18:43:33.000Z,1770662614.000 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinAltitude = 5.000000 m
2026-02-09T18:43:34.003Z,1770662614.003 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2026-02-09T18:43:34.006Z,1770662614.006 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MaxDepth = 200.000000 m
2026-02-09T18:43:34.017Z,1770662614.017 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinOffshore = 2000.000000 m
2026-02-09T18:43:34.019Z,1770662614.019 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2026-02-09T18:43:34.070Z,1770662614.070 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2026-02-09T18:43:34.096Z,1770662614.096 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2026-02-09T18:43:34.132Z,1770662614.132 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2026-02-09T18:43:34.132Z,1770662614.132 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2026-02-09T18:43:34.133Z,1770662614.133 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2026-02-09T18:43:34.173Z,1770662614.173 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2026-02-09T18:43:34.181Z,1770662614.181 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool
2026-02-09T18:43:34.634Z,1770662614.634 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2026-02-09T18:43:34.647Z,1770662614.647 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2026-02-09T18:43:34.647Z,1770662614.647 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2026-02-09T18:43:34.657Z,1770662614.657 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2026-02-09T18:43:34.918Z,1770662614.918 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2026-02-09T18:43:34.927Z,1770662614.927 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleAll = 0 bool
2026-02-09T18:43:34.931Z,1770662614.931 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad1 = 0 bool
2026-02-09T18:43:34.943Z,1770662614.943 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad2 = 0 bool
2026-02-09T18:43:34.946Z,1770662614.946 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad3 = 0 bool
2026-02-09T18:43:34.957Z,1770662614.957 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.EnabledPowerOnly = 0 bool
2026-02-09T18:43:34.974Z,1770662614.974 [ballast_and_trim:PowerOnly:E.Wait](DEBUG): Construct Wait.
2026-02-09T18:43:34.983Z,1770662614.983 [ballast_and_trim:CheckTargetDepths:CheckDepth1:B.Execute](DEBUG): Construct Execute.
2026-02-09T18:43:34.992Z,1770662614.992 [ballast_and_trim:CheckTargetDepths:CheckDepth2:B.Execute](DEBUG): Construct Execute.
2026-02-09T18:43:35.007Z,1770662615.007 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl
2026-02-09T18:43:35.007Z,1770662615.007 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BallastAndTrim.tl
2026-02-09T18:43:35.008Z,1770662615.008 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BallastAndTrim.tl
2026-02-09T18:43:35.693Z,1770662615.693 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BallastAndTrim.tl translated into:
25
0
0.4
20
0
20
10
45
10
0.25
15
99.9
1
0.25
0.005
0.00075
0
Going to target depth. Speed set to
0
13
0
Waiting for
while pumping down to neutral.
Moving to
Timed out trying to reach the target depth. Stopping mission at current depth of
stop
0
Waiting for the vehicle to settle. Depth =
gfscan
Running ballast and trim. Depth =
2026-02-09T18:43:35.729Z,1770662615.729 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2026-02-09T18:43:35.732Z,1770662615.732 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2026-02-09T18:43:35.735Z,1770662615.735 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2026-02-09T18:43:35.738Z,1770662615.738 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2026-02-09T18:43:35.742Z,1770662615.742 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2026-02-09T18:43:35.745Z,1770662615.745 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2026-02-09T18:43:35.748Z,1770662615.748 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2026-02-09T18:43:35.767Z,1770662615.767 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2026-02-09T18:43:35.783Z,1770662615.783 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2026-02-09T18:43:35.786Z,1770662615.786 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2026-02-09T18:43:35.793Z,1770662615.793 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2026-02-09T18:43:35.801Z,1770662615.801 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2026-02-09T18:43:35.804Z,1770662615.804 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2026-02-09T18:43:35.811Z,1770662615.811 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2026-02-09T18:43:35.848Z,1770662615.848 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2026-02-09T18:43:35.859Z,1770662615.859 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2026-02-09T18:43:35.871Z,1770662615.871 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2026-02-09T18:43:35.894Z,1770662615.894 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2026-02-09T18:43:35.902Z,1770662615.902 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2026-02-09T18:43:35.910Z,1770662615.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2026-02-09T18:43:35.931Z,1770662615.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2026-02-09T18:43:35.939Z,1770662615.939 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2026-02-09T18:43:35.946Z,1770662615.946 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2026-02-09T18:43:35.966Z,1770662615.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2026-02-09T18:43:35.968Z,1770662615.968 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute.
2026-02-09T18:43:35.988Z,1770662615.988 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.