2024-02-13T17:51:17.899Z,1707846677.899 [Supervisor](DEBUG): Initializing supervisor.
2024-02-13T17:51:17.904Z,1707846677.904 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-02-13T17:51:17.904Z,1707846677.904 [SyncHandler](INFO): Protected caller Thread ID is 4009
2024-02-13T17:51:17.905Z,1707846677.905 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-02-13T17:51:17.906Z,1707846677.906 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-02-13T17:51:17.907Z,1707846677.907 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4010
2024-02-13T17:51:17.911Z,1707846677.911 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-02-13T17:51:17.928Z,1707846677.928 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-02-13T17:51:17.929Z,1707846677.929 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-02-13T17:51:17.929Z,1707846677.929 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4011
2024-02-13T17:51:17.934Z,1707846677.934 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-02-13T17:51:17.935Z,1707846677.935 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-02-13T17:51:17.935Z,1707846677.935 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4012
2024-02-13T17:51:17.937Z,1707846677.937 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-02-13T17:51:17.938Z,1707846677.938 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-02-13T17:51:17.938Z,1707846677.938 [logger ThreadHandler](INFO): Protected caller Thread ID is 4013
2024-02-13T17:51:17.942Z,1707846677.942 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-02-13T17:51:17.942Z,1707846677.942 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-02-13T17:51:17.944Z,1707846677.944 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-02-13T17:51:18.219Z,1707846678.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-02-13T17:51:18.219Z,1707846678.219 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-02-13T17:51:18.301Z,1707846678.301 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-02-13T17:51:18.793Z,1707846678.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-02-13T17:51:18.793Z,1707846678.793 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-02-13T17:51:19.132Z,1707846679.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-02-13T17:51:19.133Z,1707846679.133 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-02-13T17:51:19.231Z,1707846679.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-02-13T17:51:19.232Z,1707846679.232 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-02-13T17:51:19.641Z,1707846679.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-02-13T17:51:19.642Z,1707846679.642 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-02-13T17:51:19.865Z,1707846679.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-02-13T17:51:19.866Z,1707846679.866 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-02-13T17:51:20.642Z,1707846680.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-02-13T17:51:20.643Z,1707846680.643 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-02-13T17:51:20.783Z,1707846680.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-02-13T17:51:20.784Z,1707846680.784 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-02-13T17:51:20.864Z,1707846680.864 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-02-13T17:51:21.678Z,1707846681.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-02-13T17:51:21.679Z,1707846681.679 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-02-13T17:51:22.125Z,1707846682.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-02-13T17:51:22.125Z,1707846682.125 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-02-13T17:51:22.376Z,1707846682.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-02-13T17:51:22.377Z,1707846682.377 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-02-13T17:51:22.545Z,1707846682.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-02-13T17:51:22.546Z,1707846682.546 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-02-13T17:51:23.141Z,1707846683.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-02-13T17:51:23.142Z,1707846683.142 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-02-13T17:51:23.401Z,1707846683.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-02-13T17:51:23.403Z,1707846683.403 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-02-13T17:51:23.404Z,1707846683.404 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-02-13T17:51:23.498Z,1707846683.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-02-13T17:51:23.587Z,1707846683.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-02-13T17:51:23.693Z,1707846683.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-02-13T17:51:23.779Z,1707846683.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-02-13T17:51:23.885Z,1707846683.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-02-13T17:51:23.991Z,1707846683.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-02-13T17:51:24.108Z,1707846684.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2024-02-13T17:51:24.187Z,1707846684.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-02-13T17:51:24.345Z,1707846684.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-02-13T17:51:24.492Z,1707846684.492 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-02-13T17:51:24.607Z,1707846684.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-02-13T17:51:24.875Z,1707846684.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-02-13T17:51:24.876Z,1707846684.876 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2024-02-13T17:51:24.877Z,1707846684.877 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2024-02-13T17:51:24.888Z,1707846684.888 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-02-13T17:51:24.939Z,1707846684.939 [VerticalControl](DEBUG): Construct VerticalControl.
2024-02-13T17:51:24.998Z,1707846684.998 [VerticalControl] Loaded
2024-02-13T17:51:24.998Z,1707846684.998 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-02-13T17:51:25.001Z,1707846685.001 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-02-13T17:51:25.041Z,1707846685.041 [HorizontalControl] Loaded
2024-02-13T17:51:25.041Z,1707846685.041 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-02-13T17:51:25.043Z,1707846685.043 [SpeedControl](DEBUG): Construct SpeedControl.
2024-02-13T17:51:25.046Z,1707846685.046 [SpeedControl] Loaded
2024-02-13T17:51:25.047Z,1707846685.047 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-02-13T17:51:25.050Z,1707846685.050 [LoopControl](DEBUG): Construct LoopControl.
2024-02-13T17:51:25.051Z,1707846685.051 [LoopControl] Loaded
2024-02-13T17:51:25.051Z,1707846685.051 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-02-13T17:51:25.058Z,1707846685.058 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-02-13T17:51:25.058Z,1707846685.058 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-02-13T17:51:25.068Z,1707846685.068 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-02-13T17:51:25.069Z,1707846685.069 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-02-13T17:51:25.213Z,1707846685.213 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-02-13T17:51:25.213Z,1707846685.213 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-02-13T17:51:25.366Z,1707846685.366 [BuoyancyServo] Loaded
2024-02-13T17:51:25.366Z,1707846685.366 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-02-13T17:51:25.385Z,1707846685.385 [ElevatorServo] Loaded
2024-02-13T17:51:25.386Z,1707846685.386 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-02-13T17:51:25.404Z,1707846685.404 [MassServo] Loaded
2024-02-13T17:51:25.404Z,1707846685.404 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-02-13T17:51:25.422Z,1707846685.422 [RudderServo] Loaded
2024-02-13T17:51:25.423Z,1707846685.423 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-02-13T17:51:25.437Z,1707846685.437 [ThrusterHE] Loaded
2024-02-13T17:51:25.437Z,1707846685.437 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-02-13T17:51:25.437Z,1707846685.437 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-02-13T17:51:25.438Z,1707846685.438 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-02-13T17:51:25.549Z,1707846685.549 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-02-13T17:51:25.549Z,1707846685.549 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-02-13T17:51:25.562Z,1707846685.562 [NavChart] Loaded
2024-02-13T17:51:25.563Z,1707846685.563 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-02-13T17:51:25.568Z,1707846685.568 [UniversalFixResidualReporter] Loaded
2024-02-13T17:51:25.568Z,1707846685.568 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-02-13T17:51:25.569Z,1707846685.569 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-02-13T17:51:25.569Z,1707846685.569 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-02-13T17:51:25.624Z,1707846685.624 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-02-13T17:51:25.624Z,1707846685.624 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-02-13T17:51:26.038Z,1707846686.038 [AHRS_M2] Loaded
2024-02-13T17:51:26.038Z,1707846686.038 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-02-13T17:51:26.832Z,1707846686.832 [BPC1] Loaded
2024-02-13T17:51:26.832Z,1707846686.832 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-02-13T17:51:26.908Z,1707846686.908 [DataOverHttps] Loaded
2024-02-13T17:51:26.908Z,1707846686.908 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-02-13T17:51:26.910Z,1707846686.910 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0
2024-02-13T17:51:26.910Z,1707846686.910 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4098
2024-02-13T17:51:26.930Z,1707846686.930 [Depth_Keller] Loaded
2024-02-13T17:51:26.930Z,1707846686.930 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-02-13T17:51:26.935Z,1707846686.935 [DropWeight] Loaded
2024-02-13T17:51:26.935Z,1707846686.935 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-02-13T17:51:26.996Z,1707846686.996 [NAL9602] Loaded
2024-02-13T17:51:26.996Z,1707846686.996 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-02-13T17:51:27.026Z,1707846687.026 [Onboard] Loaded
2024-02-13T17:51:27.026Z,1707846687.026 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-02-13T17:51:27.027Z,1707846687.027 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0
2024-02-13T17:51:27.028Z,1707846687.028 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4099
2024-02-13T17:51:27.041Z,1707846687.041 [Power24vConverter] Loaded
2024-02-13T17:51:27.041Z,1707846687.041 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-02-13T17:51:27.054Z,1707846687.054 [Radio_Surface] Loaded
2024-02-13T17:51:27.054Z,1707846687.054 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-02-13T17:51:27.055Z,1707846687.055 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0
2024-02-13T17:51:27.055Z,1707846687.055 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4100
2024-02-13T17:51:27.069Z,1707846687.069 [Sonardyne_Nano] Loaded
2024-02-13T17:51:27.069Z,1707846687.069 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-02-13T17:51:27.069Z,1707846687.069 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-02-13T17:51:27.070Z,1707846687.070 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-02-13T17:51:27.119Z,1707846687.119 [DepthRateCalculator] Loaded
2024-02-13T17:51:27.119Z,1707846687.119 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-02-13T17:51:27.124Z,1707846687.124 [PitchRateCalculator] Loaded
2024-02-13T17:51:27.124Z,1707846687.124 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-02-13T17:51:27.133Z,1707846687.133 [SpeedCalculator] Loaded
2024-02-13T17:51:27.133Z,1707846687.133 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-02-13T17:51:27.137Z,1707846687.137 [YawRateCalculator] Loaded
2024-02-13T17:51:27.138Z,1707846687.138 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-02-13T17:51:27.156Z,1707846687.156 [ElevatorOffsetCalculator] Loaded
2024-02-13T17:51:27.156Z,1707846687.156 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-02-13T17:51:27.156Z,1707846687.156 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-02-13T17:51:27.157Z,1707846687.157 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-02-13T17:51:27.855Z,1707846687.855 [CANONSampler] Loaded
2024-02-13T17:51:27.856Z,1707846687.856 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-02-13T17:51:27.915Z,1707846687.915 [CTD_Seabird] Loaded
2024-02-13T17:51:27.915Z,1707846687.915 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-02-13T17:51:27.916Z,1707846687.916 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2024-02-13T17:51:27.916Z,1707846687.916 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4101
2024-02-13T17:51:27.936Z,1707846687.936 [PAR_Licor] Loaded
2024-02-13T17:51:27.936Z,1707846687.936 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-02-13T17:51:27.980Z,1707846687.980 [WetLabsBB2FL] Loaded
2024-02-13T17:51:27.980Z,1707846687.980 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-02-13T17:51:27.981Z,1707846687.981 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0
2024-02-13T17:51:27.981Z,1707846687.981 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4102
2024-02-13T17:51:27.982Z,1707846687.982 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-02-13T17:51:27.983Z,1707846687.983 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-02-13T17:51:28.009Z,1707846688.009 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-02-13T17:51:28.009Z,1707846688.009 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-02-13T17:51:28.339Z,1707846688.339 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-02-13T17:51:28.339Z,1707846688.339 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-02-13T17:51:28.476Z,1707846688.476 [SBIT](DEBUG): Construct Startup Built In Test.
2024-02-13T17:51:28.485Z,1707846688.485 [SBIT] Loaded
2024-02-13T17:51:28.485Z,1707846688.485 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-02-13T17:51:28.488Z,1707846688.488 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-02-13T17:51:28.502Z,1707846688.502 [IBIT] Loaded
2024-02-13T17:51:28.502Z,1707846688.502 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-02-13T17:51:28.508Z,1707846688.508 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-02-13T17:51:28.609Z,1707846688.609 [CBIT] Loaded
2024-02-13T17:51:28.609Z,1707846688.609 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-02-13T17:51:28.610Z,1707846688.610 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-02-13T17:51:28.610Z,1707846688.610 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-02-13T17:51:28.720Z,1707846688.720 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-02-13T17:51:28.727Z,1707846688.727 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-02-13T17:51:28.729Z,1707846688.729 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-02-13T17:51:28.740Z,1707846688.740 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-02-13T17:51:28.741Z,1707846688.741 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BB14E0
2024-02-13T17:51:28.741Z,1707846688.741 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4103
2024-02-13T17:51:28.746Z,1707846688.746 [Supervisor](INFO): Main Thread ID is 3871
2024-02-13T17:51:28.746Z,1707846688.746 [Supervisor](DEBUG): Running supervisor.
2024-02-13T17:51:28.747Z,1707846688.747 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4104
2024-02-13T17:51:28.747Z,1707846688.747 [CommandExec](INFO): Initializing the command executive.
2024-02-13T17:51:28.749Z,1707846688.749 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4105
2024-02-13T17:51:28.751Z,1707846688.751 [controlThread ThreadHandler](INFO): Handler Thread ID is 4106
2024-02-13T17:51:28.752Z,1707846688.752 [controlThread](DEBUG): Initializing ControlThread
2024-02-13T17:51:28.752Z,1707846688.752 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-02-13T17:51:28.754Z,1707846688.754 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-02-13T17:51:28.755Z,1707846688.755 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-02-13T17:51:28.756Z,1707846688.756 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-02-13T17:51:28.758Z,1707846688.758 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-02-13T17:51:28.758Z,1707846688.758 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-02-13T17:51:28.764Z,1707846688.764 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-02-13T17:51:28.765Z,1707846688.765 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-02-13T17:51:28.765Z,1707846688.765 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-02-13T17:51:28.765Z,1707846688.765 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-02-13T17:51:28.766Z,1707846688.766 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-02-13T17:51:28.771Z,1707846688.771 [SBIT](INFO): Initialize SBIT Component.
2024-02-13T17:51:28.771Z,1707846688.771 [SBIT](IMPORTANT): git: 2024-01-19
2024-02-13T17:51:28.771Z,1707846688.771 [SBIT](INFO): git hash: e082c2abde225758db17f25e52fba4248c834971
2024-02-13T17:51:28.772Z,1707846688.772 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-02-13T17:51:28.773Z,1707846688.773 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-02-13T17:51:28.774Z,1707846688.774 [SBIT](INFO): Beginning SBIT in 88.000000 seconds.
2024-02-13T17:51:28.775Z,1707846688.775 [IBIT](INFO): Initialize IBIT Component.
2024-02-13T17:51:28.776Z,1707846688.776 [CBIT](DEBUG): Initialize CBIT Component.
2024-02-13T17:51:28.776Z,1707846688.776 [logger ThreadHandler](INFO): Handler Thread ID is 4107
2024-02-13T17:51:28.786Z,1707846688.786 [CBIT](DEBUG): Initialized mux pins.
2024-02-13T17:51:28.787Z,1707846688.787 [CBIT](DEBUG): Initializing the watchdog timer.
2024-02-13T17:51:28.795Z,1707846688.795 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4108
2024-02-13T17:51:28.797Z,1707846688.797 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-02-13T17:51:28.807Z,1707846688.807 [Onboard ThreadHandler](INFO): Handler Thread ID is 4109
2024-02-13T17:51:28.811Z,1707846688.811 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-02-13T17:51:28.812Z,1707846688.812 [CBIT](DEBUG): Initializing heartbeat.
2024-02-13T17:51:28.827Z,1707846688.827 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4110
2024-02-13T17:51:28.843Z,1707846688.843 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4111
2024-02-13T17:51:28.843Z,1707846688.843 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-02-13T17:51:28.848Z,1707846688.848 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4113
2024-02-13T17:51:28.851Z,1707846688.851 [WetLabsBB2FL](INFO): Powering up
2024-02-13T17:51:28.853Z,1707846688.853 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4115
2024-02-13T17:51:28.856Z,1707846688.856 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-02-13T17:51:28.856Z,1707846688.856 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-02-13T17:51:28.856Z,1707846688.856 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-02-13T17:51:28.856Z,1707846688.856 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-02-13T17:51:28.856Z,1707846688.856 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-02-13T17:51:28.856Z,1707846688.856 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-02-13T17:51:28.857Z,1707846688.857 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-02-13T17:51:28.857Z,1707846688.857 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-02-13T17:51:28.882Z,1707846688.882 [CBIT](DEBUG): Deactivating GF circuits.
2024-02-13T17:51:28.883Z,1707846688.883 [CBIT](DEBUG): Deactivating emergency mode.
2024-02-13T17:51:28.922Z,1707846688.922 [CBIT](DEBUG): Backplane powered.
2024-02-13T17:51:28.923Z,1707846688.923 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2024-02-13T17:51:28.936Z,1707846688.936 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-02-13T17:51:28.971Z,1707846688.971 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       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>


