2025-06-30T14:52:43.685Z,1751295163.685 [Supervisor](DEBUG): Initializing supervisor.
2025-06-30T14:52:43.689Z,1751295163.689 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-06-30T14:52:43.690Z,1751295163.690 [SyncHandler](INFO): Protected caller Thread ID is 8140
2025-06-30T14:52:43.690Z,1751295163.690 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-06-30T14:52:43.691Z,1751295163.691 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-06-30T14:52:43.692Z,1751295163.692 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8141
2025-06-30T14:52:43.696Z,1751295163.696 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-06-30T14:52:43.716Z,1751295163.716 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-06-30T14:52:43.717Z,1751295163.717 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-06-30T14:52:43.718Z,1751295163.718 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8142
2025-06-30T14:52:43.722Z,1751295163.722 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-06-30T14:52:43.723Z,1751295163.723 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-06-30T14:52:43.724Z,1751295163.724 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8143
2025-06-30T14:52:43.726Z,1751295163.726 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-06-30T14:52:43.727Z,1751295163.727 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-06-30T14:52:43.727Z,1751295163.727 [logger ThreadHandler](INFO): Protected caller Thread ID is 8144
2025-06-30T14:52:43.731Z,1751295163.731 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-06-30T14:52:43.731Z,1751295163.731 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-06-30T14:52:43.733Z,1751295163.733 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-06-30T14:52:43.955Z,1751295163.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-06-30T14:52:43.956Z,1751295163.956 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-06-30T14:52:44.072Z,1751295164.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-06-30T14:52:44.073Z,1751295164.073 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-06-30T14:52:44.178Z,1751295164.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-06-30T14:52:44.179Z,1751295164.179 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-06-30T14:52:44.689Z,1751295164.689 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-06-30T14:52:44.689Z,1751295164.689 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-06-30T14:52:45.058Z,1751295165.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-06-30T14:52:45.058Z,1751295165.058 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-06-30T14:52:45.416Z,1751295165.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-06-30T14:52:45.417Z,1751295165.417 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-06-30T14:52:45.612Z,1751295165.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-06-30T14:52:45.613Z,1751295165.613 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-06-30T14:52:45.703Z,1751295165.703 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-06-30T14:52:45.804Z,1751295165.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-06-30T14:52:45.804Z,1751295165.804 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-06-30T14:52:46.232Z,1751295166.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-06-30T14:52:46.233Z,1751295166.233 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-06-30T14:52:46.527Z,1751295166.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-06-30T14:52:46.528Z,1751295166.528 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-06-30T14:52:47.101Z,1751295167.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-06-30T14:52:47.102Z,1751295167.102 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-06-30T14:52:47.353Z,1751295167.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-06-30T14:52:47.353Z,1751295167.353 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-06-30T14:52:47.627Z,1751295167.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-06-30T14:52:47.627Z,1751295167.627 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-06-30T14:52:47.781Z,1751295167.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-06-30T14:52:47.781Z,1751295167.781 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-06-30T14:52:49.397Z,1751295169.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-06-30T14:52:49.399Z,1751295169.399 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2025-06-30T14:52:49.400Z,1751295169.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2025-06-30T14:52:49.494Z,1751295169.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2025-06-30T14:52:49.633Z,1751295169.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2025-06-30T14:52:49.916Z,1751295169.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-06-30T14:52:49.917Z,1751295169.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2025-06-30T14:52:50.053Z,1751295170.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2025-06-30T14:52:50.160Z,1751295170.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2025-06-30T14:52:50.264Z,1751295170.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2025-06-30T14:52:50.384Z,1751295170.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2025-06-30T14:52:50.478Z,1751295170.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2025-06-30T14:52:50.575Z,1751295170.575 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2025-06-30T14:52:50.677Z,1751295170.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2025-06-30T14:52:50.858Z,1751295170.858 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-06-30T14:52:50.868Z,1751295170.868 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-06-30T14:52:50.922Z,1751295170.922 [DepthRateCalculator] Loaded
2025-06-30T14:52:50.922Z,1751295170.922 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-06-30T14:52:50.928Z,1751295170.928 [PitchRateCalculator] Loaded
2025-06-30T14:52:50.928Z,1751295170.928 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-06-30T14:52:50.939Z,1751295170.939 [SpeedCalculator] Loaded
2025-06-30T14:52:50.939Z,1751295170.939 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-06-30T14:52:50.944Z,1751295170.944 [YawRateCalculator] Loaded
2025-06-30T14:52:50.945Z,1751295170.945 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-06-30T14:52:50.967Z,1751295170.967 [ElevatorOffsetCalculator] Loaded
2025-06-30T14:52:50.967Z,1751295170.967 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-06-30T14:52:50.968Z,1751295170.968 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-06-30T14:52:50.968Z,1751295170.968 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-06-30T14:52:50.998Z,1751295170.998 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-06-30T14:52:50.999Z,1751295170.999 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-06-30T14:52:51.009Z,1751295171.009 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-06-30T14:52:51.010Z,1751295171.010 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-06-30T14:52:51.523Z,1751295171.523 [AHRS_M2] Loaded
2025-06-30T14:52:51.523Z,1751295171.523 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-06-30T14:52:51.563Z,1751295171.563 [BackseatComponent] Loaded
2025-06-30T14:52:51.563Z,1751295171.563 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-06-30T14:52:51.564Z,1751295171.564 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0
2025-06-30T14:52:51.565Z,1751295171.565 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8226
2025-06-30T14:52:51.568Z,1751295171.568 [LcmUniversalReporter] Loaded
2025-06-30T14:52:51.568Z,1751295171.568 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-06-30T14:52:51.668Z,1751295171.668 [DAT] Loaded
2025-06-30T14:52:51.668Z,1751295171.668 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-06-30T14:52:51.669Z,1751295171.669 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0
2025-06-30T14:52:51.669Z,1751295171.669 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8227
2025-06-30T14:52:51.744Z,1751295171.744 [DataOverHttps] Loaded
2025-06-30T14:52:51.744Z,1751295171.744 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-06-30T14:52:51.745Z,1751295171.745 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0
2025-06-30T14:52:51.746Z,1751295171.746 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8228
2025-06-30T14:52:51.769Z,1751295171.769 [Depth_Keller] Loaded
2025-06-30T14:52:51.770Z,1751295171.770 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-06-30T14:52:51.775Z,1751295171.775 [DropWeight] Loaded
2025-06-30T14:52:51.775Z,1751295171.775 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-06-30T14:52:51.796Z,1751295171.796 [MultiRay](INFO): Found secondary power supply at: /dev/loadC7
2025-06-30T14:52:51.796Z,1751295171.796 [MultiRay] Loaded
2025-06-30T14:52:51.797Z,1751295171.797 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2025-06-30T14:52:51.862Z,1751295171.862 [NAL9602] Loaded
2025-06-30T14:52:51.862Z,1751295171.862 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-06-30T14:52:51.893Z,1751295171.893 [Onboard] Loaded
2025-06-30T14:52:51.893Z,1751295171.893 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-06-30T14:52:51.894Z,1751295171.894 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2025-06-30T14:52:51.894Z,1751295171.894 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8229
2025-06-30T14:52:51.900Z,1751295171.900 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-06-30T14:52:51.914Z,1751295171.914 [PowerOnly] Loaded
2025-06-30T14:52:51.914Z,1751295171.914 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-06-30T14:52:51.928Z,1751295171.928 [Power24vConverter] Loaded
2025-06-30T14:52:51.928Z,1751295171.928 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-06-30T14:52:51.946Z,1751295171.946 [Radio_Surface] Loaded
2025-06-30T14:52:51.946Z,1751295171.946 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-06-30T14:52:51.947Z,1751295171.947 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2025-06-30T14:52:51.947Z,1751295171.947 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8230
2025-06-30T14:52:51.948Z,1751295171.948 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-06-30T14:52:51.949Z,1751295171.949 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-06-30T14:52:52.063Z,1751295172.063 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-06-30T14:52:52.064Z,1751295172.064 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-06-30T14:52:52.078Z,1751295172.078 [NavChart] Loaded
2025-06-30T14:52:52.078Z,1751295172.078 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-06-30T14:52:52.084Z,1751295172.084 [UniversalFixResidualReporter] Loaded
2025-06-30T14:52:52.084Z,1751295172.084 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-06-30T14:52:52.085Z,1751295172.085 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-06-30T14:52:52.085Z,1751295172.085 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-06-30T14:52:52.250Z,1751295172.250 [SBIT](DEBUG): Construct Startup Built In Test.
2025-06-30T14:52:52.265Z,1751295172.265 [SBIT] Loaded
2025-06-30T14:52:52.265Z,1751295172.265 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-06-30T14:52:52.268Z,1751295172.268 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-06-30T14:52:52.282Z,1751295172.282 [IBIT] Loaded
2025-06-30T14:52:52.282Z,1751295172.282 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-06-30T14:52:52.288Z,1751295172.288 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-06-30T14:52:52.494Z,1751295172.494 [CBIT] Loaded
2025-06-30T14:52:52.494Z,1751295172.494 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-06-30T14:52:52.526Z,1751295172.526 [GFScanner] Loaded
2025-06-30T14:52:52.527Z,1751295172.527 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-06-30T14:52:52.527Z,1751295172.527 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-06-30T14:52:52.528Z,1751295172.528 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-06-30T14:52:52.705Z,1751295172.705 [CTD_Seabird] Loaded
2025-06-30T14:52:52.705Z,1751295172.705 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-06-30T14:52:52.706Z,1751295172.706 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4B4E0
2025-06-30T14:52:52.707Z,1751295172.707 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8231
2025-06-30T14:52:52.730Z,1751295172.730 [PAR_Licor] Loaded
2025-06-30T14:52:52.730Z,1751295172.730 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-06-30T14:52:52.776Z,1751295172.776 [WetLabsBB2FL] Loaded
2025-06-30T14:52:52.776Z,1751295172.776 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-06-30T14:52:52.777Z,1751295172.777 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7B4E0
2025-06-30T14:52:52.778Z,1751295172.778 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8232
2025-06-30T14:52:52.778Z,1751295172.778 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-06-30T14:52:52.779Z,1751295172.779 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-06-30T14:52:52.923Z,1751295172.923 [BuoyancyServo] Loaded
2025-06-30T14:52:52.923Z,1751295172.923 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-06-30T14:52:52.948Z,1751295172.948 [ElevatorServo] Loaded
2025-06-30T14:52:52.948Z,1751295172.948 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-06-30T14:52:52.972Z,1751295172.972 [MassServo] Loaded
2025-06-30T14:52:52.972Z,1751295172.972 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-06-30T14:52:52.995Z,1751295172.995 [RudderServo] Loaded
2025-06-30T14:52:52.995Z,1751295172.995 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-06-30T14:52:53.014Z,1751295173.014 [ThrusterHE] Loaded
2025-06-30T14:52:53.014Z,1751295173.014 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-06-30T14:52:53.014Z,1751295173.014 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-06-30T14:52:53.015Z,1751295173.015 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-06-30T14:52:53.073Z,1751295173.073 [VerticalControl](DEBUG): Construct VerticalControl.
2025-06-30T14:52:53.126Z,1751295173.126 [VerticalControl] Loaded
2025-06-30T14:52:53.126Z,1751295173.126 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-06-30T14:52:53.129Z,1751295173.129 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-06-30T14:52:53.175Z,1751295173.175 [HorizontalControl] Loaded
2025-06-30T14:52:53.176Z,1751295173.176 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-06-30T14:52:53.178Z,1751295173.178 [SpeedControl](DEBUG): Construct SpeedControl.
2025-06-30T14:52:53.180Z,1751295173.180 [SpeedControl] Loaded
2025-06-30T14:52:53.180Z,1751295173.180 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-06-30T14:52:53.183Z,1751295173.183 [LoopControl](DEBUG): Construct LoopControl.
2025-06-30T14:52:53.184Z,1751295173.184 [LoopControl] Loaded
2025-06-30T14:52:53.184Z,1751295173.184 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-06-30T14:52:53.184Z,1751295173.184 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-06-30T14:52:53.185Z,1751295173.185 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-06-30T14:52:53.323Z,1751295173.323 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-06-30T14:52:53.324Z,1751295173.324 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-06-30T14:52:53.512Z,1751295173.512 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-06-30T14:52:53.512Z,1751295173.512 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-06-30T14:52:53.866Z,1751295173.866 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-06-30T14:52:53.867Z,1751295173.867 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-06-30T14:52:53.927Z,1751295173.927 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-06-30T14:52:53.934Z,1751295173.934 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-06-30T14:52:53.937Z,1751295173.937 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-06-30T14:52:53.949Z,1751295173.949 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-06-30T14:52:53.950Z,1751295173.950 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2025-06-30T14:52:53.950Z,1751295173.950 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8233
2025-06-30T14:52:53.955Z,1751295173.955 [Supervisor](INFO): Main Thread ID is 829
2025-06-30T14:52:53.955Z,1751295173.955 [Supervisor](DEBUG): Running supervisor.
2025-06-30T14:52:53.955Z,1751295173.955 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8234
2025-06-30T14:52:53.956Z,1751295173.956 [CommandExec](INFO): Initializing the command executive.
2025-06-30T14:52:53.958Z,1751295173.958 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8235
2025-06-30T14:52:53.960Z,1751295173.960 [controlThread ThreadHandler](INFO): Handler Thread ID is 8236
2025-06-30T14:52:53.960Z,1751295173.960 [controlThread](DEBUG): Initializing ControlThread
2025-06-30T14:52:53.961Z,1751295173.961 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-06-30T14:52:53.961Z,1751295173.961 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-06-30T14:52:53.962Z,1751295173.962 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-06-30T14:52:53.962Z,1751295173.962 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-06-30T14:52:53.962Z,1751295173.962 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-06-30T14:52:53.966Z,1751295173.966 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-06-30T14:52:53.967Z,1751295173.967 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-06-30T14:52:53.967Z,1751295173.967 [SBIT](INFO): Initialize SBIT Component.
2025-06-30T14:52:53.967Z,1751295173.967 [SBIT](IMPORTANT): git: 2025-06-23
2025-06-30T14:52:53.968Z,1751295173.968 [SBIT](INFO): git hash: 4f30d88d07a5bb26241f3dcd8e36105bcba949e8
2025-06-30T14:52:53.968Z,1751295173.968 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-06-30T14:52:53.969Z,1751295173.969 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-06-30T14:52:53.970Z,1751295173.970 [SBIT](INFO): Beginning SBIT in 121.000000 seconds.
2025-06-30T14:52:53.971Z,1751295173.971 [IBIT](INFO): Initialize IBIT Component.
2025-06-30T14:52:53.972Z,1751295173.972 [CBIT](DEBUG): Initialize CBIT Component.
2025-06-30T14:52:53.973Z,1751295173.973 [logger ThreadHandler](INFO): Handler Thread ID is 8237
2025-06-30T14:52:53.983Z,1751295173.983 [CBIT](DEBUG): Initialized mux pins.
2025-06-30T14:52:53.983Z,1751295173.983 [CBIT](DEBUG): Initializing the watchdog timer.
2025-06-30T14:52:53.992Z,1751295173.992 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8238
2025-06-30T14:52:54.007Z,1751295174.007 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-06-30T14:52:54.008Z,1751295174.008 [CBIT](DEBUG): Initializing heartbeat.
2025-06-30T14:52:54.009Z,1751295174.009 [DAT ThreadHandler](INFO): Handler Thread ID is 8239
2025-06-30T14:52:54.009Z,1751295174.009 [DAT](INFO): Powering up
2025-06-30T14:52:54.009Z,1751295174.009 [DAT](DEBUG): Initializing DAT.
2025-06-30T14:52:54.013Z,1751295174.013 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8241
2025-06-30T14:52:54.014Z,1751295174.014 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-06-30T14:52:54.036Z,1751295174.036 [Onboard ThreadHandler](INFO): Handler Thread ID is 8242
2025-06-30T14:52:54.053Z,1751295174.053 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8243
2025-06-30T14:52:54.076Z,1751295174.076 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8244
2025-06-30T14:52:54.076Z,1751295174.076 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-06-30T14:52:54.079Z,1751295174.079 [CBIT](DEBUG): Deactivating emergency mode.
2025-06-30T14:52:54.081Z,1751295174.081 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8246
2025-06-30T14:52:54.084Z,1751295174.084 [WetLabsBB2FL](INFO): Powering up
2025-06-30T14:52:54.085Z,1751295174.085 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8248
2025-06-30T14:52:54.088Z,1751295174.088 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-06-30T14:52:54.088Z,1751295174.088 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-06-30T14:52:54.088Z,1751295174.088 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-06-30T14:52:54.088Z,1751295174.088 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-06-30T14:52:54.088Z,1751295174.088 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-06-30T14:52:54.088Z,1751295174.088 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-06-30T14:52:54.089Z,1751295174.089 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-06-30T14:52:54.089Z,1751295174.089 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-06-30T14:52:54.119Z,1751295174.119 [CBIT](DEBUG): Backplane powered.
2025-06-30T14:52:54.120Z,1751295174.120 [GFScanner](DEBUG): Initializing GFScanner
2025-06-30T14:52:54.120Z,1751295174.120 [GFScanner](DEBUG): Deactivating GF circuits.
2025-06-30T14:52:54.129Z,1751295174.129 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-06-30T14:52:54.130Z,1751295174.130 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-06-30T14:52:54.131Z,1751295174.131 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-06-30T14:52:54.132Z,1751295174.132 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-06-30T14:52:54.132Z,1751295174.132 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-06-30T14:52:54.133Z,1751295174.133 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-06-30T14:52:54.133Z,1751295174.133 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-06-30T14:52:54.135Z,1751295174.135 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-06-30T14:52:54.194Z,1751295174.194 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

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

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

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


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

