2023-03-31T16:33:55.052Z,1680280435.052 [Supervisor](DEBUG): Initializing supervisor.
2023-03-31T16:33:55.057Z,1680280435.057 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-03-31T16:33:55.057Z,1680280435.057 [SyncHandler](INFO): Protected caller Thread ID is 1914
2023-03-31T16:33:55.058Z,1680280435.058 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-03-31T16:33:55.059Z,1680280435.059 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-03-31T16:33:55.059Z,1680280435.059 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1915
2023-03-31T16:33:55.063Z,1680280435.063 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-03-31T16:33:55.080Z,1680280435.080 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-03-31T16:33:55.081Z,1680280435.081 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-03-31T16:33:55.082Z,1680280435.082 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1916
2023-03-31T16:33:55.086Z,1680280435.086 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-03-31T16:33:55.087Z,1680280435.087 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-03-31T16:33:55.087Z,1680280435.087 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1917
2023-03-31T16:33:55.089Z,1680280435.089 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-03-31T16:33:55.090Z,1680280435.090 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-03-31T16:33:55.091Z,1680280435.091 [logger ThreadHandler](INFO): Protected caller Thread ID is 1918
2023-03-31T16:33:55.094Z,1680280435.094 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-03-31T16:33:55.095Z,1680280435.095 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-03-31T16:33:55.096Z,1680280435.096 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-03-31T16:33:55.307Z,1680280435.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-03-31T16:33:55.308Z,1680280435.308 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-03-31T16:33:55.414Z,1680280435.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-03-31T16:33:55.414Z,1680280435.414 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-03-31T16:33:55.508Z,1680280435.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-03-31T16:33:55.509Z,1680280435.509 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-03-31T16:33:55.960Z,1680280435.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-03-31T16:33:55.960Z,1680280435.960 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-03-31T16:33:56.302Z,1680280436.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-03-31T16:33:56.302Z,1680280436.302 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-03-31T16:33:56.632Z,1680280436.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-03-31T16:33:56.632Z,1680280436.632 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-03-31T16:33:56.814Z,1680280436.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-03-31T16:33:56.814Z,1680280436.814 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-03-31T16:33:56.892Z,1680280436.892 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-03-31T16:33:56.971Z,1680280436.971 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-03-31T16:33:57.359Z,1680280437.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-03-31T16:33:57.360Z,1680280437.360 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-03-31T16:33:57.642Z,1680280437.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-03-31T16:33:57.642Z,1680280437.642 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-03-31T16:33:58.281Z,1680280438.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-03-31T16:33:58.281Z,1680280438.281 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-03-31T16:33:58.583Z,1680280438.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-03-31T16:33:58.584Z,1680280438.584 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-03-31T16:33:58.823Z,1680280438.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-03-31T16:33:58.824Z,1680280438.824 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-03-31T16:33:58.960Z,1680280438.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-03-31T16:33:58.960Z,1680280438.960 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-03-31T16:33:59.721Z,1680280439.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-03-31T16:33:59.723Z,1680280439.723 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2023-03-31T16:33:59.724Z,1680280439.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2023-03-31T16:33:59.806Z,1680280439.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2023-03-31T16:33:59.982Z,1680280439.982 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2023-03-31T16:34:00.247Z,1680280440.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-03-31T16:34:00.247Z,1680280440.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2023-03-31T16:34:00.367Z,1680280440.367 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2023-03-31T16:34:00.465Z,1680280440.465 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2023-03-31T16:34:00.556Z,1680280440.556 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2023-03-31T16:34:00.661Z,1680280440.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2023-03-31T16:34:00.753Z,1680280440.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2023-03-31T16:34:00.837Z,1680280440.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2023-03-31T16:34:00.934Z,1680280440.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2023-03-31T16:34:01.091Z,1680280441.091 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2023-03-31T16:34:01.091Z,1680280441.091 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-03-31T16:34:01.105Z,1680280441.105 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-03-31T16:34:01.157Z,1680280441.157 [DepthRateCalculator] Loaded
2023-03-31T16:34:01.157Z,1680280441.157 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-03-31T16:34:01.162Z,1680280441.162 [PitchRateCalculator] Loaded
2023-03-31T16:34:01.163Z,1680280441.163 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-03-31T16:34:01.173Z,1680280441.173 [SpeedCalculator] Loaded
2023-03-31T16:34:01.173Z,1680280441.173 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-03-31T16:34:01.178Z,1680280441.178 [YawRateCalculator] Loaded
2023-03-31T16:34:01.178Z,1680280441.178 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-03-31T16:34:01.199Z,1680280441.199 [ElevatorOffsetCalculator] Loaded
2023-03-31T16:34:01.199Z,1680280441.199 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-03-31T16:34:01.200Z,1680280441.200 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-03-31T16:34:01.200Z,1680280441.200 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-03-31T16:34:01.227Z,1680280441.227 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-03-31T16:34:01.228Z,1680280441.228 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-03-31T16:34:01.238Z,1680280441.238 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-03-31T16:34:01.238Z,1680280441.238 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-03-31T16:34:01.692Z,1680280441.692 [AHRS_M2] Loaded
2023-03-31T16:34:01.693Z,1680280441.693 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-03-31T16:34:01.728Z,1680280441.728 [BackseatComponent] Loaded
2023-03-31T16:34:01.728Z,1680280441.728 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-03-31T16:34:01.729Z,1680280441.729 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408474E0
2023-03-31T16:34:01.729Z,1680280441.729 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2000
2023-03-31T16:34:01.732Z,1680280441.732 [LcmUniversalReporter] Loaded
2023-03-31T16:34:01.733Z,1680280441.733 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-03-31T16:34:02.537Z,1680280442.537 [BPC1] Loaded
2023-03-31T16:34:02.538Z,1680280442.538 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-03-31T16:34:02.610Z,1680280442.610 [DataOverHttps] Loaded
2023-03-31T16:34:02.611Z,1680280442.611 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-03-31T16:34:02.612Z,1680280442.612 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408774E0
2023-03-31T16:34:02.612Z,1680280442.612 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2001
2023-03-31T16:34:02.632Z,1680280442.632 [Depth_Keller] Loaded
2023-03-31T16:34:02.633Z,1680280442.633 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-03-31T16:34:02.652Z,1680280442.652 [MultiRay] Loaded
2023-03-31T16:34:02.652Z,1680280442.652 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-03-31T16:34:02.714Z,1680280442.714 [NAL9602] Loaded
2023-03-31T16:34:02.714Z,1680280442.714 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-03-31T16:34:02.744Z,1680280442.744 [Onboard] Loaded
2023-03-31T16:34:02.744Z,1680280442.744 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-03-31T16:34:02.745Z,1680280442.745 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408A74E0
2023-03-31T16:34:02.745Z,1680280442.745 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2002
2023-03-31T16:34:02.751Z,1680280442.751 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2023-03-31T16:34:02.762Z,1680280442.762 [PowerOnly] Loaded
2023-03-31T16:34:02.762Z,1680280442.762 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-03-31T16:34:02.774Z,1680280442.774 [Power24vConverter] Loaded
2023-03-31T16:34:02.775Z,1680280442.775 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-03-31T16:34:02.789Z,1680280442.789 [Radio_Surface] Loaded
2023-03-31T16:34:02.789Z,1680280442.789 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-03-31T16:34:02.790Z,1680280442.790 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408D74E0
2023-03-31T16:34:02.791Z,1680280442.791 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2003
2023-03-31T16:34:02.814Z,1680280442.814 [Waterlinked] Loaded
2023-03-31T16:34:02.814Z,1680280442.814 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2023-03-31T16:34:02.815Z,1680280442.815 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-03-31T16:34:02.815Z,1680280442.815 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-03-31T16:34:02.927Z,1680280442.927 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-03-31T16:34:02.927Z,1680280442.927 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-03-31T16:34:02.969Z,1680280442.969 [DeadReckonUsingSpeedCalculator] Loaded
2023-03-31T16:34:02.969Z,1680280442.969 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-03-31T16:34:02.982Z,1680280442.982 [NavChart] Loaded
2023-03-31T16:34:02.983Z,1680280442.983 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-03-31T16:34:02.988Z,1680280442.988 [UniversalFixResidualReporter] Loaded
2023-03-31T16:34:02.989Z,1680280442.989 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-03-31T16:34:02.989Z,1680280442.989 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-03-31T16:34:02.990Z,1680280442.990 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-03-31T16:34:03.135Z,1680280443.135 [SBIT](DEBUG): Construct Startup Built In Test.
2023-03-31T16:34:03.149Z,1680280443.149 [SBIT] Loaded
2023-03-31T16:34:03.149Z,1680280443.149 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-03-31T16:34:03.152Z,1680280443.152 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-03-31T16:34:03.165Z,1680280443.165 [IBIT] Loaded
2023-03-31T16:34:03.165Z,1680280443.165 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-03-31T16:34:03.171Z,1680280443.171 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-03-31T16:34:03.273Z,1680280443.273 [CBIT] Loaded
2023-03-31T16:34:03.273Z,1680280443.273 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-03-31T16:34:03.273Z,1680280443.273 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-03-31T16:34:03.274Z,1680280443.274 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-03-31T16:34:03.823Z,1680280443.823 [CTD_Seabird] Loaded
2023-03-31T16:34:03.823Z,1680280443.823 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-03-31T16:34:03.824Z,1680280443.824 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2023-03-31T16:34:03.825Z,1680280443.825 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2004
2023-03-31T16:34:03.845Z,1680280443.845 [PAR_Licor] Loaded
2023-03-31T16:34:03.845Z,1680280443.845 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-03-31T16:34:03.879Z,1680280443.879 [WetLabsBB2FL] Loaded
2023-03-31T16:34:03.880Z,1680280443.880 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-03-31T16:34:03.881Z,1680280443.881 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0
2023-03-31T16:34:03.881Z,1680280443.881 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2005
2023-03-31T16:34:03.882Z,1680280443.882 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-03-31T16:34:03.882Z,1680280443.882 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-03-31T16:34:04.024Z,1680280444.024 [BuoyancyServo] Loaded
2023-03-31T16:34:04.024Z,1680280444.024 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-03-31T16:34:04.046Z,1680280444.046 [ElevatorServo] Loaded
2023-03-31T16:34:04.046Z,1680280444.046 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-03-31T16:34:04.067Z,1680280444.067 [MassServo] Loaded
2023-03-31T16:34:04.068Z,1680280444.068 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-03-31T16:34:04.089Z,1680280444.089 [RudderServo] Loaded
2023-03-31T16:34:04.089Z,1680280444.089 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-03-31T16:34:04.105Z,1680280444.105 [ThrusterHE] Loaded
2023-03-31T16:34:04.105Z,1680280444.105 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-03-31T16:34:04.106Z,1680280444.106 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-03-31T16:34:04.106Z,1680280444.106 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-03-31T16:34:04.160Z,1680280444.160 [VerticalControl](DEBUG): Construct VerticalControl.
2023-03-31T16:34:04.209Z,1680280444.209 [VerticalControl] Loaded
2023-03-31T16:34:04.210Z,1680280444.210 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-03-31T16:34:04.213Z,1680280444.213 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-03-31T16:34:04.250Z,1680280444.250 [HorizontalControl] Loaded
2023-03-31T16:34:04.250Z,1680280444.250 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-03-31T16:34:04.252Z,1680280444.252 [SpeedControl](DEBUG): Construct SpeedControl.
2023-03-31T16:34:04.254Z,1680280444.254 [SpeedControl] Loaded
2023-03-31T16:34:04.254Z,1680280444.254 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-03-31T16:34:04.257Z,1680280444.257 [LoopControl](DEBUG): Construct LoopControl.
2023-03-31T16:34:04.257Z,1680280444.257 [LoopControl] Loaded
2023-03-31T16:34:04.258Z,1680280444.258 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-03-31T16:34:04.258Z,1680280444.258 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-03-31T16:34:04.259Z,1680280444.259 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-03-31T16:34:04.361Z,1680280444.361 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-03-31T16:34:04.362Z,1680280444.362 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-03-31T16:34:04.521Z,1680280444.521 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-03-31T16:34:04.522Z,1680280444.522 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-03-31T16:34:04.844Z,1680280444.844 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-03-31T16:34:04.844Z,1680280444.844 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-03-31T16:34:04.903Z,1680280444.903 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-03-31T16:34:04.909Z,1680280444.909 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-03-31T16:34:04.912Z,1680280444.912 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-03-31T16:34:04.923Z,1680280444.923 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-03-31T16:34:04.924Z,1680280444.924 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BAE4E0
2023-03-31T16:34:04.924Z,1680280444.924 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2006
2023-03-31T16:34:04.928Z,1680280444.928 [Supervisor](INFO): Main Thread ID is 1756
2023-03-31T16:34:04.929Z,1680280444.929 [Supervisor](DEBUG): Running supervisor.
2023-03-31T16:34:04.929Z,1680280444.929 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2007
2023-03-31T16:34:04.930Z,1680280444.930 [CommandExec](INFO): Initializing the command executive.
2023-03-31T16:34:04.931Z,1680280444.931 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2008
2023-03-31T16:34:04.934Z,1680280444.934 [controlThread ThreadHandler](INFO): Handler Thread ID is 2009
2023-03-31T16:34:04.934Z,1680280444.934 [controlThread](DEBUG): Initializing ControlThread
2023-03-31T16:34:04.935Z,1680280444.935 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-03-31T16:34:04.935Z,1680280444.935 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-03-31T16:34:04.936Z,1680280444.936 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-03-31T16:34:04.936Z,1680280444.936 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-03-31T16:34:04.936Z,1680280444.936 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-03-31T16:34:04.941Z,1680280444.941 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:34:04.941Z,1680280444.941 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-03-31T16:34:04.941Z,1680280444.941 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-03-31T16:34:04.942Z,1680280444.942 [SBIT](INFO): Initialize SBIT Component.
2023-03-31T16:34:04.942Z,1680280444.942 [SBIT](IMPORTANT): git: 2023-03-30
2023-03-31T16:34:04.942Z,1680280444.942 [SBIT](INFO): git hash: f81c159dfc35abf96de73a53ce302b073cdde557
2023-03-31T16:34:04.943Z,1680280444.943 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-03-31T16:34:04.944Z,1680280444.944 [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
2023-03-31T16:34:04.945Z,1680280444.945 [SBIT](INFO): Beginning SBIT in 91.000000 seconds.
2023-03-31T16:34:04.946Z,1680280444.946 [IBIT](INFO): Initialize IBIT Component.
2023-03-31T16:34:04.946Z,1680280444.946 [CBIT](DEBUG): Initialize CBIT Component.
2023-03-31T16:34:04.947Z,1680280444.947 [logger ThreadHandler](INFO): Handler Thread ID is 2010
2023-03-31T16:34:04.959Z,1680280444.959 [CBIT](DEBUG): Initialized mux pins.
2023-03-31T16:34:04.959Z,1680280444.959 [CBIT](DEBUG): Initializing the watchdog timer.
2023-03-31T16:34:04.967Z,1680280444.967 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2011
2023-03-31T16:34:04.979Z,1680280444.979 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2012
2023-03-31T16:34:04.980Z,1680280444.980 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-03-31T16:34:04.983Z,1680280444.983 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-03-31T16:34:04.983Z,1680280444.983 [CBIT](DEBUG): Initializing heartbeat.
2023-03-31T16:34:04.991Z,1680280444.991 [Onboard ThreadHandler](INFO): Handler Thread ID is 2013
2023-03-31T16:34:05.009Z,1680280445.009 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2014
2023-03-31T16:34:05.028Z,1680280445.028 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2015
2023-03-31T16:34:05.028Z,1680280445.028 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-03-31T16:34:05.033Z,1680280445.033 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2017
2023-03-31T16:34:05.035Z,1680280445.035 [WetLabsBB2FL](INFO): Powering up
2023-03-31T16:34:05.037Z,1680280445.037 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2019
2023-03-31T16:34:05.039Z,1680280445.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-03-31T16:34:05.039Z,1680280445.039 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-03-31T16:34:05.039Z,1680280445.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-03-31T16:34:05.039Z,1680280445.039 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-03-31T16:34:05.040Z,1680280445.040 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-03-31T16:34:05.040Z,1680280445.040 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-03-31T16:34:05.040Z,1680280445.040 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-03-31T16:34:05.040Z,1680280445.040 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-03-31T16:34:05.055Z,1680280445.055 [CBIT](DEBUG): Deactivating GF circuits.
2023-03-31T16:34:05.055Z,1680280445.055 [CBIT](DEBUG): Deactivating emergency mode.
2023-03-31T16:34:05.095Z,1680280445.095 [CBIT](DEBUG): Backplane powered.
2023-03-31T16:34:05.100Z,1680280445.100 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-03-31T16:34:05.102Z,1680280445.102 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-03-31T16:34:05.103Z,1680280445.103 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-03-31T16:34:05.103Z,1680280445.103 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-03-31T16:34:05.104Z,1680280445.104 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-03-31T16:34:05.121Z,1680280445.121 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-31T16:34:05.160Z,1680280445.160 [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>


2023-03-31T16:34:05.160Z,1680280445.160 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-03-31T16:34:05.214Z,1680280445.214 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-03-31T16:34:05.215Z,1680280445.215 [Default:A.Wait](DEBUG): Construct Wait.
2023-03-31T16:34:05.217Z,1680280445.217 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-31T16:34:05.262Z,1680280445.262 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-03-31T16:34:05.265Z,1680280445.265 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-03-31T16:34:05.286Z,1680280445.286 [Default:E.Execute](DEBUG): Construct Execute.
2023-03-31T16:34:05.290Z,1680280445.290 [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>


2023-03-31T16:34:05.311Z,1680280445.311 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-03-31T16:34:05.331Z,1680280445.331 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-03-31T16:34:05.393Z,1680280445.393 [MultiRay](INFO): Powering up MultiRay Lights
2023-03-31T16:34:05.432Z,1680280445.432 [Power24vConverter](INFO): Powering up.
2023-03-31T16:34:05.439Z,1680280445.439 [Radio_Surface](INFO): Powering up
2023-03-31T16:34:05.463Z,1680280445.463 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-03-31T16:34:05.471Z,1680280445.471 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-03-31T16:34:05.472Z,1680280445.472 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-03-31T16:34:05.479Z,1680280445.479 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-03-31T16:34:05.480Z,1680280445.480 [MassServo](DEBUG): Initializing EZServoServo.
2023-03-31T16:34:05.487Z,1680280445.487 [MassServo](DEBUG): Initializing MassServo.
2023-03-31T16:34:05.488Z,1680280445.488 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-31T16:34:05.495Z,1680280445.495 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-31T16:34:05.496Z,1680280445.496 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-03-31T16:34:05.504Z,1680280445.504 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-03-31T16:34:07.647Z,1680280447.647 [WetLabsBB2FL](INFO): Powering down
2023-03-31T16:34:11.204Z,1680280451.204 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-03-31T16:34:11.503Z,1680280451.503 [Radio_Surface](INFO): Powering down
2023-03-31T16:34:12.059Z,1680280452.059 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-03-31T16:34:32.802Z,1680280472.802 [NAL9602](INFO): Powering up NAL9602
2023-03-31T16:34:33.609Z,1680280473.609 [Waterlinked](FAULT): Could not initialize.
2023-03-31T16:34:33.609Z,1680280473.609 [Waterlinked] Communications Fault, FailCount= 1
2023-03-31T16:34:33.609Z,1680280473.609 [Waterlinked](ERROR): Communications Fault
2023-03-31T16:34:33.668Z,1680280473.668 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-03-31T16:34:34.083Z,1680280474.083 [Waterlinked](INFO): Powering down
2023-03-31T16:34:34.871Z,1680280474.871 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-03-31T16:34:34.871Z,1680280474.871 [Waterlinked] No Fault, FailCount= 1
2023-03-31T16:34:36.107Z,1680280476.107 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-03-31T16:34:43.708Z,1680280483.708 [NAL9602](INFO): NAL9602 initialized
2023-03-31T16:34:56.747Z,1680280496.747 [CommandExec](IMPORTANT): got command get depth
2023-03-31T16:34:56.748Z,1680280496.748 [CommandExec](IMPORTANT): depth 2.075650 m
2023-03-31T16:35:01.567Z,1680280501.567 [CommandExec](IMPORTANT): got command show best depth
2023-03-31T16:35:01.567Z,1680280501.567 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth
2023-03-31T16:35:08.110Z,1680280508.110 [CommandExec](IMPORTANT): got command get Depth_Keller.offset
2023-03-31T16:35:08.110Z,1680280508.110 [CommandExec](IMPORTANT): Depth_Keller.offset 5.000000 db
2023-03-31T16:35:18.578Z,1680280518.578 [CommandExec](IMPORTANT): got command configSet Depth_Keller.offset 3 decibar persist
2023-03-31T16:35:19.367Z,1680280519.367 [Waterlinked](INFO): Powering down
2023-03-31T16:35:19.779Z,1680280519.779 [Radio_Surface](INFO): Powering up
2023-03-31T16:35:20.615Z,1680280520.615 [CommandExec](IMPORTANT): got command get depth
2023-03-31T16:35:20.616Z,1680280520.616 [CommandExec](IMPORTANT): depth 0.088491 m
2023-03-31T16:35:25.303Z,1680280525.303 [DataOverHttps](INFO): Radio surface powered ON.
2023-03-31T16:35:36.659Z,1680280536.659 [SBIT](IMPORTANT): Beginning Startup BIT
2023-03-31T16:35:36.664Z,1680280536.664 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-31T16:35:47.626Z,1680280547.626 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001529
CHAN A1 (24V): 0.001119
CHAN A2 (12V): -0.006414
CHAN A3 (5V): -0.003090
CHAN B0 (3.3V): -0.001031
CHAN B1 (3.15aV): -0.001517
CHAN B2 (3.15bV): -0.001852
CHAN B3 (GND): -0.000488
OPEN: 0.004882
Full Scale: +/- 1 mA
2023-03-31T16:36:30.788Z,1680280590.788 [SBIT](IMPORTANT): SBIT PASSED
2023-03-31T16:36:30.788Z,1680280590.788 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-03-31T16:36:30.789Z,1680280590.789 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;

2023-03-31T16:36:30.789Z,1680280590.789 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-03-31T16:36:30.789Z,1680280590.789 [SBIT](IMPORTANT): Depth_Keller.offset=3 decibar;

2023-03-31T16:36:30.789Z,1680280590.789 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-03-31T16:36:30.789Z,1680280590.789 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute;

2023-03-31T16:36:30.790Z,1680280590.790 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte;

2023-03-31T16:36:30.790Z,1680280590.790 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second;

2023-03-31T16:36:30.790Z,1680280590.790 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_shape 10.000000 count_per_second;

2023-03-31T16:36:30.790Z,1680280590.790 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_slope 0.200000 count_per_millimeter;

2023-03-31T16:36:30.790Z,1680280590.790 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2023-03-31T16:36:30.790Z,1680280590.790 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=236.612869 cubic_centimeter;

2023-03-31T16:36:30.790Z,1680280590.790 [SBIT](IMPORTANT): VerticalControl.massDefault=17.883769 millimeter;

2023-03-31T16:36:30.790Z,1680280590.790 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool;

2023-03-31T16:36:31.197Z,1680280591.197 [MissionManager](IMPORTANT): Started mission Startup
2023-03-31T16:36:31.197Z,1680280591.197 [Startup] Running Loop=1
2023-03-31T16:36:31.197Z,1680280591.197 [Startup](DEBUG): Aggregate::initialize Startup
2023-03-31T16:36:31.198Z,1680280591.198 [Startup:A.GoToSurface] Running Loop=1
2023-03-31T16:36:31.198Z,1680280591.198 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-31T16:36:31.198Z,1680280591.198 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-31T16:36:31.199Z,1680280591.199 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-31T16:36:31.199Z,1680280591.199 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-31T16:36:31.199Z,1680280591.199 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-31T16:36:31.200Z,1680280591.200 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-31T16:36:31.201Z,1680280591.201 [Startup:StartupSatComms] Running Loop=1
2023-03-31T16:36:31.201Z,1680280591.201 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-03-31T16:36:31.201Z,1680280591.201 [Startup:StartupSatComms:A] Running Loop=1
2023-03-31T16:36:31.606Z,1680280591.606 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-03-31T16:37:05.111Z,1680280625.111 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-03-31T16:37:05.112Z,1680280625.112 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T16:37:05.113Z,1680280625.113 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-03-31T16:37:05.113Z,1680280625.113 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T16:37:05.179Z,1680280625.179 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T16:37:05.180Z,1680280625.180 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T16:37:05.530Z,1680280625.530 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T16:37:05.530Z,1680280625.530 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-03-31T16:37:05.530Z,1680280625.530 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T16:37:05.530Z,1680280625.530 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-03-31T16:37:05.928Z,1680280625.928 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:37:31.379Z,1680280651.379 [Startup:StartupSatComms:A](INFO): Timed out from 2023-03-31T16:36:31.2Z
2023-03-31T16:37:31.379Z,1680280651.379 [Startup:StartupSatComms:A] Stopped
2023-03-31T16:37:31.379Z,1680280651.379 [Startup:StartupSatComms:B] Running Loop=1
2023-03-31T16:37:31.793Z,1680280651.793 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-31T16:37:53.081Z,1680280673.081 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004809
2023-03-31T16:38:00.363Z,1680280680.363 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230331T155942/Courier0004.lzma
2023-03-31T16:38:01.365Z,1680280681.365 [DataOverHttps](INFO): Moved sent file to Logs/20230331T155942/Courier0004.lzma.bak
2023-03-31T16:38:01.365Z,1680280681.365 [DataOverHttps](INFO): SBD MOMSN=17871309
2023-03-31T16:38:16.979Z,1680280696.979 [DataOverHttps](INFO): Sending 384 bytes from file Logs/20230331T160925/Courier0000.lzma
2023-03-31T16:38:17.981Z,1680280697.981 [DataOverHttps](INFO): Moved sent file to Logs/20230331T160925/Courier0000.lzma.bak
2023-03-31T16:38:17.981Z,1680280697.981 [DataOverHttps](INFO): SBD MOMSN=17871311
2023-03-31T16:38:31.593Z,1680280711.593 [Startup:StartupSatComms:B](INFO): Timed out from 2023-03-31T16:37:31.4Z
2023-03-31T16:38:31.593Z,1680280711.593 [Startup:StartupSatComms:B] Stopped
2023-03-31T16:38:31.594Z,1680280711.594 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-03-31T16:38:31.594Z,1680280711.594 [Startup:StartupSatComms] Stopped
2023-03-31T16:38:31.594Z,1680280711.594 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-03-31T16:38:31.595Z,1680280711.595 [Startup](INFO): Completed Startup
2023-03-31T16:38:31.599Z,1680280711.599 [MissionManager](INFO): Startup is completed.
2023-03-31T16:38:31.599Z,1680280711.599 [MissionManager](INFO): Uninitializing Mission Startup
2023-03-31T16:38:31.599Z,1680280711.599 [Startup] Stopped
2023-03-31T16:38:31.599Z,1680280711.599 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-03-31T16:38:31.599Z,1680280711.599 [Startup:A.GoToSurface] Stopped
2023-03-31T16:38:31.599Z,1680280711.599 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-31T16:38:31.997Z,1680280711.997 [MissionManager](IMPORTANT): Started mission Default
2023-03-31T16:38:31.997Z,1680280711.997 [Default] Running Loop=1
2023-03-31T16:38:31.997Z,1680280711.997 [Default](DEBUG): Aggregate::initialize Default
2023-03-31T16:38:31.997Z,1680280711.997 [Default:B.GoToSurface] Running Loop=1
2023-03-31T16:38:31.997Z,1680280711.997 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-31T16:38:31.998Z,1680280711.998 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-31T16:38:31.998Z,1680280711.998 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-31T16:38:31.998Z,1680280711.998 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-31T16:38:31.999Z,1680280711.999 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-31T16:38:32.036Z,1680280712.036 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-31T16:38:32.036Z,1680280712.036 [Default:A.Wait] Running Loop=1
2023-03-31T16:38:32.036Z,1680280712.036 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-03-31T16:38:33.518Z,1680280713.518 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230331T160925/Courier0004.lzma
2023-03-31T16:38:34.521Z,1680280714.521 [DataOverHttps](INFO): Moved sent file to Logs/20230331T160925/Courier0004.lzma.bak
2023-03-31T16:38:34.521Z,1680280714.521 [DataOverHttps](INFO): SBD MOMSN=17871318
2023-03-31T16:38:45.306Z,1680280725.306 [Default:A.Wait](INFO): Done Waiting.
2023-03-31T16:38:45.307Z,1680280725.307 [Default:A.Wait] Stopped
2023-03-31T16:38:45.307Z,1680280725.307 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-03-31T16:38:45.721Z,1680280725.721 [Default:CheckIn] Running Loop=1
2023-03-31T16:38:45.721Z,1680280725.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-31T16:38:45.722Z,1680280725.722 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-31T16:38:46.118Z,1680280726.118 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-03-31T16:39:47.117Z,1680280787.117 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-03-31T16:40:06.130Z,1680280806.130 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-03-31T16:40:06.130Z,1680280806.130 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T16:40:06.131Z,1680280806.131 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2023-03-31T16:40:06.131Z,1680280806.131 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T16:40:06.141Z,1680280806.141 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T16:40:06.142Z,1680280806.142 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T16:40:06.521Z,1680280806.521 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T16:40:06.521Z,1680280806.521 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-03-31T16:40:06.522Z,1680280806.522 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T16:40:06.522Z,1680280806.522 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2023-03-31T16:40:06.932Z,1680280806.932 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:42:14.814Z,1680280934.814 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-03-31T16:42:14.818Z,1680280934.818 [BPC1](INFO): Received data from all battery sticks.
2023-03-31T16:43:06.962Z,1680280986.962 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-03-31T16:43:06.962Z,1680280986.962 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T16:43:06.963Z,1680280986.963 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2023-03-31T16:43:06.963Z,1680280986.963 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T16:43:06.974Z,1680280986.974 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T16:43:06.974Z,1680280986.974 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T16:43:07.352Z,1680280987.352 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T16:43:07.353Z,1680280987.353 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-03-31T16:43:07.353Z,1680280987.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T16:43:07.353Z,1680280987.353 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2023-03-31T16:43:07.751Z,1680280987.751 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:43:45.739Z,1680281025.739 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-31T16:38:45.7Z
2023-03-31T16:43:45.739Z,1680281025.739 [Default:CheckIn:Read_GPS] Stopped
2023-03-31T16:43:45.739Z,1680281025.739 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-31T16:43:46.145Z,1680281026.145 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-31T16:43:52.357Z,1680281032.357 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20230331T163212/Courier0000.lzma
2023-03-31T16:43:53.359Z,1680281033.359 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163212/Courier0000.lzma.bak
2023-03-31T16:43:53.359Z,1680281033.359 [DataOverHttps](INFO): SBD MOMSN=17871678
2023-03-31T16:44:05.200Z,1680281045.200 [CBIT](FAULT): Could not open coulomb counter log file
2023-03-31T16:44:08.733Z,1680281048.733 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230331T163355/Courier0000.lzma
2023-03-31T16:44:09.735Z,1680281049.735 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Courier0000.lzma.bak
2023-03-31T16:44:09.735Z,1680281049.735 [DataOverHttps](INFO): SBD MOMSN=17871684
2023-03-31T16:44:25.681Z,1680281065.681 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230331T163355/Courier0004.lzma
2023-03-31T16:44:26.683Z,1680281066.683 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Courier0004.lzma.bak
2023-03-31T16:44:26.683Z,1680281066.683 [DataOverHttps](INFO): SBD MOMSN=17871687
2023-03-31T16:44:35.039Z,1680281075.039 [Power24vConverter](INFO): Powering down.
2023-03-31T16:44:42.050Z,1680281082.050 [DataOverHttps](INFO): Sending 88 bytes from file Logs/20230221T193253/Express0032.lzma
2023-03-31T16:44:43.051Z,1680281083.051 [DataOverHttps](INFO): Moved sent file to Logs/20230221T193253/Express0032.lzma.bak
2023-03-31T16:44:43.051Z,1680281083.051 [DataOverHttps](INFO): SBD MOMSN=17871689
2023-03-31T16:44:45.135Z,1680281085.135 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-31T16:44:45.135Z,1680281085.135 [NAL9602] Data Fault, FailCount= 1
2023-03-31T16:44:45.135Z,1680281085.135 [NAL9602](ERROR): Data Fault
2023-03-31T16:44:45.192Z,1680281085.192 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-31T16:44:45.536Z,1680281085.536 [NAL9602](INFO): Powering down
2023-03-31T16:44:46.391Z,1680281086.391 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-31T16:44:46.391Z,1680281086.391 [NAL9602] No Fault, FailCount= 1
2023-03-31T16:44:58.352Z,1680281098.352 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20230221T210749/Express0001.lzma
2023-03-31T16:44:59.247Z,1680281099.247 [DataOverHttps](INFO): Moved sent file to Logs/20230221T210749/Express0001.lzma.bak
2023-03-31T16:44:59.247Z,1680281099.247 [DataOverHttps](INFO): SBD MOMSN=17871692
2023-03-31T16:45:14.979Z,1680281114.979 [DataOverHttps](INFO): Sending 1050 bytes from file Logs/20230310T183903/Express0001.lzma
2023-03-31T16:45:15.838Z,1680281115.838 [NAL9602](INFO): Powering up NAL9602
2023-03-31T16:45:15.980Z,1680281115.980 [DataOverHttps](INFO): Moved sent file to Logs/20230310T183903/Express0001.lzma.bak
2023-03-31T16:45:15.980Z,1680281115.980 [DataOverHttps](INFO): SBD MOMSN=17871701
2023-03-31T16:45:26.751Z,1680281126.751 [NAL9602](INFO): NAL9602 initialized
2023-03-31T16:45:31.282Z,1680281131.282 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20230310T183903/Express0005.lzma
2023-03-31T16:45:32.283Z,1680281132.283 [DataOverHttps](INFO): Moved sent file to Logs/20230310T183903/Express0005.lzma.bak
2023-03-31T16:45:32.284Z,1680281132.284 [DataOverHttps](INFO): SBD MOMSN=17871736
2023-03-31T16:45:47.865Z,1680281147.865 [DataOverHttps](INFO): Sending 406 bytes from file Logs/20230331T154027/Express0001.lzma
2023-03-31T16:45:48.867Z,1680281148.867 [DataOverHttps](INFO): Moved sent file to Logs/20230331T154027/Express0001.lzma.bak
2023-03-31T16:45:48.867Z,1680281148.867 [DataOverHttps](INFO): SBD MOMSN=17871740
2023-03-31T16:46:04.166Z,1680281164.166 [DataOverHttps](INFO): Sending 1050 bytes from file Logs/20230331T154127/Express0001.lzma
2023-03-31T16:46:05.179Z,1680281165.179 [DataOverHttps](INFO): Moved sent file to Logs/20230331T154127/Express0001.lzma.bak
2023-03-31T16:46:05.180Z,1680281165.180 [DataOverHttps](INFO): SBD MOMSN=17871749
2023-03-31T16:46:07.997Z,1680281167.997 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-03-31T16:46:07.997Z,1680281167.997 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T16:46:07.998Z,1680281167.998 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2023-03-31T16:46:07.999Z,1680281167.999 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T16:46:08.009Z,1680281168.009 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T16:46:08.010Z,1680281168.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T16:46:08.435Z,1680281168.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T16:46:08.435Z,1680281168.435 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-03-31T16:46:08.435Z,1680281168.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T16:46:08.436Z,1680281168.436 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2023-03-31T16:46:08.818Z,1680281168.818 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:46:20.602Z,1680281180.602 [DataOverHttps](INFO): Sending 565 bytes from file Logs/20230331T154455/Express0001.lzma
2023-03-31T16:46:21.603Z,1680281181.603 [DataOverHttps](INFO): Moved sent file to Logs/20230331T154455/Express0001.lzma.bak
2023-03-31T16:46:21.603Z,1680281181.603 [DataOverHttps](INFO): SBD MOMSN=17871782
2023-03-31T16:46:36.845Z,1680281196.845 [DataOverHttps](INFO): Sending 2471 bytes from file Logs/20230331T154718/Express0001.lzma
2023-03-31T16:46:37.839Z,1680281197.839 [DataOverHttps](INFO): Moved sent file to Logs/20230331T154718/Express0001.lzma.bak
2023-03-31T16:46:37.839Z,1680281197.839 [DataOverHttps](INFO): SBD MOMSN=17871824
2023-03-31T16:46:53.623Z,1680281213.623 [DataOverHttps](INFO): Sending 2065 bytes from file Logs/20230331T155507/Express0001.lzma
2023-03-31T16:46:54.239Z,1680281214.239 [DataOverHttps](INFO): Moved sent file to Logs/20230331T155507/Express0001.lzma.bak
2023-03-31T16:46:54.239Z,1680281214.239 [DataOverHttps](INFO): SBD MOMSN=17872410
2023-03-31T16:47:09.561Z,1680281229.561 [DataOverHttps](INFO): Sending 477 bytes from file Logs/20230331T155810/Express0001.lzma
2023-03-31T16:47:10.563Z,1680281230.563 [DataOverHttps](INFO): Moved sent file to Logs/20230331T155810/Express0001.lzma.bak
2023-03-31T16:47:10.563Z,1680281230.563 [DataOverHttps](INFO): SBD MOMSN=17872679
2023-03-31T16:47:25.937Z,1680281245.937 [DataOverHttps](INFO): Sending 1060 bytes from file Logs/20230331T155942/Express0001.lzma
2023-03-31T16:47:26.935Z,1680281246.935 [DataOverHttps](INFO): Moved sent file to Logs/20230331T155942/Express0001.lzma.bak
2023-03-31T16:47:26.935Z,1680281246.935 [DataOverHttps](INFO): SBD MOMSN=17872686
2023-03-31T16:47:43.753Z,1680281263.753 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20230331T155942/Express0005.lzma
2023-03-31T16:47:44.755Z,1680281264.755 [DataOverHttps](INFO): Moved sent file to Logs/20230331T155942/Express0005.lzma.bak
2023-03-31T16:47:44.755Z,1680281264.755 [DataOverHttps](INFO): SBD MOMSN=17872712
2023-03-31T16:48:01.188Z,1680281281.188 [DataOverHttps](INFO): Sending 4471 bytes from file Logs/20230331T160925/Express0001.lzma
2023-03-31T16:48:02.183Z,1680281282.183 [DataOverHttps](INFO): Moved sent file to Logs/20230331T160925/Express0001.lzma.bak
2023-03-31T16:48:02.183Z,1680281282.183 [DataOverHttps](INFO): SBD MOMSN=17872721
2023-03-31T16:48:19.029Z,1680281299.029 [DataOverHttps](INFO): Sending 491 bytes from file Logs/20230331T160925/Express0005.lzma
2023-03-31T16:48:20.031Z,1680281300.031 [DataOverHttps](INFO): Moved sent file to Logs/20230331T160925/Express0005.lzma.bak
2023-03-31T16:48:20.031Z,1680281300.031 [DataOverHttps](INFO): SBD MOMSN=17874243
2023-03-31T16:48:35.339Z,1680281315.339 [DataOverHttps](INFO): Sending 1188 bytes from file Logs/20230331T163212/Express0001.lzma
2023-03-31T16:48:36.339Z,1680281316.339 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163212/Express0001.lzma.bak
2023-03-31T16:48:36.339Z,1680281316.339 [DataOverHttps](INFO): SBD MOMSN=17874369
2023-03-31T16:48:51.722Z,1680281331.722 [DataOverHttps](INFO): Sending 1093 bytes from file Logs/20230331T163355/Express0001.lzma
2023-03-31T16:48:52.723Z,1680281332.723 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Express0001.lzma.bak
2023-03-31T16:48:52.723Z,1680281332.723 [DataOverHttps](INFO): SBD MOMSN=17874456
2023-03-31T16:49:08.077Z,1680281348.077 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230331T163355/Express0005.lzma
2023-03-31T16:49:09.013Z,1680281349.013 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-03-31T16:49:09.013Z,1680281349.013 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T16:49:09.014Z,1680281349.014 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2023-03-31T16:49:09.014Z,1680281349.014 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T16:49:09.025Z,1680281349.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T16:49:09.025Z,1680281349.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T16:49:09.079Z,1680281349.079 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Express0005.lzma.bak
2023-03-31T16:49:09.079Z,1680281349.079 [DataOverHttps](INFO): SBD MOMSN=17874491
2023-03-31T16:49:09.439Z,1680281349.439 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T16:49:09.440Z,1680281349.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-03-31T16:49:09.440Z,1680281349.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T16:49:09.440Z,1680281349.440 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2023-03-31T16:49:09.814Z,1680281349.814 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:49:11.060Z,1680281351.060 [Default:CheckIn:Read_Iridium] Stopped
2023-03-31T16:49:11.060Z,1680281351.060 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-31T16:49:11.060Z,1680281351.060 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-31T16:52:10.046Z,1680281530.046 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-03-31T16:52:10.046Z,1680281530.046 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T16:52:10.048Z,1680281530.048 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2023-03-31T16:52:10.048Z,1680281530.048 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T16:52:10.116Z,1680281530.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T16:52:10.116Z,1680281530.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T16:52:10.491Z,1680281530.491 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T16:52:10.491Z,1680281530.491 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-03-31T16:52:10.491Z,1680281530.491 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T16:52:10.491Z,1680281530.491 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2023-03-31T16:52:10.861Z,1680281530.861 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:54:05.580Z,1680281645.580 [CBIT](FAULT): Could not open coulomb counter log file
2023-03-31T16:54:11.730Z,1680281651.730 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-31T16:54:11.731Z,1680281651.731 [Default:CheckIn:C.Wait] Stopped
2023-03-31T16:54:11.731Z,1680281651.731 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-31T16:54:11.733Z,1680281651.733 [Default:CheckIn:D] Running Loop=1
2023-03-31T16:54:12.105Z,1680281652.105 [Default:CheckIn:D] Stopped
2023-03-31T16:54:12.106Z,1680281652.106 [Default:CheckIn:E] Running Loop=1
2023-03-31T16:54:12.498Z,1680281652.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.668471 min 
2023-03-31T16:54:12.498Z,1680281652.498 [Default:CheckIn:E] Stopped
2023-03-31T16:54:12.498Z,1680281652.498 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-31T16:54:12.498Z,1680281652.498 [Default:CheckIn] Stopped
2023-03-31T16:54:12.498Z,1680281652.498 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-31T16:54:12.498Z,1680281652.498 [Default:CheckIn](INFO): Running loop #2
2023-03-31T16:54:12.498Z,1680281652.498 [Default:CheckIn] Running Loop=2
2023-03-31T16:54:12.499Z,1680281652.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-31T16:54:12.499Z,1680281652.499 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-31T16:55:11.099Z,1680281711.099 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-03-31T16:55:11.099Z,1680281711.099 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T16:55:11.100Z,1680281711.100 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2023-03-31T16:55:11.100Z,1680281711.100 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T16:55:11.111Z,1680281711.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T16:55:11.111Z,1680281711.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T16:55:11.504Z,1680281711.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T16:55:11.505Z,1680281711.505 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-03-31T16:55:11.505Z,1680281711.505 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T16:55:11.505Z,1680281711.505 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2023-03-31T16:55:11.885Z,1680281711.885 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:55:27.625Z,1680281727.625 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-31T16:55:27.625Z,1680281727.625 [NAL9602] Data Fault, FailCount= 2
2023-03-31T16:55:27.625Z,1680281727.625 [NAL9602](ERROR): Data Fault
2023-03-31T16:55:27.643Z,1680281727.643 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-31T16:55:28.039Z,1680281728.039 [NAL9602](INFO): Powering down
2023-03-31T16:55:28.859Z,1680281728.859 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-31T16:55:28.859Z,1680281728.859 [NAL9602] No Fault, FailCount= 2
2023-03-31T16:55:58.335Z,1680281758.335 [NAL9602](INFO): Powering up NAL9602
2023-03-31T16:56:09.243Z,1680281769.243 [NAL9602](INFO): NAL9602 initialized
2023-03-31T16:58:12.084Z,1680281892.084 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-03-31T16:58:12.084Z,1680281892.084 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T16:58:12.085Z,1680281892.085 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2023-03-31T16:58:12.085Z,1680281892.085 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T16:58:12.095Z,1680281892.095 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T16:58:12.095Z,1680281892.095 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T16:58:12.496Z,1680281892.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T16:58:12.496Z,1680281892.496 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-03-31T16:58:12.497Z,1680281892.497 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T16:58:12.497Z,1680281892.497 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2023-03-31T16:58:12.870Z,1680281892.870 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T16:59:12.668Z,1680281952.668 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-31T16:54:12.5Z
2023-03-31T16:59:12.668Z,1680281952.668 [Default:CheckIn:Read_GPS] Stopped
2023-03-31T16:59:12.668Z,1680281952.668 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-31T16:59:18.213Z,1680281958.213 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T163355/Courier0007.lzma
2023-03-31T16:59:19.215Z,1680281959.215 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Courier0007.lzma.bak
2023-03-31T16:59:19.215Z,1680281959.215 [DataOverHttps](INFO): SBD MOMSN=17874864
2023-03-31T16:59:34.577Z,1680281974.577 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20230331T163355/Express0008.lzma
2023-03-31T16:59:35.579Z,1680281975.579 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Express0008.lzma.bak
2023-03-31T16:59:35.579Z,1680281975.579 [DataOverHttps](INFO): SBD MOMSN=17874866
2023-03-31T16:59:37.732Z,1680281977.732 [Default:CheckIn:Read_Iridium] Stopped
2023-03-31T16:59:37.732Z,1680281977.732 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-31T16:59:37.732Z,1680281977.732 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-31T17:01:13.058Z,1680282073.058 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-03-31T17:01:13.058Z,1680282073.058 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:01:13.059Z,1680282073.059 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2023-03-31T17:01:13.059Z,1680282073.059 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:01:13.078Z,1680282073.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:01:13.079Z,1680282073.079 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:01:13.500Z,1680282073.500 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:01:13.500Z,1680282073.500 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-03-31T17:01:13.500Z,1680282073.500 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:01:13.501Z,1680282073.501 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2023-03-31T17:01:13.862Z,1680282073.862 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:04:05.590Z,1680282245.590 [CBIT](FAULT): Could not open coulomb counter log file
2023-03-31T17:04:14.071Z,1680282254.071 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-03-31T17:04:14.071Z,1680282254.071 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:04:14.072Z,1680282254.072 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2023-03-31T17:04:14.072Z,1680282254.072 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:04:14.083Z,1680282254.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:04:14.083Z,1680282254.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:04:14.487Z,1680282254.487 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:04:14.487Z,1680282254.487 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-03-31T17:04:14.487Z,1680282254.487 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:04:14.487Z,1680282254.487 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2023-03-31T17:04:14.857Z,1680282254.857 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:04:38.288Z,1680282278.288 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-31T17:04:38.288Z,1680282278.288 [Default:CheckIn:C.Wait] Stopped
2023-03-31T17:04:38.288Z,1680282278.288 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-31T17:04:38.289Z,1680282278.289 [Default:CheckIn:D] Running Loop=1
2023-03-31T17:04:38.710Z,1680282278.710 [Default:CheckIn:D] Stopped
2023-03-31T17:04:38.710Z,1680282278.710 [Default:CheckIn:E] Running Loop=1
2023-03-31T17:04:39.124Z,1680282279.124 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.111871 min 
2023-03-31T17:04:39.124Z,1680282279.124 [Default:CheckIn:E] Stopped
2023-03-31T17:04:39.124Z,1680282279.124 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-31T17:04:39.124Z,1680282279.124 [Default:CheckIn] Stopped
2023-03-31T17:04:39.124Z,1680282279.124 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-31T17:04:39.124Z,1680282279.124 [Default:CheckIn](INFO): Running loop #3
2023-03-31T17:04:39.124Z,1680282279.124 [Default:CheckIn] Running Loop=3
2023-03-31T17:04:39.124Z,1680282279.124 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-31T17:04:39.125Z,1680282279.125 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-31T17:06:10.407Z,1680282370.407 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-31T17:06:10.407Z,1680282370.407 [NAL9602] Data Fault, FailCount= 3
2023-03-31T17:06:10.407Z,1680282370.407 [NAL9602](ERROR): Data Fault
2023-03-31T17:06:10.425Z,1680282370.425 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-31T17:06:10.812Z,1680282370.812 [NAL9602](INFO): Powering down
2023-03-31T17:06:11.636Z,1680282371.636 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-31T17:06:11.636Z,1680282371.636 [NAL9602] No Fault, FailCount= 3
2023-03-31T17:06:41.111Z,1680282401.111 [NAL9602](INFO): Powering up NAL9602
2023-03-31T17:06:52.019Z,1680282412.019 [NAL9602](INFO): NAL9602 initialized
2023-03-31T17:07:14.968Z,1680282434.968 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-03-31T17:07:14.968Z,1680282434.968 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:07:14.969Z,1680282434.969 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2023-03-31T17:07:14.969Z,1680282434.969 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:07:14.980Z,1680282434.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:07:14.980Z,1680282434.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:07:15.377Z,1680282435.377 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:07:15.377Z,1680282435.377 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-03-31T17:07:15.378Z,1680282435.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:07:15.378Z,1680282435.378 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2023-03-31T17:07:15.779Z,1680282435.779 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:09:39.177Z,1680282579.177 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-31T17:04:39.1Z
2023-03-31T17:09:39.177Z,1680282579.177 [Default:CheckIn:Read_GPS] Stopped
2023-03-31T17:09:39.177Z,1680282579.177 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-31T17:09:46.085Z,1680282586.085 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T163355/Courier0010.lzma
2023-03-31T17:09:47.088Z,1680282587.088 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Courier0010.lzma.bak
2023-03-31T17:09:47.088Z,1680282587.088 [DataOverHttps](INFO): SBD MOMSN=17875228
2023-03-31T17:10:02.346Z,1680282602.346 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20230331T163355/Express0011.lzma
2023-03-31T17:10:03.348Z,1680282603.348 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Express0011.lzma.bak
2023-03-31T17:10:03.348Z,1680282603.348 [DataOverHttps](INFO): SBD MOMSN=17875230
2023-03-31T17:10:05.442Z,1680282605.442 [Default:CheckIn:Read_Iridium] Stopped
2023-03-31T17:10:05.442Z,1680282605.442 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-31T17:10:05.442Z,1680282605.442 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-31T17:10:15.963Z,1680282615.963 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-03-31T17:10:15.963Z,1680282615.963 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:10:15.964Z,1680282615.964 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2023-03-31T17:10:15.964Z,1680282615.964 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:10:15.983Z,1680282615.983 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:10:15.984Z,1680282615.984 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:10:16.389Z,1680282616.389 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:10:16.389Z,1680282616.389 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-03-31T17:10:16.390Z,1680282616.390 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:10:16.394Z,1680282616.394 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2023-03-31T17:10:16.766Z,1680282616.766 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:10:43.521Z,1680282643.521 [CommandExec](IMPORTANT): got command get WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2023-03-31T17:10:43.521Z,1680282643.521 [CommandExec](IMPORTANT): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water nan ug/l
2023-03-31T17:13:16.993Z,1680282796.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-03-31T17:13:16.994Z,1680282796.994 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:13:16.995Z,1680282796.995 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2023-03-31T17:13:16.995Z,1680282796.995 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:13:17.006Z,1680282797.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:13:17.006Z,1680282797.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:13:17.400Z,1680282797.400 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:13:17.400Z,1680282797.400 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-03-31T17:13:17.401Z,1680282797.401 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:13:17.401Z,1680282797.401 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2023-03-31T17:13:17.795Z,1680282797.795 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:14:05.882Z,1680282845.882 [CBIT](FAULT): Could not open coulomb counter log file
2023-03-31T17:15:06.062Z,1680282906.062 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-31T17:15:06.063Z,1680282906.063 [Default:CheckIn:C.Wait] Stopped
2023-03-31T17:15:06.063Z,1680282906.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-31T17:15:06.063Z,1680282906.063 [Default:CheckIn:D] Running Loop=1
2023-03-31T17:15:06.493Z,1680282906.493 [Default:CheckIn:D] Stopped
2023-03-31T17:15:06.493Z,1680282906.493 [Default:CheckIn:E] Running Loop=1
2023-03-31T17:15:06.897Z,1680282906.897 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.574935 min 
2023-03-31T17:15:06.897Z,1680282906.897 [Default:CheckIn:E] Stopped
2023-03-31T17:15:06.897Z,1680282906.897 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-31T17:15:06.897Z,1680282906.897 [Default:CheckIn] Stopped
2023-03-31T17:15:06.897Z,1680282906.897 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-31T17:15:06.897Z,1680282906.897 [Default:CheckIn](INFO): Running loop #4
2023-03-31T17:15:06.897Z,1680282906.897 [Default:CheckIn] Running Loop=4
2023-03-31T17:15:06.898Z,1680282906.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-31T17:15:06.898Z,1680282906.898 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-31T17:16:17.978Z,1680282977.978 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-03-31T17:16:17.979Z,1680282977.979 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:16:17.980Z,1680282977.980 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2023-03-31T17:16:17.980Z,1680282977.980 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:16:18.036Z,1680282978.036 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:16:18.036Z,1680282978.036 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:16:18.405Z,1680282978.405 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:16:18.405Z,1680282978.405 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-03-31T17:16:18.406Z,1680282978.406 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:16:18.406Z,1680282978.406 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2023-03-31T17:16:18.807Z,1680282978.807 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:16:53.192Z,1680283013.192 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-31T17:16:53.193Z,1680283013.193 [NAL9602] Data Fault, FailCount= 4
2023-03-31T17:16:53.193Z,1680283013.193 [NAL9602](ERROR): Data Fault
2023-03-31T17:16:53.247Z,1680283013.247 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-31T17:16:53.592Z,1680283013.592 [NAL9602](INFO): Powering down
2023-03-31T17:16:54.421Z,1680283014.421 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-31T17:16:54.421Z,1680283014.421 [NAL9602] No Fault, FailCount= 4
2023-03-31T17:17:23.890Z,1680283043.890 [NAL9602](INFO): Powering up NAL9602
2023-03-31T17:17:34.804Z,1680283054.804 [NAL9602](INFO): NAL9602 initialized
2023-03-31T17:19:19.053Z,1680283159.053 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2023-03-31T17:19:19.053Z,1680283159.053 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:19:19.054Z,1680283159.054 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2023-03-31T17:19:19.054Z,1680283159.054 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:19:19.112Z,1680283159.112 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:19:19.112Z,1680283159.112 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:19:19.450Z,1680283159.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:19:19.450Z,1680283159.450 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2023-03-31T17:19:19.450Z,1680283159.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:19:19.450Z,1680283159.450 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2023-03-31T17:19:19.860Z,1680283159.860 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:20:07.135Z,1680283207.135 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-31T17:15:06.9Z
2023-03-31T17:20:07.135Z,1680283207.135 [Default:CheckIn:Read_GPS] Stopped
2023-03-31T17:20:07.135Z,1680283207.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-31T17:20:13.774Z,1680283213.774 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T163355/Courier0013.lzma
2023-03-31T17:20:14.777Z,1680283214.777 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Courier0013.lzma.bak
2023-03-31T17:20:14.777Z,1680283214.777 [DataOverHttps](INFO): SBD MOMSN=17875581
2023-03-31T17:20:30.200Z,1680283230.200 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20230331T163355/Express0014.lzma
2023-03-31T17:20:31.201Z,1680283231.201 [DataOverHttps](INFO): Moved sent file to Logs/20230331T163355/Express0014.lzma.bak
2023-03-31T17:20:31.201Z,1680283231.201 [DataOverHttps](INFO): SBD MOMSN=17875583
2023-03-31T17:20:33.394Z,1680283233.394 [Default:CheckIn:Read_Iridium] Stopped
2023-03-31T17:20:33.395Z,1680283233.395 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-31T17:20:33.399Z,1680283233.399 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-31T17:22:20.035Z,1680283340.035 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2023-03-31T17:22:20.035Z,1680283340.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:22:20.037Z,1680283340.037 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2023-03-31T17:22:20.037Z,1680283340.037 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:22:20.092Z,1680283340.092 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:22:20.092Z,1680283340.092 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:22:20.449Z,1680283340.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:22:20.449Z,1680283340.449 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2023-03-31T17:22:20.450Z,1680283340.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:22:20.450Z,1680283340.450 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2023-03-31T17:22:20.852Z,1680283340.852 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:24:05.932Z,1680283445.932 [CBIT](FAULT): Could not open coulomb counter log file
2023-03-31T17:25:21.056Z,1680283521.056 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2023-03-31T17:25:21.056Z,1680283521.056 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-31T17:25:21.057Z,1680283521.057 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2023-03-31T17:25:21.057Z,1680283521.057 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-03-31T17:25:21.068Z,1680283521.068 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-31T17:25:21.069Z,1680283521.069 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-03-31T17:25:21.445Z,1680283521.445 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-31T17:25:21.445Z,1680283521.445 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2023-03-31T17:25:21.446Z,1680283521.446 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-03-31T17:25:21.446Z,1680283521.446 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2023-03-31T17:25:21.845Z,1680283521.845 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-03-31T17:25:33.986Z,1680283533.986 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-31T17:25:33.986Z,1680283533.986 [Default:CheckIn:C.Wait] Stopped
2023-03-31T17:25:33.986Z,1680283533.986 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-31T17:25:33.986Z,1680283533.986 [Default:CheckIn:D] Running Loop=1
2023-03-31T17:25:34.389Z,1680283534.389 [Default:CheckIn:D] Stopped
2023-03-31T17:25:34.389Z,1680283534.389 [Default:CheckIn:E] Running Loop=1
2023-03-31T17:25:34.779Z,1680283534.779 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.039864 min 
2023-03-31T17:25:34.779Z,1680283534.779 [Default:CheckIn:E] Stopped
2023-03-31T17:25:34.779Z,1680283534.779 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-31T17:25:34.779Z,1680283534.779 [Default:CheckIn] Stopped
2023-03-31T17:25:34.780Z,1680283534.780 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-31T17:25:34.780Z,1680283534.780 [Default:CheckIn](INFO): Running loop #5
2023-03-31T17:25:34.780Z,1680283534.780 [Default:CheckIn] Running Loop=5
2023-03-31T17:25:34.780Z,1680283534.780 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-31T17:25:34.780Z,1680283534.780 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-31T17:27:35.961Z,1680283655.961 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-31T17:27:35.961Z,1680283655.961 [NAL9602] Data Fault, FailCount= 5
2023-03-31T17:27:35.961Z,1680283655.961 [NAL9602](ERROR): Data Fault
2023-03-31T17:27:35.978Z,1680283655.978 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-31T17:27:35.978Z,1680283655.978 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-03-31T17:27:36.357Z,1680283656.357 [NAL9602](INFO): Powering down
2023-03-31T17:27:36.400Z,1680283656.400 [CommandExec](FAULT): Scheduling is paused
2023-03-31T17:27:36.400Z,1680283656.400 [CBIT](INFO): Critical error at 20230331T172735
2023-03-31T17:27:36.400Z,1680283656.400 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-03-31T17:28:12.080Z,1680283692.080 [CommandExec](IMPORTANT): got command restart application
2023-03-31T17:28:13.083Z,1680283693.083 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:13.083Z,1680283693.083 [CommandExec](INFO): Uninitializing the command executive.
2023-03-31T17:28:13.083Z,1680283693.083 [CommandExec](INFO): Uninitializing the command scheduler.
2023-03-31T17:28:13.084Z,1680283693.084 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:13.087Z,1680283693.087 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-03-31T17:28:13.087Z,1680283693.087 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-03-31T17:28:13.087Z,1680283693.087 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:13.088Z,1680283693.088 [NavChartDb](INFO): Join timeout helper Thread ID is 2179
2023-03-31T17:28:13.312Z,1680283693.312 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:13.312Z,1680283693.312 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:13.319Z,1680283693.319 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-03-31T17:28:13.319Z,1680283693.319 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:13.320Z,1680283693.320 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2180
2023-03-31T17:28:13.539Z,1680283693.539 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:13.539Z,1680283693.539 [WetLabsBB2FL](INFO): Powering down
2023-03-31T17:28:13.540Z,1680283693.540 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:13.543Z,1680283693.543 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-03-31T17:28:13.543Z,1680283693.543 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:13.543Z,1680283693.543 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2181
2023-03-31T17:28:14.119Z,1680283694.119 [CTD_Seabird](INFO): Powering down
2023-03-31T17:28:14.131Z,1680283694.131 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:14.132Z,1680283694.132 [CTD_Seabird](INFO): Powering down
2023-03-31T17:28:14.143Z,1680283694.143 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:14.159Z,1680283694.159 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-03-31T17:28:14.159Z,1680283694.159 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:14.159Z,1680283694.159 [Radio_Surface](INFO): Join timeout helper Thread ID is 2182
2023-03-31T17:28:14.499Z,1680283694.499 [Radio_Surface](INFO): Powering down
2023-03-31T17:28:14.500Z,1680283694.500 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:14.500Z,1680283694.500 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:14.507Z,1680283694.507 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-03-31T17:28:14.507Z,1680283694.507 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:14.507Z,1680283694.507 [Onboard](INFO): Join timeout helper Thread ID is 2183
2023-03-31T17:28:14.639Z,1680283694.639 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-03-31T17:28:19.179Z,1680283699.179 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:19.179Z,1680283699.179 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.188Z,1680283699.188 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-03-31T17:28:19.188Z,1680283699.188 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.188Z,1680283699.188 [DataOverHttps](INFO): Join timeout helper Thread ID is 2184
2023-03-31T17:28:19.660Z,1680283699.660 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:19.661Z,1680283699.661 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.668Z,1680283699.668 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-03-31T17:28:19.668Z,1680283699.668 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.668Z,1680283699.668 [BackseatComponent](INFO): Join timeout helper Thread ID is 2185
2023-03-31T17:28:19.843Z,1680283699.843 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:19.844Z,1680283699.844 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.857Z,1680283699.857 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-03-31T17:28:19.858Z,1680283699.858 [logger ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.858Z,1680283699.858 [logger](INFO): Join timeout helper Thread ID is 2186
2023-03-31T17:28:19.898Z,1680283699.898 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:19.898Z,1680283699.898 [logger ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.904Z,1680283699.904 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-03-31T17:28:19.904Z,1680283699.904 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.904Z,1680283699.904 [CommandLine](INFO): Join timeout helper Thread ID is 2187
2023-03-31T17:28:19.943Z,1680283699.943 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:19.943Z,1680283699.943 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.948Z,1680283699.948 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-03-31T17:28:19.948Z,1680283699.948 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.948Z,1680283699.948 [CommandExec](INFO): Join timeout helper Thread ID is 2188
2023-03-31T17:28:19.949Z,1680283699.949 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-03-31T17:28:19.949Z,1680283699.949 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:19.950Z,1680283699.950 [controlThread](INFO): Join timeout helper Thread ID is 2189
2023-03-31T17:28:20.060Z,1680283700.060 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-31T17:28:20.060Z,1680283700.060 [controlThread](DEBUG): Uninitializing ControlThread
2023-03-31T17:28:20.060Z,1680283700.060 [AHRS_M2](INFO): Powering down
2023-03-31T17:28:20.132Z,1680283700.132 [MultiRay](INFO): Powering down.
2023-03-31T17:28:20.133Z,1680283700.133 [NAL9602](INFO): Powering down
2023-03-31T17:28:20.134Z,1680283700.134 [Waterlinked](INFO): Powering down
2023-03-31T17:28:20.135Z,1680283700.135 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-03-31T17:28:20.136Z,1680283700.136 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-03-31T17:28:20.137Z,1680283700.137 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-03-31T17:28:20.137Z,1680283700.137 [MissionManager](INFO): Uninitializing Mission Default
2023-03-31T17:28:20.137Z,1680283700.137 [Default] Stopped
2023-03-31T17:28:20.137Z,1680283700.137 [Default](DEBUG): Aggregate::uninitialize Default
2023-03-31T17:28:20.137Z,1680283700.137 [Default:B.GoToSurface] Stopped
2023-03-31T17:28:20.137Z,1680283700.137 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-31T17:28:20.138Z,1680283700.138 [Default:CheckIn] Stopped
2023-03-31T17:28:20.138Z,1680283700.138 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-31T17:28:20.138Z,1680283700.138 [Default:CheckIn:Read_GPS] Stopped
2023-03-31T17:28:20.141Z,1680283700.141 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-03-31T17:28:20.141Z,1680283700.141 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-03-31T17:28:20.141Z,1680283700.141 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-03-31T17:28:20.141Z,1680283700.141 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-03-31T17:28:20.142Z,1680283700.142 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-03-31T17:28:20.142Z,1680283700.142 [BuoyancyServo](INFO): Powering down
2023-03-31T17:28:20.155Z,1680283700.155 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-03-31T17:28:20.155Z,1680283700.155 [ElevatorServo](INFO): Powering down
2023-03-31T17:28:20.156Z,1680283700.156 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-03-31T17:28:20.156Z,1680283700.156 [MassServo](INFO): Powering down
2023-03-31T17:28:20.157Z,1680283700.157 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-31T17:28:20.157Z,1680283700.157 [RudderServo](INFO): Powering down
2023-03-31T17:28:20.158Z,1680283700.158 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-03-31T17:28:20.158Z,1680283700.158 [ThrusterHE](INFO): Powering down
2023-03-31T17:28:20.159Z,1680283700.159 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-03-31T17:28:20.159Z,1680283700.159 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-03-31T17:28:20.160Z,1680283700.160 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-03-31T17:28:20.160Z,1680283700.160 [CBIT](DEBUG): Powering off loads.
2023-03-31T17:28:20.171Z,1680283700.171 [CBIT](DEBUG): Disabling WDT.
2023-03-31T17:28:20.183Z,1680283700.183 [CBIT](DEBUG): Opening all GF detection circuits.
2023-03-31T17:28:20.184Z,1680283700.184 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:20.201Z,1680283700.201 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:20.205Z,1680283700.205 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:20.211Z,1680283700.211 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:20.256Z,1680283700.256 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:20.304Z,1680283700.304 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:20.307Z,1680283700.307 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:20.356Z,1680283700.356 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-31T17:28:20.401Z,1680283700.401 [logger ThreadHandler](INFO): Thread cancelled.