2024-02-13T17:51:28.972Z,1707846688.972 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2024-02-13T17:51:29.045Z,1707846689.045 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-02-13T17:51:29.046Z,1707846689.046 [Default:A.Wait](DEBUG): Construct Wait.
2024-02-13T17:51:29.048Z,1707846689.048 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-02-13T17:51:29.092Z,1707846689.092 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-02-13T17:51:29.095Z,1707846689.095 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-02-13T17:51:29.109Z,1707846689.109 [Default:E.Execute](DEBUG): Construct Execute.
2024-02-13T17:51:29.112Z,1707846689.112 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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>


2024-02-13T17:51:29.127Z,1707846689.127 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,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,Reporter,LogSplitter,
2024-02-13T17:51:29.161Z,1707846689.161 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-02-13T17:51:29.219Z,1707846689.219 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m
2024-02-13T17:51:29.220Z,1707846689.220 [Power24vConverter](INFO): Powering up.
2024-02-13T17:51:29.221Z,1707846689.221 [Sonardyne_Nano](INFO): Initializing.
2024-02-13T17:51:29.231Z,1707846689.231 [Radio_Surface](INFO): Powering up
2024-02-13T17:51:29.239Z,1707846689.239 [CANONSampler](INFO): Powering down
2024-02-13T17:51:29.359Z,1707846689.359 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-02-13T17:51:29.390Z,1707846689.390 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-02-13T17:51:29.403Z,1707846689.403 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-02-13T17:51:29.404Z,1707846689.404 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-02-13T17:51:29.411Z,1707846689.411 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-02-13T17:51:29.412Z,1707846689.412 [MassServo](DEBUG): Initializing EZServoServo.
2024-02-13T17:51:29.419Z,1707846689.419 [MassServo](DEBUG): Initializing MassServo.
2024-02-13T17:51:29.420Z,1707846689.420 [RudderServo](DEBUG): Initializing EZServoServo.
2024-02-13T17:51:29.427Z,1707846689.427 [RudderServo](DEBUG): Initializing RudderServo.
2024-02-13T17:51:29.428Z,1707846689.428 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-02-13T17:51:29.435Z,1707846689.435 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-02-13T17:51:30.958Z,1707846690.958 [WetLabsBB2FL](INFO): Powering down
2024-02-13T17:51:31.157Z,1707846691.157 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-02-13T17:51:32.443Z,1707846692.443 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2024-02-13T17:51:35.103Z,1707846695.103 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-02-13T17:51:57.016Z,1707846717.016 [NAL9602](INFO): Powering up NAL9602
2024-02-13T17:52:04.855Z,1707846724.855 [CommandExec](IMPORTANT): got command failComponent
2024-02-13T17:52:04.855Z,1707846724.855 [CommandExec](IMPORTANT): Failed components:
2024-02-13T17:52:04.855Z,1707846724.855 [CommandExec](IMPORTANT): No failed Components.
2024-02-13T17:52:07.924Z,1707846727.924 [NAL9602](INFO): NAL9602 initialized
2024-02-13T17:52:23.723Z,1707846743.723 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2024-02-13T17:52:23.724Z,1707846743.724 [CommandExec](IMPORTANT): Onboard.Humidity 12.873886 %
2024-02-13T17:52:34.515Z,1707846754.515 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2024-02-13T17:52:34.515Z,1707846754.515 [CommandExec](IMPORTANT): Onboard.Pressure 66644.468750 Pa
2024-02-13T17:52:41.408Z,1707846761.408 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2024-02-13T17:52:41.409Z,1707846761.409 [CommandExec](IMPORTANT): Onboard.Pressure 9.686417 psi
2024-02-13T17:52:57.285Z,1707846777.285 [SBIT](IMPORTANT): Beginning Startup BIT
2024-02-13T17:52:57.290Z,1707846777.290 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-13T17:53:08.169Z,1707846788.169 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-02-13T17:53:08.558Z,1707846788.558 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-13T17:53:19.513Z,1707846799.513 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-02-13T17:53:19.924Z,1707846799.924 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-13T17:53:30.794Z,1707846810.794 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-02-13T17:53:30.795Z,1707846810.795 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-02-13T17:53:51.378Z,1707846831.378 [SBIT](IMPORTANT): SBIT PASSED
2024-02-13T17:53:51.378Z,1707846831.378 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-02-13T17:53:51.379Z,1707846831.379 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2024-02-13T17:53:51.379Z,1707846831.379 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2024-02-13T17:53:51.379Z,1707846831.379 [SBIT](IMPORTANT): BuoyancyServo.deviationVolume=6 cubic_centimeter;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=378.076889 cubic_centimeter;

2024-02-13T17:53:51.380Z,1707846831.380 [SBIT](IMPORTANT): VerticalControl.massDefault=-17.019785 millimeter;

