2023-03-28T17:08:25.488Z,1680023305.488 [Supervisor](DEBUG): Initializing supervisor.
2023-03-28T17:08:25.492Z,1680023305.492 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-03-28T17:08:25.493Z,1680023305.493 [SyncHandler](INFO): Protected caller Thread ID is 7805
2023-03-28T17:08:25.493Z,1680023305.493 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-03-28T17:08:25.494Z,1680023305.494 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-03-28T17:08:25.495Z,1680023305.495 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7806
2023-03-28T17:08:25.498Z,1680023305.498 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-03-28T17:08:25.515Z,1680023305.515 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-03-28T17:08:25.516Z,1680023305.516 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-03-28T17:08:25.517Z,1680023305.517 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7807
2023-03-28T17:08:25.521Z,1680023305.521 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-03-28T17:08:25.522Z,1680023305.522 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-03-28T17:08:25.522Z,1680023305.522 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7808
2023-03-28T17:08:25.524Z,1680023305.524 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-03-28T17:08:25.525Z,1680023305.525 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-03-28T17:08:25.525Z,1680023305.525 [logger ThreadHandler](INFO): Protected caller Thread ID is 7809
2023-03-28T17:08:25.529Z,1680023305.529 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-03-28T17:08:25.529Z,1680023305.529 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-03-28T17:08:25.531Z,1680023305.531 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-03-28T17:08:25.629Z,1680023305.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-03-28T17:08:25.630Z,1680023305.630 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-03-28T17:08:25.841Z,1680023305.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-03-28T17:08:25.841Z,1680023305.841 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-03-28T17:08:26.088Z,1680023306.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-03-28T17:08:26.089Z,1680023306.089 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-03-28T17:08:26.196Z,1680023306.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-03-28T17:08:26.196Z,1680023306.196 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-03-28T17:08:26.869Z,1680023306.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-03-28T17:08:26.869Z,1680023306.869 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-03-28T17:08:27.415Z,1680023307.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-03-28T17:08:27.415Z,1680023307.415 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-03-28T17:08:27.884Z,1680023307.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-03-28T17:08:27.885Z,1680023307.885 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-03-28T17:08:28.270Z,1680023308.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-03-28T17:08:28.270Z,1680023308.270 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-03-28T17:08:28.585Z,1680023308.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-03-28T17:08:28.586Z,1680023308.586 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-03-28T17:08:28.942Z,1680023308.942 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-03-28T17:08:28.943Z,1680023308.943 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-03-28T17:08:29.243Z,1680023309.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-03-28T17:08:29.243Z,1680023309.243 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-03-28T17:08:29.381Z,1680023309.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-03-28T17:08:29.382Z,1680023309.382 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-03-28T17:08:29.465Z,1680023309.465 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-03-28T17:08:29.545Z,1680023309.545 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-03-28T17:08:29.730Z,1680023309.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-03-28T17:08:29.731Z,1680023309.731 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-03-28T17:08:29.949Z,1680023309.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-03-28T17:08:29.951Z,1680023309.951 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-03-28T17:08:29.952Z,1680023309.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-03-28T17:08:30.036Z,1680023310.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-03-28T17:08:30.301Z,1680023310.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-03-28T17:08:30.302Z,1680023310.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-03-28T17:08:30.700Z,1680023310.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-03-28T17:08:30.945Z,1680023310.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-03-28T17:08:31.359Z,1680023311.359 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-03-28T17:08:31.503Z,1680023311.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-03-28T17:08:31.680Z,1680023311.680 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-03-28T17:08:31.906Z,1680023311.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-03-28T17:08:32.139Z,1680023312.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-03-28T17:08:32.288Z,1680023312.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-03-28T17:08:32.433Z,1680023312.433 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2023-03-28T17:08:32.433Z,1680023312.433 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-03-28T17:08:32.456Z,1680023312.456 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-03-28T17:08:32.671Z,1680023312.671 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-03-28T17:08:32.672Z,1680023312.672 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-03-28T17:08:32.865Z,1680023312.865 [BuoyancyServo] Loaded
2023-03-28T17:08:32.865Z,1680023312.865 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-03-28T17:08:32.904Z,1680023312.904 [ElevatorServo] Loaded
2023-03-28T17:08:32.904Z,1680023312.904 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-03-28T17:08:32.932Z,1680023312.932 [MassServo] Loaded
2023-03-28T17:08:32.933Z,1680023312.933 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-03-28T17:08:32.960Z,1680023312.960 [RudderServo] Loaded
2023-03-28T17:08:32.961Z,1680023312.961 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-03-28T17:08:32.984Z,1680023312.984 [ThrusterHE] Loaded
2023-03-28T17:08:32.984Z,1680023312.984 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-03-28T17:08:32.985Z,1680023312.985 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-03-28T17:08:32.985Z,1680023312.985 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-03-28T17:08:33.195Z,1680023313.195 [SBIT](DEBUG): Construct Startup Built In Test.
2023-03-28T17:08:33.217Z,1680023313.217 [SBIT] Loaded
2023-03-28T17:08:33.218Z,1680023313.218 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-03-28T17:08:33.220Z,1680023313.220 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-03-28T17:08:33.249Z,1680023313.249 [IBIT] Loaded
2023-03-28T17:08:33.249Z,1680023313.249 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-03-28T17:08:33.254Z,1680023313.254 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-03-28T17:08:33.583Z,1680023313.583 [CBIT] Loaded
2023-03-28T17:08:33.584Z,1680023313.584 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-03-28T17:08:33.584Z,1680023313.584 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-03-28T17:08:33.585Z,1680023313.585 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-03-28T17:08:33.829Z,1680023313.829 [CTD_Seabird] Loaded
2023-03-28T17:08:33.829Z,1680023313.829 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-03-28T17:08:33.830Z,1680023313.830 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406644E0
2023-03-28T17:08:33.831Z,1680023313.831 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7895
2023-03-28T17:08:33.878Z,1680023313.878 [ESPComponent] Loaded
2023-03-28T17:08:33.878Z,1680023313.878 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-03-28T17:08:33.905Z,1680023313.905 [PAR_Licor] Loaded
2023-03-28T17:08:33.906Z,1680023313.906 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-03-28T17:08:33.952Z,1680023313.952 [WetLabsBB2FL] Loaded
2023-03-28T17:08:33.952Z,1680023313.952 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-03-28T17:08:33.960Z,1680023313.960 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406944E0
2023-03-28T17:08:33.960Z,1680023313.960 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7896
2023-03-28T17:08:33.961Z,1680023313.961 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-03-28T17:08:33.962Z,1680023313.962 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-03-28T17:08:34.034Z,1680023314.034 [DepthRateCalculator] Loaded
2023-03-28T17:08:34.035Z,1680023314.035 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-03-28T17:08:34.040Z,1680023314.040 [PitchRateCalculator] Loaded
2023-03-28T17:08:34.040Z,1680023314.040 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-03-28T17:08:34.058Z,1680023314.058 [SpeedCalculator] Loaded
2023-03-28T17:08:34.058Z,1680023314.058 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-03-28T17:08:34.081Z,1680023314.081 [VerticalHomogeneityIndexCalculator] Loaded
2023-03-28T17:08:34.081Z,1680023314.081 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-03-28T17:08:34.085Z,1680023314.085 [YawRateCalculator] Loaded
2023-03-28T17:08:34.086Z,1680023314.086 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-03-28T17:08:34.113Z,1680023314.113 [ElevatorOffsetCalculator] Loaded
2023-03-28T17:08:34.113Z,1680023314.113 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-03-28T17:08:34.113Z,1680023314.113 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-03-28T17:08:34.114Z,1680023314.114 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-03-28T17:08:34.260Z,1680023314.260 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-03-28T17:08:34.260Z,1680023314.260 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-03-28T17:08:34.302Z,1680023314.302 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-03-28T17:08:34.303Z,1680023314.303 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-03-28T17:08:34.470Z,1680023314.470 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-03-28T17:08:34.470Z,1680023314.470 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-03-28T17:08:34.492Z,1680023314.492 [NavChart] Loaded
2023-03-28T17:08:34.492Z,1680023314.492 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-03-28T17:08:34.497Z,1680023314.497 [UniversalFixResidualReporter] Loaded
2023-03-28T17:08:34.498Z,1680023314.498 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-03-28T17:08:34.498Z,1680023314.498 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-03-28T17:08:34.499Z,1680023314.499 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-03-28T17:08:35.116Z,1680023315.116 [AHRS_M2] Loaded
2023-03-28T17:08:35.117Z,1680023315.117 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-03-28T17:08:35.167Z,1680023315.167 [BackseatComponent] Loaded
2023-03-28T17:08:35.167Z,1680023315.167 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-03-28T17:08:35.168Z,1680023315.168 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A244E0
2023-03-28T17:08:35.168Z,1680023315.168 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7897
2023-03-28T17:08:35.171Z,1680023315.171 [LcmUniversalReporter] Loaded
2023-03-28T17:08:35.172Z,1680023315.172 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-03-28T17:08:36.567Z,1680023316.567 [BPC1] Loaded
2023-03-28T17:08:36.576Z,1680023316.576 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-03-28T17:08:36.682Z,1680023316.682 [DataOverHttps] Loaded
2023-03-28T17:08:36.683Z,1680023316.683 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-03-28T17:08:36.692Z,1680023316.692 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A544E0
2023-03-28T17:08:36.692Z,1680023316.692 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7898
2023-03-28T17:08:36.721Z,1680023316.721 [Depth_Keller] Loaded
2023-03-28T17:08:36.721Z,1680023316.721 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-03-28T17:08:36.726Z,1680023316.726 [DropWeight] Loaded
2023-03-28T17:08:36.726Z,1680023316.726 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-03-28T17:08:36.789Z,1680023316.789 [DVL_micro] Loaded
2023-03-28T17:08:36.789Z,1680023316.789 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-03-28T17:08:36.815Z,1680023316.815 [MultiRay] Loaded
2023-03-28T17:08:36.816Z,1680023316.816 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-03-28T17:08:36.899Z,1680023316.899 [NAL9602] Loaded
2023-03-28T17:08:36.908Z,1680023316.908 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-03-28T17:08:36.942Z,1680023316.942 [Onboard] Loaded
2023-03-28T17:08:36.943Z,1680023316.943 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-03-28T17:08:36.944Z,1680023316.944 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A844E0
2023-03-28T17:08:36.944Z,1680023316.944 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7899
2023-03-28T17:08:36.956Z,1680023316.956 [Power24vConverter] Loaded
2023-03-28T17:08:36.956Z,1680023316.956 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-03-28T17:08:36.977Z,1680023316.977 [Radio_Surface] Loaded
2023-03-28T17:08:36.978Z,1680023316.978 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-03-28T17:08:36.984Z,1680023316.984 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AB44E0
2023-03-28T17:08:36.984Z,1680023316.984 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7900
2023-03-28T17:08:37.118Z,1680023317.118 [DAT] Loaded
2023-03-28T17:08:37.118Z,1680023317.118 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-03-28T17:08:37.124Z,1680023317.124 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AE44E0
2023-03-28T17:08:37.124Z,1680023317.124 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7901
2023-03-28T17:08:37.125Z,1680023317.125 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-03-28T17:08:37.125Z,1680023317.125 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-03-28T17:08:37.226Z,1680023317.226 [StratificationFrontDetector](IMPORTANT): thresholdVTHI set to: 0.050012 degC
2023-03-28T17:08:37.227Z,1680023317.227 [StratificationFrontDetector](INFO): (re)initializing
2023-03-28T17:08:37.227Z,1680023317.227 [StratificationFrontDetector] Loaded
2023-03-28T17:08:37.227Z,1680023317.227 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2023-03-28T17:08:37.228Z,1680023317.228 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-03-28T17:08:37.228Z,1680023317.228 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-03-28T17:08:37.301Z,1680023317.301 [VerticalControl](DEBUG): Construct VerticalControl.
2023-03-28T17:08:37.375Z,1680023317.375 [VerticalControl] Loaded
2023-03-28T17:08:37.375Z,1680023317.375 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-03-28T17:08:37.378Z,1680023317.378 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-03-28T17:08:37.440Z,1680023317.440 [HorizontalControl] Loaded
2023-03-28T17:08:37.440Z,1680023317.440 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-03-28T17:08:37.442Z,1680023317.442 [SpeedControl](DEBUG): Construct SpeedControl.
2023-03-28T17:08:37.444Z,1680023317.444 [SpeedControl] Loaded
2023-03-28T17:08:37.444Z,1680023317.444 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-03-28T17:08:37.447Z,1680023317.447 [LoopControl](DEBUG): Construct LoopControl.
2023-03-28T17:08:37.448Z,1680023317.448 [LoopControl] Loaded
2023-03-28T17:08:37.448Z,1680023317.448 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-03-28T17:08:37.448Z,1680023317.448 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-03-28T17:08:37.449Z,1680023317.449 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-03-28T17:08:37.467Z,1680023317.467 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-03-28T17:08:37.468Z,1680023317.468 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-03-28T17:08:37.908Z,1680023317.908 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-03-28T17:08:37.922Z,1680023317.922 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-03-28T17:08:37.925Z,1680023317.925 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-03-28T17:08:37.944Z,1680023317.944 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-03-28T17:08:37.945Z,1680023317.945 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0
2023-03-28T17:08:37.946Z,1680023317.946 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7902
2023-03-28T17:08:37.950Z,1680023317.950 [Supervisor](INFO): Main Thread ID is 7804
2023-03-28T17:08:37.950Z,1680023317.950 [Supervisor](DEBUG): Running supervisor.
2023-03-28T17:08:37.951Z,1680023317.951 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7903
2023-03-28T17:08:37.951Z,1680023317.951 [CommandExec](INFO): Initializing the command executive.
2023-03-28T17:08:37.953Z,1680023317.953 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7904
2023-03-28T17:08:37.964Z,1680023317.964 [controlThread ThreadHandler](INFO): Handler Thread ID is 7905
2023-03-28T17:08:37.965Z,1680023317.965 [controlThread](DEBUG): Initializing ControlThread
2023-03-28T17:08:37.966Z,1680023317.966 [SBIT](INFO): Initialize SBIT Component.
2023-03-28T17:08:37.967Z,1680023317.967 [SBIT](IMPORTANT): git: 2023-03-22
2023-03-28T17:08:37.967Z,1680023317.967 [SBIT](INFO): git hash: 5c491c0cb5eb82ad7031eef1a23773c9f4f92690
2023-03-28T17:08:37.967Z,1680023317.967 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-03-28T17:08:37.969Z,1680023317.969 [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-28T17:08:37.970Z,1680023317.970 [SBIT](INFO): Beginning SBIT in 145.000000 seconds.
2023-03-28T17:08:37.970Z,1680023317.970 [IBIT](INFO): Initialize IBIT Component.
2023-03-28T17:08:37.971Z,1680023317.971 [CBIT](DEBUG): Initialize CBIT Component.
2023-03-28T17:08:37.972Z,1680023317.972 [logger ThreadHandler](INFO): Handler Thread ID is 7906
2023-03-28T17:08:37.988Z,1680023317.988 [CBIT](DEBUG): Initialized mux pins.
2023-03-28T17:08:37.988Z,1680023317.988 [CBIT](DEBUG): Initializing the watchdog timer.
2023-03-28T17:08:38.000Z,1680023318.000 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7907
2023-03-28T17:08:38.016Z,1680023318.016 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-03-28T17:08:38.016Z,1680023318.016 [CBIT](DEBUG): Initializing heartbeat.
2023-03-28T17:08:38.017Z,1680023318.017 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-03-28T17:08:38.019Z,1680023318.019 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7909
2023-03-28T17:08:38.024Z,1680023318.024 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7911
2023-03-28T17:08:38.025Z,1680023318.025 [WetLabsBB2FL](INFO): Powering up
2023-03-28T17:08:38.027Z,1680023318.027 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7912
2023-03-28T17:08:38.036Z,1680023318.036 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-03-28T17:08:38.048Z,1680023318.048 [Onboard ThreadHandler](INFO): Handler Thread ID is 7913
2023-03-28T17:08:38.094Z,1680023318.094 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7914
2023-03-28T17:08:38.096Z,1680023318.096 [CBIT](DEBUG): Deactivating GF circuits.
2023-03-28T17:08:38.096Z,1680023318.096 [CBIT](DEBUG): Deactivating emergency mode.
2023-03-28T17:08:38.097Z,1680023318.097 [DAT ThreadHandler](INFO): Handler Thread ID is 7915
2023-03-28T17:08:38.097Z,1680023318.097 [DAT](INFO): Powering up
2023-03-28T17:08:38.098Z,1680023318.098 [DAT](DEBUG): Initializing DAT.
2023-03-28T17:08:38.120Z,1680023318.120 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7917
2023-03-28T17:08:38.123Z,1680023318.123 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-03-28T17:08:38.123Z,1680023318.123 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-03-28T17:08:38.123Z,1680023318.123 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-03-28T17:08:38.123Z,1680023318.123 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-03-28T17:08:38.132Z,1680023318.132 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-03-28T17:08:38.132Z,1680023318.132 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-03-28T17:08:38.132Z,1680023318.132 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-03-28T17:08:38.132Z,1680023318.132 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-03-28T17:08:38.133Z,1680023318.133 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2023-03-28T17:08:38.133Z,1680023318.133 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2023-03-28T17:08:38.136Z,1680023318.136 [CBIT](DEBUG): Backplane powered.
2023-03-28T17:08:38.140Z,1680023318.140 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-03-28T17:08:38.141Z,1680023318.141 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-03-28T17:08:38.141Z,1680023318.141 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-03-28T17:08:38.141Z,1680023318.141 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-03-28T17:08:38.142Z,1680023318.142 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-03-28T17:08:38.142Z,1680023318.142 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-03-28T17:08:38.156Z,1680023318.156 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-03-28T17:08:38.156Z,1680023318.156 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-03-28T17:08:38.171Z,1680023318.171 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-03-28T17:08:38.189Z,1680023318.189 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-03-28T17:08:38.189Z,1680023318.189 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-03-28T17:08:38.190Z,1680023318.190 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-03-28T17:08:38.191Z,1680023318.191 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-03-28T17:08:38.280Z,1680023318.280 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-28T17:08:38.327Z,1680023318.327 [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-28T17:08:38.328Z,1680023318.328 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-03-28T17:08:38.458Z,1680023318.458 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-03-28T17:08:38.492Z,1680023318.492 [Default:A.Wait](DEBUG): Construct Wait.
2023-03-28T17:08:38.495Z,1680023318.495 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-28T17:08:38.496Z,1680023318.496 [Radio_Surface](INFO): Powering up
2023-03-28T17:08:38.543Z,1680023318.543 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-03-28T17:08:38.545Z,1680023318.545 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-03-28T17:08:38.579Z,1680023318.579 [Default:E.Execute](DEBUG): Construct Execute.
2023-03-28T17:08:38.621Z,1680023318.621 [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-28T17:08:38.626Z,1680023318.626 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,MultiRay,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-03-28T17:08:38.699Z,1680023318.699 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-03-28T17:08:38.813Z,1680023318.813 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2023-03-28T17:08:38.833Z,1680023318.833 [MultiRay](INFO): Powering up MultiRay Lights
2023-03-28T17:08:38.876Z,1680023318.876 [Power24vConverter](INFO): Powering up.
2023-03-28T17:08:38.884Z,1680023318.884 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-03-28T17:08:38.910Z,1680023318.910 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-03-28T17:08:38.925Z,1680023318.925 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-03-28T17:08:38.926Z,1680023318.926 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-03-28T17:08:38.932Z,1680023318.932 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-03-28T17:08:38.933Z,1680023318.933 [MassServo](DEBUG): Initializing EZServoServo.
2023-03-28T17:08:38.948Z,1680023318.948 [MassServo](DEBUG): Initializing MassServo.
2023-03-28T17:08:38.949Z,1680023318.949 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-28T17:08:38.956Z,1680023318.956 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-28T17:08:38.957Z,1680023318.957 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-03-28T17:08:38.972Z,1680023318.972 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-03-28T17:08:39.286Z,1680023319.286 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-28T17:08:39.286Z,1680023319.286 [DropWeight] Hardware Fault, FailCount= 1
2023-03-28T17:08:39.286Z,1680023319.286 [DropWeight](ERROR): Hardware Fault
2023-03-28T17:08:39.386Z,1680023319.386 [CommandExec](FAULT): Scheduling is paused
2023-03-28T17:08:39.392Z,1680023319.392 [CBIT](INFO): Critical error at 20230328T170839
2023-03-28T17:08:39.392Z,1680023319.392 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-03-28T17:08:39.395Z,1680023319.395 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-28T17:08:39.395Z,1680023319.395 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-28T17:08:39.848Z,1680023319.848 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-03-28T17:08:39.848Z,1680023319.848 [RudderServo](FAULT): Rudder failed to initialize
2023-03-28T17:08:39.848Z,1680023319.848 [RudderServo] Communications Fault, FailCount= 1
2023-03-28T17:08:39.848Z,1680023319.848 [RudderServo](ERROR): Communications Fault
2023-03-28T17:08:39.850Z,1680023319.850 [CBIT](INFO): Critical error at 20230328T170839
2023-03-28T17:08:39.852Z,1680023319.852 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-03-28T17:08:40.184Z,1680023320.184 [WetLabsBB2FL](INFO): Powering down
2023-03-28T17:08:40.319Z,1680023320.319 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-28T17:08:40.319Z,1680023320.319 [RudderServo](INFO): Powering down
2023-03-28T17:08:40.857Z,1680023320.857 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-28T17:08:40.977Z,1680023320.977 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-28T17:08:40.983Z,1680023320.983 [CBIT](INFO): Clearing failed state for component RudderServo
2023-03-28T17:08:40.983Z,1680023320.983 [RudderServo] No Fault, FailCount= 1
2023-03-28T17:08:44.206Z,1680023324.206 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-03-28T17:08:55.742Z,1680023335.742 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008846
2023-03-28T17:09:07.103Z,1680023347.103 [NAL9602](INFO): Powering up NAL9602
2023-03-28T17:09:18.018Z,1680023358.018 [NAL9602](INFO): NAL9602 initialized
2023-03-28T17:09:34.648Z,1680023374.648 [DVL_micro](ERROR): DVL uart error: serial timeout
2023-03-28T17:09:34.648Z,1680023374.648 [DVL_micro] Communications Fault, FailCount= 1
2023-03-28T17:09:34.648Z,1680023374.648 [DVL_micro](ERROR): Communications Fault
2023-03-28T17:09:34.649Z,1680023374.649 [DVL_micro](ERROR): Failed to parse:
2023-03-28T17:09:34.726Z,1680023374.726 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-03-28T17:09:35.041Z,1680023375.041 [DVL_micro](INFO): Powering down
2023-03-28T17:09:35.821Z,1680023375.821 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-03-28T17:09:35.821Z,1680023375.821 [DVL_micro] No Fault, FailCount= 1
2023-03-28T17:09:38.116Z,1680023378.116 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2023-03-28T17:09:38.116Z,1680023378.116 [DAT] Communications Fault, FailCount= 1
2023-03-28T17:09:38.116Z,1680023378.116 [DAT](ERROR): Communications Fault
2023-03-28T17:09:38.269Z,1680023378.269 [CBIT](ERROR): Communications Fault in component: DAT
2023-03-28T17:09:38.520Z,1680023378.520 [DAT](INFO): Powering down
2023-03-28T17:09:39.492Z,1680023379.492 [CBIT](INFO): Clearing failed state for component DAT
2023-03-28T17:09:39.492Z,1680023379.492 [DAT] No Fault, FailCount= 1
2023-03-28T17:09:41.572Z,1680023381.572 [DAT](INFO): Powering up
2023-03-28T17:09:41.572Z,1680023381.572 [DAT](DEBUG): Initializing DAT.
2023-03-28T17:09:53.170Z,1680023393.170 [DAT](INFO): DAT read: 

2023-03-28T17:09:53.172Z,1680023393.172 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-03-28T17:09:54.933Z,1680023394.933 [DAT](INFO): DAT read: MF Frequency Band

2023-03-28T17:09:54.934Z,1680023394.934 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-03-28T17:09:54.935Z,1680023394.935 [DAT](INFO): DAT read: Mar 28 2023 17:09:49 

2023-03-28T17:09:55.941Z,1680023395.941 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-03-28T17:09:55.942Z,1680023395.942 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2023-03-28T17:09:55.942Z,1680023395.942 [DAT](INFO): commRate: 1200
2023-03-28T17:09:58.008Z,1680023398.008 [DAT](INFO): entering command mode
2023-03-28T17:09:58.208Z,1680023398.208 [DAT](INFO): DAT read: 

2023-03-28T17:09:58.209Z,1680023398.209 [DAT](INFO): DAT read: user:1>

2023-03-28T17:09:58.209Z,1680023398.209 [DAT](INFO): setting verbose to 3
2023-03-28T17:09:58.460Z,1680023398.460 [DAT](INFO): DAT read: user:1>

2023-03-28T17:09:58.462Z,1680023398.462 [DAT](INFO): DAT read: Verbose         | 3           

2023-03-28T17:09:58.462Z,1680023398.462 [DAT](INFO): set verbose to 3
2023-03-28T17:09:58.462Z,1680023398.462 [DAT](INFO): setting DatVerbose to 27440
2023-03-28T17:09:58.713Z,1680023398.713 [DAT](INFO): DAT read: user:2>

2023-03-28T17:09:58.714Z,1680023398.714 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-03-28T17:09:58.714Z,1680023398.714 [DAT](INFO): set DatVerbose to 27440
2023-03-28T17:09:58.714Z,1680023398.714 [DAT](INFO): setting transmit power to 8
2023-03-28T17:09:58.964Z,1680023398.964 [DAT](INFO): DAT read: user:3>

2023-03-28T17:09:58.965Z,1680023398.965 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-03-28T17:09:58.966Z,1680023398.966 [DAT](INFO): set transmit power to 8
2023-03-28T17:09:58.966Z,1680023398.966 [DAT](INFO): setting local address to 4
2023-03-28T17:09:59.217Z,1680023399.217 [DAT](INFO): DAT read: user:4>

2023-03-28T17:09:59.218Z,1680023399.218 [DAT](INFO): DAT read: LocalAddr       | 4           

2023-03-28T17:09:59.218Z,1680023399.218 [DAT](INFO): set local address to 4
2023-03-28T17:09:59.219Z,1680023399.219 [DAT](INFO): Setting time to: 17:9:59 And date to:3/28/2023
2023-03-28T17:09:59.468Z,1680023399.468 [DAT](INFO): DAT read: user:5>

2023-03-28T17:09:59.469Z,1680023399.469 [DAT](INFO): DAT read: Tue Mar 28, 2023  17:09:59  

2023-03-28T17:09:59.470Z,1680023399.470 [DAT](INFO): Local DAT time set to Tue Mar 28, 2023  17:09:59  

2023-03-28T17:10:23.050Z,1680023423.050 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:10:23.051Z,1680023423.051 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:10:26.535Z,1680023426.535 [CommandExec](IMPORTANT): got command report mod NAL9602.sigQuality
2023-03-28T17:10:26.741Z,1680023426.741 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:10:27.545Z,1680023427.545 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:10:40.032Z,1680023440.032 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:10:40.032Z,1680023440.032 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:10:44.548Z,1680023444.548 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:10:59.455Z,1680023459.455 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:11:03.464Z,1680023463.464 [SBIT](IMPORTANT): Beginning Startup BIT
2023-03-28T17:11:03.468Z,1680023463.468 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-28T17:11:14.449Z,1680023474.449 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007594
CHAN A1 (24V): 0.003008
CHAN A2 (12V): -0.004890
CHAN A3 (5V): -0.002795
CHAN B0 (3.3V): -0.000967
CHAN B1 (3.15aV): -0.000891
CHAN B2 (3.15bV): -0.001767
CHAN B3 (GND): 0.000359
OPEN: 0.006445
Full Scale: +/- 1 mA
2023-03-28T17:11:17.000Z,1680023478.000 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:11:18.000Z,1680023478.000 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:11:20.047Z,1680023480.047 [DVL_micro](ERROR): only read 3 of 4 data items
2023-03-28T17:11:20.047Z,1680023480.047 [DVL_micro](ERROR): Failed to parse:
:BI,-0851,-01350,+00BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2023-03-28T17:11:22.590Z,1680023482.590 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:11:33.838Z,1680023493.838 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:11:38.247Z,1680023498.247 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-03-28T17:11:38.247Z,1680023498.247 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-28T17:11:38.281Z,1680023498.281 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-28T17:11:38.639Z,1680023498.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-28T17:11:38.639Z,1680023498.639 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-03-28T17:11:57.610Z,1680023517.610 [SBIT](IMPORTANT): SBIT PASSED
2023-03-28T17:11:57.611Z,1680023517.611 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-03-28T17:11:57.612Z,1680023517.612 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour;

2023-03-28T17:11:57.612Z,1680023517.612 [SBIT](IMPORTANT): Depth_Keller.offset=-0.8 decibar;

2023-03-28T17:11:57.612Z,1680023517.612 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=4 minute;

2023-03-28T17:11:57.612Z,1680023517.612 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-03-28T17:11:57.612Z,1680023517.612 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2023-03-28T17:11:57.613Z,1680023517.613 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius;

2023-03-28T17:11:57.613Z,1680023517.613 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff470nm;

2023-03-28T17:11:57.613Z,1680023517.613 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff650nm;

2023-03-28T17:11:57.613Z,1680023517.613 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2023-03-28T17:11:57.613Z,1680023517.613 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-03-28T17:11:57.613Z,1680023517.613 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-03-28T17:11:57.613Z,1680023517.613 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=155.681673 cubic_centimeter;

2023-03-28T17:11:57.613Z,1680023517.613 [SBIT](IMPORTANT): VerticalControl.massDefault=31.311287 millimeter;

2023-03-28T17:11:57.614Z,1680023517.614 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=15 meter;

2023-03-28T17:11:57.614Z,1680023517.614 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=20 meter;

2023-03-28T17:11:57.614Z,1680023517.614 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2023-03-28T17:11:58.007Z,1680023518.007 [MissionManager](IMPORTANT): Started mission Startup
2023-03-28T17:11:58.007Z,1680023518.007 [Startup] Running Loop=1
2023-03-28T17:11:58.007Z,1680023518.007 [Startup](DEBUG): Aggregate::initialize Startup
2023-03-28T17:11:58.007Z,1680023518.007 [Startup:A.GoToSurface] Running Loop=1
2023-03-28T17:11:58.007Z,1680023518.007 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-28T17:11:58.008Z,1680023518.008 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-28T17:11:58.009Z,1680023518.009 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-28T17:11:58.009Z,1680023518.009 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-28T17:11:58.009Z,1680023518.009 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-28T17:11:58.010Z,1680023518.010 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-28T17:11:58.011Z,1680023518.011 [Startup:StartupSatComms] Running Loop=1
2023-03-28T17:11:58.011Z,1680023518.011 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-03-28T17:11:58.011Z,1680023518.011 [Startup:StartupSatComms:A] Running Loop=1
2023-03-28T17:11:58.409Z,1680023518.409 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-03-28T17:12:00.876Z,1680023520.876 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:12:09.349Z,1680023529.349 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:12:22.268Z,1680023542.268 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:12:32.784Z,1680023552.784 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:12:35.981Z,1680023555.981 [DVL_micro](ERROR): Failed to parse:
99.99,+9999.99,+9999.99
2023-03-28T17:12:36.821Z,1680023556.821 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:12:41.300Z,1680023561.300 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:12:57.383Z,1680023577.383 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:12:57.383Z,1680023577.383 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:12:58.194Z,1680023578.194 [Startup:StartupSatComms:A](INFO): Timed out from 2023-03-28T17:11:58.0Z
2023-03-28T17:12:58.194Z,1680023578.194 [Startup:StartupSatComms:A] Stopped
2023-03-28T17:12:58.195Z,1680023578.195 [Startup:StartupSatComms:B] Running Loop=1
2023-03-28T17:12:58.618Z,1680023578.618 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-28T17:13:01.927Z,1680023581.927 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:13:05.145Z,1680023585.145 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:13:05.819Z,1680023585.819 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230327T195724/Courier0079.lzma
2023-03-28T17:13:06.822Z,1680023586.822 [DataOverHttps](INFO): Moved sent file to Logs/20230327T195724/Courier0079.lzma.bak
2023-03-28T17:13:06.822Z,1680023586.822 [DataOverHttps](INFO): SBD MOMSN=17728194
2023-03-28T17:13:13.632Z,1680023593.632 [Reporter](INFO): NAL9602.sigQuality 4 count
2023-03-28T17:13:18.431Z,1680023598.431 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:13:18.431Z,1680023598.431 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:13:22.621Z,1680023602.621 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20230328T170825/Courier0000.lzma
2023-03-28T17:13:22.913Z,1680023602.913 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:13:23.622Z,1680023603.622 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Courier0000.lzma.bak
2023-03-28T17:13:23.622Z,1680023603.622 [DataOverHttps](INFO): SBD MOMSN=17728196
2023-03-28T17:13:26.923Z,1680023606.923 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:13:26.923Z,1680023606.923 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:13:31.404Z,1680023611.404 [Reporter](INFO): NAL9602.sigQuality 4 count
2023-03-28T17:13:35.455Z,1680023615.455 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:13:35.455Z,1680023615.455 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:13:40.017Z,1680023620.017 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T17:13:42.063Z,1680023622.063 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20230327T195724/Express0080.lzma
2023-03-28T17:13:43.066Z,1680023623.066 [DataOverHttps](INFO): Moved sent file to Logs/20230327T195724/Express0080.lzma.bak
2023-03-28T17:13:43.067Z,1680023623.067 [DataOverHttps](INFO): SBD MOMSN=17728201
2023-03-28T17:13:43.137Z,1680023623.137 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:13:43.137Z,1680023623.137 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:13:51.612Z,1680023631.612 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:13:51.612Z,1680023631.612 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:13:56.114Z,1680023636.114 [Reporter](INFO): NAL9602.sigQuality 4 count
2023-03-28T17:13:58.497Z,1680023638.497 [Startup:StartupSatComms:B](INFO): Timed out from 2023-03-28T17:12:58.2Z
2023-03-28T17:13:58.497Z,1680023638.497 [Startup:StartupSatComms:B] Stopped
2023-03-28T17:13:58.497Z,1680023638.497 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-03-28T17:13:58.497Z,1680023638.497 [Startup:StartupSatComms] Stopped
2023-03-28T17:13:58.497Z,1680023638.497 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-03-28T17:13:58.498Z,1680023638.498 [Startup](INFO): Completed Startup
2023-03-28T17:13:58.498Z,1680023638.498 [MissionManager](INFO): Startup is completed.
2023-03-28T17:13:58.498Z,1680023638.498 [MissionManager](INFO): Uninitializing Mission Startup
2023-03-28T17:13:58.498Z,1680023638.498 [Startup] Stopped
2023-03-28T17:13:58.498Z,1680023638.498 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-03-28T17:13:58.498Z,1680023638.498 [Startup:A.GoToSurface] Stopped
2023-03-28T17:13:58.498Z,1680023638.498 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-28T17:13:58.774Z,1680023638.774 [DataOverHttps](INFO): Sending 1176 bytes from file Logs/20230328T170825/Express0001.lzma
2023-03-28T17:13:58.897Z,1680023638.897 [MissionManager](IMPORTANT): Started mission Default
2023-03-28T17:13:58.897Z,1680023638.897 [Default] Running Loop=1
2023-03-28T17:13:58.897Z,1680023638.897 [Default](DEBUG): Aggregate::initialize Default
2023-03-28T17:13:58.898Z,1680023638.898 [Default:B.GoToSurface] Running Loop=1
2023-03-28T17:13:58.898Z,1680023638.898 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-28T17:13:58.898Z,1680023638.898 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-28T17:13:58.898Z,1680023638.898 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-28T17:13:58.898Z,1680023638.898 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-28T17:13:58.899Z,1680023638.899 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-28T17:13:58.899Z,1680023638.899 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-28T17:13:58.899Z,1680023638.899 [Default:A.Wait] Running Loop=1
2023-03-28T17:13:58.899Z,1680023638.899 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:13:59.774Z,1680023639.774 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Express0001.lzma.bak
2023-03-28T17:13:59.774Z,1680023639.774 [DataOverHttps](INFO): SBD MOMSN=17728205
2023-03-28T17:14:00.519Z,1680023640.519 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:14:00.519Z,1680023640.519 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:14:04.650Z,1680023644.650 [Reporter](INFO): NAL9602.sigQuality 5 count
2023-03-28T17:14:08.987Z,1680023648.987 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:14:08.987Z,1680023648.987 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:14:12.233Z,1680023652.233 [Default:A.Wait](INFO): Done Waiting.
2023-03-28T17:14:12.233Z,1680023652.233 [Default:A.Wait] Stopped
2023-03-28T17:14:12.233Z,1680023652.233 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:14:12.695Z,1680023652.695 [Default:CheckIn] Running Loop=1
2023-03-28T17:14:12.695Z,1680023652.695 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:14:12.695Z,1680023652.695 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:14:13.059Z,1680023653.059 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-03-28T17:14:17.881Z,1680023657.881 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:14:17.881Z,1680023657.881 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:14:21.114Z,1680023661.114 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-03-28T17:14:22.325Z,1680023662.325 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171421.00,A,3648.17251,N,12147.28388,W,0.058,324.60,280323,,,D*7E
2023-03-28T17:14:22.364Z,1680023662.364 [NAL9602](INFO): GPS fix at 20230328T171421: (36.802875, -121.788065)
2023-03-28T17:14:22.383Z,1680023662.383 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:14:22.383Z,1680023662.383 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:14:22.756Z,1680023662.756 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-28T17:14:30.115Z,1680023670.115 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230328T170825/Courier0004.lzma
2023-03-28T17:14:31.118Z,1680023671.118 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Courier0004.lzma.bak
2023-03-28T17:14:31.118Z,1680023671.118 [DataOverHttps](INFO): SBD MOMSN=17728237
2023-03-28T17:14:52.436Z,1680023692.436 [DataOverHttps](INFO): Sending 322 bytes from file Logs/20230328T170825/Express0005.lzma
2023-03-28T17:14:53.438Z,1680023693.438 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Express0005.lzma.bak
2023-03-28T17:14:53.439Z,1680023693.439 [DataOverHttps](INFO): SBD MOMSN=17728240
2023-03-28T17:14:54.377Z,1680023694.377 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T17:14:54.412Z,1680023694.412 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:14:54.735Z,1680023694.735 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:14:54.735Z,1680023694.735 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:14:54.735Z,1680023694.735 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:15:51.290Z,1680023751.290 [DVL_micro](ERROR): Failed to parse::SA,-03.26,+00.81,017.4
2023-03-28T17:16:18.346Z,1680023778.346 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-03-28T17:16:18.365Z,1680023778.365 [BPC1](INFO): Received data from all battery sticks.
2023-03-28T17:19:55.336Z,1680023995.336 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T17:19:55.336Z,1680023995.336 [Default:CheckIn:C.Wait] Stopped
2023-03-28T17:19:55.336Z,1680023995.336 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:19:55.336Z,1680023995.336 [Default:CheckIn:D] Running Loop=1
2023-03-28T17:19:55.750Z,1680023995.750 [Default:CheckIn:D] Stopped
2023-03-28T17:19:55.750Z,1680023995.750 [Default:CheckIn:E] Running Loop=1
2023-03-28T17:19:56.146Z,1680023996.146 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.947540 min 
2023-03-28T17:19:56.146Z,1680023996.146 [Default:CheckIn:E] Stopped
2023-03-28T17:19:56.146Z,1680023996.146 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T17:19:56.146Z,1680023996.146 [Default:CheckIn] Stopped
2023-03-28T17:19:56.146Z,1680023996.146 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T17:19:56.147Z,1680023996.147 [Default:CheckIn](INFO): Running loop #2
2023-03-28T17:19:56.147Z,1680023996.147 [Default:CheckIn] Running Loop=2
2023-03-28T17:19:56.147Z,1680023996.147 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:19:56.147Z,1680023996.147 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:19:58.159Z,1680023998.159 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171957.00,A,3648.17527,N,12147.28389,W,0.078,324.60,280323,,,D*77
2023-03-28T17:19:58.161Z,1680023998.161 [NAL9602](INFO): GPS fix at 20230328T171957: (36.802921, -121.788065)
2023-03-28T17:19:58.171Z,1680023998.171 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:19:58.171Z,1680023998.171 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:20:06.035Z,1680024006.035 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230328T170825/Courier0007.lzma
2023-03-28T17:20:07.038Z,1680024007.038 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Courier0007.lzma.bak
2023-03-28T17:20:07.038Z,1680024007.038 [DataOverHttps](INFO): SBD MOMSN=17728247
2023-03-28T17:20:22.835Z,1680024022.835 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20230328T170825/Express0008.lzma
2023-03-28T17:20:23.838Z,1680024023.838 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Express0008.lzma.bak
2023-03-28T17:20:23.838Z,1680024023.838 [DataOverHttps](INFO): SBD MOMSN=17728250
2023-03-28T17:20:25.235Z,1680024025.235 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:20:25.235Z,1680024025.235 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:20:25.235Z,1680024025.235 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:20:28.872Z,1680024028.872 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-03-28T17:20:28.956Z,1680024028.956 [NAL9602](ERROR): received: 
+SBDI: 2, 47521, 2, 0, 0, 0

OK
2023-03-28T17:20:34.577Z,1680024034.577 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:20:42.647Z,1680024042.647 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:20:56.740Z,1680024056.740 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:20:56.741Z,1680024056.741 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:21:00.845Z,1680024060.845 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:21:27.095Z,1680024087.095 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:21:39.559Z,1680024099.559 [NAL9602](INFO): SBD MO Status=2, MOMSN=47521, MT Status=2, MTMSN=0
2023-03-28T17:21:39.559Z,1680024099.559 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:21:49.255Z,1680024109.255 [NAL9602](INFO): SBD MO Status=0, MOMSN=47521, MT Status=0, MTMSN=0
2023-03-28T17:21:49.255Z,1680024109.255 [NAL9602](INFO): No messages in MT queue
2023-03-28T17:22:19.955Z,1680024139.955 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T17:22:20.002Z,1680024140.002 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:23:39.672Z,1680024219.672 [CBIT](INFO): Clearing failed state for component DropWeight
2023-03-28T17:23:39.672Z,1680024219.672 [DropWeight] No Fault, FailCount= 1
2023-03-28T17:25:25.965Z,1680024325.965 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T17:25:25.965Z,1680024325.965 [Default:CheckIn:C.Wait] Stopped
2023-03-28T17:25:25.965Z,1680024325.965 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:25:25.965Z,1680024325.965 [Default:CheckIn:D] Running Loop=1
2023-03-28T17:25:26.357Z,1680024326.357 [Default:CheckIn:D] Stopped
2023-03-28T17:25:26.357Z,1680024326.357 [Default:CheckIn:E] Running Loop=1
2023-03-28T17:25:26.774Z,1680024326.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.457666 min 
2023-03-28T17:25:26.774Z,1680024326.774 [Default:CheckIn:E] Stopped
2023-03-28T17:25:26.775Z,1680024326.775 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T17:25:26.775Z,1680024326.775 [Default:CheckIn] Stopped
2023-03-28T17:25:26.775Z,1680024326.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T17:25:26.775Z,1680024326.775 [Default:CheckIn](INFO): Running loop #3
2023-03-28T17:25:26.775Z,1680024326.775 [Default:CheckIn] Running Loop=3
2023-03-28T17:25:26.775Z,1680024326.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:25:26.775Z,1680024326.775 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:25:28.778Z,1680024328.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172528.00,A,3648.17576,N,12147.28666,W,0.019,324.60,280323,,,D*77
2023-03-28T17:25:28.781Z,1680024328.781 [NAL9602](INFO): GPS fix at 20230328T172528: (36.802929, -121.788111)
2023-03-28T17:25:28.791Z,1680024328.791 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:25:28.791Z,1680024328.791 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:25:37.876Z,1680024337.876 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:25:39.384Z,1680024339.384 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230328T170825/Courier0010.lzma
2023-03-28T17:25:40.386Z,1680024340.386 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Courier0010.lzma.bak
2023-03-28T17:25:40.386Z,1680024340.386 [DataOverHttps](INFO): SBD MOMSN=17728620
2023-03-28T17:25:47.133Z,1680024347.133 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:25:57.087Z,1680024357.087 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230328T170825/Express0011.lzma
2023-03-28T17:25:58.090Z,1680024358.090 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Express0011.lzma.bak
2023-03-28T17:25:58.090Z,1680024358.090 [DataOverHttps](INFO): SBD MOMSN=17728623
2023-03-28T17:25:59.596Z,1680024359.596 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:25:59.596Z,1680024359.596 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:25:59.597Z,1680024359.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:26:29.903Z,1680024389.903 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:26:34.097Z,1680024394.097 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:26:46.910Z,1680024406.910 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:26:55.815Z,1680024415.815 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:27:04.308Z,1680024424.308 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:27:21.642Z,1680024441.642 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:27:31.706Z,1680024451.706 [NAL9602](INFO): SBD MO Status=2, MOMSN=47522, MT Status=2, MTMSN=0
2023-03-28T17:27:31.706Z,1680024451.706 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:27:35.949Z,1680024455.949 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:27:48.754Z,1680024468.754 [NAL9602](INFO): SBD MO Status=2, MOMSN=47522, MT Status=2, MTMSN=0
2023-03-28T17:27:48.754Z,1680024468.754 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:27:53.238Z,1680024473.238 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:28:22.308Z,1680024502.308 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:28:39.819Z,1680024519.819 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:28:44.260Z,1680024524.260 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:28:57.134Z,1680024537.134 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:29:39.611Z,1680024579.611 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:29:59.362Z,1680024599.362 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:30:13.898Z,1680024613.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=47522, MT Status=2, MTMSN=0
2023-03-28T17:30:13.898Z,1680024613.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:30:18.375Z,1680024618.375 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:30:21.182Z,1680024621.182 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:30:28.815Z,1680024628.815 [NAL9602](INFO): SBD MO Status=0, MOMSN=47522, MT Status=0, MTMSN=0
2023-03-28T17:30:28.816Z,1680024628.816 [NAL9602](INFO): No messages in MT queue
2023-03-28T17:30:59.563Z,1680024659.563 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T17:30:59.590Z,1680024659.590 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:31:00.375Z,1680024660.375 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T17:31:00.375Z,1680024660.375 [Default:CheckIn:C.Wait] Stopped
2023-03-28T17:31:00.375Z,1680024660.375 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:31:00.375Z,1680024660.375 [Default:CheckIn:D] Running Loop=1
2023-03-28T17:31:00.775Z,1680024660.775 [Default:CheckIn:D] Stopped
2023-03-28T17:31:00.775Z,1680024660.775 [Default:CheckIn:E] Running Loop=1
2023-03-28T17:31:01.203Z,1680024661.203 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.031291 min 
2023-03-28T17:31:01.203Z,1680024661.203 [Default:CheckIn:E] Stopped
2023-03-28T17:31:01.204Z,1680024661.204 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T17:31:01.204Z,1680024661.204 [Default:CheckIn] Stopped
2023-03-28T17:31:01.204Z,1680024661.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T17:31:01.204Z,1680024661.204 [Default:CheckIn](INFO): Running loop #4
2023-03-28T17:31:01.204Z,1680024661.204 [Default:CheckIn] Running Loop=4
2023-03-28T17:31:01.204Z,1680024661.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:31:01.205Z,1680024661.205 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:31:03.201Z,1680024663.201 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173102.00,A,3648.17489,N,12147.28632,W,0.019,324.60,280323,,,D*7A
2023-03-28T17:31:03.203Z,1680024663.203 [NAL9602](INFO): GPS fix at 20230328T173102: (36.802915, -121.788105)
2023-03-28T17:31:03.235Z,1680024663.235 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:31:03.235Z,1680024663.235 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:31:10.831Z,1680024670.831 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T170825/Courier0013.lzma
2023-03-28T17:31:11.834Z,1680024671.834 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Courier0013.lzma.bak
2023-03-28T17:31:11.834Z,1680024671.834 [DataOverHttps](INFO): SBD MOMSN=17728628
2023-03-28T17:31:12.962Z,1680024672.962 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:31:27.031Z,1680024687.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=47523, MT Status=2, MTMSN=0
2023-03-28T17:31:27.031Z,1680024687.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:31:27.759Z,1680024687.759 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230328T170825/Express0014.lzma
2023-03-28T17:31:28.764Z,1680024688.764 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Express0014.lzma.bak
2023-03-28T17:31:28.764Z,1680024688.764 [DataOverHttps](INFO): SBD MOMSN=17728631
2023-03-28T17:31:30.276Z,1680024690.276 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:31:30.276Z,1680024690.276 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:31:30.276Z,1680024690.276 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:31:31.587Z,1680024691.587 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:31:35.271Z,1680024695.271 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:31:46.087Z,1680024706.087 [NAL9602](INFO): SBD MO Status=2, MOMSN=47523, MT Status=2, MTMSN=0
2023-03-28T17:31:46.087Z,1680024706.087 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:31:50.584Z,1680024710.584 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:31:54.577Z,1680024714.577 [DVL_micro](ERROR): only read 3 of 4 data items
2023-03-28T17:31:54.577Z,1680024714.577 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2023-03-28T17:32:17.255Z,1680024737.255 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:32:26.142Z,1680024746.142 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:32:45.530Z,1680024765.530 [NAL9602](INFO): SBD MO Status=0, MOMSN=47523, MT Status=0, MTMSN=0
2023-03-28T17:32:45.530Z,1680024765.530 [NAL9602](INFO): No messages in MT queue
2023-03-28T17:33:16.228Z,1680024796.228 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T17:33:16.285Z,1680024796.285 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:35:04.614Z,1680024904.614 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-03-28T17:35:04.615Z,1680024904.615 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+0.0,14
2023-03-28T17:35:57.438Z,1680024957.438 [CommandLine](INFO): End of History
2023-03-28T17:35:58.156Z,1680024958.156 [CommandLine](INFO): End of History
2023-03-28T17:35:58.776Z,1680024958.776 [CommandLine](INFO): End of History
2023-03-28T17:35:59.458Z,1680024959.458 [CommandLine](INFO): End of History
2023-03-28T17:36:00.168Z,1680024960.168 [CommandLine](INFO): End of History
2023-03-28T17:36:00.861Z,1680024960.861 [CommandLine](INFO): End of History
2023-03-28T17:36:01.488Z,1680024961.488 [CommandLine](INFO): End of History
2023-03-28T17:36:02.169Z,1680024962.169 [CommandLine](INFO): End of History
2023-03-28T17:36:02.799Z,1680024962.799 [CommandLine](INFO): End of History
2023-03-28T17:36:03.515Z,1680024963.515 [CommandLine](INFO): End of History
2023-03-28T17:36:04.252Z,1680024964.252 [CommandLine](INFO): End of History
2023-03-28T17:36:04.962Z,1680024964.962 [CommandLine](INFO): End of History
2023-03-28T17:36:05.597Z,1680024965.597 [CommandLine](INFO): End of History
2023-03-28T17:36:06.313Z,1680024966.313 [CommandLine](INFO): End of History
2023-03-28T17:36:07.386Z,1680024967.386 [CommandLine](INFO): End of History
2023-03-28T17:36:08.778Z,1680024968.778 [CommandLine](INFO): End of History
2023-03-28T17:36:10.242Z,1680024970.242 [CommandLine](INFO): End of History
2023-03-28T17:36:11.591Z,1680024971.591 [CommandLine](INFO): End of History
2023-03-28T17:36:12.896Z,1680024972.896 [CommandLine](INFO): End of History
2023-03-28T17:36:14.030Z,1680024974.030 [CommandLine](INFO): End of History
2023-03-28T17:36:14.745Z,1680024974.745 [CommandLine](INFO): End of History
2023-03-28T17:36:15.411Z,1680024975.411 [CommandLine](INFO): End of History
2023-03-28T17:36:16.099Z,1680024976.099 [CommandLine](INFO): End of History
2023-03-28T17:36:16.784Z,1680024976.784 [CommandLine](INFO): End of History
2023-03-28T17:36:17.464Z,1680024977.464 [CommandLine](INFO): End of History
2023-03-28T17:36:18.146Z,1680024978.146 [CommandLine](INFO): End of History
2023-03-28T17:36:18.855Z,1680024978.855 [CommandLine](INFO): End of History
2023-03-28T17:36:19.562Z,1680024979.562 [CommandLine](INFO): End of History
2023-03-28T17:36:20.179Z,1680024980.179 [CommandLine](INFO): End of History
2023-03-28T17:36:20.901Z,1680024980.901 [CommandLine](INFO): End of History
2023-03-28T17:36:21.586Z,1680024981.586 [CommandLine](INFO): End of History
2023-03-28T17:36:22.290Z,1680024982.290 [CommandLine](INFO): End of History
2023-03-28T17:36:22.938Z,1680024982.938 [CommandLine](INFO): End of History
2023-03-28T17:36:23.705Z,1680024983.705 [CommandLine](INFO): End of History
2023-03-28T17:36:24.382Z,1680024984.382 [CommandLine](INFO): End of History
2023-03-28T17:36:25.022Z,1680024985.022 [CommandLine](INFO): End of History
2023-03-28T17:36:25.725Z,1680024985.725 [CommandLine](INFO): End of History
2023-03-28T17:36:26.421Z,1680024986.421 [CommandLine](INFO): End of History
2023-03-28T17:36:27.039Z,1680024987.039 [CommandLine](INFO): End of History
2023-03-28T17:36:27.722Z,1680024987.722 [CommandLine](INFO): End of History
2023-03-28T17:36:28.437Z,1680024988.437 [CommandLine](INFO): End of History
2023-03-28T17:36:30.751Z,1680024990.751 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T17:36:30.751Z,1680024990.751 [Default:CheckIn:C.Wait] Stopped
2023-03-28T17:36:30.751Z,1680024990.751 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:36:30.751Z,1680024990.751 [Default:CheckIn:D] Running Loop=1
2023-03-28T17:36:31.134Z,1680024991.134 [Default:CheckIn:D] Stopped
2023-03-28T17:36:31.134Z,1680024991.134 [Default:CheckIn:E] Running Loop=1
2023-03-28T17:36:31.599Z,1680024991.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.537280 min 
2023-03-28T17:36:31.599Z,1680024991.599 [Default:CheckIn:E] Stopped
2023-03-28T17:36:31.631Z,1680024991.631 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T17:36:31.631Z,1680024991.631 [Default:CheckIn] Stopped
2023-03-28T17:36:31.631Z,1680024991.631 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T17:36:31.631Z,1680024991.631 [Default:CheckIn](INFO): Running loop #5
2023-03-28T17:36:31.631Z,1680024991.631 [Default:CheckIn] Running Loop=5
2023-03-28T17:36:31.631Z,1680024991.631 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:36:31.631Z,1680024991.631 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:36:33.570Z,1680024993.570 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173633.00,A,3648.17569,N,12147.28600,W,0.019,324.60,280323,,,D*71
2023-03-28T17:36:33.573Z,1680024993.573 [NAL9602](INFO): GPS fix at 20230328T173633: (36.802928, -121.788100)
2023-03-28T17:36:33.591Z,1680024993.591 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:36:33.591Z,1680024993.591 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:36:44.755Z,1680025004.755 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T170825/Courier0016.lzma
2023-03-28T17:36:45.758Z,1680025005.758 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Courier0016.lzma.bak
2023-03-28T17:36:45.759Z,1680025005.759 [DataOverHttps](INFO): SBD MOMSN=17729008
2023-03-28T17:37:01.747Z,1680025021.747 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230328T170825/Express0017.lzma
2023-03-28T17:37:02.750Z,1680025022.750 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Express0017.lzma.bak
2023-03-28T17:37:02.750Z,1680025022.750 [DataOverHttps](INFO): SBD MOMSN=17729011
2023-03-28T17:37:04.319Z,1680025024.319 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:37:04.319Z,1680025024.319 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:37:04.319Z,1680025024.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:37:04.761Z,1680025024.761 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:37:18.851Z,1680025038.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=47524, MT Status=2, MTMSN=0
2023-03-28T17:37:18.852Z,1680025038.852 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:37:23.356Z,1680025043.356 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:37:48.036Z,1680025068.036 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:37:52.494Z,1680025072.494 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:38:06.979Z,1680025086.979 [NAL9602](INFO): SBD MO Status=2, MOMSN=47524, MT Status=2, MTMSN=0
2023-03-28T17:38:06.979Z,1680025086.979 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:38:09.811Z,1680025089.811 [DVL_micro](ERROR): only read 3 of 4 data items
2023-03-28T17:38:09.821Z,1680025089.821 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+99999.99
2023-03-28T17:38:11.062Z,1680025091.062 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:38:49.898Z,1680025129.898 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:39:11.318Z,1680025151.318 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:39:20.208Z,1680025160.208 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:39:45.614Z,1680025185.614 [NAL9602](INFO): SBD MO Status=2, MOMSN=47524, MT Status=2, MTMSN=0
2023-03-28T17:39:45.614Z,1680025185.614 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:39:50.094Z,1680025190.094 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:39:53.290Z,1680025193.290 [NAL9602](INFO): SBD MO Status=2, MOMSN=47524, MT Status=2, MTMSN=0
2023-03-28T17:39:53.290Z,1680025193.290 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:39:57.774Z,1680025197.774 [Reporter](INFO): NAL9602.sigQuality 4 count
2023-03-28T17:40:01.386Z,1680025201.386 [NAL9602](INFO): SBD MO Status=2, MOMSN=47524, MT Status=2, MTMSN=0
2023-03-28T17:40:01.386Z,1680025201.386 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:40:05.870Z,1680025205.870 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:40:09.851Z,1680025209.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=47524, MT Status=2, MTMSN=0
2023-03-28T17:40:09.851Z,1680025209.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-03-28T17:40:13.963Z,1680025213.963 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:40:38.577Z,1680025238.577 [Reporter](INFO): NAL9602.sigQuality 1 count
2023-03-28T17:40:54.791Z,1680025254.791 [Reporter](INFO): NAL9602.sigQuality 3 count
2023-03-28T17:40:59.561Z,1680025259.561 [NAL9602](INFO): SBD MO Status=0, MOMSN=47524, MT Status=0, MTMSN=0
2023-03-28T17:40:59.561Z,1680025259.561 [NAL9602](INFO): No messages in MT queue
2023-03-28T17:41:30.261Z,1680025290.261 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T17:41:30.283Z,1680025290.283 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-03-28T17:42:04.999Z,1680025324.999 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-28T17:42:04.999Z,1680025324.999 [Default:CheckIn:C.Wait] Stopped
2023-03-28T17:42:04.999Z,1680025324.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-28T17:42:04.999Z,1680025324.999 [Default:CheckIn:D] Running Loop=1
2023-03-28T17:42:05.409Z,1680025325.409 [Default:CheckIn:D] Stopped
2023-03-28T17:42:05.409Z,1680025325.409 [Default:CheckIn:E] Running Loop=1
2023-03-28T17:42:05.827Z,1680025325.827 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.108531 min 
2023-03-28T17:42:05.827Z,1680025325.827 [Default:CheckIn:E] Stopped
2023-03-28T17:42:05.827Z,1680025325.827 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-28T17:42:05.827Z,1680025325.827 [Default:CheckIn] Stopped
2023-03-28T17:42:05.827Z,1680025325.827 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-28T17:42:05.828Z,1680025325.828 [Default:CheckIn](INFO): Running loop #6
2023-03-28T17:42:05.828Z,1680025325.828 [Default:CheckIn] Running Loop=6
2023-03-28T17:42:05.828Z,1680025325.828 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-28T17:42:05.828Z,1680025325.828 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-28T17:42:07.818Z,1680025327.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174207.00,A,3648.17522,N,12147.28464,W,0.058,324.60,280323,,,D*7F
2023-03-28T17:42:07.821Z,1680025327.821 [NAL9602](INFO): GPS fix at 20230328T174207: (36.802920, -121.788077)
2023-03-28T17:42:07.830Z,1680025327.830 [Default:CheckIn:Read_GPS] Stopped
2023-03-28T17:42:07.831Z,1680025327.831 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-28T17:42:15.207Z,1680025335.207 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230328T170825/Courier0019.lzma
2023-03-28T17:42:16.210Z,1680025336.210 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Courier0019.lzma.bak
2023-03-28T17:42:16.210Z,1680025336.210 [DataOverHttps](INFO): SBD MOMSN=17729015
2023-03-28T17:42:17.966Z,1680025337.966 [Reporter](INFO): NAL9602.sigQuality 2 count
2023-03-28T17:42:32.103Z,1680025352.103 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230328T170825/Express0020.lzma
2023-03-28T17:42:33.106Z,1680025353.106 [DataOverHttps](INFO): Moved sent file to Logs/20230328T170825/Express0020.lzma.bak
2023-03-28T17:42:33.106Z,1680025353.106 [DataOverHttps](INFO): SBD MOMSN=17729018
2023-03-28T17:42:33.697Z,1680025353.697 [NAL9602](INFO): SBD MO Status=0, MOMSN=47525, MT Status=0, MTMSN=0
2023-03-28T17:42:33.697Z,1680025353.697 [NAL9602](INFO): No messages in MT queue
2023-03-28T17:42:34.517Z,1680025354.517 [Default:CheckIn:Read_Iridium] Stopped
2023-03-28T17:42:34.517Z,1680025354.517 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-28T17:42:34.517Z,1680025354.517 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-28T17:43:04.431Z,1680025384.431 [NAL9602](INFO): Not Powering down - fast GPS
2023-03-28T17:43:04.489Z,1680025384.489 [Reporter](INFO): NAL9602.sigQuality 0 count