2025-08-16T00:28:39.856Z,1755304119.856 [Supervisor](DEBUG): Initializing supervisor.
2025-08-16T00:28:39.860Z,1755304119.860 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-08-16T00:28:39.861Z,1755304119.861 [SyncHandler](INFO): Protected caller Thread ID is 2964
2025-08-16T00:28:39.861Z,1755304119.861 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-08-16T00:28:39.862Z,1755304119.862 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-08-16T00:28:39.862Z,1755304119.862 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2965
2025-08-16T00:28:39.866Z,1755304119.866 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-08-16T00:28:39.886Z,1755304119.886 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-08-16T00:28:39.887Z,1755304119.887 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-08-16T00:28:39.888Z,1755304119.888 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2966
2025-08-16T00:28:39.892Z,1755304119.892 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-08-16T00:28:39.893Z,1755304119.893 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-08-16T00:28:39.894Z,1755304119.894 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2967
2025-08-16T00:28:39.896Z,1755304119.896 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-08-16T00:28:39.897Z,1755304119.897 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-08-16T00:28:39.897Z,1755304119.897 [logger ThreadHandler](INFO): Protected caller Thread ID is 2968
2025-08-16T00:28:39.901Z,1755304119.901 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-08-16T00:28:39.901Z,1755304119.901 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-08-16T00:28:39.903Z,1755304119.903 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-08-16T00:28:40.010Z,1755304120.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-08-16T00:28:40.010Z,1755304120.010 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-08-16T00:28:40.249Z,1755304120.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-08-16T00:28:40.250Z,1755304120.250 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-08-16T00:28:40.522Z,1755304120.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-08-16T00:28:40.522Z,1755304120.522 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-08-16T00:28:40.640Z,1755304120.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-08-16T00:28:40.641Z,1755304120.641 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-08-16T00:28:41.533Z,1755304121.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-08-16T00:28:41.534Z,1755304121.534 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-08-16T00:28:42.075Z,1755304122.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-08-16T00:28:42.075Z,1755304122.075 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-08-16T00:28:42.618Z,1755304122.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-08-16T00:28:42.619Z,1755304122.619 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-08-16T00:28:43.056Z,1755304123.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-08-16T00:28:43.057Z,1755304123.057 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-08-16T00:28:43.412Z,1755304123.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-08-16T00:28:43.413Z,1755304123.413 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-08-16T00:28:43.810Z,1755304123.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-08-16T00:28:43.811Z,1755304123.811 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-08-16T00:28:44.047Z,1755304124.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-08-16T00:28:44.047Z,1755304124.047 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-08-16T00:28:44.208Z,1755304124.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-08-16T00:28:44.209Z,1755304124.209 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-08-16T00:28:44.312Z,1755304124.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-08-16T00:28:44.312Z,1755304124.312 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-08-16T00:28:44.403Z,1755304124.403 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-08-16T00:28:44.596Z,1755304124.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-08-16T00:28:44.597Z,1755304124.597 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-08-16T00:28:44.852Z,1755304124.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-08-16T00:28:44.854Z,1755304124.854 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-08-16T00:28:44.855Z,1755304124.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-08-16T00:28:44.950Z,1755304124.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-08-16T00:28:45.232Z,1755304125.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-08-16T00:28:45.233Z,1755304125.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-08-16T00:28:45.329Z,1755304125.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-08-16T00:28:45.508Z,1755304125.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-08-16T00:28:45.620Z,1755304125.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-08-16T00:28:45.740Z,1755304125.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-08-16T00:28:45.861Z,1755304125.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-08-16T00:28:45.966Z,1755304125.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-08-16T00:28:46.134Z,1755304126.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-08-16T00:28:46.242Z,1755304126.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-08-16T00:28:46.338Z,1755304126.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-08-16T00:28:46.446Z,1755304126.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-08-16T00:28:46.551Z,1755304126.551 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2025-08-16T00:28:46.552Z,1755304126.552 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-08-16T00:28:46.560Z,1755304126.560 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-08-16T00:28:47.369Z,1755304127.369 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-08-16T00:28:47.370Z,1755304127.370 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-08-16T00:28:47.726Z,1755304127.726 [BuoyancyServo] Loaded
2025-08-16T00:28:47.726Z,1755304127.726 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-08-16T00:28:47.750Z,1755304127.750 [ElevatorServo] Loaded
2025-08-16T00:28:47.750Z,1755304127.750 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-08-16T00:28:47.773Z,1755304127.773 [MassServo] Loaded
2025-08-16T00:28:47.773Z,1755304127.773 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-08-16T00:28:47.795Z,1755304127.795 [RudderServo] Loaded
2025-08-16T00:28:47.795Z,1755304127.795 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-08-16T00:28:47.814Z,1755304127.814 [ThrusterHE] Loaded
2025-08-16T00:28:47.814Z,1755304127.814 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-08-16T00:28:47.814Z,1755304127.814 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-08-16T00:28:47.815Z,1755304127.815 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-08-16T00:28:47.983Z,1755304127.983 [SBIT](DEBUG): Construct Startup Built In Test.
2025-08-16T00:28:47.998Z,1755304127.998 [SBIT] Loaded
2025-08-16T00:28:47.999Z,1755304127.999 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-08-16T00:28:48.001Z,1755304128.001 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-08-16T00:28:48.023Z,1755304128.023 [IBIT] Loaded
2025-08-16T00:28:48.023Z,1755304128.023 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-08-16T00:28:48.028Z,1755304128.028 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-08-16T00:28:48.228Z,1755304128.228 [CBIT] Loaded
2025-08-16T00:28:48.228Z,1755304128.228 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-08-16T00:28:48.260Z,1755304128.260 [GFScanner] Loaded
2025-08-16T00:28:48.260Z,1755304128.260 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-08-16T00:28:48.261Z,1755304128.261 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-08-16T00:28:48.261Z,1755304128.261 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-08-16T00:28:48.434Z,1755304128.434 [CTD_Seabird] Loaded
2025-08-16T00:28:48.434Z,1755304128.434 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-08-16T00:28:48.435Z,1755304128.435 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406874E0
2025-08-16T00:28:48.435Z,1755304128.435 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3056
2025-08-16T00:28:48.471Z,1755304128.471 [ESPComponent] Loaded
2025-08-16T00:28:48.471Z,1755304128.471 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-08-16T00:28:48.493Z,1755304128.493 [PAR_Licor] Loaded
2025-08-16T00:28:48.494Z,1755304128.494 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-08-16T00:28:48.539Z,1755304128.539 [WetLabsBB2FL] Loaded
2025-08-16T00:28:48.539Z,1755304128.539 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-08-16T00:28:48.540Z,1755304128.540 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B74E0
2025-08-16T00:28:48.540Z,1755304128.540 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3057
2025-08-16T00:28:48.541Z,1755304128.541 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-08-16T00:28:48.542Z,1755304128.542 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-08-16T00:28:48.594Z,1755304128.594 [DepthRateCalculator] Loaded
2025-08-16T00:28:48.594Z,1755304128.594 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-08-16T00:28:48.599Z,1755304128.599 [PitchRateCalculator] Loaded
2025-08-16T00:28:48.599Z,1755304128.599 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-08-16T00:28:48.610Z,1755304128.610 [SpeedCalculator] Loaded
2025-08-16T00:28:48.610Z,1755304128.610 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-08-16T00:28:48.615Z,1755304128.615 [YawRateCalculator] Loaded
2025-08-16T00:28:48.615Z,1755304128.615 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-08-16T00:28:48.635Z,1755304128.635 [ElevatorOffsetCalculator] Loaded
2025-08-16T00:28:48.636Z,1755304128.636 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-08-16T00:28:48.636Z,1755304128.636 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-08-16T00:28:48.637Z,1755304128.637 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-08-16T00:28:48.776Z,1755304128.776 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-08-16T00:28:48.776Z,1755304128.776 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-08-16T00:28:48.806Z,1755304128.806 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-08-16T00:28:48.807Z,1755304128.807 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-08-16T00:28:48.923Z,1755304128.923 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-08-16T00:28:48.924Z,1755304128.924 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-08-16T00:28:48.938Z,1755304128.938 [NavChart] Loaded
2025-08-16T00:28:48.938Z,1755304128.938 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-08-16T00:28:48.944Z,1755304128.944 [UniversalFixResidualReporter] Loaded
2025-08-16T00:28:48.944Z,1755304128.944 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-08-16T00:28:48.945Z,1755304128.945 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-08-16T00:28:48.945Z,1755304128.945 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-08-16T00:28:49.439Z,1755304129.439 [AHRS_M2] Loaded
2025-08-16T00:28:49.439Z,1755304129.439 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-08-16T00:28:49.479Z,1755304129.479 [BackseatComponent] Loaded
2025-08-16T00:28:49.479Z,1755304129.479 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-08-16T00:28:49.480Z,1755304129.480 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0
2025-08-16T00:28:49.481Z,1755304129.481 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3058
2025-08-16T00:28:49.483Z,1755304129.483 [LcmUniversalReporter] Loaded
2025-08-16T00:28:49.484Z,1755304129.484 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-08-16T00:28:50.251Z,1755304130.251 [BPC1] Loaded
2025-08-16T00:28:50.251Z,1755304130.251 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-08-16T00:28:50.355Z,1755304130.355 [DAT] Loaded
2025-08-16T00:28:50.355Z,1755304130.355 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-08-16T00:28:50.356Z,1755304130.356 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AB34E0
2025-08-16T00:28:50.357Z,1755304130.357 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3059
2025-08-16T00:28:50.432Z,1755304130.432 [DataOverHttps] Loaded
2025-08-16T00:28:50.432Z,1755304130.432 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-08-16T00:28:50.433Z,1755304130.433 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AE34E0
2025-08-16T00:28:50.434Z,1755304130.434 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3060
2025-08-16T00:28:50.458Z,1755304130.458 [Depth_Keller] Loaded
2025-08-16T00:28:50.458Z,1755304130.458 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-08-16T00:28:50.501Z,1755304130.501 [DVL_micro] Loaded
2025-08-16T00:28:50.501Z,1755304130.501 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-08-16T00:28:50.566Z,1755304130.566 [NAL9602] Loaded
2025-08-16T00:28:50.566Z,1755304130.566 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-08-16T00:28:50.592Z,1755304130.592 [Onboard] Loaded
2025-08-16T00:28:50.593Z,1755304130.593 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-08-16T00:28:50.594Z,1755304130.594 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B134E0
2025-08-16T00:28:50.594Z,1755304130.594 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3061
2025-08-16T00:28:50.609Z,1755304130.609 [Power24vConverter] Loaded
2025-08-16T00:28:50.609Z,1755304130.609 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-08-16T00:28:50.625Z,1755304130.625 [Radio_Surface] Loaded
2025-08-16T00:28:50.626Z,1755304130.626 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-08-16T00:28:50.627Z,1755304130.627 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B434E0
2025-08-16T00:28:50.627Z,1755304130.627 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3062
2025-08-16T00:28:50.643Z,1755304130.643 [Sonardyne_Nano] Loaded
2025-08-16T00:28:50.643Z,1755304130.643 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-08-16T00:28:50.644Z,1755304130.644 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-08-16T00:28:50.645Z,1755304130.645 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-08-16T00:28:50.705Z,1755304130.705 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-08-16T00:28:50.706Z,1755304130.706 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-08-16T00:28:50.762Z,1755304130.762 [VerticalControl](DEBUG): Construct VerticalControl.
2025-08-16T00:28:50.814Z,1755304130.814 [VerticalControl] Loaded
2025-08-16T00:28:50.815Z,1755304130.815 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-08-16T00:28:50.818Z,1755304130.818 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-08-16T00:28:50.863Z,1755304130.863 [HorizontalControl] Loaded
2025-08-16T00:28:50.863Z,1755304130.863 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-08-16T00:28:50.866Z,1755304130.866 [SpeedControl](DEBUG): Construct SpeedControl.
2025-08-16T00:28:50.867Z,1755304130.867 [SpeedControl] Loaded
2025-08-16T00:28:50.868Z,1755304130.868 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-08-16T00:28:50.871Z,1755304130.871 [LoopControl](DEBUG): Construct LoopControl.
2025-08-16T00:28:50.871Z,1755304130.871 [LoopControl] Loaded
2025-08-16T00:28:50.871Z,1755304130.871 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-08-16T00:28:50.872Z,1755304130.872 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-08-16T00:28:50.872Z,1755304130.872 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-08-16T00:28:50.883Z,1755304130.883 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-08-16T00:28:50.883Z,1755304130.883 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-08-16T00:28:51.241Z,1755304131.241 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-08-16T00:28:51.247Z,1755304131.247 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-08-16T00:28:51.250Z,1755304131.250 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-08-16T00:28:51.262Z,1755304131.262 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-08-16T00:28:51.263Z,1755304131.263 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6A4E0
2025-08-16T00:28:51.264Z,1755304131.264 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3063
2025-08-16T00:28:51.268Z,1755304131.268 [Supervisor](INFO): Main Thread ID is 822
2025-08-16T00:28:51.268Z,1755304131.268 [Supervisor](DEBUG): Running supervisor.
2025-08-16T00:28:51.269Z,1755304131.269 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3064
2025-08-16T00:28:51.269Z,1755304131.269 [CommandExec](INFO): Initializing the command executive.
2025-08-16T00:28:51.271Z,1755304131.271 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3065
2025-08-16T00:28:51.274Z,1755304131.274 [controlThread ThreadHandler](INFO): Handler Thread ID is 3066
2025-08-16T00:28:51.274Z,1755304131.274 [controlThread](DEBUG): Initializing ControlThread
2025-08-16T00:28:51.276Z,1755304131.276 [SBIT](INFO): Initialize SBIT Component.
2025-08-16T00:28:51.276Z,1755304131.276 [SBIT](IMPORTANT): git: 2025-08-15
2025-08-16T00:28:51.277Z,1755304131.277 [SBIT](INFO): git hash: aa1cd939c0bb5080a316c7313ffe69d6279cb338
2025-08-16T00:28:51.277Z,1755304131.277 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-08-16T00:28:51.278Z,1755304131.278 [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-08-16T00:28:51.279Z,1755304131.279 [SBIT](INFO): Beginning SBIT in 147.000000 seconds.
2025-08-16T00:28:51.280Z,1755304131.280 [IBIT](INFO): Initialize IBIT Component.
2025-08-16T00:28:51.281Z,1755304131.281 [CBIT](DEBUG): Initialize CBIT Component.
2025-08-16T00:28:51.281Z,1755304131.281 [logger ThreadHandler](INFO): Handler Thread ID is 3067
2025-08-16T00:28:51.292Z,1755304131.292 [CBIT](DEBUG): Initialized mux pins.
2025-08-16T00:28:51.292Z,1755304131.292 [CBIT](DEBUG): Initializing the watchdog timer.
2025-08-16T00:28:51.304Z,1755304131.304 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3068
2025-08-16T00:28:51.305Z,1755304131.305 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-08-16T00:28:51.309Z,1755304131.309 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3070
2025-08-16T00:28:51.312Z,1755304131.312 [WetLabsBB2FL](INFO): Powering up
2025-08-16T00:28:51.314Z,1755304131.314 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3072
2025-08-16T00:28:51.316Z,1755304131.316 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-08-16T00:28:51.316Z,1755304131.316 [CBIT](DEBUG): Initializing heartbeat.
2025-08-16T00:28:51.332Z,1755304131.332 [DAT ThreadHandler](INFO): Handler Thread ID is 3073
2025-08-16T00:28:51.333Z,1755304131.333 [DAT](INFO): Powering up
2025-08-16T00:28:51.333Z,1755304131.333 [DAT](DEBUG): Initializing DAT.
2025-08-16T00:28:51.337Z,1755304131.337 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3075
2025-08-16T00:28:51.339Z,1755304131.339 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-08-16T00:28:51.352Z,1755304131.352 [Onboard ThreadHandler](INFO): Handler Thread ID is 3076
2025-08-16T00:28:51.369Z,1755304131.369 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3077
2025-08-16T00:28:51.388Z,1755304131.388 [CBIT](DEBUG): Deactivating emergency mode.
2025-08-16T00:28:51.388Z,1755304131.388 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3078
2025-08-16T00:28:51.391Z,1755304131.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-08-16T00:28:51.391Z,1755304131.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-08-16T00:28:51.391Z,1755304131.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-08-16T00:28:51.391Z,1755304131.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-08-16T00:28:51.391Z,1755304131.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-08-16T00:28:51.391Z,1755304131.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-08-16T00:28:51.392Z,1755304131.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-08-16T00:28:51.392Z,1755304131.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-08-16T00:28:51.428Z,1755304131.428 [CBIT](DEBUG): Backplane powered.
2025-08-16T00:28:51.428Z,1755304131.428 [GFScanner](DEBUG): Initializing GFScanner
2025-08-16T00:28:51.428Z,1755304131.428 [GFScanner](DEBUG): Deactivating GF circuits.
2025-08-16T00:28:51.436Z,1755304131.436 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-08-16T00:28:51.437Z,1755304131.437 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-08-16T00:28:51.437Z,1755304131.437 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-08-16T00:28:51.437Z,1755304131.437 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-08-16T00:28:51.437Z,1755304131.437 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-08-16T00:28:51.439Z,1755304131.439 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-08-16T00:28:51.439Z,1755304131.439 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-08-16T00:28:51.441Z,1755304131.441 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-08-16T00:28:51.443Z,1755304131.443 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-08-16T00:28:51.444Z,1755304131.444 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-08-16T00:28:51.444Z,1755304131.444 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-08-16T00:28:51.445Z,1755304131.445 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-08-16T00:28:51.446Z,1755304131.446 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-08-16T00:28:51.446Z,1755304131.446 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-08-16T00:28:51.447Z,1755304131.447 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-08-16T00:28:51.536Z,1755304131.536 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-08-16T00:28:51.541Z,1755304131.541 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-08-16T00:28:51.594Z,1755304131.594 [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-08-16T00:28:51.595Z,1755304131.595 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-08-16T00:28:51.595Z,1755304131.595 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-08-16T00:28:51.604Z,1755304131.604 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-08-16T00:28:51.776Z,1755304131.776 [Radio_Surface](INFO): Powering up
2025-08-16T00:28:52.024Z,1755304132.024 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-08-16T00:28:52.029Z,1755304132.029 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-08-16T00:28:52.031Z,1755304132.031 [Default:A.Wait](DEBUG): Construct Wait.
2025-08-16T00:28:52.057Z,1755304132.057 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-08-16T00:28:52.079Z,1755304132.079 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-08-16T00:28:52.102Z,1755304132.102 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-08-16T00:28:52.124Z,1755304132.124 [Default:E.Execute](DEBUG): Construct Execute.
2025-08-16T00:28:52.127Z,1755304132.127 [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-08-16T00:28:52.148Z,1755304132.148 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-08-16T00:28:52.173Z,1755304132.173 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-08-16T00:28:52.240Z,1755304132.240 [Depth_Keller](INFO): Initializing.
2025-08-16T00:28:52.245Z,1755304132.245 [Power24vConverter](INFO): Powering up.
2025-08-16T00:28:52.246Z,1755304132.246 [Sonardyne_Nano](INFO): Initializing.
2025-08-16T00:28:52.272Z,1755304132.272 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-08-16T00:28:52.295Z,1755304132.295 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-08-16T00:28:52.300Z,1755304132.300 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-08-16T00:28:52.301Z,1755304132.301 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-08-16T00:28:52.309Z,1755304132.309 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-08-16T00:28:52.310Z,1755304132.310 [MassServo](DEBUG): Initializing EZServoServo.
2025-08-16T00:28:52.316Z,1755304132.316 [MassServo](DEBUG): Initializing MassServo.
2025-08-16T00:28:52.317Z,1755304132.317 [RudderServo](DEBUG): Initializing EZServoServo.
2025-08-16T00:28:52.324Z,1755304132.324 [RudderServo](DEBUG): Initializing RudderServo.
2025-08-16T00:28:52.325Z,1755304132.325 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-08-16T00:28:52.333Z,1755304132.333 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-08-16T00:28:53.924Z,1755304133.924 [WetLabsBB2FL](INFO): Powering down
2025-08-16T00:28:54.182Z,1755304134.182 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-08-16T00:28:55.828Z,1755304135.828 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-08-16T00:29:03.432Z,1755304143.432 [DAT](INFO): DAT read:
2025-08-16T00:29:03.434Z,1755304143.434 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-08-16T00:29:05.197Z,1755304145.197 [DAT](INFO): DAT read: MF Frequency Band
2025-08-16T00:29:05.198Z,1755304145.198 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-08-16T00:29:05.199Z,1755304145.199 [DAT](INFO): DAT read: Aug 16 2025 00:28:59
2025-08-16T00:29:06.461Z,1755304146.461 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-08-16T00:29:06.462Z,1755304146.462 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-08-16T00:29:06.462Z,1755304146.462 [DAT](INFO): commRate: 1200
2025-08-16T00:29:08.528Z,1755304148.528 [DAT](INFO): entering command mode
2025-08-16T00:29:08.728Z,1755304148.728 [DAT](INFO): DAT read:
2025-08-16T00:29:08.729Z,1755304148.729 [DAT](INFO): DAT read: user:1>
2025-08-16T00:29:08.729Z,1755304148.729 [DAT](INFO): setting verbose to 3
2025-08-16T00:29:08.981Z,1755304148.981 [DAT](INFO): DAT read: user:1>
2025-08-16T00:29:08.982Z,1755304148.982 [DAT](INFO): DAT read: Verbose | 3
2025-08-16T00:29:08.982Z,1755304148.982 [DAT](INFO): set verbose to 3
2025-08-16T00:29:08.982Z,1755304148.982 [DAT](INFO): setting DatVerbose to 27440
2025-08-16T00:29:09.233Z,1755304149.233 [DAT](INFO): DAT read: user:2>
2025-08-16T00:29:09.234Z,1755304149.234 [DAT](INFO): DAT read: DatVerbose | 27440
2025-08-16T00:29:09.234Z,1755304149.234 [DAT](INFO): set DatVerbose to 27440
2025-08-16T00:29:09.234Z,1755304149.234 [DAT](INFO): setting transmit power to 8
2025-08-16T00:29:09.485Z,1755304149.485 [DAT](INFO): DAT read: user:3>
2025-08-16T00:29:09.486Z,1755304149.486 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-08-16T00:29:09.486Z,1755304149.486 [DAT](INFO): set transmit power to 8
2025-08-16T00:29:09.486Z,1755304149.486 [DAT](INFO): setting local address to 5
2025-08-16T00:29:09.737Z,1755304149.737 [DAT](INFO): DAT read: user:4>
2025-08-16T00:29:09.738Z,1755304149.738 [DAT](INFO): DAT read: LocalAddr | 5
2025-08-16T00:29:09.739Z,1755304149.739 [DAT](INFO): set local address to 5
2025-08-16T00:29:09.740Z,1755304149.740 [DAT](INFO): Setting time to: 0:29:9 And date to:8/16/2025
2025-08-16T00:29:09.990Z,1755304149.990 [DAT](INFO): DAT read: user:5>
2025-08-16T00:29:09.991Z,1755304149.991 [DAT](INFO): DAT read: Sat Aug 16, 2025 00:29:09
2025-08-16T00:29:09.992Z,1755304149.992 [DAT](INFO): Local DAT time set to Sat Aug 16, 2025 00:29:09
2025-08-16T00:29:20.863Z,1755304160.863 [NAL9602](INFO): Powering up NAL9602
2025-08-16T00:29:31.759Z,1755304171.759 [NAL9602](INFO): NAL9602 initialized
2025-08-16T00:30:40.561Z,1755304240.561 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-08-16T00:30:54.236Z,1755304254.236 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-08-16T00:31:03.358Z,1755304263.358 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004976
2025-08-16T00:31:06.759Z,1755304266.759 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-08-16T00:31:18.965Z,1755304278.965 [SBIT](IMPORTANT): Beginning Startup BIT
2025-08-16T00:31:18.970Z,1755304278.970 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-08-16T00:31:23.496Z,1755304283.496 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011853
CHAN A1 (24V): 0.002434
CHAN A2 (12V): -0.005510
CHAN A3 (5V): -0.003101
CHAN B0 (3.3V): -0.000605
CHAN B1 (3.15aV): -0.001391
CHAN B2 (3.15bV): -0.001638
CHAN B3 (GND): -0.000292
OPEN: 0.006595
Full Scale: +/- 1 mA
2025-08-16T00:31:47.220Z,1755304307.220 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-08-16T00:31:51.640Z,1755304311.640 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-08-16T00:31:51.640Z,1755304311.640 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-08-16T00:31:51.678Z,1755304311.678 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-08-16T00:31:52.061Z,1755304312.061 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-08-16T00:31:52.061Z,1755304312.061 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-08-16T00:32:12.655Z,1755304332.655 [SBIT](IMPORTANT): SBIT PASSED
2025-08-16T00:32:12.655Z,1755304332.655 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-08-16T00:32:12.672Z,1755304332.672 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2025-08-16T00:32:12.673Z,1755304332.673 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;
2025-08-16T00:32:12.673Z,1755304332.673 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2025-08-16T00:32:12.673Z,1755304332.673 [SBIT](IMPORTANT): ElevatorServo.currLimit=50 percent;
2025-08-16T00:32:12.673Z,1755304332.673 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;
2025-08-16T00:32:12.673Z,1755304332.673 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=1 bool;
2025-08-16T00:32:12.673Z,1755304332.673 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=264.941384 cubic_centimeter;
2025-08-16T00:32:12.673Z,1755304332.673 [SBIT](IMPORTANT): VerticalControl.massDefault=31.919755 millimeter;
2025-08-16T00:32:13.061Z,1755304333.061 [MissionManager](IMPORTANT): Started mission Startup
2025-08-16T00:32:13.062Z,1755304333.062 [Startup] Running Loop=1
2025-08-16T00:32:13.062Z,1755304333.062 [Startup](DEBUG): Aggregate::initialize Startup
2025-08-16T00:32:13.062Z,1755304333.062 [Startup:A.GoToSurface] Running Loop=1
2025-08-16T00:32:13.062Z,1755304333.062 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T00:32:13.063Z,1755304333.063 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-08-16T00:32:13.063Z,1755304333.063 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-08-16T00:32:13.063Z,1755304333.063 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-08-16T00:32:13.080Z,1755304333.080 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-08-16T00:32:13.080Z,1755304333.080 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T00:32:13.081Z,1755304333.081 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T00:32:13.082Z,1755304333.082 [Startup:StartupSatComms] Running Loop=1
2025-08-16T00:32:13.082Z,1755304333.082 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-08-16T00:32:13.082Z,1755304333.082 [Startup:StartupSatComms:A] Running Loop=1
2025-08-16T00:32:13.527Z,1755304333.527 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-08-16T00:33:13.273Z,1755304393.273 [Startup:StartupSatComms:A](INFO): Timed out from 2025-08-16T00:32:13.1Z
2025-08-16T00:33:13.274Z,1755304393.274 [Startup:StartupSatComms:A] Stopped
2025-08-16T00:33:13.274Z,1755304393.274 [Startup:StartupSatComms:B] Running Loop=1
2025-08-16T00:33:13.686Z,1755304393.686 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-08-16T00:33:20.991Z,1755304400.991 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250815T232729/Courier0055.lzma
2025-08-16T00:33:21.994Z,1755304401.994 [DataOverHttps](INFO): Moved sent file to Logs/20250815T232729/Courier0055.lzma.bak
2025-08-16T00:33:21.994Z,1755304401.994 [DataOverHttps](INFO): SBD MOMSN=25854375
2025-08-16T00:33:37.956Z,1755304417.956 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250816T002839/Courier0000.lzma
2025-08-16T00:33:38.958Z,1755304418.958 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0000.lzma.bak
2025-08-16T00:33:38.958Z,1755304418.958 [DataOverHttps](INFO): SBD MOMSN=25854382
2025-08-16T00:33:57.184Z,1755304437.184 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20250815T232729/Express0053.lzma
2025-08-16T00:33:58.186Z,1755304438.186 [DataOverHttps](INFO): Moved sent file to Logs/20250815T232729/Express0053.lzma.bak
2025-08-16T00:33:58.187Z,1755304438.187 [DataOverHttps](INFO): SBD MOMSN=25854392
2025-08-16T00:34:13.474Z,1755304453.474 [Startup:StartupSatComms:B](INFO): Timed out from 2025-08-16T00:33:13.3Z
2025-08-16T00:34:13.474Z,1755304453.474 [Startup:StartupSatComms:B] Stopped
2025-08-16T00:34:13.474Z,1755304453.474 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-08-16T00:34:13.474Z,1755304453.474 [Startup:StartupSatComms] Stopped
2025-08-16T00:34:13.474Z,1755304453.474 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-08-16T00:34:13.475Z,1755304453.475 [Startup](INFO): Completed Startup
2025-08-16T00:34:13.475Z,1755304453.475 [MissionManager](INFO): Startup is completed.
2025-08-16T00:34:13.475Z,1755304453.475 [MissionManager](INFO): Uninitializing Mission Startup
2025-08-16T00:34:13.475Z,1755304453.475 [Startup] Stopped
2025-08-16T00:34:13.475Z,1755304453.475 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-08-16T00:34:13.475Z,1755304453.475 [Startup:A.GoToSurface] Stopped
2025-08-16T00:34:13.475Z,1755304453.475 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T00:34:13.861Z,1755304453.861 [MissionManager](IMPORTANT): Started mission Default
2025-08-16T00:34:13.862Z,1755304453.862 [Default] Running Loop=1
2025-08-16T00:34:13.862Z,1755304453.862 [Default](DEBUG): Aggregate::initialize Default
2025-08-16T00:34:13.862Z,1755304453.862 [Default:B.GoToSurface] Running Loop=1
2025-08-16T00:34:13.862Z,1755304453.862 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T00:34:13.862Z,1755304453.862 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-08-16T00:34:13.862Z,1755304453.862 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-08-16T00:34:13.863Z,1755304453.863 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-08-16T00:34:13.863Z,1755304453.863 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-08-16T00:34:13.863Z,1755304453.863 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T00:34:13.868Z,1755304453.868 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T00:34:13.868Z,1755304453.868 [Default:A.Wait] Running Loop=1
2025-08-16T00:34:13.868Z,1755304453.868 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-08-16T00:34:15.520Z,1755304455.520 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250815T232729/Express0056.lzma
2025-08-16T00:34:16.514Z,1755304456.514 [DataOverHttps](INFO): Moved sent file to Logs/20250815T232729/Express0056.lzma.bak
2025-08-16T00:34:16.514Z,1755304456.514 [DataOverHttps](INFO): SBD MOMSN=25854396
2025-08-16T00:34:27.181Z,1755304467.181 [Default:A.Wait](INFO): Done Waiting.
2025-08-16T00:34:27.181Z,1755304467.181 [Default:A.Wait] Stopped
2025-08-16T00:34:27.181Z,1755304467.181 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-08-16T00:34:27.676Z,1755304467.676 [Default:CheckIn] Running Loop=1
2025-08-16T00:34:27.677Z,1755304467.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-16T00:34:27.677Z,1755304467.677 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-16T00:34:28.151Z,1755304468.151 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-16T00:34:39.754Z,1755304479.754 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-08-16T00:34:43.757Z,1755304483.757 [NAL9602](INFO): SBD MO Status=2, MOMSN=21844, MT Status=2, MTMSN=0
2025-08-16T00:34:43.757Z,1755304483.757 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-16T00:34:44.153Z,1755304484.153 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-16T00:34:52.660Z,1755304492.660 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-08-16T00:34:52.660Z,1755304492.660 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-08-16T00:34:52.675Z,1755304492.675 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-08-16T00:34:53.090Z,1755304493.090 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-08-16T00:34:53.090Z,1755304493.090 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-08-16T00:35:33.432Z,1755304533.432 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003533.00,A,3648.17446,N,12147.28178,W,1.263,334.78,160825,,,A*73
2025-08-16T00:35:33.436Z,1755304533.436 [NAL9602](INFO): GPS fix at 20250816T003533: (36.802908, -121.788030)
2025-08-16T00:35:33.446Z,1755304533.446 [Default:CheckIn:Read_GPS] Stopped
2025-08-16T00:35:33.446Z,1755304533.446 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-16T00:35:33.909Z,1755304533.909 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-08-16T00:35:41.140Z,1755304541.140 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250816T002839/Courier0004.lzma
2025-08-16T00:35:42.142Z,1755304542.142 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0004.lzma.bak
2025-08-16T00:35:42.142Z,1755304542.142 [DataOverHttps](INFO): SBD MOMSN=25854402
2025-08-16T00:36:01.045Z,1755304561.045 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-08-16T00:36:02.595Z,1755304562.595 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250815T232729/Express0059.lzma
2025-08-16T00:36:03.598Z,1755304563.598 [DataOverHttps](INFO): Moved sent file to Logs/20250815T232729/Express0059.lzma.bak
2025-08-16T00:36:03.598Z,1755304563.598 [DataOverHttps](INFO): SBD MOMSN=25854405
2025-08-16T00:36:06.178Z,1755304566.178 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-16T00:36:18.687Z,1755304578.687 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-16T00:36:18.687Z,1755304578.687 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD4.
2025-08-16T00:36:18.689Z,1755304578.689 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-16T00:36:18.696Z,1755304578.696 [BPC1](INFO): Received data from all battery sticks.
2025-08-16T00:36:21.473Z,1755304581.473 [DataOverHttps](INFO): Sending 885 bytes from file Logs/20250816T002839/Express0001.lzma
2025-08-16T00:36:22.470Z,1755304582.470 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Express0001.lzma.bak
2025-08-16T00:36:22.470Z,1755304582.470 [DataOverHttps](INFO): SBD MOMSN=25854407
2025-08-16T00:36:40.916Z,1755304600.916 [DataOverHttps](INFO): Sending 411 bytes from file Logs/20250816T002839/Express0005.lzma
2025-08-16T00:36:41.918Z,1755304601.918 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Express0005.lzma.bak
2025-08-16T00:36:41.918Z,1755304601.918 [DataOverHttps](INFO): SBD MOMSN=25854428
2025-08-16T00:36:44.576Z,1755304604.576 [Default:CheckIn:Read_Iridium] Stopped
2025-08-16T00:36:44.576Z,1755304604.576 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-16T00:36:44.576Z,1755304604.576 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-16T00:39:03.442Z,1755304743.442 [DataOverHttps](IMPORTANT): SBD MTMSN=20250816T003902
2025-08-16T00:39:10.886Z,1755304750.886 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.NeedCommsTime 4 min;set keepstation.Depth 0.01 m;set keepstation.Speed 0 m/s;set keepstation.MinOffshore 0.00001 km;set keepstation:NeedComms.GPSTimeout 2 min" togm 1 2
2025-08-16T00:39:10.968Z,1755304750.968 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.NeedCommsTime 4 min;set keepstation.Depth 0.01 m;set keepstation.Speed 0 m/s;set keepstation.MinOffshore 0.00001 km;set keepstation:NeedComms.GPSTimeout 2 min" togm 1 2.000000
2025-08-16T00:39:10.969Z,1755304750.969 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=togm
2025-08-16T00:39:10.970Z,1755304750.970 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='togm'): "load Transport/keepstation.tl;set keepstation.NeedCommsTime 4 min;set keepstation.Depth 0.01 m;set keepstation.Speed 0 m/s;set keepstation.MinOffshore 0.00001 km;set keepstation:NeedComms.GPSTimeout 2 min", ASAP
2025-08-16T00:39:11.886Z,1755304751.886 [DataOverHttps](IMPORTANT): SBD MTMSN=20250816T003911
2025-08-16T00:39:19.320Z,1755304759.320 [DataOverHttps](INFO): Received command: sched asap "set keepstation:NeedComms.CommsTimeout 0.5 min;set keepstation:BackseatDriver.EnableBackseat 1 bool;run" togm 2 2
2025-08-16T00:39:19.412Z,1755304759.412 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:NeedComms.CommsTimeout 0.5 min;set keepstation:BackseatDriver.EnableBackseat 1 bool;run" togm 2 2.000000
2025-08-16T00:39:19.413Z,1755304759.413 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=togm
2025-08-16T00:39:19.414Z,1755304759.414 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='togm'): "set keepstation:NeedComms.CommsTimeout 0.5 min;set keepstation:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2025-08-16T00:39:19.533Z,1755304759.533 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-08-16T00:39:19.533Z,1755304759.533 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-08-16T00:39:19.533Z,1755304759.533 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-08-16T00:39:19.535Z,1755304759.535 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-08-16T00:39:20.703Z,1755304760.703 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
4
45
NaN
NaN
30
15
4
0.75
200
10
10
45
2
0
0
1
2
3
NaN
0
Sinking to
Timed out while trying to reach target sink depth at current depth of
2025-08-16T00:39:20.712Z,1755304760.712 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-08-16T00:39:20.715Z,1755304760.715 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-08-16T00:39:20.718Z,1755304760.718 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-08-16T00:39:20.721Z,1755304760.721 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-08-16T00:39:20.724Z,1755304760.724 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-08-16T00:39:20.727Z,1755304760.727 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-08-16T00:39:20.730Z,1755304760.730 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-08-16T00:39:20.773Z,1755304760.773 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-08-16T00:39:20.780Z,1755304760.780 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-08-16T00:39:20.783Z,1755304760.783 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-08-16T00:39:20.795Z,1755304760.795 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-08-16T00:39:20.802Z,1755304760.802 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-08-16T00:39:20.807Z,1755304760.807 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-08-16T00:39:20.815Z,1755304760.815 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-08-16T00:39:20.834Z,1755304760.834 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-08-16T00:39:20.850Z,1755304760.850 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-08-16T00:39:20.857Z,1755304760.857 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-08-16T00:39:20.864Z,1755304760.864 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-08-16T00:39:20.912Z,1755304760.912 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-08-16T00:39:20.915Z,1755304760.915 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-08-16T00:39:20.915Z,1755304760.915 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-08-16T00:39:20.920Z,1755304760.920 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-08-16T00:39:20.921Z,1755304760.921 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-08-16T00:39:21.461Z,1755304761.461 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-08-16T00:39:21.470Z,1755304761.470 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-08-16T00:39:21.474Z,1755304761.474 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-08-16T00:39:21.479Z,1755304761.479 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-08-16T00:39:21.483Z,1755304761.483 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-08-16T00:39:21.488Z,1755304761.488 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-08-16T00:39:21.493Z,1755304761.493 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-08-16T00:39:21.497Z,1755304761.497 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-08-16T00:39:21.502Z,1755304761.502 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-08-16T00:39:21.540Z,1755304761.540 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-08-16T00:39:21.623Z,1755304761.623 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-08-16T00:39:21.623Z,1755304761.623 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-08-16T00:39:21.624Z,1755304761.624 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-08-16T00:39:21.838Z,1755304761.838 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-08-16T00:39:21.846Z,1755304761.846 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-08-16T00:39:21.851Z,1755304761.851 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-08-16T00:39:21.870Z,1755304761.870 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-08-16T00:39:21.896Z,1755304761.896 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-08-16T00:39:21.899Z,1755304761.899 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-08-16T00:39:21.920Z,1755304761.920 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-08-16T00:39:21.938Z,1755304761.938 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-08-16T00:39:21.961Z,1755304761.961 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-08-16T00:39:21.961Z,1755304761.961 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-08-16T00:39:21.962Z,1755304761.962 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-08-16T00:39:22.029Z,1755304762.029 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-08-16T00:39:22.041Z,1755304762.041 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-08-16T00:39:22.048Z,1755304762.048 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-08-16T00:39:22.051Z,1755304762.051 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-08-16T00:39:22.051Z,1755304762.051 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-08-16T00:39:22.056Z,1755304762.056 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-08-16T00:39:22.337Z,1755304762.337 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-08-16T00:39:22.358Z,1755304762.358 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-08-16T00:39:22.361Z,1755304762.361 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-08-16T00:39:22.372Z,1755304762.372 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-08-16T00:39:22.375Z,1755304762.375 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-08-16T00:39:22.389Z,1755304762.389 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-08-16T00:39:22.405Z,1755304762.405 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-08-16T00:39:22.407Z,1755304762.407 [keepstation:H.Pitch](DEBUG): Construct.
2025-08-16T00:39:22.532Z,1755304762.532 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-08-16T00:39:22.544Z,1755304762.544 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-08-16T00:39:22.561Z,1755304762.561 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-08-16T00:39:22.621Z,1755304762.621 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-08-16T00:39:22.629Z,1755304762.629 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-08-16T00:39:22.635Z,1755304762.635 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-08-16T00:39:22.650Z,1755304762.650 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-08-16T00:39:22.668Z,1755304762.668 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-08-16T00:39:22.686Z,1755304762.686 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-08-16T00:39:22.717Z,1755304762.717 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-08-16T00:39:22.749Z,1755304762.749 [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-08-16T00:39:22.750Z,1755304762.750 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-08-16T00:39:38.081Z,1755304778.081 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 4 minute
2025-08-16T00:39:38.082Z,1755304778.082 [CommandExec](IMPORTANT): got command set keepstation.Depth 0.01 meter
2025-08-16T00:39:38.083Z,1755304778.083 [CommandExec](IMPORTANT): got command set keepstation.Speed 0 meter_per_second
2025-08-16T00:39:38.083Z,1755304778.083 [CommandExec](IMPORTANT): got command set keepstation.MinOffshore 0.00001 kilometer
2025-08-16T00:39:38.084Z,1755304778.084 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.GPSTimeout 2 minute
2025-08-16T00:39:50.661Z,1755304790.661 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 0.5 minute
2025-08-16T00:39:50.662Z,1755304790.662 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 1 bool
2025-08-16T00:39:50.663Z,1755304790.663 [CommandExec](IMPORTANT): got command run
2025-08-16T00:39:50.669Z,1755304790.669 [CommandExec](IMPORTANT): Running
2025-08-16T00:39:50.829Z,1755304790.829 [Default] Stopped
2025-08-16T00:39:50.829Z,1755304790.829 [Default](DEBUG): Aggregate::uninitialize Default
2025-08-16T00:39:50.830Z,1755304790.830 [Default:B.GoToSurface] Stopped
2025-08-16T00:39:50.830Z,1755304790.830 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T00:39:50.830Z,1755304790.830 [Default:CheckIn] Stopped
2025-08-16T00:39:50.830Z,1755304790.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-16T00:39:50.830Z,1755304790.830 [Default:CheckIn:C.Wait] Stopped
2025-08-16T00:39:50.830Z,1755304790.830 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-16T00:39:50.830Z,1755304790.830 [MissionManager](IMPORTANT): Started mission keepstation
2025-08-16T00:39:50.830Z,1755304790.830 [keepstation] Running Loop=1
2025-08-16T00:39:50.830Z,1755304790.830 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-08-16T00:39:50.831Z,1755304790.831 [keepstation:StandardEnvelopes] Running Loop=1
2025-08-16T00:39:50.831Z,1755304790.831 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-08-16T00:39:50.831Z,1755304790.831 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-08-16T00:39:50.831Z,1755304790.831 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-08-16T00:39:50.831Z,1755304790.831 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-08-16T00:39:50.831Z,1755304790.831 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-08-16T00:39:50.831Z,1755304790.831 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-08-16T00:39:50.831Z,1755304790.831 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:BackseatDriver] Running Loop=1
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:PowerOnly] Running Loop=1
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:PowerOnly:A] Running Loop=1
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:PowerOnly:B] Running Loop=1
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:PowerOnly:C] Running Loop=1
2025-08-16T00:39:50.836Z,1755304790.836 [keepstation:PowerOnly:D] Running Loop=1
2025-08-16T00:39:50.837Z,1755304790.837 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-08-16T00:39:50.837Z,1755304790.837 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-08-16T00:39:50.837Z,1755304790.837 [keepstation:H.Pitch] Running Loop=1
2025-08-16T00:39:50.837Z,1755304790.837 [keepstation:H.Pitch](DEBUG): Initialize.
2025-08-16T00:39:50.837Z,1755304790.837 [keepstation:B] Running Loop=1
2025-08-16T00:39:50.838Z,1755304790.838 [keepstation:SurfaceComms] Running Loop=1
2025-08-16T00:39:50.838Z,1755304790.838 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-16T00:39:50.838Z,1755304790.838 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-16T00:39:50.839Z,1755304790.839 [keepstation:SurfaceComms:A] Stopped
2025-08-16T00:39:50.839Z,1755304790.839 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-16T00:39:50.839Z,1755304790.839 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-16T00:39:50.839Z,1755304790.839 [keepstation:H.Pitch] Running Loop=1
2025-08-16T00:39:50.839Z,1755304790.839 [keepstation:PowerOnly] Running Loop=1
2025-08-16T00:39:50.860Z,1755304790.860 [keepstation:PowerOnly:D] Stopped
2025-08-16T00:39:50.860Z,1755304790.860 [keepstation:PowerOnly:C] Stopped
2025-08-16T00:39:50.860Z,1755304790.860 [keepstation:PowerOnly:B] Stopped
2025-08-16T00:39:50.860Z,1755304790.860 [keepstation:PowerOnly:A] Stopped
2025-08-16T00:39:50.860Z,1755304790.860 [keepstation:BackseatDriver] Running Loop=1
2025-08-16T00:39:50.861Z,1755304790.861 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-08-16T00:39:50.945Z,1755304790.945 [keepstation:StandardEnvelopes] Running Loop=1
2025-08-16T00:39:50.945Z,1755304790.945 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-08-16T00:39:50.946Z,1755304790.946 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2025-08-16T00:39:50.970Z,1755304790.970 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-08-16T00:39:50.971Z,1755304790.971 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-08-16T00:39:50.971Z,1755304790.971 [keepstation:B] Stopped
2025-08-16T00:39:50.980Z,1755304790.980 [keepstation:D] Running Loop=1
2025-08-16T00:39:50.992Z,1755304790.992 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-08-16T00:39:50.993Z,1755304790.993 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-08-16T00:39:50.993Z,1755304790.993 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-08-16T00:39:51.128Z,1755304791.128 [BackseatComponent](INFO): Powering up
2025-08-16T00:39:51.129Z,1755304791.129 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-08-16T00:39:51.280Z,1755304791.280 [keepstation:D] Stopped
2025-08-16T00:39:51.280Z,1755304791.280 [keepstation:E] Running Loop=1
2025-08-16T00:39:51.305Z,1755304791.305 [keepstation:NeedComms] Running Loop=1
2025-08-16T00:39:51.305Z,1755304791.305 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-16T00:39:51.305Z,1755304791.305 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-16T00:39:51.305Z,1755304791.305 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T00:39:51.305Z,1755304791.305 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-16T00:39:51.306Z,1755304791.306 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-16T00:39:51.306Z,1755304791.306 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-16T00:39:51.306Z,1755304791.306 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-16T00:39:51.307Z,1755304791.307 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T00:39:51.307Z,1755304791.307 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T00:39:51.307Z,1755304791.307 [keepstation:NeedComms:A] Running Loop=1
2025-08-16T00:39:51.313Z,1755304791.313 [keepstation:NeedComms:A](INFO): last time_fix was: 1755304533.000000 second since 1970/01/01T00:00:00Z
2025-08-16T00:39:51.313Z,1755304791.313 [keepstation:NeedComms:A] Stopped
2025-08-16T00:39:51.636Z,1755304791.636 [keepstation:E] Stopped
2025-08-16T00:39:51.637Z,1755304791.637 [keepstation:StartingMission] Running Loop=1
2025-08-16T00:39:51.637Z,1755304791.637 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-08-16T00:39:51.638Z,1755304791.638 [keepstation:NeedComms:C] Running Loop=1
2025-08-16T00:39:52.049Z,1755304792.049 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-16T00:39:52.421Z,1755304792.421 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-08-16T00:39:53.627Z,1755304793.627 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003953.00,A,3648.16343,N,12147.28536,W,0.117,334.78,160825,,,D*70
2025-08-16T00:39:53.629Z,1755304793.629 [NAL9602](INFO): GPS fix at 20250816T003953: (36.802724, -121.788089)
2025-08-16T00:39:53.645Z,1755304793.645 [keepstation:NeedComms:C] Stopped
2025-08-16T00:39:53.645Z,1755304793.645 [keepstation:NeedComms:D] Running Loop=1
2025-08-16T00:39:54.065Z,1755304794.065 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-08-16T00:39:55.235Z,1755304795.235 [DataOverHttps](IMPORTANT): SBD MTMSN=20250816T003953
2025-08-16T00:40:01.892Z,1755304801.892 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250816T002839/Courier0007.lzma
2025-08-16T00:40:01.894Z,1755304801.894 [DataOverHttps](INFO): Received command: sched 20250816T0050 "set keepstation:backseatdriver.enablebackseat 0 bool"
2025-08-16T00:40:01.955Z,1755304801.955 [CommandExec](IMPORTANT): got command schedule 20250816T005000 "set keepstation:backseatdriver.enablebackseat 0 bool"
2025-08-16T00:40:01.956Z,1755304801.956 [CommandExec](IMPORTANT): Scheduled #3: "set keepstation:backseatdriver.enablebackseat 0 bool", AT TIME: 2025-08-16T00:50:00Z
2025-08-16T00:40:03.133Z,1755304803.133 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0007.lzma.bak
2025-08-16T00:40:03.133Z,1755304803.133 [DataOverHttps](IMPORTANT): SBD MOMSN=25854437, MTMSN=20250816T004002
2025-08-16T00:40:10.628Z,1755304810.628 [DataOverHttps](INFO): Received command: sched 20250816T0055 "set keepstation:backseatdriver.enablebackseat 1 bool"
2025-08-16T00:40:10.711Z,1755304810.711 [CommandExec](IMPORTANT): got command schedule 20250816T005500 "set keepstation:backseatdriver.enablebackseat 1 bool"
2025-08-16T00:40:10.712Z,1755304810.712 [CommandExec](IMPORTANT): Scheduled #4: "set keepstation:backseatdriver.enablebackseat 1 bool", AT TIME: 2025-08-16T00:55:00Z
2025-08-16T00:40:12.013Z,1755304812.013 [DataOverHttps](IMPORTANT): SBD MTMSN=20250816T004010
2025-08-16T00:40:19.194Z,1755304819.194 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250816T002839/Courier0012.lzma
2025-08-16T00:40:19.198Z,1755304819.198 [DataOverHttps](INFO): Received command: sched 20250816T0105 "restart sys"
2025-08-16T00:40:19.259Z,1755304819.259 [CommandExec](IMPORTANT): got command schedule 20250816T010500 "restart sys"
2025-08-16T00:40:19.260Z,1755304819.260 [CommandExec](IMPORTANT): Scheduled #5: "restart sys", AT TIME: 2025-08-16T01:05:00Z
2025-08-16T00:40:20.453Z,1755304820.453 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0012.lzma.bak
2025-08-16T00:40:20.453Z,1755304820.453 [DataOverHttps](IMPORTANT): SBD MOMSN=25854444, MTMSN=20250816T004019
2025-08-16T00:40:23.985Z,1755304823.985 [keepstation:NeedComms:D](INFO): Timed out from 2025-08-16T00:39:53.6Z
2025-08-16T00:40:23.985Z,1755304823.985 [keepstation:NeedComms:D:A_Timeout] Running Loop=1
2025-08-16T00:40:23.985Z,1755304823.985 [keepstation:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:D:A_Timeout
2025-08-16T00:40:23.986Z,1755304823.986 [keepstation:NeedComms:D:A_Timeout:A] Running Loop=1
2025-08-16T00:40:23.986Z,1755304823.986 [keepstation:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 0.500000 min minutes
2025-08-16T00:40:23.986Z,1755304823.986 [keepstation:NeedComms:D:A_Timeout:A] Stopped
2025-08-16T00:40:23.986Z,1755304823.986 [keepstation:NeedComms:D:A_Timeout](INFO): Completed keepstation:NeedComms:D:A_Timeout
2025-08-16T00:40:23.986Z,1755304823.986 [keepstation:NeedComms:D] Stopped
2025-08-16T00:40:23.987Z,1755304823.987 [keepstation:NeedComms:E] Running Loop=1
2025-08-16T00:40:24.417Z,1755304824.417 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-16T00:40:27.972Z,1755304827.972 [DataOverHttps](INFO): Received command: schedule resume
2025-08-16T00:40:28.010Z,1755304828.010 [CommandExec](IMPORTANT): got command schedule resume
2025-08-16T00:40:29.558Z,1755304829.558 [BackseatComponent](IMPORTANT): piscivore: running backseat_app backseat application.
2025-08-16T00:40:37.615Z,1755304837.615 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250816T002839/Courier0016.lzma
2025-08-16T00:40:38.618Z,1755304838.618 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0016.lzma.bak
2025-08-16T00:40:38.618Z,1755304838.618 [DataOverHttps](INFO): SBD MOMSN=25854448
2025-08-16T00:42:23.997Z,1755304943.997 [keepstation:NeedComms:E](INFO): Timed out from 2025-08-16T00:40:23.0Z
2025-08-16T00:42:23.997Z,1755304943.997 [keepstation:NeedComms:E:A_Timeout] Running Loop=1
2025-08-16T00:42:23.997Z,1755304943.997 [keepstation:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:E:A_Timeout
2025-08-16T00:42:23.998Z,1755304943.998 [keepstation:NeedComms:E:A_Timeout:A] Running Loop=1
2025-08-16T00:42:23.998Z,1755304943.998 [keepstation:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout
2025-08-16T00:42:23.998Z,1755304943.998 [keepstation:NeedComms:E:A_Timeout:A] Stopped
2025-08-16T00:42:23.998Z,1755304943.998 [keepstation:NeedComms:E:A_Timeout](INFO): Completed keepstation:NeedComms:E:A_Timeout
2025-08-16T00:42:23.998Z,1755304943.998 [keepstation:NeedComms:E] Stopped
2025-08-16T00:42:23.999Z,1755304943.999 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-16T00:42:23.999Z,1755304943.999 [keepstation:NeedComms] Stopped
2025-08-16T00:42:23.999Z,1755304943.999 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-16T00:42:23.999Z,1755304943.999 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-16T00:42:23.999Z,1755304943.999 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T00:42:24.414Z,1755304944.414 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-16T00:42:24.414Z,1755304944.414 [keepstation:SurfaceComms:B] Stopped
2025-08-16T00:42:24.414Z,1755304944.414 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-16T00:42:24.414Z,1755304944.414 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-16T00:42:24.414Z,1755304944.414 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-16T00:42:24.415Z,1755304944.415 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-16T00:42:24.793Z,1755304944.793 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-16T00:42:24.793Z,1755304944.793 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-08-16T00:42:24.793Z,1755304944.793 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-16T00:42:24.794Z,1755304944.794 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-16T00:42:24.794Z,1755304944.794 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-16T00:42:24.794Z,1755304944.794 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-16T00:42:24.794Z,1755304944.794 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-16T00:42:25.228Z,1755304945.228 [keepstation:SurfaceComms:setStation] Stopped
2025-08-16T00:42:25.228Z,1755304945.228 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-16T00:42:25.228Z,1755304945.228 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-16T00:42:25.228Z,1755304945.228 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-16T00:42:25.229Z,1755304945.229 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-16T00:42:25.229Z,1755304945.229 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-16T00:42:25.611Z,1755304945.611 [keepstation:SurfaceComms:setSink] Stopped
2025-08-16T00:42:25.611Z,1755304945.611 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-16T00:42:25.611Z,1755304945.611 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-16T00:42:25.611Z,1755304945.611 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-16T00:42:25.611Z,1755304945.611 [keepstation:SurfaceComms] Stopped
2025-08-16T00:42:25.616Z,1755304945.616 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:StartingMission] Stopped
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:TransitToStation] Running Loop=1
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:TransitToStation:Dive] Running Loop=1
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-08-16T00:42:26.029Z,1755304946.029 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-08-16T00:42:26.030Z,1755304946.030 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-08-16T00:42:26.030Z,1755304946.030 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-08-16T00:42:26.454Z,1755304946.454 [keepstation:TransitToStation:Dive] Running Loop=1
2025-08-16T00:42:26.454Z,1755304946.454 [keepstation:DiveCmd] Running Loop=1
2025-08-16T00:42:26.454Z,1755304946.454 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-08-16T00:42:26.454Z,1755304946.454 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-16T00:42:26.454Z,1755304946.454 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-08-16T00:42:26.455Z,1755304946.455 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-08-16T00:42:26.460Z,1755304946.460 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-08-16T00:42:26.460Z,1755304946.460 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-08-16T00:42:26.460Z,1755304946.460 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-08-16T00:42:26.460Z,1755304946.460 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-16T00:42:26.460Z,1755304946.460 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-08-16T00:42:26.460Z,1755304946.460 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-16T00:42:26.461Z,1755304946.461 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-16T00:42:26.462Z,1755304946.462 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-16T00:42:26.462Z,1755304946.462 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-16T00:42:26.462Z,1755304946.462 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-16T00:42:26.462Z,1755304946.462 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-16T00:42:26.462Z,1755304946.462 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-16T00:42:26.463Z,1755304946.463 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-16T00:42:26.463Z,1755304946.463 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-08-16T00:42:26.463Z,1755304946.463 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-08-16T00:42:26.463Z,1755304946.463 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-08-16T00:42:26.463Z,1755304946.463 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-08-16T00:42:26.496Z,1755304946.496 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-08-16T00:42:26.496Z,1755304946.496 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-08-16T00:42:26.496Z,1755304946.496 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-16T00:42:26.496Z,1755304946.496 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-16T00:42:26.496Z,1755304946.496 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-16T00:42:26.496Z,1755304946.496 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-16T00:42:26.496Z,1755304946.496 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-16T00:42:26.496Z,1755304946.496 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-16T00:42:26.497Z,1755304946.497 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-16T00:43:02.040Z,1755304982.040 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2025-08-16T00:43:02.041Z,1755304982.041 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2025-08-16T00:43:02.041Z,1755304982.041 [BuoyancyServo] Communications Fault, FailCount= 1
2025-08-16T00:43:02.041Z,1755304982.041 [BuoyancyServo](ERROR): Communications Fault
2025-08-16T00:43:02.179Z,1755304982.179 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2025-08-16T00:43:03.978Z,1755304983.978 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-08-16T00:43:03.978Z,1755304983.978 [BuoyancyServo](INFO): Powering down
2025-08-16T00:43:04.943Z,1755304984.943 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2025-08-16T00:43:04.944Z,1755304984.944 [BuoyancyServo] No Fault, FailCount= 1
2025-08-16T00:43:05.178Z,1755304985.178 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-08-16T00:43:05.179Z,1755304985.179 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-08-16T00:43:52.834Z,1755305032.834 [NAL9602](INFO): SBD MO Status=2, MOMSN=21844, MT Status=2, MTMSN=0
2025-08-16T00:43:52.834Z,1755305032.834 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-16T00:43:53.656Z,1755305033.656 [keepstation:SurfaceComms] Running Loop=1
2025-08-16T00:43:53.656Z,1755305033.656 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-16T00:43:53.656Z,1755305033.656 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-16T00:43:53.657Z,1755305033.657 [keepstation:SurfaceComms:A] Stopped
2025-08-16T00:43:53.657Z,1755305033.657 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-16T00:43:53.657Z,1755305033.657 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-16T00:43:54.057Z,1755305034.057 [keepstation:TransitToStation:Dive] Stopped
2025-08-16T00:43:54.057Z,1755305034.057 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-08-16T00:43:54.057Z,1755305034.057 [keepstation:TransitToStation:Dive:A] Stopped
2025-08-16T00:43:54.057Z,1755305034.057 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation] Stopped
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-08-16T00:43:54.058Z,1755305034.058 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-08-16T00:43:54.061Z,1755305034.061 [keepstation:NeedComms] Running Loop=1
2025-08-16T00:43:54.061Z,1755305034.061 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-16T00:43:54.061Z,1755305034.061 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-16T00:43:54.061Z,1755305034.061 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T00:43:54.061Z,1755305034.061 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-16T00:43:54.062Z,1755305034.062 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-16T00:43:54.062Z,1755305034.062 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-16T00:43:54.062Z,1755305034.062 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-16T00:43:54.063Z,1755305034.063 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T00:43:54.063Z,1755305034.063 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T00:43:54.063Z,1755305034.063 [keepstation:NeedComms:A] Running Loop=1
2025-08-16T00:43:54.064Z,1755305034.064 [keepstation:NeedComms:A](INFO): last time_fix was: 1755304793.000000 second since 1970/01/01T00:00:00Z
2025-08-16T00:43:54.064Z,1755305034.064 [keepstation:NeedComms:A] Stopped
2025-08-16T00:43:54.489Z,1755305034.489 [keepstation:NeedComms:C] Running Loop=1
2025-08-16T00:44:39.308Z,1755305079.308 [NAL9602](INFO): SBD MO Status=2, MOMSN=21844, MT Status=2, MTMSN=0
2025-08-16T00:44:39.308Z,1755305079.308 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-16T00:44:55.862Z,1755305095.862 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-16T00:45:00.716Z,1755305100.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004500.00,A,3648.16480,N,12147.28403,W,0.156,66.59,160825,,,D*40
2025-08-16T00:45:00.719Z,1755305100.719 [NAL9602](INFO): GPS fix at 20250816T004500: (36.802747, -121.788067)
2025-08-16T00:45:00.730Z,1755305100.730 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-08-16T00:45:00.730Z,1755305100.730 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-08-16T00:45:00.730Z,1755305100.730 [keepstation:TransitToStation:C] Running Loop=1
2025-08-16T00:45:00.737Z,1755305100.737 [keepstation:NeedComms:C] Stopped
2025-08-16T00:45:00.737Z,1755305100.737 [keepstation:NeedComms:D] Running Loop=1
2025-08-16T00:45:01.140Z,1755305101.140 [keepstation:TransitToStation:C] Stopped
2025-08-16T00:45:01.140Z,1755305101.140 [keepstation:TransitToStation:D] Running Loop=1
2025-08-16T00:45:01.141Z,1755305101.141 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-16T00:45:01.141Z,1755305101.141 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-16T00:45:01.141Z,1755305101.141 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-16T00:45:01.141Z,1755305101.141 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-16T00:45:01.141Z,1755305101.141 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-16T00:45:01.141Z,1755305101.141 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-16T00:45:01.141Z,1755305101.141 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-16T00:45:01.142Z,1755305101.142 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-16T00:45:01.142Z,1755305101.142 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-16T00:45:01.142Z,1755305101.142 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-16T00:45:01.143Z,1755305101.143 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-16T00:45:01.521Z,1755305101.521 [keepstation:TransitToStation:D] Stopped
2025-08-16T00:45:01.521Z,1755305101.521 [keepstation:TransitToStation:Dive] Running Loop=1
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation] Stopped
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation:Dive] Stopped
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-08-16T00:45:01.522Z,1755305101.522 [keepstation:TransitToStation:Dive:A] Stopped
2025-08-16T00:45:01.523Z,1755305101.523 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-08-16T00:45:01.523Z,1755305101.523 [keepstation:OnStation] Running Loop=1
2025-08-16T00:45:01.523Z,1755305101.523 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-08-16T00:45:08.751Z,1755305108.751 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250816T002839/Courier0019.lzma
2025-08-16T00:45:09.754Z,1755305109.754 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0019.lzma.bak
2025-08-16T00:45:09.754Z,1755305109.754 [DataOverHttps](INFO): SBD MOMSN=25854450
2025-08-16T00:45:25.911Z,1755305125.911 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250816T002839/Courier0022.lzma
2025-08-16T00:45:26.914Z,1755305126.914 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0022.lzma.bak
2025-08-16T00:45:26.914Z,1755305126.914 [DataOverHttps](INFO): SBD MOMSN=25854452
2025-08-16T00:45:31.021Z,1755305131.021 [keepstation:NeedComms:D](INFO): Timed out from 2025-08-16T00:45:00.7Z
2025-08-16T00:45:31.021Z,1755305131.021 [keepstation:NeedComms:D:A_Timeout] Running Loop=1
2025-08-16T00:45:31.021Z,1755305131.021 [keepstation:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:D:A_Timeout
2025-08-16T00:45:31.021Z,1755305131.021 [keepstation:NeedComms:D:A_Timeout:A] Running Loop=1
2025-08-16T00:45:31.022Z,1755305131.022 [keepstation:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 0.500000 min minutes
2025-08-16T00:45:31.022Z,1755305131.022 [keepstation:NeedComms:D:A_Timeout:A] Stopped
2025-08-16T00:45:31.022Z,1755305131.022 [keepstation:NeedComms:D:A_Timeout](INFO): Completed keepstation:NeedComms:D:A_Timeout
2025-08-16T00:45:31.022Z,1755305131.022 [keepstation:NeedComms:D] Stopped
2025-08-16T00:45:31.022Z,1755305131.022 [keepstation:NeedComms:E] Running Loop=1
2025-08-16T00:45:33.037Z,1755305133.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004532.00,A,3648.16466,N,12147.28457,W,0.039,66.59,160825,,,D*40
2025-08-16T00:45:33.044Z,1755305133.044 [NAL9602](INFO): GPS fix at 20250816T004532: (36.802744, -121.788076)
2025-08-16T00:45:33.105Z,1755305133.105 [keepstation:NeedComms:E] Stopped
2025-08-16T00:45:33.106Z,1755305133.106 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-16T00:45:33.106Z,1755305133.106 [keepstation:NeedComms] Stopped
2025-08-16T00:45:33.106Z,1755305133.106 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-16T00:45:33.106Z,1755305133.106 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-16T00:45:33.106Z,1755305133.106 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:OnStation] Stopped
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:KeepStation] Running Loop=1
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-16T00:45:33.465Z,1755305133.465 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-16T00:45:33.466Z,1755305133.466 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-08-16T00:45:33.466Z,1755305133.466 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-08-16T00:45:33.467Z,1755305133.467 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-16T00:45:33.467Z,1755305133.467 [keepstation:SurfaceComms:B] Stopped
2025-08-16T00:45:33.467Z,1755305133.467 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-16T00:45:33.472Z,1755305133.472 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-16T00:45:33.472Z,1755305133.472 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-16T00:45:33.472Z,1755305133.472 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-16T00:45:33.861Z,1755305133.861 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-16T00:45:33.862Z,1755305133.862 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-16T00:45:33.862Z,1755305133.862 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-16T00:45:33.863Z,1755305133.863 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-16T00:45:33.863Z,1755305133.863 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-16T00:45:33.863Z,1755305133.863 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-16T00:45:33.863Z,1755305133.863 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-16T00:45:34.284Z,1755305134.284 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-16T00:45:34.284Z,1755305134.284 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-08-16T00:45:34.285Z,1755305134.285 [keepstation:SurfaceComms:setStation] Stopped
2025-08-16T00:45:34.285Z,1755305134.285 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-16T00:45:34.285Z,1755305134.285 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-16T00:45:34.285Z,1755305134.285 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-16T00:45:34.285Z,1755305134.285 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-16T00:45:34.709Z,1755305134.709 [keepstation:SurfaceComms:setSink] Stopped
2025-08-16T00:45:34.710Z,1755305134.710 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-16T00:45:34.710Z,1755305134.710 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-16T00:45:34.710Z,1755305134.710 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-16T00:45:34.710Z,1755305134.710 [keepstation:SurfaceComms] Stopped
2025-08-16T00:45:34.710Z,1755305134.710 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-16T00:45:43.966Z,1755305143.966 [DataOverHttps](INFO): Sending 675 bytes from file Logs/20250816T002839/Express0008.lzma
2025-08-16T00:45:44.966Z,1755305144.966 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Express0008.lzma.bak
2025-08-16T00:45:44.966Z,1755305144.966 [DataOverHttps](INFO): SBD MOMSN=25854455
2025-08-16T00:46:05.345Z,1755305165.345 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-16T00:49:33.058Z,1755305373.058 [keepstation:SurfaceComms] Running Loop=1
2025-08-16T00:49:33.059Z,1755305373.059 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-16T00:49:33.059Z,1755305373.059 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-16T00:49:33.059Z,1755305373.059 [keepstation:SurfaceComms:A] Stopped
2025-08-16T00:49:33.059Z,1755305373.059 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-16T00:49:33.059Z,1755305373.059 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-16T00:49:33.439Z,1755305373.439 [keepstation:KeepStation:Dive] Stopped
2025-08-16T00:49:33.439Z,1755305373.439 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-16T00:49:33.439Z,1755305373.439 [keepstation:KeepStation:Dive:A] Stopped
2025-08-16T00:49:33.439Z,1755305373.439 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-16T00:49:33.440Z,1755305373.440 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-16T00:49:33.440Z,1755305373.440 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-16T00:49:33.440Z,1755305373.440 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-16T00:49:33.440Z,1755305373.440 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-16T00:49:33.440Z,1755305373.440 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-16T00:49:33.440Z,1755305373.440 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-16T00:49:33.445Z,1755305373.445 [keepstation:NeedComms] Running Loop=1
2025-08-16T00:49:33.445Z,1755305373.445 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-16T00:49:33.445Z,1755305373.445 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-16T00:49:33.445Z,1755305373.445 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T00:49:33.445Z,1755305373.445 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-16T00:49:33.446Z,1755305373.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-16T00:49:33.446Z,1755305373.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-16T00:49:33.446Z,1755305373.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-16T00:49:33.447Z,1755305373.447 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T00:49:33.447Z,1755305373.447 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T00:49:33.447Z,1755305373.447 [keepstation:NeedComms:A] Running Loop=1
2025-08-16T00:49:33.464Z,1755305373.464 [keepstation:NeedComms:A](INFO): last time_fix was: 1755305132.000000 second since 1970/01/01T00:00:00Z
2025-08-16T00:49:33.465Z,1755305373.465 [keepstation:NeedComms:A] Stopped
2025-08-16T00:49:33.853Z,1755305373.853 [keepstation:NeedComms:C] Running Loop=1
2025-08-16T00:49:35.876Z,1755305375.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004935.00,A,3648.16370,N,12147.28005,W,0.194,338.43,160825,,,D*7D
2025-08-16T00:49:35.878Z,1755305375.878 [NAL9602](INFO): GPS fix at 20250816T004935: (36.802728, -121.788001)
2025-08-16T00:49:35.893Z,1755305375.893 [keepstation:NeedComms:C] Stopped
2025-08-16T00:49:35.893Z,1755305375.893 [keepstation:NeedComms:D] Running Loop=1
2025-08-16T00:49:44.020Z,1755305384.020 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250816T002839/Courier0028.lzma
2025-08-16T00:49:45.022Z,1755305385.022 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0028.lzma.bak
2025-08-16T00:49:45.022Z,1755305385.022 [DataOverHttps](INFO): SBD MOMSN=25854478
2025-08-16T00:50:04.393Z,1755305404.393 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20250816T002839/Express0011.lzma
2025-08-16T00:50:05.394Z,1755305405.394 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Express0011.lzma.bak
2025-08-16T00:50:05.394Z,1755305405.394 [DataOverHttps](INFO): SBD MOMSN=25854481
2025-08-16T00:50:06.213Z,1755305406.213 [keepstation:NeedComms:D](INFO): Timed out from 2025-08-16T00:49:35.9Z
2025-08-16T00:50:06.213Z,1755305406.213 [keepstation:NeedComms:D:A_Timeout] Running Loop=1
2025-08-16T00:50:06.213Z,1755305406.213 [keepstation:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:D:A_Timeout
2025-08-16T00:50:06.213Z,1755305406.213 [keepstation:NeedComms:D:A_Timeout:A] Running Loop=1
2025-08-16T00:50:06.214Z,1755305406.214 [keepstation:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 0.500000 min minutes
2025-08-16T00:50:06.214Z,1755305406.214 [keepstation:NeedComms:D:A_Timeout:A] Stopped
2025-08-16T00:50:06.214Z,1755305406.214 [keepstation:NeedComms:D:A_Timeout](INFO): Completed keepstation:NeedComms:D:A_Timeout
2025-08-16T00:50:06.214Z,1755305406.214 [keepstation:NeedComms:D] Stopped
2025-08-16T00:50:06.214Z,1755305406.214 [keepstation:NeedComms:E] Running Loop=1
2025-08-16T00:50:06.577Z,1755305406.577 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-16T00:50:06.652Z,1755305406.652 [NAL9602](ERROR): received:
+CSQ:1
OK844, 2, 0, 0, 0
OK
2025-08-16T00:50:08.851Z,1755305408.851 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 0 bool
2025-08-16T00:50:09.021Z,1755305409.021 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Uninitializing backseat
2025-08-16T00:50:10.412Z,1755305410.412 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-08-16T00:50:10.412Z,1755305410.412 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-08-16T00:50:28.580Z,1755305428.580 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-08-16T00:50:28.581Z,1755305428.581 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-08-16T00:50:28.581Z,1755305428.581 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-08-16T00:52:06.605Z,1755305526.605 [keepstation:NeedComms:E](INFO): Timed out from 2025-08-16T00:50:06.2Z
2025-08-16T00:52:06.605Z,1755305526.605 [keepstation:NeedComms:E:A_Timeout] Running Loop=1
2025-08-16T00:52:06.606Z,1755305526.606 [keepstation:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:E:A_Timeout
2025-08-16T00:52:06.606Z,1755305526.606 [keepstation:NeedComms:E:A_Timeout:A] Running Loop=1
2025-08-16T00:52:06.606Z,1755305526.606 [keepstation:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout
2025-08-16T00:52:06.606Z,1755305526.606 [keepstation:NeedComms:E:A_Timeout:A] Stopped
2025-08-16T00:52:06.606Z,1755305526.606 [keepstation:NeedComms:E:A_Timeout](INFO): Completed keepstation:NeedComms:E:A_Timeout
2025-08-16T00:52:06.606Z,1755305526.606 [keepstation:NeedComms:E] Stopped
2025-08-16T00:52:06.607Z,1755305526.607 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-16T00:52:06.607Z,1755305526.607 [keepstation:NeedComms] Stopped
2025-08-16T00:52:06.607Z,1755305526.607 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-16T00:52:06.607Z,1755305526.607 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-16T00:52:06.607Z,1755305526.607 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T00:52:06.984Z,1755305526.984 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-16T00:52:06.984Z,1755305526.984 [keepstation:SurfaceComms:B] Stopped
2025-08-16T00:52:06.984Z,1755305526.984 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-16T00:52:06.984Z,1755305526.984 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-16T00:52:06.984Z,1755305526.984 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-16T00:52:06.984Z,1755305526.984 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-16T00:52:07.413Z,1755305527.413 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-16T00:52:07.413Z,1755305527.413 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-16T00:52:07.413Z,1755305527.413 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-16T00:52:07.413Z,1755305527.413 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-16T00:52:07.414Z,1755305527.414 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-16T00:52:07.414Z,1755305527.414 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-16T00:52:07.813Z,1755305527.813 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-16T00:52:07.813Z,1755305527.813 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-08-16T00:52:07.813Z,1755305527.813 [keepstation:SurfaceComms:setStation] Stopped
2025-08-16T00:52:07.813Z,1755305527.813 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-16T00:52:07.813Z,1755305527.813 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-16T00:52:07.813Z,1755305527.813 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-16T00:52:07.813Z,1755305527.813 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-16T00:52:08.195Z,1755305528.195 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-16T00:52:08.195Z,1755305528.195 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-16T00:52:08.195Z,1755305528.195 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-16T00:52:08.195Z,1755305528.195 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-16T00:52:08.197Z,1755305528.197 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-16T00:52:08.197Z,1755305528.197 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-16T00:52:08.197Z,1755305528.197 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-16T00:52:08.197Z,1755305528.197 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-16T00:52:08.197Z,1755305528.197 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-16T00:52:08.197Z,1755305528.197 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-16T00:52:08.197Z,1755305528.197 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-16T00:52:08.199Z,1755305528.199 [keepstation:SurfaceComms:setSink] Stopped
2025-08-16T00:52:08.199Z,1755305528.199 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-16T00:52:08.199Z,1755305528.199 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-16T00:52:08.199Z,1755305528.199 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-16T00:52:08.199Z,1755305528.199 [keepstation:SurfaceComms] Stopped
2025-08-16T00:52:08.199Z,1755305528.199 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-16T00:53:36.267Z,1755305616.267 [keepstation:SurfaceComms] Running Loop=1
2025-08-16T00:53:36.267Z,1755305616.267 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-16T00:53:36.268Z,1755305616.268 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-16T00:53:36.268Z,1755305616.268 [keepstation:SurfaceComms:A] Stopped
2025-08-16T00:53:36.268Z,1755305616.268 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-16T00:53:36.268Z,1755305616.268 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-16T00:53:36.677Z,1755305616.677 [keepstation:KeepStation:Dive] Stopped
2025-08-16T00:53:36.677Z,1755305616.677 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-16T00:53:36.677Z,1755305616.677 [keepstation:KeepStation:Dive:A] Stopped
2025-08-16T00:53:36.677Z,1755305616.677 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-16T00:53:36.677Z,1755305616.677 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-16T00:53:36.678Z,1755305616.678 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-16T00:53:36.678Z,1755305616.678 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-16T00:53:36.678Z,1755305616.678 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-16T00:53:36.678Z,1755305616.678 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-16T00:53:36.678Z,1755305616.678 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-16T00:53:36.679Z,1755305616.679 [keepstation:NeedComms] Running Loop=1
2025-08-16T00:53:36.679Z,1755305616.679 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-16T00:53:36.680Z,1755305616.680 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-16T00:53:36.680Z,1755305616.680 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T00:53:36.680Z,1755305616.680 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-16T00:53:36.681Z,1755305616.681 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-16T00:53:36.681Z,1755305616.681 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-16T00:53:36.681Z,1755305616.681 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-16T00:53:36.682Z,1755305616.682 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T00:53:36.682Z,1755305616.682 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T00:53:36.682Z,1755305616.682 [keepstation:NeedComms:A] Running Loop=1
2025-08-16T00:53:36.683Z,1755305616.683 [keepstation:NeedComms:A](INFO): last time_fix was: 1755305375.000000 second since 1970/01/01T00:00:00Z
2025-08-16T00:53:36.683Z,1755305616.683 [keepstation:NeedComms:A] Stopped
2025-08-16T00:53:37.083Z,1755305617.083 [keepstation:NeedComms:C] Running Loop=1
2025-08-16T00:54:38.064Z,1755305678.064 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-16T00:54:43.730Z,1755305683.730 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005443.00,A,3648.16633,N,12147.27851,W,0.389,263.16,160825,,,D*75
2025-08-16T00:54:43.732Z,1755305683.732 [NAL9602](INFO): GPS fix at 20250816T005443: (36.802772, -121.787975)
2025-08-16T00:54:43.745Z,1755305683.745 [keepstation:NeedComms:C] Stopped
2025-08-16T00:54:43.745Z,1755305683.745 [keepstation:NeedComms:D] Running Loop=1
2025-08-16T00:54:50.335Z,1755305690.335 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250816T002839/Courier0031.lzma
2025-08-16T00:54:51.338Z,1755305691.338 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0031.lzma.bak
2025-08-16T00:54:51.339Z,1755305691.339 [DataOverHttps](INFO): SBD MOMSN=25854486
2025-08-16T00:55:06.680Z,1755305706.680 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 1 bool
2025-08-16T00:55:06.820Z,1755305706.820 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-08-16T00:55:07.080Z,1755305707.080 [BackseatComponent](INFO): Powering up
2025-08-16T00:55:07.080Z,1755305707.080 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-08-16T00:55:10.104Z,1755305710.104 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250816T002839/Express0017.lzma
2025-08-16T00:55:11.106Z,1755305711.106 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Express0017.lzma.bak
2025-08-16T00:55:11.106Z,1755305711.106 [DataOverHttps](INFO): SBD MOMSN=25854489
2025-08-16T00:55:14.053Z,1755305714.053 [keepstation:NeedComms:D](INFO): Timed out from 2025-08-16T00:54:43.7Z
2025-08-16T00:55:14.053Z,1755305714.053 [keepstation:NeedComms:D:A_Timeout] Running Loop=1
2025-08-16T00:55:14.053Z,1755305714.053 [keepstation:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:D:A_Timeout
2025-08-16T00:55:14.053Z,1755305714.053 [keepstation:NeedComms:D:A_Timeout:A] Running Loop=1
2025-08-16T00:55:14.054Z,1755305714.054 [keepstation:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 0.500000 min minutes
2025-08-16T00:55:14.054Z,1755305714.054 [keepstation:NeedComms:D:A_Timeout:A] Stopped
2025-08-16T00:55:14.054Z,1755305714.054 [keepstation:NeedComms:D:A_Timeout](INFO): Completed keepstation:NeedComms:D:A_Timeout
2025-08-16T00:55:14.054Z,1755305714.054 [keepstation:NeedComms:D] Stopped
2025-08-16T00:55:14.054Z,1755305714.054 [keepstation:NeedComms:E] Running Loop=1
2025-08-16T00:55:16.055Z,1755305716.055 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005515.00,A,3648.16516,N,12147.27809,W,0.097,350.60,160825,,,D*72
2025-08-16T00:55:16.057Z,1755305716.057 [NAL9602](INFO): GPS fix at 20250816T005515: (36.802753, -121.787968)
2025-08-16T00:55:16.109Z,1755305716.109 [keepstation:NeedComms:E] Stopped
2025-08-16T00:55:16.109Z,1755305716.109 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-16T00:55:16.110Z,1755305716.110 [keepstation:NeedComms] Stopped
2025-08-16T00:55:16.110Z,1755305716.110 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-16T00:55:16.110Z,1755305716.110 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-16T00:55:16.110Z,1755305716.110 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T00:55:16.506Z,1755305716.506 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-16T00:55:16.506Z,1755305716.506 [keepstation:SurfaceComms:B] Stopped
2025-08-16T00:55:16.507Z,1755305716.507 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-16T00:55:16.507Z,1755305716.507 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-16T00:55:16.507Z,1755305716.507 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-16T00:55:16.507Z,1755305716.507 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-16T00:55:16.913Z,1755305716.913 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-16T00:55:16.913Z,1755305716.913 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-16T00:55:16.913Z,1755305716.913 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-16T00:55:16.914Z,1755305716.914 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-16T00:55:16.914Z,1755305716.914 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-16T00:55:16.914Z,1755305716.914 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-16T00:55:17.354Z,1755305717.354 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-16T00:55:17.354Z,1755305717.354 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-08-16T00:55:17.354Z,1755305717.354 [keepstation:SurfaceComms:setStation] Stopped
2025-08-16T00:55:17.354Z,1755305717.354 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-16T00:55:17.354Z,1755305717.354 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-16T00:55:17.355Z,1755305717.355 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-16T00:55:17.355Z,1755305717.355 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-16T00:55:17.726Z,1755305717.726 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-16T00:55:17.726Z,1755305717.726 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-16T00:55:17.727Z,1755305717.727 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-16T00:55:17.727Z,1755305717.727 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-16T00:55:17.727Z,1755305717.727 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-16T00:55:17.727Z,1755305717.727 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-16T00:55:17.727Z,1755305717.727 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-16T00:55:17.727Z,1755305717.727 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-16T00:55:17.727Z,1755305717.727 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-16T00:55:17.752Z,1755305717.752 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-16T00:55:17.752Z,1755305717.752 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-16T00:55:17.754Z,1755305717.754 [keepstation:SurfaceComms:setSink] Stopped
2025-08-16T00:55:17.754Z,1755305717.754 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-16T00:55:17.754Z,1755305717.754 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-16T00:55:17.754Z,1755305717.754 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-16T00:55:17.754Z,1755305717.754 [keepstation:SurfaceComms] Stopped
2025-08-16T00:55:17.754Z,1755305717.754 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-16T00:55:29.060Z,1755305729.060 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20250816T002839/Express0020.lzma
2025-08-16T00:55:30.062Z,1755305730.062 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Express0020.lzma.bak
2025-08-16T00:55:30.062Z,1755305730.062 [DataOverHttps](INFO): SBD MOMSN=25854494
2025-08-16T00:55:45.501Z,1755305745.501 [BackseatComponent](IMPORTANT): piscivore: running backseat_app backseat application.
2025-08-16T00:55:47.982Z,1755305747.982 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-16T00:58:19.041Z,1755305899.041 [CommandExec](IMPORTANT): got command show stack
2025-08-16T00:58:19.041Z,1755305899.041 [CommandExec](IMPORTANT): Behavior Stack:
2025-08-16T00:58:19.042Z,1755305899.042 [keepstation:StandardEnvelopes](IMPORTANT): Priority 0: keepstation:StandardEnvelopes:A.AltitudeEnvelope
2025-08-16T00:58:19.042Z,1755305899.042 [keepstation:StandardEnvelopes](IMPORTANT): Priority 1: keepstation:StandardEnvelopes:B.DepthEnvelope
2025-08-16T00:58:19.043Z,1755305899.043 [keepstation:StandardEnvelopes](IMPORTANT): Priority 2: keepstation:StandardEnvelopes:C.OffshoreEnvelope
2025-08-16T00:58:19.043Z,1755305899.043 [keepstation:BackseatDriver](IMPORTANT): Priority 3: keepstation:BackseatDriver:A.BackseatDriver
2025-08-16T00:58:19.043Z,1755305899.043 [keepstation:PowerOnly](IMPORTANT): Priority 4: keepstation:PowerOnly:E.Wait
2025-08-16T00:58:19.050Z,1755305899.050 [keepstation](IMPORTANT): Priority 5: keepstation:H.Pitch
2025-08-16T00:58:19.051Z,1755305899.051 [keepstation:DiveCmd:StationKeep](IMPORTANT): Priority 6: keepstation:DiveCmd:StationKeep:A
2025-08-16T00:58:19.051Z,1755305899.051 [keepstation:DiveCmd:StationKeep](IMPORTANT): Priority 7: keepstation:DiveCmd:StationKeep:B.Pitch
2025-08-16T00:58:19.051Z,1755305899.051 [keepstation:DiveCmd:StationKeep](IMPORTANT): Priority 8: keepstation:DiveCmd:StationKeep:C.KeepStation
2025-08-16T00:58:19.051Z,1755305899.051 [keepstation:KeepStation](IMPORTANT): Priority 9: keepstation:KeepStation:B.Wait
2025-08-16T00:59:16.060Z,1755305956.060 [keepstation:SurfaceComms] Running Loop=1
2025-08-16T00:59:16.060Z,1755305956.060 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-16T00:59:16.061Z,1755305956.061 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-16T00:59:16.061Z,1755305956.061 [keepstation:SurfaceComms:A] Stopped
2025-08-16T00:59:16.061Z,1755305956.061 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-16T00:59:16.061Z,1755305956.061 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-16T00:59:16.441Z,1755305956.441 [keepstation:KeepStation:Dive] Stopped
2025-08-16T00:59:16.441Z,1755305956.441 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-16T00:59:16.441Z,1755305956.441 [keepstation:KeepStation:Dive:A] Stopped
2025-08-16T00:59:16.441Z,1755305956.441 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-16T00:59:16.442Z,1755305956.442 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-16T00:59:16.442Z,1755305956.442 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-16T00:59:16.442Z,1755305956.442 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-16T00:59:16.442Z,1755305956.442 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-16T00:59:16.442Z,1755305956.442 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-16T00:59:16.442Z,1755305956.442 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-16T00:59:16.445Z,1755305956.445 [keepstation:NeedComms] Running Loop=1
2025-08-16T00:59:16.445Z,1755305956.445 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-16T00:59:16.445Z,1755305956.445 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-16T00:59:16.445Z,1755305956.445 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T00:59:16.445Z,1755305956.445 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-16T00:59:16.446Z,1755305956.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-16T00:59:16.446Z,1755305956.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-16T00:59:16.446Z,1755305956.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-16T00:59:16.447Z,1755305956.447 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T00:59:16.447Z,1755305956.447 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T00:59:16.447Z,1755305956.447 [keepstation:NeedComms:A] Running Loop=1
2025-08-16T00:59:16.448Z,1755305956.448 [keepstation:NeedComms:A](INFO): last time_fix was: 1755305715.000000 second since 1970/01/01T00:00:00Z
2025-08-16T00:59:16.448Z,1755305956.448 [keepstation:NeedComms:A] Stopped
2025-08-16T00:59:16.857Z,1755305956.857 [keepstation:NeedComms:C] Running Loop=1
2025-08-16T00:59:18.866Z,1755305958.866 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005918.00,A,3648.16535,N,12147.28874,W,0.350,350.60,160825,,,D*7F
2025-08-16T00:59:18.868Z,1755305958.868 [NAL9602](INFO): GPS fix at 20250816T005918: (36.802756, -121.788146)
2025-08-16T00:59:18.885Z,1755305958.885 [keepstation:NeedComms:C] Stopped
2025-08-16T00:59:18.885Z,1755305958.885 [keepstation:NeedComms:D] Running Loop=1
2025-08-16T00:59:26.487Z,1755305966.487 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250816T002839/Courier0034.lzma
2025-08-16T00:59:27.490Z,1755305967.490 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Courier0034.lzma.bak
2025-08-16T00:59:27.490Z,1755305967.490 [DataOverHttps](INFO): SBD MOMSN=25854499
2025-08-16T00:59:45.073Z,1755305985.073 [DataOverHttps](INFO): Sending 474 bytes from file Logs/20250816T002839/Express0023.lzma
2025-08-16T00:59:46.075Z,1755305986.075 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Express0023.lzma.bak
2025-08-16T00:59:46.075Z,1755305986.075 [DataOverHttps](INFO): SBD MOMSN=25854502
2025-08-16T00:59:49.181Z,1755305989.181 [keepstation:NeedComms:D](INFO): Timed out from 2025-08-16T00:59:18.9Z
2025-08-16T00:59:49.181Z,1755305989.181 [keepstation:NeedComms:D:A_Timeout] Running Loop=1
2025-08-16T00:59:49.181Z,1755305989.181 [keepstation:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:D:A_Timeout
2025-08-16T00:59:49.182Z,1755305989.182 [keepstation:NeedComms:D:A_Timeout:A] Running Loop=1
2025-08-16T00:59:49.182Z,1755305989.182 [keepstation:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 0.500000 min minutes
2025-08-16T00:59:49.182Z,1755305989.182 [keepstation:NeedComms:D:A_Timeout:A] Stopped
2025-08-16T00:59:49.182Z,1755305989.182 [keepstation:NeedComms:D:A_Timeout](INFO): Completed keepstation:NeedComms:D:A_Timeout
2025-08-16T00:59:49.182Z,1755305989.182 [keepstation:NeedComms:D] Stopped
2025-08-16T00:59:49.183Z,1755305989.183 [keepstation:NeedComms:E] Running Loop=1
2025-08-16T00:59:49.571Z,1755305989.571 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-16T00:59:49.652Z,1755305989.652 [NAL9602](ERROR): received:
+CSQ:1
OK844, 2, 0, 0, 0
OK
2025-08-16T01:00:05.259Z,1755306005.259 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250816T002839/Express0026.lzma
2025-08-16T01:00:06.262Z,1755306006.262 [DataOverHttps](INFO): Moved sent file to Logs/20250816T002839/Express0026.lzma.bak
2025-08-16T01:00:06.262Z,1755306006.262 [DataOverHttps](INFO): SBD MOMSN=25854511
2025-08-16T01:00:38.855Z,1755306038.855 [NAL9602](INFO): SBD MO Status=2, MOMSN=21844, MT Status=2, MTMSN=0
2025-08-16T01:00:38.855Z,1755306038.855 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-16T01:00:52.587Z,1755306052.587 [NAL9602](INFO): SBD MO Status=0, MOMSN=21844, MT Status=0, MTMSN=0
2025-08-16T01:00:52.587Z,1755306052.587 [NAL9602](INFO): No messages in MT queue
2025-08-16T01:00:53.802Z,1755306053.802 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010053.00,A,3648.16613,N,12147.28395,W,0.117,350.60,160825,,,D*7F
2025-08-16T01:00:53.804Z,1755306053.804 [NAL9602](INFO): GPS fix at 20250816T010053: (36.802769, -121.788066)
2025-08-16T01:00:53.817Z,1755306053.817 [keepstation:NeedComms:E] Stopped
2025-08-16T01:00:53.818Z,1755306053.818 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-16T01:00:53.818Z,1755306053.818 [keepstation:NeedComms] Stopped
2025-08-16T01:00:53.818Z,1755306053.818 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-16T01:00:53.818Z,1755306053.818 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-16T01:00:53.818Z,1755306053.818 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T01:00:54.237Z,1755306054.237 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-16T01:00:54.237Z,1755306054.237 [keepstation:SurfaceComms:B] Stopped
2025-08-16T01:00:54.238Z,1755306054.238 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-16T01:00:54.238Z,1755306054.238 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-16T01:00:54.238Z,1755306054.238 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-16T01:00:54.238Z,1755306054.238 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-16T01:00:54.623Z,1755306054.623 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-16T01:00:54.623Z,1755306054.623 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-16T01:00:54.623Z,1755306054.623 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-16T01:00:54.623Z,1755306054.623 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-16T01:00:54.623Z,1755306054.623 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-16T01:00:54.623Z,1755306054.623 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-16T01:00:55.023Z,1755306055.023 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-16T01:00:55.023Z,1755306055.023 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-08-16T01:00:55.023Z,1755306055.023 [keepstation:SurfaceComms:setStation] Stopped
2025-08-16T01:00:55.023Z,1755306055.023 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-16T01:00:55.023Z,1755306055.023 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-16T01:00:55.023Z,1755306055.023 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-16T01:00:55.023Z,1755306055.023 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-16T01:00:55.431Z,1755306055.431 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-16T01:00:55.431Z,1755306055.431 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-16T01:00:55.431Z,1755306055.431 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-16T01:00:55.432Z,1755306055.432 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-16T01:00:55.432Z,1755306055.432 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-16T01:00:55.432Z,1755306055.432 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-16T01:00:55.432Z,1755306055.432 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-16T01:00:55.433Z,1755306055.433 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-16T01:00:55.433Z,1755306055.433 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-16T01:00:55.433Z,1755306055.433 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-16T01:00:55.433Z,1755306055.433 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-16T01:00:55.434Z,1755306055.434 [keepstation:SurfaceComms:setSink] Stopped
2025-08-16T01:00:55.434Z,1755306055.434 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-16T01:00:55.435Z,1755306055.435 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-16T01:00:55.435Z,1755306055.435 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-16T01:00:55.435Z,1755306055.435 [keepstation:SurfaceComms] Stopped
2025-08-16T01:00:55.435Z,1755306055.435 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-16T01:01:26.121Z,1755306086.121 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-16T01:04:53.816Z,1755306293.816 [keepstation:SurfaceComms] Running Loop=1
2025-08-16T01:04:53.816Z,1755306293.816 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-16T01:04:53.817Z,1755306293.817 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-16T01:04:53.817Z,1755306293.817 [keepstation:SurfaceComms:A] Stopped
2025-08-16T01:04:53.817Z,1755306293.817 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-16T01:04:53.817Z,1755306293.817 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-16T01:04:54.233Z,1755306294.233 [keepstation:KeepStation:Dive] Stopped
2025-08-16T01:04:54.233Z,1755306294.233 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-16T01:04:54.233Z,1755306294.233 [keepstation:KeepStation:Dive:A] Stopped
2025-08-16T01:04:54.234Z,1755306294.234 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-16T01:04:54.234Z,1755306294.234 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-16T01:04:54.234Z,1755306294.234 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-16T01:04:54.234Z,1755306294.234 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-16T01:04:54.234Z,1755306294.234 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-16T01:04:54.234Z,1755306294.234 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-16T01:04:54.234Z,1755306294.234 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-16T01:04:54.237Z,1755306294.237 [keepstation:NeedComms] Running Loop=1
2025-08-16T01:04:54.237Z,1755306294.237 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-16T01:04:54.237Z,1755306294.237 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-16T01:04:54.237Z,1755306294.237 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T01:04:54.237Z,1755306294.237 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-16T01:04:54.238Z,1755306294.238 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-16T01:04:54.238Z,1755306294.238 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-16T01:04:54.238Z,1755306294.238 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-16T01:04:54.239Z,1755306294.239 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T01:04:54.239Z,1755306294.239 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T01:04:54.239Z,1755306294.239 [keepstation:NeedComms:A] Running Loop=1
2025-08-16T01:04:54.241Z,1755306294.241 [keepstation:NeedComms:A](INFO): last time_fix was: 1755306053.000000 second since 1970/01/01T00:00:00Z
2025-08-16T01:04:54.241Z,1755306294.241 [keepstation:NeedComms:A] Stopped
2025-08-16T01:04:54.617Z,1755306294.617 [keepstation:NeedComms:C] Running Loop=1
2025-08-16T01:04:56.646Z,1755306296.646 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010456.00,A,3648.16790,N,12147.28239,W,0.136,350.60,160825,,,D*70
2025-08-16T01:04:56.648Z,1755306296.648 [NAL9602](INFO): GPS fix at 20250816T010456: (36.802798, -121.788040)
2025-08-16T01:04:56.677Z,1755306296.677 [keepstation:NeedComms:C] Stopped
2025-08-16T01:04:56.677Z,1755306296.677 [keepstation:NeedComms:D] Running Loop=1
2025-08-16T01:05:00.045Z,1755306300.045 [CommandExec](IMPORTANT): got command restart system
2025-08-16T01:05:02.232Z,1755306302.232 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-08-16T01:05:02.232Z,1755306302.232 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:02.232Z,1755306302.232 [CommandExec](INFO): Uninitializing the command executive.
2025-08-16T01:05:02.233Z,1755306302.233 [CommandExec](INFO): Uninitializing the command scheduler.
2025-08-16T01:05:02.233Z,1755306302.233 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:02.240Z,1755306302.240 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-08-16T01:05:02.240Z,1755306302.240 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-08-16T01:05:02.240Z,1755306302.240 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:02.241Z,1755306302.241 [NavChartDb](INFO): Join timeout helper Thread ID is 3135
2025-08-16T01:05:02.389Z,1755306302.389 [MissionManager](INFO): MissionManager is completed.
2025-08-16T01:05:02.390Z,1755306302.390 [MissionManager](INFO): Uninitializing Mission keepstation
2025-08-16T01:05:02.390Z,1755306302.390 [keepstation] Stopped
2025-08-16T01:05:02.390Z,1755306302.390 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-08-16T01:05:02.390Z,1755306302.390 [keepstation:NeedComms] Stopped
2025-08-16T01:05:02.390Z,1755306302.390 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-16T01:05:02.390Z,1755306302.390 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-16T01:05:02.390Z,1755306302.390 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T01:05:02.390Z,1755306302.390 [keepstation:NeedComms:D] Stopped
2025-08-16T01:05:02.390Z,1755306302.390 [keepstation:StandardEnvelopes] Stopped
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:BackseatDriver] Stopped
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-08-16T01:05:02.391Z,1755306302.391 [keepstation:PowerOnly] Stopped
2025-08-16T01:05:02.396Z,1755306302.396 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-08-16T01:05:02.396Z,1755306302.396 [keepstation:PowerOnly:E.Wait] Stopped
2025-08-16T01:05:02.396Z,1755306302.396 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-08-16T01:05:02.396Z,1755306302.396 [keepstation:H.Pitch] Stopped
2025-08-16T01:05:02.396Z,1755306302.396 [keepstation:SurfaceComms] Stopped
2025-08-16T01:05:02.396Z,1755306302.396 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-16T01:05:02.396Z,1755306302.396 [keepstation:SurfaceComms:B] Stopped
2025-08-16T01:05:02.397Z,1755306302.397 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-16T01:05:02.397Z,1755306302.397 [keepstation:DiveCmd] Stopped
2025-08-16T01:05:02.397Z,1755306302.397 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2025-08-16T01:05:02.397Z,1755306302.397 [keepstation:KeepStation] Stopped
2025-08-16T01:05:02.397Z,1755306302.397 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-08-16T01:05:02.397Z,1755306302.397 [keepstation:KeepStation:B.Wait] Stopped
2025-08-16T01:05:02.397Z,1755306302.397 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component.
2025-08-16T01:05:02.521Z,1755306302.521 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:02.521Z,1755306302.521 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:02.532Z,1755306302.532 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-08-16T01:05:02.532Z,1755306302.532 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:02.533Z,1755306302.533 [Radio_Surface](INFO): Join timeout helper Thread ID is 3136
2025-08-16T01:05:02.734Z,1755306302.734 [MissionManager](IMPORTANT): Started mission Default
2025-08-16T01:05:02.734Z,1755306302.734 [Default] Running Loop=1
2025-08-16T01:05:02.734Z,1755306302.734 [Default](DEBUG): Aggregate::initialize Default
2025-08-16T01:05:02.734Z,1755306302.734 [Default:B.GoToSurface] Running Loop=1
2025-08-16T01:05:02.735Z,1755306302.735 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-16T01:05:02.735Z,1755306302.735 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-08-16T01:05:02.735Z,1755306302.735 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-08-16T01:05:02.735Z,1755306302.735 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-08-16T01:05:02.773Z,1755306302.773 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-08-16T01:05:02.773Z,1755306302.773 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-16T01:05:02.774Z,1755306302.774 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-16T01:05:02.812Z,1755306302.812 [Radio_Surface](INFO): Powering down
2025-08-16T01:05:02.813Z,1755306302.813 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:02.813Z,1755306302.813 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:02.832Z,1755306302.832 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-08-16T01:05:02.832Z,1755306302.832 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:02.832Z,1755306302.832 [Onboard](INFO): Join timeout helper Thread ID is 3137
2025-08-16T01:05:03.144Z,1755306303.144 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:03.144Z,1755306303.144 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:03.156Z,1755306303.156 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-08-16T01:05:03.156Z,1755306303.156 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:03.156Z,1755306303.156 [DataOverHttps](INFO): Join timeout helper Thread ID is 3138
2025-08-16T01:05:03.519Z,1755306303.519 [Default:A.Wait] Running Loop=1
2025-08-16T01:05:03.519Z,1755306303.519 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-08-16T01:05:03.838Z,1755306303.838 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-08-16T01:05:03.838Z,1755306303.838 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-08-16T01:05:05.299Z,1755306305.299 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:05.303Z,1755306305.303 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:05.313Z,1755306305.313 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-08-16T01:05:05.313Z,1755306305.313 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:05.313Z,1755306305.313 [DAT](INFO): Join timeout helper Thread ID is 3139
2025-08-16T01:05:05.544Z,1755306305.544 [DAT](INFO): Powering down
2025-08-16T01:05:05.613Z,1755306305.613 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:05.613Z,1755306305.613 [DAT](INFO): Powering down
2025-08-16T01:05:05.614Z,1755306305.614 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:05.621Z,1755306305.621 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-08-16T01:05:05.621Z,1755306305.621 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:05.621Z,1755306305.621 [BackseatComponent](INFO): Join timeout helper Thread ID is 3140
2025-08-16T01:05:05.672Z,1755306305.672 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:05.673Z,1755306305.673 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:05.685Z,1755306305.685 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-08-16T01:05:05.685Z,1755306305.685 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:05.685Z,1755306305.685 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3141
2025-08-16T01:05:06.100Z,1755306306.100 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:06.100Z,1755306306.100 [WetLabsBB2FL](INFO): Powering down
2025-08-16T01:05:06.101Z,1755306306.101 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.113Z,1755306306.113 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-08-16T01:05:06.113Z,1755306306.113 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.113Z,1755306306.113 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3142
2025-08-16T01:05:06.292Z,1755306306.292 [CTD_Seabird](INFO): Powering down
2025-08-16T01:05:06.304Z,1755306306.304 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:06.304Z,1755306306.304 [CTD_Seabird](INFO): Powering down
2025-08-16T01:05:06.316Z,1755306306.316 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.325Z,1755306306.325 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-08-16T01:05:06.325Z,1755306306.325 [logger ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.325Z,1755306306.325 [logger](INFO): Join timeout helper Thread ID is 3143
2025-08-16T01:05:06.336Z,1755306306.336 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:06.336Z,1755306306.336 [logger ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.344Z,1755306306.344 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-08-16T01:05:06.345Z,1755306306.345 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.345Z,1755306306.345 [CommandLine](INFO): Join timeout helper Thread ID is 3144
2025-08-16T01:05:06.424Z,1755306306.424 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:06.424Z,1755306306.424 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.437Z,1755306306.437 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-08-16T01:05:06.437Z,1755306306.437 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.437Z,1755306306.437 [CommandExec](INFO): Join timeout helper Thread ID is 3145
2025-08-16T01:05:06.450Z,1755306306.450 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-08-16T01:05:06.450Z,1755306306.450 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:06.451Z,1755306306.451 [controlThread](INFO): Join timeout helper Thread ID is 3146
2025-08-16T01:05:06.478Z,1755306306.478 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-16T01:05:06.478Z,1755306306.478 [controlThread](DEBUG): Uninitializing ControlThread
2025-08-16T01:05:06.479Z,1755306306.479 [AHRS_M2](INFO): Powering down
2025-08-16T01:05:06.656Z,1755306306.656 [DVL_micro](INFO): Powering down
2025-08-16T01:05:06.657Z,1755306306.657 [NAL9602](INFO): Powering down
2025-08-16T01:05:06.658Z,1755306306.658 [Sonardyne_Nano](INFO): Powering down
2025-08-16T01:05:06.864Z,1755306306.864 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-08-16T01:05:06.865Z,1755306306.865 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-08-16T01:05:06.865Z,1755306306.865 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-08-16T01:05:06.866Z,1755306306.866 [MissionManager](INFO): Uninitializing Mission Default
2025-08-16T01:05:06.866Z,1755306306.866 [Default] Stopped
2025-08-16T01:05:06.866Z,1755306306.866 [Default](DEBUG): Aggregate::uninitialize Default
2025-08-16T01:05:06.866Z,1755306306.866 [Default:A.Wait] Stopped
2025-08-16T01:05:06.866Z,1755306306.866 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-08-16T01:05:06.866Z,1755306306.866 [Default:B.GoToSurface] Stopped
2025-08-16T01:05:06.866Z,1755306306.866 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-16T01:05:06.869Z,1755306306.869 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-08-16T01:05:06.870Z,1755306306.870 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-08-16T01:05:06.870Z,1755306306.870 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-08-16T01:05:06.870Z,1755306306.870 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-08-16T01:05:06.871Z,1755306306.871 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-08-16T01:05:06.871Z,1755306306.871 [BuoyancyServo](INFO): Powering down
2025-08-16T01:05:06.000Z,1755306307.000 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-08-16T01:05:06.000Z,1755306307.000 [ElevatorServo](INFO): Powering down
2025-08-16T01:05:07.001Z,1755306307.001 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-08-16T01:05:07.001Z,1755306307.001 [MassServo](INFO): Powering down
2025-08-16T01:05:07.002Z,1755306307.002 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-08-16T01:05:07.002Z,1755306307.002 [RudderServo](INFO): Powering down
2025-08-16T01:05:07.003Z,1755306307.003 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-08-16T01:05:07.003Z,1755306307.003 [ThrusterHE](INFO): Powering down
2025-08-16T01:05:07.004Z,1755306307.004 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-08-16T01:05:07.004Z,1755306307.004 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-08-16T01:05:07.005Z,1755306307.005 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-08-16T01:05:07.005Z,1755306307.005 [CBIT](DEBUG): Powering off loads.
2025-08-16T01:05:07.016Z,1755306307.016 [CBIT](DEBUG): Disabling WDT.
2025-08-16T01:05:07.028Z,1755306307.028 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-08-16T01:05:07.028Z,1755306307.028 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-08-16T01:05:07.029Z,1755306307.029 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.078Z,1755306307.078 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-08-16T01:05:07.078Z,1755306307.078 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-08-16T01:05:07.093Z,1755306307.093 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.100Z,1755306307.100 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.133Z,1755306307.133 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.135Z,1755306307.135 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.143Z,1755306307.143 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.150Z,1755306307.150 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.195Z,1755306307.195 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.252Z,1755306307.252 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-16T01:05:07.328Z,1755306307.328 [logger ThreadHandler](INFO): Thread cancelled.