2025-02-06T02:26:11.918Z,1738808771.918 [Supervisor](DEBUG): Initializing supervisor.
2025-02-06T02:26:11.922Z,1738808771.922 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-02-06T02:26:11.922Z,1738808771.922 [SyncHandler](INFO): Protected caller Thread ID is 1842
2025-02-06T02:26:11.923Z,1738808771.923 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-02-06T02:26:11.924Z,1738808771.924 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-02-06T02:26:11.924Z,1738808771.924 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1843
2025-02-06T02:26:11.928Z,1738808771.928 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-02-06T02:26:11.945Z,1738808771.945 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-02-06T02:26:11.946Z,1738808771.946 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-02-06T02:26:11.947Z,1738808771.947 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1844
2025-02-06T02:26:11.951Z,1738808771.951 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-02-06T02:26:11.952Z,1738808771.952 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-02-06T02:26:11.952Z,1738808771.952 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1845
2025-02-06T02:26:11.954Z,1738808771.954 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-02-06T02:26:11.955Z,1738808771.955 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-02-06T02:26:11.956Z,1738808771.956 [logger ThreadHandler](INFO): Protected caller Thread ID is 1846
2025-02-06T02:26:11.959Z,1738808771.959 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-02-06T02:26:11.960Z,1738808771.960 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-02-06T02:26:11.962Z,1738808771.962 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-02-06T02:26:12.211Z,1738808772.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-02-06T02:26:12.212Z,1738808772.212 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-02-06T02:26:12.442Z,1738808772.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-02-06T02:26:12.442Z,1738808772.442 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-02-06T02:26:12.558Z,1738808772.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-02-06T02:26:12.558Z,1738808772.558 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-02-06T02:26:12.761Z,1738808772.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-02-06T02:26:12.762Z,1738808772.762 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-02-06T02:26:13.271Z,1738808773.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-02-06T02:26:13.272Z,1738808773.272 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-02-06T02:26:13.400Z,1738808773.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-02-06T02:26:13.400Z,1738808773.400 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-02-06T02:26:13.691Z,1738808773.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-02-06T02:26:13.691Z,1738808773.691 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-02-06T02:26:13.921Z,1738808773.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-02-06T02:26:13.922Z,1738808773.922 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-02-06T02:26:14.479Z,1738808774.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-02-06T02:26:14.479Z,1738808774.479 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-02-06T02:26:14.913Z,1738808774.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-02-06T02:26:14.914Z,1738808774.914 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-02-06T02:26:15.015Z,1738808775.015 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-02-06T02:26:15.407Z,1738808775.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-02-06T02:26:15.408Z,1738808775.408 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-02-06T02:26:15.568Z,1738808775.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-02-06T02:26:15.569Z,1738808775.569 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-02-06T02:26:16.449Z,1738808776.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-02-06T02:26:16.450Z,1738808776.450 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-02-06T02:26:16.820Z,1738808776.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-02-06T02:26:16.820Z,1738808776.820 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-02-06T02:26:16.924Z,1738808776.924 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2025-02-06T02:26:16.925Z,1738808776.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2025-02-06T02:26:17.030Z,1738808777.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2025-02-06T02:26:17.156Z,1738808777.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2025-02-06T02:26:17.313Z,1738808777.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2025-02-06T02:26:17.444Z,1738808777.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2025-02-06T02:26:17.551Z,1738808777.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2025-02-06T02:26:17.675Z,1738808777.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2025-02-06T02:26:17.838Z,1738808777.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2025-02-06T02:26:18.024Z,1738808778.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2025-02-06T02:26:18.161Z,1738808778.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2025-02-06T02:26:18.446Z,1738808778.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-02-06T02:26:18.447Z,1738808778.447 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-02-06T02:26:18.456Z,1738808778.456 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-02-06T02:26:18.804Z,1738808778.804 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-02-06T02:26:18.805Z,1738808778.805 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-02-06T02:26:19.349Z,1738808779.349 [AHRS_M2] Loaded
2025-02-06T02:26:19.349Z,1738808779.349 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-02-06T02:26:19.388Z,1738808779.388 [BackseatComponent] Loaded
2025-02-06T02:26:19.388Z,1738808779.388 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-02-06T02:26:19.389Z,1738808779.389 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408AB4E0
2025-02-06T02:26:19.390Z,1738808779.390 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1925
2025-02-06T02:26:19.392Z,1738808779.392 [LcmUniversalReporter] Loaded
2025-02-06T02:26:19.393Z,1738808779.393 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-02-06T02:26:19.399Z,1738808779.399 [BioacousticsDataBridge] Loaded
2025-02-06T02:26:19.399Z,1738808779.399 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-02-06T02:26:20.210Z,1738808780.210 [BPC1] Loaded
2025-02-06T02:26:20.210Z,1738808780.210 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-02-06T02:26:20.287Z,1738808780.287 [DataOverHttps] Loaded
2025-02-06T02:26:20.288Z,1738808780.288 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-02-06T02:26:20.289Z,1738808780.289 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408DB4E0
2025-02-06T02:26:20.289Z,1738808780.289 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1926
2025-02-06T02:26:20.312Z,1738808780.312 [Depth_Keller] Loaded
2025-02-06T02:26:20.312Z,1738808780.312 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-02-06T02:26:20.317Z,1738808780.317 [DropWeight] Loaded
2025-02-06T02:26:20.317Z,1738808780.317 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-02-06T02:26:20.380Z,1738808780.380 [NAL9602] Loaded
2025-02-06T02:26:20.380Z,1738808780.380 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-02-06T02:26:20.409Z,1738808780.409 [Onboard] Loaded
2025-02-06T02:26:20.410Z,1738808780.410 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-02-06T02:26:20.410Z,1738808780.410 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2025-02-06T02:26:20.411Z,1738808780.411 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1927
2025-02-06T02:26:20.425Z,1738808780.425 [Power24vConverter] Loaded
2025-02-06T02:26:20.425Z,1738808780.425 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-02-06T02:26:20.442Z,1738808780.442 [Radio_Surface] Loaded
2025-02-06T02:26:20.442Z,1738808780.442 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-02-06T02:26:20.443Z,1738808780.443 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2025-02-06T02:26:20.444Z,1738808780.444 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1928
2025-02-06T02:26:20.459Z,1738808780.459 [Sonardyne_Nano] Loaded
2025-02-06T02:26:20.459Z,1738808780.459 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-02-06T02:26:20.460Z,1738808780.460 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-02-06T02:26:20.460Z,1738808780.460 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-02-06T02:26:20.595Z,1738808780.595 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-02-06T02:26:20.596Z,1738808780.596 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-02-06T02:26:20.649Z,1738808780.649 [VerticalControl](DEBUG): Construct VerticalControl.
2025-02-06T02:26:20.709Z,1738808780.709 [VerticalControl] Loaded
2025-02-06T02:26:20.709Z,1738808780.709 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-02-06T02:26:20.712Z,1738808780.712 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-02-06T02:26:20.829Z,1738808780.829 [HorizontalControl] Loaded
2025-02-06T02:26:20.829Z,1738808780.829 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-02-06T02:26:20.831Z,1738808780.831 [SpeedControl](DEBUG): Construct SpeedControl.
2025-02-06T02:26:20.835Z,1738808780.835 [SpeedControl] Loaded
2025-02-06T02:26:20.835Z,1738808780.835 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-02-06T02:26:20.838Z,1738808780.838 [LoopControl](DEBUG): Construct LoopControl.
2025-02-06T02:26:20.839Z,1738808780.839 [LoopControl] Loaded
2025-02-06T02:26:20.839Z,1738808780.839 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-02-06T02:26:20.839Z,1738808780.839 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-02-06T02:26:20.840Z,1738808780.840 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-02-06T02:26:20.850Z,1738808780.850 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-02-06T02:26:20.851Z,1738808780.851 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-02-06T02:26:20.877Z,1738808780.877 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-02-06T02:26:20.878Z,1738808780.878 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-02-06T02:26:20.990Z,1738808780.990 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-02-06T02:26:20.991Z,1738808780.991 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-02-06T02:26:21.004Z,1738808781.004 [NavChart] Loaded
2025-02-06T02:26:21.005Z,1738808781.005 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-02-06T02:26:21.011Z,1738808781.011 [UniversalFixResidualReporter] Loaded
2025-02-06T02:26:21.011Z,1738808781.011 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-02-06T02:26:21.011Z,1738808781.011 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-02-06T02:26:21.012Z,1738808781.012 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-02-06T02:26:21.157Z,1738808781.157 [SBIT](DEBUG): Construct Startup Built In Test.
2025-02-06T02:26:21.166Z,1738808781.166 [SBIT] Loaded
2025-02-06T02:26:21.166Z,1738808781.166 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-02-06T02:26:21.169Z,1738808781.169 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-02-06T02:26:21.182Z,1738808781.182 [IBIT] Loaded
2025-02-06T02:26:21.182Z,1738808781.182 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-02-06T02:26:21.188Z,1738808781.188 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-02-06T02:26:21.291Z,1738808781.291 [CBIT] Loaded
2025-02-06T02:26:21.292Z,1738808781.292 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-02-06T02:26:21.292Z,1738808781.292 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-02-06T02:26:21.293Z,1738808781.293 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-02-06T02:26:21.471Z,1738808781.471 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-02-06T02:26:21.471Z,1738808781.471 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-02-06T02:26:21.607Z,1738808781.607 [BuoyancyServo] Loaded
2025-02-06T02:26:21.607Z,1738808781.607 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-02-06T02:26:21.642Z,1738808781.642 [ElevatorServo] Loaded
2025-02-06T02:26:21.643Z,1738808781.643 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-02-06T02:26:21.693Z,1738808781.693 [MassServo] Loaded
2025-02-06T02:26:21.693Z,1738808781.693 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-02-06T02:26:21.719Z,1738808781.719 [RudderServo] Loaded
2025-02-06T02:26:21.719Z,1738808781.719 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-02-06T02:26:21.736Z,1738808781.736 [ThrusterHE] Loaded
2025-02-06T02:26:21.737Z,1738808781.737 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-02-06T02:26:21.737Z,1738808781.737 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-02-06T02:26:21.738Z,1738808781.738 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-02-06T02:26:21.914Z,1738808781.914 [CTD_Seabird] Loaded
2025-02-06T02:26:21.914Z,1738808781.914 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-02-06T02:26:21.915Z,1738808781.915 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B6C4E0
2025-02-06T02:26:21.916Z,1738808781.916 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1929
2025-02-06T02:26:21.938Z,1738808781.938 [PAR_Licor] Loaded
2025-02-06T02:26:21.939Z,1738808781.939 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-02-06T02:26:21.983Z,1738808781.983 [WetLabsBB2FL] Loaded
2025-02-06T02:26:21.984Z,1738808781.984 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-02-06T02:26:21.985Z,1738808781.985 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B9C4E0
2025-02-06T02:26:21.985Z,1738808781.985 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1930
2025-02-06T02:26:21.986Z,1738808781.986 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-02-06T02:26:21.986Z,1738808781.986 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-02-06T02:26:22.037Z,1738808782.037 [DepthRateCalculator] Loaded
2025-02-06T02:26:22.037Z,1738808782.037 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-02-06T02:26:22.042Z,1738808782.042 [PitchRateCalculator] Loaded
2025-02-06T02:26:22.043Z,1738808782.043 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-02-06T02:26:22.052Z,1738808782.052 [SpeedCalculator] Loaded
2025-02-06T02:26:22.052Z,1738808782.052 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-02-06T02:26:22.056Z,1738808782.056 [YawRateCalculator] Loaded
2025-02-06T02:26:22.057Z,1738808782.057 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-02-06T02:26:22.076Z,1738808782.076 [ElevatorOffsetCalculator] Loaded
2025-02-06T02:26:22.077Z,1738808782.077 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-02-06T02:26:22.077Z,1738808782.077 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-02-06T02:26:22.078Z,1738808782.078 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-02-06T02:26:22.137Z,1738808782.137 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-02-06T02:26:22.143Z,1738808782.143 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-02-06T02:26:22.146Z,1738808782.146 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-02-06T02:26:22.158Z,1738808782.158 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-02-06T02:26:22.159Z,1738808782.159 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C294E0
2025-02-06T02:26:22.159Z,1738808782.159 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1931
2025-02-06T02:26:22.163Z,1738808782.163 [Supervisor](INFO): Main Thread ID is 828
2025-02-06T02:26:22.164Z,1738808782.164 [Supervisor](DEBUG): Running supervisor.
2025-02-06T02:26:22.164Z,1738808782.164 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1932
2025-02-06T02:26:22.165Z,1738808782.165 [CommandExec](INFO): Initializing the command executive.
2025-02-06T02:26:22.166Z,1738808782.166 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1933
2025-02-06T02:26:22.168Z,1738808782.168 [controlThread ThreadHandler](INFO): Handler Thread ID is 1934
2025-02-06T02:26:22.169Z,1738808782.169 [controlThread](DEBUG): Initializing ControlThread
2025-02-06T02:26:22.176Z,1738808782.176 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-02-06T02:26:22.177Z,1738808782.177 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-02-06T02:26:22.178Z,1738808782.178 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-02-06T02:26:22.179Z,1738808782.179 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-02-06T02:26:22.179Z,1738808782.179 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-02-06T02:26:22.180Z,1738808782.180 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-02-06T02:26:22.180Z,1738808782.180 [SBIT](INFO): Initialize SBIT Component.
2025-02-06T02:26:22.181Z,1738808782.181 [SBIT](IMPORTANT): git: 2025-02-05
2025-02-06T02:26:22.181Z,1738808782.181 [SBIT](INFO): git hash: a4c59949123439bf87ea957d0729f09aa77b2fd6
2025-02-06T02:26:22.181Z,1738808782.181 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-02-06T02:26:22.182Z,1738808782.182 [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-06T02:26:22.184Z,1738808782.184 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2025-02-06T02:26:22.184Z,1738808782.184 [IBIT](INFO): Initialize IBIT Component.
2025-02-06T02:26:22.185Z,1738808782.185 [CBIT](DEBUG): Initialize CBIT Component.
2025-02-06T02:26:22.186Z,1738808782.186 [logger ThreadHandler](INFO): Handler Thread ID is 1935
2025-02-06T02:26:22.197Z,1738808782.197 [CBIT](DEBUG): Initialized mux pins.
2025-02-06T02:26:22.197Z,1738808782.197 [CBIT](DEBUG): Initializing the watchdog timer.
2025-02-06T02:26:22.206Z,1738808782.206 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1936
2025-02-06T02:26:22.218Z,1738808782.218 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1937
2025-02-06T02:26:22.219Z,1738808782.219 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-02-06T02:26:22.221Z,1738808782.221 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-02-06T02:26:22.222Z,1738808782.222 [CBIT](DEBUG): Initializing heartbeat.
2025-02-06T02:26:22.238Z,1738808782.238 [Onboard ThreadHandler](INFO): Handler Thread ID is 1938
2025-02-06T02:26:22.268Z,1738808782.268 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1939
2025-02-06T02:26:22.282Z,1738808782.282 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1940
2025-02-06T02:26:22.282Z,1738808782.282 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-02-06T02:26:22.287Z,1738808782.287 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1942
2025-02-06T02:26:22.290Z,1738808782.290 [WetLabsBB2FL](INFO): Powering up
2025-02-06T02:26:22.291Z,1738808782.291 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1944
2025-02-06T02:26:22.293Z,1738808782.293 [CBIT](DEBUG): Deactivating GF circuits.
2025-02-06T02:26:22.294Z,1738808782.294 [CBIT](DEBUG): Deactivating emergency mode.
2025-02-06T02:26:22.294Z,1738808782.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-02-06T02:26:22.294Z,1738808782.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-02-06T02:26:22.295Z,1738808782.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-02-06T02:26:22.295Z,1738808782.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-02-06T02:26:22.295Z,1738808782.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-02-06T02:26:22.295Z,1738808782.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-02-06T02:26:22.295Z,1738808782.295 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-02-06T02:26:22.295Z,1738808782.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-02-06T02:26:22.333Z,1738808782.333 [CBIT](DEBUG): Backplane powered.
2025-02-06T02:26:22.338Z,1738808782.338 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-02-06T02:26:22.338Z,1738808782.338 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-02-06T02:26:22.338Z,1738808782.338 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-02-06T02:26:22.339Z,1738808782.339 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-02-06T02:26:22.339Z,1738808782.339 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-02-06T02:26:22.340Z,1738808782.340 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-02-06T02:26:22.341Z,1738808782.341 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-02-06T02:26:22.341Z,1738808782.341 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-02-06T02:26:22.342Z,1738808782.342 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-02-06T02:26:22.412Z,1738808782.412 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-02-06T02:26:22.416Z,1738808782.416 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-06T02:26:22.475Z,1738808782.475 [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-06T02:26:22.476Z,1738808782.476 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-02-06T02:26:22.476Z,1738808782.476 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-02-06T02:26:22.493Z,1738808782.493 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-02-06T02:26:22.669Z,1738808782.669 [Radio_Surface](INFO): Powering up
2025-02-06T02:26:22.858Z,1738808782.858 [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-06T02:26:22.868Z,1738808782.868 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-02-06T02:26:22.869Z,1738808782.869 [Default:A.Wait](DEBUG): Construct Wait.
2025-02-06T02:26:22.871Z,1738808782.871 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-06T02:26:22.938Z,1738808782.938 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-02-06T02:26:22.941Z,1738808782.941 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-02-06T02:26:22.947Z,1738808782.947 [Default:E.Execute](DEBUG): Construct Execute.
2025-02-06T02:26:22.966Z,1738808782.966 [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-06T02:26:22.971Z,1738808782.971 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,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-06T02:26:22.993Z,1738808782.993 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-02-06T02:26:23.068Z,1738808783.068 [Power24vConverter](INFO): Powering up.
2025-02-06T02:26:23.069Z,1738808783.069 [Sonardyne_Nano](INFO): Initializing.
2025-02-06T02:26:23.127Z,1738808783.127 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-02-06T02:26:23.134Z,1738808783.134 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-02-06T02:26:23.135Z,1738808783.135 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-02-06T02:26:23.146Z,1738808783.146 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-02-06T02:26:23.147Z,1738808783.147 [MassServo](DEBUG): Initializing EZServoServo.
2025-02-06T02:26:23.154Z,1738808783.154 [MassServo](DEBUG): Initializing MassServo.
2025-02-06T02:26:23.154Z,1738808783.154 [RudderServo](DEBUG): Initializing EZServoServo.
2025-02-06T02:26:23.162Z,1738808783.162 [RudderServo](DEBUG): Initializing RudderServo.
2025-02-06T02:26:23.162Z,1738808783.162 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-06T02:26:23.170Z,1738808783.170 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-06T02:26:24.397Z,1738808784.397 [WetLabsBB2FL](INFO): Powering down
2025-02-06T02:26:24.000Z,1738808785.000 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-02-06T02:26:26.286Z,1738808786.286 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409
2025-02-06T02:26:28.882Z,1738808788.882 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-02-06T02:26:50.455Z,1738808810.455 [NAL9602](INFO): Powering up NAL9602
2025-02-06T02:27:01.372Z,1738808821.372 [NAL9602](INFO): NAL9602 initialized
2025-02-06T02:27:08.663Z,1738808828.663 [SBIT](IMPORTANT): Beginning Startup BIT
2025-02-06T02:27:08.668Z,1738808828.668 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-06T02:27:17.138Z,1738808837.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=12539, MT Status=0, MTMSN=0
2025-02-06T02:27:17.138Z,1738808837.138 [NAL9602](INFO): No messages in MT queue
2025-02-06T02:27:20.015Z,1738808840.015 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000399
CHAN A1 (24V): -0.000858
CHAN A2 (12V): 0.000386
CHAN A3 (5V): -0.001422
CHAN B0 (3.3V): -0.000916
CHAN B1 (3.15aV): -0.001712
CHAN B2 (3.15bV): -0.001322
CHAN B3 (GND): -0.002017
OPEN: 0.004762
Full Scale: +/- 1 mA
2025-02-06T02:28:02.948Z,1738808882.948 [SBIT](IMPORTANT): SBIT PASSED
2025-02-06T02:28:02.949Z,1738808882.949 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): DATMMP.loadAtStartup=0 bool;
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): Express none platform_roll_angle;
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-0.5 ampere_hour;
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=10 volt;
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.925 meter_per_second;
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=424.293083 cubic_centimeter;
2025-02-06T02:28:02.950Z,1738808882.950 [SBIT](IMPORTANT): VerticalControl.massDefault=6.708742 millimeter;
2025-02-06T02:28:03.406Z,1738808883.406 [MissionManager](IMPORTANT): Started mission Startup
2025-02-06T02:28:03.406Z,1738808883.406 [Startup] Running Loop=1
2025-02-06T02:28:03.406Z,1738808883.406 [Startup](DEBUG): Aggregate::initialize Startup
2025-02-06T02:28:03.406Z,1738808883.406 [Startup:A.GoToSurface] Running Loop=1
2025-02-06T02:28:03.406Z,1738808883.406 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T02:28:03.407Z,1738808883.407 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-06T02:28:03.407Z,1738808883.407 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-06T02:28:03.407Z,1738808883.407 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-06T02:28:03.408Z,1738808883.408 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-06T02:28:03.408Z,1738808883.408 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T02:28:03.408Z,1738808883.408 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T02:28:03.410Z,1738808883.410 [Startup:StartupSatComms] Running Loop=1
2025-02-06T02:28:03.410Z,1738808883.410 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-02-06T02:28:03.410Z,1738808883.410 [Startup:StartupSatComms:A] Running Loop=1
2025-02-06T02:28:03.744Z,1738808883.744 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-02-06T02:28:27.161Z,1738808907.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022823.00,A,3647.61161,N,12150.69391,W,0.233,107.38,060225,,,A*7D
2025-02-06T02:28:27.164Z,1738808907.164 [NAL9602](INFO): GPS fix at 20250206T022823: (36.793527, -121.844898)
2025-02-06T02:28:27.176Z,1738808907.176 [Startup:StartupSatComms:A] Stopped
2025-02-06T02:28:27.176Z,1738808907.176 [Startup:StartupSatComms:B] Running Loop=1
2025-02-06T02:28:27.701Z,1738808907.701 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-06T02:28:36.727Z,1738808916.727 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004194
2025-02-06T02:28:58.196Z,1738808938.196 [NAL9602](INFO): SBD MO Status=2, MOMSN=12540, MT Status=2, MTMSN=0
2025-02-06T02:28:58.196Z,1738808938.196 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-06T02:28:58.565Z,1738808938.565 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T022152/Courier0007.lzma
2025-02-06T02:28:59.567Z,1738808939.567 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022152/Courier0007.lzma.bak
2025-02-06T02:28:59.568Z,1738808939.568 [DataOverHttps](INFO): SBD MOMSN=23958614
2025-02-06T02:29:23.757Z,1738808963.757 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T022152/Courier0010.lzma
2025-02-06T02:29:24.759Z,1738808964.759 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022152/Courier0010.lzma.bak
2025-02-06T02:29:24.759Z,1738808964.759 [DataOverHttps](INFO): SBD MOMSN=23958631
2025-02-06T02:29:27.385Z,1738808967.385 [Startup:StartupSatComms:B](INFO): Timed out from 2025-02-06T02:28:27.2Z
2025-02-06T02:29:27.385Z,1738808967.385 [Startup:StartupSatComms:B] Stopped
2025-02-06T02:29:27.385Z,1738808967.385 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-02-06T02:29:27.406Z,1738808967.406 [Startup:StartupSatComms] Stopped
2025-02-06T02:29:27.407Z,1738808967.407 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-02-06T02:29:27.418Z,1738808967.418 [Startup](INFO): Completed Startup
2025-02-06T02:29:27.418Z,1738808967.418 [MissionManager](INFO): Startup is completed.
2025-02-06T02:29:27.418Z,1738808967.418 [MissionManager](INFO): Uninitializing Mission Startup
2025-02-06T02:29:27.418Z,1738808967.418 [Startup] Stopped
2025-02-06T02:29:27.418Z,1738808967.418 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-02-06T02:29:27.418Z,1738808967.418 [Startup:A.GoToSurface] Stopped
2025-02-06T02:29:27.418Z,1738808967.418 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:29:27.835Z,1738808967.835 [MissionManager](IMPORTANT): Started mission Default
2025-02-06T02:29:27.835Z,1738808967.835 [Default] Running Loop=1
2025-02-06T02:29:27.835Z,1738808967.835 [Default](DEBUG): Aggregate::initialize Default
2025-02-06T02:29:27.835Z,1738808967.835 [Default:B.GoToSurface] Running Loop=1
2025-02-06T02:29:27.836Z,1738808967.836 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T02:29:27.836Z,1738808967.836 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-06T02:29:27.836Z,1738808967.836 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-06T02:29:27.836Z,1738808967.836 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-06T02:29:27.837Z,1738808967.837 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-06T02:29:27.837Z,1738808967.837 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T02:29:27.841Z,1738808967.841 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T02:29:27.842Z,1738808967.842 [Default:A.Wait] Running Loop=1
2025-02-06T02:29:27.842Z,1738808967.842 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:29:28.994Z,1738808968.994 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T02:29:41.139Z,1738808981.139 [Default:A.Wait](INFO): Done Waiting.
2025-02-06T02:29:41.140Z,1738808981.140 [Default:A.Wait] Stopped
2025-02-06T02:29:41.140Z,1738808981.140 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:29:41.540Z,1738808981.540 [Default:CheckIn] Running Loop=1
2025-02-06T02:29:41.541Z,1738808981.541 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-06T02:29:41.541Z,1738808981.541 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-06T02:29:41.933Z,1738808981.933 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-06T02:29:42.410Z,1738808982.410 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250206T022611/Courier0000.lzma
2025-02-06T02:29:43.411Z,1738808983.411 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0000.lzma.bak
2025-02-06T02:29:43.412Z,1738808983.412 [DataOverHttps](INFO): SBD MOMSN=23958633
2025-02-06T02:29:43.553Z,1738808983.553 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022942.00,A,3647.60306,N,12150.69597,W,0.253,178.84,060225,,,A*70
2025-02-06T02:29:43.555Z,1738808983.555 [NAL9602](INFO): GPS fix at 20250206T022942: (36.793384, -121.844933)
2025-02-06T02:29:43.586Z,1738808983.586 [Default:CheckIn:Read_GPS] Stopped
2025-02-06T02:29:43.586Z,1738808983.586 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-06T02:29:43.975Z,1738808983.975 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-06T02:30:01.340Z,1738809001.340 [NAL9602](INFO): SBD MO Status=1, MOMSN=12540, MT Status=0, MTMSN=0
2025-02-06T02:30:01.340Z,1738809001.340 [NAL9602](INFO): No messages in MT queue
2025-02-06T02:30:02.877Z,1738809002.877 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250206T022611/Courier0004.lzma
2025-02-06T02:30:03.879Z,1738809003.879 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0004.lzma.bak
2025-02-06T02:30:03.879Z,1738809003.879 [DataOverHttps](INFO): SBD MOMSN=23958637
2025-02-06T02:30:08.858Z,1738809008.858 [CommandExec](IMPORTANT): got command failComponent
2025-02-06T02:30:08.858Z,1738809008.858 [CommandExec](IMPORTANT): Failed components:
2025-02-06T02:30:08.858Z,1738809008.858 [CommandExec](IMPORTANT): No failed Components.
2025-02-06T02:30:11.261Z,1738809011.261 [CommandExec](IMPORTANT): got command get latitude
2025-02-06T02:30:11.262Z,1738809011.262 [CommandExec](IMPORTANT): latitude 36.793384 arcdeg
2025-02-06T02:30:20.593Z,1738809020.593 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20250206T022152/Express0005.lzma
2025-02-06T02:30:21.595Z,1738809021.595 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022152/Express0005.lzma.bak
2025-02-06T02:30:21.595Z,1738809021.595 [DataOverHttps](INFO): SBD MOMSN=23958640
2025-02-06T02:30:32.030Z,1738809032.030 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T02:30:37.674Z,1738809037.674 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20250206T022152/Express0008.lzma
2025-02-06T02:30:38.671Z,1738809038.671 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022152/Express0008.lzma.bak
2025-02-06T02:30:38.671Z,1738809038.671 [DataOverHttps](IMPORTANT): SBD MOMSN=23958649, MTMSN=20250206T023037
2025-02-06T02:30:46.277Z,1738809046.277 [DataOverHttps](INFO): Received command: schedule resume
2025-02-06T02:30:46.312Z,1738809046.312 [CommandExec](IMPORTANT): got command schedule resume
2025-02-06T02:30:54.685Z,1738809054.685 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250206T022152/Express0011.lzma
2025-02-06T02:30:55.688Z,1738809055.688 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022152/Express0011.lzma.bak
2025-02-06T02:30:55.688Z,1738809055.688 [DataOverHttps](IMPORTANT): SBD MOMSN=23958656, MTMSN=20250206T023054
2025-02-06T02:31:03.698Z,1738809063.698 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 60 min;set transit.NeedCommsTime 30 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Speed 0.8 m/s;set transit.MaxDepth 20 m" 1ahnc 1 2
2025-02-06T02:31:03.771Z,1738809063.771 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 60 min;set transit.NeedCommsTime 30 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Speed 0.8 m/s;set transit.MaxDepth 20 m" 1ahnc 1 2.000000
2025-02-06T02:31:03.771Z,1738809063.771 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1ahnc
2025-02-06T02:31:03.772Z,1738809063.772 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='1ahnc'): "load Transport/transit.tl;set transit.MissionTimeout 60 min;set transit.NeedCommsTime 30 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Speed 0.8 m/s;set transit.MaxDepth 20 m", ASAP
2025-02-06T02:31:05.078Z,1738809065.078 [DataOverHttps](IMPORTANT): SBD MTMSN=20250206T023103
2025-02-06T02:31:15.781Z,1738809075.781 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T022611/Courier0007.lzma
2025-02-06T02:31:15.784Z,1738809075.784 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 1 km;run" 1ahnc 2 2
2025-02-06T02:31:15.888Z,1738809075.888 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 1 km;run" 1ahnc 2 2.000000
2025-02-06T02:31:15.889Z,1738809075.889 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1ahnc
2025-02-06T02:31:15.890Z,1738809075.890 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='1ahnc'): "set transit.MinOffshore 1 km;run", ASAP
2025-02-06T02:31:16.002Z,1738809076.002 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-02-06T02:31:16.003Z,1738809076.003 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-02-06T02:31:16.003Z,1738809076.003 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-02-06T02:31:16.004Z,1738809076.004 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-02-06T02:31:16.688Z,1738809076.688 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-02-06T02:31:16.697Z,1738809076.697 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-02-06T02:31:16.700Z,1738809076.700 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-02-06T02:31:16.703Z,1738809076.703 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-02-06T02:31:16.706Z,1738809076.706 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-02-06T02:31:16.709Z,1738809076.709 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-02-06T02:31:16.712Z,1738809076.712 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-02-06T02:31:16.715Z,1738809076.715 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-02-06T02:31:16.718Z,1738809076.718 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-02-06T02:31:16.721Z,1738809076.721 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-02-06T02:31:16.724Z,1738809076.724 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-02-06T02:31:16.727Z,1738809076.727 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-02-06T02:31:16.731Z,1738809076.731 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-02-06T02:31:16.734Z,1738809076.734 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-02-06T02:31:16.734Z,1738809076.734 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-06T02:31:16.735Z,1738809076.735 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-06T02:31:16.736Z,1738809076.736 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-06T02:31:17.286Z,1738809077.286 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0007.lzma.bak
2025-02-06T02:31:17.287Z,1738809077.287 [DataOverHttps](IMPORTANT): SBD MOMSN=23958663, MTMSN=20250206T023115
2025-02-06T02:31:17.294Z,1738809077.294 [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-06T02:31:17.300Z,1738809077.300 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-02-06T02:31:17.357Z,1738809077.357 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-06T02:31:17.360Z,1738809077.360 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-06T02:31:17.363Z,1738809077.363 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-02-06T02:31:17.378Z,1738809077.378 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-06T02:31:17.381Z,1738809077.381 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-06T02:31:17.384Z,1738809077.384 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-02-06T02:31:17.386Z,1738809077.386 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-02-06T02:31:17.403Z,1738809077.403 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-06T02:31:17.475Z,1738809077.475 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:31:17.475Z,1738809077.475 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:31:17.477Z,1738809077.477 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:31:17.633Z,1738809077.633 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-02-06T02:31:17.647Z,1738809077.647 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-06T02:31:17.651Z,1738809077.651 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-06T02:31:17.666Z,1738809077.666 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-06T02:31:17.668Z,1738809077.668 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-06T02:31:17.670Z,1738809077.670 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-06T02:31:17.694Z,1738809077.694 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-06T02:31:17.709Z,1738809077.709 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-06T02:31:17.774Z,1738809077.774 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-06T02:31:17.774Z,1738809077.774 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-06T02:31:17.775Z,1738809077.775 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-06T02:31:17.867Z,1738809077.867 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-02-06T02:31:17.882Z,1738809077.882 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-02-06T02:31:17.884Z,1738809077.884 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-06T02:31:17.888Z,1738809077.888 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-06T02:31:17.893Z,1738809077.893 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-06T02:31:17.895Z,1738809077.895 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-06T02:31:18.091Z,1738809078.091 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-02-06T02:31:18.108Z,1738809078.108 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-02-06T02:31:18.114Z,1738809078.114 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-02-06T02:31:18.117Z,1738809078.117 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-02-06T02:31:18.189Z,1738809078.189 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-02-06T02:31:18.201Z,1738809078.201 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-06T02:31:18.232Z,1738809078.232 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-06T02:31:18.247Z,1738809078.247 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-06T02:31:18.255Z,1738809078.255 [transit:MassHold.Pitch](DEBUG): Construct.
2025-02-06T02:31:18.287Z,1738809078.287 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-02-06T02:31:18.301Z,1738809078.301 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-02-06T02:31:18.325Z,1738809078.325 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-06T02:31:18.364Z,1738809078.364 [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-06T02:31:18.365Z,1738809078.365 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-02-06T02:31:29.549Z,1738809089.549 [DataOverHttps](INFO): Received command: sched "run Maintenance/ballast_and_trim.tl"
2025-02-06T02:31:39.783Z,1738809099.783 [CommandExec](IMPORTANT): got command set 60 minute
2025-02-06T02:31:39.783Z,1738809099.783 [CommandExec](FAULT): No target variable specified
2025-02-06T02:31:39.783Z,1738809099.783 [CommandExec](IMPORTANT): got command set 30 minute
2025-02-06T02:31:39.784Z,1738809099.784 [CommandExec](FAULT): No target variable specified
2025-02-06T02:31:39.784Z,1738809099.784 [CommandExec](IMPORTANT): got command set 36.797 degree
2025-02-06T02:31:39.784Z,1738809099.784 [CommandExec](FAULT): No target variable specified
2025-02-06T02:31:39.784Z,1738809099.784 [CommandExec](IMPORTANT): got command set -121.847 degree
2025-02-06T02:31:39.784Z,1738809099.784 [CommandExec](FAULT): No target variable specified
2025-02-06T02:31:39.785Z,1738809099.785 [CommandExec](IMPORTANT): got command set 0.8 meter_per_second
2025-02-06T02:31:39.785Z,1738809099.785 [CommandExec](FAULT): No target variable specified
2025-02-06T02:31:39.785Z,1738809099.785 [CommandExec](IMPORTANT): got command set 20 meter
2025-02-06T02:31:39.785Z,1738809099.785 [CommandExec](FAULT): No target variable specified
2025-02-06T02:31:39.793Z,1738809099.793 [CommandExec](IMPORTANT): got command schedule "run Maintenance/ballast_and_trim.tl"
2025-02-06T02:31:39.794Z,1738809099.794 [CommandExec](IMPORTANT): Scheduled #3: "run Maintenance/ballast_and_trim.tl", AFTER MISSION
2025-02-06T02:31:41.890Z,1738809101.890 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T022611/Courier0010.lzma
2025-02-06T02:31:42.649Z,1738809102.649 [CommandExec](IMPORTANT): got command set 1 kilometer
2025-02-06T02:31:42.649Z,1738809102.649 [CommandExec](FAULT): No target variable specified
2025-02-06T02:31:42.649Z,1738809102.649 [CommandExec](IMPORTANT): got command run
2025-02-06T02:31:42.651Z,1738809102.651 [CommandExec](IMPORTANT): Running
2025-02-06T02:31:42.821Z,1738809102.821 [Default] Stopped
2025-02-06T02:31:42.821Z,1738809102.821 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-06T02:31:42.821Z,1738809102.821 [Default:B.GoToSurface] Stopped
2025-02-06T02:31:42.822Z,1738809102.822 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:31:42.822Z,1738809102.822 [Default:CheckIn] Stopped
2025-02-06T02:31:42.822Z,1738809102.822 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-06T02:31:42.822Z,1738809102.822 [Default:CheckIn:Read_Iridium] Stopped
2025-02-06T02:31:42.822Z,1738809102.822 [MissionManager](IMPORTANT): Started mission transit
2025-02-06T02:31:42.822Z,1738809102.822 [transit] Running Loop=1
2025-02-06T02:31:42.822Z,1738809102.822 [transit](DEBUG): Aggregate::initialize transit
2025-02-06T02:31:42.822Z,1738809102.822 [transit:StandardEnvelopes] Running Loop=1
2025-02-06T02:31:42.823Z,1738809102.823 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-02-06T02:31:42.823Z,1738809102.823 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-06T02:31:42.823Z,1738809102.823 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-06T02:31:42.823Z,1738809102.823 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-06T02:31:42.823Z,1738809102.823 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-06T02:31:42.823Z,1738809102.823 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-06T02:31:42.823Z,1738809102.823 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-06T02:31:42.823Z,1738809102.823 [transit:BackseatDriver] Running Loop=1
2025-02-06T02:31:42.823Z,1738809102.823 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-02-06T02:31:42.824Z,1738809102.824 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-06T02:31:42.824Z,1738809102.824 [transit:PowerOnly] Running Loop=1
2025-02-06T02:31:42.824Z,1738809102.824 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-02-06T02:31:42.824Z,1738809102.824 [transit:PowerOnly:A] Running Loop=1
2025-02-06T02:31:42.824Z,1738809102.824 [transit:PowerOnly:B] Running Loop=1
2025-02-06T02:31:42.824Z,1738809102.824 [transit:PowerOnly:C] Running Loop=1
2025-02-06T02:31:42.824Z,1738809102.824 [transit:PowerOnly:D] Running Loop=1
2025-02-06T02:31:42.824Z,1738809102.824 [transit:PowerOnly:E.Wait] Running Loop=1
2025-02-06T02:31:42.824Z,1738809102.824 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:31:42.824Z,1738809102.824 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-06T02:31:42.824Z,1738809102.824 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-06T02:31:42.824Z,1738809102.824 [transit:MassHold.Pitch] Running Loop=1
2025-02-06T02:31:42.825Z,1738809102.825 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-02-06T02:31:42.825Z,1738809102.825 [transit:B] Running Loop=1
2025-02-06T02:31:42.826Z,1738809102.826 [transit:MassHold.Pitch] Running Loop=1
2025-02-06T02:31:42.826Z,1738809102.826 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-06T02:31:42.826Z,1738809102.826 [transit:PowerOnly] Running Loop=1
2025-02-06T02:31:42.827Z,1738809102.827 [transit:PowerOnly:D] Stopped
2025-02-06T02:31:42.827Z,1738809102.827 [transit:PowerOnly:C] Stopped
2025-02-06T02:31:42.827Z,1738809102.827 [transit:PowerOnly:B] Stopped
2025-02-06T02:31:42.827Z,1738809102.827 [transit:PowerOnly:A] Stopped
2025-02-06T02:31:42.827Z,1738809102.827 [transit:BackseatDriver] Running Loop=1
2025-02-06T02:31:42.827Z,1738809102.827 [transit:StandardEnvelopes] Running Loop=1
2025-02-06T02:31:42.827Z,1738809102.827 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-06T02:31:42.828Z,1738809102.828 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-06T02:31:42.829Z,1738809102.829 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-06T02:31:42.830Z,1738809102.830 [transit:B] Stopped
2025-02-06T02:31:42.830Z,1738809102.830 [transit:C] Running Loop=1
2025-02-06T02:31:42.891Z,1738809102.891 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0010.lzma.bak
2025-02-06T02:31:42.891Z,1738809102.891 [DataOverHttps](INFO): SBD MOMSN=23958667
2025-02-06T02:31:43.242Z,1738809103.242 [transit:C] Stopped
2025-02-06T02:31:43.242Z,1738809103.242 [transit:E] Running Loop=1
2025-02-06T02:31:43.618Z,1738809103.618 [transit:E] Stopped
2025-02-06T02:31:43.619Z,1738809103.619 [transit:F] Running Loop=1
2025-02-06T02:31:44.027Z,1738809104.027 [transit:F] Stopped
2025-02-06T02:31:44.027Z,1738809104.027 [transit:G] Running Loop=1
2025-02-06T02:31:44.428Z,1738809104.428 [transit:G] Stopped
2025-02-06T02:31:44.429Z,1738809104.429 [transit:Transit] Running Loop=1
2025-02-06T02:31:44.429Z,1738809104.429 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-02-06T02:31:44.429Z,1738809104.429 [transit:Transit:A.Pitch] Running Loop=1
2025-02-06T02:31:44.429Z,1738809104.429 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-02-06T02:31:44.445Z,1738809104.445 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-06T02:31:44.445Z,1738809104.445 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-02-06T02:31:44.446Z,1738809104.446 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-02-06T02:31:44.446Z,1738809104.446 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-06T02:31:44.447Z,1738809104.447 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-06T02:31:44.834Z,1738809104.834 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-06T02:31:44.835Z,1738809104.835 [transit:Transit:A.Pitch] Running Loop=1
2025-02-06T02:31:44.844Z,1738809104.844 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-02-06T02:31:44.844Z,1738809104.844 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-02-06T02:31:44.845Z,1738809104.845 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-02-06T02:31:54.356Z,1738809114.356 [transit:Transit:Wpt1.Waypoint] Stopped
2025-02-06T02:31:54.356Z,1738809114.356 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-06T02:31:54.356Z,1738809114.356 [transit:Transit:PhoneHome] Running Loop=1
2025-02-06T02:31:54.356Z,1738809114.356 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome
2025-02-06T02:31:54.787Z,1738809114.787 [transit:NeedComms] Running Loop=1
2025-02-06T02:31:54.787Z,1738809114.787 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-02-06T02:31:54.787Z,1738809114.787 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-02-06T02:31:54.787Z,1738809114.787 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T02:31:54.788Z,1738809114.788 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-06T02:31:54.788Z,1738809114.788 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-06T02:31:54.788Z,1738809114.788 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-06T02:31:54.789Z,1738809114.789 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-06T02:31:54.789Z,1738809114.789 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T02:31:54.790Z,1738809114.790 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T02:31:54.790Z,1738809114.790 [transit:NeedComms:A] Running Loop=1
2025-02-06T02:31:54.791Z,1738809114.791 [transit:NeedComms:A](INFO): last time_fix was: 1738808982.000000 second since 1970/01/01T00:00:00Z
2025-02-06T02:31:54.791Z,1738809114.791 [transit:NeedComms:A] Stopped
2025-02-06T02:31:55.162Z,1738809115.162 [transit:NeedComms:C] Running Loop=1
2025-02-06T02:31:55.606Z,1738809115.606 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-06T02:31:57.203Z,1738809117.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,023156.00,A,3647.58836,N,12150.68977,W,1.205,145.81,060225,,,D*70
2025-02-06T02:31:57.213Z,1738809117.213 [NAL9602](INFO): GPS fix at 20250206T023156: (36.793139, -121.844830)
2025-02-06T02:31:57.231Z,1738809117.231 [transit:NeedComms:C] Stopped
2025-02-06T02:31:57.231Z,1738809117.231 [transit:NeedComms:D] Running Loop=1
2025-02-06T02:31:57.641Z,1738809117.641 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-06T02:32:04.949Z,1738809124.949 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T022611/Courier0013.lzma
2025-02-06T02:32:05.951Z,1738809125.951 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0013.lzma.bak
2025-02-06T02:32:05.951Z,1738809125.951 [DataOverHttps](INFO): SBD MOMSN=23958669
2025-02-06T02:32:18.624Z,1738809138.624 [NAL9602](INFO): SBD MO Status=2, MOMSN=12541, MT Status=2, MTMSN=0
2025-02-06T02:32:18.624Z,1738809138.624 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-06T02:32:22.201Z,1738809142.201 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T022611/Courier0016.lzma
2025-02-06T02:32:23.207Z,1738809143.207 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0016.lzma.bak
2025-02-06T02:32:23.207Z,1738809143.207 [DataOverHttps](INFO): SBD MOMSN=23958671
2025-02-06T02:32:29.520Z,1738809149.520 [NAL9602](INFO): SBD MO Status=1, MOMSN=12541, MT Status=0, MTMSN=0
2025-02-06T02:32:29.520Z,1738809149.520 [NAL9602](INFO): No messages in MT queue
2025-02-06T02:32:39.573Z,1738809159.573 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T022611/Courier0019.lzma
2025-02-06T02:32:40.575Z,1738809160.575 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0019.lzma.bak
2025-02-06T02:32:40.575Z,1738809160.575 [DataOverHttps](INFO): SBD MOMSN=23958674
2025-02-06T02:32:57.039Z,1738809177.039 [DataOverHttps](INFO): Sending 953 bytes from file Logs/20250206T022611/Express0001.lzma
2025-02-06T02:32:58.039Z,1738809178.039 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0001.lzma.bak
2025-02-06T02:32:58.039Z,1738809178.039 [DataOverHttps](INFO): SBD MOMSN=23958677
2025-02-06T02:33:00.316Z,1738809180.316 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T02:33:19.017Z,1738809199.017 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20250206T022611/Express0005.lzma
2025-02-06T02:33:20.019Z,1738809200.019 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0005.lzma.bak
2025-02-06T02:33:20.019Z,1738809200.019 [DataOverHttps](INFO): SBD MOMSN=23958712
2025-02-06T02:33:36.135Z,1738809216.135 [CommandExec](IMPORTANT): got command show stack
2025-02-06T02:33:36.135Z,1738809216.135 [CommandExec](IMPORTANT): Behavior Stack:
2025-02-06T02:33:36.135Z,1738809216.135 [transit:NeedComms](IMPORTANT): Priority 0: transit:NeedComms:B.GoToSurface
2025-02-06T02:33:36.136Z,1738809216.136 [transit:NeedComms](IMPORTANT): Priority 1: transit:NeedComms:D
2025-02-06T02:33:36.136Z,1738809216.136 [transit:StandardEnvelopes](IMPORTANT): Priority 2: transit:StandardEnvelopes:A.AltitudeEnvelope
2025-02-06T02:33:36.136Z,1738809216.136 [transit:StandardEnvelopes](IMPORTANT): Priority 3: transit:StandardEnvelopes:B.DepthEnvelope
2025-02-06T02:33:36.136Z,1738809216.136 [transit:StandardEnvelopes](IMPORTANT): Priority 4: transit:StandardEnvelopes:C.OffshoreEnvelope
2025-02-06T02:33:36.137Z,1738809216.137 [transit:BackseatDriver](IMPORTANT): Priority 5: transit:BackseatDriver:A.BackseatDriver
2025-02-06T02:33:36.137Z,1738809216.137 [transit:PowerOnly](IMPORTANT): Priority 6: transit:PowerOnly:E.Wait
2025-02-06T02:33:36.137Z,1738809216.137 [transit](IMPORTANT): Priority 7: transit:BuoyancyHold.Buoyancy
2025-02-06T02:33:36.138Z,1738809216.138 [transit](IMPORTANT): Priority 8: transit:MassHold.Pitch
2025-02-06T02:33:36.138Z,1738809216.138 [transit:Transit](IMPORTANT): Priority 9: transit:Transit:A.Pitch
2025-02-06T02:33:36.138Z,1738809216.138 [transit:Transit](IMPORTANT): Priority 10: transit:Transit:B.SetSpeed
2025-02-06T02:33:36.834Z,1738809216.834 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20250206T022611/Express0008.lzma
2025-02-06T02:33:37.836Z,1738809217.836 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0008.lzma.bak
2025-02-06T02:33:37.836Z,1738809217.836 [DataOverHttps](INFO): SBD MOMSN=23958723
2025-02-06T02:33:44.520Z,1738809224.520 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2025-02-06T02:33:44.520Z,1738809224.520 [CommandExec](IMPORTANT): platform_buoyancy_position 902.888855 cc
2025-02-06T02:33:47.562Z,1738809227.562 [CommandExec](IMPORTANT): got command stop
2025-02-06T02:33:47.562Z,1738809227.562 [CommandExec](IMPORTANT): Scheduling is paused for 1 commands
2025-02-06T02:33:47.563Z,1738809227.563 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-06T02:33:47.681Z,1738809227.681 [MissionManager](INFO): MissionManager is completed.
2025-02-06T02:33:47.681Z,1738809227.681 [MissionManager](INFO): Uninitializing Mission transit
2025-02-06T02:33:47.682Z,1738809227.682 [transit] Stopped
2025-02-06T02:33:47.682Z,1738809227.682 [transit](DEBUG): Aggregate::uninitialize transit
2025-02-06T02:33:47.682Z,1738809227.682 [transit:NeedComms] Stopped
2025-02-06T02:33:47.682Z,1738809227.682 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-02-06T02:33:47.682Z,1738809227.682 [transit:NeedComms:B.GoToSurface] Stopped
2025-02-06T02:33:47.682Z,1738809227.682 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:33:47.682Z,1738809227.682 [transit:NeedComms:D] Stopped
2025-02-06T02:33:47.682Z,1738809227.682 [transit:StandardEnvelopes] Stopped
2025-02-06T02:33:47.682Z,1738809227.682 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-02-06T02:33:47.682Z,1738809227.682 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-06T02:33:47.682Z,1738809227.682 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-06T02:33:47.682Z,1738809227.682 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-06T02:33:47.682Z,1738809227.682 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-06T02:33:47.683Z,1738809227.683 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-06T02:33:47.683Z,1738809227.683 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-06T02:33:47.683Z,1738809227.683 [transit:BackseatDriver] Stopped
2025-02-06T02:33:47.683Z,1738809227.683 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-02-06T02:33:47.683Z,1738809227.683 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-02-06T02:33:47.683Z,1738809227.683 [transit:PowerOnly] Stopped
2025-02-06T02:33:47.683Z,1738809227.683 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-02-06T02:33:47.683Z,1738809227.683 [transit:PowerOnly:E.Wait] Stopped
2025-02-06T02:33:47.683Z,1738809227.683 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:33:47.683Z,1738809227.683 [transit:BuoyancyHold.Buoyancy] Stopped
2025-02-06T02:33:47.683Z,1738809227.683 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-06T02:33:47.683Z,1738809227.683 [transit:MassHold.Pitch] Stopped
2025-02-06T02:33:47.683Z,1738809227.683 [transit:Transit] Stopped
2025-02-06T02:33:47.684Z,1738809227.684 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-02-06T02:33:47.684Z,1738809227.684 [transit:Transit:A.Pitch] Stopped
2025-02-06T02:33:47.684Z,1738809227.684 [transit:Transit:B.SetSpeed] Stopped
2025-02-06T02:33:47.684Z,1738809227.684 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-02-06T02:33:47.684Z,1738809227.684 [transit:Transit:PhoneHome] Stopped
2025-02-06T02:33:47.684Z,1738809227.684 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome
2025-02-06T02:33:48.038Z,1738809228.038 [MissionManager](IMPORTANT): Started mission Default
2025-02-06T02:33:48.039Z,1738809228.039 [Default] Running Loop=1
2025-02-06T02:33:48.039Z,1738809228.039 [Default](DEBUG): Aggregate::initialize Default
2025-02-06T02:33:48.039Z,1738809228.039 [Default:B.GoToSurface] Running Loop=1
2025-02-06T02:33:48.039Z,1738809228.039 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T02:33:48.039Z,1738809228.039 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-06T02:33:48.039Z,1738809228.039 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-06T02:33:48.040Z,1738809228.040 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-06T02:33:48.040Z,1738809228.040 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-06T02:33:48.040Z,1738809228.040 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T02:33:48.041Z,1738809228.041 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T02:33:48.041Z,1738809228.041 [Default:A.Wait] Running Loop=1
2025-02-06T02:33:48.041Z,1738809228.041 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:33:56.025Z,1738809236.025 [CommandExec](IMPORTANT): got command schedule clear
2025-02-06T02:33:56.025Z,1738809236.025 [CommandExec](IMPORTANT): Cleared 1 scheduled commands.
2025-02-06T02:33:56.025Z,1738809236.025 [CommandExec](IMPORTANT): got command schedule resume
2025-02-06T02:33:56.026Z,1738809236.026 [CommandExec](IMPORTANT): Scheduling is resumed
2025-02-06T02:33:56.541Z,1738809236.541 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20250206T022611/Express0011.lzma
2025-02-06T02:33:57.543Z,1738809237.543 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0011.lzma.bak
2025-02-06T02:33:57.543Z,1738809237.543 [DataOverHttps](INFO): SBD MOMSN=23958732
2025-02-06T02:34:01.466Z,1738809241.466 [Default:A.Wait](INFO): Done Waiting.
2025-02-06T02:34:01.466Z,1738809241.466 [Default:A.Wait] Stopped
2025-02-06T02:34:01.466Z,1738809241.466 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:34:01.801Z,1738809241.801 [Default:CheckIn] Running Loop=1
2025-02-06T02:34:01.801Z,1738809241.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-06T02:34:01.801Z,1738809241.801 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-06T02:34:03.792Z,1738809243.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,023403.00,A,3647.57387,N,12150.68441,W,0.505,154.51,060225,,,D*78
2025-02-06T02:34:03.795Z,1738809243.795 [NAL9602](INFO): GPS fix at 20250206T023403: (36.792898, -121.844740)
2025-02-06T02:34:03.819Z,1738809243.819 [Default:CheckIn:Read_GPS] Stopped
2025-02-06T02:34:03.819Z,1738809243.819 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-06T02:34:12.231Z,1738809252.231 [DataOverHttps](IMPORTANT): SBD MTMSN=20250206T023411
2025-02-06T02:34:20.352Z,1738809260.352 [NAL9602](INFO): SBD MO Status=1, MOMSN=12542, MT Status=0, MTMSN=0
2025-02-06T02:34:20.352Z,1738809260.352 [NAL9602](INFO): No messages in MT queue
2025-02-06T02:34:20.715Z,1738809260.714 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250206T022611/Courier0022.lzma
2025-02-06T02:34:20.717Z,1738809260.717 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 60 min;set transit.NeedCommsTime 30 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Speed 0.8 m/s;set transit.MaxDepth 20 m" 1ahsy 1 2
2025-02-06T02:34:20.747Z,1738809260.747 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 60 min;set transit.NeedCommsTime 30 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Speed 0.8 m/s;set transit.MaxDepth 20 m" 1ahsy 1 2.000000
2025-02-06T02:34:20.747Z,1738809260.747 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1ahsy
2025-02-06T02:34:20.748Z,1738809260.748 [CommandExec](IMPORTANT): Scheduled #4 (#1 of 2 with id='1ahsy'): "load Transport/transit.tl;set transit.MissionTimeout 60 min;set transit.NeedCommsTime 30 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Speed 0.8 m/s;set transit.MaxDepth 20 m", ASAP
2025-02-06T02:34:21.036Z,1738809261.036 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0022.lzma.bak
2025-02-06T02:34:21.036Z,1738809261.036 [DataOverHttps](IMPORTANT): SBD MOMSN=23958740, MTMSN=20250206T023420
2025-02-06T02:34:28.345Z,1738809268.345 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 1 km;run" 1ahsy 2 2
2025-02-06T02:34:28.390Z,1738809268.390 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 1 km;run" 1ahsy 2 2.000000
2025-02-06T02:34:28.391Z,1738809268.391 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1ahsy
2025-02-06T02:34:28.392Z,1738809268.392 [CommandExec](IMPORTANT): Scheduled #5 (#2 of 2 with id='1ahsy'): "set transit.MinOffshore 1 km;run", ASAP
2025-02-06T02:34:28.504Z,1738809268.504 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-02-06T02:34:28.504Z,1738809268.504 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-02-06T02:34:28.505Z,1738809268.505 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-02-06T02:34:28.506Z,1738809268.506 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-02-06T02:34:29.159Z,1738809269.159 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-02-06T02:34:29.168Z,1738809269.168 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-02-06T02:34:29.170Z,1738809269.170 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-02-06T02:34:29.172Z,1738809269.172 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-02-06T02:34:29.174Z,1738809269.174 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-02-06T02:34:29.176Z,1738809269.176 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-02-06T02:34:29.178Z,1738809269.178 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-02-06T02:34:29.180Z,1738809269.180 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-02-06T02:34:29.182Z,1738809269.182 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-02-06T02:34:29.184Z,1738809269.184 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-02-06T02:34:29.186Z,1738809269.186 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-02-06T02:34:29.188Z,1738809269.188 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-02-06T02:34:29.190Z,1738809269.190 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-02-06T02:34:29.191Z,1738809269.191 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-02-06T02:34:29.192Z,1738809269.192 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-06T02:34:29.192Z,1738809269.192 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-06T02:34:29.194Z,1738809269.194 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-06T02:34:29.804Z,1738809269.804 [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-06T02:34:29.817Z,1738809269.817 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-02-06T02:34:29.819Z,1738809269.819 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-06T02:34:29.821Z,1738809269.821 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-06T02:34:29.823Z,1738809269.823 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-02-06T02:34:29.872Z,1738809269.872 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-06T02:34:29.875Z,1738809269.875 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-06T02:34:29.894Z,1738809269.894 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-02-06T02:34:29.896Z,1738809269.896 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-02-06T02:34:29.902Z,1738809269.902 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-06T02:34:29.920Z,1738809269.920 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:34:29.920Z,1738809269.920 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:34:29.921Z,1738809269.921 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:34:30.004Z,1738809270.004 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-02-06T02:34:30.007Z,1738809270.007 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-06T02:34:30.010Z,1738809270.010 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-06T02:34:30.012Z,1738809270.012 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-06T02:34:30.015Z,1738809270.015 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-06T02:34:30.015Z,1738809270.015 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-06T02:34:30.021Z,1738809270.021 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-06T02:34:30.027Z,1738809270.027 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-06T02:34:30.116Z,1738809270.116 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-06T02:34:30.116Z,1738809270.116 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-06T02:34:30.117Z,1738809270.117 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-06T02:34:30.182Z,1738809270.182 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-02-06T02:34:30.190Z,1738809270.190 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-02-06T02:34:30.192Z,1738809270.192 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-06T02:34:30.195Z,1738809270.195 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-06T02:34:30.196Z,1738809270.196 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-06T02:34:30.197Z,1738809270.197 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-06T02:34:30.340Z,1738809270.340 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-02-06T02:34:30.343Z,1738809270.343 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-02-06T02:34:30.345Z,1738809270.345 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-02-06T02:34:30.347Z,1738809270.347 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-02-06T02:34:30.349Z,1738809270.349 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-02-06T02:34:30.352Z,1738809270.352 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-06T02:34:30.358Z,1738809270.358 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-06T02:34:30.360Z,1738809270.360 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-06T02:34:30.419Z,1738809270.419 [transit:MassHold.Pitch](DEBUG): Construct.
2025-02-06T02:34:30.426Z,1738809270.426 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-02-06T02:34:30.430Z,1738809270.430 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-02-06T02:34:30.432Z,1738809270.432 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-06T02:34:30.447Z,1738809270.447 [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-06T02:34:30.447Z,1738809270.447 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-02-06T02:34:40.565Z,1738809280.565 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T022611/Courier0025.lzma
2025-02-06T02:34:41.567Z,1738809281.567 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0025.lzma.bak
2025-02-06T02:34:41.567Z,1738809281.567 [DataOverHttps](INFO): SBD MOMSN=23958747
2025-02-06T02:34:47.974Z,1738809287.974 [CommandExec](IMPORTANT): got command set 60 minute
2025-02-06T02:34:47.974Z,1738809287.974 [CommandExec](FAULT): No target variable specified
2025-02-06T02:34:47.974Z,1738809287.974 [CommandExec](IMPORTANT): got command set 30 minute
2025-02-06T02:34:47.974Z,1738809287.974 [CommandExec](FAULT): No target variable specified
2025-02-06T02:34:47.974Z,1738809287.974 [CommandExec](IMPORTANT): got command set 36.797 degree
2025-02-06T02:34:47.975Z,1738809287.975 [CommandExec](FAULT): No target variable specified
2025-02-06T02:34:47.975Z,1738809287.975 [CommandExec](IMPORTANT): got command set -121.847 degree
2025-02-06T02:34:47.975Z,1738809287.975 [CommandExec](FAULT): No target variable specified
2025-02-06T02:34:47.975Z,1738809287.975 [CommandExec](IMPORTANT): got command set 0.8 meter_per_second
2025-02-06T02:34:47.975Z,1738809287.975 [CommandExec](FAULT): No target variable specified
2025-02-06T02:34:47.976Z,1738809287.976 [CommandExec](IMPORTANT): got command set 20 meter
2025-02-06T02:34:47.976Z,1738809287.976 [CommandExec](FAULT): No target variable specified
2025-02-06T02:34:51.106Z,1738809291.106 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T02:34:52.414Z,1738809292.414 [CommandExec](IMPORTANT): got command set 1 kilometer
2025-02-06T02:34:52.414Z,1738809292.414 [CommandExec](FAULT): No target variable specified
2025-02-06T02:34:52.415Z,1738809292.415 [CommandExec](IMPORTANT): got command run
2025-02-06T02:34:52.416Z,1738809292.416 [CommandExec](IMPORTANT): Running
2025-02-06T02:34:52.746Z,1738809292.746 [Default] Stopped
2025-02-06T02:34:52.746Z,1738809292.746 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-06T02:34:52.746Z,1738809292.746 [Default:B.GoToSurface] Stopped
2025-02-06T02:34:52.746Z,1738809292.746 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:34:52.747Z,1738809292.747 [Default:CheckIn] Stopped
2025-02-06T02:34:52.747Z,1738809292.747 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-06T02:34:52.747Z,1738809292.747 [Default:CheckIn:Read_Iridium] Stopped
2025-02-06T02:34:52.747Z,1738809292.747 [MissionManager](IMPORTANT): Started mission transit
2025-02-06T02:34:52.747Z,1738809292.747 [transit] Running Loop=1
2025-02-06T02:34:52.747Z,1738809292.747 [transit](DEBUG): Aggregate::initialize transit
2025-02-06T02:34:52.747Z,1738809292.747 [transit:StandardEnvelopes] Running Loop=1
2025-02-06T02:34:52.747Z,1738809292.747 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-02-06T02:34:52.748Z,1738809292.748 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-06T02:34:52.748Z,1738809292.748 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-06T02:34:52.748Z,1738809292.748 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-06T02:34:52.748Z,1738809292.748 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-06T02:34:52.748Z,1738809292.748 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-06T02:34:52.748Z,1738809292.748 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-06T02:34:52.748Z,1738809292.748 [transit:BackseatDriver] Running Loop=1
2025-02-06T02:34:52.748Z,1738809292.748 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-02-06T02:34:52.748Z,1738809292.748 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-06T02:34:52.748Z,1738809292.748 [transit:PowerOnly] Running Loop=1
2025-02-06T02:34:52.749Z,1738809292.749 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-02-06T02:34:52.749Z,1738809292.749 [transit:PowerOnly:A] Running Loop=1
2025-02-06T02:34:52.749Z,1738809292.749 [transit:PowerOnly:B] Running Loop=1
2025-02-06T02:34:52.749Z,1738809292.749 [transit:PowerOnly:C] Running Loop=1
2025-02-06T02:34:52.749Z,1738809292.749 [transit:PowerOnly:D] Running Loop=1
2025-02-06T02:34:52.749Z,1738809292.749 [transit:PowerOnly:E.Wait] Running Loop=1
2025-02-06T02:34:52.749Z,1738809292.749 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:34:52.749Z,1738809292.749 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-06T02:34:52.749Z,1738809292.749 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-06T02:34:52.749Z,1738809292.749 [transit:MassHold.Pitch] Running Loop=1
2025-02-06T02:34:52.749Z,1738809292.749 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-02-06T02:34:52.750Z,1738809292.750 [transit:B] Running Loop=1
2025-02-06T02:34:52.750Z,1738809292.750 [transit:MassHold.Pitch] Running Loop=1
2025-02-06T02:34:52.751Z,1738809292.751 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-06T02:34:52.751Z,1738809292.751 [transit:PowerOnly] Running Loop=1
2025-02-06T02:34:52.751Z,1738809292.751 [transit:PowerOnly:D] Stopped
2025-02-06T02:34:52.751Z,1738809292.751 [transit:PowerOnly:C] Stopped
2025-02-06T02:34:52.751Z,1738809292.751 [transit:PowerOnly:B] Stopped
2025-02-06T02:34:52.751Z,1738809292.751 [transit:PowerOnly:A] Stopped
2025-02-06T02:34:52.751Z,1738809292.751 [transit:BackseatDriver] Running Loop=1
2025-02-06T02:34:52.752Z,1738809292.752 [transit:StandardEnvelopes] Running Loop=1
2025-02-06T02:34:52.752Z,1738809292.752 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-06T02:34:52.752Z,1738809292.752 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-06T02:34:52.752Z,1738809292.752 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-06T02:34:52.753Z,1738809292.753 [transit:B] Stopped
2025-02-06T02:34:52.753Z,1738809292.753 [transit:C] Running Loop=1
2025-02-06T02:34:53.129Z,1738809293.129 [transit:C] Stopped
2025-02-06T02:34:53.130Z,1738809293.130 [transit:E] Running Loop=1
2025-02-06T02:34:53.534Z,1738809293.534 [transit:E] Stopped
2025-02-06T02:34:53.534Z,1738809293.534 [transit:F] Running Loop=1
2025-02-06T02:34:53.941Z,1738809293.941 [transit:F] Stopped
2025-02-06T02:34:53.941Z,1738809293.941 [transit:G] Running Loop=1
2025-02-06T02:34:54.359Z,1738809294.359 [transit:G] Stopped
2025-02-06T02:34:54.359Z,1738809294.359 [transit:Transit] Running Loop=1
2025-02-06T02:34:54.359Z,1738809294.359 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-02-06T02:34:54.359Z,1738809294.359 [transit:Transit:A.Pitch] Running Loop=1
2025-02-06T02:34:54.359Z,1738809294.359 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-02-06T02:34:54.359Z,1738809294.359 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-06T02:34:54.359Z,1738809294.359 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-02-06T02:34:54.359Z,1738809294.359 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-02-06T02:34:54.359Z,1738809294.359 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-06T02:34:54.361Z,1738809294.361 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-06T02:34:54.747Z,1738809294.747 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-06T02:34:54.748Z,1738809294.748 [transit:Transit:A.Pitch] Running Loop=1
2025-02-06T02:34:59.627Z,1738809299.627 [transit:Transit:Wpt1.Waypoint] Stopped
2025-02-06T02:34:59.628Z,1738809299.628 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-06T02:34:59.628Z,1738809299.628 [transit:Transit:PhoneHome] Running Loop=1
2025-02-06T02:34:59.628Z,1738809299.628 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome
2025-02-06T02:35:00.040Z,1738809300.040 [transit:NeedComms] Running Loop=1
2025-02-06T02:35:00.040Z,1738809300.040 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-02-06T02:35:00.040Z,1738809300.040 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-02-06T02:35:00.040Z,1738809300.040 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T02:35:00.041Z,1738809300.041 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-06T02:35:00.049Z,1738809300.049 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-06T02:35:00.050Z,1738809300.050 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-06T02:35:00.050Z,1738809300.050 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-06T02:35:00.050Z,1738809300.050 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T02:35:00.051Z,1738809300.051 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T02:35:00.051Z,1738809300.051 [transit:NeedComms:A] Running Loop=1
2025-02-06T02:35:00.052Z,1738809300.052 [transit:NeedComms:A](INFO): last time_fix was: 1738809243.000000 second since 1970/01/01T00:00:00Z
2025-02-06T02:35:00.052Z,1738809300.052 [transit:NeedComms:A] Stopped
2025-02-06T02:35:00.425Z,1738809300.425 [transit:NeedComms:C] Running Loop=1
2025-02-06T02:35:00.890Z,1738809300.890 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-06T02:35:02.480Z,1738809302.480 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,023501.00,A,3647.56700,N,12150.68315,W,0.855,118.28,060225,,,A*7C
2025-02-06T02:35:02.483Z,1738809302.483 [NAL9602](INFO): GPS fix at 20250206T023501: (36.792783, -121.844719)
2025-02-06T02:35:02.570Z,1738809302.570 [transit:NeedComms:C] Stopped
2025-02-06T02:35:02.570Z,1738809302.570 [transit:NeedComms:D] Running Loop=1
2025-02-06T02:35:02.917Z,1738809302.917 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-06T02:35:03.853Z,1738809303.853 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T022611/Courier0028.lzma
2025-02-06T02:35:04.855Z,1738809304.855 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0028.lzma.bak
2025-02-06T02:35:04.855Z,1738809304.855 [DataOverHttps](INFO): SBD MOMSN=23958749
2025-02-06T02:35:07.566Z,1738809307.566 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2025-02-06T02:35:07.567Z,1738809307.567 [CommandExec](IMPORTANT): platform_buoyancy_position 900.605774 cc
2025-02-06T02:35:10.217Z,1738809310.217 [CommandExec](IMPORTANT): got command get depth
2025-02-06T02:35:10.218Z,1738809310.218 [CommandExec](IMPORTANT): depth 0.229032 m
2025-02-06T02:35:13.180Z,1738809313.180 [NAL9602](INFO): SBD MO Status=1, MOMSN=12543, MT Status=0, MTMSN=0
2025-02-06T02:35:13.180Z,1738809313.180 [NAL9602](INFO): No messages in MT queue
2025-02-06T02:35:19.579Z,1738809319.579 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2025-02-06T02:35:19.580Z,1738809319.580 [CommandExec](IMPORTANT): platform_buoyancy_position 900.605774 cc
2025-02-06T02:35:26.825Z,1738809326.825 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250206T022611/Courier0031.lzma
2025-02-06T02:35:27.827Z,1738809327.827 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0031.lzma.bak
2025-02-06T02:35:27.827Z,1738809327.827 [DataOverHttps](INFO): SBD MOMSN=23958751
2025-02-06T02:35:33.020Z,1738809333.020 [CommandExec](IMPORTANT): got command get SpeedControl.propOmegaAction
2025-02-06T02:35:33.021Z,1738809333.021 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction 0.000000 rad/s
2025-02-06T02:35:36.582Z,1738809336.582 [CommandExec](IMPORTANT): got command show stack
2025-02-06T02:35:36.582Z,1738809336.582 [CommandExec](IMPORTANT): Behavior Stack:
2025-02-06T02:35:36.582Z,1738809336.582 [transit:NeedComms](IMPORTANT): Priority 0: transit:NeedComms:B.GoToSurface
2025-02-06T02:35:36.582Z,1738809336.582 [transit:NeedComms](IMPORTANT): Priority 1: transit:NeedComms:D
2025-02-06T02:35:36.583Z,1738809336.583 [transit:StandardEnvelopes](IMPORTANT): Priority 2: transit:StandardEnvelopes:A.AltitudeEnvelope
2025-02-06T02:35:36.583Z,1738809336.583 [transit:StandardEnvelopes](IMPORTANT): Priority 3: transit:StandardEnvelopes:B.DepthEnvelope
2025-02-06T02:35:36.583Z,1738809336.583 [transit:StandardEnvelopes](IMPORTANT): Priority 4: transit:StandardEnvelopes:C.OffshoreEnvelope
2025-02-06T02:35:36.583Z,1738809336.583 [transit:BackseatDriver](IMPORTANT): Priority 5: transit:BackseatDriver:A.BackseatDriver
2025-02-06T02:35:36.584Z,1738809336.584 [transit:PowerOnly](IMPORTANT): Priority 6: transit:PowerOnly:E.Wait
2025-02-06T02:35:36.584Z,1738809336.584 [transit](IMPORTANT): Priority 7: transit:BuoyancyHold.Buoyancy
2025-02-06T02:35:36.584Z,1738809336.584 [transit](IMPORTANT): Priority 8: transit:MassHold.Pitch
2025-02-06T02:35:36.585Z,1738809336.585 [transit:Transit](IMPORTANT): Priority 9: transit:Transit:A.Pitch
2025-02-06T02:35:36.585Z,1738809336.585 [transit:Transit](IMPORTANT): Priority 10: transit:Transit:B.SetSpeed
2025-02-06T02:35:43.603Z,1738809343.603 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T02:35:45.893Z,1738809345.893 [DataOverHttps](INFO): Sending 258 bytes from file Logs/20250206T022611/Express0014.lzma
2025-02-06T02:35:46.896Z,1738809346.896 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0014.lzma.bak
2025-02-06T02:35:46.896Z,1738809346.896 [DataOverHttps](INFO): SBD MOMSN=23958756
2025-02-06T02:35:52.021Z,1738809352.021 [CommandExec](IMPORTANT): got command get SpeedControl.propOmegaAction
2025-02-06T02:35:52.030Z,1738809352.030 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction 0.000000 rad/s
2025-02-06T02:36:06.700Z,1738809366.700 [CommandExec](IMPORTANT): got command get SpeedControl.propOmegaAction
2025-02-06T02:36:06.701Z,1738809366.701 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction 0.000000 rad/s
2025-02-06T02:36:07.401Z,1738809367.401 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20250206T022611/Express0017.lzma
2025-02-06T02:36:08.403Z,1738809368.403 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0017.lzma.bak
2025-02-06T02:36:08.403Z,1738809368.403 [DataOverHttps](INFO): SBD MOMSN=23958763
2025-02-06T02:36:23.919Z,1738809383.919 [Power24vConverter](INFO): Powering down.
2025-02-06T02:36:24.593Z,1738809384.593 [DataOverHttps](INFO): Sending 334 bytes from file Logs/20250206T022611/Express0020.lzma
2025-02-06T02:36:25.595Z,1738809385.595 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0020.lzma.bak
2025-02-06T02:36:25.595Z,1738809385.595 [DataOverHttps](INFO): SBD MOMSN=23958767
2025-02-06T02:36:34.427Z,1738809394.427 [CommandExec](IMPORTANT): got command get SpeedControl.propOmegaAction
2025-02-06T02:36:34.438Z,1738809394.438 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction 0.000000 rad/s
2025-02-06T02:36:45.294Z,1738809405.294 [DataOverHttps](INFO): Sending 560 bytes from file Logs/20250206T022611/Express0023.lzma
2025-02-06T02:36:46.295Z,1738809406.295 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0023.lzma.bak
2025-02-06T02:36:46.296Z,1738809406.296 [DataOverHttps](INFO): SBD MOMSN=23958785
2025-02-06T02:37:04.497Z,1738809424.497 [DataOverHttps](INFO): Sending 333 bytes from file Logs/20250206T022611/Express0026.lzma
2025-02-06T02:37:05.484Z,1738809425.484 [CommandExec](IMPORTANT): got command get SpeedControl.propOmegaAction
2025-02-06T02:37:05.484Z,1738809425.484 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction 0.000000 rad/s
2025-02-06T02:37:05.499Z,1738809425.499 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0026.lzma.bak
2025-02-06T02:37:05.500Z,1738809425.500 [DataOverHttps](INFO): SBD MOMSN=23958809
2025-02-06T02:37:24.489Z,1738809444.489 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20250206T022611/Express0029.lzma
2025-02-06T02:37:25.494Z,1738809445.494 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0029.lzma.bak
2025-02-06T02:37:25.494Z,1738809445.494 [DataOverHttps](INFO): SBD MOMSN=23958815
2025-02-06T02:37:42.121Z,1738809462.121 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20250206T022611/Express0032.lzma
2025-02-06T02:37:43.123Z,1738809463.123 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0032.lzma.bak
2025-02-06T02:37:43.123Z,1738809463.123 [DataOverHttps](INFO): SBD MOMSN=23958822
2025-02-06T02:37:44.453Z,1738809464.453 [transit:NeedComms:D] Stopped
2025-02-06T02:37:44.453Z,1738809464.453 [transit:NeedComms:E] Running Loop=1
2025-02-06T02:37:44.836Z,1738809464.836 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-06T02:37:45.730Z,1738809465.730 [CommandExec](IMPORTANT): got command stop
2025-02-06T02:37:45.732Z,1738809465.732 [CommandExec](IMPORTANT): Scheduling is paused
2025-02-06T02:37:45.732Z,1738809465.732 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-06T02:37:46.074Z,1738809466.074 [MissionManager](INFO): MissionManager is completed.
2025-02-06T02:37:46.074Z,1738809466.074 [MissionManager](INFO): Uninitializing Mission transit
2025-02-06T02:37:46.074Z,1738809466.074 [transit] Stopped
2025-02-06T02:37:46.074Z,1738809466.074 [transit](DEBUG): Aggregate::uninitialize transit
2025-02-06T02:37:46.074Z,1738809466.074 [transit:NeedComms] Stopped
2025-02-06T02:37:46.074Z,1738809466.074 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-02-06T02:37:46.074Z,1738809466.074 [transit:NeedComms:B.GoToSurface] Stopped
2025-02-06T02:37:46.075Z,1738809466.075 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:37:46.075Z,1738809466.075 [transit:NeedComms:E] Stopped
2025-02-06T02:37:46.075Z,1738809466.075 [transit:StandardEnvelopes] Stopped
2025-02-06T02:37:46.075Z,1738809466.075 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-02-06T02:37:46.075Z,1738809466.075 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-06T02:37:46.075Z,1738809466.075 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-06T02:37:46.075Z,1738809466.075 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-06T02:37:46.075Z,1738809466.075 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-06T02:37:46.075Z,1738809466.075 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-06T02:37:46.075Z,1738809466.075 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-06T02:37:46.075Z,1738809466.075 [transit:BackseatDriver] Stopped
2025-02-06T02:37:46.075Z,1738809466.075 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-02-06T02:37:46.075Z,1738809466.075 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-02-06T02:37:46.075Z,1738809466.075 [transit:PowerOnly] Stopped
2025-02-06T02:37:46.076Z,1738809466.076 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-02-06T02:37:46.076Z,1738809466.076 [transit:PowerOnly:E.Wait] Stopped
2025-02-06T02:37:46.076Z,1738809466.076 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:37:46.076Z,1738809466.076 [transit:BuoyancyHold.Buoyancy] Stopped
2025-02-06T02:37:46.076Z,1738809466.076 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-06T02:37:46.076Z,1738809466.076 [transit:MassHold.Pitch] Stopped
2025-02-06T02:37:46.076Z,1738809466.076 [transit:Transit] Stopped
2025-02-06T02:37:46.076Z,1738809466.076 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-02-06T02:37:46.076Z,1738809466.076 [transit:Transit:A.Pitch] Stopped
2025-02-06T02:37:46.076Z,1738809466.076 [transit:Transit:B.SetSpeed] Stopped
2025-02-06T02:37:46.076Z,1738809466.076 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-02-06T02:37:46.076Z,1738809466.076 [transit:Transit:PhoneHome] Stopped
2025-02-06T02:37:46.076Z,1738809466.076 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome
2025-02-06T02:37:46.440Z,1738809466.440 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,023745.00,A,3647.54742,N,12150.67326,W,1.283,130.30,060225,,,A*76
2025-02-06T02:37:46.442Z,1738809466.442 [NAL9602](INFO): GPS fix at 20250206T023745: (36.792457, -121.844554)
2025-02-06T02:37:46.467Z,1738809466.467 [MissionManager](IMPORTANT): Started mission Default
2025-02-06T02:37:46.467Z,1738809466.467 [Default] Running Loop=1
2025-02-06T02:37:46.467Z,1738809466.467 [Default](DEBUG): Aggregate::initialize Default
2025-02-06T02:37:46.467Z,1738809466.467 [Default:B.GoToSurface] Running Loop=1
2025-02-06T02:37:46.467Z,1738809466.467 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T02:37:46.467Z,1738809466.467 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-06T02:37:46.468Z,1738809466.468 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-06T02:37:46.468Z,1738809466.468 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-06T02:37:46.468Z,1738809466.468 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-06T02:37:46.468Z,1738809466.468 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T02:37:46.469Z,1738809466.469 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T02:37:46.497Z,1738809466.497 [Default:A.Wait] Running Loop=1
2025-02-06T02:37:46.497Z,1738809466.497 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:37:59.784Z,1738809479.784 [Default:A.Wait](INFO): Done Waiting.
2025-02-06T02:37:59.784Z,1738809479.784 [Default:A.Wait] Stopped
2025-02-06T02:37:59.784Z,1738809479.784 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:38:00.204Z,1738809480.204 [Default:CheckIn] Running Loop=1
2025-02-06T02:38:00.204Z,1738809480.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-06T02:38:00.204Z,1738809480.204 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-06T02:38:03.816Z,1738809483.816 [NAL9602](INFO): SBD MO Status=1, MOMSN=12544, MT Status=0, MTMSN=0
2025-02-06T02:38:03.816Z,1738809483.816 [NAL9602](INFO): No messages in MT queue
2025-02-06T02:38:05.024Z,1738809485.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,023804.00,A,3647.54529,N,12150.67328,W,0.039,147.45,060225,,,D*78
2025-02-06T02:38:05.027Z,1738809485.027 [NAL9602](INFO): GPS fix at 20250206T023804: (36.792422, -121.844555)
2025-02-06T02:38:05.038Z,1738809485.038 [Default:CheckIn:Read_GPS] Stopped
2025-02-06T02:38:05.038Z,1738809485.038 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-06T02:38:12.633Z,1738809492.633 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250206T022611/Courier0034.lzma
2025-02-06T02:38:13.635Z,1738809493.635 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0034.lzma.bak
2025-02-06T02:38:13.635Z,1738809493.635 [DataOverHttps](INFO): SBD MOMSN=23958839
2025-02-06T02:38:29.690Z,1738809509.690 [DataOverHttps](INFO): Sending 565 bytes from file Logs/20250206T022611/Express0035.lzma
2025-02-06T02:38:30.691Z,1738809510.691 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0035.lzma.bak
2025-02-06T02:38:30.691Z,1738809510.691 [DataOverHttps](INFO): SBD MOMSN=23958844
2025-02-06T02:38:32.110Z,1738809512.110 [Default:CheckIn:Read_Iridium] Stopped
2025-02-06T02:38:32.110Z,1738809512.110 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-06T02:38:32.110Z,1738809512.110 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:38:37.066Z,1738809517.066 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T02:43:32.851Z,1738809812.851 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-06T02:43:32.852Z,1738809812.852 [Default:CheckIn:C.Wait] Stopped
2025-02-06T02:43:32.852Z,1738809812.852 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:43:32.852Z,1738809812.852 [Default:CheckIn:D] Running Loop=1
2025-02-06T02:43:33.248Z,1738809813.248 [Default:CheckIn:D] Stopped
2025-02-06T02:43:33.249Z,1738809813.249 [Default:CheckIn:E] Running Loop=1
2025-02-06T02:43:33.651Z,1738809813.651 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.779693 min
2025-02-06T02:43:33.651Z,1738809813.651 [Default:CheckIn:E] Stopped
2025-02-06T02:43:33.651Z,1738809813.651 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-06T02:43:33.651Z,1738809813.651 [Default:CheckIn] Stopped
2025-02-06T02:43:33.651Z,1738809813.651 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-06T02:43:33.652Z,1738809813.652 [Default:CheckIn](INFO): Running loop #2
2025-02-06T02:43:33.652Z,1738809813.652 [Default:CheckIn] Running Loop=2
2025-02-06T02:43:33.652Z,1738809813.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-06T02:43:33.652Z,1738809813.652 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-06T02:43:35.680Z,1738809815.681 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024334.00,A,3647.50355,N,12150.66246,W,0.525,143.99,060225,,,A*7E
2025-02-06T02:43:35.683Z,1738809815.683 [NAL9602](INFO): GPS fix at 20250206T024334: (36.791726, -121.844374)
2025-02-06T02:43:35.694Z,1738809815.694 [Default:CheckIn:Read_GPS] Stopped
2025-02-06T02:43:35.694Z,1738809815.694 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-06T02:43:43.673Z,1738809823.673 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250206T022611/Courier0037.lzma
2025-02-06T02:43:44.675Z,1738809824.675 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Courier0037.lzma.bak
2025-02-06T02:43:44.675Z,1738809824.675 [DataOverHttps](INFO): SBD MOMSN=23958878
2025-02-06T02:43:50.608Z,1738809830.608 [NAL9602](INFO): SBD MO Status=1, MOMSN=12545, MT Status=0, MTMSN=0
2025-02-06T02:43:50.608Z,1738809830.608 [NAL9602](INFO): No messages in MT queue
2025-02-06T02:44:00.870Z,1738809840.870 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20250206T022611/Express0038.lzma
2025-02-06T02:44:01.871Z,1738809841.871 [DataOverHttps](INFO): Moved sent file to Logs/20250206T022611/Express0038.lzma.bak
2025-02-06T02:44:01.871Z,1738809841.871 [DataOverHttps](INFO): SBD MOMSN=23958881
2025-02-06T02:44:03.179Z,1738809843.179 [Default:CheckIn:Read_Iridium] Stopped
2025-02-06T02:44:03.179Z,1738809843.179 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-06T02:44:03.179Z,1738809843.179 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:44:21.314Z,1738809861.314 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T02:45:50.339Z,1738809950.339 [CommandExec](IMPORTANT): got command restart application
2025-02-06T02:45:51.341Z,1738809951.341 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:51.342Z,1738809951.342 [CommandExec](INFO): Uninitializing the command executive.
2025-02-06T02:45:51.342Z,1738809951.342 [CommandExec](INFO): Uninitializing the command scheduler.
2025-02-06T02:45:51.342Z,1738809951.342 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:51.380Z,1738809951.380 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-02-06T02:45:51.380Z,1738809951.380 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-02-06T02:45:51.380Z,1738809951.380 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:51.381Z,1738809951.381 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:51.382Z,1738809951.382 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:51.382Z,1738809951.382 [NavChartDb](INFO): Join timeout helper Thread ID is 2161
2025-02-06T02:45:51.382Z,1738809951.382 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-02-06T02:45:51.382Z,1738809951.382 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:51.383Z,1738809951.383 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2162
2025-02-06T02:45:51.585Z,1738809951.585 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:51.586Z,1738809951.586 [WetLabsBB2FL](INFO): Powering down
2025-02-06T02:45:51.586Z,1738809951.586 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:51.601Z,1738809951.601 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-02-06T02:45:51.602Z,1738809951.602 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:51.602Z,1738809951.602 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2163
2025-02-06T02:45:52.449Z,1738809952.449 [CTD_Seabird](INFO): Powering down
2025-02-06T02:45:52.461Z,1738809952.461 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:52.461Z,1738809952.461 [CTD_Seabird](INFO): Powering down
2025-02-06T02:45:52.477Z,1738809952.477 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:52.485Z,1738809952.485 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-02-06T02:45:52.485Z,1738809952.485 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:52.486Z,1738809952.486 [Radio_Surface](INFO): Join timeout helper Thread ID is 2164
2025-02-06T02:45:52.497Z,1738809952.497 [Radio_Surface](INFO): Powering down
2025-02-06T02:45:52.498Z,1738809952.498 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:52.499Z,1738809952.499 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:52.505Z,1738809952.505 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-02-06T02:45:52.505Z,1738809952.505 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:52.506Z,1738809952.506 [Onboard](INFO): Join timeout helper Thread ID is 2165
2025-02-06T02:45:53.229Z,1738809953.229 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:53.230Z,1738809953.230 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.250Z,1738809953.250 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-02-06T02:45:53.250Z,1738809953.250 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.251Z,1738809953.251 [DataOverHttps](INFO): Join timeout helper Thread ID is 2166
2025-02-06T02:45:53.489Z,1738809953.489 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:53.490Z,1738809953.490 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.498Z,1738809953.498 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-02-06T02:45:53.498Z,1738809953.498 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.498Z,1738809953.498 [BackseatComponent](INFO): Join timeout helper Thread ID is 2167
2025-02-06T02:45:53.577Z,1738809953.577 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:53.578Z,1738809953.578 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.598Z,1738809953.598 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-02-06T02:45:53.598Z,1738809953.598 [logger ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.598Z,1738809953.598 [logger](INFO): Join timeout helper Thread ID is 2168
2025-02-06T02:45:53.619Z,1738809953.619 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:53.619Z,1738809953.619 [logger ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.622Z,1738809953.622 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-02-06T02:45:53.622Z,1738809953.622 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.623Z,1738809953.623 [CommandLine](INFO): Join timeout helper Thread ID is 2169
2025-02-06T02:45:53.649Z,1738809953.649 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:53.650Z,1738809953.650 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.666Z,1738809953.666 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-02-06T02:45:53.666Z,1738809953.666 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.666Z,1738809953.666 [CommandExec](INFO): Join timeout helper Thread ID is 2170
2025-02-06T02:45:53.668Z,1738809953.668 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-02-06T02:45:53.668Z,1738809953.668 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:53.668Z,1738809953.668 [controlThread](INFO): Join timeout helper Thread ID is 2171
2025-02-06T02:45:54.005Z,1738809954.005 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:45:54.006Z,1738809954.006 [controlThread](DEBUG): Uninitializing ControlThread
2025-02-06T02:45:54.006Z,1738809954.006 [AHRS_M2](INFO): Powering down
2025-02-06T02:45:54.079Z,1738809954.079 [NAL9602](INFO): Powering down
2025-02-06T02:45:54.080Z,1738809954.080 [Sonardyne_Nano](INFO): Powering down
2025-02-06T02:45:54.202Z,1738809954.202 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-02-06T02:45:54.203Z,1738809954.203 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-02-06T02:45:54.203Z,1738809954.203 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-02-06T02:45:54.204Z,1738809954.204 [MissionManager](INFO): Uninitializing Mission Default
2025-02-06T02:45:54.204Z,1738809954.204 [Default] Stopped
2025-02-06T02:45:54.204Z,1738809954.204 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-06T02:45:54.204Z,1738809954.204 [Default:B.GoToSurface] Stopped
2025-02-06T02:45:54.204Z,1738809954.204 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:45:54.204Z,1738809954.204 [Default:CheckIn] Stopped
2025-02-06T02:45:54.204Z,1738809954.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-06T02:45:54.204Z,1738809954.204 [Default:CheckIn:C.Wait] Stopped
2025-02-06T02:45:54.204Z,1738809954.204 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:45:54.207Z,1738809954.207 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-02-06T02:45:54.208Z,1738809954.208 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-02-06T02:45:54.208Z,1738809954.208 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-02-06T02:45:54.208Z,1738809954.208 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-02-06T02:45:54.209Z,1738809954.209 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-02-06T02:45:54.209Z,1738809954.209 [BuoyancyServo](INFO): Powering down
2025-02-06T02:45:54.221Z,1738809954.221 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-02-06T02:45:54.222Z,1738809954.222 [ElevatorServo](INFO): Powering down
2025-02-06T02:45:54.222Z,1738809954.222 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-02-06T02:45:54.222Z,1738809954.222 [MassServo](INFO): Powering down
2025-02-06T02:45:54.223Z,1738809954.223 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-02-06T02:45:54.223Z,1738809954.223 [RudderServo](INFO): Powering down
2025-02-06T02:45:54.224Z,1738809954.224 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-02-06T02:45:54.224Z,1738809954.224 [ThrusterHE](INFO): Powering down
2025-02-06T02:45:54.225Z,1738809954.225 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-02-06T02:45:54.226Z,1738809954.226 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-02-06T02:45:54.226Z,1738809954.226 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-02-06T02:45:54.226Z,1738809954.226 [CBIT](DEBUG): Powering off loads.
2025-02-06T02:45:54.237Z,1738809954.237 [CBIT](DEBUG): Disabling WDT.
2025-02-06T02:45:54.249Z,1738809954.249 [CBIT](DEBUG): Opening all GF detection circuits.
2025-02-06T02:45:54.250Z,1738809954.250 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:54.283Z,1738809954.283 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:54.286Z,1738809954.286 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:54.292Z,1738809954.292 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:54.335Z,1738809954.335 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:54.437Z,1738809954.437 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:54.442Z,1738809954.442 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:54.465Z,1738809954.465 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:45:54.536Z,1738809954.536 [logger ThreadHandler](INFO): Thread cancelled.