2025-06-30T14:52:54.842Z,1751295174.842 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-06-30T14:52:54.847Z,1751295174.847 [Default:A.Wait](DEBUG): Construct Wait.
2025-06-30T14:52:54.850Z,1751295174.850 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-30T14:52:54.879Z,1751295174.879 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-06-30T14:52:54.882Z,1751295174.882 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-06-30T14:52:54.904Z,1751295174.904 [Default:E.Execute](DEBUG): Construct Execute.
2025-06-30T14:52:54.907Z,1751295174.907 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

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

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

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

          set command = "Burn on"
        }

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

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

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

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-06-30T14:52:54.911Z,1751295174.911 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-06-30T14:52:54.919Z,1751295174.919 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-06-30T14:52:54.941Z,1751295174.941 [Depth_Keller](INFO): Initializing.
2025-06-30T14:52:54.942Z,1751295174.942 [MultiRay](INFO): Powering up MultiRay Lights
2025-06-30T14:52:54.942Z,1751295174.942 [MultiRay](INFO): Powering up secondary power supply.
2025-06-30T14:52:54.973Z,1751295174.973 [Power24vConverter](INFO): Powering up.
2025-06-30T14:52:54.980Z,1751295174.980 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-30T14:52:55.003Z,1751295175.003 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-06-30T14:52:55.012Z,1751295175.012 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-06-30T14:52:55.013Z,1751295175.013 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-30T14:52:55.020Z,1751295175.020 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-30T14:52:55.021Z,1751295175.021 [MassServo](DEBUG): Initializing EZServoServo.
2025-06-30T14:52:55.028Z,1751295175.028 [MassServo](DEBUG): Initializing MassServo.
2025-06-30T14:52:55.028Z,1751295175.028 [RudderServo](DEBUG): Initializing EZServoServo.
2025-06-30T14:52:55.040Z,1751295175.040 [RudderServo](DEBUG): Initializing RudderServo.
2025-06-30T14:52:55.040Z,1751295175.040 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-06-30T14:52:55.048Z,1751295175.048 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-06-30T14:52:55.050Z,1751295175.050 [CBIT](FAULT): Main Battery Failure
2025-06-30T14:52:56.144Z,1751295176.144 [MultiRay](INFO): Powering down
2025-06-30T14:52:56.264Z,1751295176.264 [MultiRay](INFO): Powering down LCB2
2025-06-30T14:52:56.696Z,1751295176.696 [WetLabsBB2FL](INFO): Powering down
2025-06-30T14:53:01.112Z,1751295181.112 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-06-30T14:53:05.600Z,1751295185.600 [DAT](INFO): DAT read: 

2025-06-30T14:53:05.601Z,1751295185.601 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-06-30T14:53:07.112Z,1751295187.112 [DAT](INFO): DAT read: MF Frequency Band

2025-06-30T14:53:07.113Z,1751295187.113 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-06-30T14:53:07.114Z,1751295187.114 [DAT](INFO): DAT read: Jun 30 2025 14:53:02 

2025-06-30T14:53:08.385Z,1751295188.385 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-06-30T14:53:08.386Z,1751295188.386 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-06-30T14:53:08.386Z,1751295188.386 [DAT](INFO): commRate: 800
2025-06-30T14:53:10.452Z,1751295190.452 [DAT](INFO): entering command mode
2025-06-30T14:53:10.652Z,1751295190.652 [DAT](INFO): DAT read: 

2025-06-30T14:53:10.652Z,1751295190.652 [DAT](INFO): DAT read: user:1>

2025-06-30T14:53:10.653Z,1751295190.653 [DAT](INFO): setting verbose to 3
2025-06-30T14:53:10.904Z,1751295190.904 [DAT](INFO): DAT read: user:1>

2025-06-30T14:53:10.905Z,1751295190.905 [DAT](INFO): DAT read: Verbose         | 3           

2025-06-30T14:53:10.905Z,1751295190.905 [DAT](INFO): set verbose to 3
2025-06-30T14:53:10.906Z,1751295190.906 [DAT](INFO): setting DatVerbose to 27440
2025-06-30T14:53:11.156Z,1751295191.156 [DAT](INFO): DAT read: user:2>

2025-06-30T14:53:11.157Z,1751295191.157 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-06-30T14:53:11.158Z,1751295191.158 [DAT](INFO): set DatVerbose to 27440
2025-06-30T14:53:11.158Z,1751295191.158 [DAT](INFO): setting transmit power to 8
2025-06-30T14:53:11.408Z,1751295191.408 [DAT](INFO): DAT read: user:3>

2025-06-30T14:53:11.409Z,1751295191.409 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-06-30T14:53:11.409Z,1751295191.409 [DAT](INFO): set transmit power to 8
2025-06-30T14:53:11.410Z,1751295191.410 [DAT](INFO): setting local address to 9
2025-06-30T14:53:11.660Z,1751295191.660 [DAT](INFO): DAT read: user:4>

2025-06-30T14:53:11.661Z,1751295191.661 [DAT](INFO): DAT read: LocalAddr       | 9           

2025-06-30T14:53:11.661Z,1751295191.661 [DAT](INFO): set local address to 9
2025-06-30T14:53:11.662Z,1751295191.662 [DAT](INFO): Setting time to: 14:53:11 And date to:6/30/2025
2025-06-30T14:53:11.912Z,1751295191.912 [DAT](INFO): DAT read: user:5>

2025-06-30T14:53:11.913Z,1751295191.913 [DAT](INFO): DAT read: Mon Jun 30, 2025  14:53:11  

2025-06-30T14:53:11.913Z,1751295191.913 [DAT](INFO): Local DAT time set to Mon Jun 30, 2025  14:53:11  

