2026-03-12T16:32:53.022Z,1773333173.022 [Supervisor](DEBUG): Initializing supervisor. 2026-03-12T16:32:53.027Z,1773333173.027 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2026-03-12T16:32:53.027Z,1773333173.027 [SyncHandler](INFO): Protected caller Thread ID is 3139 2026-03-12T16:32:53.028Z,1773333173.028 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2026-03-12T16:32:53.028Z,1773333173.028 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2026-03-12T16:32:53.029Z,1773333173.029 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3140 2026-03-12T16:32:53.033Z,1773333173.033 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2026-03-12T16:32:53.053Z,1773333173.053 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2026-03-12T16:32:53.054Z,1773333173.054 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2026-03-12T16:32:53.054Z,1773333173.054 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3141 2026-03-12T16:32:53.058Z,1773333173.058 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2026-03-12T16:32:53.059Z,1773333173.059 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2026-03-12T16:32:53.060Z,1773333173.060 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3142 2026-03-12T16:32:53.062Z,1773333173.062 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2026-03-12T16:32:53.063Z,1773333173.063 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2026-03-12T16:32:53.063Z,1773333173.063 [logger ThreadHandler](INFO): Protected caller Thread ID is 3143 2026-03-12T16:32:53.067Z,1773333173.067 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2026-03-12T16:32:53.067Z,1773333173.067 [Supervisor](INFO): Looking for Config files in directory: Config/ 2026-03-12T16:32:53.070Z,1773333173.070 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2026-03-12T16:32:53.481Z,1773333173.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2026-03-12T16:32:53.481Z,1773333173.481 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2026-03-12T16:32:53.579Z,1773333173.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2026-03-12T16:32:53.580Z,1773333173.580 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2026-03-12T16:32:53.693Z,1773333173.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2026-03-12T16:32:53.695Z,1773333173.695 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2026-03-12T16:32:53.832Z,1773333173.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2026-03-12T16:32:53.834Z,1773333173.834 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2026-03-12T16:32:54.177Z,1773333174.177 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2026-03-12T16:32:54.178Z,1773333174.178 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2026-03-12T16:32:54.282Z,1773333174.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2026-03-12T16:32:54.284Z,1773333174.284 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2026-03-12T16:32:54.673Z,1773333174.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2026-03-12T16:32:54.674Z,1773333174.674 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2026-03-12T16:32:54.898Z,1773333174.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2026-03-12T16:32:54.899Z,1773333174.899 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2026-03-12T16:32:54.987Z,1773333174.987 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2026-03-12T16:32:55.226Z,1773333175.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2026-03-12T16:32:55.227Z,1773333175.227 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2026-03-12T16:32:55.445Z,1773333175.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2026-03-12T16:32:55.447Z,1773333175.447 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2026-03-12T16:32:55.980Z,1773333175.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2026-03-12T16:32:55.982Z,1773333175.982 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2026-03-12T16:32:56.165Z,1773333176.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2026-03-12T16:32:56.166Z,1773333176.166 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2026-03-12T16:32:56.447Z,1773333176.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2026-03-12T16:32:56.449Z,1773333176.449 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2026-03-12T16:32:57.290Z,1773333177.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2026-03-12T16:32:57.292Z,1773333177.292 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2026-03-12T16:32:57.837Z,1773333177.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2026-03-12T16:32:57.839Z,1773333177.839 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2026-03-12T16:32:57.842Z,1773333177.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2026-03-12T16:32:57.947Z,1773333177.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2026-03-12T16:32:58.081Z,1773333178.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2026-03-12T16:32:58.174Z,1773333178.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2026-03-12T16:32:58.288Z,1773333178.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2026-03-12T16:32:58.388Z,1773333178.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2026-03-12T16:32:58.651Z,1773333178.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2026-03-12T16:32:58.651Z,1773333178.651 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2026-03-12T16:32:58.767Z,1773333178.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2026-03-12T16:32:58.871Z,1773333178.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2026-03-12T16:32:59.015Z,1773333179.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2026-03-12T16:32:59.108Z,1773333179.108 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2026-03-12T16:32:59.118Z,1773333179.118 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2026-03-12T16:32:59.278Z,1773333179.278 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2026-03-12T16:32:59.279Z,1773333179.279 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2026-03-12T16:32:59.345Z,1773333179.345 [VerticalControl](DEBUG): Construct VerticalControl. 2026-03-12T16:32:59.407Z,1773333179.407 [VerticalControl] Loaded 2026-03-12T16:32:59.407Z,1773333179.407 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2026-03-12T16:32:59.410Z,1773333179.410 [HorizontalControl](DEBUG): Construct HorizontalControl. 2026-03-12T16:32:59.457Z,1773333179.457 [HorizontalControl] Loaded 2026-03-12T16:32:59.457Z,1773333179.457 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2026-03-12T16:32:59.459Z,1773333179.459 [SpeedControl](DEBUG): Construct SpeedControl. 2026-03-12T16:32:59.462Z,1773333179.462 [SpeedControl] Loaded 2026-03-12T16:32:59.463Z,1773333179.463 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2026-03-12T16:32:59.465Z,1773333179.465 [LoopControl](DEBUG): Construct LoopControl. 2026-03-12T16:32:59.466Z,1773333179.466 [LoopControl] Loaded 2026-03-12T16:32:59.466Z,1773333179.466 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2026-03-12T16:32:59.467Z,1773333179.467 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2026-03-12T16:32:59.467Z,1773333179.467 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2026-03-12T16:32:59.562Z,1773333179.562 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2026-03-12T16:32:59.563Z,1773333179.563 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2026-03-12T16:33:00.229Z,1773333180.229 [AHRS_M2] Loaded 2026-03-12T16:33:00.230Z,1773333180.230 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2026-03-12T16:33:00.268Z,1773333180.268 [BackseatComponent] Loaded 2026-03-12T16:33:00.268Z,1773333180.268 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2026-03-12T16:33:00.269Z,1773333180.269 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408DA4E0 2026-03-12T16:33:00.269Z,1773333180.269 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3222 2026-03-12T16:33:00.273Z,1773333180.273 [LcmPublisher] Loaded 2026-03-12T16:33:00.273Z,1773333180.273 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2026-03-12T16:33:01.063Z,1773333181.063 [BPC1] Loaded 2026-03-12T16:33:01.063Z,1773333181.063 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2026-03-12T16:33:01.149Z,1773333181.149 [DATMMP] Loaded 2026-03-12T16:33:01.150Z,1773333181.150 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2026-03-12T16:33:01.151Z,1773333181.151 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0 2026-03-12T16:33:01.151Z,1773333181.151 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 3223 2026-03-12T16:33:01.224Z,1773333181.224 [DataOverHttps] Loaded 2026-03-12T16:33:01.225Z,1773333181.225 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2026-03-12T16:33:01.226Z,1773333181.226 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0 2026-03-12T16:33:01.226Z,1773333181.226 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3224 2026-03-12T16:33:01.248Z,1773333181.248 [Depth_Keller] Loaded 2026-03-12T16:33:01.249Z,1773333181.249 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2026-03-12T16:33:01.254Z,1773333181.254 [DropWeight] Loaded 2026-03-12T16:33:01.254Z,1773333181.254 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2026-03-12T16:33:01.317Z,1773333181.317 [NAL9602] Loaded 2026-03-12T16:33:01.317Z,1773333181.317 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2026-03-12T16:33:01.346Z,1773333181.346 [Onboard] Loaded 2026-03-12T16:33:01.346Z,1773333181.346 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2026-03-12T16:33:01.347Z,1773333181.347 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0 2026-03-12T16:33:01.347Z,1773333181.347 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3225 2026-03-12T16:33:01.361Z,1773333181.361 [Power24vConverter] Loaded 2026-03-12T16:33:01.361Z,1773333181.361 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2026-03-12T16:33:01.377Z,1773333181.377 [Radio_Surface] Loaded 2026-03-12T16:33:01.377Z,1773333181.377 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2026-03-12T16:33:01.378Z,1773333181.378 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0 2026-03-12T16:33:01.379Z,1773333181.379 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3226 2026-03-12T16:33:01.394Z,1773333181.394 [Sonardyne_Nano] Loaded 2026-03-12T16:33:01.394Z,1773333181.394 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2026-03-12T16:33:01.395Z,1773333181.395 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2026-03-12T16:33:01.395Z,1773333181.395 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2026-03-12T16:33:01.408Z,1773333181.408 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2026-03-12T16:33:01.409Z,1773333181.409 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2026-03-12T16:33:01.451Z,1773333181.451 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2026-03-12T16:33:01.452Z,1773333181.452 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2026-03-12T16:33:01.637Z,1773333181.637 [SBIT](DEBUG): Construct Startup Built In Test. 2026-03-12T16:33:01.646Z,1773333181.646 [SBIT] Loaded 2026-03-12T16:33:01.647Z,1773333181.647 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2026-03-12T16:33:01.649Z,1773333181.649 [IBIT](DEBUG): Construct Initiated Built In Test. 2026-03-12T16:33:01.662Z,1773333181.662 [IBIT] Loaded 2026-03-12T16:33:01.662Z,1773333181.662 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2026-03-12T16:33:01.667Z,1773333181.667 [CBIT](DEBUG): Construct Continuous Built In Test. 2026-03-12T16:33:01.746Z,1773333181.746 [CBIT] Loaded 2026-03-12T16:33:01.747Z,1773333181.747 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2026-03-12T16:33:01.779Z,1773333181.779 [GFScanner] Loaded 2026-03-12T16:33:01.779Z,1773333181.779 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2026-03-12T16:33:01.780Z,1773333181.780 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2026-03-12T16:33:01.780Z,1773333181.780 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2026-03-12T16:33:02.165Z,1773333182.165 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2026-03-12T16:33:02.165Z,1773333182.165 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2026-03-12T16:33:02.254Z,1773333182.254 [DepthRateCalculator] Loaded 2026-03-12T16:33:02.254Z,1773333182.254 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2026-03-12T16:33:02.259Z,1773333182.259 [PitchRateCalculator] Loaded 2026-03-12T16:33:02.259Z,1773333182.259 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2026-03-12T16:33:02.270Z,1773333182.270 [SpeedCalculator] Loaded 2026-03-12T16:33:02.270Z,1773333182.270 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2026-03-12T16:33:02.274Z,1773333182.274 [YawRateCalculator] Loaded 2026-03-12T16:33:02.274Z,1773333182.274 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2026-03-12T16:33:02.293Z,1773333182.293 [ElevatorOffsetCalculator] Loaded 2026-03-12T16:33:02.293Z,1773333182.293 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2026-03-12T16:33:02.294Z,1773333182.294 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2026-03-12T16:33:02.294Z,1773333182.294 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2026-03-12T16:33:02.500Z,1773333182.500 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2026-03-12T16:33:02.500Z,1773333182.500 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2026-03-12T16:33:02.631Z,1773333182.631 [DeadReckonUsingMultipleVelocitySources] Loaded 2026-03-12T16:33:02.632Z,1773333182.632 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2026-03-12T16:33:02.646Z,1773333182.646 [NavChart] Loaded 2026-03-12T16:33:02.646Z,1773333182.646 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2026-03-12T16:33:02.651Z,1773333182.651 [UniversalFixResidualReporter] Loaded 2026-03-12T16:33:02.652Z,1773333182.652 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2026-03-12T16:33:02.652Z,1773333182.652 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2026-03-12T16:33:02.653Z,1773333182.653 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2026-03-12T16:33:02.811Z,1773333182.811 [BuoyancyServo] Loaded 2026-03-12T16:33:02.811Z,1773333182.811 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2026-03-12T16:33:02.834Z,1773333182.834 [ElevatorServo] Loaded 2026-03-12T16:33:02.835Z,1773333182.835 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2026-03-12T16:33:02.856Z,1773333182.856 [MassServo] Loaded 2026-03-12T16:33:02.856Z,1773333182.856 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2026-03-12T16:33:02.877Z,1773333182.877 [RudderServo] Loaded 2026-03-12T16:33:02.877Z,1773333182.877 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2026-03-12T16:33:02.894Z,1773333182.894 [ThrusterHE] Loaded 2026-03-12T16:33:02.894Z,1773333182.894 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2026-03-12T16:33:02.894Z,1773333182.894 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2026-03-12T16:33:02.895Z,1773333182.895 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2026-03-12T16:33:03.101Z,1773333183.101 [CTD_Seabird] Loaded 2026-03-12T16:33:03.102Z,1773333183.102 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2026-03-12T16:33:03.103Z,1773333183.103 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C2B4E0 2026-03-12T16:33:03.103Z,1773333183.103 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3227 2026-03-12T16:33:03.139Z,1773333183.139 [ESPComponent] Loaded 2026-03-12T16:33:03.140Z,1773333183.140 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2026-03-12T16:33:03.162Z,1773333183.162 [PAR_Licor] Loaded 2026-03-12T16:33:03.162Z,1773333183.162 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2026-03-12T16:33:03.207Z,1773333183.207 [WetLabsBB2FL] Loaded 2026-03-12T16:33:03.208Z,1773333183.208 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2026-03-12T16:33:03.209Z,1773333183.209 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C5B4E0 2026-03-12T16:33:03.209Z,1773333183.209 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3228 2026-03-12T16:33:03.210Z,1773333183.210 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2026-03-12T16:33:03.216Z,1773333183.216 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2026-03-12T16:33:03.219Z,1773333183.219 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2026-03-12T16:33:03.230Z,1773333183.230 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2026-03-12T16:33:03.231Z,1773333183.231 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C8B4E0 2026-03-12T16:33:03.231Z,1773333183.231 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3229 2026-03-12T16:33:03.236Z,1773333183.236 [Supervisor](INFO): Main Thread ID is 827 2026-03-12T16:33:03.236Z,1773333183.236 [Supervisor](DEBUG): Running supervisor. 2026-03-12T16:33:03.237Z,1773333183.237 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3230 2026-03-12T16:33:03.237Z,1773333183.237 [CommandExec](INFO): Initializing the command executive. 2026-03-12T16:33:03.239Z,1773333183.239 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3231 2026-03-12T16:33:03.241Z,1773333183.241 [controlThread ThreadHandler](INFO): Handler Thread ID is 3232 2026-03-12T16:33:03.241Z,1773333183.241 [controlThread](DEBUG): Initializing ControlThread 2026-03-12T16:33:03.242Z,1773333183.242 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2026-03-12T16:33:03.244Z,1773333183.244 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2026-03-12T16:33:03.244Z,1773333183.244 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2026-03-12T16:33:03.245Z,1773333183.245 [LoopControl](DEBUG): Initialize LoopControlComponent. 2026-03-12T16:33:03.248Z,1773333183.248 [SBIT](INFO): Initialize SBIT Component. 2026-03-12T16:33:03.248Z,1773333183.248 [SBIT](IMPORTANT): git: 2026-03-03 2026-03-12T16:33:03.249Z,1773333183.249 [SBIT](INFO): git hash: f7a2e94ac3e2178d8a146c43cd471ee86a10fe19 2026-03-12T16:33:03.249Z,1773333183.249 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2026-03-12T16:33:03.250Z,1773333183.250 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2026-03-12T16:33:03.251Z,1773333183.251 [SBIT](INFO): Beginning SBIT in 29.000000 seconds. 2026-03-12T16:33:03.252Z,1773333183.252 [IBIT](INFO): Initialize IBIT Component. 2026-03-12T16:33:03.252Z,1773333183.252 [CBIT](DEBUG): Initialize CBIT Component. 2026-03-12T16:33:03.253Z,1773333183.253 [logger ThreadHandler](INFO): Handler Thread ID is 3233 2026-03-12T16:33:03.265Z,1773333183.265 [CBIT](DEBUG): Initialized mux pins. 2026-03-12T16:33:03.265Z,1773333183.265 [CBIT](DEBUG): Initializing the watchdog timer. 2026-03-12T16:33:03.273Z,1773333183.273 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3234 2026-03-12T16:33:03.289Z,1773333183.289 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2026-03-12T16:33:03.289Z,1773333183.289 [CBIT](DEBUG): Initializing heartbeat. 2026-03-12T16:33:03.290Z,1773333183.290 [DATMMP ThreadHandler](INFO): Handler Thread ID is 3235 2026-03-12T16:33:03.291Z,1773333183.291 [DATMMP](INFO): Start 2026-03-12T16:33:03.291Z,1773333183.291 [DATMMP](INFO): Powering up 2026-03-12T16:33:03.291Z,1773333183.291 [DATMMP](DEBUG): Initializing DATMMP. 2026-03-12T16:33:03.294Z,1773333183.294 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3237 2026-03-12T16:33:03.296Z,1773333183.296 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2026-03-12T16:33:03.317Z,1773333183.317 [Onboard ThreadHandler](INFO): Handler Thread ID is 3238 2026-03-12T16:33:03.335Z,1773333183.335 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3239 2026-03-12T16:33:03.356Z,1773333183.356 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3240 2026-03-12T16:33:03.356Z,1773333183.356 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2026-03-12T16:33:03.361Z,1773333183.361 [CBIT](DEBUG): Deactivating emergency mode. 2026-03-12T16:33:03.362Z,1773333183.362 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3242 2026-03-12T16:33:03.365Z,1773333183.365 [WetLabsBB2FL](INFO): Powering up 2026-03-12T16:33:03.366Z,1773333183.366 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3244 2026-03-12T16:33:03.373Z,1773333183.373 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2026-03-12T16:33:03.373Z,1773333183.373 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2026-03-12T16:33:03.374Z,1773333183.374 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2026-03-12T16:33:03.374Z,1773333183.374 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2026-03-12T16:33:03.374Z,1773333183.374 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2026-03-12T16:33:03.374Z,1773333183.374 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2026-03-12T16:33:03.374Z,1773333183.374 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2026-03-12T16:33:03.374Z,1773333183.374 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2026-03-12T16:33:03.401Z,1773333183.401 [CBIT](DEBUG): Backplane powered. 2026-03-12T16:33:03.401Z,1773333183.401 [GFScanner](DEBUG): Initializing GFScanner 2026-03-12T16:33:03.401Z,1773333183.401 [GFScanner](DEBUG): Deactivating GF circuits. 2026-03-12T16:33:03.405Z,1773333183.405 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2026-03-12T16:33:03.406Z,1773333183.406 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2026-03-12T16:33:03.406Z,1773333183.406 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2026-03-12T16:33:03.407Z,1773333183.407 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2026-03-12T16:33:03.407Z,1773333183.407 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2026-03-12T16:33:03.408Z,1773333183.408 [NavChart](DEBUG): Initialize NavChart Navigation. 2026-03-12T16:33:03.408Z,1773333183.408 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2026-03-12T16:33:03.413Z,1773333183.413 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2026-03-12T16:33:03.414Z,1773333183.414 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2026-03-12T16:33:03.415Z,1773333183.415 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2026-03-12T16:33:03.421Z,1773333183.421 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2026-03-12T16:33:03.502Z,1773333183.502 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2026-03-12T16:33:03.506Z,1773333183.506 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2026-03-12T16:33:03.541Z,1773333183.541 [DATMMP](INFO): Starting 2026-03-12T16:33:03.548Z,1773333183.548 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2026-03-12T16:33:03.567Z,1773333183.567 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2026-03-12T16:33:03.567Z,1773333183.567 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2026-03-12T16:33:03.581Z,1773333183.581 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2026-03-12T16:33:03.745Z,1773333183.745 [Radio_Surface](INFO): Powering up 2026-03-12T16:33:03.793Z,1773333183.793 [DATMMP](INFO): Sent Tx Power Config 8 2026-03-12T16:33:03.793Z,1773333183.793 [DATMMP](INFO): Setting local address: 1 2026-03-12T16:33:03.886Z,1773333183.886 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2026-03-12T16:33:03.892Z,1773333183.892 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2026-03-12T16:33:03.893Z,1773333183.893 [Default:A.Wait](DEBUG): Construct Wait. 2026-03-12T16:33:03.895Z,1773333183.895 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2026-03-12T16:33:03.949Z,1773333183.949 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2026-03-12T16:33:03.951Z,1773333183.951 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2026-03-12T16:33:03.969Z,1773333183.969 [Default:E.Execute](DEBUG): Construct Execute. 2026-03-12T16:33:03.972Z,1773333183.972 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2026-03-12T16:33:03.993Z,1773333183.993 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter, 2026-03-12T16:33:04.013Z,1773333184.013 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2026-03-12T16:33:04.073Z,1773333184.073 [Depth_Keller](INFO): Initializing. 2026-03-12T16:33:04.075Z,1773333184.075 [Power24vConverter](INFO): Powering up. 2026-03-12T16:33:04.075Z,1773333184.075 [Sonardyne_Nano](INFO): Initializing. 2026-03-12T16:33:04.117Z,1773333184.117 [DepthRateCalculator](ERROR): Depth measurement is not active 2026-03-12T16:33:04.143Z,1773333184.143 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2026-03-12T16:33:04.149Z,1773333184.149 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2026-03-12T16:33:04.151Z,1773333184.151 [ElevatorServo](DEBUG): Initializing EZServoServo. 2026-03-12T16:33:04.157Z,1773333184.157 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2026-03-12T16:33:04.158Z,1773333184.158 [MassServo](DEBUG): Initializing EZServoServo. 2026-03-12T16:33:04.165Z,1773333184.165 [MassServo](DEBUG): Initializing MassServo. 2026-03-12T16:33:04.166Z,1773333184.166 [RudderServo](DEBUG): Initializing EZServoServo. 2026-03-12T16:33:04.174Z,1773333184.174 [RudderServo](DEBUG): Initializing RudderServo. 2026-03-12T16:33:04.175Z,1773333184.174 [ThrusterHE](DEBUG): Initializing EZServoServo. 2026-03-12T16:33:04.182Z,1773333184.182 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2026-03-12T16:33:05.977Z,1773333185.977 [WetLabsBB2FL](INFO): Powering down 2026-03-12T16:33:06.023Z,1773333186.023 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2026-03-12T16:33:07.312Z,1773333187.312 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2026-03-12T16:33:10.385Z,1773333190.385 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2026-03-12T16:33:31.478Z,1773333211.478 [NAL9602](INFO): Powering up NAL9602 2026-03-12T16:33:32.719Z,1773333212.719 [SBIT](IMPORTANT): Beginning Startup BIT 2026-03-12T16:33:32.727Z,1773333212.727 [GFScanner](IMPORTANT): Beginning ground fault scan 2026-03-12T16:33:37.218Z,1773333217.218 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000216 CHAN A1 (24V): 0.001381 CHAN A2 (12V): 0.000434 CHAN A3 (5V): -0.001633 CHAN B0 (3.3V): 0.000193 CHAN B1 (3.15aV): -0.000214 CHAN B2 (3.15bV): 0.000036 CHAN B3 (GND): -0.000192 OPEN: 0.006801 Full Scale: +/- 1 mA 2026-03-12T16:33:42.387Z,1773333222.387 [NAL9602](INFO): NAL9602 initialized 2026-03-12T16:33:58.549Z,1773333238.549 [NAL9602](INFO): SBD MO Status=0, MOMSN=32538, MT Status=0, MTMSN=0 2026-03-12T16:33:58.549Z,1773333238.549 [NAL9602](INFO): No messages in MT queue 2026-03-12T16:34:02.992Z,1773333242.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163401.00,A,3647.92912,N,12150.85140,W,1.147,0.00,120326,,,A*72 2026-03-12T16:34:02.995Z,1773333242.995 [NAL9602](INFO): GPS fix at 20260312T163401: (36.798819, -121.847523) 2026-03-12T16:34:26.455Z,1773333266.455 [SBIT](IMPORTANT): SBIT PASSED 2026-03-12T16:34:26.455Z,1773333266.455 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2026-03-12T16:34:26.456Z,1773333266.456 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=26 count; 2026-03-12T16:34:26.456Z,1773333266.456 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2026-03-12T16:34:26.456Z,1773333266.456 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2026-03-12T16:34:26.457Z,1773333266.457 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 2.000000 count_per_second; 2026-03-12T16:34:26.458Z,1773333266.458 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 100.000000 count_per_second; 2026-03-12T16:34:26.458Z,1773333266.458 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=112.231750 cubic_centimeter; 2026-03-12T16:34:26.458Z,1773333266.458 [SBIT](IMPORTANT): VerticalControl.massDefault=-2.427157 millimeter; 2026-03-12T16:34:26.458Z,1773333266.458 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2026-03-12T16:34:26.868Z,1773333266.868 [MissionManager](IMPORTANT): Started mission Startup 2026-03-12T16:34:26.868Z,1773333266.868 [Startup] Running Loop=1 2026-03-12T16:34:26.868Z,1773333266.868 [Startup](DEBUG): Aggregate::initialize Startup 2026-03-12T16:34:26.868Z,1773333266.868 [Startup:A.GoToSurface] Running Loop=1 2026-03-12T16:34:26.868Z,1773333266.868 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T16:34:26.869Z,1773333266.869 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-03-12T16:34:26.870Z,1773333266.870 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-03-12T16:34:26.870Z,1773333266.870 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-03-12T16:34:26.870Z,1773333266.870 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-03-12T16:34:26.871Z,1773333266.871 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T16:34:26.871Z,1773333266.871 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T16:34:26.872Z,1773333266.872 [Startup:StartupSatComms] Running Loop=1 2026-03-12T16:34:26.872Z,1773333266.872 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2026-03-12T16:34:26.872Z,1773333266.872 [Startup:StartupSatComms:A] Running Loop=1 2026-03-12T16:34:27.257Z,1773333267.257 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2026-03-12T16:34:28.868Z,1773333268.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163427.00,A,3647.93003,N,12150.85307,W,1.089,298.55,120326,,,A*7F 2026-03-12T16:34:28.870Z,1773333268.870 [NAL9602](INFO): GPS fix at 20260312T163427: (36.798834, -121.847551) 2026-03-12T16:34:28.881Z,1773333268.881 [Startup:StartupSatComms:A] Stopped 2026-03-12T16:34:28.881Z,1773333268.881 [Startup:StartupSatComms:B] Running Loop=1 2026-03-12T16:34:29.298Z,1773333269.298 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-03-12T16:34:53.543Z,1773333293.543 [NAL9602](INFO): SBD MO Status=1, MOMSN=32539, MT Status=0, MTMSN=0 2026-03-12T16:34:53.593Z,1773333293.593 [NAL9602](INFO): Sent 39 bytes from file Logs/20260312T053442/Courier0045.lzma 2026-03-12T16:34:53.593Z,1773333293.593 [NAL9602](INFO): Packets left to send: 0 2026-03-12T16:35:05.312Z,1773333305.312 [NAL9602](INFO): SBD MO Status=1, MOMSN=32540, MT Status=0, MTMSN=0 2026-03-12T16:35:05.369Z,1773333305.369 [NAL9602](INFO): Sent 268 bytes from file Logs/20260312T163252/Courier0000.lzma 2026-03-12T16:35:05.369Z,1773333305.369 [NAL9602](INFO): Packets left to send: 0 2026-03-12T16:35:17.475Z,1773333317.475 [NAL9602](INFO): SBD MO Status=1, MOMSN=32541, MT Status=0, MTMSN=0 2026-03-12T16:35:17.525Z,1773333317.525 [NAL9602](INFO): Sent 248 bytes from file Logs/20260312T053442/Express0046.lzma 2026-03-12T16:35:17.525Z,1773333317.525 [NAL9602](INFO): Packets left to send: 0 2026-03-12T16:35:29.261Z,1773333329.261 [Startup:StartupSatComms:B](INFO): Timed out from 2026-03-12T16:34:28.9Z 2026-03-12T16:35:29.261Z,1773333329.261 [Startup:StartupSatComms:B] Stopped 2026-03-12T16:35:29.261Z,1773333329.261 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2026-03-12T16:35:29.261Z,1773333329.261 [Startup:StartupSatComms] Stopped 2026-03-12T16:35:29.261Z,1773333329.261 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2026-03-12T16:35:29.262Z,1773333329.262 [Startup](INFO): Completed Startup 2026-03-12T16:35:29.262Z,1773333329.262 [MissionManager](INFO): Startup is completed. 2026-03-12T16:35:29.262Z,1773333329.262 [MissionManager](INFO): Uninitializing Mission Startup 2026-03-12T16:35:29.263Z,1773333329.263 [Startup] Stopped 2026-03-12T16:35:29.263Z,1773333329.263 [Startup](DEBUG): Aggregate::uninitialize Startup 2026-03-12T16:35:29.263Z,1773333329.263 [Startup:A.GoToSurface] Stopped 2026-03-12T16:35:29.263Z,1773333329.263 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T16:35:29.668Z,1773333329.668 [MissionManager](IMPORTANT): Started mission Default 2026-03-12T16:35:29.669Z,1773333329.669 [Default] Running Loop=1 2026-03-12T16:35:29.669Z,1773333329.669 [Default](DEBUG): Aggregate::initialize Default 2026-03-12T16:35:29.669Z,1773333329.669 [Default:B.GoToSurface] Running Loop=1 2026-03-12T16:35:29.669Z,1773333329.669 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T16:35:29.669Z,1773333329.669 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-03-12T16:35:29.670Z,1773333329.670 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-03-12T16:35:29.670Z,1773333329.670 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-03-12T16:35:29.670Z,1773333329.670 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-03-12T16:35:29.670Z,1773333329.670 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T16:35:29.671Z,1773333329.671 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T16:35:29.671Z,1773333329.671 [Default:A.Wait] Running Loop=1 2026-03-12T16:35:29.671Z,1773333329.671 [Default:A.Wait](DEBUG): Initialize Wait Component. 2026-03-12T16:35:31.268Z,1773333331.268 [NAL9602](INFO): SBD MO Status=1, MOMSN=32542, MT Status=0, MTMSN=0 2026-03-12T16:35:31.322Z,1773333331.322 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T163252/Express0001.lzma 2026-03-12T16:35:31.322Z,1773333331.322 [NAL9602](INFO): Packets left to send: 3 2026-03-12T16:35:42.995Z,1773333342.995 [Default:A.Wait](INFO): Done Waiting. 2026-03-12T16:35:42.996Z,1773333342.996 [Default:A.Wait] Stopped 2026-03-12T16:35:42.996Z,1773333342.996 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T16:35:43.409Z,1773333343.409 [Default:CheckIn] Running Loop=1 2026-03-12T16:35:43.409Z,1773333343.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-03-12T16:35:43.409Z,1773333343.409 [Default:CheckIn:Read_GPS] Running Loop=1 2026-03-12T16:35:43.820Z,1773333343.820 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-03-12T16:35:45.412Z,1773333345.412 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163543.00,A,3647.93248,N,12150.85661,W,1.011,2.01,120326,,,A*75 2026-03-12T16:35:45.414Z,1773333345.414 [NAL9602](INFO): GPS fix at 20260312T163543: (36.798875, -121.847610) 2026-03-12T16:35:45.444Z,1773333345.444 [Default:CheckIn:Read_GPS] Stopped 2026-03-12T16:35:45.444Z,1773333345.444 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-03-12T16:35:45.855Z,1773333345.855 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-03-12T16:36:02.020Z,1773333362.020 [NAL9602](INFO): SBD MO Status=1, MOMSN=32543, MT Status=0, MTMSN=0 2026-03-12T16:36:02.069Z,1773333362.069 [NAL9602](INFO): Sent 73 bytes from file Logs/20260312T163252/Courier0004.lzma 2026-03-12T16:36:02.069Z,1773333362.069 [NAL9602](INFO): Packets left to send: 0 2026-03-12T16:36:23.543Z,1773333383.543 [NAL9602](INFO): SBD MO Status=2, MOMSN=32544, MT Status=2, MTMSN=0 2026-03-12T16:36:23.544Z,1773333383.544 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-12T16:36:41.373Z,1773333401.373 [NAL9602](INFO): SBD MO Status=2, MOMSN=32544, MT Status=2, MTMSN=0 2026-03-12T16:36:41.373Z,1773333401.373 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-12T16:36:49.067Z,1773333409.067 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004248 2026-03-12T16:36:55.786Z,1773333415.786 [NAL9602](INFO): SBD MO Status=1, MOMSN=32544, MT Status=0, MTMSN=0 2026-03-12T16:36:55.832Z,1773333415.832 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T163252/Express0001.lzma 2026-03-12T16:36:55.832Z,1773333415.832 [NAL9602](INFO): Packets left to send: 2 2026-03-12T16:36:55.991Z,1773333415.991 [Default:CheckIn:Read_Iridium] Stopped 2026-03-12T16:36:55.992Z,1773333415.992 [Default:CheckIn:C.Wait] Running Loop=1 2026-03-12T16:36:55.992Z,1773333415.992 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-03-12T16:36:59.540Z,1773333419.540 [DataOverHttps](INFO): Sending 1015 bytes from file Logs/20260312T163252/Express0001.lzma 2026-03-12T16:37:00.541Z,1773333420.541 [DataOverHttps](INFO): Moved sent file to Logs/20260312T163252/Express0001.lzma.bak 2026-03-12T16:37:00.541Z,1773333420.541 [DataOverHttps](INFO): SBD MOMSN=26722434 2026-03-12T16:37:24.112Z,1773333444.112 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2026-03-12T16:37:26.476Z,1773333446.476 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T16:39:16.857Z,1773333556.857 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T163915 2026-03-12T16:39:24.351Z,1773333564.351 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.NeedCommsTime 10 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;run 2026-03-12T16:39:24.375Z,1773333564.375 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2026-03-12T16:39:24.377Z,1773333564.377 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2026-03-12T16:39:24.377Z,1773333564.377 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2026-03-12T16:39:24.382Z,1773333564.382 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2026-03-12T16:39:25.581Z,1773333565.581 [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 2026-03-12T16:39:25.595Z,1773333565.595 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2026-03-12T16:39:25.598Z,1773333565.598 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2026-03-12T16:39:25.615Z,1773333565.615 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2026-03-12T16:39:25.622Z,1773333565.622 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2026-03-12T16:39:25.633Z,1773333565.633 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2026-03-12T16:39:25.669Z,1773333565.669 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2026-03-12T16:39:25.676Z,1773333565.676 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2026-03-12T16:39:25.679Z,1773333565.679 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2026-03-12T16:39:25.698Z,1773333565.698 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2026-03-12T16:39:25.705Z,1773333565.705 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2026-03-12T16:39:25.715Z,1773333565.715 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2026-03-12T16:39:25.722Z,1773333565.722 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2026-03-12T16:39:25.726Z,1773333565.726 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2026-03-12T16:39:25.741Z,1773333565.741 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2026-03-12T16:39:25.747Z,1773333565.747 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2026-03-12T16:39:25.758Z,1773333565.758 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2026-03-12T16:39:25.765Z,1773333565.765 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2026-03-12T16:39:25.768Z,1773333565.768 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2026-03-12T16:39:25.780Z,1773333565.780 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2026-03-12T16:39:25.783Z,1773333565.783 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2026-03-12T16:39:25.784Z,1773333565.784 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2026-03-12T16:39:25.784Z,1773333565.784 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2026-03-12T16:39:25.785Z,1773333565.785 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2026-03-12T16:39:26.228Z,1773333566.228 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1500 7 30 0 0 0 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2026-03-12T16:39:26.234Z,1773333566.234 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2026-03-12T16:39:26.237Z,1773333566.237 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2026-03-12T16:39:26.240Z,1773333566.240 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2026-03-12T16:39:26.243Z,1773333566.243 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2026-03-12T16:39:26.246Z,1773333566.246 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2026-03-12T16:39:26.249Z,1773333566.249 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1500.000000 s 2026-03-12T16:39:26.252Z,1773333566.252 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2026-03-12T16:39:26.255Z,1773333566.255 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2026-03-12T16:39:26.258Z,1773333566.258 [MissionManager](INFO): DefineArg keepstation:NeedComms.DoingCommsMode = 0.000000 count 2026-03-12T16:39:26.261Z,1773333566.261 [MissionManager](INFO): DefineArg keepstation:NeedComms.RestoreMode = 0.000000 count 2026-03-12T16:39:26.298Z,1773333566.298 [MissionManager](INFO): DefineOutput keepstation:NeedComms.IsActive = 0 bool 2026-03-12T16:39:26.301Z,1773333566.301 [MissionManager](INFO): DefineOutput keepstation:NeedComms.RequiredDiveMode = 0.000000 count 2026-03-12T16:39:26.323Z,1773333566.323 [keepstation:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface. 2026-03-12T16:39:26.374Z,1773333566.374 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2026-03-12T16:39:26.374Z,1773333566.374 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2026-03-12T16:39:26.375Z,1773333566.375 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2026-03-12T16:39:26.548Z,1773333566.548 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2026-03-12T16:39:26.554Z,1773333566.554 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2026-03-12T16:39:26.565Z,1773333566.565 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2026-03-12T16:39:26.572Z,1773333566.572 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2026-03-12T16:39:26.574Z,1773333566.574 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2026-03-12T16:39:26.580Z,1773333566.580 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2026-03-12T16:39:26.596Z,1773333566.596 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2026-03-12T16:39:26.619Z,1773333566.619 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2026-03-12T16:39:26.641Z,1773333566.641 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2026-03-12T16:39:26.641Z,1773333566.641 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2026-03-12T16:39:26.642Z,1773333566.642 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2026-03-12T16:39:26.681Z,1773333566.681 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2026-03-12T16:39:26.689Z,1773333566.689 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2026-03-12T16:39:26.696Z,1773333566.696 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2026-03-12T16:39:26.699Z,1773333566.699 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2026-03-12T16:39:26.703Z,1773333566.703 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2026-03-12T16:39:26.705Z,1773333566.705 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2026-03-12T16:39:26.953Z,1773333566.953 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2026-03-12T16:39:26.966Z,1773333566.966 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2026-03-12T16:39:26.969Z,1773333566.969 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2026-03-12T16:39:26.980Z,1773333566.980 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2026-03-12T16:39:26.983Z,1773333566.983 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2026-03-12T16:39:26.998Z,1773333566.998 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool 2026-03-12T16:39:27.013Z,1773333567.013 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2026-03-12T16:39:27.015Z,1773333567.015 [keepstation:H.Pitch](DEBUG): Construct. 2026-03-12T16:39:27.095Z,1773333567.095 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2026-03-12T16:39:27.098Z,1773333567.098 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2026-03-12T16:39:27.116Z,1773333567.116 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2026-03-12T16:39:27.151Z,1773333567.151 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2026-03-12T16:39:27.164Z,1773333567.164 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2026-03-12T16:39:27.169Z,1773333567.169 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2026-03-12T16:39:27.187Z,1773333567.187 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2026-03-12T16:39:27.197Z,1773333567.197 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2026-03-12T16:39:27.223Z,1773333567.223 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2026-03-12T16:39:27.284Z,1773333567.284 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2026-03-12T16:39:27.329Z,1773333567.329 [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 HandleNan { run in sequence break if ( not isNaN ( Latitude ) and not isNaN ( Longitude ) ) # If both lat and lon are nan, we are implicitly on station and should skip initial transit, so force OnStation to true aggregate SetOnStation { run in sequence break if ( not isNaN ( Latitude ) or not isNaN ( Longitude ) ) assign in sequence OnStation = true } # The "assigns" here mean we should only ever run these aggregates once # An initial NaN lat/lon should be set here during the initial surface NeedComms call # Use the most recent fix -- if there isn't one, the keepstation behavior will use Universal:lat/lon aggregate SetLat { run in sequence break if ( not isNaN ( Latitude ) ) assign in sequence Latitude = Universal:latitude_fix } aggregate SetLon { run in sequence break if ( not isNaN ( Longitude ) ) assign in sequence Longitude = Universal:longitude_fix } } 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 } } } 2026-03-12T16:39:27.330Z,1773333567.330 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2026-03-12T16:39:35.234Z,1773333575.234 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 10 minute 2026-03-12T16:39:35.242Z,1773333575.242 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree 2026-03-12T16:39:35.243Z,1773333575.243 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree 2026-03-12T16:39:35.244Z,1773333575.244 [CommandExec](IMPORTANT): got command run 2026-03-12T16:39:35.246Z,1773333575.246 [CommandExec](IMPORTANT): Running 2026-03-12T16:39:35.368Z,1773333575.368 [Default] Stopped 2026-03-12T16:39:35.368Z,1773333575.368 [Default](DEBUG): Aggregate::uninitialize Default 2026-03-12T16:39:35.368Z,1773333575.368 [Default:B.GoToSurface] Stopped 2026-03-12T16:39:35.368Z,1773333575.368 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T16:39:35.368Z,1773333575.368 [Default:CheckIn] Stopped 2026-03-12T16:39:35.368Z,1773333575.368 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-03-12T16:39:35.368Z,1773333575.368 [Default:CheckIn:C.Wait] Stopped 2026-03-12T16:39:35.368Z,1773333575.368 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T16:39:35.368Z,1773333575.368 [MissionManager](IMPORTANT): Started mission keepstation 2026-03-12T16:39:35.369Z,1773333575.369 [keepstation] Running Loop=1 2026-03-12T16:39:35.369Z,1773333575.369 [keepstation](DEBUG): Aggregate::initialize keepstation 2026-03-12T16:39:35.369Z,1773333575.369 [keepstation:StandardEnvelopes] Running Loop=1 2026-03-12T16:39:35.369Z,1773333575.369 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2026-03-12T16:39:35.369Z,1773333575.369 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2026-03-12T16:39:35.369Z,1773333575.369 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-03-12T16:39:35.369Z,1773333575.369 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:BackseatDriver] Running Loop=1 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:PowerOnly] Running Loop=1 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2026-03-12T16:39:35.370Z,1773333575.370 [keepstation:PowerOnly:A] Running Loop=1 2026-03-12T16:39:35.371Z,1773333575.371 [keepstation:PowerOnly:B] Running Loop=1 2026-03-12T16:39:35.371Z,1773333575.371 [keepstation:PowerOnly:C] Running Loop=1 2026-03-12T16:39:35.371Z,1773333575.371 [keepstation:PowerOnly:D] Running Loop=1 2026-03-12T16:39:35.371Z,1773333575.371 [keepstation:PowerOnly:E.Wait] Running Loop=1 2026-03-12T16:39:35.371Z,1773333575.371 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2026-03-12T16:39:35.371Z,1773333575.371 [keepstation:H.Pitch] Running Loop=1 2026-03-12T16:39:35.371Z,1773333575.371 [keepstation:H.Pitch](DEBUG): Initialize. 2026-03-12T16:39:35.372Z,1773333575.372 [keepstation:B] Running Loop=1 2026-03-12T16:39:35.372Z,1773333575.372 [keepstation:H.Pitch] Running Loop=1 2026-03-12T16:39:35.373Z,1773333575.373 [keepstation:PowerOnly] Running Loop=1 2026-03-12T16:39:35.373Z,1773333575.373 [keepstation:PowerOnly:D] Stopped 2026-03-12T16:39:35.373Z,1773333575.373 [keepstation:PowerOnly:C] Stopped 2026-03-12T16:39:35.373Z,1773333575.373 [keepstation:PowerOnly:B] Stopped 2026-03-12T16:39:35.373Z,1773333575.373 [keepstation:PowerOnly:A] Stopped 2026-03-12T16:39:35.373Z,1773333575.373 [keepstation:BackseatDriver] Running Loop=1 2026-03-12T16:39:35.374Z,1773333575.374 [keepstation:StandardEnvelopes] Running Loop=1 2026-03-12T16:39:35.374Z,1773333575.374 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2026-03-12T16:39:35.374Z,1773333575.374 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2026-03-12T16:39:35.375Z,1773333575.375 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2026-03-12T16:39:35.376Z,1773333575.376 [keepstation:B] Stopped 2026-03-12T16:39:35.376Z,1773333575.376 [keepstation:D] Running Loop=1 2026-03-12T16:39:35.766Z,1773333575.766 [keepstation:D] Stopped 2026-03-12T16:39:35.766Z,1773333575.766 [keepstation:E] Running Loop=1 2026-03-12T16:39:36.168Z,1773333576.168 [keepstation:E] Stopped 2026-03-12T16:39:36.168Z,1773333576.168 [keepstation:StartingMission] Running Loop=1 2026-03-12T16:39:36.168Z,1773333576.168 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2026-03-12T16:39:36.580Z,1773333576.580 [keepstation:SurfaceComms] Running Loop=1 2026-03-12T16:39:36.581Z,1773333576.581 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-12T16:39:36.581Z,1773333576.581 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-12T16:39:36.581Z,1773333576.581 [keepstation:SurfaceComms:A] Stopped 2026-03-12T16:39:36.582Z,1773333576.582 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-12T16:39:36.582Z,1773333576.582 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-12T16:39:36.988Z,1773333576.988 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2026-03-12T16:39:36.989Z,1773333576.989 [keepstation:NeedComms] Running Loop=1 2026-03-12T16:39:36.989Z,1773333576.989 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T16:39:36.989Z,1773333576.989 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T16:39:36.989Z,1773333576.989 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T16:39:36.989Z,1773333576.989 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T16:39:36.990Z,1773333576.990 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T16:39:36.990Z,1773333576.990 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T16:39:36.990Z,1773333576.990 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T16:39:36.991Z,1773333576.991 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T16:39:36.991Z,1773333576.991 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T16:39:36.995Z,1773333576.995 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T16:39:36.996Z,1773333576.996 [keepstation:NeedComms:A](INFO): last time_fix was: 1773333343.000000 second since 1970/01/01T00:00:00Z 2026-03-12T16:39:36.997Z,1773333576.997 [keepstation:NeedComms:A] Stopped 2026-03-12T16:39:36.997Z,1773333576.997 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T16:39:36.997Z,1773333576.997 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T16:39:36.997Z,1773333576.997 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T16:39:37.386Z,1773333577.386 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T16:39:37.386Z,1773333577.386 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T16:39:37.790Z,1773333577.790 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T16:39:37.790Z,1773333577.790 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T16:39:37.791Z,1773333577.791 [keepstation:NeedComms:setActive] Stopped 2026-03-12T16:39:37.791Z,1773333577.791 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T16:39:38.193Z,1773333578.193 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T16:39:38.608Z,1773333578.608 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-03-12T16:39:40.197Z,1773333580.197 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163939.00,A,3647.94338,N,12150.86312,W,0.330,351.17,120326,,,D*71 2026-03-12T16:39:40.200Z,1773333580.200 [NAL9602](INFO): GPS fix at 20260312T163939: (36.799056, -121.847719) 2026-03-12T16:39:40.234Z,1773333580.234 [keepstation:NeedComms:D] Stopped 2026-03-12T16:39:40.234Z,1773333580.234 [keepstation:NeedComms:E] Running Loop=1 2026-03-12T16:39:40.641Z,1773333580.641 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-03-12T16:39:48.035Z,1773333588.035 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260312T163252/Courier0007.lzma 2026-03-12T16:39:49.037Z,1773333589.037 [DataOverHttps](INFO): Moved sent file to Logs/20260312T163252/Courier0007.lzma.bak 2026-03-12T16:39:49.037Z,1773333589.037 [DataOverHttps](INFO): SBD MOMSN=26722488 2026-03-12T16:39:51.113Z,1773333591.113 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2026-03-12T16:39:51.114Z,1773333591.114 [BPC1](ERROR): Battery stick #30 (s/n: 015F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.114Z,1773333591.114 [BPC1](ERROR): Battery stick #37 (s/n: 0181) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.115Z,1773333591.115 [BPC1](ERROR): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.116Z,1773333591.116 [BPC1](ERROR): Battery stick #39 (s/n: 0189) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.117Z,1773333591.117 [BPC1](ERROR): Battery stick #40 (s/n: 01A4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.117Z,1773333591.117 [BPC1](ERROR): Battery stick #44 (s/n: 01BB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.118Z,1773333591.118 [BPC1](ERROR): Battery stick #50 (s/n: 019C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.119Z,1773333591.119 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.119Z,1773333591.119 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.120Z,1773333591.120 [BPC1](ERROR): Battery stick #53 (s/n: 01EE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.121Z,1773333591.121 [BPC1](ERROR): Battery stick #56 (s/n: 0183) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.122Z,1773333591.122 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T16:39:51.122Z,1773333591.122 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x5AD0. 2026-03-12T16:39:51.123Z,1773333591.123 [BPC1](INFO): Calculating totals. Valid battery stick count: 22. Valid reserve battery stick count: 2. 2026-03-12T16:39:51.126Z,1773333591.126 [BPC1](INFO): Received data from all battery sticks. 2026-03-12T16:40:01.610Z,1773333601.610 [NAL9602](INFO): SBD MO Status=0, MOMSN=32545, MT Status=0, MTMSN=0 2026-03-12T16:40:01.610Z,1773333601.610 [NAL9602](INFO): No messages in MT queue 2026-03-12T16:40:04.799Z,1773333604.799 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20260312T163252/Express0005.lzma 2026-03-12T16:40:05.802Z,1773333605.802 [DataOverHttps](INFO): Moved sent file to Logs/20260312T163252/Express0005.lzma.bak 2026-03-12T16:40:05.802Z,1773333605.802 [DataOverHttps](INFO): SBD MOMSN=26722491 2026-03-12T16:40:21.698Z,1773333621.698 [DataOverHttps](INFO): Sending 364 bytes from file Logs/20260312T163252/Express0008.lzma 2026-03-12T16:40:22.697Z,1773333622.697 [DataOverHttps](INFO): Moved sent file to Logs/20260312T163252/Express0008.lzma.bak 2026-03-12T16:40:22.697Z,1773333622.697 [DataOverHttps](INFO): SBD MOMSN=26722494 2026-03-12T16:40:32.312Z,1773333632.312 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T16:40:38.519Z,1773333638.519 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20260312T163252/Express0011.lzma 2026-03-12T16:40:39.521Z,1773333639.521 [DataOverHttps](INFO): Moved sent file to Logs/20260312T163252/Express0011.lzma.bak 2026-03-12T16:40:39.521Z,1773333639.521 [DataOverHttps](INFO): SBD MOMSN=26722505 2026-03-12T16:40:40.818Z,1773333640.818 [keepstation:NeedComms:E] Stopped 2026-03-12T16:40:40.818Z,1773333640.818 [keepstation:NeedComms:F] Running Loop=1 2026-03-12T16:40:41.223Z,1773333641.223 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-03-12T16:40:42.822Z,1773333642.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164042.00,A,3647.94550,N,12150.86579,W,0.544,0.27,120326,,,D*71 2026-03-12T16:40:42.835Z,1773333642.835 [NAL9602](INFO): GPS fix at 20260312T164042: (36.799092, -121.847763) 2026-03-12T16:40:42.866Z,1773333642.866 [keepstation:NeedComms:F] Stopped 2026-03-12T16:40:42.866Z,1773333642.866 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2026-03-12T16:40:42.866Z,1773333642.866 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2026-03-12T16:40:42.866Z,1773333642.866 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2026-03-12T16:40:43.270Z,1773333643.270 [keepstation:NeedComms:setRestoreMode:A] Stopped 2026-03-12T16:40:43.270Z,1773333643.270 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2026-03-12T16:40:43.270Z,1773333643.270 [keepstation:NeedComms:setRestoreMode] Stopped 2026-03-12T16:40:43.270Z,1773333643.270 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2026-03-12T16:40:43.270Z,1773333643.270 [keepstation:NeedComms:setInactive] Running Loop=1 2026-03-12T16:40:43.271Z,1773333643.271 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2026-03-12T16:40:43.271Z,1773333643.271 [keepstation:NeedComms:setInactive:A] Running Loop=1 2026-03-12T16:40:43.641Z,1773333643.641 [keepstation:NeedComms:setInactive:A] Stopped 2026-03-12T16:40:43.642Z,1773333643.642 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2026-03-12T16:40:43.642Z,1773333643.642 [keepstation:NeedComms:setInactive] Stopped 2026-03-12T16:40:43.642Z,1773333643.642 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2026-03-12T16:40:43.642Z,1773333643.642 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2026-03-12T16:40:43.642Z,1773333643.642 [keepstation:NeedComms] Stopped 2026-03-12T16:40:43.643Z,1773333643.643 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T16:40:43.643Z,1773333643.643 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T16:40:43.643Z,1773333643.643 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T16:40:44.053Z,1773333644.053 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2026-03-12T16:40:44.053Z,1773333644.053 [keepstation:SurfaceComms:B] Stopped 2026-03-12T16:40:44.054Z,1773333644.054 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-12T16:40:44.054Z,1773333644.054 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2026-03-12T16:40:44.054Z,1773333644.054 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2026-03-12T16:40:44.054Z,1773333644.054 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2026-03-12T16:40:44.054Z,1773333644.054 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T16:40:44.054Z,1773333644.054 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2026-03-12T16:40:44.464Z,1773333644.464 [keepstation:SurfaceComms:HandleNan] Stopped 2026-03-12T16:40:44.464Z,1773333644.464 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2026-03-12T16:40:44.464Z,1773333644.464 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2026-03-12T16:40:44.464Z,1773333644.464 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T16:40:44.464Z,1773333644.464 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2026-03-12T16:40:44.464Z,1773333644.464 [keepstation:SurfaceComms:setTransit] Running Loop=1 2026-03-12T16:40:44.465Z,1773333644.465 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2026-03-12T16:40:44.465Z,1773333644.465 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2026-03-12T16:40:44.878Z,1773333644.878 [keepstation:SurfaceComms:setTransit:A] Stopped 2026-03-12T16:40:44.878Z,1773333644.878 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit 2026-03-12T16:40:44.879Z,1773333644.879 [keepstation:SurfaceComms:setTransit] Stopped 2026-03-12T16:40:44.879Z,1773333644.879 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2026-03-12T16:40:44.879Z,1773333644.879 [keepstation:SurfaceComms:setStation] Running Loop=1 2026-03-12T16:40:44.879Z,1773333644.879 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2026-03-12T16:40:44.879Z,1773333644.879 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2026-03-12T16:40:45.290Z,1773333645.290 [keepstation:SurfaceComms:setStation] Stopped 2026-03-12T16:40:45.290Z,1773333645.290 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2026-03-12T16:40:45.290Z,1773333645.290 [keepstation:SurfaceComms:setStation:A] Stopped 2026-03-12T16:40:45.290Z,1773333645.290 [keepstation:SurfaceComms:setSink] Running Loop=1 2026-03-12T16:40:45.290Z,1773333645.290 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2026-03-12T16:40:45.290Z,1773333645.290 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2026-03-12T16:40:45.665Z,1773333645.665 [keepstation:SurfaceComms:setSink] Stopped 2026-03-12T16:40:45.665Z,1773333645.665 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2026-03-12T16:40:45.665Z,1773333645.665 [keepstation:SurfaceComms:setSink:A] Stopped 2026-03-12T16:40:45.665Z,1773333645.665 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2026-03-12T16:40:45.665Z,1773333645.665 [keepstation:SurfaceComms] Stopped 2026-03-12T16:40:45.665Z,1773333645.665 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-12T16:40:46.073Z,1773333646.073 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2026-03-12T16:40:46.073Z,1773333646.073 [keepstation:StartingMission] Stopped 2026-03-12T16:40:46.073Z,1773333646.073 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2026-03-12T16:40:46.073Z,1773333646.073 [keepstation:TransitToStation] Running Loop=1 2026-03-12T16:40:46.073Z,1773333646.073 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2026-03-12T16:40:46.073Z,1773333646.073 [keepstation:TransitToStation:Dive] Running Loop=1 2026-03-12T16:40:46.073Z,1773333646.073 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2026-03-12T16:40:46.073Z,1773333646.073 [keepstation:TransitToStation:Dive:A] Running Loop=1 2026-03-12T16:40:46.074Z,1773333646.074 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2026-03-12T16:40:46.074Z,1773333646.074 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2026-03-12T16:40:46.074Z,1773333646.074 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2026-03-12T16:40:46.080Z,1773333646.080 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2026-03-12T16:40:46.465Z,1773333646.465 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-03-12T16:40:46.465Z,1773333646.465 [keepstation:TransitToStation:Dive] Running Loop=1 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd] Running Loop=1 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-03-12T16:40:46.466Z,1773333646.466 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-03-12T16:40:46.467Z,1773333646.467 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-03-12T16:40:46.467Z,1773333646.467 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2026-03-12T16:40:46.467Z,1773333646.467 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2026-03-12T16:40:46.467Z,1773333646.467 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2026-03-12T16:40:46.467Z,1773333646.467 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2026-03-12T16:40:46.467Z,1773333646.467 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2026-03-12T16:40:46.467Z,1773333646.467 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2026-03-12T16:40:46.468Z,1773333646.468 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T16:40:46.469Z,1773333646.469 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2026-03-12T16:40:46.469Z,1773333646.469 [keepstation:DiveCmd:StationKeep] Stopped 2026-03-12T16:40:46.469Z,1773333646.469 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2026-03-12T16:40:46.469Z,1773333646.469 [keepstation:DiveCmd:StationKeep:A] Stopped 2026-03-12T16:40:46.470Z,1773333646.470 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2026-03-12T16:40:46.470Z,1773333646.470 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2026-03-12T16:40:46.470Z,1773333646.470 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2026-03-12T16:40:46.470Z,1773333646.470 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2026-03-12T16:40:46.470Z,1773333646.470 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2026-03-12T16:40:46.470Z,1773333646.470 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2026-03-12T16:40:46.471Z,1773333646.471 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2026-03-12T16:40:46.471Z,1773333646.471 [keepstation:DiveCmd:SurfaceSink] Stopped 2026-03-12T16:40:46.471Z,1773333646.471 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2026-03-12T16:40:46.488Z,1773333646.488 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2026-03-12T16:40:46.488Z,1773333646.488 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2026-03-12T16:40:46.488Z,1773333646.488 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-03-12T16:40:46.488Z,1773333646.488 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-03-12T16:40:46.488Z,1773333646.488 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-03-12T16:40:46.488Z,1773333646.488 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-03-12T16:40:46.488Z,1773333646.488 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2026-03-12T16:40:46.504Z,1773333646.504 [HorizontalControl](DEBUG): kpHeading = 0.400000 2026-03-12T16:40:46.505Z,1773333646.505 [HorizontalControl](DEBUG): kiHeading = 0.001000 2026-03-12T16:40:46.505Z,1773333646.505 [HorizontalControl](DEBUG): kdHeading = 0.050000 2026-03-12T16:40:56.266Z,1773333656.266 [NAL9602](INFO): SBD MO Status=0, MOMSN=32546, MT Status=0, MTMSN=0 2026-03-12T16:40:56.266Z,1773333656.266 [NAL9602](INFO): No messages in MT queue 2026-03-12T16:41:26.686Z,1773333686.686 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T16:42:11.496Z,1773333731.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:42:42.620Z,1773333762.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:42:44.971Z,1773333764.971 [Radio_Surface](INFO): Powering down 2026-03-12T16:43:13.756Z,1773333793.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:43:14.790Z,1773333794.790 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-03-12T16:44:20.048Z,1773333860.048 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.761093 2026-03-12T16:47:58.344Z,1773334078.344 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000 2026-03-12T16:47:58.344Z,1773334078.344 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2026-03-12T16:47:58.344Z,1773334078.344 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-03-12T16:47:58.344Z,1773334078.344 [keepstation:TransitToStation:C] Running Loop=1 2026-03-12T16:47:58.752Z,1773334078.752 [keepstation:TransitToStation:C] Stopped 2026-03-12T16:47:58.752Z,1773334078.752 [keepstation:TransitToStation:D] Running Loop=1 2026-03-12T16:47:58.753Z,1773334078.753 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T16:47:58.753Z,1773334078.753 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2026-03-12T16:47:58.753Z,1773334078.753 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T16:47:58.753Z,1773334078.753 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T16:47:58.753Z,1773334078.753 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2026-03-12T16:47:58.753Z,1773334078.753 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T16:47:58.753Z,1773334078.753 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2026-03-12T16:47:58.753Z,1773334078.753 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T16:47:58.754Z,1773334078.754 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T16:47:58.754Z,1773334078.754 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T16:47:58.755Z,1773334078.755 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T16:47:58.755Z,1773334078.755 [keepstation:DiveCmd:TransitToStation] Stopped 2026-03-12T16:47:58.755Z,1773334078.755 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2026-03-12T16:47:58.755Z,1773334078.755 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2026-03-12T16:47:58.755Z,1773334078.755 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2026-03-12T16:47:58.755Z,1773334078.755 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2026-03-12T16:47:58.756Z,1773334078.756 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-03-12T16:47:58.756Z,1773334078.756 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2026-03-12T16:47:58.756Z,1773334078.756 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2026-03-12T16:47:58.756Z,1773334078.756 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2026-03-12T16:47:59.150Z,1773334079.150 [keepstation:TransitToStation:D] Stopped 2026-03-12T16:47:59.151Z,1773334079.151 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2026-03-12T16:47:59.151Z,1773334079.151 [keepstation:TransitToStation] Stopped 2026-03-12T16:47:59.151Z,1773334079.151 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2026-03-12T16:47:59.151Z,1773334079.151 [keepstation:TransitToStation:Dive] Stopped 2026-03-12T16:47:59.167Z,1773334079.167 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2026-03-12T16:47:59.167Z,1773334079.167 [keepstation:TransitToStation:Dive:A] Stopped 2026-03-12T16:47:59.167Z,1773334079.167 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2026-03-12T16:47:59.168Z,1773334079.168 [keepstation:OnStation] Running Loop=1 2026-03-12T16:47:59.168Z,1773334079.168 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2026-03-12T16:47:59.558Z,1773334079.558 [keepstation:NeedComms] Running Loop=1 2026-03-12T16:47:59.558Z,1773334079.558 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T16:47:59.558Z,1773334079.558 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T16:47:59.558Z,1773334079.558 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T16:47:59.559Z,1773334079.559 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T16:47:59.559Z,1773334079.559 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T16:47:59.560Z,1773334079.560 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T16:47:59.560Z,1773334079.560 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T16:47:59.560Z,1773334079.560 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T16:47:59.561Z,1773334079.561 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T16:47:59.561Z,1773334079.561 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T16:47:59.563Z,1773334079.563 [keepstation:NeedComms:A](INFO): last time_fix was: 1773333642.000000 second since 1970/01/01T00:00:00Z 2026-03-12T16:47:59.563Z,1773334079.563 [keepstation:NeedComms:A] Stopped 2026-03-12T16:47:59.563Z,1773334079.563 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T16:47:59.563Z,1773334079.563 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T16:47:59.563Z,1773334079.563 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T16:47:59.964Z,1773334079.964 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T16:47:59.964Z,1773334079.964 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T16:48:00.372Z,1773334080.372 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T16:48:00.372Z,1773334080.372 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T16:48:00.372Z,1773334080.372 [keepstation:NeedComms:setActive] Stopped 2026-03-12T16:48:00.372Z,1773334080.372 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T16:49:06.147Z,1773334146.147 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T16:49:06.800Z,1773334146.800 [Radio_Surface](INFO): Powering up 2026-03-12T16:49:12.284Z,1773334152.284 [DataOverHttps](INFO): Radio surface powered ON. 2026-03-12T16:49:30.769Z,1773334170.769 [NAL9602](INFO): SBD MO Status=2, MOMSN=32547, MT Status=2, MTMSN=0 2026-03-12T16:49:30.769Z,1773334170.769 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-12T16:49:42.404Z,1773334182.404 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:50:13.536Z,1773334213.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:50:19.658Z,1773334219.658 [NAL9602](INFO): SBD MO Status=0, MOMSN=32547, MT Status=0, MTMSN=0 2026-03-12T16:50:19.659Z,1773334219.659 [NAL9602](INFO): No messages in MT queue 2026-03-12T16:50:20.874Z,1773334220.874 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165020.00,A,3647.82251,N,12150.82192,W,1.108,293.53,120326,,,D*76 2026-03-12T16:50:20.876Z,1773334220.876 [NAL9602](INFO): GPS fix at 20260312T165020: (36.797042, -121.847032) 2026-03-12T16:50:20.891Z,1773334220.891 [keepstation:NeedComms:D] Stopped 2026-03-12T16:50:20.891Z,1773334220.891 [keepstation:NeedComms:E] Running Loop=1 2026-03-12T16:50:36.294Z,1773334236.294 [NAL9602](INFO): SBD MO Status=1, MOMSN=32548, MT Status=0, MTMSN=0 2026-03-12T16:50:36.349Z,1773334236.349 [NAL9602](INFO): Sent 202 bytes from file Logs/20260312T163252/Courier0013.lzma 2026-03-12T16:50:36.349Z,1773334236.349 [NAL9602](INFO): Packets left to send: 0 2026-03-12T16:50:44.660Z,1773334244.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:50:51.726Z,1773334251.726 [NAL9602](INFO): SBD MO Status=1, MOMSN=32549, MT Status=0, MTMSN=0 2026-03-12T16:50:51.801Z,1773334251.801 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T163252/Express0014.lzma 2026-03-12T16:50:51.801Z,1773334251.801 [NAL9602](INFO): Packets left to send: 1 2026-03-12T16:51:00.297Z,1773334260.297 [NAL9602](INFO): SBD MO Status=1, MOMSN=32550, MT Status=0, MTMSN=0 2026-03-12T16:51:00.344Z,1773334260.344 [NAL9602](INFO): Sent 19 bytes from file Logs/20260312T163252/Express0014.lzma 2026-03-12T16:51:00.344Z,1773334260.344 [NAL9602](INFO): Packets left to send: 0 2026-03-12T16:51:09.998Z,1773334269.998 [NAL9602](INFO): SBD MO Status=0, MOMSN=32551, MT Status=0, MTMSN=0 2026-03-12T16:51:10.074Z,1773334270.074 [keepstation:NeedComms:E] Stopped 2026-03-12T16:51:10.075Z,1773334270.075 [keepstation:NeedComms:F] Running Loop=1 2026-03-12T16:51:12.422Z,1773334272.422 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165111.00,A,3647.82668,N,12150.82514,W,0.603,344.82,120326,,,D*7B 2026-03-12T16:51:12.424Z,1773334272.424 [NAL9602](INFO): GPS fix at 20260312T165111: (36.797111, -121.847086) 2026-03-12T16:51:12.455Z,1773334272.455 [keepstation:NeedComms:F] Stopped 2026-03-12T16:51:12.455Z,1773334272.455 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2026-03-12T16:51:12.455Z,1773334272.455 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2026-03-12T16:51:12.455Z,1773334272.455 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2026-03-12T16:51:12.869Z,1773334272.869 [keepstation:NeedComms:setRestoreMode:A] Stopped 2026-03-12T16:51:12.869Z,1773334272.869 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2026-03-12T16:51:12.869Z,1773334272.869 [keepstation:NeedComms:setRestoreMode] Stopped 2026-03-12T16:51:12.870Z,1773334272.870 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2026-03-12T16:51:12.870Z,1773334272.870 [keepstation:NeedComms:setInactive] Running Loop=1 2026-03-12T16:51:12.870Z,1773334272.870 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2026-03-12T16:51:12.870Z,1773334272.870 [keepstation:NeedComms:setInactive:A] Running Loop=1 2026-03-12T16:51:13.246Z,1773334273.246 [keepstation:NeedComms:setInactive:A] Stopped 2026-03-12T16:51:13.246Z,1773334273.246 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2026-03-12T16:51:13.246Z,1773334273.246 [keepstation:NeedComms:setInactive] Stopped 2026-03-12T16:51:13.246Z,1773334273.246 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2026-03-12T16:51:13.247Z,1773334273.247 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2026-03-12T16:51:13.247Z,1773334273.247 [keepstation:NeedComms] Stopped 2026-03-12T16:51:13.248Z,1773334273.248 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T16:51:13.248Z,1773334273.248 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T16:51:13.248Z,1773334273.248 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T16:51:13.669Z,1773334273.669 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2026-03-12T16:51:13.669Z,1773334273.669 [keepstation:OnStation] Stopped 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:KeepStation] Running Loop=1 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:KeepStation:Dive] Running Loop=1 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:KeepStation:Dive:A] Running Loop=1 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:KeepStation:B.Wait] Running Loop=1 2026-03-12T16:51:13.670Z,1773334273.670 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2026-03-12T16:51:14.043Z,1773334274.043 [keepstation:KeepStation:Dive] Running Loop=1 2026-03-12T16:51:15.784Z,1773334275.784 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:51:44.360Z,1773334304.360 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T16:51:46.908Z,1773334306.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:52:18.052Z,1773334338.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:52:49.176Z,1773334369.176 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:53:20.301Z,1773334400.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:53:37.931Z,1773334417.931 [Radio_Surface](INFO): Powering down 2026-03-12T16:53:51.424Z,1773334431.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T16:53:52.428Z,1773334432.428 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-03-12T17:01:12.777Z,1773334872.777 [keepstation:SurfaceComms] Running Loop=1 2026-03-12T17:01:12.777Z,1773334872.777 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-12T17:01:12.777Z,1773334872.777 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-12T17:01:12.778Z,1773334872.778 [keepstation:SurfaceComms:A] Stopped 2026-03-12T17:01:12.778Z,1773334872.778 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-12T17:01:12.778Z,1773334872.778 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-12T17:01:12.780Z,1773334872.780 [keepstation:NeedComms] Running Loop=1 2026-03-12T17:01:12.780Z,1773334872.780 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T17:01:12.780Z,1773334872.780 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T17:01:12.780Z,1773334872.780 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T17:01:12.781Z,1773334872.781 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T17:01:12.781Z,1773334872.781 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T17:01:12.782Z,1773334872.782 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T17:01:12.782Z,1773334872.782 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T17:01:12.782Z,1773334872.782 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T17:01:12.782Z,1773334872.782 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T17:01:12.783Z,1773334872.783 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T17:01:12.805Z,1773334872.805 [keepstation:NeedComms:A](INFO): last time_fix was: 1773334271.000000 second since 1970/01/01T00:00:00Z 2026-03-12T17:01:12.805Z,1773334872.805 [keepstation:NeedComms:A] Stopped 2026-03-12T17:01:12.805Z,1773334872.805 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T17:01:12.805Z,1773334872.805 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T17:01:12.805Z,1773334872.805 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T17:01:13.179Z,1773334873.179 [keepstation:KeepStation:Dive] Stopped 2026-03-12T17:01:13.179Z,1773334873.179 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2026-03-12T17:01:13.179Z,1773334873.179 [keepstation:KeepStation:Dive:A] Stopped 2026-03-12T17:01:13.179Z,1773334873.179 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2026-03-12T17:01:13.180Z,1773334873.180 [keepstation:DiveCmd:StationKeep] Stopped 2026-03-12T17:01:13.180Z,1773334873.180 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2026-03-12T17:01:13.180Z,1773334873.180 [keepstation:DiveCmd:StationKeep:A] Stopped 2026-03-12T17:01:13.180Z,1773334873.180 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2026-03-12T17:01:13.180Z,1773334873.180 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2026-03-12T17:01:13.180Z,1773334873.180 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2026-03-12T17:01:13.183Z,1773334873.183 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T17:01:13.183Z,1773334873.183 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T17:01:13.594Z,1773334873.594 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T17:01:13.594Z,1773334873.594 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T17:01:13.594Z,1773334873.594 [keepstation:NeedComms:setActive] Stopped 2026-03-12T17:01:13.594Z,1773334873.594 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T17:02:47.335Z,1773334967.335 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T17:02:47.800Z,1773334967.800 [Radio_Surface](INFO): Powering up 2026-03-12T17:02:53.668Z,1773334973.668 [DataOverHttps](INFO): Radio surface powered ON. 2026-03-12T17:03:15.197Z,1773334995.197 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=32552, MT Status=1, MTMSN=1197 2026-03-12T17:03:15.198Z,1773334995.198 [NAL9602](INFO): Data available in MT queue 2026-03-12T17:03:15.692Z,1773334995.692 [NAL9602](INFO): Received command: stop 2026-03-12T17:03:15.756Z,1773334995.756 [CommandExec](IMPORTANT): got command stop 2026-03-12T17:03:15.756Z,1773334995.756 [CommandExec](IMPORTANT): Scheduling is paused 2026-03-12T17:03:15.756Z,1773334995.756 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2026-03-12T17:03:16.030Z,1773334996.030 [MissionManager](INFO): MissionManager is completed. 2026-03-12T17:03:16.031Z,1773334996.031 [MissionManager](INFO): Uninitializing Mission keepstation 2026-03-12T17:03:16.031Z,1773334996.031 [keepstation] Stopped 2026-03-12T17:03:16.031Z,1773334996.031 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2026-03-12T17:03:16.031Z,1773334996.031 [keepstation:NeedComms] Stopped 2026-03-12T17:03:16.031Z,1773334996.031 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T17:03:16.031Z,1773334996.031 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T17:03:16.031Z,1773334996.031 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T17:03:16.031Z,1773334996.031 [keepstation:NeedComms:D] Stopped 2026-03-12T17:03:16.031Z,1773334996.031 [keepstation:StandardEnvelopes] Stopped 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:BackseatDriver] Stopped 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:PowerOnly] Stopped 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2026-03-12T17:03:16.032Z,1773334996.032 [keepstation:PowerOnly:E.Wait] Stopped 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:H.Pitch] Stopped 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:SurfaceComms] Stopped 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:SurfaceComms:B] Stopped 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:DiveCmd] Stopped 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:KeepStation] Stopped 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:KeepStation:B.Wait] Stopped 2026-03-12T17:03:16.033Z,1773334996.033 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T17:03:16.442Z,1773334996.442 [MissionManager](IMPORTANT): Started mission Default 2026-03-12T17:03:16.442Z,1773334996.442 [Default] Running Loop=1 2026-03-12T17:03:16.442Z,1773334996.442 [Default](DEBUG): Aggregate::initialize Default 2026-03-12T17:03:16.442Z,1773334996.442 [Default:B.GoToSurface] Running Loop=1 2026-03-12T17:03:16.443Z,1773334996.443 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T17:03:16.443Z,1773334996.443 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-03-12T17:03:16.443Z,1773334996.443 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-03-12T17:03:16.444Z,1773334996.444 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-03-12T17:03:16.444Z,1773334996.444 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-03-12T17:03:16.445Z,1773334996.445 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T17:03:16.445Z,1773334996.445 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T17:03:16.445Z,1773334996.445 [Default:A.Wait] Running Loop=1 2026-03-12T17:03:16.445Z,1773334996.445 [Default:A.Wait](DEBUG): Initialize Wait Component. 2026-03-12T17:03:16.818Z,1773334996.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170316.00,A,3647.84782,N,12150.81500,W,0.292,147.54,120326,,,A*7C 2026-03-12T17:03:16.820Z,1773334996.820 [NAL9602](INFO): GPS fix at 20260312T170316: (36.797464, -121.846917) 2026-03-12T17:03:23.796Z,1773335003.796 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T17:03:29.755Z,1773335009.755 [Default:A.Wait](INFO): Done Waiting. 2026-03-12T17:03:29.755Z,1773335009.755 [Default:A.Wait] Stopped 2026-03-12T17:03:29.755Z,1773335009.755 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T17:03:30.160Z,1773335010.160 [Default:CheckIn] Running Loop=1 2026-03-12T17:03:30.161Z,1773335010.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-03-12T17:03:30.161Z,1773335010.161 [Default:CheckIn:Read_GPS] Running Loop=1 2026-03-12T17:03:32.171Z,1773335012.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170331.00,A,3647.84842,N,12150.81478,W,0.797,32.87,120326,,,D*4C 2026-03-12T17:03:32.181Z,1773335012.181 [NAL9602](INFO): GPS fix at 20260312T170331: (36.797474, -121.846913) 2026-03-12T17:03:32.191Z,1773335012.191 [Default:CheckIn:Read_GPS] Stopped 2026-03-12T17:03:32.192Z,1773335012.192 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-03-12T17:03:54.928Z,1773335034.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T17:04:00.129Z,1773335040.129 [NAL9602](INFO): SBD MO Status=1, MOMSN=32553, MT Status=0, MTMSN=0 2026-03-12T17:04:00.184Z,1773335040.184 [NAL9602](INFO): Sent 73 bytes from file Logs/20260312T163252/Courier0016.lzma 2026-03-12T17:04:00.184Z,1773335040.184 [NAL9602](INFO): Packets left to send: 0 2026-03-12T17:04:10.274Z,1773335050.274 [NAL9602](INFO): SBD MO Status=1, MOMSN=32554, MT Status=0, MTMSN=0 2026-03-12T17:04:10.324Z,1773335050.324 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T163252/Express0017.lzma 2026-03-12T17:04:10.324Z,1773335050.324 [NAL9602](INFO): Packets left to send: 1 2026-03-12T17:04:23.682Z,1773335063.682 [NAL9602](INFO): SBD MO Status=1, MOMSN=32555, MT Status=0, MTMSN=0 2026-03-12T17:04:23.736Z,1773335063.736 [NAL9602](INFO): Sent 50 bytes from file Logs/20260312T163252/Express0017.lzma 2026-03-12T17:04:23.736Z,1773335063.736 [NAL9602](INFO): Packets left to send: 0 2026-03-12T17:04:26.056Z,1773335066.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T17:04:33.780Z,1773335073.780 [NAL9602](INFO): SBD MO Status=0, MOMSN=32556, MT Status=0, MTMSN=0 2026-03-12T17:04:55.262Z,1773335095.262 [NAL9602](INFO): SBD MO Status=1, MOMSN=32557, MT Status=0, MTMSN=0 2026-03-12T17:04:55.312Z,1773335095.312 [NAL9602](INFO): Sent 39 bytes from file Logs/20260312T163252/Courier0019.lzma 2026-03-12T17:04:55.312Z,1773335095.312 [NAL9602](INFO): Packets left to send: 0 2026-03-12T17:04:57.184Z,1773335097.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T17:05:06.264Z,1773335106.264 [NAL9602](INFO): SBD MO Status=1, MOMSN=32558, MT Status=0, MTMSN=0 2026-03-12T17:05:06.313Z,1773335106.313 [NAL9602](INFO): Sent 76 bytes from file Logs/20260312T163252/Express0020.lzma 2026-03-12T17:05:06.313Z,1773335106.313 [NAL9602](INFO): Packets left to send: 0 2026-03-12T17:05:17.574Z,1773335117.574 [NAL9602](INFO): SBD MO Status=0, MOMSN=32559, MT Status=0, MTMSN=0 2026-03-12T17:05:17.646Z,1773335117.646 [Default:CheckIn:Read_Iridium] Stopped 2026-03-12T17:05:17.646Z,1773335117.646 [Default:CheckIn:C.Wait] Running Loop=1 2026-03-12T17:05:17.646Z,1773335117.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-03-12T17:05:28.320Z,1773335128.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T17:05:48.267Z,1773335148.267 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T17:05:59.456Z,1773335159.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T17:06:30.592Z,1773335190.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T17:07:01.716Z,1773335221.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T17:07:10.188Z,1773335230.188 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003246 2026-03-12T17:07:40.649Z,1773335260.649 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T170739 2026-03-12T17:07:48.159Z,1773335268.159 [DataOverHttps](INFO): Received command: schedule resume;restart logs