2024-02-13T17:53:51.790Z,1707846831.790 [MissionManager](IMPORTANT): Started mission Startup
2024-02-13T17:53:51.790Z,1707846831.790 [Startup] Running Loop=1
2024-02-13T17:53:51.790Z,1707846831.790 [Startup](DEBUG): Aggregate::initialize Startup
2024-02-13T17:53:51.790Z,1707846831.790 [Startup:A.GoToSurface] Running Loop=1
2024-02-13T17:53:51.790Z,1707846831.790 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-02-13T17:53:51.791Z,1707846831.791 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-02-13T17:53:51.791Z,1707846831.791 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-02-13T17:53:51.792Z,1707846831.792 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-02-13T17:53:51.792Z,1707846831.792 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-02-13T17:53:51.792Z,1707846831.792 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-02-13T17:53:51.794Z,1707846831.794 [Startup:StartupSatComms] Running Loop=1
2024-02-13T17:53:51.794Z,1707846831.794 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-02-13T17:53:51.794Z,1707846831.794 [Startup:StartupSatComms:A] Running Loop=1
2024-02-13T17:53:52.190Z,1707846832.190 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-02-13T17:54:11.725Z,1707846851.725 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004971
2024-02-13T17:54:29.038Z,1707846869.038 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-02-13T17:54:29.038Z,1707846869.038 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-13T17:54:29.075Z,1707846869.075 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-13T17:54:29.402Z,1707846869.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-13T17:54:29.402Z,1707846869.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-02-13T17:54:51.994Z,1707846891.994 [Startup:StartupSatComms:A](INFO): Timed out from 2024-02-13T17:53:51.8Z
2024-02-13T17:54:51.994Z,1707846891.994 [Startup:StartupSatComms:A] Stopped
2024-02-13T17:54:51.994Z,1707846891.994 [Startup:StartupSatComms:B] Running Loop=1
2024-02-13T17:54:52.401Z,1707846892.401 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-02-13T17:54:59.369Z,1707846899.369 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20240213T174719/Courier0000.lzma
2024-02-13T17:55:00.368Z,1707846900.368 [DataOverHttps](INFO): Moved sent file to Logs/20240213T174719/Courier0000.lzma.bak
2024-02-13T17:55:00.368Z,1707846900.368 [DataOverHttps](INFO): SBD MOMSN=19276819
2024-02-13T17:55:08.263Z,1707846908.263 [CommandExec](IMPORTANT): got command ibit
2024-02-13T17:55:08.584Z,1707846908.584 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-02-13T17:55:08.584Z,1707846908.584 [IBIT](IMPORTANT): Beginning control surface checks.
2024-02-13T17:55:08.588Z,1707846908.588 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-13T17:55:16.246Z,1707846916.246 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240213T174719/Courier0004.lzma
2024-02-13T17:55:17.248Z,1707846917.248 [DataOverHttps](INFO): Moved sent file to Logs/20240213T174719/Courier0004.lzma.bak
2024-02-13T17:55:17.248Z,1707846917.248 [DataOverHttps](INFO): SBD MOMSN=19276824
2024-02-13T17:55:19.511Z,1707846919.511 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-02-13T17:55:19.915Z,1707846919.915 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-13T17:55:30.848Z,1707846930.848 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-02-13T17:55:31.403Z,1707846931.403 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-13T17:55:33.158Z,1707846933.158 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240213T175117/Courier0000.lzma
2024-02-13T17:55:34.160Z,1707846934.160 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0000.lzma.bak
2024-02-13T17:55:34.160Z,1707846934.160 [DataOverHttps](INFO): SBD MOMSN=19276826
2024-02-13T17:55:42.587Z,1707846942.587 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.003825
CHAN A1 (24V): 0.000627
CHAN A2 (12V): -0.002059
CHAN A3 (5V): -0.001388
CHAN B0 (3.3V): 0.000149
CHAN B1 (3.15aV): 0.000255
CHAN B2 (3.15bV): 0.000225
CHAN B3 (GND): 0.000370
OPEN: 4.764929
Full Scale: +/- 1 mA
2024-02-13T17:55:49.928Z,1707846949.928 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20240124T172639/Express0097.lzma
2024-02-13T17:55:50.929Z,1707846950.929 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0097.lzma.bak
2024-02-13T17:55:50.929Z,1707846950.929 [DataOverHttps](INFO): SBD MOMSN=19276829
2024-02-13T17:56:06.460Z,1707846966.460 [DataOverHttps](INFO): Sending 1089 bytes from file Logs/20240213T174719/Express0001.lzma
2024-02-13T17:56:07.460Z,1707846967.460 [DataOverHttps](INFO): Moved sent file to Logs/20240213T174719/Express0001.lzma.bak
2024-02-13T17:56:07.460Z,1707846967.460 [DataOverHttps](INFO): SBD MOMSN=19276836
2024-02-13T17:56:21.974Z,1707846981.974 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20240213T174719/Express0005.lzma
2024-02-13T17:56:22.976Z,1707846982.976 [DataOverHttps](INFO): Moved sent file to Logs/20240213T174719/Express0005.lzma.bak
2024-02-13T17:56:22.976Z,1707846982.976 [DataOverHttps](INFO): SBD MOMSN=19276866
2024-02-13T17:56:38.348Z,1707846998.348 [DataOverHttps](INFO): Sending 1079 bytes from file Logs/20240213T175117/Express0001.lzma
2024-02-13T17:56:39.348Z,1707846999.348 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0001.lzma.bak
2024-02-13T17:56:39.348Z,1707846999.348 [DataOverHttps](INFO): SBD MOMSN=19276869
2024-02-13T17:56:59.676Z,1707847019.676 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-02-13T17:57:00.095Z,1707847020.095 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2024-02-13T17:57:00.504Z,1707847020.504 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-02-13T17:57:00.504Z,1707847020.504 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-02-13T17:57:00.505Z,1707847020.505 [IBIT](IMPORTANT): Pressure:9.670515 PSI
2024-02-13T17:57:00.505Z,1707847020.505 [IBIT](IMPORTANT): Humidity:12.922721 %
2024-02-13T17:57:00.877Z,1707847020.877 [IBIT](IMPORTANT): Vehicle Pitch:-1.005466 degrees
2024-02-13T17:57:00.878Z,1707847020.878 [IBIT](IMPORTANT): Vehicle  Roll:-0.068639 degrees
2024-02-13T17:57:00.878Z,1707847020.878 [IBIT](IMPORTANT): Vehicle  Heading:184.578949 degrees
2024-02-13T17:57:01.276Z,1707847021.276 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-02-13T17:57:01.276Z,1707847021.276 [IBIT](IMPORTANT): buoyancyNeutral: 378.076874 cc
2024-02-13T17:57:01.277Z,1707847021.277 [IBIT](IMPORTANT): massDefault: -1.701978 cm
2024-02-13T17:57:01.277Z,1707847021.277 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-02-13T17:57:01.277Z,1707847021.277 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-02-13T17:57:01.277Z,1707847021.277 [IBIT](IMPORTANT): IBIT FAILED
2024-02-13T17:57:01.669Z,1707847021.669 [Startup:StartupSatComms:B](INFO): Timed out from 2024-02-13T17:54:51.0Z
2024-02-13T17:57:01.669Z,1707847021.669 [Startup:StartupSatComms:B] Stopped
2024-02-13T17:57:01.669Z,1707847021.669 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-02-13T17:57:01.670Z,1707847021.670 [Startup:StartupSatComms] Stopped
2024-02-13T17:57:01.670Z,1707847021.670 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-02-13T17:57:01.699Z,1707847021.699 [Startup](INFO): Completed Startup
2024-02-13T17:57:01.700Z,1707847021.700 [MissionManager](INFO): Startup is completed.
2024-02-13T17:57:01.700Z,1707847021.700 [MissionManager](INFO): Uninitializing Mission Startup
2024-02-13T17:57:01.700Z,1707847021.700 [Startup] Stopped
2024-02-13T17:57:01.700Z,1707847021.700 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-02-13T17:57:01.700Z,1707847021.700 [Startup:A.GoToSurface] Stopped
2024-02-13T17:57:01.700Z,1707847021.700 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-02-13T17:57:02.077Z,1707847022.077 [MissionManager](IMPORTANT): Started mission Default
2024-02-13T17:57:02.077Z,1707847022.077 [Default] Running Loop=1
2024-02-13T17:57:02.077Z,1707847022.077 [Default](DEBUG): Aggregate::initialize Default
2024-02-13T17:57:02.077Z,1707847022.077 [Default:B.GoToSurface] Running Loop=1
2024-02-13T17:57:02.077Z,1707847022.077 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-02-13T17:57:02.078Z,1707847022.078 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-02-13T17:57:02.078Z,1707847022.078 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-02-13T17:57:02.078Z,1707847022.078 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-02-13T17:57:02.079Z,1707847022.079 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-02-13T17:57:02.079Z,1707847022.079 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-02-13T17:57:02.079Z,1707847022.079 [Default:A.Wait] Running Loop=1
2024-02-13T17:57:02.079Z,1707847022.079 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-02-13T17:57:11.356Z,1707847031.356 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-02-13T17:57:15.405Z,1707847035.405 [Default:A.Wait](INFO): Done Waiting.
2024-02-13T17:57:15.405Z,1707847035.405 [Default:A.Wait] Stopped
2024-02-13T17:57:15.405Z,1707847035.405 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T17:57:15.825Z,1707847035.825 [Default:CheckIn] Running Loop=1
2024-02-13T17:57:15.825Z,1707847035.825 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T17:57:15.825Z,1707847035.825 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T17:57:16.239Z,1707847036.239 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-02-13T17:57:22.659Z,1707847042.659 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:57:29.956Z,1707847049.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-02-13T17:57:29.956Z,1707847049.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-13T17:57:29.987Z,1707847049.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-13T17:57:30.373Z,1707847050.373 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-13T17:57:30.373Z,1707847050.373 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-02-13T17:57:35.185Z,1707847055.185 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:57:47.707Z,1707847067.707 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:58:00.231Z,1707847080.231 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:58:13.159Z,1707847093.159 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:58:25.685Z,1707847105.685 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:58:38.207Z,1707847118.207 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:58:50.743Z,1707847130.743 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:59:04.072Z,1707847144.072 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:59:16.587Z,1707847156.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:59:29.515Z,1707847169.515 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:59:42.443Z,1707847182.443 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T17:59:44.471Z,1707847184.471 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-02-13T17:59:44.475Z,1707847184.475 [BPC1](INFO): Received data from all battery sticks.
2024-02-13T18:00:31.024Z,1707847231.024 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-02-13T18:00:31.024Z,1707847231.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-13T18:00:31.035Z,1707847231.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-13T18:00:31.430Z,1707847231.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-13T18:00:31.430Z,1707847231.430 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-02-13T18:01:30.391Z,1707847290.391 [Power24vConverter](INFO): Powering down.
2024-02-13T18:02:10.776Z,1707847330.776 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-13T18:02:10.776Z,1707847330.776 [NAL9602] Data Fault, FailCount= 1
2024-02-13T18:02:10.776Z,1707847330.776 [NAL9602](ERROR): Data Fault
2024-02-13T18:02:10.849Z,1707847330.849 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-13T18:02:11.180Z,1707847331.180 [NAL9602](INFO): Powering down
2024-02-13T18:02:12.016Z,1707847332.016 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-13T18:02:12.016Z,1707847332.016 [NAL9602] No Fault, FailCount= 1
2024-02-13T18:02:16.040Z,1707847336.040 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-13T17:57:15.8Z
2024-02-13T18:02:16.040Z,1707847336.040 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T18:02:16.040Z,1707847336.040 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T18:02:16.444Z,1707847336.444 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-02-13T18:02:22.878Z,1707847342.878 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240213T175117/Courier0004.lzma
2024-02-13T18:02:23.880Z,1707847343.880 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0004.lzma.bak
2024-02-13T18:02:23.880Z,1707847343.880 [DataOverHttps](INFO): SBD MOMSN=19276904
2024-02-13T18:02:39.564Z,1707847359.564 [DataOverHttps](INFO): Sending 799 bytes from file Logs/20240213T175117/Express0005.lzma
2024-02-13T18:02:40.564Z,1707847360.564 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0005.lzma.bak
2024-02-13T18:02:40.564Z,1707847360.564 [DataOverHttps](INFO): SBD MOMSN=19276906
2024-02-13T18:02:41.480Z,1707847361.480 [NAL9602](INFO): Powering up NAL9602
2024-02-13T18:02:41.908Z,1707847361.908 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T18:02:41.908Z,1707847361.908 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T18:02:41.908Z,1707847361.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T18:02:52.392Z,1707847372.392 [NAL9602](INFO): NAL9602 initialized
2024-02-13T18:03:31.995Z,1707847411.995 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-02-13T18:03:31.995Z,1707847411.995 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-13T18:03:32.006Z,1707847412.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-13T18:03:32.416Z,1707847412.416 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-13T18:03:32.416Z,1707847412.416 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-02-13T18:06:32.997Z,1707847592.997 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-02-13T18:06:32.997Z,1707847592.997 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-02-13T18:06:33.008Z,1707847593.008 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-02-13T18:06:33.412Z,1707847593.412 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-02-13T18:06:33.413Z,1707847593.413 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-02-13T18:06:43.971Z,1707847603.971 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T18:07:42.501Z,1707847662.501 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T18:07:42.501Z,1707847662.501 [Default:CheckIn:C.Wait] Stopped
2024-02-13T18:07:42.502Z,1707847662.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T18:07:42.502Z,1707847662.502 [Default:CheckIn:D] Running Loop=1
2024-02-13T18:07:42.916Z,1707847662.916 [Default:CheckIn:D] Stopped
2024-02-13T18:07:42.916Z,1707847662.916 [Default:CheckIn:E] Running Loop=1
2024-02-13T18:07:43.333Z,1707847663.333 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.680650 min 
2024-02-13T18:07:43.333Z,1707847663.333 [Default:CheckIn:E] Stopped
2024-02-13T18:07:43.334Z,1707847663.334 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T18:07:43.334Z,1707847663.334 [Default:CheckIn] Stopped
2024-02-13T18:07:43.334Z,1707847663.334 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T18:07:43.334Z,1707847663.334 [Default:CheckIn](INFO): Running loop #2
2024-02-13T18:07:43.334Z,1707847663.334 [Default:CheckIn] Running Loop=2
2024-02-13T18:07:43.334Z,1707847663.334 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T18:07:43.334Z,1707847663.334 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T18:08:50.030Z,1707847730.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180848.00,A,3648.17429,N,12147.28536,W,0.272,0.00,130224,,,A*7B
2024-02-13T18:08:50.104Z,1707847730.104 [NAL9602](INFO): GPS fix at 20240213T180848: (36.802905, -121.788089)
2024-02-13T18:08:50.337Z,1707847730.337 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T18:08:50.337Z,1707847730.337 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T18:08:59.934Z,1707847739.934 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240213T175117/Courier0007.lzma
2024-02-13T18:09:00.576Z,1707847740.576 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0007.lzma.bak
2024-02-13T18:09:00.576Z,1707847740.576 [DataOverHttps](INFO): SBD MOMSN=19276933
2024-02-13T18:09:09.368Z,1707847749.368 [DataOverHttps](IMPORTANT): SBD MTMSN=20240213T180908
2024-02-13T18:09:17.898Z,1707847757.898 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240213T175117/Express0008.lzma
2024-02-13T18:09:17.901Z,1707847757.901 [DataOverHttps](INFO): Received command: ibit
2024-02-13T18:09:17.939Z,1707847757.939 [CommandExec](IMPORTANT): got command ibit
2024-02-13T18:09:18.255Z,1707847758.255 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-02-13T18:09:18.255Z,1707847758.255 [IBIT](IMPORTANT): Beginning control surface checks.
2024-02-13T18:09:18.259Z,1707847758.259 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-13T18:09:19.219Z,1707847759.219 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0008.lzma.bak
2024-02-13T18:09:19.219Z,1707847759.219 [DataOverHttps](INFO): SBD MOMSN=19276937
2024-02-13T18:09:19.858Z,1707847759.858 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180918.00,A,3648.17471,N,12147.28731,W,0.097,0.00,130224,,,A*7E
2024-02-13T18:09:19.860Z,1707847759.860 [NAL9602](INFO): GPS fix at 20240213T180918: (36.802912, -121.788122)
2024-02-13T18:09:21.159Z,1707847761.159 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2024-02-13T18:09:23.085Z,1707847763.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180921.00,A,3648.17467,N,12147.28727,W,0.136,0.00,130224,,,A*7E
2024-02-13T18:09:23.087Z,1707847763.087 [NAL9602](INFO): GPS fix at 20240213T180921: (36.802911, -121.788121)
2024-02-13T18:09:25.919Z,1707847765.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180924.00,A,3648.17477,N,12147.28717,W,0.039,0.00,130224,,,A*77
2024-02-13T18:09:25.921Z,1707847765.921 [NAL9602](INFO): GPS fix at 20240213T180924: (36.802913, -121.788119)
2024-02-13T18:09:28.742Z,1707847768.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180927.00,A,3648.17495,N,12147.28712,W,0.039,0.00,130224,,,A*7D
2024-02-13T18:09:28.744Z,1707847768.744 [NAL9602](INFO): GPS fix at 20240213T180927: (36.802916, -121.788119)
2024-02-13T18:09:29.191Z,1707847769.191 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002406
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002251
CHAN A3 (5V): -0.001333
CHAN B0 (3.3V): -0.000221
CHAN B1 (3.15aV): -0.000196
CHAN B2 (3.15bV): -0.000393
CHAN B3 (GND): 0.000084
OPEN: -0.000268
Full Scale: +/- 1 mA
2024-02-13T18:09:31.973Z,1707847771.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180930.00,A,3648.17520,N,12147.28715,W,0.097,0.00,130224,,,A*77
2024-02-13T18:09:31.976Z,1707847771.976 [NAL9602](INFO): GPS fix at 20240213T180930: (36.802920, -121.788119)
2024-02-13T18:09:34.805Z,1707847774.805 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180933.00,A,3648.17555,N,12147.28731,W,0.019,0.00,130224,,,A*76
2024-02-13T18:09:34.808Z,1707847774.808 [NAL9602](INFO): GPS fix at 20240213T180933: (36.802926, -121.788122)
2024-02-13T18:09:35.315Z,1707847775.315 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240213T175117/Courier0010.lzma
2024-02-13T18:09:36.316Z,1707847776.316 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0010.lzma.bak
2024-02-13T18:09:36.316Z,1707847776.316 [DataOverHttps](INFO): SBD MOMSN=19276941
2024-02-13T18:09:38.038Z,1707847778.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180936.00,A,3648.17573,N,12147.28739,W,0.058,0.00,130224,,,A*7A
2024-02-13T18:09:38.040Z,1707847778.040 [NAL9602](INFO): GPS fix at 20240213T180936: (36.802929, -121.788123)
2024-02-13T18:09:40.865Z,1707847780.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180939.00,A,3648.17585,N,12147.28745,W,0.058,0.00,130224,,,A*77
2024-02-13T18:09:40.868Z,1707847780.868 [NAL9602](INFO): GPS fix at 20240213T180939: (36.802931, -121.788124)
2024-02-13T18:09:44.094Z,1707847784.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180942.00,A,3648.17593,N,12147.28754,W,0.039,0.00,130224,,,A*7B
2024-02-13T18:09:44.096Z,1707847784.096 [NAL9602](INFO): GPS fix at 20240213T180942: (36.802932, -121.788126)
2024-02-13T18:09:46.925Z,1707847786.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180945.00,A,3648.17605,N,12147.28763,W,0.058,0.00,130224,,,A*73
2024-02-13T18:09:46.928Z,1707847786.928 [NAL9602](INFO): GPS fix at 20240213T180945: (36.802934, -121.788127)
2024-02-13T18:09:50.158Z,1707847790.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180948.00,A,3648.17615,N,12147.28769,W,0.078,0.00,130224,,,A*77
2024-02-13T18:09:50.161Z,1707847790.161 [NAL9602](INFO): GPS fix at 20240213T180948: (36.802936, -121.788128)
2024-02-13T18:09:52.985Z,1707847792.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180951.00,A,3648.17623,N,12147.28775,W,0.019,0.00,130224,,,A*70
2024-02-13T18:09:52.997Z,1707847792.997 [NAL9602](INFO): GPS fix at 20240213T180951: (36.802937, -121.788129)
2024-02-13T18:09:53.542Z,1707847793.542 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20240213T175117/Express0011.lzma
2024-02-13T18:09:54.544Z,1707847794.544 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0011.lzma.bak
2024-02-13T18:09:54.544Z,1707847794.544 [DataOverHttps](INFO): SBD MOMSN=19276943
2024-02-13T18:09:55.809Z,1707847795.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180954.00,A,3648.17633,N,12147.28781,W,0.039,0.00,130224,,,A*7D
2024-02-13T18:09:55.812Z,1707847795.812 [NAL9602](INFO): GPS fix at 20240213T180954: (36.802939, -121.788130)
2024-02-13T18:09:59.041Z,1707847799.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180957.00,A,3648.17640,N,12147.28787,W,0.039,0.00,130224,,,A*7C
2024-02-13T18:09:59.044Z,1707847799.044 [NAL9602](INFO): GPS fix at 20240213T180957: (36.802940, -121.788131)
2024-02-13T18:10:01.873Z,1707847801.873 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181000.00,A,3648.17655,N,12147.28798,W,0.019,0.00,130224,,,A*7E
2024-02-13T18:10:01.876Z,1707847801.876 [NAL9602](INFO): GPS fix at 20240213T181000: (36.802943, -121.788133)
2024-02-13T18:10:05.109Z,1707847805.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181003.00,A,3648.17667,N,12147.28808,W,0.019,0.00,130224,,,A*7A
2024-02-13T18:10:05.112Z,1707847805.112 [NAL9602](INFO): GPS fix at 20240213T181003: (36.802945, -121.788135)
2024-02-13T18:10:07.937Z,1707847807.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181006.00,A,3648.17674,N,12147.28817,W,0.000,0.00,130224,,,A*7B
2024-02-13T18:10:07.940Z,1707847807.940 [NAL9602](INFO): GPS fix at 20240213T181006: (36.802946, -121.788136)
2024-02-13T18:10:11.165Z,1707847811.165 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181009.00,A,3648.17678,N,12147.28825,W,0.058,0.00,130224,,,A*74
2024-02-13T18:10:11.168Z,1707847811.168 [NAL9602](INFO): GPS fix at 20240213T181009: (36.802946, -121.788138)
2024-02-13T18:10:13.989Z,1707847813.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181012.00,A,3648.17683,N,12147.28835,W,0.000,0.00,130224,,,A*76
2024-02-13T18:10:14.002Z,1707847814.002 [NAL9602](INFO): GPS fix at 20240213T181012: (36.802947, -121.788139)
2024-02-13T18:10:16.818Z,1707847816.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181015.00,A,3648.17679,N,12147.28841,W,0.019,0.00,130224,,,A*7F
2024-02-13T18:10:16.820Z,1707847816.820 [NAL9602](INFO): GPS fix at 20240213T181015: (36.802946, -121.788140)
2024-02-13T18:10:20.097Z,1707847820.097 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181018.00,A,3648.17672,N,12147.28841,W,0.078,0.00,130224,,,A*7E
2024-02-13T18:10:20.100Z,1707847820.100 [NAL9602](INFO): GPS fix at 20240213T181018: (36.802945, -121.788140)
2024-02-13T18:10:22.920Z,1707847822.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181021.00,A,3648.17666,N,12147.28840,W,0.000,0.00,130224,,,A*7F
2024-02-13T18:10:22.922Z,1707847822.922 [NAL9602](INFO): GPS fix at 20240213T181021: (36.802944, -121.788140)
2024-02-13T18:10:26.170Z,1707847826.170 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181024.00,A,3648.17659,N,12147.28839,W,0.019,0.00,130224,,,A*70
2024-02-13T18:10:26.172Z,1707847826.172 [NAL9602](INFO): GPS fix at 20240213T181024: (36.802943, -121.788140)
2024-02-13T18:10:29.006Z,1707847829.006 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181027.00,A,3648.17653,N,12147.28839,W,0.058,0.00,130224,,,A*7C
2024-02-13T18:10:29.008Z,1707847829.008 [NAL9602](INFO): GPS fix at 20240213T181027: (36.802942, -121.788140)
2024-02-13T18:10:31.833Z,1707847831.833 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181030.00,A,3648.17651,N,12147.28840,W,0.039,0.00,130224,,,A*71
2024-02-13T18:10:31.836Z,1707847831.836 [NAL9602](INFO): GPS fix at 20240213T181030: (36.802942, -121.788140)
2024-02-13T18:10:35.067Z,1707847835.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181033.00,A,3648.17653,N,12147.28841,W,0.058,0.00,130224,,,A*76
2024-02-13T18:10:35.083Z,1707847835.083 [NAL9602](INFO): GPS fix at 20240213T181033: (36.802942, -121.788140)
2024-02-13T18:10:37.905Z,1707847837.905 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181036.00,A,3648.17657,N,12147.28845,W,0.039,0.00,130224,,,A*74
2024-02-13T18:10:37.908Z,1707847837.908 [NAL9602](INFO): GPS fix at 20240213T181036: (36.802943, -121.788141)
2024-02-13T18:10:41.126Z,1707847841.126 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181039.00,A,3648.17659,N,12147.28852,W,0.019,0.00,130224,,,A*71
2024-02-13T18:10:41.142Z,1707847841.142 [NAL9602](INFO): GPS fix at 20240213T181039: (36.802943, -121.788142)
2024-02-13T18:10:43.957Z,1707847843.957 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181042.00,A,3648.17660,N,12147.28858,W,0.019,0.00,130224,,,A*7D
2024-02-13T18:10:43.960Z,1707847843.960 [NAL9602](INFO): GPS fix at 20240213T181042: (36.802943, -121.788143)
2024-02-13T18:10:46.781Z,1707847846.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181045.00,A,3648.17659,N,12147.28861,W,0.039,0.00,130224,,,A*78
2024-02-13T18:10:46.783Z,1707847846.783 [NAL9602](INFO): GPS fix at 20240213T181045: (36.802943, -121.788144)
2024-02-13T18:10:50.029Z,1707847850.029 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181048.00,A,3648.17654,N,12147.28861,W,0.058,0.00,130224,,,A*7F
2024-02-13T18:10:50.032Z,1707847850.032 [NAL9602](INFO): GPS fix at 20240213T181048: (36.802942, -121.788144)
2024-02-13T18:10:52.881Z,1707847852.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181051.00,A,3648.17650,N,12147.28859,W,0.039,0.00,130224,,,A*7F
2024-02-13T18:10:52.884Z,1707847852.884 [NAL9602](INFO): GPS fix at 20240213T181051: (36.802942, -121.788143)
2024-02-13T18:10:56.149Z,1707847856.149 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181054.00,A,3648.17645,N,12147.28856,W,0.000,0.00,130224,,,A*7B
2024-02-13T18:10:56.152Z,1707847856.152 [NAL9602](INFO): GPS fix at 20240213T181054: (36.802941, -121.788143)
2024-02-13T18:10:58.973Z,1707847858.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181057.00,A,3648.17644,N,12147.28853,W,0.039,0.00,130224,,,A*76
2024-02-13T18:10:58.975Z,1707847858.975 [NAL9602](INFO): GPS fix at 20240213T181057: (36.802941, -121.788142)
2024-02-13T18:11:01.802Z,1707847861.802 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181100.00,A,3648.17644,N,12147.28849,W,0.019,0.00,130224,,,A*7C
2024-02-13T18:11:01.808Z,1707847861.808 [NAL9602](INFO): GPS fix at 20240213T181100: (36.802941, -121.788141)
2024-02-13T18:11:05.040Z,1707847865.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181103.00,A,3648.17642,N,12147.28846,W,0.019,0.00,130224,,,A*76
2024-02-13T18:11:05.043Z,1707847865.043 [NAL9602](INFO): GPS fix at 20240213T181103: (36.802940, -121.788141)
2024-02-13T18:11:07.861Z,1707847867.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181107.00,A,3648.17647,N,12147.28848,W,0.058,0.00,130224,,,A*7C
2024-02-13T18:11:07.864Z,1707847867.864 [NAL9602](INFO): GPS fix at 20240213T181107: (36.802941, -121.788141)
2024-02-13T18:11:09.544Z,1707847869.544 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-02-13T18:11:09.921Z,1707847869.921 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.620000
2024-02-13T18:11:09.921Z,1707847869.921 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-02-13T18:11:09.921Z,1707847869.921 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-02-13T18:11:10.324Z,1707847870.324 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-02-13T18:11:10.324Z,1707847870.324 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-02-13T18:11:10.325Z,1707847870.325 [IBIT](IMPORTANT): Pressure:9.690977 PSI
2024-02-13T18:11:10.325Z,1707847870.325 [IBIT](IMPORTANT): Humidity:12.843365 %
2024-02-13T18:11:10.752Z,1707847870.752 [IBIT](IMPORTANT): Vehicle Pitch:-0.819657 degrees
2024-02-13T18:11:10.753Z,1707847870.753 [IBIT](IMPORTANT): Vehicle  Roll:0.357776 degrees
2024-02-13T18:11:10.753Z,1707847870.753 [IBIT](IMPORTANT): Vehicle  Heading:205.010345 degrees
2024-02-13T18:11:11.098Z,1707847871.098 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181110.00,A,3648.17638,N,12147.28847,W,0.039,0.00,130224,,,A*7A
2024-02-13T18:11:11.100Z,1707847871.100 [NAL9602](INFO): GPS fix at 20240213T181110: (36.802940, -121.788141)
2024-02-13T18:11:11.133Z,1707847871.133 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-02-13T18:11:11.134Z,1707847871.134 [IBIT](IMPORTANT): buoyancyNeutral: 378.076874 cc
2024-02-13T18:11:11.134Z,1707847871.134 [IBIT](IMPORTANT): massDefault: -1.701978 cm
2024-02-13T18:11:11.158Z,1707847871.158 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-02-13T18:11:11.159Z,1707847871.159 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-02-13T18:11:11.159Z,1707847871.159 [IBIT](IMPORTANT): IBIT FAILED
2024-02-13T18:11:11.535Z,1707847871.535 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T18:11:11.535Z,1707847871.535 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T18:11:11.536Z,1707847871.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T18:11:43.411Z,1707847903.411 [NAL9602](INFO): Not Powering down - fast GPS
2024-02-13T18:16:11.949Z,1707848171.949 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T18:16:11.949Z,1707848171.949 [Default:CheckIn:C.Wait] Stopped
2024-02-13T18:16:11.949Z,1707848171.949 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T18:16:11.949Z,1707848171.949 [Default:CheckIn:D] Running Loop=1
2024-02-13T18:16:12.395Z,1707848172.395 [Default:CheckIn:D] Stopped
2024-02-13T18:16:12.395Z,1707848172.395 [Default:CheckIn:E] Running Loop=1
2024-02-13T18:16:12.766Z,1707848172.766 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.171962 min 
2024-02-13T18:16:12.766Z,1707848172.766 [Default:CheckIn:E] Stopped
2024-02-13T18:16:12.766Z,1707848172.766 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T18:16:12.766Z,1707848172.766 [Default:CheckIn] Stopped
2024-02-13T18:16:12.767Z,1707848172.767 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T18:16:12.767Z,1707848172.767 [Default:CheckIn](INFO): Running loop #3
2024-02-13T18:16:12.767Z,1707848172.767 [Default:CheckIn] Running Loop=3
2024-02-13T18:16:12.767Z,1707848172.767 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T18:16:12.767Z,1707848172.767 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T18:16:14.789Z,1707848174.789 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181614.00,A,3648.16586,N,12147.28067,W,0.991,175.92,130224,,,A*77
2024-02-13T18:16:14.792Z,1707848174.792 [NAL9602](INFO): GPS fix at 20240213T181614: (36.802764, -121.788011)
2024-02-13T18:16:14.803Z,1707848174.803 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T18:16:14.803Z,1707848174.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T18:16:25.762Z,1707848185.762 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240213T175117/Courier0013.lzma
2024-02-13T18:16:26.764Z,1707848186.764 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0013.lzma.bak
2024-02-13T18:16:26.764Z,1707848186.764 [DataOverHttps](INFO): SBD MOMSN=19276950
2024-02-13T18:16:43.616Z,1707848203.616 [DataOverHttps](INFO): Sending 680 bytes from file Logs/20240213T175117/Express0014.lzma
2024-02-13T18:16:44.616Z,1707848204.616 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0014.lzma.bak
2024-02-13T18:16:44.616Z,1707848204.616 [DataOverHttps](INFO): SBD MOMSN=19276953
2024-02-13T18:16:45.967Z,1707848205.967 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T18:16:45.967Z,1707848205.967 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T18:16:45.967Z,1707848205.967 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T18:18:08.820Z,1707848288.820 [NAL9602](INFO): SBD MO Status=0, MOMSN=46935, MT Status=0, MTMSN=0
2024-02-13T18:18:08.820Z,1707848288.820 [NAL9602](INFO): No messages in MT queue
2024-02-13T18:18:39.605Z,1707848319.605 [NAL9602](INFO): Not Powering down - fast GPS
2024-02-13T18:21:46.393Z,1707848506.393 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T18:21:46.394Z,1707848506.394 [Default:CheckIn:C.Wait] Stopped
2024-02-13T18:21:46.394Z,1707848506.394 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T18:21:46.394Z,1707848506.394 [Default:CheckIn:D] Running Loop=1
2024-02-13T18:21:46.784Z,1707848506.784 [Default:CheckIn:D] Stopped
2024-02-13T18:21:46.784Z,1707848506.784 [Default:CheckIn:E] Running Loop=1
2024-02-13T18:21:47.194Z,1707848507.194 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.745125 min 
2024-02-13T18:21:47.194Z,1707848507.194 [Default:CheckIn:E] Stopped
2024-02-13T18:21:47.195Z,1707848507.195 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T18:21:47.195Z,1707848507.195 [Default:CheckIn] Stopped
2024-02-13T18:21:47.195Z,1707848507.195 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T18:21:47.195Z,1707848507.195 [Default:CheckIn](INFO): Running loop #4
2024-02-13T18:21:47.195Z,1707848507.195 [Default:CheckIn] Running Loop=4
2024-02-13T18:21:47.195Z,1707848507.195 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T18:21:47.195Z,1707848507.195 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T18:21:49.201Z,1707848509.201 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182148.00,A,3648.16676,N,12147.28403,W,0.544,144.40,130224,,,A*79
2024-02-13T18:21:49.203Z,1707848509.203 [NAL9602](INFO): GPS fix at 20240213T182148: (36.802779, -121.788067)
2024-02-13T18:21:49.233Z,1707848509.233 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T18:21:49.233Z,1707848509.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T18:21:56.510Z,1707848516.510 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240213T175117/Courier0016.lzma
2024-02-13T18:21:57.512Z,1707848517.512 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0016.lzma.bak
2024-02-13T18:21:57.512Z,1707848517.512 [DataOverHttps](INFO): SBD MOMSN=19276975
2024-02-13T18:22:13.382Z,1707848533.382 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240213T175117/Express0017.lzma
2024-02-13T18:22:14.386Z,1707848534.386 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0017.lzma.bak
2024-02-13T18:22:14.386Z,1707848534.386 [DataOverHttps](INFO): SBD MOMSN=19276978
2024-02-13T18:22:15.887Z,1707848535.887 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T18:22:15.887Z,1707848535.887 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T18:22:15.887Z,1707848535.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T18:26:30.608Z,1707848790.608 [DataOverHttps](IMPORTANT): SBD MTMSN=20240213T182629
2024-02-13T18:26:38.066Z,1707848798.066 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.tl
2024-02-13T18:26:38.170Z,1707848798.170 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.tl
2024-02-13T18:26:38.171Z,1707848798.171 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl
2024-02-13T18:26:38.171Z,1707848798.171 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx
2024-02-13T18:26:38.248Z,1707848798.248 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2024-02-13T18:26:38.251Z,1707848798.251 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2024-02-13T18:26:38.253Z,1707848798.253 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2024-02-13T18:26:38.256Z,1707848798.256 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2024-02-13T18:26:38.259Z,1707848798.259 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2024-02-13T18:26:38.262Z,1707848798.262 [MissionManager](INFO): DefineArg ballast_and_trim.TargetDepthTimeout = 20.000000 min
2024-02-13T18:26:38.265Z,1707848798.265 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2024-02-13T18:26:38.268Z,1707848798.268 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2024-02-13T18:26:38.270Z,1707848798.270 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2024-02-13T18:26:38.273Z,1707848798.273 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2024-02-13T18:26:38.276Z,1707848798.276 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2024-02-13T18:26:38.279Z,1707848798.279 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2024-02-13T18:26:38.281Z,1707848798.281 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2024-02-13T18:26:38.282Z,1707848798.282 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2024-02-13T18:26:38.282Z,1707848798.282 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2024-02-13T18:26:38.788Z,1707848798.788 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2024-02-13T18:26:38.795Z,1707848798.795 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2024-02-13T18:26:38.797Z,1707848798.797 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2024-02-13T18:26:38.808Z,1707848798.808 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2024-02-13T18:26:38.815Z,1707848798.815 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2024-02-13T18:26:38.817Z,1707848798.817 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2024-02-13T18:26:38.821Z,1707848798.821 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2024-02-13T18:26:38.837Z,1707848798.837 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2024-02-13T18:26:38.844Z,1707848798.844 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2024-02-13T18:26:38.852Z,1707848798.852 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2024-02-13T18:26:38.858Z,1707848798.858 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2024-02-13T18:26:38.869Z,1707848798.869 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2024-02-13T18:26:38.875Z,1707848798.875 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2024-02-13T18:26:38.880Z,1707848798.880 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2024-02-13T18:26:38.883Z,1707848798.883 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2024-02-13T18:26:38.886Z,1707848798.886 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2024-02-13T18:26:38.889Z,1707848798.889 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2024-02-13T18:26:38.909Z,1707848798.909 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2024-02-13T18:26:38.912Z,1707848798.912 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2024-02-13T18:26:38.916Z,1707848798.916 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2024-02-13T18:26:38.939Z,1707848798.939 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2024-02-13T18:26:38.942Z,1707848798.942 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2024-02-13T18:26:38.945Z,1707848798.945 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2024-02-13T18:26:38.948Z,1707848798.948 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2024-02-13T18:26:38.963Z,1707848798.963 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2024-02-13T18:26:38.966Z,1707848798.966 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2024-02-13T18:26:38.968Z,1707848798.968 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool
2024-02-13T18:26:38.971Z,1707848798.971 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l
2024-02-13T18:26:38.974Z,1707848798.974 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l
2024-02-13T18:26:38.996Z,1707848798.996 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count
2024-02-13T18:26:38.999Z,1707848798.999 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l
2024-02-13T18:26:39.002Z,1707848799.002 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m
2024-02-13T18:26:39.015Z,1707848799.015 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC
2024-02-13T18:26:39.018Z,1707848799.018 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2024-02-13T18:26:39.021Z,1707848799.021 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2024-02-13T18:26:39.050Z,1707848799.050 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l
2024-02-13T18:26:39.053Z,1707848799.053 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m
2024-02-13T18:26:39.056Z,1707848799.056 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2024-02-13T18:26:39.071Z,1707848799.071 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2024-02-13T18:26:39.074Z,1707848799.074 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3
2024-02-13T18:26:39.077Z,1707848799.077 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m
2024-02-13T18:26:39.080Z,1707848799.080 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2024-02-13T18:26:39.096Z,1707848799.096 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2024-02-13T18:26:39.099Z,1707848799.099 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb
2024-02-13T18:26:39.102Z,1707848799.102 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m
2024-02-13T18:26:39.104Z,1707848799.104 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2024-02-13T18:26:39.152Z,1707848799.152 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2024-02-13T18:26:39.155Z,1707848799.155 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2024-02-13T18:26:39.157Z,1707848799.157 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2024-02-13T18:26:39.185Z,1707848799.185 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu
2024-02-13T18:26:39.189Z,1707848799.189 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m
2024-02-13T18:26:39.198Z,1707848799.198 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC
2024-02-13T18:26:39.201Z,1707848799.201 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2024-02-13T18:26:39.204Z,1707848799.204 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2024-02-13T18:26:39.235Z,1707848799.235 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l
2024-02-13T18:26:39.238Z,1707848799.238 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m
2024-02-13T18:26:39.241Z,1707848799.241 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2024-02-13T18:26:39.244Z,1707848799.244 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2024-02-13T18:26:39.264Z,1707848799.264 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2024-02-13T18:26:39.270Z,1707848799.270 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu
2024-02-13T18:26:39.281Z,1707848799.281 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m
2024-02-13T18:26:39.295Z,1707848799.295 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2024-02-13T18:26:39.298Z,1707848799.298 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2024-02-13T18:26:39.305Z,1707848799.305 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2024-02-13T18:26:39.313Z,1707848799.313 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu
2024-02-13T18:26:39.317Z,1707848799.317 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m
2024-02-13T18:26:39.329Z,1707848799.329 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2024-02-13T18:26:39.333Z,1707848799.333 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2024-02-13T18:26:39.344Z,1707848799.344 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2024-02-13T18:26:39.347Z,1707848799.347 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool
2024-02-13T18:26:39.358Z,1707848799.358 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l
2024-02-13T18:26:39.432Z,1707848799.432 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2024-02-13T18:26:39.456Z,1707848799.456 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2024-02-13T18:26:39.491Z,1707848799.491 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2024-02-13T18:26:39.520Z,1707848799.520 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2024-02-13T18:26:39.582Z,1707848799.582 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2024-02-13T18:26:39.614Z,1707848799.614 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2024-02-13T18:26:39.638Z,1707848799.638 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2024-02-13T18:26:39.673Z,1707848799.673 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2024-02-13T18:26:39.694Z,1707848799.694 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2024-02-13T18:26:39.713Z,1707848799.713 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-02-13T18:26:39.713Z,1707848799.713 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2024-02-13T18:26:39.736Z,1707848799.736 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool
2024-02-13T18:26:40.172Z,1707848800.172 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-02-13T18:26:40.192Z,1707848800.192 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl
2024-02-13T18:26:40.192Z,1707848800.192 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx
2024-02-13T18:26:40.444Z,1707848800.444 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2024-02-13T18:26:40.447Z,1707848800.447 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2024-02-13T18:26:40.467Z,1707848800.467 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2024-02-13T18:26:40.470Z,1707848800.470 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2024-02-13T18:26:40.473Z,1707848800.473 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2024-02-13T18:26:40.476Z,1707848800.476 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2024-02-13T18:26:40.487Z,1707848800.487 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2024-02-13T18:26:40.490Z,1707848800.490 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2024-02-13T18:26:40.497Z,1707848800.497 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2024-02-13T18:26:40.501Z,1707848800.501 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2024-02-13T18:26:40.512Z,1707848800.512 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2024-02-13T18:26:40.517Z,1707848800.517 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2024-02-13T18:26:40.528Z,1707848800.528 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2024-02-13T18:26:40.535Z,1707848800.535 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2024-02-13T18:26:40.561Z,1707848800.561 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2024-02-13T18:26:40.573Z,1707848800.573 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2024-02-13T18:26:40.585Z,1707848800.585 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2024-02-13T18:26:40.606Z,1707848800.606 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2024-02-13T18:26:40.610Z,1707848800.610 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2024-02-13T18:26:40.617Z,1707848800.617 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2024-02-13T18:26:40.633Z,1707848800.633 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2024-02-13T18:26:40.641Z,1707848800.641 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2024-02-13T18:26:40.644Z,1707848800.644 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2024-02-13T18:26:40.681Z,1707848800.681 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2024-02-13T18:26:40.682Z,1707848800.682 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute.
2024-02-13T18:26:40.700Z,1707848800.700 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2024-02-13T18:26:40.727Z,1707848800.727 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2024-02-13T18:26:40.729Z,1707848800.729 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout
2024-02-13T18:26:40.730Z,1707848800.730 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2024-02-13T18:26:40.732Z,1707848800.732 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2024-02-13T18:26:40.734Z,1707848800.734 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2024-02-13T18:26:40.780Z,1707848800.780 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2024-02-13T18:26:40.781Z,1707848800.781 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2024-02-13T18:26:40.791Z,1707848800.791 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2024-02-13T18:26:40.818Z,1707848800.818 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2024-02-13T18:26:40.822Z,1707848800.822 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2024-02-13T18:26:40.897Z,1707848800.897 [MissionManager](DEBUG): 
mission ballast_and_trim {
  arguments {
    MissionTimeout = 120 minute
      """
      Maximum duration of mission.
      """

    Depth1 = 7 meter
      """
      Target depth for ballast and trim.
      """

    Depth2 = NaN meter
      """
      Target depth to run a second ballast and trim, skipped if NaN.
      """

    DepthDeadband = 0.1 meter
      """
      How much vertical drift from the specified depth is allowed during the
      mission.
      """

    SurfaceThreshold = 2.5 meter
      """
      Surface threshold depth, relevant only to the ascent.
      """

    TargetDepthTimeout = 20 minute
      """
      Maximum wait time for the vehicle to reach the target ballast and trim
      depth.
      """

    SettleTime = 10 minute
      """
      How long to wait after reaching target depth before starting ballast and
      trim.
      """

    EstimationTimeout = 45 minute
      """
      Timeout for ballast and trim estimation.
      """

    MinEstimationTime = 10 minute
      """
      Minimum time to run ballast and trim estimation before determining
      convergence (must be shorter than timeout).
      """

    MassEstimationErrorBound = 0.25 millimeter
      """
      Desired range of the estimated sample mean from the true mean statistic
      (smaller values take longer to converge).
      """

    BuoyEstimationErrorBound = 15 cubic_centimeter
      """
      Desired range of the estimated sample mean from the true mean statistic
      (smaller values take longer to converge).
      """

    EstimationConfidence = 99.9 percent
      """
      Confidence level that the estimator has reached the desired error range
      (larger values take longer to converge).
      """

    WaitToFloat = 5 minute
      """
      Timeout for reaching surface threshold on float ascent, mission will end
      when reached.
      """
  }

  timeout duration=MissionTimeout

  insert Insert/Science.tl

  insert Insert/BackseatDriver.tl

  aggregate RunBallastAndTrim {
    run in sequence

    insert Insert/BallastAndTrim.tl {
      redefineArg InsertDepthDeadband = DepthDeadband
      redefineArg InsertApproachDepthTimeout = TargetDepthTimeout
      redefineArg InsertSettleTime = SettleTime
      redefineArg EstTimeout = EstimationTimeout
      redefineArg MinEstTime = MinEstimationTime
      redefineArg MassEstErrorBound = MassEstimationErrorBound
      redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound
      redefineArg EstConfidence = EstimationConfidence
    }

    aggregate Depth1 {
      run in sequence

      break if ( isNaN ( Depth1 ) )

      assign in sequence BallastAndTrim:InsertDepth = Depth1

      call refId="BallastAndTrim"
    }

    aggregate Depth2 {
      run in sequence

      break if ( isNaN ( Depth2 ) )

      assign in sequence BallastAndTrim:InsertDepth = Depth2

      call refId="BallastAndTrim"
    }
  }

  aggregate Float_Up {
    """
    Float to surface instead of driving.
    """

    run in sequence

    break if ( Universal:depth < SurfaceThreshold )

    behavior Guidance:Buoyancy {
      run in parallel

      set position = Control:VerticalControl.buoyancyDefault
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = WaitToFloat
    }
  }
}