2025-06-30T14:53:22.081Z,1751295202.081 [NAL9602](INFO): Powering up NAL9602
2025-06-30T14:53:32.994Z,1751295212.994 [NAL9602](INFO): NAL9602 initialized
2025-06-30T14:53:49.154Z,1751295229.154 [NAL9602](INFO): SBD MO Status=0, MOMSN=20283, MT Status=0, MTMSN=0
2025-06-30T14:53:49.155Z,1751295229.155 [NAL9602](INFO): No messages in MT queue
2025-06-30T14:53:58.847Z,1751295238.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145359.01,A,3646.82707,N,12156.81071,W,1.477,0.00,300625,,,A*76
2025-06-30T14:53:58.850Z,1751295238.850 [NAL9602](INFO): GPS fix at 20250630T145359: (36.780451, -121.946845)
2025-06-30T14:54:31.169Z,1751295271.169 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T14:54:55.432Z,1751295295.432 [SBIT](IMPORTANT): Beginning Startup BIT
2025-06-30T14:54:55.436Z,1751295295.436 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-30T14:54:59.953Z,1751295299.953 [GFScanner](IMPORTANT): Low side ground fault detected
mA:
CHAN A0 (Batt): 0.887142
CHAN A1 (24V): 0.835452
CHAN A2 (12V): 0.817634
CHAN A3 (5V): 0.333139
CHAN B0 (3.3V): 0.214399
CHAN B1 (3.15aV): 0.243554
CHAN B2 (3.15bV): 0.245062
CHAN B3 (GND): -0.014438
OPEN: 0.019916
Full Scale: +/- 1 mA
2025-06-30T14:55:49.619Z,1751295349.619 [SBIT](IMPORTANT): SBIT PASSED
2025-06-30T14:55:49.620Z,1751295349.620 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-06-30T14:55:49.620Z,1751295349.620 [SBIT](IMPORTANT): BPC1.loadAtStartup=0 bool;

2025-06-30T14:55:49.621Z,1751295349.621 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2025-06-30T14:55:49.621Z,1751295349.621 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-1 ampere_hour;

2025-06-30T14:55:49.621Z,1751295349.621 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=10 volt;

2025-06-30T14:55:49.621Z,1751295349.621 [SBIT](IMPORTANT): MultiRay.brightnessWhite=88 none;

2025-06-30T14:55:49.621Z,1751295349.621 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;

2025-06-30T14:55:49.621Z,1751295349.621 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;

2025-06-30T14:55:49.621Z,1751295349.621 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2025-06-30T14:55:49.621Z,1751295349.621 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=215.486556 cubic_centimeter;

2025-06-30T14:55:49.622Z,1751295349.622 [SBIT](IMPORTANT): VerticalControl.massDefault=25.322496 millimeter;

2025-06-30T14:55:50.011Z,1751295350.011 [MissionManager](IMPORTANT): Started mission Startup
2025-06-30T14:55:50.012Z,1751295350.012 [Startup] Running Loop=1
2025-06-30T14:55:50.012Z,1751295350.012 [Startup](DEBUG): Aggregate::initialize Startup
2025-06-30T14:55:50.012Z,1751295350.012 [Startup:A.GoToSurface] Running Loop=1
2025-06-30T14:55:50.012Z,1751295350.012 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-30T14:55:50.012Z,1751295350.012 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-30T14:55:50.013Z,1751295350.013 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-30T14:55:50.013Z,1751295350.013 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-30T14:55:50.013Z,1751295350.013 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-30T14:55:50.014Z,1751295350.014 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-30T14:55:50.014Z,1751295350.014 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-30T14:55:50.033Z,1751295350.033 [Startup:StartupSatComms] Running Loop=1
2025-06-30T14:55:50.033Z,1751295350.033 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-06-30T14:55:50.033Z,1751295350.033 [Startup:StartupSatComms:A] Running Loop=1
2025-06-30T14:55:50.410Z,1751295350.410 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-06-30T14:55:52.022Z,1751295352.022 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145552.00,A,3646.84275,N,12156.80304,W,0.855,6.04,300625,,,A*73
2025-06-30T14:55:52.025Z,1751295352.025 [NAL9602](INFO): GPS fix at 20250630T145552: (36.780712, -121.946717)
2025-06-30T14:55:52.035Z,1751295352.035 [Startup:StartupSatComms:A] Stopped
2025-06-30T14:55:52.035Z,1751295352.035 [Startup:StartupSatComms:B] Running Loop=1
2025-06-30T14:55:52.452Z,1751295352.452 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-30T14:56:20.200Z,1751295380.200 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-06-30T14:56:42.785Z,1751295402.785 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004084
2025-06-30T14:56:50.179Z,1751295410.179 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250630T073837/Courier0091.lzma
2025-06-30T14:56:52.246Z,1751295412.246 [Startup:StartupSatComms:B](INFO): Timed out from 2025-06-30T14:55:52.0Z
2025-06-30T14:56:52.246Z,1751295412.246 [Startup:StartupSatComms:B] Stopped
2025-06-30T14:56:52.246Z,1751295412.246 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-06-30T14:56:52.246Z,1751295412.246 [Startup:StartupSatComms] Stopped
2025-06-30T14:56:52.246Z,1751295412.246 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-06-30T14:56:52.248Z,1751295412.248 [Startup](INFO): Completed Startup
2025-06-30T14:56:52.248Z,1751295412.248 [MissionManager](INFO): Startup is completed.
2025-06-30T14:56:52.248Z,1751295412.248 [MissionManager](INFO): Uninitializing Mission Startup
2025-06-30T14:56:52.248Z,1751295412.248 [Startup] Stopped
2025-06-30T14:56:52.248Z,1751295412.248 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-06-30T14:56:52.248Z,1751295412.248 [Startup:A.GoToSurface] Stopped
2025-06-30T14:56:52.248Z,1751295412.248 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-30T14:56:52.653Z,1751295412.653 [MissionManager](IMPORTANT): Started mission Default
2025-06-30T14:56:52.654Z,1751295412.654 [Default] Running Loop=1
2025-06-30T14:56:52.654Z,1751295412.654 [Default](DEBUG): Aggregate::initialize Default
2025-06-30T14:56:52.654Z,1751295412.654 [Default:B.GoToSurface] Running Loop=1
2025-06-30T14:56:52.654Z,1751295412.654 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-30T14:56:52.654Z,1751295412.654 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-30T14:56:52.654Z,1751295412.654 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-30T14:56:52.655Z,1751295412.655 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-30T14:56:52.655Z,1751295412.655 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-30T14:56:52.656Z,1751295412.656 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-30T14:56:52.656Z,1751295412.656 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-30T14:56:52.656Z,1751295412.656 [Default:A.Wait] Running Loop=1
2025-06-30T14:56:52.656Z,1751295412.656 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-30T14:57:01.222Z,1751295421.222 [DataOverHttps](INFO): Moved sent file to Logs/20250630T073837/Courier0091.lzma.bak
2025-06-30T14:57:01.222Z,1751295421.222 [DataOverHttps](INFO): SBD MOMSN=25283431
2025-06-30T14:57:05.999Z,1751295425.999 [Default:A.Wait](INFO): Done Waiting.
2025-06-30T14:57:05.999Z,1751295425.999 [Default:A.Wait] Stopped
2025-06-30T14:57:05.999Z,1751295425.999 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-30T14:57:06.403Z,1751295426.403 [Default:CheckIn] Running Loop=1
2025-06-30T14:57:06.407Z,1751295426.407 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-30T14:57:06.407Z,1751295426.407 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-30T14:57:06.800Z,1751295426.800 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-30T14:57:08.394Z,1751295428.394 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145708.00,A,3646.85285,N,12156.79718,W,0.272,54.93,300625,,,D*4C
2025-06-30T14:57:08.397Z,1751295428.397 [NAL9602](INFO): GPS fix at 20250630T145708: (36.780881, -121.946620)
2025-06-30T14:57:08.406Z,1751295428.406 [Default:CheckIn:Read_GPS] Stopped
2025-06-30T14:57:08.406Z,1751295428.406 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-30T14:57:08.828Z,1751295428.828 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-30T14:57:27.227Z,1751295447.227 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250630T145243/Courier0000.lzma
2025-06-30T14:57:38.269Z,1751295458.269 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Courier0000.lzma.bak
2025-06-30T14:57:38.269Z,1751295458.269 [DataOverHttps](INFO): SBD MOMSN=25283433
2025-06-30T14:58:04.103Z,1751295484.103 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250630T145243/Courier0004.lzma
2025-06-30T14:58:13.137Z,1751295493.137 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Courier0004.lzma.bak
2025-06-30T14:58:13.137Z,1751295493.137 [DataOverHttps](INFO): SBD MOMSN=25283437
2025-06-30T14:58:39.012Z,1751295519.012 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20250630T073837/Express0092.lzma
2025-06-30T14:58:46.976Z,1751295526.976 [NAL9602](INFO): SBD MO Status=0, MOMSN=20284, MT Status=0, MTMSN=0
2025-06-30T14:58:46.976Z,1751295526.976 [NAL9602](INFO): No messages in MT queue
2025-06-30T14:58:50.066Z,1751295530.066 [DataOverHttps](INFO): Moved sent file to Logs/20250630T073837/Express0092.lzma.bak
2025-06-30T14:58:50.066Z,1751295530.066 [DataOverHttps](INFO): SBD MOMSN=25283440
2025-06-30T14:59:15.814Z,1751295555.814 [DataOverHttps](INFO): Sending 1077 bytes from file Logs/20250630T145243/Express0001.lzma
2025-06-30T14:59:17.681Z,1751295557.681 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T14:59:26.853Z,1751295566.853 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Express0001.lzma.bak
2025-06-30T14:59:26.853Z,1751295566.853 [DataOverHttps](INFO): SBD MOMSN=25283448
2025-06-30T14:59:52.929Z,1751295592.929 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20250630T145243/Express0005.lzma
2025-06-30T15:00:03.969Z,1751295603.969 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Express0005.lzma.bak
2025-06-30T15:00:03.969Z,1751295603.969 [DataOverHttps](INFO): SBD MOMSN=25283479
2025-06-30T15:00:05.363Z,1751295605.363 [Default:CheckIn:Read_Iridium] Stopped
2025-06-30T15:00:05.379Z,1751295605.379 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-30T15:00:05.379Z,1751295605.379 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-30T15:00:22.406Z,1751295622.406 [DataOverHttps](IMPORTANT): SBD MTMSN=20250630T150023
2025-06-30T15:00:30.776Z,1751295630.776 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.MissionTimeout 10 h;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.Speed 0.8 m/s;run
2025-06-30T15:00:30.818Z,1751295630.818 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-06-30T15:00:30.819Z,1751295630.819 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-06-30T15:00:30.819Z,1751295630.819 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-06-30T15:00:30.821Z,1751295630.821 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-06-30T15:00:31.894Z,1751295631.894 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="keepstation">
    <DefineArg Name="MissionTimeout">
        <Units:hour />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="ApproachDepth">
        <Units:meter />
        <Value>15</Value>
    </DefineArg>
    <DefineArg Name="DepthDeadband">
        <Units:meter />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>0.75</Value>
    </DefineArg>
    <DefineArg Name="Radius">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="SinkOffSurface">
        <False />
    </DefineArg>
    <DefineArg Name="SinkDepth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SinkDepthTimeout">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>2</Value>
    </DefineArg>
    <DefineOutput Name="DiveMode">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="DoingComms">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="SurfaceSink">
        <Units:count />
        <Value>1</Value>
    </DefineOutput>
    <DefineOutput Name="TransitToStation">
        <Units:count />
        <Value>2</Value>
    </DefineOutput>
    <DefineOutput Name="StationKeep">
        <Units:count />
        <Value>3</Value>
    </DefineOutput>
    <DefineOutput Name="OnStation">
        <False />
    </DefineOutput>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MaxDepth" />
        <Arg Name="MaxDepth" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinOffshore" />
        <Arg Name="MinOffshore" />
    </Assign>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:Pitch>
        <Parallel />
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="SurfaceComms">
        <When>
            <Called />
            <Or>
                <Elapsed>
                    <Universal:time_fix />
                </Elapsed>
                <Gt>
                    <Arg Name="NeedCommsTime" />
                </Gt>
            </Or>
        </When>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="DoingComms" />
        </Assign>
        <Call RefId="NeedComms" PriorityHere="false" />
        <Aggregate Id="setTransit">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Arg Name="OnStation" />
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="TransitToStation" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setStation">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Not>
                        <Arg Name="OnStation" />
                    </Not>
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="StationKeep" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setSink">
            <Sequence />
            <Break>
                <Not>
                    <Arg Name="SinkOffSurface" />
                </Not>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="SurfaceSink" />
            </Assign>
        </Aggregate>
    </Aggregate>
    <Call RefId="SurfaceComms" Id="StartingMission" PriorityHere="false" />
    <Aggregate Id="DiveCmd">
        <When>
            <Called />
        </When>
        <Aggregate Id="SurfaceSink">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="SurfaceSink" />
                </Eq>
            </While>
            <Aggregate Id="sink">
                <Sequence />
                <Guidance:Buoyancy Id="BuoyancyHold">
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Units:cubic_centimeter />
                        <Value>NaN</Value>
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Units:meter_per_second />
                        <Value>0</Value>
                    </Setting>
                </Guidance:SetSpeed>
                <Syslog Severity="Info">
                    Sinking to 
                    <Arg Name="SinkDepth" />
                    <Units:meter />
                </Syslog>
                <Guidance:Pitch>
                    <Sequence />
                    <Timeout Duration="SinkDepthTimeout">
                        <Syslog Severity="Important">
                            Timed out while trying to reach target sink depth at current depth of 
                            <Universal:depth />
                            <Units:meter />
                        </Syslog>
                    </Timeout>
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="SinkDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Aggregate Id="sinkToTransit">
                    <Sequence />
                    <Break>
                        <Arg Name="OnStation" />
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="TransitToStation" />
                    </Assign>
                </Aggregate>
                <Aggregate Id="sinkToStation">
                    <Sequence />
                    <Break>
                        <Not>
                            <Arg Name="OnStation" />
                        </Not>
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="StationKeep" />
                    </Assign>
                </Aggregate>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="TransitToStation">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="TransitToStation" />
                </Eq>
            </While>
            <Aggregate Id="dive">
                <Sequence />
                <Guidance:Buoyancy>
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Control:VerticalControl.buoyancyNeutral />
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:Pitch>
                    <Parallel />
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="ApproachDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Arg Name="Speed" />
                    </Setting>
                </Guidance:SetSpeed>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="StationKeep">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="StationKeep" />
                </Eq>
            </While>
            <Assign>
                <Parallel />
                <Control:VerticalControl.depthDeadband />
                <Arg Name="DepthDeadband" />
            </Assign>
            <Guidance:Pitch>
                <Parallel />
                <Setting>
                    <Guidance:Pitch.depth />
                    <Arg Name="Depth" />
                </Setting>
            </Guidance:Pitch>
            <Guidance:KeepStation>
                <Parallel />
                <Setting>
                    <Guidance:KeepStation.latitude />
                    <Arg Name="Latitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.longitude />
                    <Arg Name="Longitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.radius />
                    <Arg Name="Radius" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.speed />
                    <Arg Name="Speed" />
                </Setting>
            </Guidance:KeepStation>
        </Aggregate>
    </Aggregate>
    <Aggregate Id="TransitToStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" PriorityHere="false" />
        </Aggregate>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
        </Guidance:Waypoint>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="StationKeep" />
        </Assign>
        <Assign>
            <Sequence />
            <Arg Name="OnStation" />
            <True />
        </Assign>
    </Aggregate>
    <Call RefId="NeedComms" Id="OnStation" PriorityHere="false" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" PriorityHere="false" />
        </Aggregate>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-06-30T15:00:31.908Z,1751295631.908 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-06-30T15:00:31.915Z,1751295631.915 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-06-30T15:00:31.918Z,1751295631.918 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-06-30T15:00:31.921Z,1751295631.921 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-06-30T15:00:31.924Z,1751295631.924 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-06-30T15:00:31.927Z,1751295631.927 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-06-30T15:00:31.930Z,1751295631.930 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-06-30T15:00:31.933Z,1751295631.933 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-06-30T15:00:31.936Z,1751295631.936 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-06-30T15:00:31.938Z,1751295631.938 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-06-30T15:00:31.941Z,1751295631.941 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-06-30T15:00:31.944Z,1751295631.944 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-06-30T15:00:31.947Z,1751295631.947 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-06-30T15:00:31.950Z,1751295631.950 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-06-30T15:00:31.979Z,1751295631.979 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-06-30T15:00:31.982Z,1751295631.982 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-06-30T15:00:31.985Z,1751295631.985 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-06-30T15:00:32.024Z,1751295632.024 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-06-30T15:00:32.027Z,1751295632.027 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-06-30T15:00:32.038Z,1751295632.038 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-06-30T15:00:32.039Z,1751295632.039 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-06-30T15:00:32.043Z,1751295632.043 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-06-30T15:00:32.045Z,1751295632.045 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-06-30T15:00:32.475Z,1751295632.475 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="NeedComms">
    <DefineArg Name="DiveInterval">
        <Units:hour />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="WaitForPitchUp">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SurfacePitch">
        <Units:degree />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="SurfaceDepthRate">
        <Units:meter_per_second />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="SurfaceSpeed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="SurfacingTimeout">
        <Units:second />
        <Value>1000</Value>
    </DefineArg>
    <DefineArg Name="GPSTimeout">
        <Units:minute />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="CommsTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <When>
        <Called />
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
                <Add>
                    <Arg Name="WaitForPitchUp" />
                </Add>
            </Gt>
        </Or>
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
            </Gt>
            <And>
                <Universal:platform_pitch_angle />
                <Gt>
                    <Units:degree />
                    <Value>0</Value>
                </Gt>
            </And>
        </Or>
    </When>
    <Syslog Severity="Info">
        last time_fix was: 
        <Universal:time_fix />
        <Units:epoch_second />
    </Syslog>
    <Guidance:GoToSurface>
        <Progression />
        <Setting>
            <Guidance:GoToSurface.pitch />
            <Arg Name="SurfacePitch" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.depthRate />
            <Arg Name="SurfaceDepthRate" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.speed />
            <Arg Name="SurfaceSpeed" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.surfaceTimeout />
            <Arg Name="SurfacingTimeout" />
        </Setting>
    </Guidance:GoToSurface>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">first GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="CommsTimeout">
            <Syslog Severity="Important">
                Comms timed out after 
                <Arg Name="CommsTimeout" />
                <Units:minute />
                minutes
            </Syslog>
        </Timeout>
        <Universal:platform_communications />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">second GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
