2025-02-06T01:52:56.117Z,1738806776.117 [Supervisor](DEBUG): Initializing supervisor.
2025-02-06T01:52:56.121Z,1738806776.121 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-02-06T01:52:56.122Z,1738806776.122 [SyncHandler](INFO): Protected caller Thread ID is 1066
2025-02-06T01:52:56.122Z,1738806776.122 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-02-06T01:52:56.123Z,1738806776.123 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-02-06T01:52:56.123Z,1738806776.123 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1067
2025-02-06T01:52:56.127Z,1738806776.127 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-02-06T01:52:56.145Z,1738806776.145 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-02-06T01:52:56.146Z,1738806776.146 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-02-06T01:52:56.146Z,1738806776.146 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1068
2025-02-06T01:52:56.150Z,1738806776.150 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-02-06T01:52:56.151Z,1738806776.151 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-02-06T01:52:56.151Z,1738806776.151 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1069
2025-02-06T01:52:56.154Z,1738806776.154 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-02-06T01:52:56.154Z,1738806776.154 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-02-06T01:52:56.155Z,1738806776.155 [logger ThreadHandler](INFO): Protected caller Thread ID is 1070
2025-02-06T01:52:56.159Z,1738806776.159 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-02-06T01:52:56.159Z,1738806776.159 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-02-06T01:52:56.160Z,1738806776.160 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-02-06T01:52:56.400Z,1738806776.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-02-06T01:52:56.401Z,1738806776.401 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-02-06T01:52:56.646Z,1738806776.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-02-06T01:52:56.647Z,1738806776.647 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-02-06T01:52:56.755Z,1738806776.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-02-06T01:52:56.756Z,1738806776.756 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-02-06T01:52:56.951Z,1738806776.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-02-06T01:52:56.952Z,1738806776.952 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-02-06T01:52:57.450Z,1738806777.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-02-06T01:52:57.451Z,1738806777.451 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-02-06T01:52:57.571Z,1738806777.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-02-06T01:52:57.572Z,1738806777.572 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-02-06T01:52:57.854Z,1738806777.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-02-06T01:52:57.854Z,1738806777.854 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-02-06T01:52:58.077Z,1738806778.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-02-06T01:52:58.077Z,1738806778.077 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-02-06T01:52:58.623Z,1738806778.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-02-06T01:52:58.623Z,1738806778.623 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-02-06T01:52:59.046Z,1738806779.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-02-06T01:52:59.047Z,1738806779.047 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-02-06T01:52:59.141Z,1738806779.141 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-02-06T01:52:59.523Z,1738806779.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-02-06T01:52:59.524Z,1738806779.524 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-02-06T01:52:59.677Z,1738806779.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-02-06T01:52:59.677Z,1738806779.677 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-02-06T01:53:00.531Z,1738806780.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-02-06T01:53:00.532Z,1738806780.532 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-02-06T01:53:00.892Z,1738806780.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-02-06T01:53:00.893Z,1738806780.893 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-02-06T01:53:00.990Z,1738806780.990 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2025-02-06T01:53:00.990Z,1738806780.990 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2025-02-06T01:53:01.088Z,1738806781.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2025-02-06T01:53:01.206Z,1738806781.206 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2025-02-06T01:53:01.338Z,1738806781.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2025-02-06T01:53:01.446Z,1738806781.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2025-02-06T01:53:01.546Z,1738806781.546 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2025-02-06T01:53:01.663Z,1738806781.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2025-02-06T01:53:01.819Z,1738806781.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2025-02-06T01:53:01.997Z,1738806781.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2025-02-06T01:53:02.148Z,1738806782.148 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2025-02-06T01:53:02.430Z,1738806782.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-02-06T01:53:02.430Z,1738806782.430 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-02-06T01:53:02.438Z,1738806782.438 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-02-06T01:53:02.782Z,1738806782.782 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-02-06T01:53:02.783Z,1738806782.783 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-02-06T01:53:03.272Z,1738806783.272 [AHRS_M2] Loaded
2025-02-06T01:53:03.273Z,1738806783.273 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-02-06T01:53:03.311Z,1738806783.311 [BackseatComponent] Loaded
2025-02-06T01:53:03.311Z,1738806783.311 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-02-06T01:53:03.312Z,1738806783.312 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408AB4E0
2025-02-06T01:53:03.313Z,1738806783.313 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1149
2025-02-06T01:53:03.316Z,1738806783.316 [LcmUniversalReporter] Loaded
2025-02-06T01:53:03.316Z,1738806783.316 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-02-06T01:53:03.322Z,1738806783.322 [BioacousticsDataBridge] Loaded
2025-02-06T01:53:03.322Z,1738806783.322 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-02-06T01:53:04.159Z,1738806784.159 [BPC1] Loaded
2025-02-06T01:53:04.160Z,1738806784.160 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-02-06T01:53:04.235Z,1738806784.235 [DataOverHttps] Loaded
2025-02-06T01:53:04.235Z,1738806784.235 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-02-06T01:53:04.236Z,1738806784.236 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408DB4E0
2025-02-06T01:53:04.237Z,1738806784.237 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1150
2025-02-06T01:53:04.259Z,1738806784.259 [Depth_Keller] Loaded
2025-02-06T01:53:04.260Z,1738806784.260 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-02-06T01:53:04.264Z,1738806784.264 [DropWeight] Loaded
2025-02-06T01:53:04.264Z,1738806784.264 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-02-06T01:53:04.327Z,1738806784.327 [NAL9602] Loaded
2025-02-06T01:53:04.327Z,1738806784.327 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-02-06T01:53:04.356Z,1738806784.356 [Onboard] Loaded
2025-02-06T01:53:04.356Z,1738806784.356 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-02-06T01:53:04.357Z,1738806784.357 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2025-02-06T01:53:04.357Z,1738806784.357 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1151
2025-02-06T01:53:04.371Z,1738806784.371 [Power24vConverter] Loaded
2025-02-06T01:53:04.371Z,1738806784.371 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-02-06T01:53:04.387Z,1738806784.387 [Radio_Surface] Loaded
2025-02-06T01:53:04.388Z,1738806784.388 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-02-06T01:53:04.389Z,1738806784.389 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2025-02-06T01:53:04.389Z,1738806784.389 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1152
2025-02-06T01:53:04.404Z,1738806784.404 [Sonardyne_Nano] Loaded
2025-02-06T01:53:04.404Z,1738806784.404 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-02-06T01:53:04.405Z,1738806784.405 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-02-06T01:53:04.405Z,1738806784.405 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-02-06T01:53:04.539Z,1738806784.539 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-02-06T01:53:04.540Z,1738806784.540 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-02-06T01:53:04.592Z,1738806784.592 [VerticalControl](DEBUG): Construct VerticalControl.
2025-02-06T01:53:04.651Z,1738806784.651 [VerticalControl] Loaded
2025-02-06T01:53:04.652Z,1738806784.652 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-02-06T01:53:04.655Z,1738806784.655 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-02-06T01:53:04.750Z,1738806784.750 [HorizontalControl] Loaded
2025-02-06T01:53:04.751Z,1738806784.751 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-02-06T01:53:04.753Z,1738806784.753 [SpeedControl](DEBUG): Construct SpeedControl.
2025-02-06T01:53:04.756Z,1738806784.756 [SpeedControl] Loaded
2025-02-06T01:53:04.757Z,1738806784.757 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-02-06T01:53:04.759Z,1738806784.759 [LoopControl](DEBUG): Construct LoopControl.
2025-02-06T01:53:04.760Z,1738806784.760 [LoopControl] Loaded
2025-02-06T01:53:04.760Z,1738806784.760 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-02-06T01:53:04.760Z,1738806784.760 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-02-06T01:53:04.761Z,1738806784.761 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-02-06T01:53:04.771Z,1738806784.771 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-02-06T01:53:04.772Z,1738806784.772 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-02-06T01:53:04.798Z,1738806784.798 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-02-06T01:53:04.798Z,1738806784.798 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-02-06T01:53:04.911Z,1738806784.911 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-02-06T01:53:04.911Z,1738806784.911 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-02-06T01:53:04.924Z,1738806784.924 [NavChart] Loaded
2025-02-06T01:53:04.925Z,1738806784.925 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-02-06T01:53:04.930Z,1738806784.930 [UniversalFixResidualReporter] Loaded
2025-02-06T01:53:04.931Z,1738806784.931 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-02-06T01:53:04.931Z,1738806784.931 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-02-06T01:53:04.932Z,1738806784.932 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-02-06T01:53:05.075Z,1738806785.075 [SBIT](DEBUG): Construct Startup Built In Test.
2025-02-06T01:53:05.084Z,1738806785.084 [SBIT] Loaded
2025-02-06T01:53:05.084Z,1738806785.084 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-02-06T01:53:05.087Z,1738806785.087 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-02-06T01:53:05.100Z,1738806785.100 [IBIT] Loaded
2025-02-06T01:53:05.100Z,1738806785.100 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-02-06T01:53:05.106Z,1738806785.106 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-02-06T01:53:05.209Z,1738806785.209 [CBIT] Loaded
2025-02-06T01:53:05.209Z,1738806785.209 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-02-06T01:53:05.209Z,1738806785.209 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-02-06T01:53:05.210Z,1738806785.210 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-02-06T01:53:05.386Z,1738806785.386 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-02-06T01:53:05.387Z,1738806785.387 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-02-06T01:53:05.521Z,1738806785.521 [BuoyancyServo] Loaded
2025-02-06T01:53:05.521Z,1738806785.521 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-02-06T01:53:05.541Z,1738806785.541 [ElevatorServo] Loaded
2025-02-06T01:53:05.542Z,1738806785.542 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-02-06T01:53:05.563Z,1738806785.563 [MassServo] Loaded
2025-02-06T01:53:05.564Z,1738806785.564 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-02-06T01:53:05.584Z,1738806785.584 [RudderServo] Loaded
2025-02-06T01:53:05.584Z,1738806785.584 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-02-06T01:53:05.601Z,1738806785.601 [ThrusterHE] Loaded
2025-02-06T01:53:05.602Z,1738806785.602 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-02-06T01:53:05.602Z,1738806785.602 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-02-06T01:53:05.602Z,1738806785.602 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-02-06T01:53:05.821Z,1738806785.821 [CTD_Seabird] Loaded
2025-02-06T01:53:05.821Z,1738806785.821 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-02-06T01:53:05.822Z,1738806785.822 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B6C4E0
2025-02-06T01:53:05.822Z,1738806785.822 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1153
2025-02-06T01:53:05.845Z,1738806785.845 [PAR_Licor] Loaded
2025-02-06T01:53:05.846Z,1738806785.846 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-02-06T01:53:05.890Z,1738806785.890 [WetLabsBB2FL] Loaded
2025-02-06T01:53:05.890Z,1738806785.890 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-02-06T01:53:05.891Z,1738806785.891 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B9C4E0
2025-02-06T01:53:05.892Z,1738806785.892 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1154
2025-02-06T01:53:05.892Z,1738806785.892 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-02-06T01:53:05.893Z,1738806785.893 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-02-06T01:53:05.943Z,1738806785.943 [DepthRateCalculator] Loaded
2025-02-06T01:53:05.943Z,1738806785.943 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-02-06T01:53:05.948Z,1738806785.948 [PitchRateCalculator] Loaded
2025-02-06T01:53:05.948Z,1738806785.948 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-02-06T01:53:05.957Z,1738806785.957 [SpeedCalculator] Loaded
2025-02-06T01:53:05.957Z,1738806785.957 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-02-06T01:53:05.962Z,1738806785.962 [YawRateCalculator] Loaded
2025-02-06T01:53:05.962Z,1738806785.962 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-02-06T01:53:05.982Z,1738806785.982 [ElevatorOffsetCalculator] Loaded
2025-02-06T01:53:05.982Z,1738806785.982 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-02-06T01:53:05.982Z,1738806785.982 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-02-06T01:53:05.983Z,1738806785.983 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-02-06T01:53:06.042Z,1738806786.042 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-02-06T01:53:06.048Z,1738806786.048 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-02-06T01:53:06.051Z,1738806786.051 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-02-06T01:53:06.062Z,1738806786.062 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-02-06T01:53:06.063Z,1738806786.063 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C294E0
2025-02-06T01:53:06.064Z,1738806786.064 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1155
2025-02-06T01:53:06.068Z,1738806786.068 [Supervisor](INFO): Main Thread ID is 828
2025-02-06T01:53:06.068Z,1738806786.068 [Supervisor](DEBUG): Running supervisor.
2025-02-06T01:53:06.069Z,1738806786.069 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1156
2025-02-06T01:53:06.070Z,1738806786.070 [CommandExec](INFO): Initializing the command executive.
2025-02-06T01:53:06.071Z,1738806786.071 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1157
2025-02-06T01:53:06.073Z,1738806786.073 [controlThread ThreadHandler](INFO): Handler Thread ID is 1158
2025-02-06T01:53:06.074Z,1738806786.074 [controlThread](DEBUG): Initializing ControlThread
2025-02-06T01:53:06.079Z,1738806786.079 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-02-06T01:53:06.081Z,1738806786.081 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-02-06T01:53:06.082Z,1738806786.082 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-02-06T01:53:06.082Z,1738806786.082 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-02-06T01:53:06.083Z,1738806786.083 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-02-06T01:53:06.083Z,1738806786.083 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-02-06T01:53:06.083Z,1738806786.083 [SBIT](INFO): Initialize SBIT Component.
2025-02-06T01:53:06.084Z,1738806786.084 [SBIT](IMPORTANT): git: 2025-02-05
2025-02-06T01:53:06.084Z,1738806786.084 [SBIT](INFO): git hash: a4c59949123439bf87ea957d0729f09aa77b2fd6
2025-02-06T01:53:06.084Z,1738806786.084 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-02-06T01:53:06.086Z,1738806786.086 [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-06T01:53:06.087Z,1738806786.087 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2025-02-06T01:53:06.087Z,1738806786.087 [IBIT](INFO): Initialize IBIT Component.
2025-02-06T01:53:06.088Z,1738806786.088 [CBIT](DEBUG): Initialize CBIT Component.
2025-02-06T01:53:06.089Z,1738806786.089 [logger ThreadHandler](INFO): Handler Thread ID is 1159
2025-02-06T01:53:06.101Z,1738806786.101 [CBIT](DEBUG): Initialized mux pins.
2025-02-06T01:53:06.101Z,1738806786.101 [CBIT](DEBUG): Initializing the watchdog timer.
2025-02-06T01:53:06.110Z,1738806786.110 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1160
2025-02-06T01:53:06.122Z,1738806786.122 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1161
2025-02-06T01:53:06.123Z,1738806786.123 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-02-06T01:53:06.125Z,1738806786.125 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-02-06T01:53:06.126Z,1738806786.126 [CBIT](DEBUG): Initializing heartbeat.
2025-02-06T01:53:06.134Z,1738806786.134 [Onboard ThreadHandler](INFO): Handler Thread ID is 1162
2025-02-06T01:53:06.151Z,1738806786.151 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1163
2025-02-06T01:53:06.170Z,1738806786.170 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1164
2025-02-06T01:53:06.170Z,1738806786.170 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-02-06T01:53:06.175Z,1738806786.175 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1166
2025-02-06T01:53:06.178Z,1738806786.178 [WetLabsBB2FL](INFO): Powering up
2025-02-06T01:53:06.179Z,1738806786.179 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1168
2025-02-06T01:53:06.182Z,1738806786.182 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-02-06T01:53:06.182Z,1738806786.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-02-06T01:53:06.182Z,1738806786.182 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-02-06T01:53:06.182Z,1738806786.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-02-06T01:53:06.182Z,1738806786.182 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-02-06T01:53:06.182Z,1738806786.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-02-06T01:53:06.183Z,1738806786.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-02-06T01:53:06.183Z,1738806786.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-02-06T01:53:06.197Z,1738806786.197 [CBIT](DEBUG): Deactivating GF circuits.
2025-02-06T01:53:06.197Z,1738806786.197 [CBIT](DEBUG): Deactivating emergency mode.
2025-02-06T01:53:06.237Z,1738806786.237 [CBIT](DEBUG): Backplane powered.
2025-02-06T01:53:06.242Z,1738806786.242 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-02-06T01:53:06.242Z,1738806786.242 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-02-06T01:53:06.242Z,1738806786.242 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-02-06T01:53:06.243Z,1738806786.243 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-02-06T01:53:06.243Z,1738806786.243 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-02-06T01:53:06.244Z,1738806786.244 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-02-06T01:53:06.245Z,1738806786.245 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-02-06T01:53:06.245Z,1738806786.245 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-02-06T01:53:06.246Z,1738806786.246 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-02-06T01:53:06.323Z,1738806786.323 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-02-06T01:53:06.328Z,1738806786.328 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-06T01:53:06.372Z,1738806786.372 [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-06T01:53:06.373Z,1738806786.373 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-02-06T01:53:06.373Z,1738806786.373 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-02-06T01:53:06.374Z,1738806786.374 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-02-06T01:53:06.565Z,1738806786.565 [Radio_Surface](INFO): Powering up
2025-02-06T01:53:06.677Z,1738806786.677 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-02-06T01:53:06.683Z,1738806786.683 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-02-06T01:53:06.684Z,1738806786.684 [Default:A.Wait](DEBUG): Construct Wait.
2025-02-06T01:53:06.703Z,1738806786.703 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-06T01:53:06.736Z,1738806786.736 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-02-06T01:53:06.751Z,1738806786.751 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-02-06T01:53:06.758Z,1738806786.758 [Default:E.Execute](DEBUG): Construct Execute.
2025-02-06T01:53:06.760Z,1738806786.760 [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-06T01:53:06.774Z,1738806786.774 [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-06T01:53:06.793Z,1738806786.793 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-02-06T01:53:06.856Z,1738806786.856 [Power24vConverter](INFO): Powering up.
2025-02-06T01:53:06.856Z,1738806786.856 [Sonardyne_Nano](INFO): Initializing.
2025-02-06T01:53:06.944Z,1738806786.944 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-02-06T01:53:06.950Z,1738806786.950 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-02-06T01:53:06.951Z,1738806786.951 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-02-06T01:53:06.958Z,1738806786.958 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-02-06T01:53:06.958Z,1738806786.958 [MassServo](DEBUG): Initializing EZServoServo.
2025-02-06T01:53:06.966Z,1738806786.966 [MassServo](DEBUG): Initializing MassServo.
2025-02-06T01:53:06.966Z,1738806786.966 [RudderServo](DEBUG): Initializing EZServoServo.
2025-02-06T01:53:06.974Z,1738806786.974 [RudderServo](DEBUG): Initializing RudderServo.
2025-02-06T01:53:06.974Z,1738806786.974 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-06T01:53:06.982Z,1738806786.982 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-06T01:53:08.285Z,1738806788.285 [WetLabsBB2FL](INFO): Powering down
2025-02-06T01:53:08.804Z,1738806788.804 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-02-06T01:53:10.090Z,1738806790.090 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409
2025-02-06T01:53:34.671Z,1738806814.671 [NAL9602](INFO): Powering up NAL9602
2025-02-06T01:53:45.583Z,1738806825.583 [NAL9602](INFO): NAL9602 initialized
2025-02-06T01:53:52.864Z,1738806832.864 [SBIT](IMPORTANT): Beginning Startup BIT
2025-02-06T01:53:52.888Z,1738806832.888 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-06T01:54:02.968Z,1738806842.968 [NAL9602](INFO): SBD MO Status=0, MOMSN=12527, MT Status=0, MTMSN=0
2025-02-06T01:54:02.968Z,1738806842.968 [NAL9602](INFO): No messages in MT queue
2025-02-06T01:54:04.250Z,1738806844.250 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000714
CHAN A1 (24V): -0.000460
CHAN A2 (12V): 0.000439
CHAN A3 (5V): -0.001842
CHAN B0 (3.3V): -0.001125
CHAN B1 (3.15aV): -0.001574
CHAN B2 (3.15bV): -0.001476
CHAN B3 (GND): -0.002089
OPEN: 0.004679
Full Scale: +/- 1 mA
2025-02-06T01:54:47.052Z,1738806887.052 [SBIT](IMPORTANT): SBIT PASSED
2025-02-06T01:54:47.052Z,1738806887.052 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-02-06T01:54:47.053Z,1738806887.053 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2025-02-06T01:54:47.053Z,1738806887.053 [SBIT](IMPORTANT): DATMMP.loadAtStartup=0 bool;

2025-02-06T01:54:47.054Z,1738806887.054 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;

2025-02-06T01:54:47.054Z,1738806887.054 [SBIT](IMPORTANT): Express none platform_roll_angle;

2025-02-06T01:54:47.054Z,1738806887.054 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.925 meter_per_second;

2025-02-06T01:54:47.054Z,1738806887.054 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=424.293083 cubic_centimeter;

2025-02-06T01:54:47.054Z,1738806887.054 [SBIT](IMPORTANT): VerticalControl.massDefault=6.708742 millimeter;

2025-02-06T01:54:47.463Z,1738806887.463 [MissionManager](IMPORTANT): Started mission Startup
2025-02-06T01:54:47.463Z,1738806887.463 [Startup] Running Loop=1
2025-02-06T01:54:47.463Z,1738806887.463 [Startup](DEBUG): Aggregate::initialize Startup
2025-02-06T01:54:47.463Z,1738806887.463 [Startup:A.GoToSurface] Running Loop=1
2025-02-06T01:54:47.463Z,1738806887.463 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T01:54:47.464Z,1738806887.464 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-06T01:54:47.464Z,1738806887.464 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-06T01:54:47.465Z,1738806887.465 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-06T01:54:47.465Z,1738806887.465 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-06T01:54:47.465Z,1738806887.465 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T01:54:47.466Z,1738806887.466 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T01:54:47.467Z,1738806887.467 [Startup:StartupSatComms] Running Loop=1
2025-02-06T01:54:47.468Z,1738806887.468 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-02-06T01:54:47.468Z,1738806887.468 [Startup:StartupSatComms:A] Running Loop=1
2025-02-06T01:54:47.872Z,1738806887.872 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-02-06T01:55:18.019Z,1738806918.019 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004380
2025-02-06T01:55:21.785Z,1738806921.785 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015518.00,A,3647.35448,N,12150.50223,W,0.505,173.04,060225,,,A*7F
2025-02-06T01:55:21.795Z,1738806921.795 [NAL9602](INFO): GPS fix at 20250206T015518: (36.789241, -121.841704)
2025-02-06T01:55:21.807Z,1738806921.807 [Startup:StartupSatComms:A] Stopped
2025-02-06T01:55:21.807Z,1738806921.807 [Startup:StartupSatComms:B] Running Loop=1
2025-02-06T01:55:22.221Z,1738806922.221 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-06T01:55:32.382Z,1738806932.382 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T014651/Courier0007.lzma
2025-02-06T01:55:33.359Z,1738806933.359 [DataOverHttps](INFO): Moved sent file to Logs/20250206T014651/Courier0007.lzma.bak
2025-02-06T01:55:33.359Z,1738806933.359 [DataOverHttps](INFO): SBD MOMSN=23958444
2025-02-06T01:55:53.569Z,1738806953.569 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250206T015256/Courier0000.lzma
2025-02-06T01:55:54.279Z,1738806954.279 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T01:55:54.571Z,1738806954.571 [DataOverHttps](INFO): Moved sent file to Logs/20250206T015256/Courier0000.lzma.bak
2025-02-06T01:55:54.571Z,1738806954.571 [DataOverHttps](INFO): SBD MOMSN=23958446
2025-02-06T01:56:12.481Z,1738806972.481 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20250206T014651/Express0008.lzma
2025-02-06T01:56:13.483Z,1738806973.483 [DataOverHttps](INFO): Moved sent file to Logs/20250206T014651/Express0008.lzma.bak
2025-02-06T01:56:13.483Z,1738806973.483 [DataOverHttps](INFO): SBD MOMSN=23958450
2025-02-06T01:56:22.182Z,1738806982.182 [Startup:StartupSatComms:B](INFO): Timed out from 2025-02-06T01:55:21.8Z
2025-02-06T01:56:22.182Z,1738806982.182 [Startup:StartupSatComms:B] Stopped
2025-02-06T01:56:22.182Z,1738806982.182 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-02-06T01:56:22.182Z,1738806982.182 [Startup:StartupSatComms] Stopped
2025-02-06T01:56:22.182Z,1738806982.182 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-02-06T01:56:22.183Z,1738806982.183 [Startup](INFO): Completed Startup
2025-02-06T01:56:22.183Z,1738806982.183 [MissionManager](INFO): Startup is completed.
2025-02-06T01:56:22.183Z,1738806982.183 [MissionManager](INFO): Uninitializing Mission Startup
2025-02-06T01:56:22.183Z,1738806982.183 [Startup] Stopped
2025-02-06T01:56:22.184Z,1738806982.184 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-02-06T01:56:22.184Z,1738806982.184 [Startup:A.GoToSurface] Stopped
2025-02-06T01:56:22.184Z,1738806982.184 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T01:56:22.597Z,1738806982.597 [MissionManager](IMPORTANT): Started mission Default
2025-02-06T01:56:22.598Z,1738806982.598 [Default] Running Loop=1
2025-02-06T01:56:22.598Z,1738806982.598 [Default](DEBUG): Aggregate::initialize Default
2025-02-06T01:56:22.598Z,1738806982.598 [Default:B.GoToSurface] Running Loop=1
2025-02-06T01:56:22.598Z,1738806982.598 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T01:56:22.598Z,1738806982.598 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-06T01:56:22.598Z,1738806982.598 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-06T01:56:22.599Z,1738806982.599 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-06T01:56:22.599Z,1738806982.599 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-06T01:56:22.599Z,1738806982.599 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T01:56:22.599Z,1738806982.599 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T01:56:22.600Z,1738806982.600 [Default:A.Wait] Running Loop=1
2025-02-06T01:56:22.600Z,1738806982.600 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-06T01:56:29.434Z,1738806989.434 [DataOverHttps](INFO): Sending 890 bytes from file Logs/20250206T015256/Express0001.lzma
2025-02-06T01:56:30.435Z,1738806990.435 [DataOverHttps](INFO): Moved sent file to Logs/20250206T015256/Express0001.lzma.bak
2025-02-06T01:56:30.435Z,1738806990.435 [DataOverHttps](INFO): SBD MOMSN=23958453
2025-02-06T01:56:35.920Z,1738806995.920 [Default:A.Wait](INFO): Done Waiting.
2025-02-06T01:56:35.920Z,1738806995.920 [Default:A.Wait] Stopped
2025-02-06T01:56:35.920Z,1738806995.920 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T01:56:36.348Z,1738806996.348 [Default:CheckIn] Running Loop=1
2025-02-06T01:56:36.348Z,1738806996.348 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-06T01:56:36.349Z,1738806996.349 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-06T01:56:36.719Z,1738806996.719 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-06T01:56:38.350Z,1738806998.350 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015634.00,A,3647.34555,N,12150.49633,W,1.244,137.13,060225,,,A*76
2025-02-06T01:56:38.353Z,1738806998.353 [NAL9602](INFO): GPS fix at 20250206T015634: (36.789093, -121.841606)
2025-02-06T01:56:38.410Z,1738806998.410 [Default:CheckIn:Read_GPS] Stopped
2025-02-06T01:56:38.410Z,1738806998.410 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-06T01:56:38.832Z,1738806998.832 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-06T01:56:54.009Z,1738807014.009 [NAL9602](INFO): SBD MO Status=0, MOMSN=12528, MT Status=0, MTMSN=0
2025-02-06T01:56:54.009Z,1738807014.009 [NAL9602](INFO): No messages in MT queue
2025-02-06T01:56:57.397Z,1738807017.397 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250206T015256/Courier0004.lzma
2025-02-06T01:56:58.402Z,1738807018.402 [DataOverHttps](INFO): Moved sent file to Logs/20250206T015256/Courier0004.lzma.bak
2025-02-06T01:56:58.402Z,1738807018.402 [DataOverHttps](INFO): SBD MOMSN=23958473
2025-02-06T01:57:19.157Z,1738807039.157 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20250206T015256/Express0005.lzma
2025-02-06T01:57:20.159Z,1738807040.159 [DataOverHttps](INFO): Moved sent file to Logs/20250206T015256/Express0005.lzma.bak
2025-02-06T01:57:20.159Z,1738807040.159 [DataOverHttps](INFO): SBD MOMSN=23958476
2025-02-06T01:57:21.506Z,1738807041.506 [Default:CheckIn:Read_Iridium] Stopped
2025-02-06T01:57:21.506Z,1738807041.506 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-06T01:57:21.506Z,1738807041.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-06T01:57:24.722Z,1738807044.722 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T01:58:48.710Z,1738807128.710 [CommandExec](IMPORTANT): got command failComponent
2025-02-06T01:58:48.710Z,1738807128.710 [CommandExec](IMPORTANT): Failed components:
2025-02-06T01:58:48.711Z,1738807128.711 [CommandExec](IMPORTANT): No failed Components.
2025-02-06T01:59:15.628Z,1738807155.628 [CommandExec](IMPORTANT): got command show variable threshold
2025-02-06T01:59:15.677Z,1738807155.677 [CommandExec](IMPORTANT): StratificationFrontDetector.threshold_VTHI (celsius)
2025-02-06T01:59:15.678Z,1738807155.678 [CommandExec](IMPORTANT): StratificationFrontDetector.threshold_VSHI (practical_salinity_unit)
2025-02-06T01:59:15.678Z,1738807155.678 [CommandExec](IMPORTANT): DepAvgTempFrontDetector.cntThreshold (count)
2025-02-06T01:59:15.679Z,1738807155.679 [CommandExec](IMPORTANT): DepAvgTempFrontDetector.tempHoriGradThreshold (celsius)
2025-02-06T01:59:15.687Z,1738807155.687 [CommandExec](IMPORTANT): CBIT.humidityThreshold (percent)
2025-02-06T01:59:15.687Z,1738807155.687 [CommandExec](IMPORTANT): CBIT.pressureThreshold (pound_per_square_inch)
2025-02-06T01:59:15.688Z,1738807155.688 [CommandExec](IMPORTANT): CBIT.tempThreshold (fahrenheit)
2025-02-06T01:59:15.688Z,1738807155.688 [CommandExec](IMPORTANT): CBIT.navErrorThreshold (percent)
2025-02-06T01:59:15.689Z,1738807155.689 [CommandExec](IMPORTANT): CBIT.battTempThreshold (celsius)
2025-02-06T01:59:15.690Z,1738807155.690 [CommandExec](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere)
2025-02-06T01:59:15.690Z,1738807155.690 [CommandExec](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere)
2025-02-06T01:59:15.690Z,1738807155.690 [CommandExec](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere)
2025-02-06T01:59:15.691Z,1738807155.691 [CommandExec](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere)
2025-02-06T01:59:15.691Z,1738807155.691 [CommandExec](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere)
2025-02-06T01:59:15.691Z,1738807155.691 [CommandExec](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere)
2025-02-06T01:59:15.692Z,1738807155.692 [CommandExec](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere)
2025-02-06T01:59:15.692Z,1738807155.692 [CommandExec](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere)
2025-02-06T01:59:15.694Z,1738807155.694 [CommandExec](IMPORTANT): IBIT.batteryCapacityThreshold (ampere_hour)
2025-02-06T01:59:15.694Z,1738807155.694 [CommandExec](IMPORTANT): IBIT.batteryVoltageThreshold (volt)
2025-02-06T01:59:15.815Z,1738807155.815 [CommandExec](IMPORTANT): AMEcho.depthThreshold (meter)
2025-02-06T01:59:15.817Z,1738807155.817 [CommandExec](IMPORTANT): AcousticModem_Benthos_ATM900.surfaceThreshold (meter)
2025-02-06T01:59:15.819Z,1738807155.819 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold (count)
2025-02-06T01:59:15.867Z,1738807155.867 [CommandExec](IMPORTANT): DAT.surfaceThreshold (meter)
2025-02-06T01:59:15.868Z,1738807155.868 [CommandExec](IMPORTANT): DATMMP.surfaceThreshold (meter)
2025-02-06T01:59:15.869Z,1738807155.869 [CommandExec](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2025-02-06T01:59:15.888Z,1738807155.888 [CommandExec](IMPORTANT): Micromodem.surfaceThreshold (meter)
2025-02-06T01:59:15.963Z,1738807155.963 [CommandExec](IMPORTANT): VerticalControl.surfaceThreshold (meter)
2025-02-06T01:59:22.516Z,1738807162.516 [CommandExec](IMPORTANT): got command get IBIT.batteryCapacityThreshold
2025-02-06T01:59:22.517Z,1738807162.517 [CommandExec](IMPORTANT): IBIT.batteryCapacityThreshold -0.500000 Ah
2025-02-06T02:00:01.676Z,1738807201.676 [DataOverHttps](IMPORTANT): SBD MTMSN=20250206T020000
2025-02-06T02:00:09.222Z,1738807209.222 [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 1 m/s;set transit.MaxDepth 20 m" 1ag76 1 2
2025-02-06T02:00:09.260Z,1738807209.260 [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 1 m/s;set transit.MaxDepth 20 m" 1ag76 1 2.000000
2025-02-06T02:00:09.260Z,1738807209.260 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1ag76
2025-02-06T02:00:09.261Z,1738807209.261 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='1ag76'): "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 1 m/s;set transit.MaxDepth 20 m", ASAP
2025-02-06T02:00:10.224Z,1738807210.224 [DataOverHttps](IMPORTANT): SBD MTMSN=20250206T020009
2025-02-06T02:00:17.769Z,1738807217.769 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 1 km;run" 1ag76 2 2
2025-02-06T02:00:17.792Z,1738807217.792 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 1 km;run" 1ag76 2 2.000000
2025-02-06T02:00:17.793Z,1738807217.793 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1ag76
2025-02-06T02:00:17.793Z,1738807217.793 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='1ag76'): "set transit.MinOffshore 1 km;run", ASAP
2025-02-06T02:00:17.908Z,1738807217.908 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-02-06T02:00:17.908Z,1738807217.908 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-02-06T02:00:17.908Z,1738807217.908 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-02-06T02:00:17.913Z,1738807217.913 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-02-06T02:00:18.648Z,1738807218.648 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="transit">
    <DefineArg Name="MissionTimeout">
        <Units:minute />
        <Value>60</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Northings">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="Eastings">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="MassHold">
        <True />
    </DefineArg>
    <DefineArg Name="BuoyancyHold">
        <True />
    </DefineArg>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:WaitForPitchUp" />
        <Units:second />
        <Value>1</Value>
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinAltitude" />
        <Arg Name="MinAltitude" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MaxDepth" />
        <Arg Name="MaxDepth" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinOffshore" />
        <Arg Name="MinOffshore" />
    </Assign>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:Buoyancy Id="BuoyancyHold">
        <While>
            <Arg Name="BuoyancyHold" />
        </While>
        <Setting>
            <Guidance:Buoyancy.position />
            <Control:VerticalControl.buoyancyNeutral />
        </Setting>
    </Guidance:Buoyancy>
    <Guidance:Pitch Id="MassHold">
        <While>
            <Arg Name="MassHold" />
        </While>
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="Transit">
        <Sequence />
        <Guidance:Pitch>
            <Parallel />
            <Setting>
                <Guidance:Pitch.depth />
                <Arg Name="Depth" />
            </Setting>
        </Guidance:Pitch>
        <Guidance:SetSpeed>
            <Parallel />
            <Setting>
                <Guidance:SetSpeed.speed />
                <Arg Name="Speed" />
            </Setting>
        </Guidance:SetSpeed>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.northingsDelta />
                <Arg Name="Northings" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.eastingsDelta />
                <Arg Name="Eastings" />
            </Setting>
        </Guidance:Waypoint>
        <Call RefId="NeedComms" Id="PhoneHome" />
    </Aggregate>
</Mission>

2025-02-06T02:00:18.654Z,1738807218.654 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-02-06T02:00:18.659Z,1738807218.659 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-02-06T02:00:18.662Z,1738807218.662 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-02-06T02:00:18.666Z,1738807218.666 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-02-06T02:00:18.669Z,1738807218.669 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-02-06T02:00:18.672Z,1738807218.672 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-02-06T02:00:18.675Z,1738807218.675 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-02-06T02:00:18.678Z,1738807218.678 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-02-06T02:00:18.682Z,1738807218.682 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-02-06T02:00:18.685Z,1738807218.685 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-02-06T02:00:18.688Z,1738807218.688 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-02-06T02:00:18.690Z,1738807218.690 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-02-06T02:00:18.693Z,1738807218.693 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-02-06T02:00:18.693Z,1738807218.693 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-06T02:00:18.694Z,1738807218.694 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-06T02:00:18.695Z,1738807218.695 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-06T02:00:19.063Z,1738807219.063 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="NeedComms">
    <DefineArg Name="DiveInterval">
        <Units:hour />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="WaitForPitchUp">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SurfacePitch">
        <Units:degree />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="SurfaceDepthRate">
        <Units:meter_per_second />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="SurfaceSpeed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="SurfacingTimeout">
        <Units:second />
        <Value>1000</Value>
    </DefineArg>
    <DefineArg Name="GPSTimeout">
        <Units:minute />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="CommsTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <When>
        <Called />
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
                <Add>
                    <Arg Name="WaitForPitchUp" />
                </Add>
            </Gt>
        </Or>
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
            </Gt>
            <And>
                <Universal:platform_pitch_angle />
                <Gt>
                    <Units:degree />
                    <Value>0</Value>
                </Gt>
            </And>
        </Or>
    </When>
    <Syslog Severity="Info">
        last time_fix was: 
        <Universal:time_fix />
        <Units:epoch_second />
    </Syslog>
    <Guidance:GoToSurface>
        <Progression />
        <Setting>
            <Guidance:GoToSurface.pitch />
            <Arg Name="SurfacePitch" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.depthRate />
            <Arg Name="SurfaceDepthRate" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.speed />
            <Arg Name="SurfaceSpeed" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.surfaceTimeout />
            <Arg Name="SurfacingTimeout" />
        </Setting>
    </Guidance:GoToSurface>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">first GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="CommsTimeout">
            <Syslog Severity="Important">
                Comms timed out after 
                <Arg Name="CommsTimeout" />
                <Units:minute />
                minutes
            </Syslog>
        </Timeout>
        <Universal:platform_communications />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">second GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
</Aggregate>

2025-02-06T02:00:19.068Z,1738807219.068 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-02-06T02:00:19.072Z,1738807219.072 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-06T02:00:19.075Z,1738807219.075 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-06T02:00:19.078Z,1738807219.078 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-02-06T02:00:19.081Z,1738807219.081 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-06T02:00:19.084Z,1738807219.084 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-06T02:00:19.086Z,1738807219.086 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-02-06T02:00:19.089Z,1738807219.089 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-02-06T02:00:19.096Z,1738807219.096 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-06T02:00:19.145Z,1738807219.145 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:00:19.149Z,1738807219.149 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:00:19.150Z,1738807219.150 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-06T02:00:19.331Z,1738807219.331 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="StandardEnvelopes">
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepthIgnore">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:meter />
        <Value>2000</Value>
    </DefineArg>
    <Parallel />
    <Guidance:AltitudeEnvelope>
        <Parallel />
        <Setting>
            <Guidance:AltitudeEnvelope.minAltitude />
            <Arg Name="MinAltitude" />
        </Setting>
        <Setting>
            <Guidance:AltitudeEnvelope.maxDepthIgnore />
            <Arg Name="MaxDepthIgnore" />
        </Setting>
    </Guidance:AltitudeEnvelope>
    <Guidance:DepthEnvelope>
        <Parallel />
        <Setting>
            <Guidance:DepthEnvelope.maxDepth />
            <Arg Name="MaxDepth" />
        </Setting>
    </Guidance:DepthEnvelope>
    <Guidance:OffshoreEnvelope>
        <Parallel />
        <Setting>
            <Guidance:OffshoreEnvelope.minOffshore />
            <Arg Name="MinOffshore" />
        </Setting>
    </Guidance:OffshoreEnvelope>
</Aggregate>

2025-02-06T02:00:19.341Z,1738807219.341 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-06T02:00:19.348Z,1738807219.348 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-06T02:00:19.356Z,1738807219.356 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-06T02:00:19.360Z,1738807219.360 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-06T02:00:19.369Z,1738807219.369 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-06T02:00:19.390Z,1738807219.390 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-06T02:00:19.406Z,1738807219.406 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-06T02:00:19.436Z,1738807219.436 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-06T02:00:19.436Z,1738807219.436 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-06T02:00:19.438Z,1738807219.438 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-06T02:00:19.485Z,1738807219.485 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="BackseatDriver">
    <DefineArg Name="EnableBackseat">
        <False />
    </DefineArg>
    <Parallel />
    <Guidance:BackseatDriver>
        <Sequence />
        <Setting>
            <Guidance:BackseatDriver.powerBackseat />
            <Arg Name="EnableBackseat" />
        </Setting>
    </Guidance:BackseatDriver>
</Aggregate>

2025-02-06T02:00:19.489Z,1738807219.489 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-02-06T02:00:19.509Z,1738807219.509 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-06T02:00:19.512Z,1738807219.512 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-06T02:00:19.512Z,1738807219.512 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-06T02:00:19.513Z,1738807219.513 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-06T02:00:19.752Z,1738807219.752 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="PowerOnly">
    <DefineArg Name="SampleAll">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad1">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad2">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad3">
        <False />
    </DefineArg>
    <DefineArg Name="EnabledPowerOnly">
        <Sensor:PowerOnly.loadAtStartup />
    </DefineArg>
    <Parallel />
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleAll" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.samplePowerOnly />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad1" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad1 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad2" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad2 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad3" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad3 />
    </ReadData>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:hour />
            <Value>99999</Value>
        </Setting>
    </Guidance:Wait>
</Aggregate>

2025-02-06T02:00:19.761Z,1738807219.761 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-02-06T02:00:19.767Z,1738807219.767 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-02-06T02:00:19.770Z,1738807219.770 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-02-06T02:00:19.773Z,1738807219.773 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-02-06T02:00:19.784Z,1738807219.784 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-06T02:00:19.799Z,1738807219.799 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-06T02:00:19.801Z,1738807219.801 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-06T02:00:19.817Z,1738807219.817 [transit:MassHold.Pitch](DEBUG): Construct.
2025-02-06T02:00:19.840Z,1738807219.840 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-02-06T02:00:19.853Z,1738807219.853 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-02-06T02:00:19.856Z,1738807219.856 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-06T02:00:19.905Z,1738807219.905 [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:00:19.906Z,1738807219.906 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-02-06T02:00:32.369Z,1738807232.369 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 60 minute
2025-02-06T02:00:32.370Z,1738807232.370 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 30 minute
2025-02-06T02:00:32.371Z,1738807232.371 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2025-02-06T02:00:32.371Z,1738807232.371 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2025-02-06T02:00:32.372Z,1738807232.372 [CommandExec](IMPORTANT): got command set transit.Speed 1 meter_per_second
2025-02-06T02:00:32.372Z,1738807232.372 [CommandExec](IMPORTANT): got command set transit.MaxDepth 20 meter
2025-02-06T02:00:34.518Z,1738807234.518 [CommandExec](IMPORTANT): got command set transit.MinOffshore 1 kilometer
2025-02-06T02:00:34.519Z,1738807234.519 [CommandExec](IMPORTANT): got command run
2025-02-06T02:00:34.520Z,1738807234.520 [CommandExec](IMPORTANT): Running 
2025-02-06T02:00:34.648Z,1738807234.648 [Default] Stopped
2025-02-06T02:00:34.648Z,1738807234.648 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-06T02:00:34.648Z,1738807234.648 [Default:B.GoToSurface] Stopped
2025-02-06T02:00:34.648Z,1738807234.648 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:00:34.649Z,1738807234.649 [Default:CheckIn] Stopped
2025-02-06T02:00:34.649Z,1738807234.649 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-06T02:00:34.649Z,1738807234.649 [Default:CheckIn:C.Wait] Stopped
2025-02-06T02:00:34.649Z,1738807234.649 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:00:34.649Z,1738807234.649 [MissionManager](IMPORTANT): Started mission transit
2025-02-06T02:00:34.649Z,1738807234.649 [transit] Running Loop=1
2025-02-06T02:00:34.649Z,1738807234.649 [transit](DEBUG): Aggregate::initialize transit
2025-02-06T02:00:34.649Z,1738807234.649 [transit:StandardEnvelopes] Running Loop=1
2025-02-06T02:00:34.650Z,1738807234.650 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-02-06T02:00:34.650Z,1738807234.650 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-06T02:00:34.650Z,1738807234.650 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-06T02:00:34.650Z,1738807234.650 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-06T02:00:34.650Z,1738807234.650 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-06T02:00:34.650Z,1738807234.650 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-06T02:00:34.650Z,1738807234.650 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-06T02:00:34.650Z,1738807234.650 [transit:BackseatDriver] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-02-06T02:00:34.651Z,1738807234.651 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:PowerOnly] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-02-06T02:00:34.651Z,1738807234.651 [transit:PowerOnly:A] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:PowerOnly:B] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:PowerOnly:C] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:PowerOnly:D] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:PowerOnly:E.Wait] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:00:34.651Z,1738807234.651 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-06T02:00:34.651Z,1738807234.651 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-06T02:00:34.651Z,1738807234.651 [transit:MassHold.Pitch] Running Loop=1
2025-02-06T02:00:34.652Z,1738807234.652 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-02-06T02:00:34.652Z,1738807234.652 [transit:B] Running Loop=1
2025-02-06T02:00:34.652Z,1738807234.652 [transit:MassHold.Pitch] Running Loop=1
2025-02-06T02:00:34.653Z,1738807234.653 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-06T02:00:34.653Z,1738807234.653 [transit:PowerOnly] Running Loop=1
2025-02-06T02:00:34.654Z,1738807234.654 [transit:PowerOnly:D] Stopped
2025-02-06T02:00:34.654Z,1738807234.654 [transit:PowerOnly:C] Stopped
2025-02-06T02:00:34.654Z,1738807234.654 [transit:PowerOnly:B] Stopped
2025-02-06T02:00:34.654Z,1738807234.654 [transit:PowerOnly:A] Stopped
2025-02-06T02:00:34.654Z,1738807234.654 [transit:BackseatDriver] Running Loop=1
2025-02-06T02:00:34.654Z,1738807234.654 [transit:StandardEnvelopes] Running Loop=1
2025-02-06T02:00:34.654Z,1738807234.654 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-06T02:00:34.655Z,1738807234.655 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-06T02:00:34.656Z,1738807234.656 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-06T02:00:34.656Z,1738807234.656 [transit:B] Stopped
2025-02-06T02:00:34.656Z,1738807234.656 [transit:C] Running Loop=1
2025-02-06T02:00:35.048Z,1738807235.048 [transit:C] Stopped
2025-02-06T02:00:35.049Z,1738807235.049 [transit:E] Running Loop=1
2025-02-06T02:00:35.455Z,1738807235.455 [transit:E] Stopped
2025-02-06T02:00:35.455Z,1738807235.455 [transit:F] Running Loop=1
2025-02-06T02:00:35.870Z,1738807235.870 [transit:F] Stopped
2025-02-06T02:00:35.871Z,1738807235.871 [transit:G] Running Loop=1
2025-02-06T02:00:36.254Z,1738807236.254 [transit:G] Stopped
2025-02-06T02:00:36.254Z,1738807236.254 [transit:Transit] Running Loop=1
2025-02-06T02:00:36.254Z,1738807236.254 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-02-06T02:00:36.254Z,1738807236.254 [transit:Transit:A.Pitch] Running Loop=1
2025-02-06T02:00:36.254Z,1738807236.254 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-02-06T02:00:36.255Z,1738807236.255 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-06T02:00:36.255Z,1738807236.255 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-02-06T02:00:36.255Z,1738807236.255 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-02-06T02:00:36.255Z,1738807236.255 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-06T02:00:36.256Z,1738807236.256 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-06T02:00:36.258Z,1738807236.258 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-06T02:00:36.668Z,1738807236.668 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-06T02:00:36.668Z,1738807236.668 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-06T02:00:36.668Z,1738807236.668 [transit:Transit:A.Pitch] Running Loop=1
2025-02-06T02:00:36.678Z,1738807236.678 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-02-06T02:00:36.678Z,1738807236.678 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-02-06T02:00:36.678Z,1738807236.678 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-02-06T02:01:42.282Z,1738807302.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-06T02:02:13.414Z,1738807333.414 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-06T02:02:44.521Z,1738807364.521 [Radio_Surface](INFO): Powering down
2025-02-06T02:02:44.538Z,1738807364.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-06T02:02:45.542Z,1738807365.542 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-06T02:03:07.727Z,1738807387.727 [Power24vConverter](INFO): Powering down.
2025-02-06T02:03:50.810Z,1738807430.810 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735092
2025-02-06T02:10:59.212Z,1738807859.212 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-02-06T02:17:47.431Z,1738808267.431 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2025-02-06T02:17:47.431Z,1738808267.431 [transit:Transit:Wpt1.Waypoint] Stopped
2025-02-06T02:17:47.431Z,1738808267.431 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-06T02:17:47.431Z,1738808267.431 [transit:Transit:PhoneHome] Running Loop=1
2025-02-06T02:17:47.431Z,1738808267.431 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome
2025-02-06T02:17:47.757Z,1738808267.757 [transit:NeedComms] Running Loop=1
2025-02-06T02:17:47.758Z,1738808267.758 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-02-06T02:17:47.758Z,1738808267.758 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-02-06T02:17:47.758Z,1738808267.758 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T02:17:47.758Z,1738808267.758 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-06T02:17:47.758Z,1738808267.758 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-06T02:17:47.759Z,1738808267.759 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-06T02:17:47.759Z,1738808267.759 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-06T02:17:47.759Z,1738808267.759 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T02:17:47.760Z,1738808267.760 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T02:17:47.760Z,1738808267.760 [transit:NeedComms:A] Running Loop=1
2025-02-06T02:17:47.762Z,1738808267.762 [transit:NeedComms:A](INFO): last time_fix was:  1738806994.000000 second since 1970/01/01T00:00:00Z 
2025-02-06T02:17:47.762Z,1738808267.762 [transit:NeedComms:A] Stopped
2025-02-06T02:18:19.343Z,1738808299.343 [transit:NeedComms:C] Running Loop=1
2025-02-06T02:18:19.751Z,1738808299.751 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-06T02:18:19.818Z,1738808299.818 [Radio_Surface](INFO): Powering up
2025-02-06T02:18:25.382Z,1738808305.382 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-06T02:18:47.612Z,1738808327.612 [NAL9602](INFO): SBD MO Status=2, MOMSN=12529, MT Status=2, MTMSN=0
2025-02-06T02:18:47.612Z,1738808327.612 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-06T02:18:55.518Z,1738808335.518 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-06T02:19:10.648Z,1738808350.648 [NAL9602](INFO): SBD MO Status=0, MOMSN=12529, MT Status=0, MTMSN=0
2025-02-06T02:19:10.648Z,1738808350.648 [NAL9602](INFO): No messages in MT queue
2025-02-06T02:19:11.856Z,1738808351.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021908.00,A,3647.67451,N,12150.71007,W,0.700,116.60,060225,,,A*7B
2025-02-06T02:19:11.858Z,1738808351.858 [NAL9602](INFO): GPS fix at 20250206T021908: (36.794575, -121.845168)
2025-02-06T02:19:11.871Z,1738808351.871 [UniversalFixResidualReporter](INFO): Fix residual: 33.2 %DT, over the last 1039.1 m. Residual distance 344.7 m at bearing -29.9 degrees. Fix at (36.7946, -121.8452) with 687.2 m made good.
2025-02-06T02:19:11.874Z,1738808351.874 [transit:NeedComms:C] Stopped
2025-02-06T02:19:11.874Z,1738808351.874 [transit:NeedComms:D] Running Loop=1
2025-02-06T02:19:12.290Z,1738808352.290 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-06T02:19:26.642Z,1738808366.642 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-06T02:19:29.284Z,1738808369.284 [NAL9602](INFO): SBD MO Status=1, MOMSN=12530, MT Status=0, MTMSN=0
2025-02-06T02:19:29.346Z,1738808369.346 [NAL9602](INFO): Sent 205 bytes from file Logs/20250206T015256/Courier0007.lzma
2025-02-06T02:19:29.346Z,1738808369.346 [NAL9602](INFO): Packets left to send: 0
2025-02-06T02:19:43.060Z,1738808383.060 [NAL9602](INFO): SBD MO Status=1, MOMSN=12531, MT Status=0, MTMSN=0
2025-02-06T02:19:43.126Z,1738808383.126 [NAL9602](INFO): Sent 332 bytes from file Logs/20250206T015256/Express0008.lzma
2025-02-06T02:19:43.126Z,1738808383.126 [NAL9602](INFO): Packets left to send: 3
2025-02-06T02:19:55.640Z,1738808395.640 [NAL9602](INFO): SBD MO Status=1, MOMSN=12532, MT Status=0, MTMSN=0
2025-02-06T02:19:55.690Z,1738808395.690 [NAL9602](INFO): Sent 332 bytes from file Logs/20250206T015256/Express0008.lzma
2025-02-06T02:19:55.690Z,1738808395.690 [NAL9602](INFO): Packets left to send: 2
2025-02-06T02:19:57.766Z,1738808397.766 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-06T02:20:07.808Z,1738808407.808 [NAL9602](INFO): SBD MO Status=1, MOMSN=12533, MT Status=0, MTMSN=0
2025-02-06T02:20:07.858Z,1738808407.858 [NAL9602](INFO): Sent 332 bytes from file Logs/20250206T015256/Express0008.lzma
2025-02-06T02:20:07.858Z,1738808407.858 [NAL9602](INFO): Packets left to send: 1
2025-02-06T02:20:16.768Z,1738808416.768 [NAL9602](INFO): SBD MO Status=1, MOMSN=12534, MT Status=0, MTMSN=0
2025-02-06T02:20:16.838Z,1738808416.838 [NAL9602](INFO): Sent 185 bytes from file Logs/20250206T015256/Express0008.lzma
2025-02-06T02:20:16.838Z,1738808416.838 [NAL9602](INFO): Packets left to send: 0
2025-02-06T02:20:22.023Z,1738808422.023 [CommandExec](IMPORTANT): got command show stack
2025-02-06T02:20:22.023Z,1738808422.023 [CommandExec](IMPORTANT): Behavior Stack:

2025-02-06T02:20:22.023Z,1738808422.023 [transit:NeedComms](IMPORTANT): Priority 0: transit:NeedComms:B.GoToSurface
2025-02-06T02:20:22.024Z,1738808422.024 [transit:NeedComms](IMPORTANT): Priority 1: transit:NeedComms:D
2025-02-06T02:20:22.024Z,1738808422.024 [transit:StandardEnvelopes](IMPORTANT): Priority 2: transit:StandardEnvelopes:A.AltitudeEnvelope
2025-02-06T02:20:22.024Z,1738808422.024 [transit:StandardEnvelopes](IMPORTANT): Priority 3: transit:StandardEnvelopes:B.DepthEnvelope
2025-02-06T02:20:22.024Z,1738808422.024 [transit:StandardEnvelopes](IMPORTANT): Priority 4: transit:StandardEnvelopes:C.OffshoreEnvelope
2025-02-06T02:20:22.025Z,1738808422.025 [transit:BackseatDriver](IMPORTANT): Priority 5: transit:BackseatDriver:A.BackseatDriver
2025-02-06T02:20:22.025Z,1738808422.025 [transit:PowerOnly](IMPORTANT): Priority 6: transit:PowerOnly:E.Wait
2025-02-06T02:20:22.025Z,1738808422.025 [transit](IMPORTANT): Priority 7: transit:BuoyancyHold.Buoyancy
2025-02-06T02:20:22.026Z,1738808422.026 [transit](IMPORTANT): Priority 8: transit:MassHold.Pitch
2025-02-06T02:20:22.026Z,1738808422.026 [transit:Transit](IMPORTANT): Priority 9: transit:Transit:A.Pitch
2025-02-06T02:20:22.026Z,1738808422.026 [transit:Transit](IMPORTANT): Priority 10: transit:Transit:B.SetSpeed
2025-02-06T02:20:23.224Z,1738808423.224 [NAL9602](INFO): SBD MO Status=0, MOMSN=12535, MT Status=0, MTMSN=0
2025-02-06T02:20:23.313Z,1738808423.313 [transit:NeedComms:D] Stopped
2025-02-06T02:20:23.313Z,1738808423.313 [transit:NeedComms:E] Running Loop=1
2025-02-06T02:20:23.652Z,1738808423.652 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-06T02:20:24.538Z,1738808424.538 [CommandExec](IMPORTANT): got command stop
2025-02-06T02:20:24.538Z,1738808424.538 [CommandExec](IMPORTANT): Scheduling is paused
2025-02-06T02:20:24.539Z,1738808424.539 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-06T02:20:24.862Z,1738808424.862 [MissionManager](INFO): MissionManager is completed.
2025-02-06T02:20:24.862Z,1738808424.862 [MissionManager](INFO): Uninitializing Mission transit
2025-02-06T02:20:24.862Z,1738808424.862 [transit] Stopped
2025-02-06T02:20:24.862Z,1738808424.862 [transit](DEBUG): Aggregate::uninitialize transit
2025-02-06T02:20:24.862Z,1738808424.862 [transit:NeedComms] Stopped
2025-02-06T02:20:24.862Z,1738808424.862 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-02-06T02:20:24.862Z,1738808424.862 [transit:NeedComms:B.GoToSurface] Stopped
2025-02-06T02:20:24.862Z,1738808424.862 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:20:24.862Z,1738808424.862 [transit:NeedComms:E] Stopped
2025-02-06T02:20:24.863Z,1738808424.863 [transit:StandardEnvelopes] Stopped
2025-02-06T02:20:24.863Z,1738808424.863 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-02-06T02:20:24.863Z,1738808424.863 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-06T02:20:24.863Z,1738808424.863 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-06T02:20:24.863Z,1738808424.863 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-06T02:20:24.863Z,1738808424.863 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-06T02:20:24.863Z,1738808424.863 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-06T02:20:24.863Z,1738808424.863 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-06T02:20:24.863Z,1738808424.863 [transit:BackseatDriver] Stopped
2025-02-06T02:20:24.863Z,1738808424.863 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-02-06T02:20:24.863Z,1738808424.863 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-02-06T02:20:24.863Z,1738808424.863 [transit:PowerOnly] Stopped
2025-02-06T02:20:24.863Z,1738808424.863 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-02-06T02:20:24.864Z,1738808424.864 [transit:PowerOnly:E.Wait] Stopped
2025-02-06T02:20:24.864Z,1738808424.864 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:20:24.864Z,1738808424.864 [transit:BuoyancyHold.Buoyancy] Stopped
2025-02-06T02:20:24.864Z,1738808424.864 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-06T02:20:24.864Z,1738808424.864 [transit:MassHold.Pitch] Stopped
2025-02-06T02:20:24.864Z,1738808424.864 [transit:Transit] Stopped
2025-02-06T02:20:24.864Z,1738808424.864 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-02-06T02:20:24.864Z,1738808424.864 [transit:Transit:A.Pitch] Stopped
2025-02-06T02:20:24.864Z,1738808424.864 [transit:Transit:B.SetSpeed] Stopped
2025-02-06T02:20:24.864Z,1738808424.864 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-02-06T02:20:24.864Z,1738808424.864 [transit:Transit:PhoneHome] Stopped
2025-02-06T02:20:24.864Z,1738808424.864 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome
2025-02-06T02:20:25.274Z,1738808425.274 [MissionManager](IMPORTANT): Started mission Default
2025-02-06T02:20:25.274Z,1738808425.274 [Default] Running Loop=1
2025-02-06T02:20:25.274Z,1738808425.274 [Default](DEBUG): Aggregate::initialize Default
2025-02-06T02:20:25.274Z,1738808425.274 [Default:B.GoToSurface] Running Loop=1
2025-02-06T02:20:25.274Z,1738808425.274 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-06T02:20:25.274Z,1738808425.274 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-06T02:20:25.275Z,1738808425.275 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-06T02:20:25.275Z,1738808425.275 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-06T02:20:25.275Z,1738808425.276 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-06T02:20:25.276Z,1738808425.276 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-06T02:20:25.276Z,1738808425.276 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-06T02:20:25.276Z,1738808425.276 [Default:A.Wait] Running Loop=1
2025-02-06T02:20:25.276Z,1738808425.276 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-06T02:20:25.644Z,1738808425.644 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022021.00,A,3647.66552,N,12150.70605,W,0.641,182.56,060225,,,A*70
2025-02-06T02:20:25.646Z,1738808425.646 [NAL9602](INFO): GPS fix at 20250206T022021: (36.794425, -121.845101)
2025-02-06T02:20:28.890Z,1738808428.890 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-06T02:20:38.557Z,1738808438.557 [Default:A.Wait](INFO): Done Waiting.
2025-02-06T02:20:38.557Z,1738808438.557 [Default:A.Wait] Stopped
2025-02-06T02:20:38.557Z,1738808438.557 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-06T02:20:38.971Z,1738808438.971 [Default:CheckIn] Running Loop=1
2025-02-06T02:20:38.971Z,1738808438.971 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-06T02:20:38.971Z,1738808438.971 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-06T02:20:41.342Z,1738808441.342 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022037.00,A,3647.66352,N,12150.70493,W,0.311,153.51,060225,,,A*77
2025-02-06T02:20:41.344Z,1738808441.344 [NAL9602](INFO): GPS fix at 20250206T022037: (36.794392, -121.845082)
2025-02-06T02:20:41.564Z,1738808441.564 [Default:CheckIn:Read_GPS] Stopped
2025-02-06T02:20:41.564Z,1738808441.564 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-06T02:20:50.592Z,1738808450.592 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005372
2025-02-06T02:21:03.384Z,1738808463.384 [NAL9602](INFO): SBD MO Status=2, MOMSN=12536, MT Status=2, MTMSN=0
2025-02-06T02:21:03.384Z,1738808463.384 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-06T02:21:06.481Z,1738808466.481 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250206T015256/Courier0010.lzma
2025-02-06T02:21:07.485Z,1738808467.485 [DataOverHttps](INFO): Moved sent file to Logs/20250206T015256/Courier0010.lzma.bak
2025-02-06T02:21:07.485Z,1738808467.485 [DataOverHttps](INFO): SBD MOMSN=23958548
2025-02-06T02:21:33.922Z,1738808493.922 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-06T02:21:34.053Z,1738808494.053 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250206T015256/Courier0013.lzma
2025-02-06T02:21:35.055Z,1738808495.055 [DataOverHttps](INFO): Moved sent file to Logs/20250206T015256/Courier0013.lzma.bak
2025-02-06T02:21:35.055Z,1738808495.055 [DataOverHttps](INFO): SBD MOMSN=23958551
2025-02-06T02:21:43.643Z,1738808503.643 [CommandExec](IMPORTANT): got command restart application
2025-02-06T02:21:44.645Z,1738808504.645 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:44.646Z,1738808504.646 [CommandExec](INFO): Uninitializing the command executive.
2025-02-06T02:21:44.646Z,1738808504.646 [CommandExec](INFO): Uninitializing the command scheduler.
2025-02-06T02:21:44.646Z,1738808504.646 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:44.789Z,1738808504.789 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-02-06T02:21:44.789Z,1738808504.789 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-02-06T02:21:44.790Z,1738808504.790 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:44.790Z,1738808504.790 [NavChartDb](INFO): Join timeout helper Thread ID is 1627
2025-02-06T02:21:44.970Z,1738808504.970 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:44.970Z,1738808504.970 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:44.984Z,1738808504.984 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-02-06T02:21:44.984Z,1738808504.984 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:44.984Z,1738808504.984 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1628
2025-02-06T02:21:45.413Z,1738808505.413 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:45.414Z,1738808505.414 [WetLabsBB2FL](INFO): Powering down
2025-02-06T02:21:45.414Z,1738808505.414 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:45.421Z,1738808505.421 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-02-06T02:21:45.421Z,1738808505.421 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:45.422Z,1738808505.422 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1629
2025-02-06T02:21:45.422Z,1738808505.422 [CTD_Seabird](INFO): Powering down
2025-02-06T02:21:45.433Z,1738808505.433 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:45.433Z,1738808505.433 [CTD_Seabird](INFO): Powering down
2025-02-06T02:21:45.445Z,1738808505.445 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:45.461Z,1738808505.461 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-02-06T02:21:45.461Z,1738808505.461 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:45.462Z,1738808505.462 [Radio_Surface](INFO): Join timeout helper Thread ID is 1630
2025-02-06T02:21:45.801Z,1738808505.801 [Radio_Surface](INFO): Powering down
2025-02-06T02:21:45.802Z,1738808505.802 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:45.803Z,1738808505.803 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:45.817Z,1738808505.817 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-02-06T02:21:45.817Z,1738808505.817 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:45.818Z,1738808505.818 [Onboard](INFO): Join timeout helper Thread ID is 1631
2025-02-06T02:21:47.557Z,1738808507.557 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:47.558Z,1738808507.558 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:47.566Z,1738808507.566 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-02-06T02:21:47.566Z,1738808507.566 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:47.567Z,1738808507.567 [DataOverHttps](INFO): Join timeout helper Thread ID is 1632
2025-02-06T02:21:48.285Z,1738808508.285 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:48.286Z,1738808508.286 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.302Z,1738808508.302 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-02-06T02:21:48.302Z,1738808508.302 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.302Z,1738808508.302 [BackseatComponent](INFO): Join timeout helper Thread ID is 1633
2025-02-06T02:21:48.437Z,1738808508.437 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:48.438Z,1738808508.438 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.446Z,1738808508.446 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-02-06T02:21:48.446Z,1738808508.446 [logger ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.446Z,1738808508.446 [logger](INFO): Join timeout helper Thread ID is 1634
2025-02-06T02:21:48.477Z,1738808508.477 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:48.478Z,1738808508.478 [logger ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.486Z,1738808508.486 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-02-06T02:21:48.486Z,1738808508.486 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.487Z,1738808508.487 [CommandLine](INFO): Join timeout helper Thread ID is 1635
2025-02-06T02:21:48.510Z,1738808508.510 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:48.510Z,1738808508.510 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.514Z,1738808508.514 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-02-06T02:21:48.514Z,1738808508.514 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.515Z,1738808508.515 [CommandExec](INFO): Join timeout helper Thread ID is 1636
2025-02-06T02:21:48.516Z,1738808508.516 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-02-06T02:21:48.516Z,1738808508.516 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:48.516Z,1738808508.516 [controlThread](INFO): Join timeout helper Thread ID is 1637
2025-02-06T02:21:48.869Z,1738808508.869 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-02-06T02:21:48.870Z,1738808508.870 [controlThread](DEBUG): Uninitializing ControlThread
2025-02-06T02:21:48.870Z,1738808508.870 [AHRS_M2](INFO): Powering down
2025-02-06T02:21:48.943Z,1738808508.943 [NAL9602](INFO): Powering down
2025-02-06T02:21:48.944Z,1738808508.944 [Sonardyne_Nano](INFO): Powering down
2025-02-06T02:21:49.062Z,1738808509.062 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-02-06T02:21:49.063Z,1738808509.063 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-02-06T02:21:49.063Z,1738808509.063 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-02-06T02:21:49.064Z,1738808509.064 [MissionManager](INFO): Uninitializing Mission Default
2025-02-06T02:21:49.064Z,1738808509.064 [Default] Stopped
2025-02-06T02:21:49.064Z,1738808509.064 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-06T02:21:49.064Z,1738808509.064 [Default:B.GoToSurface] Stopped
2025-02-06T02:21:49.064Z,1738808509.064 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-06T02:21:49.064Z,1738808509.064 [Default:CheckIn] Stopped
2025-02-06T02:21:49.064Z,1738808509.064 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-06T02:21:49.064Z,1738808509.064 [Default:CheckIn:Read_Iridium] Stopped
2025-02-06T02:21:49.067Z,1738808509.067 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-02-06T02:21:49.067Z,1738808509.067 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-02-06T02:21:49.068Z,1738808509.068 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-02-06T02:21:49.068Z,1738808509.068 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-02-06T02:21:49.068Z,1738808509.068 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-02-06T02:21:49.069Z,1738808509.069 [BuoyancyServo](INFO): Powering down
2025-02-06T02:21:49.081Z,1738808509.081 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-02-06T02:21:49.082Z,1738808509.082 [ElevatorServo](INFO): Powering down
2025-02-06T02:21:49.082Z,1738808509.082 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-02-06T02:21:49.082Z,1738808509.082 [MassServo](INFO): Powering down
2025-02-06T02:21:49.083Z,1738808509.083 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-02-06T02:21:49.083Z,1738808509.083 [RudderServo](INFO): Powering down
2025-02-06T02:21:49.084Z,1738808509.084 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-02-06T02:21:49.084Z,1738808509.084 [ThrusterHE](INFO): Powering down
2025-02-06T02:21:49.085Z,1738808509.085 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-02-06T02:21:49.086Z,1738808509.086 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-02-06T02:21:49.086Z,1738808509.086 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-02-06T02:21:49.086Z,1738808509.086 [CBIT](DEBUG): Powering off loads.
2025-02-06T02:21:49.097Z,1738808509.097 [CBIT](DEBUG): Disabling WDT.
2025-02-06T02:21:49.109Z,1738808509.109 [CBIT](DEBUG): Opening all GF detection circuits.
2025-02-06T02:21:49.110Z,1738808509.110 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:49.128Z,1738808509.128 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:49.131Z,1738808509.131 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:49.137Z,1738808509.137 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:49.181Z,1738808509.181 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:49.267Z,1738808509.267 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:49.272Z,1738808509.272 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:49.290Z,1738808509.290 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-02-06T02:21:49.366Z,1738808509.366 [logger ThreadHandler](INFO): Thread cancelled.