2024-02-13T18:26:40.905Z,1707848800.905 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.tl
2024-02-13T18:26:41.187Z,1707848801.187 [Default] Stopped
2024-02-13T18:26:41.188Z,1707848801.188 [Default](DEBUG): Aggregate::uninitialize Default
2024-02-13T18:26:41.188Z,1707848801.188 [Default:B.GoToSurface] Stopped
2024-02-13T18:26:41.188Z,1707848801.188 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-02-13T18:26:41.188Z,1707848801.188 [Default:CheckIn] Stopped
2024-02-13T18:26:41.188Z,1707848801.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T18:26:41.188Z,1707848801.188 [Default:CheckIn:C.Wait] Stopped
2024-02-13T18:26:41.188Z,1707848801.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T18:26:41.188Z,1707848801.188 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2024-02-13T18:26:41.188Z,1707848801.188 [ballast_and_trim] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:A] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:B] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:C] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:D] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:E] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:F] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2024-02-13T18:26:41.189Z,1707848801.189 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2024-02-13T18:26:41.190Z,1707848801.190 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2024-02-13T18:26:41.193Z,1707848801.193 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2024-02-13T18:26:41.193Z,1707848801.193 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2024-02-13T18:26:41.193Z,1707848801.193 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2024-02-13T18:26:41.193Z,1707848801.193 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2024-02-13T18:26:41.193Z,1707848801.193 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2024-02-13T18:26:41.193Z,1707848801.193 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2024-02-13T18:26:41.194Z,1707848801.194 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2024-02-13T18:26:41.194Z,1707848801.194 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2024-02-13T18:26:41.204Z,1707848801.204 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2024-02-13T18:26:41.204Z,1707848801.204 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2024-02-13T18:26:41.204Z,1707848801.204 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2024-02-13T18:26:41.204Z,1707848801.204 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2024-02-13T18:26:41.206Z,1707848801.206 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2024-02-13T18:26:41.206Z,1707848801.206 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2024-02-13T18:26:41.206Z,1707848801.206 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2024-02-13T18:26:41.206Z,1707848801.206 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2024-02-13T18:26:41.211Z,1707848801.211 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2024-02-13T18:26:41.211Z,1707848801.211 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2024-02-13T18:26:41.211Z,1707848801.211 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2024-02-13T18:26:41.211Z,1707848801.211 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2024-02-13T18:26:41.213Z,1707848801.213 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2024-02-13T18:26:41.213Z,1707848801.213 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2024-02-13T18:26:41.213Z,1707848801.213 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2024-02-13T18:26:41.213Z,1707848801.213 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2024-02-13T18:26:41.219Z,1707848801.219 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2024-02-13T18:26:41.219Z,1707848801.219 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2024-02-13T18:26:41.220Z,1707848801.220 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2024-02-13T18:26:41.220Z,1707848801.220 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2024-02-13T18:26:41.220Z,1707848801.220 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1
2024-02-13T18:26:41.220Z,1707848801.220 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger
2024-02-13T18:26:41.220Z,1707848801.220 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2024-02-13T18:26:41.221Z,1707848801.221 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2024-02-13T18:26:41.221Z,1707848801.221 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2024-02-13T18:26:41.221Z,1707848801.221 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2024-02-13T18:26:41.221Z,1707848801.221 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2024-02-13T18:26:41.221Z,1707848801.221 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2024-02-13T18:26:41.221Z,1707848801.221 [ballast_and_trim:BackseatDriver] Running Loop=1
2024-02-13T18:26:41.221Z,1707848801.221 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver
2024-02-13T18:26:41.222Z,1707848801.222 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-02-13T18:26:41.222Z,1707848801.222 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2024-02-13T18:26:41.222Z,1707848801.222 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2024-02-13T18:26:41.222Z,1707848801.222 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2024-02-13T18:26:41.226Z,1707848801.226 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2024-02-13T18:26:41.226Z,1707848801.226 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2024-02-13T18:26:41.227Z,1707848801.227 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2024-02-13T18:26:41.227Z,1707848801.227 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2024-02-13T18:26:41.227Z,1707848801.227 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2024-02-13T18:26:41.227Z,1707848801.227 [ballast_and_trim:BackseatDriver] Running Loop=1
2024-02-13T18:26:41.228Z,1707848801.228 [ballast_and_trim:Science] Running Loop=1
2024-02-13T18:26:41.229Z,1707848801.229 [ballast_and_trim:Science:ChlThreshTrigger] Stopped
2024-02-13T18:26:41.229Z,1707848801.229 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger
2024-02-13T18:26:41.229Z,1707848801.229 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2024-02-13T18:26:41.229Z,1707848801.229 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2024-02-13T18:26:41.229Z,1707848801.229 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2024-02-13T18:26:41.229Z,1707848801.229 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2024-02-13T18:26:41.229Z,1707848801.229 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2024-02-13T18:26:41.229Z,1707848801.229 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2024-02-13T18:26:41.230Z,1707848801.230 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2024-02-13T18:26:41.230Z,1707848801.230 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2024-02-13T18:26:41.230Z,1707848801.230 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2024-02-13T18:26:41.230Z,1707848801.230 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2024-02-13T18:26:41.230Z,1707848801.230 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2024-02-13T18:26:41.230Z,1707848801.230 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2024-02-13T18:26:41.230Z,1707848801.230 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2024-02-13T18:26:41.230Z,1707848801.230 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2024-02-13T18:26:41.234Z,1707848801.234 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:PeakDetectOil] Stopped
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2024-02-13T18:26:41.235Z,1707848801.235 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:PeakDetectChl] Stopped
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:Read_Oil] Stopped
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:F] Stopped
2024-02-13T18:26:41.236Z,1707848801.236 [ballast_and_trim:Science:E] Running Loop=1
2024-02-13T18:26:41.237Z,1707848801.237 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2024-02-13T18:26:41.237Z,1707848801.237 [ballast_and_trim:Science:D] Stopped
2024-02-13T18:26:41.237Z,1707848801.237 [ballast_and_trim:Science:C] Running Loop=1
2024-02-13T18:26:41.238Z,1707848801.238 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2024-02-13T18:26:41.239Z,1707848801.239 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2024-02-13T18:26:41.239Z,1707848801.239 [ballast_and_trim:Science:B] Running Loop=1
2024-02-13T18:26:41.240Z,1707848801.240 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2024-02-13T18:26:41.240Z,1707848801.240 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2024-02-13T18:26:41.240Z,1707848801.240 [ballast_and_trim:Science:A] Stopped
2024-02-13T18:26:41.428Z,1707848801.428 [WetLabsBB2FL](INFO): Powering up
2024-02-13T18:26:41.600Z,1707848801.600 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2024-02-13T18:26:41.600Z,1707848801.600 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2024-02-13T18:26:41.601Z,1707848801.601 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2024-02-13T18:26:41.601Z,1707848801.601 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2024-02-13T18:26:41.601Z,1707848801.601 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2024-02-13T18:26:41.601Z,1707848801.601 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2024-02-13T18:26:41.601Z,1707848801.601 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2024-02-13T18:26:41.601Z,1707848801.601 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2024-02-13T18:26:41.603Z,1707848801.603 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2024-02-13T18:26:41.603Z,1707848801.603 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2024-02-13T18:26:41.603Z,1707848801.603 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2024-02-13T18:26:41.603Z,1707848801.603 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2024-02-13T18:26:41.603Z,1707848801.603 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2024-02-13T18:26:41.603Z,1707848801.603 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2024-02-13T18:26:41.603Z,1707848801.603 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2024-02-13T18:26:41.604Z,1707848801.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2024-02-13T18:26:41.604Z,1707848801.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2024-02-13T18:26:41.604Z,1707848801.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2024-02-13T18:26:41.604Z,1707848801.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2024-02-13T18:26:41.604Z,1707848801.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2024-02-13T18:26:41.604Z,1707848801.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2024-02-13T18:26:41.604Z,1707848801.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2024-02-13T18:26:41.604Z,1707848801.604 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2024-02-13T18:26:41.605Z,1707848801.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2024-02-13T18:26:41.605Z,1707848801.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2024-02-13T18:26:41.605Z,1707848801.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2024-02-13T18:26:41.605Z,1707848801.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2024-02-13T18:26:41.605Z,1707848801.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2024-02-13T18:26:41.605Z,1707848801.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2024-02-13T18:26:42.004Z,1707848802.004 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2024-02-13T18:26:42.005Z,1707848802.005 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2024-02-13T18:26:42.005Z,1707848802.005 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2024-02-13T18:26:42.388Z,1707848802.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2024-02-13T18:26:42.388Z,1707848802.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2024-02-13T18:26:42.388Z,1707848802.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T18:26:42.389Z,1707848802.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2024-02-13T18:26:42.389Z,1707848802.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2024-02-13T18:26:42.389Z,1707848802.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2024-02-13T18:26:42.389Z,1707848802.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2024-02-13T18:26:42.389Z,1707848802.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2024-02-13T18:26:42.389Z,1707848802.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2024-02-13T18:26:42.389Z,1707848802.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2024-02-13T18:26:42.389Z,1707848802.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2024-02-13T18:26:42.796Z,1707848802.796 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2024-02-13T18:26:42.797Z,1707848802.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2024-02-13T18:26:42.797Z,1707848802.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2024-02-13T18:26:42.797Z,1707848802.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2024-02-13T18:26:42.797Z,1707848802.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2024-02-13T18:26:45.235Z,1707848805.235 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2024-02-13T18:26:47.245Z,1707848807.245 [CTD_Seabird](ERROR): Salinity reading out of range: 15.424173 psu
2024-02-13T18:26:47.248Z,1707848807.248 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:26:51.259Z,1707848811.259 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-02-13T18:27:21.967Z,1707848841.967 [NAL9602](INFO): Not Powering down - fast GPS
2024-02-13T18:28:32.871Z,1707848912.871 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T18:29:04.036Z,1707848944.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T18:29:30.983Z,1707848970.983 [Radio_Surface](INFO): Powering down
2024-02-13T18:29:35.159Z,1707848975.159 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T18:29:36.163Z,1707848976.163 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-02-13T18:30:41.439Z,1707849041.439 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.779312
2024-02-13T18:30:46.415Z,1707849046.415 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2024-02-13T18:30:46.415Z,1707849046.415 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2024-02-13T18:30:46.415Z,1707849046.415 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2024-02-13T18:30:46.415Z,1707849046.415 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2024-02-13T18:30:46.416Z,1707849046.416 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2024-02-13T18:30:46.416Z,1707849046.416 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2024-02-13T18:30:46.416Z,1707849046.416 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2024-02-13T18:30:46.416Z,1707849046.416 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2024-02-13T18:30:46.416Z,1707849046.416 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2024-02-13T18:30:46.416Z,1707849046.416 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2024-02-13T18:30:46.416Z,1707849046.416 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2024-02-13T18:30:46.417Z,1707849046.417 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2024-02-13T18:30:46.417Z,1707849046.417 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2024-02-13T18:30:46.417Z,1707849046.417 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2024-02-13T18:30:46.417Z,1707849046.417 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2024-02-13T18:30:46.417Z,1707849046.417 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2024-02-13T18:30:46.417Z,1707849046.417 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2024-02-13T18:30:46.827Z,1707849046.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.052853 m 
2024-02-13T18:30:46.827Z,1707849046.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2024-02-13T18:30:46.827Z,1707849046.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2024-02-13T18:30:46.827Z,1707849046.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2024-02-13T18:30:46.827Z,1707849046.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2024-02-13T18:30:46.828Z,1707849046.828 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2024-02-13T18:40:47.615Z,1707849647.615 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2024-02-13T18:40:47.615Z,1707849647.615 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2024-02-13T18:40:47.615Z,1707849647.615 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T18:40:47.616Z,1707849647.616 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute] Running Loop=1
2024-02-13T18:40:48.024Z,1707849648.024 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](INFO): Executing command gfscan
2024-02-13T18:40:48.025Z,1707849648.025 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute] Stopped
2024-02-13T18:40:48.025Z,1707849648.025 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F] Running Loop=1
2024-02-13T18:40:48.051Z,1707849648.051 [CommandExec](IMPORTANT): got command gfscan
2024-02-13T18:40:48.111Z,1707849648.111 [CBIT](IMPORTANT): Beginning ground fault scan
2024-02-13T18:40:48.407Z,1707849648.407 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F](INFO): Running ballast and trim. Depth = 7.207528 m 
2024-02-13T18:40:48.407Z,1707849648.407 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F] Stopped
2024-02-13T18:40:48.407Z,1707849648.407 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Running Loop=1
2024-02-13T18:40:48.407Z,1707849648.407 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Initializing BallastAndTrim.
2024-02-13T18:40:48.776Z,1707849648.776 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:48.778Z,1707849648.778 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:49.780Z,1707849649.780 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:49.782Z,1707849649.782 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:50.784Z,1707849650.784 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:50.786Z,1707849650.786 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:50.927Z,1707849650.927 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2024-02-13T18:40:51.788Z,1707849651.788 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:51.789Z,1707849651.789 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:52.792Z,1707849652.792 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:52.793Z,1707849652.793 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:53.796Z,1707849653.796 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:53.797Z,1707849653.797 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:54.800Z,1707849654.800 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:54.802Z,1707849654.802 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:55.805Z,1707849655.805 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:55.806Z,1707849655.806 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:56.808Z,1707849656.808 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:56.809Z,1707849656.809 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:57.812Z,1707849657.812 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:57.813Z,1707849657.813 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:58.816Z,1707849658.816 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2024-02-13T18:40:58.817Z,1707849658.817 [CTD_Seabird](INFO): some bad data, not updating bins
2024-02-13T18:40:58.982Z,1707849658.982 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002524
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002067
CHAN A3 (5V): -0.001136
CHAN B0 (3.3V): 0.000035
CHAN B1 (3.15aV): 0.000017
CHAN B2 (3.15bV): -0.000678
CHAN B3 (GND): -0.000282
OPEN: -0.000423
Full Scale: +/- 1 mA
2024-02-13T18:59:24.717Z,1707850764.717 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): BallastAndTrim completed. Estimation time: 18.60 minutes.
2024-02-13T18:59:24.718Z,1707850764.718 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Estimated buoyancyNeutral: 215.727710 +/- 14.998507 cc (conf. level 99.90%, sigma: 24.239956 cc).
2024-02-13T18:59:24.720Z,1707850764.720 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Estimated massDefault: -11.248903 +/- 0.202809 mm (conf. level 99.90%, sigma: 0.327771 mm).
2024-02-13T18:59:24.720Z,1707850764.720 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Changing persisted config values to: 215.727710 cc buoyancy neutral and -11.248903 mm mass default
2024-02-13T18:59:24.725Z,1707850764.725 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-02-13T18:59:24.726Z,1707850764.726 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2024-02-13T18:59:24.727Z,1707850764.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2024-02-13T18:59:24.727Z,1707850764.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): BuoyancyServo.deviationVolume=6 cubic_centimeter;