</Aggregate>

2025-06-30T15:00:32.490Z,1751295632.490 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-06-30T15:00:32.499Z,1751295632.499 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-06-30T15:00:32.502Z,1751295632.502 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-06-30T15:00:32.505Z,1751295632.505 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-06-30T15:00:32.508Z,1751295632.508 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-06-30T15:00:32.511Z,1751295632.511 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-06-30T15:00:32.514Z,1751295632.514 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-06-30T15:00:32.517Z,1751295632.517 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-06-30T15:00:32.526Z,1751295632.526 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-30T15:00:32.585Z,1751295632.585 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-06-30T15:00:32.586Z,1751295632.586 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-06-30T15:00:32.587Z,1751295632.587 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-06-30T15:00:32.721Z,1751295632.721 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="StandardEnvelopes">
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepthIgnore">
        <Units:meter />
        <Value>1.5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:meter />
        <Value>2000</Value>
    </DefineArg>
    <Parallel />
    <Guidance:AltitudeEnvelope>
        <Parallel />
        <Setting>
            <Guidance:AltitudeEnvelope.minAltitude />
            <Arg Name="MinAltitude" />
        </Setting>
        <Setting>
            <Guidance:AltitudeEnvelope.maxDepthIgnore />
            <Arg Name="MaxDepthIgnore" />
        </Setting>
    </Guidance:AltitudeEnvelope>
    <Guidance:DepthEnvelope>
        <Parallel />
        <Setting>
            <Guidance:DepthEnvelope.maxDepth />
            <Arg Name="MaxDepth" />
        </Setting>
    </Guidance:DepthEnvelope>
    <Guidance:OffshoreEnvelope>
        <Parallel />
        <Setting>
            <Guidance:OffshoreEnvelope.minOffshore />
            <Arg Name="MinOffshore" />
        </Setting>
    </Guidance:OffshoreEnvelope>
</Aggregate>

2025-06-30T15:00:32.730Z,1751295632.730 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-06-30T15:00:32.733Z,1751295632.733 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-06-30T15:00:32.745Z,1751295632.745 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-06-30T15:00:32.752Z,1751295632.752 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-06-30T15:00:32.753Z,1751295632.753 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-06-30T15:00:32.766Z,1751295632.766 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-06-30T15:00:32.774Z,1751295632.774 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-06-30T15:00:32.800Z,1751295632.800 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-06-30T15:00:32.800Z,1751295632.800 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-06-30T15:00:32.801Z,1751295632.801 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-06-30T15:00:32.904Z,1751295632.904 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="BackseatDriver">
    <DefineArg Name="EnableBackseat">
        <False />
    </DefineArg>
    <Parallel />
    <Guidance:BackseatDriver>
        <Sequence />
        <Setting>
            <Guidance:BackseatDriver.powerBackseat />
            <Arg Name="EnableBackseat" />
        </Setting>
    </Guidance:BackseatDriver>
</Aggregate>

2025-06-30T15:00:32.912Z,1751295632.912 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-06-30T15:00:32.919Z,1751295632.919 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-06-30T15:00:32.922Z,1751295632.922 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-06-30T15:00:32.922Z,1751295632.922 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-06-30T15:00:32.928Z,1751295632.928 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-06-30T15:00:33.127Z,1751295633.127 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="PowerOnly">
    <DefineArg Name="SampleAll">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad1">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad2">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad3">
        <False />
    </DefineArg>
    <DefineArg Name="EnabledPowerOnly">
        <Sensor:PowerOnly.loadAtStartup />
    </DefineArg>
    <Parallel />
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleAll" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.samplePowerOnly />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad1" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad1 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad2" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad2 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad3" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad3 />
    </ReadData>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:hour />
            <Value>99999</Value>
        </Setting>
    </Guidance:Wait>
</Aggregate>

