2025-02-26T17:39:40.576Z,1740591580.576 [Supervisor](DEBUG): Initializing supervisor.
2025-02-26T17:39:40.580Z,1740591580.580 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-02-26T17:39:40.580Z,1740591580.580 [SyncHandler](INFO): Protected caller Thread ID is 7632
2025-02-26T17:39:40.581Z,1740591580.581 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-02-26T17:39:40.582Z,1740591580.582 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-02-26T17:39:40.582Z,1740591580.582 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7633
2025-02-26T17:39:40.586Z,1740591580.586 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-02-26T17:39:40.604Z,1740591580.604 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-02-26T17:39:40.605Z,1740591580.605 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-02-26T17:39:40.605Z,1740591580.605 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7634
2025-02-26T17:39:40.609Z,1740591580.609 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-02-26T17:39:40.610Z,1740591580.610 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-02-26T17:39:40.611Z,1740591580.611 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7635
2025-02-26T17:39:40.613Z,1740591580.613 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-02-26T17:39:40.614Z,1740591580.614 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-02-26T17:39:40.614Z,1740591580.614 [logger ThreadHandler](INFO): Protected caller Thread ID is 7636
2025-02-26T17:39:40.618Z,1740591580.618 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-02-26T17:39:40.619Z,1740591580.619 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-02-26T17:39:40.622Z,1740591580.622 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-02-26T17:39:40.924Z,1740591580.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-02-26T17:39:40.926Z,1740591580.926 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-02-26T17:39:41.149Z,1740591581.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-02-26T17:39:41.151Z,1740591581.151 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-02-26T17:39:41.262Z,1740591581.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-02-26T17:39:41.263Z,1740591581.263 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-02-26T17:39:41.468Z,1740591581.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-02-26T17:39:41.470Z,1740591581.470 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-02-26T17:39:41.962Z,1740591581.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-02-26T17:39:41.963Z,1740591581.963 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-02-26T17:39:42.086Z,1740591582.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-02-26T17:39:42.088Z,1740591582.088 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-02-26T17:39:42.370Z,1740591582.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-02-26T17:39:42.371Z,1740591582.371 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-02-26T17:39:42.593Z,1740591582.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-02-26T17:39:42.594Z,1740591582.594 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-02-26T17:39:43.131Z,1740591583.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-02-26T17:39:43.132Z,1740591583.132 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-02-26T17:39:43.556Z,1740591583.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-02-26T17:39:43.557Z,1740591583.557 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-02-26T17:39:46.304Z,1740591586.304 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-02-26T17:39:53.067Z,1740591593.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-02-26T17:39:53.068Z,1740591593.068 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-02-26T17:39:54.630Z,1740591594.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-02-26T17:39:54.631Z,1740591594.631 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-02-26T17:39:55.477Z,1740591595.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-02-26T17:39:55.478Z,1740591595.478 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-02-26T17:39:55.836Z,1740591595.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-02-26T17:39:55.838Z,1740591595.838 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-02-26T17:39:55.937Z,1740591595.937 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2025-02-26T17:39:55.939Z,1740591595.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2025-02-26T17:39:56.039Z,1740591596.039 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2025-02-26T17:39:56.159Z,1740591596.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2025-02-26T17:39:56.280Z,1740591596.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2025-02-26T17:39:56.384Z,1740591596.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2025-02-26T17:39:56.486Z,1740591596.486 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2025-02-26T17:39:56.604Z,1740591596.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2025-02-26T17:39:56.761Z,1740591596.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2025-02-26T17:39:56.940Z,1740591596.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2025-02-26T17:39:57.050Z,1740591597.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2025-02-26T17:39:57.331Z,1740591597.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-02-26T17:39:57.332Z,1740591597.332 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-02-26T17:39:57.353Z,1740591597.353 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-02-26T17:39:57.733Z,1740591597.733 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-02-26T17:39:57.734Z,1740591597.734 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-02-26T17:39:58.408Z,1740591598.408 [AHRS_M2] Loaded
2025-02-26T17:39:58.408Z,1740591598.408 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-02-26T17:39:58.446Z,1740591598.446 [BackseatComponent] Loaded
2025-02-26T17:39:58.447Z,1740591598.447 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-02-26T17:39:58.448Z,1740591598.448 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408AB4E0
2025-02-26T17:39:58.448Z,1740591598.448 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7715
2025-02-26T17:39:58.451Z,1740591598.451 [LcmUniversalReporter] Loaded
2025-02-26T17:39:58.451Z,1740591598.451 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-02-26T17:39:59.283Z,1740591599.283 [BPC1] Loaded
2025-02-26T17:39:59.284Z,1740591599.284 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-02-26T17:39:59.378Z,1740591599.378 [DAT] Loaded
2025-02-26T17:39:59.379Z,1740591599.379 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-02-26T17:39:59.380Z,1740591599.380 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408DB4E0
2025-02-26T17:39:59.380Z,1740591599.380 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7716
2025-02-26T17:39:59.454Z,1740591599.454 [DataOverHttps] Loaded
2025-02-26T17:39:59.454Z,1740591599.454 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-02-26T17:39:59.455Z,1740591599.455 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2025-02-26T17:39:59.456Z,1740591599.456 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7717
2025-02-26T17:39:59.479Z,1740591599.479 [Depth_Keller] Loaded
2025-02-26T17:39:59.479Z,1740591599.479 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-02-26T17:39:59.484Z,1740591599.484 [DropWeight] Loaded
2025-02-26T17:39:59.484Z,1740591599.484 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-02-26T17:39:59.548Z,1740591599.548 [NAL9602] Loaded
2025-02-26T17:39:59.548Z,1740591599.548 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-02-26T17:39:59.577Z,1740591599.577 [Onboard] Loaded
2025-02-26T17:39:59.577Z,1740591599.577 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-02-26T17:39:59.578Z,1740591599.578 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2025-02-26T17:39:59.579Z,1740591599.579 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7718
2025-02-26T17:39:59.592Z,1740591599.592 [Power24vConverter] Loaded
2025-02-26T17:39:59.593Z,1740591599.593 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-02-26T17:39:59.609Z,1740591599.609 [Radio_Surface] Loaded
2025-02-26T17:39:59.610Z,1740591599.610 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-02-26T17:39:59.611Z,1740591599.611 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2025-02-26T17:39:59.611Z,1740591599.611 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7719
2025-02-26T17:39:59.626Z,1740591599.626 [Sonardyne_Nano] Loaded
2025-02-26T17:39:59.627Z,1740591599.627 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-02-26T17:39:59.627Z,1740591599.627 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-02-26T17:39:59.628Z,1740591599.628 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-02-26T17:39:59.768Z,1740591599.768 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-02-26T17:39:59.769Z,1740591599.769 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-02-26T17:39:59.824Z,1740591599.824 [VerticalControl](DEBUG): Construct VerticalControl.
2025-02-26T17:39:59.897Z,1740591599.897 [VerticalControl] Loaded
2025-02-26T17:39:59.897Z,1740591599.897 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-02-26T17:39:59.900Z,1740591599.900 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-02-26T17:39:59.968Z,1740591599.968 [HorizontalControl] Loaded
2025-02-26T17:39:59.968Z,1740591599.968 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-02-26T17:39:59.971Z,1740591599.971 [SpeedControl](DEBUG): Construct SpeedControl.
2025-02-26T17:39:59.974Z,1740591599.974 [SpeedControl] Loaded
2025-02-26T17:39:59.974Z,1740591599.974 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-02-26T17:39:59.977Z,1740591599.977 [LoopControl](DEBUG): Construct LoopControl.
2025-02-26T17:39:59.978Z,1740591599.978 [LoopControl] Loaded
2025-02-26T17:39:59.978Z,1740591599.978 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-02-26T17:39:59.978Z,1740591599.978 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-02-26T17:39:59.979Z,1740591599.979 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-02-26T17:39:59.989Z,1740591599.989 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-02-26T17:39:59.990Z,1740591599.990 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-02-26T17:40:00.019Z,1740591600.019 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-02-26T17:40:00.020Z,1740591600.020 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-02-26T17:40:01.874Z,1740591601.874 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-02-26T17:40:01.874Z,1740591601.874 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-02-26T17:40:01.888Z,1740591601.888 [NavChart] Loaded
2025-02-26T17:40:01.889Z,1740591601.889 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-02-26T17:40:01.894Z,1740591601.894 [UniversalFixResidualReporter] Loaded
2025-02-26T17:40:01.895Z,1740591601.895 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-02-26T17:40:01.895Z,1740591601.895 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-02-26T17:40:01.896Z,1740591601.896 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-02-26T17:40:02.788Z,1740591602.788 [SBIT](DEBUG): Construct Startup Built In Test.
2025-02-26T17:40:02.797Z,1740591602.797 [SBIT] Loaded
2025-02-26T17:40:02.798Z,1740591602.798 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-02-26T17:40:02.800Z,1740591602.800 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-02-26T17:40:02.813Z,1740591602.813 [IBIT] Loaded
2025-02-26T17:40:02.814Z,1740591602.814 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-02-26T17:40:02.819Z,1740591602.819 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-02-26T17:40:02.927Z,1740591602.927 [CBIT] Loaded
2025-02-26T17:40:02.927Z,1740591602.927 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-02-26T17:40:02.927Z,1740591602.927 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-02-26T17:40:02.928Z,1740591602.928 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-02-26T17:40:03.117Z,1740591603.117 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-02-26T17:40:03.117Z,1740591603.117 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-02-26T17:40:03.261Z,1740591603.261 [BuoyancyServo] Loaded
2025-02-26T17:40:03.261Z,1740591603.261 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-02-26T17:40:03.284Z,1740591603.284 [ElevatorServo] Loaded
2025-02-26T17:40:03.284Z,1740591603.284 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-02-26T17:40:03.307Z,1740591603.307 [MassServo] Loaded
2025-02-26T17:40:03.307Z,1740591603.307 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-02-26T17:40:03.328Z,1740591603.328 [RudderServo] Loaded
2025-02-26T17:40:03.329Z,1740591603.329 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-02-26T17:40:03.346Z,1740591603.346 [ThrusterHE] Loaded
2025-02-26T17:40:03.346Z,1740591603.346 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-02-26T17:40:03.347Z,1740591603.347 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-02-26T17:40:03.347Z,1740591603.347 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-02-26T17:40:03.542Z,1740591603.542 [CTD_Seabird] Loaded
2025-02-26T17:40:03.542Z,1740591603.542 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-02-26T17:40:03.543Z,1740591603.543 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA14E0
2025-02-26T17:40:03.544Z,1740591603.544 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7720
2025-02-26T17:40:03.580Z,1740591603.580 [ESPComponent] Loaded
2025-02-26T17:40:03.580Z,1740591603.580 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-02-26T17:40:03.603Z,1740591603.603 [PAR_Licor] Loaded
2025-02-26T17:40:03.603Z,1740591603.603 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-02-26T17:40:03.648Z,1740591603.648 [WetLabsBB2FL] Loaded
2025-02-26T17:40:03.649Z,1740591603.649 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-02-26T17:40:03.650Z,1740591603.650 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD14E0
2025-02-26T17:40:03.650Z,1740591603.650 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7721
2025-02-26T17:40:03.651Z,1740591603.651 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-02-26T17:40:03.652Z,1740591603.652 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-02-26T17:40:03.712Z,1740591603.712 [DepthRateCalculator] Loaded
2025-02-26T17:40:03.712Z,1740591603.712 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-02-26T17:40:03.718Z,1740591603.718 [PitchRateCalculator] Loaded
2025-02-26T17:40:03.718Z,1740591603.718 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-02-26T17:40:03.727Z,1740591603.727 [SpeedCalculator] Loaded
2025-02-26T17:40:03.727Z,1740591603.727 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-02-26T17:40:03.731Z,1740591603.731 [YawRateCalculator] Loaded
2025-02-26T17:40:03.732Z,1740591603.732 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-02-26T17:40:03.751Z,1740591603.751 [ElevatorOffsetCalculator] Loaded
2025-02-26T17:40:03.752Z,1740591603.752 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-02-26T17:40:03.752Z,1740591603.752 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-02-26T17:40:03.753Z,1740591603.753 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-02-26T17:40:03.822Z,1740591603.822 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-02-26T17:40:03.829Z,1740591603.829 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-02-26T17:40:03.832Z,1740591603.832 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-02-26T17:40:03.844Z,1740591603.844 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-02-26T17:40:03.845Z,1740591603.845 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C5E4E0
2025-02-26T17:40:03.845Z,1740591603.845 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7722
2025-02-26T17:40:03.850Z,1740591603.850 [Supervisor](INFO): Main Thread ID is 829
2025-02-26T17:40:03.850Z,1740591603.850 [Supervisor](DEBUG): Running supervisor.
2025-02-26T17:40:03.850Z,1740591603.850 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7723
2025-02-26T17:40:03.851Z,1740591603.851 [CommandExec](INFO): Initializing the command executive.
2025-02-26T17:40:03.852Z,1740591603.852 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7724
2025-02-26T17:40:03.854Z,1740591603.854 [controlThread ThreadHandler](INFO): Handler Thread ID is 7725
2025-02-26T17:40:03.855Z,1740591603.855 [controlThread](DEBUG): Initializing ControlThread
2025-02-26T17:40:03.859Z,1740591603.859 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-02-26T17:40:03.860Z,1740591603.860 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-02-26T17:40:03.861Z,1740591603.861 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-02-26T17:40:03.862Z,1740591603.862 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-02-26T17:40:03.862Z,1740591603.862 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-02-26T17:40:03.863Z,1740591603.863 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-02-26T17:40:03.863Z,1740591603.863 [SBIT](INFO): Initialize SBIT Component.
2025-02-26T17:40:03.863Z,1740591603.863 [SBIT](IMPORTANT): git: 2025-02-24
2025-02-26T17:40:03.863Z,1740591603.863 [SBIT](INFO): git hash: a6046a38350dbb557659cdb2136a92a0beb43de0
2025-02-26T17:40:03.864Z,1740591603.864 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-02-26T17:40:03.865Z,1740591603.865 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Dec 4 01:17:04 UTC 2024
2025-02-26T17:40:03.867Z,1740591603.867 [SBIT](INFO): Beginning SBIT in 66.000000 seconds.
2025-02-26T17:40:03.867Z,1740591603.867 [IBIT](INFO): Initialize IBIT Component.
2025-02-26T17:40:03.868Z,1740591603.868 [CBIT](DEBUG): Initialize CBIT Component.
2025-02-26T17:40:03.869Z,1740591603.869 [logger ThreadHandler](INFO): Handler Thread ID is 7726
2025-02-26T17:40:03.881Z,1740591603.881 [CBIT](DEBUG): Initialized mux pins.
2025-02-26T17:40:03.881Z,1740591603.881 [CBIT](DEBUG): Initializing the watchdog timer.
2025-02-26T17:40:03.889Z,1740591603.889 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7727
2025-02-26T17:40:03.901Z,1740591603.901 [DAT ThreadHandler](INFO): Handler Thread ID is 7728
2025-02-26T17:40:03.902Z,1740591603.902 [DAT](INFO): Powering up
2025-02-26T17:40:03.902Z,1740591603.902 [DAT](DEBUG): Initializing DAT.
2025-02-26T17:40:03.905Z,1740591603.905 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-02-26T17:40:03.905Z,1740591603.905 [CBIT](DEBUG): Initializing heartbeat.
2025-02-26T17:40:03.907Z,1740591603.907 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7730
2025-02-26T17:40:03.909Z,1740591603.909 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-02-26T17:40:03.918Z,1740591603.918 [Onboard ThreadHandler](INFO): Handler Thread ID is 7731
2025-02-26T17:40:03.947Z,1740591603.947 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7732
2025-02-26T17:40:03.965Z,1740591603.965 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7733
2025-02-26T17:40:03.966Z,1740591603.966 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-02-26T17:40:03.970Z,1740591603.970 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7735
2025-02-26T17:40:03.973Z,1740591603.973 [WetLabsBB2FL](INFO): Powering up
2025-02-26T17:40:03.975Z,1740591603.975 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7737
2025-02-26T17:40:03.977Z,1740591603.977 [CBIT](DEBUG): Deactivating GF circuits.
2025-02-26T17:40:03.977Z,1740591603.977 [CBIT](DEBUG): Deactivating emergency mode.
2025-02-26T17:40:03.979Z,1740591603.979 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-02-26T17:40:03.979Z,1740591603.979 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-02-26T17:40:03.979Z,1740591603.979 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-02-26T17:40:03.980Z,1740591603.980 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-02-26T17:40:03.980Z,1740591603.980 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-02-26T17:40:03.980Z,1740591603.980 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-02-26T17:40:03.980Z,1740591603.980 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-02-26T17:40:03.980Z,1740591603.980 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-02-26T17:40:04.017Z,1740591604.017 [CBIT](DEBUG): Backplane powered.
2025-02-26T17:40:04.021Z,1740591604.021 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-02-26T17:40:04.022Z,1740591604.022 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-02-26T17:40:04.022Z,1740591604.022 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-02-26T17:40:04.022Z,1740591604.022 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-02-26T17:40:04.023Z,1740591604.023 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-02-26T17:40:04.024Z,1740591604.024 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-02-26T17:40:04.024Z,1740591604.024 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-02-26T17:40:04.025Z,1740591604.025 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-02-26T17:40:04.031Z,1740591604.031 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-02-26T17:40:04.095Z,1740591604.095 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-02-26T17:40:04.100Z,1740591604.100 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-26T17:40:04.148Z,1740591604.148 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-02-26T17:40:04.149Z,1740591604.149 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-02-26T17:40:04.149Z,1740591604.149 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-02-26T17:40:04.155Z,1740591604.155 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-02-26T17:40:04.349Z,1740591604.349 [Radio_Surface](INFO): Powering up
2025-02-26T17:40:04.433Z,1740591604.433 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-02-26T17:40:04.439Z,1740591604.439 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-02-26T17:40:04.440Z,1740591604.440 [Default:A.Wait](DEBUG): Construct Wait.
2025-02-26T17:40:04.462Z,1740591604.462 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-26T17:40:04.496Z,1740591604.496 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-02-26T17:40:04.503Z,1740591604.503 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-02-26T17:40:04.529Z,1740591604.529 [Default:E.Execute](DEBUG): Construct Execute.
2025-02-26T17:40:04.532Z,1740591604.532 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-02-26T17:40:04.537Z,1740591604.537 [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,LcmUniversalReporter,Reporter,LogSplitter,
2025-02-26T17:40:04.545Z,1740591604.545 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-02-26T17:40:04.611Z,1740591604.611 [Power24vConverter](INFO): Powering up.
2025-02-26T17:40:04.612Z,1740591604.612 [Sonardyne_Nano](INFO): Initializing.
2025-02-26T17:40:04.683Z,1740591604.683 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-02-26T17:40:04.689Z,1740591604.689 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-02-26T17:40:04.690Z,1740591604.690 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-02-26T17:40:04.697Z,1740591604.697 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-02-26T17:40:04.698Z,1740591604.698 [MassServo](DEBUG): Initializing EZServoServo.
2025-02-26T17:40:04.706Z,1740591604.706 [MassServo](DEBUG): Initializing MassServo.
2025-02-26T17:40:04.707Z,1740591604.707 [RudderServo](DEBUG): Initializing EZServoServo.
2025-02-26T17:40:04.713Z,1740591604.713 [RudderServo](DEBUG): Initializing RudderServo.
2025-02-26T17:40:04.714Z,1740591604.714 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-26T17:40:04.721Z,1740591604.721 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-26T17:40:06.081Z,1740591606.081 [WetLabsBB2FL](INFO): Powering down
2025-02-26T17:40:06.567Z,1740591606.567 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-02-26T17:40:07.854Z,1740591607.854 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409
2025-02-26T17:40:10.414Z,1740591610.414 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-02-26T17:40:10.997Z,1740591610.997 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-02-26T17:40:15.494Z,1740591615.494 [DAT](INFO): DAT read:
2025-02-26T17:40:15.496Z,1740591615.496 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-02-26T17:40:17.006Z,1740591617.006 [DAT](INFO): DAT read: MF Frequency Band
2025-02-26T17:40:17.007Z,1740591617.007 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-02-26T17:40:17.008Z,1740591617.008 [DAT](INFO): DAT read: Feb 26 2025 17:39:57
2025-02-26T17:40:18.266Z,1740591618.266 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-02-26T17:40:18.267Z,1740591618.267 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-02-26T17:40:18.268Z,1740591618.268 [DAT](INFO): commRate: 800
2025-02-26T17:40:20.335Z,1740591620.335 [DAT](INFO): entering command mode
2025-02-26T17:40:20.533Z,1740591620.533 [DAT](INFO): DAT read:
2025-02-26T17:40:20.534Z,1740591620.534 [DAT](INFO): DAT read: user:1>
2025-02-26T17:40:20.534Z,1740591620.534 [DAT](INFO): setting verbose to 3
2025-02-26T17:40:20.786Z,1740591620.786 [DAT](INFO): DAT read: user:1>
2025-02-26T17:40:20.787Z,1740591620.787 [DAT](INFO): DAT read: Verbose | 3
2025-02-26T17:40:20.787Z,1740591620.787 [DAT](INFO): set verbose to 3
2025-02-26T17:40:20.787Z,1740591620.787 [DAT](INFO): setting DatVerbose to 27440
2025-02-26T17:40:21.038Z,1740591621.038 [DAT](INFO): DAT read: user:2>
2025-02-26T17:40:21.039Z,1740591621.039 [DAT](INFO): DAT read: DatVerbose | 27440
2025-02-26T17:40:21.039Z,1740591621.039 [DAT](INFO): set DatVerbose to 27440
2025-02-26T17:40:21.039Z,1740591621.039 [DAT](INFO): setting transmit power to 8
2025-02-26T17:40:21.290Z,1740591621.290 [DAT](INFO): DAT read: user:3>
2025-02-26T17:40:21.291Z,1740591621.291 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-02-26T17:40:21.291Z,1740591621.291 [DAT](INFO): set transmit power to 8
2025-02-26T17:40:21.291Z,1740591621.291 [DAT](INFO): setting local address to 2
2025-02-26T17:40:21.542Z,1740591621.542 [DAT](INFO): DAT read: user:4>
2025-02-26T17:40:21.543Z,1740591621.543 [DAT](INFO): DAT read: LocalAddr | 2
2025-02-26T17:40:21.543Z,1740591621.543 [DAT](INFO): set local address to 2
2025-02-26T17:40:21.544Z,1740591621.544 [DAT](INFO): Setting time to: 17:40:21 And date to:2/26/2025
2025-02-26T17:40:21.794Z,1740591621.794 [DAT](INFO): DAT read: user:5>
2025-02-26T17:40:21.794Z,1740591621.794 [DAT](INFO): DAT read: Wed Feb 26, 2025 17:40:21
2025-02-26T17:40:21.795Z,1740591621.795 [DAT](INFO): Local DAT time set to Wed Feb 26, 2025 17:40:21
2025-02-26T17:40:29.602Z,1740591629.602 [NAL9602](INFO): Powering up NAL9602
2025-02-26T17:40:40.511Z,1740591640.511 [NAL9602](INFO): NAL9602 initialized
2025-02-26T17:40:51.828Z,1740591651.828 [NAL9602](INFO): SBD MO Status=0, MOMSN=13589, MT Status=0, MTMSN=0
2025-02-26T17:40:51.828Z,1740591651.828 [NAL9602](INFO): No messages in MT queue
2025-02-26T17:41:10.587Z,1740591670.587 [SBIT](IMPORTANT): Beginning Startup BIT
2025-02-26T17:41:10.591Z,1740591670.591 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-26T17:41:21.893Z,1740591681.893 [CBIT](IMPORTANT): Low side ground fault detected
mA:
CHAN A0 (Batt): 0.933925
CHAN A1 (24V): 0.844952
CHAN A2 (12V): 0.947615
CHAN A3 (5V): 0.405993
CHAN B0 (3.3V): 0.225405
CHAN B1 (3.15aV): 0.279281
CHAN B2 (3.15bV): 0.278595
CHAN B3 (GND): -0.065106
OPEN: 0.012312
Full Scale: +/- 1 mA
2025-02-26T17:42:02.156Z,1740591722.156 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174158.00,A,3649.18400,N,12149.27312,W,0.933,89.61,260225,,,A*43
2025-02-26T17:42:02.160Z,1740591722.160 [NAL9602](INFO): GPS fix at 20250226T174158: (36.819733, -121.821219)
2025-02-26T17:42:04.474Z,1740591724.474 [SBIT](IMPORTANT): SBIT PASSED
2025-02-26T17:42:04.474Z,1740591724.474 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-02-26T17:42:04.475Z,1740591724.475 [SBIT](IMPORTANT): BioacousticsDataBridge.loadAtStartup=0 bool;
2025-02-26T17:42:04.475Z,1740591724.475 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;
2025-02-26T17:42:04.475Z,1740591724.475 [SBIT](IMPORTANT): Courier mostRecent latitude_fix;
2025-02-26T17:42:04.475Z,1740591724.475 [SBIT](IMPORTANT): Courier mostRecent longitude_fix;
2025-02-26T17:42:04.475Z,1740591724.475 [SBIT](IMPORTANT): Courier mostRecent time_fix;
2025-02-26T17:42:04.475Z,1740591724.475 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): DATMMP.loadAtStartup=0 bool;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=1 bool;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): Express none PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=0.1 ampere_hour;
2025-02-26T17:42:04.476Z,1740591724.476 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2025-02-26T17:42:04.477Z,1740591724.477 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.950000 m/s;
2025-02-26T17:42:04.477Z,1740591724.477 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=305.318315 cubic_centimeter;
2025-02-26T17:42:04.477Z,1740591724.477 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.5 degree;
2025-02-26T17:42:04.477Z,1740591724.477 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=0.1 second;
2025-02-26T17:42:04.477Z,1740591724.477 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=0.001 reciprocal_second;
2025-02-26T17:42:04.477Z,1740591724.477 [SBIT](IMPORTANT): VerticalControl.kpDepthBuoy=30 ratio;
2025-02-26T17:42:04.477Z,1740591724.477 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=0.1 none;
2025-02-26T17:42:04.477Z,1740591724.477 [SBIT](IMPORTANT): VerticalControl.massDeadband=0 millimeter;
2025-02-26T17:42:04.478Z,1740591724.478 [SBIT](IMPORTANT): VerticalControl.massDefault=11.647778 millimeter;
2025-02-26T17:42:04.478Z,1740591724.478 [SBIT](IMPORTANT): VerticalControl.massOnlyForceInt=1 bool;
2025-02-26T17:42:04.613Z,1740591724.613 [MissionManager](IMPORTANT): Started mission Startup
2025-02-26T17:42:04.613Z,1740591724.613 [Startup] Running Loop=1
2025-02-26T17:42:04.613Z,1740591724.613 [Startup](DEBUG): Aggregate::initialize Startup
2025-02-26T17:42:04.614Z,1740591724.614 [Startup:A.GoToSurface] Running Loop=1
2025-02-26T17:42:04.614Z,1740591724.614 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-26T17:42:04.614Z,1740591724.614 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-26T17:42:04.615Z,1740591724.615 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-26T17:42:04.615Z,1740591724.615 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-26T17:42:04.615Z,1740591724.615 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-26T17:42:04.616Z,1740591724.616 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-26T17:42:04.616Z,1740591724.616 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-26T17:42:04.622Z,1740591724.622 [Startup:StartupSatComms] Running Loop=1
2025-02-26T17:42:04.622Z,1740591724.622 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-02-26T17:42:04.622Z,1740591724.622 [Startup:StartupSatComms:A] Running Loop=1
2025-02-26T17:42:04.994Z,1740591724.994 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-02-26T17:42:06.600Z,1740591726.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174202.00,A,3649.18225,N,12149.27354,W,0.564,89.61,260225,,,A*42
2025-02-26T17:42:06.611Z,1740591726.611 [NAL9602](INFO): GPS fix at 20250226T174202: (36.819704, -121.821226)
2025-02-26T17:42:06.622Z,1740591726.622 [Startup:StartupSatComms:A] Stopped
2025-02-26T17:42:06.622Z,1740591726.622 [Startup:StartupSatComms:B] Running Loop=1
2025-02-26T17:42:07.037Z,1740591727.037 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-26T17:42:17.235Z,1740591737.235 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008568
2025-02-26T17:42:25.178Z,1740591745.178 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250226T074052/Courier0169.lzma
2025-02-26T17:42:26.179Z,1740591746.179 [DataOverHttps](INFO): Moved sent file to Logs/20250226T074052/Courier0169.lzma.bak
2025-02-26T17:42:26.179Z,1740591746.179 [DataOverHttps](INFO): SBD MOMSN=23979240
2025-02-26T17:42:33.613Z,1740591753.613 [NAL9602](INFO): SBD MO Status=1, MOMSN=13590, MT Status=0, MTMSN=0
2025-02-26T17:42:33.665Z,1740591753.665 [NAL9602](INFO): Sent 39 bytes from file Logs/20250226T074052/Courier0169.lzma
2025-02-26T17:42:33.665Z,1740591753.665 [NAL9602](INFO): Packets left to send: 0
2025-02-26T17:42:33.697Z,1740591753.697 [Startup:StartupSatComms:B] Stopped
2025-02-26T17:42:33.697Z,1740591753.697 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-02-26T17:42:33.697Z,1740591753.697 [Startup:StartupSatComms] Stopped
2025-02-26T17:42:33.698Z,1740591753.698 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-02-26T17:42:33.698Z,1740591753.698 [Startup](INFO): Completed Startup
2025-02-26T17:42:33.699Z,1740591753.699 [MissionManager](INFO): Startup is completed.
2025-02-26T17:42:33.699Z,1740591753.699 [MissionManager](INFO): Uninitializing Mission Startup
2025-02-26T17:42:33.699Z,1740591753.699 [Startup] Stopped
2025-02-26T17:42:33.699Z,1740591753.699 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-02-26T17:42:33.699Z,1740591753.699 [Startup:A.GoToSurface] Stopped
2025-02-26T17:42:33.699Z,1740591753.699 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-26T17:42:34.058Z,1740591754.058 [MissionManager](IMPORTANT): Started mission Default
2025-02-26T17:42:34.058Z,1740591754.058 [Default] Running Loop=1
2025-02-26T17:42:34.058Z,1740591754.058 [Default](DEBUG): Aggregate::initialize Default
2025-02-26T17:42:34.058Z,1740591754.058 [Default:B.GoToSurface] Running Loop=1
2025-02-26T17:42:34.058Z,1740591754.058 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-26T17:42:34.058Z,1740591754.058 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-26T17:42:34.059Z,1740591754.059 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-26T17:42:34.059Z,1740591754.059 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-26T17:42:34.059Z,1740591754.059 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-26T17:42:34.060Z,1740591754.060 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-26T17:42:34.060Z,1740591754.060 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-26T17:42:34.060Z,1740591754.060 [Default:A.Wait] Running Loop=1
2025-02-26T17:42:34.060Z,1740591754.060 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-26T17:42:42.837Z,1740591762.837 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250226T074052/Courier0172.lzma
2025-02-26T17:42:43.840Z,1740591763.840 [DataOverHttps](INFO): Moved sent file to Logs/20250226T074052/Courier0172.lzma.bak
2025-02-26T17:42:43.840Z,1740591763.840 [DataOverHttps](INFO): SBD MOMSN=23979242
2025-02-26T17:42:47.122Z,1740591767.122 [Default:A.Wait](INFO): Done Waiting.
2025-02-26T17:42:47.122Z,1740591767.122 [Default:A.Wait] Stopped
2025-02-26T17:42:47.122Z,1740591767.122 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-26T17:42:47.539Z,1740591767.539 [Default:CheckIn] Running Loop=1
2025-02-26T17:42:47.539Z,1740591767.539 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-26T17:42:47.539Z,1740591767.539 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-26T17:42:47.949Z,1740591767.949 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-26T17:42:49.552Z,1740591769.552 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174245.00,A,3649.18306,N,12149.27385,W,0.603,292.23,260225,,,A*71
2025-02-26T17:42:49.554Z,1740591769.554 [NAL9602](INFO): GPS fix at 20250226T174245: (36.819718, -121.821231)
2025-02-26T17:42:49.598Z,1740591769.598 [Default:CheckIn:Read_GPS] Stopped
2025-02-26T17:42:49.598Z,1740591769.598 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-26T17:42:49.971Z,1740591769.971 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-26T17:43:10.702Z,1740591790.702 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250226T173940/Courier0000.lzma
2025-02-26T17:43:11.703Z,1740591791.703 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Courier0000.lzma.bak
2025-02-26T17:43:11.703Z,1740591791.703 [DataOverHttps](INFO): SBD MOMSN=23979246
2025-02-26T17:43:21.110Z,1740591801.110 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-26T17:43:36.965Z,1740591816.965 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250226T173940/Courier0004.lzma
2025-02-26T17:43:37.967Z,1740591817.967 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Courier0004.lzma.bak
2025-02-26T17:43:37.967Z,1740591817.967 [DataOverHttps](INFO): SBD MOMSN=23979250
2025-02-26T17:43:54.661Z,1740591834.661 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20250226T074052/Express0170.lzma
2025-02-26T17:43:55.664Z,1740591835.664 [DataOverHttps](INFO): Moved sent file to Logs/20250226T074052/Express0170.lzma.bak
2025-02-26T17:43:55.664Z,1740591835.664 [DataOverHttps](INFO): SBD MOMSN=23979253
2025-02-26T17:44:14.550Z,1740591854.550 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20250226T074052/Express0173.lzma
2025-02-26T17:44:15.551Z,1740591855.551 [DataOverHttps](INFO): Moved sent file to Logs/20250226T074052/Express0173.lzma.bak
2025-02-26T17:44:15.551Z,1740591855.551 [DataOverHttps](INFO): SBD MOMSN=23979259
2025-02-26T17:44:24.535Z,1740591864.535 [DataOverHttps](IMPORTANT): SBD MTMSN=20250226T174423
2025-02-26T17:44:26.645Z,1740591866.645 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:44:32.403Z,1740591872.403 [DataOverHttps](INFO): Sending 1231 bytes from file Logs/20250226T173940/Express0001.lzma
2025-02-26T17:44:32.410Z,1740591872.410 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.NeedCommsTime 15 min;set transit.Latitude 36.81025 degree;set transit.Longitude -121.80203 degree;set transit.Depth 5 m;set transit.Speed 1.4 m/s;set transit.MaxDepth 10 m" 2cp9r 1 2
2025-02-26T17:44:32.519Z,1740591872.519 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.NeedCommsTime 15 min;set transit.Latitude 36.81025 degree;set transit.Longitude -121.80203 degree;set transit.Depth 5 m;set transit.Speed 1.4 m/s;set transit.MaxDepth 10 m" 2cp9r 1 2.000000
2025-02-26T17:44:32.519Z,1740591872.519 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=2cp9r
2025-02-26T17:44:32.520Z,1740591872.520 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='2cp9r'): "load Transport/transit.tl;set transit.NeedCommsTime 15 min;set transit.Latitude 36.81025 degree;set transit.Longitude -121.80203 degree;set transit.Depth 5 m;set transit.Speed 1.4 m/s;set transit.MaxDepth 10 m", ASAP
2025-02-26T17:44:33.699Z,1740591873.699 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Express0001.lzma.bak
2025-02-26T17:44:33.700Z,1740591873.700 [DataOverHttps](IMPORTANT): SBD MOMSN=23979263, MTMSN=20250226T174432
2025-02-26T17:44:37.955Z,1740591877.955 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:44:41.530Z,1740591881.530 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.5 km;run" 2cp9r 2 2
2025-02-26T17:44:41.592Z,1740591881.592 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.5 km;run" 2cp9r 2 2.000000
2025-02-26T17:44:41.592Z,1740591881.592 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=2cp9r
2025-02-26T17:44:41.593Z,1740591881.593 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='2cp9r'): "set transit.MinOffshore 0.5 km;run", ASAP
2025-02-26T17:44:41.714Z,1740591881.714 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-02-26T17:44:41.714Z,1740591881.714 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-02-26T17:44:41.714Z,1740591881.714 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-02-26T17:44:42.340Z,1740591882.340 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-02-26T17:44:43.116Z,1740591883.116 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-02-26T17:44:43.122Z,1740591883.122 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-02-26T17:44:43.125Z,1740591883.125 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-02-26T17:44:43.128Z,1740591883.128 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-02-26T17:44:43.131Z,1740591883.131 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-02-26T17:44:43.134Z,1740591883.134 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-02-26T17:44:43.137Z,1740591883.137 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-02-26T17:44:43.140Z,1740591883.140 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-02-26T17:44:43.143Z,1740591883.143 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-02-26T17:44:43.148Z,1740591883.148 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-02-26T17:44:43.151Z,1740591883.151 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-02-26T17:44:43.154Z,1740591883.154 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-02-26T17:44:43.157Z,1740591883.157 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-02-26T17:44:43.159Z,1740591883.159 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-02-26T17:44:43.160Z,1740591883.160 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-26T17:44:43.160Z,1740591883.160 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-26T17:44:43.161Z,1740591883.161 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-26T17:44:43.410Z,1740591883.410 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-02-26T17:44:43.416Z,1740591883.416 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-02-26T17:44:43.419Z,1740591883.419 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-26T17:44:43.422Z,1740591883.422 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-26T17:44:43.425Z,1740591883.425 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-02-26T17:44:43.428Z,1740591883.428 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-26T17:44:43.430Z,1740591883.430 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-26T17:44:43.434Z,1740591883.434 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-02-26T17:44:43.437Z,1740591883.437 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-02-26T17:44:43.443Z,1740591883.443 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-26T17:44:43.465Z,1740591883.465 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-26T17:44:43.465Z,1740591883.465 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-26T17:44:43.466Z,1740591883.466 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-26T17:44:43.543Z,1740591883.543 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-02-26T17:44:43.548Z,1740591883.548 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-26T17:44:43.551Z,1740591883.551 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-26T17:44:43.554Z,1740591883.554 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-26T17:44:43.558Z,1740591883.558 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-26T17:44:43.559Z,1740591883.559 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-26T17:44:43.567Z,1740591883.567 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-26T17:44:43.577Z,1740591883.577 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-26T17:44:43.588Z,1740591883.588 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-26T17:44:43.588Z,1740591883.588 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-26T17:44:43.590Z,1740591883.590 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-26T17:44:43.608Z,1740591883.608 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-02-26T17:44:43.612Z,1740591883.612 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-02-26T17:44:43.621Z,1740591883.621 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-26T17:44:43.624Z,1740591883.624 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-26T17:44:43.624Z,1740591883.624 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-26T17:44:47.238Z,1740591887.238 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-26T17:44:47.468Z,1740591887.468 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-02-26T17:44:47.485Z,1740591887.485 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-02-26T17:44:47.488Z,1740591887.488 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-02-26T17:44:47.491Z,1740591887.491 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-02-26T17:44:47.509Z,1740591887.509 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-02-26T17:44:47.518Z,1740591887.518 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-26T17:44:47.542Z,1740591887.542 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-26T17:44:47.544Z,1740591887.544 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-26T17:44:47.560Z,1740591887.560 [transit:MassHold.Pitch](DEBUG): Construct.
2025-02-26T17:44:47.575Z,1740591887.575 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-02-26T17:44:47.589Z,1740591887.589 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-02-26T17:44:47.592Z,1740591887.592 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-26T17:44:47.649Z,1740591887.649 [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 transit {
"""
Vehicle transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" refId="NeedComms"
}
}
2025-02-26T17:44:47.650Z,1740591887.650 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-02-26T17:44:49.403Z,1740591889.403 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:44:59.532Z,1740591899.532 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250226T173940/Courier0007.lzma
2025-02-26T17:45:00.539Z,1740591900.539 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Courier0007.lzma.bak
2025-02-26T17:45:00.540Z,1740591900.540 [DataOverHttps](INFO): SBD MOMSN=23979303
2025-02-26T17:45:01.156Z,1740591901.156 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:45:12.657Z,1740591912.657 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:45:16.598Z,1740591916.598 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 15 minute
2025-02-26T17:45:16.599Z,1740591916.599 [CommandExec](IMPORTANT): got command set transit.Latitude 36.81025 degree
2025-02-26T17:45:16.600Z,1740591916.600 [CommandExec](IMPORTANT): got command set transit.Longitude -121.80203 degree
2025-02-26T17:45:16.600Z,1740591916.600 [CommandExec](IMPORTANT): got command set transit.Depth 5 meter
2025-02-26T17:45:16.605Z,1740591916.605 [CommandExec](IMPORTANT): got command set transit.Speed 1.4 meter_per_second
2025-02-26T17:45:16.606Z,1740591916.606 [CommandExec](IMPORTANT): got command set transit.MaxDepth 10 meter
2025-02-26T17:45:21.275Z,1740591921.275 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.5 kilometer
2025-02-26T17:45:21.276Z,1740591921.276 [CommandExec](IMPORTANT): got command run
2025-02-26T17:45:21.278Z,1740591921.278 [CommandExec](IMPORTANT): Running
2025-02-26T17:45:21.318Z,1740591921.318 [Default] Stopped
2025-02-26T17:45:21.318Z,1740591921.318 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-26T17:45:21.318Z,1740591921.318 [Default:B.GoToSurface] Stopped
2025-02-26T17:45:21.318Z,1740591921.318 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-26T17:45:21.318Z,1740591921.318 [Default:CheckIn] Stopped
2025-02-26T17:45:21.318Z,1740591921.318 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-26T17:45:21.318Z,1740591921.318 [Default:CheckIn:Read_Iridium] Stopped
2025-02-26T17:45:21.318Z,1740591921.318 [MissionManager](IMPORTANT): Started mission transit
2025-02-26T17:45:21.319Z,1740591921.319 [transit] Running Loop=1
2025-02-26T17:45:21.319Z,1740591921.319 [transit](DEBUG): Aggregate::initialize transit
2025-02-26T17:45:21.319Z,1740591921.319 [transit:StandardEnvelopes] Running Loop=1
2025-02-26T17:45:21.319Z,1740591921.319 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-02-26T17:45:21.319Z,1740591921.319 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-26T17:45:21.319Z,1740591921.319 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-26T17:45:21.319Z,1740591921.319 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-26T17:45:21.319Z,1740591921.319 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-26T17:45:21.320Z,1740591921.320 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-26T17:45:21.320Z,1740591921.320 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-26T17:45:21.320Z,1740591921.320 [transit:BackseatDriver] Running Loop=1
2025-02-26T17:45:21.320Z,1740591921.320 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-02-26T17:45:21.320Z,1740591921.320 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-26T17:45:21.320Z,1740591921.320 [transit:PowerOnly] Running Loop=1
2025-02-26T17:45:21.320Z,1740591921.320 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-02-26T17:45:21.320Z,1740591921.320 [transit:PowerOnly:A] Running Loop=1
2025-02-26T17:45:21.320Z,1740591921.320 [transit:PowerOnly:B] Running Loop=1
2025-02-26T17:45:21.320Z,1740591921.320 [transit:PowerOnly:C] Running Loop=1
2025-02-26T17:45:21.320Z,1740591921.320 [transit:PowerOnly:D] Running Loop=1
2025-02-26T17:45:21.321Z,1740591921.321 [transit:PowerOnly:E.Wait] Running Loop=1
2025-02-26T17:45:21.321Z,1740591921.321 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-26T17:45:21.321Z,1740591921.321 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-26T17:45:21.321Z,1740591921.321 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-26T17:45:21.321Z,1740591921.321 [transit:MassHold.Pitch] Running Loop=1
2025-02-26T17:45:21.321Z,1740591921.321 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-02-26T17:45:21.322Z,1740591921.322 [transit:B] Running Loop=1
2025-02-26T17:45:21.322Z,1740591921.322 [transit:MassHold.Pitch] Running Loop=1
2025-02-26T17:45:21.323Z,1740591921.323 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-26T17:45:21.323Z,1740591921.323 [transit:PowerOnly] Running Loop=1
2025-02-26T17:45:21.349Z,1740591921.349 [transit:PowerOnly:D] Stopped
2025-02-26T17:45:21.349Z,1740591921.349 [transit:PowerOnly:C] Stopped
2025-02-26T17:45:21.349Z,1740591921.349 [transit:PowerOnly:B] Stopped
2025-02-26T17:45:21.349Z,1740591921.349 [transit:PowerOnly:A] Stopped
2025-02-26T17:45:21.349Z,1740591921.349 [transit:BackseatDriver] Running Loop=1
2025-02-26T17:45:21.350Z,1740591921.350 [transit:StandardEnvelopes] Running Loop=1
2025-02-26T17:45:21.350Z,1740591921.350 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-26T17:45:21.350Z,1740591921.350 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-26T17:45:21.351Z,1740591921.351 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-26T17:45:21.352Z,1740591921.352 [transit:B] Stopped
2025-02-26T17:45:21.352Z,1740591921.352 [transit:C] Running Loop=1
2025-02-26T17:45:21.719Z,1740591921.719 [transit:C] Stopped
2025-02-26T17:45:21.720Z,1740591921.720 [transit:E] Running Loop=1
2025-02-26T17:45:22.129Z,1740591922.129 [transit:E] Stopped
2025-02-26T17:45:22.129Z,1740591922.129 [transit:F] Running Loop=1
2025-02-26T17:45:22.542Z,1740591922.542 [transit:F] Stopped
2025-02-26T17:45:22.542Z,1740591922.542 [transit:G] Running Loop=1
2025-02-26T17:45:22.982Z,1740591922.982 [transit:G] Stopped
2025-02-26T17:45:22.982Z,1740591922.982 [transit:Transit] Running Loop=1
2025-02-26T17:45:22.982Z,1740591922.982 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-02-26T17:45:22.982Z,1740591922.982 [transit:Transit:A.Pitch] Running Loop=1
2025-02-26T17:45:22.982Z,1740591922.982 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-02-26T17:45:22.982Z,1740591922.982 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-26T17:45:22.982Z,1740591922.982 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-02-26T17:45:22.983Z,1740591922.983 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-02-26T17:45:22.983Z,1740591922.983 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-26T17:45:22.985Z,1740591922.985 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.810249,-121.802032
2025-02-26T17:45:23.006Z,1740591923.006 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-26T17:45:23.375Z,1740591923.375 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.810249,-121.802032
2025-02-26T17:45:23.376Z,1740591923.376 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-26T17:45:23.376Z,1740591923.376 [transit:Transit:A.Pitch] Running Loop=1
2025-02-26T17:45:23.399Z,1740591923.399 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-02-26T17:45:23.399Z,1740591923.399 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-02-26T17:45:23.399Z,1740591923.399 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-02-26T17:45:24.233Z,1740591924.233 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:45:31.670Z,1740591931.670 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250226T173940/Courier0010.lzma
2025-02-26T17:45:33.526Z,1740591933.526 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Courier0010.lzma.bak
2025-02-26T17:45:33.527Z,1740591933.527 [DataOverHttps](INFO): SBD MOMSN=23979305
2025-02-26T17:45:35.719Z,1740591935.719 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:45:47.003Z,1740591947.003 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:45:58.140Z,1740591958.140 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:46:09.804Z,1740591969.804 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:46:21.254Z,1740591981.254 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:46:22.045Z,1740591982.045 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-02-26T17:46:32.936Z,1740591992.936 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:46:44.272Z,1740592004.272 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-26T17:46:53.186Z,1740592013.186 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T17:46:54.613Z,1740592014.613 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-26T17:46:54.620Z,1740592014.620 [BPC1](ERROR): Battery stick #57 (s/n: 01A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0BD0.
2025-02-26T17:46:54.621Z,1740592014.621 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-26T17:46:54.622Z,1740592014.622 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6.
2025-02-26T17:46:54.625Z,1740592014.625 [BPC1](INFO): Received data from all battery sticks.
2025-02-26T17:47:24.309Z,1740592044.309 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T17:47:26.393Z,1740592046.393 [Radio_Surface](INFO): Powering down
2025-02-26T17:47:55.433Z,1740592075.433 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T17:47:56.438Z,1740592076.438 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-26T17:48:33.585Z,1740592113.585 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.911194
2025-02-26T17:56:36.465Z,1740592596.465 [MassServo](ERROR): getPosition uart error serial timeout
2025-02-26T17:56:36.466Z,1740592596.466 [MassServo](FAULT): Invalid EZ Servo response:"\377"
2025-02-26T17:56:36.466Z,1740592596.466 [MassServo] Communications Fault, FailCount= 1
2025-02-26T17:56:36.466Z,1740592596.466 [MassServo](ERROR): Communications Fault
2025-02-26T17:56:36.680Z,1740592596.680 [CBIT](ERROR): Communications Fault in component: MassServo
2025-02-26T17:56:36.779Z,1740592596.779 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-02-26T17:56:36.779Z,1740592596.779 [MassServo](INFO): Powering down
2025-02-26T17:56:37.634Z,1740592597.634 [MassServo](DEBUG): Initializing EZServoServo.
2025-02-26T17:56:37.754Z,1740592597.754 [MassServo](DEBUG): Initializing MassServo.
2025-02-26T17:56:37.856Z,1740592597.856 [CBIT](INFO): Clearing failed state for component MassServo
2025-02-26T17:56:37.856Z,1740592597.856 [MassServo] No Fault, FailCount= 1
2025-02-26T17:57:50.822Z,1740592670.822 [transit:NeedComms] Running Loop=1
2025-02-26T17:57:50.822Z,1740592670.822 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-02-26T17:57:50.822Z,1740592670.822 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-02-26T17:57:50.822Z,1740592670.822 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-26T17:57:50.822Z,1740592670.822 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-26T17:57:50.823Z,1740592670.823 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-26T17:57:50.823Z,1740592670.823 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-26T17:57:50.823Z,1740592670.823 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-26T17:57:50.824Z,1740592670.824 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-26T17:57:50.824Z,1740592670.824 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-26T17:57:50.824Z,1740592670.824 [transit:NeedComms:A] Running Loop=1
2025-02-26T17:57:50.843Z,1740592670.843 [transit:NeedComms:A](INFO): last time_fix was: 1740591765.000000 second since 1970/01/01T00:00:00Z
2025-02-26T17:57:50.843Z,1740592670.843 [transit:NeedComms:A] Stopped
2025-02-26T17:57:56.617Z,1740592676.617 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-02-26T17:57:56.713Z,1740592676.713 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-02-26T17:57:56.713Z,1740592676.713 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-02-26T17:57:56.713Z,1740592676.713 [ThrusterHE] Communications Fault, FailCount= 1
2025-02-26T17:57:56.713Z,1740592676.713 [ThrusterHE](ERROR): Communications Fault
2025-02-26T17:57:56.717Z,1740592676.717 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-02-26T17:57:56.926Z,1740592676.926 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-02-26T17:57:56.927Z,1740592676.927 [ThrusterHE](INFO): Powering down
2025-02-26T17:57:57.730Z,1740592677.730 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-26T17:57:57.850Z,1740592677.850 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-26T17:57:57.854Z,1740592677.854 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-02-26T17:57:57.854Z,1740592677.854 [ThrusterHE] No Fault, FailCount= 1
2025-02-26T17:58:08.874Z,1740592688.874 [transit:NeedComms:C] Running Loop=1
2025-02-26T17:58:09.265Z,1740592689.265 [Radio_Surface](INFO): Powering up
2025-02-26T17:58:09.293Z,1740592689.293 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-26T17:58:14.982Z,1740592694.982 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-26T17:58:24.610Z,1740592704.610 [NAL9602](INFO): SBD MO Status=2, MOMSN=13591, MT Status=2, MTMSN=0
2025-02-26T17:58:24.610Z,1740592704.610 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-26T17:58:45.129Z,1740592725.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T17:59:01.048Z,1740592741.048 [NAL9602](INFO): SBD MO Status=2, MOMSN=13591, MT Status=2, MTMSN=0
2025-02-26T17:59:01.048Z,1740592741.048 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-26T17:59:16.253Z,1740592756.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T17:59:17.200Z,1740592757.200 [NAL9602](INFO): SBD MO Status=0, MOMSN=13591, MT Status=0, MTMSN=0
2025-02-26T17:59:17.200Z,1740592757.200 [NAL9602](INFO): No messages in MT queue
2025-02-26T17:59:18.411Z,1740592758.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175917.00,A,3648.92927,N,12148.68068,W,1.263,173.11,260225,,,A*72
2025-02-26T17:59:18.424Z,1740592758.424 [NAL9602](INFO): GPS fix at 20250226T175917: (36.815488, -121.811345)
2025-02-26T17:59:18.438Z,1740592758.438 [UniversalFixResidualReporter](INFO): Fix residual: 7.9 %DT, over the last 1055.6 m. Residual distance 83.1 m at bearing 170.7 degrees. Fix at (36.8155, -121.8113) with 997.8 m made good.
2025-02-26T17:59:18.442Z,1740592758.442 [transit:NeedComms:C] Stopped
2025-02-26T17:59:18.443Z,1740592758.443 [transit:NeedComms:D] Running Loop=1
2025-02-26T17:59:18.853Z,1740592758.853 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-26T17:59:41.928Z,1740592781.928 [NAL9602](INFO): SBD MO Status=2, MOMSN=13592, MT Status=2, MTMSN=0
2025-02-26T17:59:41.928Z,1740592781.928 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-26T17:59:47.396Z,1740592787.396 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T18:00:18.518Z,1740592818.518 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T18:00:27.554Z,1740592827.554 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002875
2025-02-26T18:00:35.234Z,1740592835.234 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20250226T173940/Courier0013.lzma
2025-02-26T18:00:36.236Z,1740592836.236 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Courier0013.lzma.bak
2025-02-26T18:00:36.236Z,1740592836.236 [DataOverHttps](INFO): SBD MOMSN=23979308
2025-02-26T18:00:55.093Z,1740592855.093 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20250226T173940/Express0005.lzma
2025-02-26T18:00:56.095Z,1740592856.095 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Express0005.lzma.bak
2025-02-26T18:00:56.095Z,1740592856.095 [DataOverHttps](INFO): SBD MOMSN=23979311
2025-02-26T18:00:59.578Z,1740592859.578 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-26T18:01:16.958Z,1740592876.958 [DataOverHttps](INFO): Sending 371 bytes from file Logs/20250226T173940/Express0008.lzma
2025-02-26T18:01:17.959Z,1740592877.959 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Express0008.lzma.bak
2025-02-26T18:01:17.959Z,1740592877.959 [DataOverHttps](INFO): SBD MOMSN=23979314
2025-02-26T18:01:34.717Z,1740592894.717 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20250226T173940/Express0011.lzma
2025-02-26T18:01:35.723Z,1740592895.723 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Express0011.lzma.bak
2025-02-26T18:01:35.723Z,1740592895.723 [DataOverHttps](INFO): SBD MOMSN=23979320
2025-02-26T18:01:52.249Z,1740592912.249 [DataOverHttps](INFO): Sending 563 bytes from file Logs/20250226T173940/Express0014.lzma
2025-02-26T18:01:53.251Z,1740592913.251 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Express0014.lzma.bak
2025-02-26T18:01:53.251Z,1740592913.251 [DataOverHttps](INFO): SBD MOMSN=23979327
2025-02-26T18:01:54.994Z,1740592914.994 [transit:NeedComms:D] Stopped
2025-02-26T18:01:54.994Z,1740592914.994 [transit:NeedComms:E] Running Loop=1
2025-02-26T18:01:55.393Z,1740592915.393 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-26T18:01:56.996Z,1740592916.996 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180156.00,A,3648.93887,N,12148.67847,W,0.952,318.58,260225,,,A*7F
2025-02-26T18:01:56.998Z,1740592916.998 [NAL9602](INFO): GPS fix at 20250226T180156: (36.815648, -121.811308)
2025-02-26T18:01:57.028Z,1740592917.028 [transit:NeedComms:E] Stopped
2025-02-26T18:01:57.057Z,1740592917.057 [transit:NeedComms](INFO): Completed transit:NeedComms
2025-02-26T18:01:57.058Z,1740592917.058 [transit:NeedComms] Stopped
2025-02-26T18:01:57.058Z,1740592917.058 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-02-26T18:01:57.058Z,1740592917.058 [transit:NeedComms:B.GoToSurface] Stopped
2025-02-26T18:01:57.058Z,1740592917.058 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-26T18:02:19.038Z,1740592939.038 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.40 m/s, pitch 20.00 deg, mass-position 11.65 mm (1 active estimators).
2025-02-26T18:02:55.817Z,1740592975.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T18:03:19.308Z,1740592999.308 [Radio_Surface](INFO): Powering down
2025-02-26T18:03:26.941Z,1740593006.941 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T18:03:27.945Z,1740593007.945 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-26T18:05:03.337Z,1740593103.337 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735291
2025-02-26T18:06:59.199Z,1740593219.199 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-02-26T18:07:29.682Z,1740593249.682 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-26T18:14:55.790Z,1740593695.790 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.810249,-121.802032
2025-02-26T18:14:55.790Z,1740593695.790 [transit:Transit:Wpt1.Waypoint] Stopped
2025-02-26T18:14:55.790Z,1740593695.790 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-26T18:14:55.791Z,1740593695.791 [transit:Transit:PhoneHome] Running Loop=1
2025-02-26T18:14:55.791Z,1740593695.791 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome
2025-02-26T18:14:56.197Z,1740593696.197 [transit:NeedComms] Running Loop=1
2025-02-26T18:14:56.198Z,1740593696.198 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-02-26T18:14:56.198Z,1740593696.198 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-02-26T18:14:56.198Z,1740593696.198 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-26T18:14:56.198Z,1740593696.198 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-26T18:14:56.199Z,1740593696.199 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-26T18:14:56.199Z,1740593696.199 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-26T18:14:56.199Z,1740593696.199 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-26T18:14:56.200Z,1740593696.200 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-26T18:14:56.200Z,1740593696.200 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-26T18:14:56.200Z,1740593696.200 [transit:NeedComms:A] Running Loop=1
2025-02-26T18:14:56.202Z,1740593696.202 [transit:NeedComms:A](INFO): last time_fix was: 1740592916.000000 second since 1970/01/01T00:00:00Z
2025-02-26T18:14:56.202Z,1740593696.202 [transit:NeedComms:A] Stopped
2025-02-26T18:15:06.386Z,1740593706.386 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-02-26T18:15:06.481Z,1740593706.481 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-02-26T18:15:06.481Z,1740593706.481 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-02-26T18:15:06.481Z,1740593706.481 [ThrusterHE] Communications Fault, FailCount= 1
2025-02-26T18:15:06.481Z,1740593706.481 [ThrusterHE](ERROR): Communications Fault
2025-02-26T18:15:06.485Z,1740593706.485 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-02-26T18:15:06.835Z,1740593706.835 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-02-26T18:15:06.835Z,1740593706.835 [ThrusterHE](INFO): Powering down
2025-02-26T18:15:07.599Z,1740593707.599 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-26T18:15:07.718Z,1740593707.718 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-26T18:15:07.722Z,1740593707.722 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-02-26T18:15:07.722Z,1740593707.722 [ThrusterHE] No Fault, FailCount= 1
2025-02-26T18:15:08.969Z,1740593708.969 [transit:NeedComms:C] Running Loop=1
2025-02-26T18:15:09.709Z,1740593709.709 [Radio_Surface](INFO): Powering up
2025-02-26T18:15:15.870Z,1740593715.870 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-26T18:15:41.397Z,1740593741.397 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-02-26T18:15:41.473Z,1740593741.473 [NAL9602](ERROR): received:
+CSQ:0
OK592, 2, 0, 0, 0
OK
2025-02-26T18:15:45.993Z,1740593745.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T18:15:59.580Z,1740593759.580 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=13592, MT Status=1, MTMSN=297
2025-02-26T18:15:59.580Z,1740593759.580 [NAL9602](INFO): Data available in MT queue
2025-02-26T18:16:00.065Z,1740593760.065 [NAL9602](INFO): Received command: stop
2025-02-26T18:16:00.070Z,1740593760.070 [CommandExec](IMPORTANT): got command stop
2025-02-26T18:16:00.070Z,1740593760.070 [CommandExec](IMPORTANT): Scheduling is paused
2025-02-26T18:16:00.070Z,1740593760.070 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-26T18:16:00.116Z,1740593760.116 [MissionManager](INFO): MissionManager is completed.
2025-02-26T18:16:00.117Z,1740593760.117 [MissionManager](INFO): Uninitializing Mission transit
2025-02-26T18:16:00.121Z,1740593760.121 [transit] Stopped
2025-02-26T18:16:00.121Z,1740593760.121 [transit](DEBUG): Aggregate::uninitialize transit
2025-02-26T18:16:00.122Z,1740593760.122 [transit:NeedComms] Stopped
2025-02-26T18:16:00.122Z,1740593760.122 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-02-26T18:16:00.122Z,1740593760.122 [transit:NeedComms:B.GoToSurface] Stopped
2025-02-26T18:16:00.122Z,1740593760.122 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-26T18:16:00.122Z,1740593760.122 [transit:NeedComms:C] Stopped
2025-02-26T18:16:00.122Z,1740593760.122 [transit:StandardEnvelopes] Stopped
2025-02-26T18:16:00.122Z,1740593760.122 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-02-26T18:16:00.122Z,1740593760.122 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-26T18:16:00.122Z,1740593760.122 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-26T18:16:00.122Z,1740593760.122 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-26T18:16:00.122Z,1740593760.122 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-26T18:16:00.122Z,1740593760.122 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-26T18:16:00.122Z,1740593760.122 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-26T18:16:00.123Z,1740593760.123 [transit:BackseatDriver] Stopped
2025-02-26T18:16:00.123Z,1740593760.123 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-02-26T18:16:00.123Z,1740593760.123 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-02-26T18:16:00.123Z,1740593760.123 [transit:PowerOnly] Stopped
2025-02-26T18:16:00.123Z,1740593760.123 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-02-26T18:16:00.123Z,1740593760.123 [transit:PowerOnly:E.Wait] Stopped
2025-02-26T18:16:00.123Z,1740593760.123 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-26T18:16:00.123Z,1740593760.123 [transit:BuoyancyHold.Buoyancy] Stopped
2025-02-26T18:16:00.123Z,1740593760.123 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-26T18:16:00.123Z,1740593760.123 [transit:MassHold.Pitch] Stopped
2025-02-26T18:16:00.123Z,1740593760.123 [transit:Transit] Stopped
2025-02-26T18:16:00.123Z,1740593760.123 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-02-26T18:16:00.123Z,1740593760.123 [transit:Transit:A.Pitch] Stopped
2025-02-26T18:16:00.124Z,1740593760.124 [transit:Transit:B.SetSpeed] Stopped
2025-02-26T18:16:00.124Z,1740593760.124 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-02-26T18:16:00.124Z,1740593760.124 [transit:Transit:PhoneHome] Stopped
2025-02-26T18:16:00.124Z,1740593760.124 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome
2025-02-26T18:16:00.400Z,1740593760.400 [MissionManager](IMPORTANT): Started mission Default
2025-02-26T18:16:00.400Z,1740593760.400 [Default] Running Loop=1
2025-02-26T18:16:00.400Z,1740593760.400 [Default](DEBUG): Aggregate::initialize Default
2025-02-26T18:16:00.401Z,1740593760.401 [Default:B.GoToSurface] Running Loop=1
2025-02-26T18:16:00.401Z,1740593760.401 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-26T18:16:00.401Z,1740593760.401 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-26T18:16:00.401Z,1740593760.401 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-26T18:16:00.402Z,1740593760.402 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-26T18:16:00.402Z,1740593760.402 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-26T18:16:00.402Z,1740593760.402 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-26T18:16:00.403Z,1740593760.403 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-26T18:16:00.403Z,1740593760.403 [Default:A.Wait] Running Loop=1
2025-02-26T18:16:00.403Z,1740593760.403 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-26T18:16:13.724Z,1740593773.724 [Default:A.Wait](INFO): Done Waiting.
2025-02-26T18:16:13.724Z,1740593773.724 [Default:A.Wait] Stopped
2025-02-26T18:16:13.724Z,1740593773.724 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-26T18:16:14.129Z,1740593774.129 [Default:CheckIn] Running Loop=1
2025-02-26T18:16:14.129Z,1740593774.129 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-26T18:16:14.129Z,1740593774.129 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-26T18:16:16.139Z,1740593776.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181615.00,A,3648.62897,N,12148.12276,W,0.700,145.06,260225,,,A*73
2025-02-26T18:16:16.142Z,1740593776.142 [NAL9602](INFO): GPS fix at 20250226T181615: (36.810483, -121.802046)
2025-02-26T18:16:16.154Z,1740593776.154 [UniversalFixResidualReporter](INFO): Fix residual: 5.6 %DT, over the last 1096.6 m. Residual distance 61.0 m at bearing 158.1 degrees. Fix at (36.8105, -121.8020) with 1004.8 m made good.
2025-02-26T18:16:16.155Z,1740593776.155 [Default:CheckIn:Read_GPS] Stopped
2025-02-26T18:16:16.155Z,1740593776.155 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-26T18:16:17.117Z,1740593777.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T18:16:33.552Z,1740593793.552 [NAL9602](INFO): SBD MO Status=1, MOMSN=13593, MT Status=0, MTMSN=0
2025-02-26T18:16:33.602Z,1740593793.602 [NAL9602](INFO): Sent 82 bytes from file Logs/20250226T173940/Courier0016.lzma
2025-02-26T18:16:33.602Z,1740593793.602 [NAL9602](INFO): Packets left to send: 0
2025-02-26T18:16:47.516Z,1740593807.516 [NAL9602](INFO): SBD MO Status=1, MOMSN=13594, MT Status=0, MTMSN=0
2025-02-26T18:16:47.566Z,1740593807.566 [NAL9602](INFO): Sent 332 bytes from file Logs/20250226T173940/Express0017.lzma
2025-02-26T18:16:47.567Z,1740593807.567 [NAL9602](INFO): Packets left to send: 1
2025-02-26T18:16:48.249Z,1740593808.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T18:16:55.434Z,1740593815.434 [NAL9602](INFO): SBD MO Status=1, MOMSN=13595, MT Status=0, MTMSN=0
2025-02-26T18:16:55.482Z,1740593815.482 [NAL9602](INFO): Sent 104 bytes from file Logs/20250226T173940/Express0017.lzma
2025-02-26T18:16:55.482Z,1740593815.482 [NAL9602](INFO): Packets left to send: 0
2025-02-26T18:17:07.967Z,1740593827.967 [NAL9602](INFO): SBD MO Status=0, MOMSN=13596, MT Status=0, MTMSN=0
2025-02-26T18:17:19.398Z,1740593839.398 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-26T18:17:24.952Z,1740593844.952 [NAL9602](INFO): SBD MO Status=1, MOMSN=13597, MT Status=0, MTMSN=0
2025-02-26T18:17:25.001Z,1740593845.001 [NAL9602](INFO): Sent 39 bytes from file Logs/20250226T173940/Courier0019.lzma
2025-02-26T18:17:25.001Z,1740593845.001 [NAL9602](INFO): Packets left to send: 0
2025-02-26T18:17:28.465Z,1740593848.465 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002868
2025-02-26T18:17:33.399Z,1740593853.399 [NAL9602](INFO): SBD MO Status=1, MOMSN=13598, MT Status=0, MTMSN=0
2025-02-26T18:17:33.457Z,1740593853.457 [NAL9602](INFO): Sent 73 bytes from file Logs/20250226T173940/Express0020.lzma
2025-02-26T18:17:33.457Z,1740593853.457 [NAL9602](INFO): Packets left to send: 0
2025-02-26T18:17:35.882Z,1740593855.882 [Default:CheckIn:Read_Iridium] Stopped
2025-02-26T18:17:35.883Z,1740593855.883 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-26T18:17:35.883Z,1740593855.883 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-26T18:18:04.102Z,1740593884.102 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-26T18:22:36.412Z,1740594156.412 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-26T18:22:36.412Z,1740594156.412 [Default:CheckIn:C.Wait] Stopped
2025-02-26T18:22:36.412Z,1740594156.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-26T18:22:36.414Z,1740594156.414 [Default:CheckIn:D] Running Loop=1
2025-02-26T18:22:36.829Z,1740594156.829 [Default:CheckIn:D] Stopped
2025-02-26T18:22:36.830Z,1740594156.830 [Default:CheckIn:E] Running Loop=1
2025-02-26T18:22:37.212Z,1740594157.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.607151 min
2025-02-26T18:22:37.212Z,1740594157.212 [Default:CheckIn:E] Stopped
2025-02-26T18:22:37.212Z,1740594157.212 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-26T18:22:37.212Z,1740594157.212 [Default:CheckIn] Stopped
2025-02-26T18:22:37.212Z,1740594157.212 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-26T18:22:37.213Z,1740594157.213 [Default:CheckIn](INFO): Running loop #2
2025-02-26T18:22:37.214Z,1740594157.214 [Default:CheckIn] Running Loop=2
2025-02-26T18:22:37.214Z,1740594157.214 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-26T18:22:37.214Z,1740594157.214 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-26T18:22:39.226Z,1740594159.226 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182238.00,A,3648.64754,N,12148.13310,W,0.428,52.89,260225,,,D*41
2025-02-26T18:22:39.228Z,1740594159.228 [NAL9602](INFO): GPS fix at 20250226T182238: (36.810792, -121.802218)
2025-02-26T18:22:39.244Z,1740594159.244 [Default:CheckIn:Read_GPS] Stopped
2025-02-26T18:22:39.244Z,1740594159.244 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-26T18:22:46.933Z,1740594166.933 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250226T173940/Courier0022.lzma
2025-02-26T18:22:47.935Z,1740594167.935 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Courier0022.lzma.bak
2025-02-26T18:22:47.935Z,1740594167.935 [DataOverHttps](INFO): SBD MOMSN=23979368
2025-02-26T18:23:03.480Z,1740594183.480 [NAL9602](INFO): SBD MO Status=0, MOMSN=13599, MT Status=0, MTMSN=0
2025-02-26T18:23:03.480Z,1740594183.480 [NAL9602](INFO): No messages in MT queue
2025-02-26T18:23:06.297Z,1740594186.297 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20250226T173940/Express0023.lzma
2025-02-26T18:23:07.299Z,1740594187.299 [DataOverHttps](INFO): Moved sent file to Logs/20250226T173940/Express0023.lzma.bak
2025-02-26T18:23:07.299Z,1740594187.299 [DataOverHttps](INFO): SBD MOMSN=23979371
2025-02-26T18:23:09.575Z,1740594189.575 [Default:CheckIn:Read_Iridium] Stopped
2025-02-26T18:23:09.575Z,1740594189.575 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-26T18:23:09.575Z,1740594189.575 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-26T18:23:34.218Z,1740594214.218 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-26T18:28:04.791Z,1740594484.791 [DataOverHttps](IMPORTANT): SBD MTMSN=20250226T182803
2025-02-26T18:28:10.188Z,1740594490.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-26T18:28:10.188Z,1740594490.188 [Default:CheckIn:C.Wait] Stopped
2025-02-26T18:28:10.188Z,1740594490.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-26T18:28:10.188Z,1740594490.188 [Default:CheckIn:D] Running Loop=1
2025-02-26T18:28:10.609Z,1740594490.609 [Default:CheckIn:D] Stopped
2025-02-26T18:28:10.609Z,1740594490.609 [Default:CheckIn:E] Running Loop=1
2025-02-26T18:28:10.993Z,1740594490.993 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.170146 min
2025-02-26T18:28:10.993Z,1740594490.993 [Default:CheckIn:E] Stopped
2025-02-26T18:28:10.994Z,1740594490.994 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-26T18:28:10.994Z,1740594490.994 [Default:CheckIn] Stopped
2025-02-26T18:28:10.994Z,1740594490.994 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-26T18:28:10.994Z,1740594490.994 [Default:CheckIn](INFO): Running loop #3
2025-02-26T18:28:10.994Z,1740594490.994 [Default:CheckIn] Running Loop=3
2025-02-26T18:28:10.994Z,1740594490.994 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-26T18:28:10.994Z,1740594490.994 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-26T18:28:12.338Z,1740594492.338 [DataOverHttps](INFO): Received command: restart logs