2024-02-13T18:59:24.727Z,1707850764.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2024-02-13T18:59:24.727Z,1707850764.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2024-02-13T18:59:24.727Z,1707850764.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): CBIT.runNavErrorCritical=1 bool;

2024-02-13T18:59:24.727Z,1707850764.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): DAT.loadAtStartup=0 bool;

2024-02-13T18:59:24.727Z,1707850764.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2024-02-13T18:59:24.727Z,1707850764.727 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2024-02-13T18:59:24.728Z,1707850764.728 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): ThrusterServo.currLimit=50 percent;

2024-02-13T18:59:24.728Z,1707850764.728 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): VerticalControl.buoyancyNeutral=215.727710 cubic_centimeter;

2024-02-13T18:59:24.728Z,1707850764.728 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): VerticalControl.massDefault=-11.248903 millimeter;

2024-02-13T18:59:24.728Z,1707850764.728 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Stopped
2024-02-13T18:59:24.728Z,1707850764.728 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Uninitializing BallastAndTrim.
2024-02-13T18:59:24.729Z,1707850764.729 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2024-02-13T18:59:24.729Z,1707850764.729 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2024-02-13T18:59:24.729Z,1707850764.729 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2024-02-13T18:59:24.729Z,1707850764.729 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2024-02-13T18:59:24.729Z,1707850764.729 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2024-02-13T18:59:24.730Z,1707850764.730 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2024-02-13T18:59:24.731Z,1707850764.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2024-02-13T18:59:24.731Z,1707850764.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2024-02-13T18:59:24.731Z,1707850764.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2024-02-13T18:59:24.731Z,1707850764.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2024-02-13T18:59:24.731Z,1707850764.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2024-02-13T18:59:24.731Z,1707850764.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2024-02-13T18:59:24.731Z,1707850764.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2024-02-13T18:59:24.731Z,1707850764.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2024-02-13T18:59:25.128Z,1707850765.128 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B
2024-02-13T18:59:25.128Z,1707850765.128 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2024-02-13T18:59:25.128Z,1707850765.128 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2024-02-13T18:59:25.128Z,1707850765.128 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1
2024-02-13T18:59:25.128Z,1707850765.128 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2024-02-13T18:59:25.128Z,1707850765.128 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2024-02-13T18:59:25.128Z,1707850765.128 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1
2024-02-13T18:59:25.128Z,1707850765.128 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2
2024-02-13T18:59:25.129Z,1707850765.129 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1
2024-02-13T18:59:25.518Z,1707850765.518 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped
2024-02-13T18:59:25.518Z,1707850765.518 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2
2024-02-13T18:59:25.518Z,1707850765.518 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped
2024-02-13T18:59:25.518Z,1707850765.518 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2024-02-13T18:59:25.518Z,1707850765.518 [ballast_and_trim:RunBallastAndTrim] Stopped
2024-02-13T18:59:25.518Z,1707850765.518 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2024-02-13T18:59:25.519Z,1707850765.519 [ballast_and_trim:Float_Up] Running Loop=1
2024-02-13T18:59:25.519Z,1707850765.519 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up
2024-02-13T18:59:25.520Z,1707850765.520 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2024-02-13T18:59:25.520Z,1707850765.520 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2024-02-13T18:59:25.520Z,1707850765.520 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1
2024-02-13T18:59:25.520Z,1707850765.520 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2024-02-13T18:59:25.926Z,1707850765.926 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2024-02-13T19:00:51.557Z,1707850851.557 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T19:01:04.485Z,1707850864.485 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T19:01:17.425Z,1707850877.425 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T19:01:29.944Z,1707850889.944 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T19:01:42.873Z,1707850902.873 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T19:01:55.397Z,1707850915.397 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T19:02:08.729Z,1707850928.729 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T19:02:56.030Z,1707850976.030 [ballast_and_trim:Float_Up] Stopped
2024-02-13T19:02:56.030Z,1707850976.030 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up
2024-02-13T19:02:56.030Z,1707850976.030 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped
2024-02-13T19:02:56.030Z,1707850976.030 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2024-02-13T19:02:56.030Z,1707850976.030 [ballast_and_trim:Float_Up:B.Wait] Stopped
2024-02-13T19:02:56.030Z,1707850976.030 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T19:02:56.032Z,1707850976.032 [ballast_and_trim](INFO): Completed ballast_and_trim
2024-02-13T19:02:56.032Z,1707850976.032 [MissionManager](INFO): ballast_and_trim is completed.
2024-02-13T19:02:56.032Z,1707850976.032 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2024-02-13T19:02:56.032Z,1707850976.032 [ballast_and_trim] Stopped
2024-02-13T19:02:56.032Z,1707850976.032 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2024-02-13T19:02:56.032Z,1707850976.032 [ballast_and_trim:Science] Stopped
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:Science:B] Stopped
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:Science:C] Stopped
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:Science:E] Stopped
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:Science:OceanCurrent] Stopped
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:BackseatDriver] Stopped
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver
2024-02-13T19:02:56.033Z,1707850976.033 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped
2024-02-13T19:02:56.402Z,1707850976.402 [WetLabsBB2FL](INFO): Powering down
2024-02-13T19:02:56.443Z,1707850976.443 [MissionManager](IMPORTANT): Started mission Default
2024-02-13T19:02:56.444Z,1707850976.444 [Default] Running Loop=1
2024-02-13T19:02:56.444Z,1707850976.444 [Default](DEBUG): Aggregate::initialize Default
2024-02-13T19:02:56.444Z,1707850976.444 [Default:B.GoToSurface] Running Loop=1
2024-02-13T19:02:56.444Z,1707850976.444 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-02-13T19:02:56.444Z,1707850976.444 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-02-13T19:02:56.444Z,1707850976.444 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-02-13T19:02:56.445Z,1707850976.445 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-02-13T19:02:56.445Z,1707850976.445 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-02-13T19:02:56.445Z,1707850976.445 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-02-13T19:02:56.446Z,1707850976.446 [Default:A.Wait] Running Loop=1
2024-02-13T19:02:56.446Z,1707850976.446 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-02-13T19:02:56.820Z,1707850976.820 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -35.00 mm (1 active estimators).
2024-02-13T19:02:56.950Z,1707850976.950 [Radio_Surface](INFO): Powering up
2024-02-13T19:03:02.475Z,1707850982.475 [DataOverHttps](INFO): Radio surface powered ON.
2024-02-13T19:03:09.767Z,1707850989.767 [Default:A.Wait](INFO): Done Waiting.
2024-02-13T19:03:09.767Z,1707850989.767 [Default:A.Wait] Stopped
2024-02-13T19:03:09.767Z,1707850989.767 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T19:03:32.595Z,1707851012.595 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:03:56.616Z,1707851036.616 [Default:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -2.55
2024-02-13T19:04:03.743Z,1707851043.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:04:34.891Z,1707851074.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:04:49.127Z,1707851089.127 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-02-13T19:04:49.129Z,1707851089.129 [BPC1](INFO): Received data from all battery sticks.
2024-02-13T19:05:06.043Z,1707851106.043 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:05:37.167Z,1707851137.167 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:06:08.291Z,1707851168.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:06:39.415Z,1707851199.415 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:07:10.551Z,1707851230.551 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:07:41.695Z,1707851261.695 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:08:12.819Z,1707851292.819 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:08:43.943Z,1707851323.943 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:09:15.067Z,1707851355.067 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:09:46.199Z,1707851386.199 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:10:17.323Z,1707851417.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:10:48.475Z,1707851448.475 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:11:19.619Z,1707851479.619 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:11:50.743Z,1707851510.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:12:21.867Z,1707851541.867 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:12:53.063Z,1707851573.063 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:13:24.223Z,1707851604.223 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:13:55.359Z,1707851635.359 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:14:26.491Z,1707851666.491 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:14:57.615Z,1707851697.615 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:15:28.739Z,1707851728.739 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:15:52.506Z,1707851752.506 [VerticalControl](CRITICAL): Failure to ascend, stopping mission after 769.219055 seconds, depthRate=0.002102 m/s, pitch=0.157133 deg.
2024-02-13T19:15:52.524Z,1707851752.524 [CommandExec](FAULT): Scheduling is paused
2024-02-13T19:15:52.525Z,1707851752.525 [CBIT](INFO): Critical error at 20240213T191552
2024-02-13T19:15:52.525Z,1707851752.525 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-02-13T19:15:59.863Z,1707851759.863 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:16:18.366Z,1707851778.366 [Default:CheckIn] Running Loop=1
2024-02-13T19:16:18.366Z,1707851778.366 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T19:16:18.366Z,1707851778.366 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T19:16:30.987Z,1707851790.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-02-13T19:16:51.069Z,1707851811.069 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-02-13T19:16:51.142Z,1707851811.142 [NAL9602](ERROR): received: 
+CSQ:0

OK935, 0, 0, 0, 0

OK
2024-02-13T19:17:00.783Z,1707851820.783 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003100
2024-02-13T19:21:18.596Z,1707852078.596 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-13T19:16:18.4Z
2024-02-13T19:21:18.596Z,1707852078.596 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T19:21:18.596Z,1707852078.596 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T19:21:22.216Z,1707852082.216 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-02-13T19:21:25.870Z,1707852085.870 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20240213T175117/Courier0019.lzma
2024-02-13T19:21:26.872Z,1707852086.872 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0019.lzma.bak
2024-02-13T19:21:26.872Z,1707852086.872 [DataOverHttps](INFO): SBD MOMSN=19276983
2024-02-13T19:21:42.901Z,1707852102.901 [DataOverHttps](INFO): Sending 1643 bytes from file Logs/20240213T175117/Express0020.lzma
2024-02-13T19:21:43.903Z,1707852103.903 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0020.lzma.bak
2024-02-13T19:21:43.903Z,1707852103.903 [DataOverHttps](INFO): SBD MOMSN=19276986
2024-02-13T19:21:52.927Z,1707852112.927 [NAL9602](INFO): Not Powering down - fast GPS
2024-02-13T19:21:54.256Z,1707852114.256 [CommandExec](IMPORTANT): got command burn off
2024-02-13T19:21:54.256Z,1707852114.256 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-02-13T19:22:00.110Z,1707852120.110 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240213T175117/Express0023.lzma
2024-02-13T19:22:01.112Z,1707852121.112 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0023.lzma.bak
2024-02-13T19:22:01.112Z,1707852121.112 [DataOverHttps](INFO): SBD MOMSN=19277019
2024-02-13T19:22:02.633Z,1707852122.633 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T19:22:02.634Z,1707852122.634 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T19:22:02.634Z,1707852122.634 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T19:27:03.203Z,1707852423.203 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T19:27:03.203Z,1707852423.203 [Default:CheckIn:C.Wait] Stopped
2024-02-13T19:27:03.203Z,1707852423.203 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T19:27:03.204Z,1707852423.204 [Default:CheckIn:D] Running Loop=1
2024-02-13T19:27:03.618Z,1707852423.618 [Default:CheckIn:D] Stopped
2024-02-13T19:27:03.618Z,1707852423.618 [Default:CheckIn:E] Running Loop=1
2024-02-13T19:27:04.010Z,1707852424.010 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.119562 min 
2024-02-13T19:27:04.010Z,1707852424.010 [Default:CheckIn:E] Stopped
2024-02-13T19:27:04.010Z,1707852424.010 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T19:27:04.010Z,1707852424.010 [Default:CheckIn] Stopped
2024-02-13T19:27:04.011Z,1707852424.011 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T19:27:04.011Z,1707852424.011 [Default:CheckIn](INFO): Running loop #2
2024-02-13T19:27:04.011Z,1707852424.011 [Default:CheckIn] Running Loop=2
2024-02-13T19:27:04.011Z,1707852424.011 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T19:27:04.011Z,1707852424.011 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T19:27:05.620Z,1707852425.620 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-02-13T19:27:05.620Z,1707852425.620 [NAL9602] Data Fault, FailCount= 1
2024-02-13T19:27:05.620Z,1707852425.620 [NAL9602](ERROR): Data Fault
2024-02-13T19:27:05.640Z,1707852425.640 [CBIT](ERROR): Data Fault in component: NAL9602
2024-02-13T19:27:06.024Z,1707852426.024 [NAL9602](INFO): Powering down
2024-02-13T19:27:06.871Z,1707852426.871 [CBIT](INFO): Clearing failed state for component NAL9602
2024-02-13T19:27:06.871Z,1707852426.871 [NAL9602] No Fault, FailCount= 1
2024-02-13T19:27:36.324Z,1707852456.324 [NAL9602](INFO): Powering up NAL9602
2024-02-13T19:27:47.228Z,1707852467.228 [NAL9602](INFO): NAL9602 initialized
2024-02-13T19:28:16.268Z,1707852496.268 [CommandExec](IMPORTANT): got command strobe off
2024-02-13T19:28:16.268Z,1707852496.268 [CommandExec](IMPORTANT): Deactivating strobe
2024-02-13T19:28:18.576Z,1707852498.576 [CommandExec](IMPORTANT): got command burn off
2024-02-13T19:28:18.576Z,1707852498.576 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-02-13T19:28:21.083Z,1707852501.083 [CommandExec](IMPORTANT): got command failComponent
2024-02-13T19:28:21.083Z,1707852501.083 [CommandExec](IMPORTANT): Failed components:
2024-02-13T19:28:21.083Z,1707852501.083 [CommandExec](IMPORTANT): No failed Components.
2024-02-13T19:32:04.205Z,1707852724.205 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-02-13T19:27:04.0Z
2024-02-13T19:32:04.205Z,1707852724.205 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T19:32:04.205Z,1707852724.205 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T19:32:11.990Z,1707852731.990 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240213T175117/Courier0025.lzma
2024-02-13T19:32:12.992Z,1707852732.992 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0025.lzma.bak
2024-02-13T19:32:12.992Z,1707852732.992 [DataOverHttps](INFO): SBD MOMSN=19277021
2024-02-13T19:32:32.295Z,1707852752.295 [DataOverHttps](INFO): Sending 357 bytes from file Logs/20240213T175117/Express0026.lzma
2024-02-13T19:32:33.296Z,1707852753.296 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0026.lzma.bak
2024-02-13T19:32:33.296Z,1707852753.296 [DataOverHttps](INFO): SBD MOMSN=19277023
2024-02-13T19:32:34.558Z,1707852754.558 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T19:32:34.558Z,1707852754.558 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T19:32:34.558Z,1707852754.558 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T19:32:50.700Z,1707852770.700 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-02-13T19:33:21.403Z,1707852801.403 [NAL9602](INFO): Not Powering down - fast GPS
2024-02-13T19:37:35.133Z,1707853055.133 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T19:37:35.133Z,1707853055.133 [Default:CheckIn:C.Wait] Stopped
2024-02-13T19:37:35.133Z,1707853055.133 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T19:37:35.133Z,1707853055.133 [Default:CheckIn:D] Running Loop=1
2024-02-13T19:37:35.548Z,1707853055.548 [Default:CheckIn:D] Stopped
2024-02-13T19:37:35.548Z,1707853055.548 [Default:CheckIn:E] Running Loop=1
2024-02-13T19:37:35.960Z,1707853055.960 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.651729 min 
2024-02-13T19:37:35.960Z,1707853055.960 [Default:CheckIn:E] Stopped
2024-02-13T19:37:35.960Z,1707853055.960 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T19:37:35.960Z,1707853055.960 [Default:CheckIn] Stopped
2024-02-13T19:37:35.960Z,1707853055.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T19:37:35.961Z,1707853055.961 [Default:CheckIn](INFO): Running loop #3
2024-02-13T19:37:35.961Z,1707853055.961 [Default:CheckIn] Running Loop=3
2024-02-13T19:37:35.961Z,1707853055.961 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T19:37:35.961Z,1707853055.961 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T19:37:37.957Z,1707853057.957 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193736.00,A,3648.16388,N,12147.28547,W,0.505,46.81,130224,,,A*48
2024-02-13T19:37:37.959Z,1707853057.959 [NAL9602](INFO): GPS fix at 20240213T193736: (36.802731, -121.788091)
2024-02-13T19:37:37.970Z,1707853057.970 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T19:37:37.970Z,1707853057.970 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T19:37:45.834Z,1707853065.834 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240213T175117/Courier0028.lzma
2024-02-13T19:37:46.836Z,1707853066.836 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0028.lzma.bak
2024-02-13T19:37:46.836Z,1707853066.836 [DataOverHttps](INFO): SBD MOMSN=19277037
2024-02-13T19:38:02.698Z,1707853082.698 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20240213T175117/Express0029.lzma
2024-02-13T19:38:03.700Z,1707853083.700 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0029.lzma.bak
2024-02-13T19:38:03.701Z,1707853083.701 [DataOverHttps](INFO): SBD MOMSN=19277040
2024-02-13T19:38:05.046Z,1707853085.046 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T19:38:05.046Z,1707853085.046 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T19:38:05.047Z,1707853085.047 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T19:38:08.656Z,1707853088.656 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-02-13T19:38:08.738Z,1707853088.738 [NAL9602](ERROR): received: 
+CSQ:0

OK935, 0, 0, 0, 0

OK
2024-02-13T19:39:37.538Z,1707853177.538 [NAL9602](INFO): SBD MO Status=2, MOMSN=46936, MT Status=2, MTMSN=0
2024-02-13T19:39:37.538Z,1707853177.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-02-13T19:41:35.560Z,1707853295.560 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2024-02-13T19:42:40.188Z,1707853360.188 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-02-13T19:43:05.667Z,1707853385.667 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T19:43:05.667Z,1707853385.667 [Default:CheckIn:C.Wait] Stopped
2024-02-13T19:43:05.667Z,1707853385.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T19:43:05.668Z,1707853385.668 [Default:CheckIn:D] Running Loop=1
2024-02-13T19:43:06.054Z,1707853386.054 [Default:CheckIn:D] Stopped
2024-02-13T19:43:06.054Z,1707853386.054 [Default:CheckIn:E] Running Loop=1
2024-02-13T19:43:06.458Z,1707853386.458 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.160168 min 
2024-02-13T19:43:06.458Z,1707853386.458 [Default:CheckIn:E] Stopped
2024-02-13T19:43:06.458Z,1707853386.458 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T19:43:06.458Z,1707853386.458 [Default:CheckIn] Stopped
2024-02-13T19:43:06.458Z,1707853386.458 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T19:43:06.458Z,1707853386.458 [Default:CheckIn](INFO): Running loop #4
2024-02-13T19:43:06.458Z,1707853386.458 [Default:CheckIn] Running Loop=4
2024-02-13T19:43:06.458Z,1707853386.458 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T19:43:06.459Z,1707853386.459 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T19:43:08.474Z,1707853388.474 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194307.00,A,3648.15827,N,12147.28904,W,0.156,46.81,130224,,,A*4D
2024-02-13T19:43:08.476Z,1707853388.476 [NAL9602](INFO): GPS fix at 20240213T194307: (36.802638, -121.788151)
2024-02-13T19:43:08.487Z,1707853388.487 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T19:43:08.487Z,1707853388.487 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T19:43:15.726Z,1707853395.726 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240213T175117/Courier0031.lzma
2024-02-13T19:43:16.728Z,1707853396.728 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0031.lzma.bak
2024-02-13T19:43:16.728Z,1707853396.728 [DataOverHttps](INFO): SBD MOMSN=19277044
2024-02-13T19:43:32.699Z,1707853412.699 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20240213T175117/Express0032.lzma
2024-02-13T19:43:33.700Z,1707853413.700 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0032.lzma.bak
2024-02-13T19:43:33.700Z,1707853413.700 [DataOverHttps](INFO): SBD MOMSN=19277047
2024-02-13T19:43:35.143Z,1707853415.143 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T19:43:35.143Z,1707853415.143 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T19:43:35.143Z,1707853415.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T19:43:40.379Z,1707853420.379 [NAL9602](INFO): Not Powering down - fast GPS
2024-02-13T19:48:35.736Z,1707853715.736 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T19:48:35.736Z,1707853715.736 [Default:CheckIn:C.Wait] Stopped
2024-02-13T19:48:35.736Z,1707853715.736 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T19:48:35.736Z,1707853715.736 [Default:CheckIn:D] Running Loop=1
2024-02-13T19:48:36.130Z,1707853716.130 [Default:CheckIn:D] Stopped
2024-02-13T19:48:36.130Z,1707853716.130 [Default:CheckIn:E] Running Loop=1
2024-02-13T19:48:36.538Z,1707853716.538 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.661438 min 
2024-02-13T19:48:36.538Z,1707853716.538 [Default:CheckIn:E] Stopped
2024-02-13T19:48:36.538Z,1707853716.538 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T19:48:36.539Z,1707853716.539 [Default:CheckIn] Stopped
2024-02-13T19:48:36.539Z,1707853716.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T19:48:36.539Z,1707853716.539 [Default:CheckIn](INFO): Running loop #5
2024-02-13T19:48:36.539Z,1707853716.539 [Default:CheckIn] Running Loop=5
2024-02-13T19:48:36.539Z,1707853716.539 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T19:48:36.539Z,1707853716.539 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T19:48:38.549Z,1707853718.549 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194837.00,A,3648.16507,N,12147.30206,W,0.117,106.72,130224,,,A*75
2024-02-13T19:48:38.552Z,1707853718.552 [NAL9602](INFO): GPS fix at 20240213T194837: (36.802751, -121.788368)
2024-02-13T19:48:38.563Z,1707853718.563 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T19:48:38.564Z,1707853718.564 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T19:48:46.246Z,1707853726.246 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240213T175117/Courier0034.lzma
2024-02-13T19:48:47.249Z,1707853727.249 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0034.lzma.bak
2024-02-13T19:48:47.249Z,1707853727.249 [DataOverHttps](INFO): SBD MOMSN=19277051
2024-02-13T19:49:06.050Z,1707853746.050 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240213T175117/Express0035.lzma
2024-02-13T19:49:07.052Z,1707853747.052 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0035.lzma.bak
2024-02-13T19:49:07.052Z,1707853747.053 [DataOverHttps](INFO): SBD MOMSN=19277054
2024-02-13T19:49:08.515Z,1707853748.515 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T19:49:08.515Z,1707853748.515 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T19:49:08.515Z,1707853748.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T19:49:09.304Z,1707853749.304 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-02-13T19:49:09.378Z,1707853749.378 [NAL9602](ERROR): received: 
+CSQ:0

OK936, 2, 0, 0, 0

OK
2024-02-13T19:51:35.557Z,1707853895.557 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2024-02-13T19:51:35.557Z,1707853895.557 [DropWeight] Hardware Fault, FailCount= 1
2024-02-13T19:51:35.557Z,1707853895.557 [DropWeight](ERROR): Hardware Fault
2024-02-13T19:51:35.577Z,1707853895.577 [CBIT](INFO): Critical error at 20240213T195135
2024-02-13T19:51:35.579Z,1707853895.579 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-02-13T19:51:35.580Z,1707853895.580 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-02-13T19:51:35.987Z,1707853895.987 [CBIT](INFO): Critical error at 20240213T195135
2024-02-13T19:53:40.384Z,1707854020.384 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-02-13T19:54:09.082Z,1707854049.082 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T19:54:09.082Z,1707854049.082 [Default:CheckIn:C.Wait] Stopped
2024-02-13T19:54:09.082Z,1707854049.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T19:54:09.083Z,1707854049.083 [Default:CheckIn:D] Running Loop=1
2024-02-13T19:54:09.497Z,1707854049.497 [Default:CheckIn:D] Stopped
2024-02-13T19:54:09.497Z,1707854049.497 [Default:CheckIn:E] Running Loop=1
2024-02-13T19:54:09.890Z,1707854049.890 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.217558 min 
2024-02-13T19:54:09.890Z,1707854049.890 [Default:CheckIn:E] Stopped
2024-02-13T19:54:09.890Z,1707854049.890 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T19:54:09.890Z,1707854049.890 [Default:CheckIn] Stopped
2024-02-13T19:54:09.890Z,1707854049.890 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T19:54:09.891Z,1707854049.891 [Default:CheckIn](INFO): Running loop #6
2024-02-13T19:54:09.891Z,1707854049.891 [Default:CheckIn] Running Loop=6
2024-02-13T19:54:09.891Z,1707854049.891 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T19:54:09.891Z,1707854049.891 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T19:54:11.896Z,1707854051.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195411.00,A,3648.17629,N,12147.27802,W,0.758,19.65,130224,,,A*4E
2024-02-13T19:54:11.899Z,1707854051.899 [NAL9602](INFO): GPS fix at 20240213T195411: (36.802938, -121.787967)
2024-02-13T19:54:11.910Z,1707854051.910 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T19:54:11.910Z,1707854051.910 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T19:54:20.151Z,1707854060.151 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20240213T175117/Courier0037.lzma
2024-02-13T19:54:21.152Z,1707854061.152 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0037.lzma.bak
2024-02-13T19:54:21.153Z,1707854061.153 [DataOverHttps](INFO): SBD MOMSN=19277057
2024-02-13T19:54:39.710Z,1707854079.710 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20240213T175117/Express0038.lzma
2024-02-13T19:54:40.712Z,1707854080.712 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0038.lzma.bak
2024-02-13T19:54:40.712Z,1707854080.712 [DataOverHttps](INFO): SBD MOMSN=19277062
2024-02-13T19:54:42.257Z,1707854082.257 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T19:54:42.258Z,1707854082.258 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T19:54:42.258Z,1707854082.258 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T19:54:44.659Z,1707854084.659 [NAL9602](INFO): Not Powering down - fast GPS
2024-02-13T19:59:42.935Z,1707854382.935 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T19:59:42.935Z,1707854382.935 [Default:CheckIn:C.Wait] Stopped
2024-02-13T19:59:42.935Z,1707854382.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T19:59:42.935Z,1707854382.935 [Default:CheckIn:D] Running Loop=1
2024-02-13T19:59:43.326Z,1707854383.326 [Default:CheckIn:D] Stopped
2024-02-13T19:59:43.326Z,1707854383.326 [Default:CheckIn:E] Running Loop=1
2024-02-13T19:59:43.734Z,1707854383.734 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.781368 min 
2024-02-13T19:59:43.734Z,1707854383.734 [Default:CheckIn:E] Stopped
2024-02-13T19:59:43.734Z,1707854383.734 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T19:59:43.734Z,1707854383.734 [Default:CheckIn] Stopped
2024-02-13T19:59:43.734Z,1707854383.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T19:59:43.735Z,1707854383.735 [Default:CheckIn](INFO): Running loop #7
2024-02-13T19:59:43.735Z,1707854383.735 [Default:CheckIn] Running Loop=7
2024-02-13T19:59:43.735Z,1707854383.735 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T19:59:43.735Z,1707854383.735 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T19:59:45.755Z,1707854385.755 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195945.00,A,3648.17212,N,12147.27401,W,0.253,248.97,130224,,,A*74
2024-02-13T19:59:45.758Z,1707854385.758 [NAL9602](INFO): GPS fix at 20240213T195945: (36.802869, -121.787900)
2024-02-13T19:59:45.769Z,1707854385.769 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T19:59:45.769Z,1707854385.769 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T19:59:52.650Z,1707854392.650 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240213T175117/Courier0040.lzma
2024-02-13T19:59:53.652Z,1707854393.652 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0040.lzma.bak
2024-02-13T19:59:53.653Z,1707854393.653 [DataOverHttps](INFO): SBD MOMSN=19277066
2024-02-13T20:00:10.219Z,1707854410.219 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240213T175117/Express0041.lzma
2024-02-13T20:00:11.220Z,1707854411.220 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0041.lzma.bak
2024-02-13T20:00:11.220Z,1707854411.220 [DataOverHttps](INFO): SBD MOMSN=19277069
2024-02-13T20:00:12.416Z,1707854412.416 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T20:00:12.416Z,1707854412.416 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T20:00:12.416Z,1707854412.416 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T20:00:16.455Z,1707854416.455 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-02-13T20:00:16.531Z,1707854416.531 [NAL9602](ERROR): received: 
+CSQ:0

OK936, 2, 0, 0, 0

OK
2024-02-13T20:04:48.477Z,1707854688.477 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-02-13T20:05:13.198Z,1707854713.198 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-02-13T20:05:13.198Z,1707854713.198 [Default:CheckIn:C.Wait] Stopped
2024-02-13T20:05:13.198Z,1707854713.198 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T20:05:13.198Z,1707854713.198 [Default:CheckIn:D] Running Loop=1
2024-02-13T20:05:13.602Z,1707854713.602 [Default:CheckIn:D] Stopped
2024-02-13T20:05:13.602Z,1707854713.602 [Default:CheckIn:E] Running Loop=1
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.285966 min 
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn:E] Stopped
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn] Stopped
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn](INFO): Running loop #8
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn] Running Loop=8
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-02-13T20:05:14.012Z,1707854714.012 [Default:CheckIn:Read_GPS] Running Loop=1
2024-02-13T20:05:16.017Z,1707854716.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200515.00,A,3648.17099,N,12147.27002,W,1.108,206.89,130224,,,A*7D
2024-02-13T20:05:16.019Z,1707854716.019 [NAL9602](INFO): GPS fix at 20240213T200515: (36.802850, -121.787834)
2024-02-13T20:05:16.058Z,1707854716.058 [Default:CheckIn:Read_GPS] Stopped
2024-02-13T20:05:16.058Z,1707854716.058 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-02-13T20:05:23.450Z,1707854723.450 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240213T175117/Courier0043.lzma
2024-02-13T20:05:47.543Z,1707854747.543 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-02-13T20:05:48.342Z,1707854748.342 [NAL9602](INFO): Not Powering down - fast GPS
2024-02-13T20:05:54.954Z,1707854754.954 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240213T175117/Courier0043.lzma
2024-02-13T20:05:55.960Z,1707854755.960 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Courier0043.lzma.bak
2024-02-13T20:05:55.961Z,1707854755.961 [DataOverHttps](INFO): SBD MOMSN=19277072
2024-02-13T20:05:56.011Z,1707854756.011 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T20:06:08.933Z,1707854768.933 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T20:06:12.078Z,1707854772.078 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20240213T175117/Express0044.lzma
2024-02-13T20:06:13.080Z,1707854773.080 [DataOverHttps](INFO): Moved sent file to Logs/20240213T175117/Express0044.lzma.bak
2024-02-13T20:06:13.081Z,1707854773.081 [DataOverHttps](INFO): SBD MOMSN=19277075
2024-02-13T20:06:14.611Z,1707854774.611 [Default:CheckIn:Read_Iridium] Stopped
2024-02-13T20:06:14.611Z,1707854774.611 [Default:CheckIn:C.Wait] Running Loop=1
2024-02-13T20:06:14.611Z,1707854774.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-02-13T20:06:21.457Z,1707854781.457 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T20:06:34.385Z,1707854794.385 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T20:06:35.648Z,1707854795.648 [CBIT](INFO): Clearing failed state for component DropWeight
2024-02-13T20:06:35.649Z,1707854795.649 [DropWeight] No Fault, FailCount= 1
2024-02-13T20:06:46.910Z,1707854806.910 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T20:07:00.243Z,1707854820.243 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T20:07:13.172Z,1707854833.172 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-02-13T20:08:58.263Z,1707854938.263 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:09:09.591Z,1707854949.591 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:09:20.150Z,1707854960.150 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:09:31.025Z,1707854971.025 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:09:41.507Z,1707854981.507 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:09:45.531Z,1707854985.531 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-13T20:09:52.040Z,1707854992.040 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:09:56.860Z,1707854996.860 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-13T20:10:02.918Z,1707855002.918 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:10:07.799Z,1707855007.799 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-13T20:10:14.337Z,1707855014.337 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:10:18.007Z,1707855018.007 [CommandExec](IMPORTANT): got command quit
2024-02-13T20:10:18.803Z,1707855018.803 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-02-13T20:10:19.010Z,1707855019.010 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:19.011Z,1707855019.011 [CommandExec](INFO): Uninitializing the command executive.
2024-02-13T20:10:19.011Z,1707855019.011 [CommandExec](INFO): Uninitializing the command scheduler.
2024-02-13T20:10:19.011Z,1707855019.011 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:19.099Z,1707855019.099 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2024-02-13T20:10:19.099Z,1707855019.099 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-02-13T20:10:19.100Z,1707855019.100 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:19.100Z,1707855019.100 [NavChartDb](INFO): Join timeout helper Thread ID is 4846
2024-02-13T20:10:19.227Z,1707855019.227 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:19.227Z,1707855019.227 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:19.245Z,1707855019.245 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-02-13T20:10:19.246Z,1707855019.246 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:19.246Z,1707855019.246 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4847
2024-02-13T20:10:19.483Z,1707855019.483 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:19.483Z,1707855019.483 [WetLabsBB2FL](INFO): Powering down
2024-02-13T20:10:19.484Z,1707855019.484 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:19.498Z,1707855019.498 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-02-13T20:10:19.499Z,1707855019.499 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:19.499Z,1707855019.499 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4848
2024-02-13T20:10:19.827Z,1707855019.827 [CTD_Seabird](INFO): Powering down
2024-02-13T20:10:19.839Z,1707855019.839 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:19.840Z,1707855019.840 [CTD_Seabird](INFO): Powering down
2024-02-13T20:10:19.855Z,1707855019.855 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:19.862Z,1707855019.862 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-02-13T20:10:19.863Z,1707855019.863 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:19.863Z,1707855019.863 [Radio_Surface](INFO): Join timeout helper Thread ID is 4849
2024-02-13T20:10:19.987Z,1707855019.987 [Radio_Surface](INFO): Powering down
2024-02-13T20:10:19.988Z,1707855019.988 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:19.988Z,1707855019.988 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:20.002Z,1707855020.002 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-02-13T20:10:20.003Z,1707855020.003 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:20.003Z,1707855020.003 [Onboard](INFO): Join timeout helper Thread ID is 4850
2024-02-13T20:10:20.943Z,1707855020.943 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:20.943Z,1707855020.943 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:20.951Z,1707855020.951 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-02-13T20:10:20.951Z,1707855020.951 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:20.952Z,1707855020.952 [DataOverHttps](INFO): Join timeout helper Thread ID is 4851
2024-02-13T20:10:25.259Z,1707855025.259 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-02-13T20:10:28.051Z,1707855028.051 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:28.054Z,1707855028.054 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.071Z,1707855028.071 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-02-13T20:10:28.071Z,1707855028.071 [logger ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.072Z,1707855028.072 [logger](INFO): Join timeout helper Thread ID is 4852
2024-02-13T20:10:28.083Z,1707855028.083 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:28.083Z,1707855028.083 [logger ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.084Z,1707855028.084 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-02-13T20:10:28.084Z,1707855028.084 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.085Z,1707855028.085 [CommandLine](INFO): Join timeout helper Thread ID is 4853
2024-02-13T20:10:28.163Z,1707855028.163 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:28.163Z,1707855028.163 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.175Z,1707855028.175 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-02-13T20:10:28.175Z,1707855028.175 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.176Z,1707855028.176 [CommandExec](INFO): Join timeout helper Thread ID is 4854
2024-02-13T20:10:28.183Z,1707855028.183 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-02-13T20:10:28.184Z,1707855028.184 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.184Z,1707855028.184 [controlThread](INFO): Join timeout helper Thread ID is 4855
2024-02-13T20:10:28.291Z,1707855028.291 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-02-13T20:10:28.291Z,1707855028.291 [controlThread](DEBUG): Uninitializing ControlThread
2024-02-13T20:10:28.291Z,1707855028.291 [AHRS_M2](INFO): Powering down
2024-02-13T20:10:28.364Z,1707855028.364 [NAL9602](INFO): Powering down
2024-02-13T20:10:28.365Z,1707855028.365 [Sonardyne_Nano](INFO): Powering down
2024-02-13T20:10:28.484Z,1707855028.484 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-02-13T20:10:28.485Z,1707855028.485 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-02-13T20:10:28.485Z,1707855028.485 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-02-13T20:10:28.486Z,1707855028.486 [MissionManager](INFO): Uninitializing Mission Default
2024-02-13T20:10:28.486Z,1707855028.486 [Default] Stopped
2024-02-13T20:10:28.487Z,1707855028.487 [Default](DEBUG): Aggregate::uninitialize Default
2024-02-13T20:10:28.487Z,1707855028.487 [Default:B.GoToSurface] Stopped
2024-02-13T20:10:28.487Z,1707855028.487 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-02-13T20:10:28.487Z,1707855028.487 [Default:CheckIn] Stopped
2024-02-13T20:10:28.487Z,1707855028.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-02-13T20:10:28.487Z,1707855028.487 [Default:CheckIn:C.Wait] Stopped
2024-02-13T20:10:28.487Z,1707855028.487 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-02-13T20:10:28.490Z,1707855028.490 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-02-13T20:10:28.490Z,1707855028.490 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-02-13T20:10:28.491Z,1707855028.491 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-02-13T20:10:28.491Z,1707855028.491 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-02-13T20:10:28.491Z,1707855028.491 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-02-13T20:10:28.491Z,1707855028.491 [BuoyancyServo](INFO): Powering down
2024-02-13T20:10:28.503Z,1707855028.503 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-02-13T20:10:28.503Z,1707855028.503 [ElevatorServo](INFO): Powering down
2024-02-13T20:10:28.504Z,1707855028.504 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-02-13T20:10:28.504Z,1707855028.504 [MassServo](INFO): Powering down
2024-02-13T20:10:28.505Z,1707855028.505 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-02-13T20:10:28.505Z,1707855028.505 [RudderServo](INFO): Powering down
2024-02-13T20:10:28.506Z,1707855028.506 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-02-13T20:10:28.506Z,1707855028.506 [ThrusterHE](INFO): Powering down
2024-02-13T20:10:28.507Z,1707855028.507 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-02-13T20:10:28.507Z,1707855028.507 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-02-13T20:10:28.507Z,1707855028.507 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-02-13T20:10:28.507Z,1707855028.507 [CBIT](DEBUG): Powering off loads.
2024-02-13T20:10:28.519Z,1707855028.519 [CBIT](DEBUG): Disabling WDT.
2024-02-13T20:10:28.530Z,1707855028.530 [CBIT](DEBUG): Opening all GF detection circuits.
2024-02-13T20:10:28.531Z,1707855028.531 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.597Z,1707855028.597 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.600Z,1707855028.600 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.616Z,1707855028.616 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.737Z,1707855028.737 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.741Z,1707855028.741 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.783Z,1707855028.783 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-02-13T20:10:28.861Z,1707855028.861 [logger ThreadHandler](INFO): Thread cancelled.