2025-06-30T15:00:33.139Z,1751295633.139 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-06-30T15:00:33.142Z,1751295633.142 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-06-30T15:00:33.153Z,1751295633.153 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-06-30T15:00:33.160Z,1751295633.160 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-06-30T15:00:33.163Z,1751295633.163 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-06-30T15:00:33.182Z,1751295633.182 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-06-30T15:00:33.188Z,1751295633.188 [keepstation:H.Pitch](DEBUG): Construct.
2025-06-30T15:00:33.322Z,1751295633.322 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-06-30T15:00:33.335Z,1751295633.335 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-06-30T15:00:33.350Z,1751295633.350 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-06-30T15:00:33.416Z,1751295633.416 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-06-30T15:00:33.424Z,1751295633.424 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-06-30T15:00:33.430Z,1751295633.430 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-06-30T15:00:33.448Z,1751295633.448 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-06-30T15:00:33.458Z,1751295633.458 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-06-30T15:00:33.480Z,1751295633.480 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-06-30T15:00:33.513Z,1751295633.513 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-06-30T15:00:33.544Z,1751295633.544 [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 keepstation {
  """
  Vehicle transits to desired waypoint (or stays put if none is commanded)
  and stays within the specified radius.
  """

  arguments {
    MissionTimeout = 4 hour
      """
      Maximum duration of mission
      """

    NeedCommsTime = 45 minute
      """
      How often to surface for communications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to hold. If NaN, hold the latitude at start of
      mission.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to hold. If NaN, hold the longitude at start of
      mission.
      """

    Depth = 30 meter
      """
      Depth held during drift mode
      """

    ApproachDepth = 15 meter
      """
      Depth for initial approach to station.
      """

    DepthDeadband = 4 meter
      """
      How much vertical drift from the specified depth is allowed in drift
      mode
      """

    Speed = 0.75 meter_per_second
      """
      Vehicle transit speed.
      """

    Radius = 200 meter
      """
      Radius of circle around waypoint to hold.
      """

    SinkOffSurface = false
      """
      If true, use buoyancy only to decend to SinkDepth before driving to first target depth
      """

    SinkDepth = 10 meter
      """
      Initial depth to decend using only buoyancy before driving
      """

    SinkDepthTimeout = 10 minute
      """
      Max wait time for the vehicle to reach the target depth
      """

    MaxDepth = 45 meter
      """
      Maximum allowable depth during the mission.
      """

    MinOffshore = 2 kilometer
      """
      Minimum distance from the shoreline to maintain
      """
  }

  output {
    DiveMode = 0 count
      """
      Mission variable (don't change). The mission sets this variable to
      switch between dive modes. Initialized to DoingComms.
      """

    DoingComms = 0 count
      """
      Mission variable (don't change). The mission will run in this mode
      when doing surface comms.
      """

    SurfaceSink = 1 count
      """
      Mission variable (don't change). The mission will run in this mode
      when sinking from the surface using only buoyancy.
      """

    TransitToStation = 2 count
      """
      Mission variable (don't change). The mission will run in this mode
      when performing the transit to station
      """

    StationKeep = 3 count
      """
      Mission variable (don't change). The mission will run in this mode
      when keeping station
      """

    OnStation = false
      """
      Mission variable (don't change). Determines last mode (Transit or StationKeep)
      to resume after needcomms
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Pitch {
    run in parallel

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate SurfaceComms {
    run when (
      called
      or ( elapsed ( Universal:time_fix ) > NeedCommsTime )
    )

    assign in sequence DiveMode = DoingComms

    call priorityHere=false refId="NeedComms"

    aggregate setTransit {
      run in sequence

      break if (
        SinkOffSurface
        or OnStation
      )

      assign in sequence DiveMode = TransitToStation
    }

    aggregate setStation {
      run in sequence

      break if (
        SinkOffSurface
        or not OnStation
      )

      assign in sequence DiveMode = StationKeep
    }

    aggregate setSink {
      run in sequence

      break if ( not SinkOffSurface )

      assign in sequence DiveMode = SurfaceSink
    }
  }

  call id="StartingMission" priorityHere=false refId="SurfaceComms"

  aggregate DiveCmd {
    run when ( called )

    aggregate SurfaceSink {
      run while ( DiveMode == SurfaceSink )

      aggregate sink {
        run in sequence

        behavior Guidance:Buoyancy id="BuoyancyHold" {
          run in parallel

          set position = NaN cc
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = 0 m/s
        }

        syslog info "Sinking to " + SinkDepth~meter

        behavior Guidance:Pitch {
          run in sequence

          timeout duration=SinkDepthTimeout {
            syslog important "Timed out while trying to reach target sink depth at current depth of "
                 + Universal:depth~meter
          }

          set depth = SinkDepth
        }

        # Once we've sunk, either resume transit or station keeping depths and speeds
        aggregate sinkToTransit {
          run in sequence

          break if ( OnStation )

          assign in sequence DiveMode = TransitToStation
        }

        aggregate sinkToStation {
          run in sequence

          break if ( not OnStation )

          assign in sequence DiveMode = StationKeep
        }
      }
    }

    aggregate TransitToStation {
      run while ( DiveMode == TransitToStation )

      aggregate dive {
        run in sequence

        behavior Guidance:Buoyancy {
          run in parallel

          set position = Control:VerticalControl.buoyancyNeutral
        }

        behavior Guidance:Pitch {
          run in parallel

          set depth = ApproachDepth
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = Speed
        }
      }
    }

    aggregate StationKeep {
      run while ( DiveMode == StationKeep )

      assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband

      behavior Guidance:Pitch {
        run in parallel

        set depth = Depth
      }

      behavior Guidance:KeepStation {
        run in parallel

        set latitude = Latitude
        set longitude = Longitude
        set radius = Radius
        set speed = Speed
      }
    }
  }

  aggregate TransitToStation {
    """
    Need a separate aggregate for transit if we want to specify a
    different depth for the approach.
    """

    run in sequence

    aggregate Dive {
      run while ( not ( DiveMode == DoingComms ) )

      call priorityHere=false refId="DiveCmd"
    }

    behavior Guidance:Waypoint id="Wpt1" {
      run in sequence

      set latitude = Latitude
      set longitude = Longitude
    }

    assign in sequence DiveMode = StationKeep
    assign in sequence OnStation = true
  }

  call id="OnStation" priorityHere=false refId="NeedComms"

  aggregate KeepStation {
    run in sequence

    aggregate Dive {
      run while ( not ( DiveMode == DoingComms ) )

      call priorityHere=false refId="DiveCmd"
    }

    behavior Guidance:Wait {
      """
      Due to the way the KeepStation behavior is currently
      implemented, we must run it in parallel and use a Wait to keep
      from completing the mission before the timeout expires. However,
      this new version of the mission uses a top-level timeout so that
      the entire mission will not run for longer then MissionTimeout.
      """

      run in sequence

      set duration = MissionTimeout
    }
  }
}


2025-06-30T15:00:33.545Z,1751295633.545 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-06-30T15:00:45.947Z,1751295645.947 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 10 hour
2025-06-30T15:00:45.948Z,1751295645.948 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute
2025-06-30T15:00:45.949Z,1751295645.949 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree
2025-06-30T15:00:45.949Z,1751295645.949 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree
2025-06-30T15:00:45.950Z,1751295645.950 [CommandExec](IMPORTANT): got command set keepstation.Speed 0.8 meter_per_second
2025-06-30T15:00:45.951Z,1751295645.951 [CommandExec](IMPORTANT): got command run
2025-06-30T15:00:45.954Z,1751295645.954 [CommandExec](IMPORTANT): Running 
2025-06-30T15:00:46.171Z,1751295646.171 [Default] Stopped
2025-06-30T15:00:46.171Z,1751295646.171 [Default](DEBUG): Aggregate::uninitialize Default
2025-06-30T15:00:46.171Z,1751295646.171 [Default:B.GoToSurface] Stopped
2025-06-30T15:00:46.171Z,1751295646.171 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-30T15:00:46.171Z,1751295646.171 [Default:CheckIn] Stopped
2025-06-30T15:00:46.187Z,1751295646.187 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-30T15:00:46.187Z,1751295646.187 [Default:CheckIn:C.Wait] Stopped
2025-06-30T15:00:46.188Z,1751295646.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-30T15:00:46.188Z,1751295646.188 [MissionManager](IMPORTANT): Started mission keepstation
2025-06-30T15:00:46.188Z,1751295646.188 [keepstation] Running Loop=1
2025-06-30T15:00:46.188Z,1751295646.188 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-06-30T15:00:46.188Z,1751295646.188 [keepstation:StandardEnvelopes] Running Loop=1
2025-06-30T15:00:46.188Z,1751295646.188 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-06-30T15:00:46.188Z,1751295646.188 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-06-30T15:00:46.188Z,1751295646.188 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-06-30T15:00:46.189Z,1751295646.189 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-06-30T15:00:46.189Z,1751295646.189 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-06-30T15:00:46.189Z,1751295646.189 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-06-30T15:00:46.189Z,1751295646.189 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-06-30T15:00:46.189Z,1751295646.189 [keepstation:BackseatDriver] Running Loop=1
2025-06-30T15:00:46.189Z,1751295646.189 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-06-30T15:00:46.189Z,1751295646.189 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-06-30T15:00:46.189Z,1751295646.189 [keepstation:PowerOnly] Running Loop=1
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:PowerOnly:A] Running Loop=1
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:PowerOnly:B] Running Loop=1
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:PowerOnly:C] Running Loop=1
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:PowerOnly:D] Running Loop=1
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:H.Pitch] Running Loop=1
2025-06-30T15:00:46.190Z,1751295646.190 [keepstation:H.Pitch](DEBUG): Initialize.
2025-06-30T15:00:46.191Z,1751295646.191 [keepstation:B] Running Loop=1
2025-06-30T15:00:46.195Z,1751295646.195 [keepstation:H.Pitch] Running Loop=1
2025-06-30T15:00:46.196Z,1751295646.196 [keepstation:PowerOnly] Running Loop=1
2025-06-30T15:00:46.196Z,1751295646.196 [keepstation:PowerOnly:D] Stopped
2025-06-30T15:00:46.196Z,1751295646.196 [keepstation:PowerOnly:C] Stopped
2025-06-30T15:00:46.196Z,1751295646.196 [keepstation:PowerOnly:B] Stopped
2025-06-30T15:00:46.196Z,1751295646.196 [keepstation:PowerOnly:A] Stopped
2025-06-30T15:00:46.197Z,1751295646.197 [keepstation:BackseatDriver] Running Loop=1
2025-06-30T15:00:46.197Z,1751295646.197 [keepstation:StandardEnvelopes] Running Loop=1
2025-06-30T15:00:46.197Z,1751295646.197 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-06-30T15:00:46.197Z,1751295646.197 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-06-30T15:00:46.198Z,1751295646.198 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-06-30T15:00:46.199Z,1751295646.199 [keepstation:B] Stopped
2025-06-30T15:00:46.199Z,1751295646.199 [keepstation:D] Running Loop=1
2025-06-30T15:00:46.573Z,1751295646.573 [keepstation:D] Stopped
2025-06-30T15:00:46.573Z,1751295646.573 [keepstation:E] Running Loop=1
2025-06-30T15:00:46.974Z,1751295646.974 [keepstation:E] Stopped
2025-06-30T15:00:46.975Z,1751295646.975 [keepstation:StartingMission] Running Loop=1
2025-06-30T15:00:46.975Z,1751295646.975 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-06-30T15:00:47.384Z,1751295647.384 [keepstation:SurfaceComms] Running Loop=1
2025-06-30T15:00:47.384Z,1751295647.384 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-30T15:00:47.384Z,1751295647.384 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-30T15:00:47.384Z,1751295647.384 [keepstation:SurfaceComms:A] Stopped
2025-06-30T15:00:47.384Z,1751295647.384 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-30T15:00:47.384Z,1751295647.384 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-30T15:00:47.799Z,1751295647.799 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-06-30T15:00:47.800Z,1751295647.800 [keepstation:NeedComms] Running Loop=1
2025-06-30T15:00:47.800Z,1751295647.800 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-30T15:00:47.800Z,1751295647.800 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-30T15:00:47.800Z,1751295647.800 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-30T15:00:47.800Z,1751295647.800 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-30T15:00:47.801Z,1751295647.801 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-30T15:00:47.801Z,1751295647.801 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-30T15:00:47.801Z,1751295647.801 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-30T15:00:47.802Z,1751295647.802 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-30T15:00:47.802Z,1751295647.802 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-30T15:00:47.802Z,1751295647.802 [keepstation:NeedComms:A] Running Loop=1
2025-06-30T15:00:47.812Z,1751295647.812 [keepstation:NeedComms:A](INFO): last time_fix was:  1751295428.000000 second since 1970/01/01T00:00:00Z 
2025-06-30T15:00:47.812Z,1751295647.812 [keepstation:NeedComms:A] Stopped
2025-06-30T15:00:48.188Z,1751295648.188 [keepstation:NeedComms:C] Running Loop=1
2025-06-30T15:00:48.591Z,1751295648.591 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-30T15:00:50.197Z,1751295650.197 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150050.00,A,3646.88137,N,12156.78105,W,0.816,347.09,300625,,,D*74
2025-06-30T15:00:50.199Z,1751295650.199 [NAL9602](INFO): GPS fix at 20250630T150050: (36.781356, -121.946351)
2025-06-30T15:00:50.210Z,1751295650.210 [keepstation:NeedComms:C] Stopped
2025-06-30T15:00:50.211Z,1751295650.211 [keepstation:NeedComms:D] Running Loop=1
2025-06-30T15:00:50.619Z,1751295650.619 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-30T15:00:57.279Z,1751295657.279 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250630T145243/Courier0007.lzma
2025-06-30T15:01:08.321Z,1751295668.321 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Courier0007.lzma.bak
2025-06-30T15:01:08.321Z,1751295668.321 [DataOverHttps](INFO): SBD MOMSN=25283529
2025-06-30T15:01:12.418Z,1751295672.418 [NAL9602](INFO): SBD MO Status=2, MOMSN=20285, MT Status=2, MTMSN=0
2025-06-30T15:01:12.419Z,1751295672.419 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-30T15:01:34.218Z,1751295694.218 [DataOverHttps](INFO): Sending 401 bytes from file Logs/20250630T145243/Express0008.lzma
2025-06-30T15:01:41.101Z,1751295701.101 [NAL9602](INFO): SBD MO Status=0, MOMSN=20285, MT Status=0, MTMSN=0
2025-06-30T15:01:41.101Z,1751295701.101 [NAL9602](INFO): No messages in MT queue
2025-06-30T15:01:45.256Z,1751295705.256 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Express0008.lzma.bak
2025-06-30T15:01:45.256Z,1751295705.256 [DataOverHttps](INFO): SBD MOMSN=25283532
2025-06-30T15:02:11.808Z,1751295731.808 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T15:02:12.031Z,1751295732.031 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20250630T145243/Express0011.lzma
2025-06-30T15:02:23.073Z,1751295743.073 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Express0011.lzma.bak
2025-06-30T15:02:23.073Z,1751295743.073 [DataOverHttps](INFO): SBD MOMSN=25283546
2025-06-30T15:02:24.353Z,1751295744.353 [keepstation:NeedComms:D] Stopped
2025-06-30T15:02:24.353Z,1751295744.353 [keepstation:NeedComms:E] Running Loop=1
2025-06-30T15:02:24.757Z,1751295744.757 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-30T15:02:26.355Z,1751295746.355 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150226.00,A,3646.89446,N,12156.77540,W,0.525,0.35,300625,,,D*7D
2025-06-30T15:02:26.357Z,1751295746.357 [NAL9602](INFO): GPS fix at 20250630T150226: (36.781574, -121.946257)
2025-06-30T15:02:26.404Z,1751295746.404 [keepstation:NeedComms:E] Stopped
2025-06-30T15:02:26.405Z,1751295746.405 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-30T15:02:26.405Z,1751295746.405 [keepstation:NeedComms] Stopped
2025-06-30T15:02:26.406Z,1751295746.406 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-30T15:02:26.406Z,1751295746.406 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-30T15:02:26.406Z,1751295746.406 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-30T15:02:26.796Z,1751295746.796 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-30T15:02:26.796Z,1751295746.796 [keepstation:SurfaceComms:B] Stopped
2025-06-30T15:02:26.796Z,1751295746.796 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-30T15:02:26.796Z,1751295746.796 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-30T15:02:26.796Z,1751295746.796 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-30T15:02:26.796Z,1751295746.796 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-30T15:02:27.184Z,1751295747.184 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-30T15:02:27.184Z,1751295747.184 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-06-30T15:02:27.184Z,1751295747.184 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-30T15:02:27.184Z,1751295747.184 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-30T15:02:27.185Z,1751295747.185 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-30T15:02:27.185Z,1751295747.185 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-30T15:02:27.185Z,1751295747.185 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-30T15:02:27.604Z,1751295747.604 [keepstation:SurfaceComms:setStation] Stopped
2025-06-30T15:02:27.604Z,1751295747.604 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-30T15:02:27.604Z,1751295747.604 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-30T15:02:27.604Z,1751295747.604 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-30T15:02:27.604Z,1751295747.604 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-30T15:02:27.604Z,1751295747.604 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-30T15:02:28.003Z,1751295748.003 [keepstation:SurfaceComms:setSink] Stopped
2025-06-30T15:02:28.003Z,1751295748.003 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-30T15:02:28.003Z,1751295748.003 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-30T15:02:28.007Z,1751295748.007 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-30T15:02:28.007Z,1751295748.007 [keepstation:SurfaceComms] Stopped
2025-06-30T15:02:28.008Z,1751295748.008 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-30T15:02:28.410Z,1751295748.410 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-06-30T15:02:28.410Z,1751295748.410 [keepstation:StartingMission] Stopped
2025-06-30T15:02:28.411Z,1751295748.411 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-06-30T15:02:28.411Z,1751295748.411 [keepstation:TransitToStation] Running Loop=1
2025-06-30T15:02:28.411Z,1751295748.411 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-06-30T15:02:28.411Z,1751295748.411 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-30T15:02:28.411Z,1751295748.411 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-30T15:02:28.415Z,1751295748.415 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-30T15:02:28.415Z,1751295748.415 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-30T15:02:28.416Z,1751295748.416 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-06-30T15:02:28.416Z,1751295748.416 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-06-30T15:02:28.417Z,1751295748.417 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-06-30T15:02:28.804Z,1751295748.804 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd] Running Loop=1
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-30T15:02:28.805Z,1751295748.805 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-30T15:02:28.806Z,1751295748.806 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-30T15:02:28.807Z,1751295748.807 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-30T15:02:28.807Z,1751295748.807 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-30T15:02:28.807Z,1751295748.807 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-30T15:02:28.807Z,1751295748.807 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-30T15:02:28.807Z,1751295748.807 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-30T15:02:28.839Z,1751295748.839 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-30T15:02:28.839Z,1751295748.839 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-30T15:02:28.839Z,1751295748.839 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-30T15:02:28.840Z,1751295748.840 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-30T15:02:28.840Z,1751295748.840 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-30T15:02:28.841Z,1751295748.841 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-30T15:02:28.842Z,1751295748.842 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-30T15:02:28.842Z,1751295748.842 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-30T15:02:28.842Z,1751295748.842 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-30T15:02:28.842Z,1751295748.842 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-30T15:02:28.843Z,1751295748.843 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-30T15:02:28.843Z,1751295748.843 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-30T15:02:28.843Z,1751295748.843 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-30T15:02:28.843Z,1751295748.843 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-30T15:02:28.843Z,1751295748.843 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-30T15:02:28.843Z,1751295748.843 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-30T15:02:28.863Z,1751295748.863 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-06-30T15:02:28.872Z,1751295748.872 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-06-30T15:02:28.872Z,1751295748.872 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-06-30T15:03:30.076Z,1751295810.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T15:03:42.140Z,1751295822.140 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255142
2025-06-30T15:04:40.392Z,1751295880.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T15:05:10.280Z,1751295910.280 [Radio_Surface](INFO): Powering down
2025-06-30T15:05:15.532Z,1751295915.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T15:05:16.536Z,1751295916.536 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-30T15:07:27.989Z,1751296047.989 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-06-30T15:07:58.688Z,1751296078.688 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T15:52:55.205Z,1751298775.205 [CBIT](FAULT): Main Battery Failure
2025-06-30T16:02:26.392Z,1751299346.392 [keepstation:SurfaceComms] Running Loop=1
2025-06-30T16:02:26.392Z,1751299346.392 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-30T16:02:26.393Z,1751299346.393 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-30T16:02:26.393Z,1751299346.393 [keepstation:SurfaceComms:A] Stopped
2025-06-30T16:02:26.393Z,1751299346.393 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-30T16:02:26.393Z,1751299346.393 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-30T16:02:26.395Z,1751299346.395 [keepstation:NeedComms] Running Loop=1
2025-06-30T16:02:26.395Z,1751299346.395 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-30T16:02:26.395Z,1751299346.395 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-30T16:02:26.395Z,1751299346.395 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-30T16:02:26.396Z,1751299346.396 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-30T16:02:26.396Z,1751299346.396 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-30T16:02:26.396Z,1751299346.396 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-30T16:02:26.397Z,1751299346.397 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-30T16:02:26.397Z,1751299346.397 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-30T16:02:26.397Z,1751299346.397 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-30T16:02:26.398Z,1751299346.398 [keepstation:NeedComms:A] Running Loop=1
2025-06-30T16:02:26.400Z,1751299346.400 [keepstation:NeedComms:A](INFO): last time_fix was:  1751295746.000000 second since 1970/01/01T00:00:00Z 
2025-06-30T16:02:26.400Z,1751299346.400 [keepstation:NeedComms:A] Stopped
2025-06-30T16:02:26.791Z,1751299346.791 [keepstation:TransitToStation:Dive] Stopped
2025-06-30T16:02:26.791Z,1751299346.791 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-30T16:02:26.791Z,1751299346.791 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-30T16:02:26.791Z,1751299346.791 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-30T16:02:26.792Z,1751299346.792 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-30T16:03:35.991Z,1751299415.991 [keepstation:NeedComms:C] Running Loop=1
2025-06-30T16:03:36.452Z,1751299416.452 [Radio_Surface](INFO): Powering up
2025-06-30T16:03:41.868Z,1751299421.868 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-30T16:04:11.993Z,1751299451.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T16:04:47.140Z,1751299487.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T16:05:22.280Z,1751299522.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T16:05:57.428Z,1751299557.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T16:06:32.580Z,1751299592.580 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T16:07:08.216Z,1751299628.216 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-06-30T16:07:30.801Z,1751299650.801 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004782
2025-06-30T16:08:43.454Z,1751299723.454 [NAL9602](INFO): SBD MO Status=0, MOMSN=20286, MT Status=0, MTMSN=0
2025-06-30T16:08:43.454Z,1751299723.454 [NAL9602](INFO): No messages in MT queue
2025-06-30T16:08:44.664Z,1751299724.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160846.00,A,3647.20120,N,12155.29383,W,0.661,30.91,300625,,,A*4F
2025-06-30T16:08:44.666Z,1751299724.666 [NAL9602](INFO): GPS fix at 20250630T160846: (36.786687, -121.921564)
2025-06-30T16:08:44.680Z,1751299724.680 [UniversalFixResidualReporter](INFO): Fix residual: 22.1 %DT, over the last 2898.1 m. Residual distance 641.1 m at bearing 91.0 degrees. Fix at (36.7867, -121.9216) with 2271.3 m made good.
2025-06-30T16:08:44.683Z,1751299724.683 [keepstation:NeedComms:C] Stopped
2025-06-30T16:08:44.684Z,1751299724.684 [keepstation:NeedComms:D] Running Loop=1
2025-06-30T16:08:52.031Z,1751299732.031 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20250630T145243/Courier0013.lzma
2025-06-30T16:09:03.073Z,1751299743.073 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Courier0013.lzma.bak
2025-06-30T16:09:03.073Z,1751299743.073 [DataOverHttps](INFO): SBD MOMSN=25283940
2025-06-30T16:09:17.393Z,1751299757.393 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T16:09:29.116Z,1751299769.116 [DataOverHttps](INFO): Sending 563 bytes from file Logs/20250630T145243/Express0014.lzma
2025-06-30T16:09:40.157Z,1751299780.157 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Express0014.lzma.bak
2025-06-30T16:09:40.157Z,1751299780.157 [DataOverHttps](INFO): SBD MOMSN=25283944
2025-06-30T16:09:41.654Z,1751299781.654 [keepstation:NeedComms:D] Stopped
2025-06-30T16:09:41.654Z,1751299781.654 [keepstation:NeedComms:E] Running Loop=1
2025-06-30T16:09:43.671Z,1751299783.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160945.00,A,3647.20576,N,12155.28989,W,0.525,356.19,300625,,,A*7B
2025-06-30T16:09:43.685Z,1751299783.685 [NAL9602](INFO): GPS fix at 20250630T160945: (36.786763, -121.921498)
2025-06-30T16:09:43.785Z,1751299783.785 [keepstation:NeedComms:E] Stopped
2025-06-30T16:09:43.786Z,1751299783.786 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-30T16:09:43.786Z,1751299783.786 [keepstation:NeedComms] Stopped
2025-06-30T16:09:43.786Z,1751299783.786 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-30T16:09:43.786Z,1751299783.786 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-30T16:09:43.786Z,1751299783.786 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-30T16:09:44.160Z,1751299784.160 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-30T16:09:44.160Z,1751299784.160 [keepstation:SurfaceComms:B] Stopped
2025-06-30T16:09:44.160Z,1751299784.160 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-30T16:09:44.160Z,1751299784.160 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-30T16:09:44.160Z,1751299784.160 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-30T16:09:44.160Z,1751299784.160 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-30T16:09:44.550Z,1751299784.550 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-30T16:09:44.550Z,1751299784.550 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-06-30T16:09:44.550Z,1751299784.550 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-30T16:09:44.550Z,1751299784.550 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-30T16:09:44.551Z,1751299784.551 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-30T16:09:44.551Z,1751299784.551 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-30T16:09:44.551Z,1751299784.551 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-30T16:09:44.940Z,1751299784.940 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-30T16:09:44.940Z,1751299784.940 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-30T16:09:44.940Z,1751299784.940 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-30T16:09:44.940Z,1751299784.940 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-30T16:09:44.941Z,1751299784.941 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-30T16:09:44.941Z,1751299784.941 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-30T16:09:44.941Z,1751299784.941 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-30T16:09:44.941Z,1751299784.941 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-30T16:09:44.941Z,1751299784.941 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-30T16:09:44.941Z,1751299784.941 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-30T16:09:44.941Z,1751299784.941 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-30T16:09:44.942Z,1751299784.942 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-30T16:09:44.942Z,1751299784.942 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-30T16:09:44.942Z,1751299784.942 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-30T16:09:44.943Z,1751299784.943 [keepstation:SurfaceComms:setStation] Stopped
2025-06-30T16:09:44.943Z,1751299784.943 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-30T16:09:44.943Z,1751299784.943 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-30T16:09:44.983Z,1751299784.983 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-30T16:09:44.984Z,1751299784.984 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-30T16:09:44.984Z,1751299784.984 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-30T16:09:45.326Z,1751299785.326 [keepstation:SurfaceComms:setSink] Stopped
2025-06-30T16:09:45.327Z,1751299785.327 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-30T16:09:45.327Z,1751299785.327 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-30T16:09:45.327Z,1751299785.327 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-30T16:09:45.327Z,1751299785.327 [keepstation:SurfaceComms] Stopped
2025-06-30T16:09:45.327Z,1751299785.327 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-30T16:09:55.882Z,1751299795.882 [NAL9602](INFO): SBD MO Status=0, MOMSN=20287, MT Status=0, MTMSN=0
2025-06-30T16:09:55.882Z,1751299795.882 [NAL9602](INFO): No messages in MT queue
2025-06-30T16:10:26.585Z,1751299826.585 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T16:10:26.997Z,1751299826.997 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 25.32 mm (1 active estimators).
2025-06-30T16:11:02.540Z,1751299862.540 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.059402
2025-06-30T16:11:27.280Z,1751299887.280 [Radio_Surface](INFO): Powering down
2025-06-30T16:11:27.640Z,1751299887.640 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-30T16:52:55.573Z,1751302375.573 [CBIT](FAULT): Main Battery Failure
2025-06-30T17:09:43.949Z,1751303383.949 [keepstation:SurfaceComms] Running Loop=1
2025-06-30T17:09:43.949Z,1751303383.949 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-30T17:09:43.949Z,1751303383.949 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-30T17:09:43.950Z,1751303383.950 [keepstation:SurfaceComms:A] Stopped
2025-06-30T17:09:43.950Z,1751303383.950 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-30T17:09:43.950Z,1751303383.950 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-30T17:09:44.333Z,1751303384.333 [keepstation:TransitToStation:Dive] Stopped
2025-06-30T17:09:44.333Z,1751303384.333 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-30T17:09:44.333Z,1751303384.333 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-30T17:09:44.333Z,1751303384.333 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-30T17:09:44.334Z,1751303384.334 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-30T17:09:44.336Z,1751303384.336 [keepstation:NeedComms] Running Loop=1
2025-06-30T17:09:44.336Z,1751303384.336 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-30T17:09:44.336Z,1751303384.336 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-30T17:09:44.337Z,1751303384.337 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-30T17:09:44.337Z,1751303384.337 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-30T17:09:44.337Z,1751303384.337 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-30T17:09:44.338Z,1751303384.338 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-30T17:09:44.338Z,1751303384.338 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-30T17:09:44.338Z,1751303384.338 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-30T17:09:44.339Z,1751303384.339 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-30T17:09:44.339Z,1751303384.339 [keepstation:NeedComms:A] Running Loop=1
2025-06-30T17:09:44.341Z,1751303384.341 [keepstation:NeedComms:A](INFO): last time_fix was:  1751299785.000000 second since 1970/01/01T00:00:00Z 
2025-06-30T17:09:44.341Z,1751303384.341 [keepstation:NeedComms:A] Stopped
2025-06-30T17:10:55.245Z,1751303455.245 [keepstation:NeedComms:C] Running Loop=1
2025-06-30T17:10:55.824Z,1751303455.824 [Radio_Surface](INFO): Powering up
2025-06-30T17:11:01.364Z,1751303461.364 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-30T17:11:31.484Z,1751303491.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T17:12:00.280Z,1751303520.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=20288, MT Status=0, MTMSN=0
2025-06-30T17:12:00.280Z,1751303520.280 [NAL9602](INFO): No messages in MT queue
2025-06-30T17:12:01.485Z,1751303521.485 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171203.00,A,3647.46860,N,12153.93046,W,0.136,65.95,300625,,,A*45
2025-06-30T17:12:01.488Z,1751303521.488 [NAL9602](INFO): GPS fix at 20250630T171203: (36.791143, -121.898841)
2025-06-30T17:12:01.499Z,1751303521.499 [UniversalFixResidualReporter](INFO): Fix residual: 25.3 %DT, over the last 2903.0 m. Residual distance 735.4 m at bearing 98.3 degrees. Fix at (36.7911, -121.8988) with 2075.6 m made good.
2025-06-30T17:12:01.507Z,1751303521.507 [keepstation:NeedComms:C] Stopped
2025-06-30T17:12:01.509Z,1751303521.509 [keepstation:NeedComms:D] Running Loop=1
2025-06-30T17:12:06.636Z,1751303526.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T17:12:17.726Z,1751303537.726 [NAL9602](INFO): SBD MO Status=1, MOMSN=20289, MT Status=0, MTMSN=0
2025-06-30T17:12:17.792Z,1751303537.792 [NAL9602](INFO): Sent 82 bytes from file Logs/20250630T145243/Courier0016.lzma
2025-06-30T17:12:17.792Z,1751303537.792 [NAL9602](INFO): Packets left to send: 0
2025-06-30T17:12:27.537Z,1751303547.537 [NAL9602](INFO): SBD MO Status=1, MOMSN=20290, MT Status=0, MTMSN=0
2025-06-30T17:12:27.590Z,1751303547.590 [NAL9602](INFO): Sent 305 bytes from file Logs/20250630T145243/Express0017.lzma
2025-06-30T17:12:27.590Z,1751303547.590 [NAL9602](INFO): Packets left to send: 0
2025-06-30T17:12:41.271Z,1751303561.271 [NAL9602](INFO): SBD MO Status=2, MOMSN=20291, MT Status=2, MTMSN=0
2025-06-30T17:12:41.271Z,1751303561.271 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-30T17:12:41.776Z,1751303561.776 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T17:12:46.288Z,1751303566.288 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-06-30T17:12:48.310Z,1751303568.310 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-06-30T17:12:52.344Z,1751303572.344 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-06-30T17:12:56.785Z,1751303576.785 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-06-30T17:13:06.116Z,1751303586.116 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-06-30T17:13:14.742Z,1751303594.742 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-06-30T17:13:16.924Z,1751303596.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T17:13:26.569Z,1751303606.569 [NAL9602](INFO): SBD MO Status=0, MOMSN=20291, MT Status=0, MTMSN=0
2025-06-30T17:13:26.650Z,1751303606.650 [keepstation:NeedComms:D] Stopped
2025-06-30T17:13:26.650Z,1751303606.650 [keepstation:NeedComms:E] Running Loop=1
2025-06-30T17:13:28.985Z,1751303608.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171330.00,A,3647.47393,N,12153.91835,W,0.136,68.31,300625,,,D*4A
2025-06-30T17:13:28.987Z,1751303608.987 [NAL9602](INFO): GPS fix at 20250630T171330: (36.791232, -121.898639)
2025-06-30T17:13:28.000Z,1751303609.000 [keepstation:NeedComms:E] Stopped
2025-06-30T17:13:29.001Z,1751303609.001 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-30T17:13:29.001Z,1751303609.001 [keepstation:NeedComms] Stopped
2025-06-30T17:13:29.001Z,1751303609.001 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-30T17:13:29.001Z,1751303609.001 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-30T17:13:29.001Z,1751303609.001 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-30T17:13:29.417Z,1751303609.417 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-30T17:13:29.417Z,1751303609.417 [keepstation:SurfaceComms:B] Stopped
2025-06-30T17:13:29.417Z,1751303609.417 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-30T17:13:29.417Z,1751303609.417 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-30T17:13:29.417Z,1751303609.417 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-30T17:13:29.417Z,1751303609.417 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-30T17:13:29.812Z,1751303609.812 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-30T17:13:29.813Z,1751303609.813 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-06-30T17:13:29.813Z,1751303609.813 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-30T17:13:29.813Z,1751303609.813 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-30T17:13:29.813Z,1751303609.813 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-30T17:13:29.813Z,1751303609.813 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-30T17:13:29.813Z,1751303609.813 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-30T17:13:30.207Z,1751303610.207 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-30T17:13:30.208Z,1751303610.208 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-30T17:13:30.208Z,1751303610.208 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-30T17:13:30.208Z,1751303610.208 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-30T17:13:30.208Z,1751303610.208 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-30T17:13:30.208Z,1751303610.208 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-30T17:13:30.209Z,1751303610.209 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-30T17:13:30.209Z,1751303610.209 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-30T17:13:30.209Z,1751303610.209 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-30T17:13:30.209Z,1751303610.209 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-30T17:13:30.209Z,1751303610.209 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-30T17:13:30.209Z,1751303610.209 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-30T17:13:30.209Z,1751303610.209 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-30T17:13:30.209Z,1751303610.209 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-30T17:13:30.210Z,1751303610.210 [keepstation:SurfaceComms:setStation] Stopped
2025-06-30T17:13:30.210Z,1751303610.210 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-30T17:13:30.210Z,1751303610.210 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-30T17:13:30.211Z,1751303610.211 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-30T17:13:30.211Z,1751303610.211 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-30T17:13:30.211Z,1751303610.211 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-30T17:13:30.618Z,1751303610.618 [keepstation:SurfaceComms:setSink] Stopped
2025-06-30T17:13:30.618Z,1751303610.618 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-30T17:13:30.618Z,1751303610.618 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-30T17:13:30.618Z,1751303610.618 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-30T17:13:30.619Z,1751303610.619 [keepstation:SurfaceComms] Stopped
2025-06-30T17:13:30.619Z,1751303610.619 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-30T17:13:52.064Z,1751303632.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T17:14:01.389Z,1751303641.389 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T17:14:27.204Z,1751303667.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T17:15:02.345Z,1751303702.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T17:15:04.284Z,1751303704.284 [Radio_Surface](INFO): Powering down
2025-06-30T17:15:04.352Z,1751303704.352 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-30T17:52:55.697Z,1751305975.697 [CBIT](FAULT): Main Battery Failure
2025-06-30T18:13:29.321Z,1751307209.321 [keepstation:SurfaceComms] Running Loop=1
2025-06-30T18:13:29.321Z,1751307209.321 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-30T18:13:29.321Z,1751307209.321 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-30T18:13:29.322Z,1751307209.322 [keepstation:SurfaceComms:A] Stopped
2025-06-30T18:13:29.322Z,1751307209.322 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-30T18:13:29.322Z,1751307209.322 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-30T18:13:29.324Z,1751307209.324 [keepstation:NeedComms] Running Loop=1
2025-06-30T18:13:29.324Z,1751307209.324 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-30T18:13:29.324Z,1751307209.324 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-30T18:13:29.324Z,1751307209.324 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-30T18:13:29.324Z,1751307209.324 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-30T18:13:29.325Z,1751307209.325 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-30T18:13:29.325Z,1751307209.325 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-30T18:13:29.325Z,1751307209.325 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-30T18:13:29.326Z,1751307209.326 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-30T18:13:29.326Z,1751307209.326 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-30T18:13:29.326Z,1751307209.326 [keepstation:NeedComms:A] Running Loop=1
2025-06-30T18:13:29.328Z,1751307209.328 [keepstation:NeedComms:A](INFO): last time_fix was:  1751303610.000000 second since 1970/01/01T00:00:00Z 
2025-06-30T18:13:29.328Z,1751307209.328 [keepstation:NeedComms:A] Stopped
2025-06-30T18:13:29.732Z,1751307209.732 [keepstation:TransitToStation:Dive] Stopped
2025-06-30T18:13:29.732Z,1751307209.732 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-30T18:13:29.732Z,1751307209.732 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-30T18:13:29.732Z,1751307209.732 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-30T18:13:29.733Z,1751307209.733 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-30T18:14:38.134Z,1751307278.134 [keepstation:NeedComms:C] Running Loop=1
2025-06-30T18:14:38.591Z,1751307278.591 [Radio_Surface](INFO): Powering up
2025-06-30T18:14:44.044Z,1751307284.044 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-30T18:15:14.192Z,1751307314.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T18:15:49.332Z,1751307349.332 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T18:16:24.473Z,1751307384.473 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T18:16:59.612Z,1751307419.612 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T18:17:34.760Z,1751307454.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T18:18:09.900Z,1751307489.900 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T18:18:45.444Z,1751307525.444 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-06-30T18:19:11.029Z,1751307551.029 [DataOverHttps](IMPORTANT): SBD MTMSN=20250630T181912
2025-06-30T18:19:11.032Z,1751307551.032 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003751
2025-06-30T18:19:18.527Z,1751307558.527 [DataOverHttps](INFO): Received command: stop
2025-06-30T18:19:18.541Z,1751307558.541 [CommandExec](IMPORTANT): got command stop
2025-06-30T18:19:18.541Z,1751307558.541 [CommandExec](IMPORTANT): Scheduling is paused
2025-06-30T18:19:18.541Z,1751307558.541 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-06-30T18:19:18.911Z,1751307558.911 [MissionManager](INFO): MissionManager is completed.
2025-06-30T18:19:18.911Z,1751307558.911 [MissionManager](INFO): Uninitializing Mission keepstation
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation] Stopped
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:NeedComms] Stopped
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:NeedComms:C] Stopped
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:StandardEnvelopes] Stopped
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-06-30T18:19:18.912Z,1751307558.912 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:BackseatDriver] Stopped
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:PowerOnly] Stopped
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:PowerOnly:E.Wait] Stopped
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:H.Pitch] Stopped
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:SurfaceComms] Stopped
2025-06-30T18:19:18.913Z,1751307558.913 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-30T18:19:18.914Z,1751307558.914 [keepstation:SurfaceComms:B] Stopped
2025-06-30T18:19:18.914Z,1751307558.914 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-30T18:19:18.914Z,1751307558.914 [keepstation:DiveCmd] Stopped
2025-06-30T18:19:18.914Z,1751307558.914 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2025-06-30T18:19:18.914Z,1751307558.914 [keepstation:TransitToStation] Stopped
2025-06-30T18:19:18.914Z,1751307558.914 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-06-30T18:19:18.914Z,1751307558.914 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-06-30T18:19:18.914Z,1751307558.914 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-06-30T18:19:19.342Z,1751307559.342 [MissionManager](IMPORTANT): Started mission Default
2025-06-30T18:19:19.342Z,1751307559.342 [Default] Running Loop=1
2025-06-30T18:19:19.342Z,1751307559.342 [Default](DEBUG): Aggregate::initialize Default
2025-06-30T18:19:19.342Z,1751307559.342 [Default:B.GoToSurface] Running Loop=1
2025-06-30T18:19:19.342Z,1751307559.342 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-30T18:19:19.343Z,1751307559.343 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-30T18:19:19.343Z,1751307559.343 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-30T18:19:19.343Z,1751307559.343 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-30T18:19:19.344Z,1751307559.344 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-30T18:19:19.344Z,1751307559.344 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-30T18:19:19.344Z,1751307559.344 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-30T18:19:19.345Z,1751307559.345 [Default:A.Wait] Running Loop=1
2025-06-30T18:19:19.345Z,1751307559.345 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-30T18:19:32.642Z,1751307572.642 [Default:A.Wait](INFO): Done Waiting.
2025-06-30T18:19:32.642Z,1751307572.642 [Default:A.Wait] Stopped
2025-06-30T18:19:32.642Z,1751307572.642 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-30T18:19:33.075Z,1751307573.075 [Default:CheckIn] Running Loop=1
2025-06-30T18:19:33.075Z,1751307573.075 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-30T18:19:33.075Z,1751307573.075 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-30T18:19:41.923Z,1751307581.923 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-06-30T18:19:43.149Z,1751307583.149 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181944.00,A,3647.66423,N,12152.46233,W,0.408,116.91,300625,,,A*7B
2025-06-30T18:19:43.151Z,1751307583.151 [NAL9602](INFO): GPS fix at 20250630T181944: (36.794404, -121.874372)
2025-06-30T18:19:43.172Z,1751307583.172 [UniversalFixResidualReporter](INFO): Fix residual: 21.6 %DT, over the last 2900.2 m. Residual distance 627.6 m at bearing 95.9 degrees. Fix at (36.7944, -121.8744) with 2189.5 m made good.
2025-06-30T18:19:43.172Z,1751307583.172 [Default:CheckIn:Read_GPS] Stopped
2025-06-30T18:19:43.173Z,1751307583.173 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-30T18:19:51.412Z,1751307591.412 [CommandExec](IMPORTANT): got command stop
2025-06-30T18:19:55.163Z,1751307595.163 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250630T145243/Courier0019.lzma
2025-06-30T18:20:06.210Z,1751307606.210 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Courier0019.lzma.bak
2025-06-30T18:20:06.210Z,1751307606.210 [DataOverHttps](INFO): SBD MOMSN=25284781
2025-06-30T18:20:14.995Z,1751307614.995 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T18:20:32.364Z,1751307632.364 [DataOverHttps](INFO): Sending 479 bytes from file Logs/20250630T145243/Express0020.lzma
2025-06-30T18:20:43.405Z,1751307643.405 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Express0020.lzma.bak
2025-06-30T18:20:43.405Z,1751307643.405 [DataOverHttps](INFO): SBD MOMSN=25284790
2025-06-30T18:20:44.899Z,1751307644.899 [Default:CheckIn:Read_Iridium] Stopped
2025-06-30T18:20:44.899Z,1751307644.899 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-30T18:20:44.899Z,1751307644.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-30T18:25:45.547Z,1751307945.547 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-30T18:25:45.548Z,1751307945.548 [Default:CheckIn:C.Wait] Stopped
2025-06-30T18:25:45.548Z,1751307945.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-30T18:25:45.548Z,1751307945.548 [Default:CheckIn:D] Running Loop=1
2025-06-30T18:25:45.957Z,1751307945.957 [Default:CheckIn:D] Stopped
2025-06-30T18:25:45.957Z,1751307945.957 [Default:CheckIn:E] Running Loop=1
2025-06-30T18:25:46.355Z,1751307946.355 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  6.443582 min 
2025-06-30T18:25:46.355Z,1751307946.355 [Default:CheckIn:E] Stopped
2025-06-30T18:25:46.355Z,1751307946.355 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-30T18:25:46.356Z,1751307946.356 [Default:CheckIn] Stopped
2025-06-30T18:25:46.356Z,1751307946.356 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-30T18:25:46.356Z,1751307946.356 [Default:CheckIn](INFO): Running loop #2
2025-06-30T18:25:46.356Z,1751307946.356 [Default:CheckIn] Running Loop=2
2025-06-30T18:25:46.356Z,1751307946.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-30T18:25:46.356Z,1751307946.356 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-30T18:25:48.354Z,1751307948.354 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182549.00,A,3647.65516,N,12152.39792,W,0.486,70.63,300625,,,D*44
2025-06-30T18:25:48.366Z,1751307948.366 [NAL9602](INFO): GPS fix at 20250630T182549: (36.794253, -121.873299)
2025-06-30T18:25:48.375Z,1751307948.375 [Default:CheckIn:Read_GPS] Stopped
2025-06-30T18:25:48.375Z,1751307948.375 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-30T18:25:55.431Z,1751307955.431 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250630T145243/Courier0022.lzma
2025-06-30T18:26:06.473Z,1751307966.473 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Courier0022.lzma.bak
2025-06-30T18:26:06.473Z,1751307966.473 [DataOverHttps](INFO): SBD MOMSN=25284847
2025-06-30T18:26:19.062Z,1751307979.062 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-06-30T18:26:19.135Z,1751307979.135 [NAL9602](ERROR): received: 
+CSQ:0

OK291, 0, 0, 0, 0

OK
2025-06-30T18:26:31.595Z,1751307991.595 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20250630T145243/Express0024.lzma
2025-06-30T18:26:41.633Z,1751308001.633 [DataOverHttps](INFO): Moved sent file to Logs/20250630T145243/Express0024.lzma.bak
2025-06-30T18:26:41.634Z,1751308001.634 [DataOverHttps](INFO): SBD MOMSN=25284850
2025-06-30T18:26:42.910Z,1751308002.910 [Default:CheckIn:Read_Iridium] Stopped
2025-06-30T18:26:42.911Z,1751308002.911 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-30T18:26:42.911Z,1751308002.911 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-30T18:27:13.168Z,1751308033.168 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-06-30T18:28:41.852Z,1751308121.852 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239460
2025-06-30T18:29:00.264Z,1751308140.264 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=20292, MT Status=1, MTMSN=1292
2025-06-30T18:29:00.264Z,1751308140.264 [NAL9602](INFO): Data available in MT queue
2025-06-30T18:29:00.752Z,1751308140.752 [NAL9602](INFO): Received command: stop
2025-06-30T18:29:00.781Z,1751308140.781 [CommandExec](IMPORTANT): got command stop
2025-06-30T18:29:31.368Z,1751308171.368 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-30T18:29:34.060Z,1751308174.060 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-30T18:30:45.125Z,1751308245.125 [DataOverHttps](IMPORTANT): SBD MTMSN=20250630T183043
2025-06-30T18:30:45.128Z,1751308245.128 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003730
2025-06-30T18:30:52.679Z,1751308252.679 [DataOverHttps](INFO): Received command: restart logs