2023-08-23T19:18:49.532Z,1692818329.532 [Supervisor](DEBUG): Initializing supervisor.
2023-08-23T19:18:49.536Z,1692818329.536 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-23T19:18:49.537Z,1692818329.537 [SyncHandler](INFO): Protected caller Thread ID is 3796
2023-08-23T19:18:49.537Z,1692818329.537 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-23T19:18:49.538Z,1692818329.538 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-23T19:18:49.539Z,1692818329.539 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3797
2023-08-23T19:18:49.543Z,1692818329.543 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-23T19:18:49.560Z,1692818329.560 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-23T19:18:49.561Z,1692818329.561 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-23T19:18:49.561Z,1692818329.561 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3798
2023-08-23T19:18:49.565Z,1692818329.565 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-23T19:18:49.566Z,1692818329.566 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-23T19:18:49.566Z,1692818329.566 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3799
2023-08-23T19:18:49.568Z,1692818329.568 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-23T19:18:49.569Z,1692818329.569 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-23T19:18:49.570Z,1692818329.570 [logger ThreadHandler](INFO): Protected caller Thread ID is 3800
2023-08-23T19:18:49.573Z,1692818329.573 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-23T19:18:49.573Z,1692818329.573 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-23T19:18:49.575Z,1692818329.575 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-23T19:18:49.783Z,1692818329.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-23T19:18:49.784Z,1692818329.784 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-23T19:18:49.889Z,1692818329.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-23T19:18:49.890Z,1692818329.890 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-23T19:18:49.984Z,1692818329.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-23T19:18:49.984Z,1692818329.984 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-23T19:18:50.434Z,1692818330.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-23T19:18:50.435Z,1692818330.435 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-23T19:18:50.766Z,1692818330.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-23T19:18:50.766Z,1692818330.766 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-23T19:18:51.080Z,1692818331.080 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-23T19:18:51.081Z,1692818331.081 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-23T19:18:51.259Z,1692818331.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-23T19:18:51.260Z,1692818331.260 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-23T19:18:51.339Z,1692818331.339 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-23T19:18:51.420Z,1692818331.420 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-23T19:18:51.800Z,1692818331.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-23T19:18:51.800Z,1692818331.800 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-23T19:18:52.067Z,1692818332.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-23T19:18:52.068Z,1692818332.068 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-23T19:18:52.562Z,1692818332.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-23T19:18:52.563Z,1692818332.563 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-23T19:18:52.777Z,1692818332.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-23T19:18:52.778Z,1692818332.778 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-23T19:18:53.004Z,1692818333.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-23T19:18:53.005Z,1692818333.005 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-23T19:18:53.142Z,1692818333.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-23T19:18:53.142Z,1692818333.142 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-23T19:18:53.922Z,1692818333.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-23T19:18:53.924Z,1692818333.924 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2023-08-23T19:18:53.925Z,1692818333.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2023-08-23T19:18:54.011Z,1692818334.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2023-08-23T19:18:54.140Z,1692818334.140 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2023-08-23T19:18:54.466Z,1692818334.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-23T19:18:54.467Z,1692818334.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2023-08-23T19:18:54.694Z,1692818334.694 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2023-08-23T19:18:54.855Z,1692818334.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2023-08-23T19:18:55.136Z,1692818335.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2023-08-23T19:18:55.353Z,1692818335.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2023-08-23T19:18:55.447Z,1692818335.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2023-08-23T19:18:55.680Z,1692818335.680 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2023-08-23T19:18:55.780Z,1692818335.780 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2023-08-23T19:18:55.939Z,1692818335.939 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-08-23T19:18:55.957Z,1692818335.957 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-23T19:18:56.006Z,1692818336.006 [DepthRateCalculator] Loaded
2023-08-23T19:18:56.006Z,1692818336.006 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-23T19:18:56.011Z,1692818336.011 [PitchRateCalculator] Loaded
2023-08-23T19:18:56.011Z,1692818336.011 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-23T19:18:56.021Z,1692818336.021 [SpeedCalculator] Loaded
2023-08-23T19:18:56.022Z,1692818336.022 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-23T19:18:56.026Z,1692818336.026 [YawRateCalculator] Loaded
2023-08-23T19:18:56.027Z,1692818336.027 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-23T19:18:56.047Z,1692818336.047 [ElevatorOffsetCalculator] Loaded
2023-08-23T19:18:56.048Z,1692818336.048 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-23T19:18:56.048Z,1692818336.048 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-23T19:18:56.049Z,1692818336.049 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-23T19:18:56.075Z,1692818336.075 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-23T19:18:56.075Z,1692818336.075 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-23T19:18:56.085Z,1692818336.085 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-23T19:18:56.086Z,1692818336.086 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-23T19:18:56.492Z,1692818336.492 [AHRS_M2] Loaded
2023-08-23T19:18:56.493Z,1692818336.493 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-23T19:18:56.527Z,1692818336.527 [BackseatComponent] Loaded
2023-08-23T19:18:56.527Z,1692818336.527 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-08-23T19:18:56.528Z,1692818336.528 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0
2023-08-23T19:18:56.528Z,1692818336.528 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3882
2023-08-23T19:18:56.531Z,1692818336.531 [LcmUniversalReporter] Loaded
2023-08-23T19:18:56.531Z,1692818336.531 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-08-23T19:18:57.318Z,1692818337.318 [BPC1] Loaded
2023-08-23T19:18:57.318Z,1692818337.318 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-23T19:18:57.393Z,1692818337.393 [DataOverHttps] Loaded
2023-08-23T19:18:57.393Z,1692818337.393 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-23T19:18:57.394Z,1692818337.394 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0
2023-08-23T19:18:57.394Z,1692818337.394 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3883
2023-08-23T19:18:57.414Z,1692818337.414 [Depth_Keller] Loaded
2023-08-23T19:18:57.415Z,1692818337.415 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-23T19:18:57.419Z,1692818337.419 [DropWeight] Loaded
2023-08-23T19:18:57.420Z,1692818337.420 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-23T19:18:57.480Z,1692818337.480 [NAL9602] Loaded
2023-08-23T19:18:57.481Z,1692818337.481 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-23T19:18:57.510Z,1692818337.510 [Onboard] Loaded
2023-08-23T19:18:57.510Z,1692818337.510 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-23T19:18:57.511Z,1692818337.511 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0
2023-08-23T19:18:57.511Z,1692818337.511 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3884
2023-08-23T19:18:57.517Z,1692818337.517 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2023-08-23T19:18:57.528Z,1692818337.528 [PowerOnly] Loaded
2023-08-23T19:18:57.529Z,1692818337.529 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-08-23T19:18:57.540Z,1692818337.540 [Power24vConverter] Loaded
2023-08-23T19:18:57.540Z,1692818337.540 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-23T19:18:57.554Z,1692818337.554 [Radio_Surface] Loaded
2023-08-23T19:18:57.554Z,1692818337.554 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-23T19:18:57.555Z,1692818337.555 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0
2023-08-23T19:18:57.556Z,1692818337.556 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3885
2023-08-23T19:18:57.557Z,1692818337.557 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-23T19:18:57.557Z,1692818337.557 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-23T19:18:57.675Z,1692818337.675 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-08-23T19:18:57.675Z,1692818337.675 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-08-23T19:18:57.716Z,1692818337.716 [DeadReckonUsingSpeedCalculator] Loaded
2023-08-23T19:18:57.716Z,1692818337.716 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-08-23T19:18:57.730Z,1692818337.730 [NavChart] Loaded
2023-08-23T19:18:57.730Z,1692818337.730 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-23T19:18:57.735Z,1692818337.735 [UniversalFixResidualReporter] Loaded
2023-08-23T19:18:57.736Z,1692818337.736 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-23T19:18:57.737Z,1692818337.737 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-23T19:18:57.737Z,1692818337.737 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-23T19:18:57.873Z,1692818337.873 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-23T19:18:57.886Z,1692818337.886 [SBIT] Loaded
2023-08-23T19:18:57.887Z,1692818337.887 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-23T19:18:57.889Z,1692818337.889 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-23T19:18:57.902Z,1692818337.902 [IBIT] Loaded
2023-08-23T19:18:57.902Z,1692818337.902 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-23T19:18:57.907Z,1692818337.907 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-23T19:18:58.007Z,1692818338.007 [CBIT] Loaded
2023-08-23T19:18:58.007Z,1692818338.007 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-23T19:18:58.008Z,1692818338.008 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-23T19:18:58.009Z,1692818338.009 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-23T19:18:58.176Z,1692818338.176 [CTD_Seabird] Loaded
2023-08-23T19:18:58.176Z,1692818338.176 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-23T19:18:58.177Z,1692818338.177 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0
2023-08-23T19:18:58.178Z,1692818338.178 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3886
2023-08-23T19:18:58.197Z,1692818338.197 [PAR_Licor] Loaded
2023-08-23T19:18:58.197Z,1692818338.197 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-23T19:18:58.236Z,1692818338.236 [WetLabsBB2FL] Loaded
2023-08-23T19:18:58.237Z,1692818338.237 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-23T19:18:58.238Z,1692818338.238 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2023-08-23T19:18:58.238Z,1692818338.238 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3887
2023-08-23T19:18:58.239Z,1692818338.239 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-23T19:18:58.240Z,1692818338.240 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-23T19:18:58.369Z,1692818338.369 [BuoyancyServo] Loaded
2023-08-23T19:18:58.369Z,1692818338.369 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-23T19:18:58.390Z,1692818338.390 [ElevatorServo] Loaded
2023-08-23T19:18:58.391Z,1692818338.391 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-23T19:18:58.412Z,1692818338.412 [MassServo] Loaded
2023-08-23T19:18:58.412Z,1692818338.412 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-23T19:18:58.432Z,1692818338.432 [RudderServo] Loaded
2023-08-23T19:18:58.432Z,1692818338.432 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-23T19:18:58.448Z,1692818338.448 [ThrusterHE] Loaded
2023-08-23T19:18:58.448Z,1692818338.448 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-08-23T19:18:58.449Z,1692818338.449 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-23T19:18:58.449Z,1692818338.449 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-23T19:18:58.499Z,1692818338.499 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-23T19:18:58.548Z,1692818338.548 [VerticalControl] Loaded
2023-08-23T19:18:58.548Z,1692818338.548 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-23T19:18:58.551Z,1692818338.551 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-23T19:18:58.587Z,1692818338.587 [HorizontalControl] Loaded
2023-08-23T19:18:58.587Z,1692818338.587 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-23T19:18:58.590Z,1692818338.590 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-23T19:18:58.591Z,1692818338.591 [SpeedControl] Loaded
2023-08-23T19:18:58.592Z,1692818338.592 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-23T19:18:58.595Z,1692818338.595 [LoopControl](DEBUG): Construct LoopControl.
2023-08-23T19:18:58.595Z,1692818338.595 [LoopControl] Loaded
2023-08-23T19:18:58.595Z,1692818338.595 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-23T19:18:58.596Z,1692818338.596 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-23T19:18:58.596Z,1692818338.596 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-23T19:18:58.692Z,1692818338.692 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-23T19:18:58.693Z,1692818338.693 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-23T19:18:58.836Z,1692818338.836 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-23T19:18:58.837Z,1692818338.837 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-23T19:18:59.135Z,1692818339.135 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-23T19:18:59.135Z,1692818339.135 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-23T19:18:59.206Z,1692818339.206 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC
2023-08-23T19:18:59.207Z,1692818339.207 [StratificationFrontDetector](DEBUG): (re)initializing
2023-08-23T19:18:59.207Z,1692818339.207 [StratificationFrontDetector] Loaded
2023-08-23T19:18:59.207Z,1692818339.207 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2023-08-23T19:18:59.208Z,1692818339.208 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-23T19:18:59.214Z,1692818339.214 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-23T19:18:59.217Z,1692818339.217 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-23T19:18:59.227Z,1692818339.227 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-23T19:18:59.228Z,1692818339.228 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0
2023-08-23T19:18:59.229Z,1692818339.229 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3888
2023-08-23T19:18:59.233Z,1692818339.233 [Supervisor](INFO): Main Thread ID is 3795
2023-08-23T19:18:59.233Z,1692818339.233 [Supervisor](DEBUG): Running supervisor.
2023-08-23T19:18:59.234Z,1692818339.234 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3889
2023-08-23T19:18:59.234Z,1692818339.234 [CommandExec](INFO): Initializing the command executive.
2023-08-23T19:18:59.236Z,1692818339.236 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3890
2023-08-23T19:18:59.238Z,1692818339.238 [controlThread ThreadHandler](INFO): Handler Thread ID is 3891
2023-08-23T19:18:59.238Z,1692818339.238 [controlThread](DEBUG): Initializing ControlThread
2023-08-23T19:18:59.239Z,1692818339.239 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-23T19:18:59.240Z,1692818339.240 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-23T19:18:59.240Z,1692818339.240 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-23T19:18:59.240Z,1692818339.240 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-23T19:18:59.241Z,1692818339.241 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-23T19:18:59.247Z,1692818339.247 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T19:18:59.247Z,1692818339.247 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-23T19:18:59.247Z,1692818339.247 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-23T19:18:59.248Z,1692818339.248 [SBIT](INFO): Initialize SBIT Component.
2023-08-23T19:18:59.248Z,1692818339.248 [SBIT](IMPORTANT): git: 2023-08-21
2023-08-23T19:18:59.248Z,1692818339.248 [SBIT](INFO): git hash: a90fa4c688a1aaa9675a08dc769bbd0e140c3a66
2023-08-23T19:18:59.249Z,1692818339.249 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-23T19:18:59.250Z,1692818339.250 [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-08-23T19:18:59.251Z,1692818339.251 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2023-08-23T19:18:59.251Z,1692818339.251 [IBIT](INFO): Initialize IBIT Component.
2023-08-23T19:18:59.253Z,1692818339.253 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-23T19:18:59.253Z,1692818339.253 [logger ThreadHandler](INFO): Handler Thread ID is 3892
2023-08-23T19:18:59.264Z,1692818339.264 [CBIT](DEBUG): Initialized mux pins.
2023-08-23T19:18:59.264Z,1692818339.264 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-23T19:18:59.272Z,1692818339.272 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3893
2023-08-23T19:18:59.284Z,1692818339.284 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3894
2023-08-23T19:18:59.285Z,1692818339.285 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-23T19:18:59.288Z,1692818339.288 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-23T19:18:59.289Z,1692818339.289 [CBIT](DEBUG): Initializing heartbeat.
2023-08-23T19:18:59.296Z,1692818339.296 [Onboard ThreadHandler](INFO): Handler Thread ID is 3895
2023-08-23T19:18:59.314Z,1692818339.314 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3896
2023-08-23T19:18:59.337Z,1692818339.337 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3897
2023-08-23T19:18:59.337Z,1692818339.337 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-23T19:18:59.341Z,1692818339.341 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3899
2023-08-23T19:18:59.344Z,1692818339.344 [WetLabsBB2FL](INFO): Powering up
2023-08-23T19:18:59.346Z,1692818339.346 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3901
2023-08-23T19:18:59.349Z,1692818339.349 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-08-23T19:18:59.349Z,1692818339.349 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-08-23T19:18:59.349Z,1692818339.349 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-08-23T19:18:59.349Z,1692818339.349 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-08-23T19:18:59.350Z,1692818339.350 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-08-23T19:18:59.350Z,1692818339.350 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-08-23T19:18:59.350Z,1692818339.350 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-08-23T19:18:59.350Z,1692818339.350 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-08-23T19:18:59.360Z,1692818339.360 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-23T19:18:59.360Z,1692818339.360 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-23T19:18:59.400Z,1692818339.400 [CBIT](DEBUG): Backplane powered.
2023-08-23T19:18:59.405Z,1692818339.405 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-23T19:18:59.407Z,1692818339.407 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-23T19:18:59.408Z,1692818339.408 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-23T19:18:59.409Z,1692818339.409 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-23T19:18:59.410Z,1692818339.410 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-23T19:18:59.443Z,1692818339.443 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-23T19:18:59.488Z,1692818339.488 [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-08-23T19:18:59.489Z,1692818339.489 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-23T19:18:59.579Z,1692818339.579 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-23T19:18:59.596Z,1692818339.596 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-23T19:18:59.598Z,1692818339.598 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-23T19:18:59.666Z,1692818339.666 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-23T19:18:59.669Z,1692818339.669 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-23T19:18:59.674Z,1692818339.674 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-23T19:18:59.678Z,1692818339.678 [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-08-23T19:18:59.683Z,1692818339.683 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-08-23T19:18:59.689Z,1692818339.689 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-23T19:18:59.729Z,1692818339.729 [Radio_Surface](INFO): Powering up
2023-08-23T19:18:59.751Z,1692818339.751 [Power24vConverter](INFO): Powering up.
2023-08-23T19:18:59.806Z,1692818339.806 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-23T19:18:59.807Z,1692818339.807 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-23T19:18:59.837Z,1692818339.837 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-23T19:18:59.850Z,1692818339.850 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-23T19:18:59.851Z,1692818339.851 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-23T19:18:59.864Z,1692818339.864 [MassServo](DEBUG): Initializing MassServo.
2023-08-23T19:18:59.865Z,1692818339.865 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-23T19:18:59.872Z,1692818339.872 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-23T19:18:59.873Z,1692818339.873 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-23T19:18:59.880Z,1692818339.880 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-23T19:19:00.149Z,1692818340.149 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-08-23T19:19:00.149Z,1692818340.149 [DropWeight] Hardware Fault, FailCount= 1
2023-08-23T19:19:00.149Z,1692818340.149 [DropWeight](ERROR): Hardware Fault
2023-08-23T19:19:00.185Z,1692818340.185 [CommandExec](FAULT): Scheduling is paused
2023-08-23T19:19:00.185Z,1692818340.185 [CBIT](INFO): Critical error at 20230823T191900
2023-08-23T19:19:00.186Z,1692818340.186 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-08-23T19:19:00.192Z,1692818340.192 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-08-23T19:19:00.193Z,1692818340.193 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-08-23T19:19:00.546Z,1692818340.546 [CBIT](INFO): Critical error at 20230823T191900
2023-08-23T19:19:01.956Z,1692818341.956 [WetLabsBB2FL](INFO): Powering down
2023-08-23T19:19:05.585Z,1692818345.585 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-08-23T19:19:08.766Z,1692818348.766 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004881
2023-08-23T19:19:27.577Z,1692818367.577 [NAL9602](INFO): Powering up NAL9602
2023-08-23T19:19:38.491Z,1692818378.491 [NAL9602](INFO): NAL9602 initialized
2023-08-23T19:19:43.098Z,1692818383.098 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2023-08-23T19:19:43.098Z,1692818383.098 [CommandExec](IMPORTANT): Onboard.Humidity 22.762789 %
2023-08-23T19:19:51.256Z,1692818391.256 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2023-08-23T19:19:51.257Z,1692818391.257 [CommandExec](IMPORTANT): Onboard.Pressure 10.024822 psi
2023-08-23T19:19:58.700Z,1692818398.700 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-23T19:19:58.705Z,1692818398.705 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-23T19:20:10.053Z,1692818410.053 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006928
CHAN A1 (24V): -0.000117
CHAN A2 (12V): -0.005146
CHAN A3 (5V): -0.002542
CHAN B0 (3.3V): -0.000983
CHAN B1 (3.15aV): -0.001502
CHAN B2 (3.15bV): -0.001814
CHAN B3 (GND): -0.000803
OPEN: 0.004800
Full Scale: +/- 1 mA
2023-08-23T19:20:52.836Z,1692818452.836 [SBIT](IMPORTANT): SBIT PASSED
2023-08-23T19:20:52.836Z,1692818452.836 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-08-23T19:20:52.837Z,1692818452.837 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour;

2023-08-23T19:20:52.837Z,1692818452.837 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-08-23T19:20:52.837Z,1692818452.837 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute;

2023-08-23T19:20:52.837Z,1692818452.837 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-08-23T19:20:52.837Z,1692818452.837 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;

2023-08-23T19:20:52.837Z,1692818452.837 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Express none _.ayeris_particle_shape;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Express none _.ayeris_particle_slope;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2023-08-23T19:20:52.838Z,1692818452.838 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2023-08-23T19:20:52.839Z,1692818452.839 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2023-08-23T19:20:52.839Z,1692818452.839 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=381.902688 cubic_centimeter;

2023-08-23T19:20:52.839Z,1692818452.839 [SBIT](IMPORTANT): VerticalControl.massDefault=-9.969354 millimeter;

2023-08-23T19:20:52.839Z,1692818452.839 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-08-23T19:20:52.839Z,1692818452.839 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-08-23T19:20:52.839Z,1692818452.839 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;

2023-08-23T19:20:53.242Z,1692818453.242 [MissionManager](IMPORTANT): Started mission Startup
2023-08-23T19:20:53.243Z,1692818453.243 [Startup] Running Loop=1
2023-08-23T19:20:53.243Z,1692818453.243 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-23T19:20:53.243Z,1692818453.243 [Startup:A.GoToSurface] Running Loop=1
2023-08-23T19:20:53.243Z,1692818453.243 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-23T19:20:53.244Z,1692818453.244 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-23T19:20:53.244Z,1692818453.244 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-23T19:20:53.244Z,1692818453.244 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-23T19:20:53.245Z,1692818453.245 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-23T19:20:53.245Z,1692818453.245 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-23T19:20:53.247Z,1692818453.247 [Startup:StartupSatComms] Running Loop=1
2023-08-23T19:20:53.247Z,1692818453.247 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-23T19:20:53.247Z,1692818453.247 [Startup:StartupSatComms:A] Running Loop=1
2023-08-23T19:20:53.642Z,1692818453.642 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-23T19:21:53.347Z,1692818513.347 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-23T19:20:53.2Z
2023-08-23T19:21:53.347Z,1692818513.347 [Startup:StartupSatComms:A] Stopped
2023-08-23T19:21:53.347Z,1692818513.347 [Startup:StartupSatComms:B] Running Loop=1
2023-08-23T19:21:53.631Z,1692818513.631 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-23T19:21:59.382Z,1692818519.382 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-08-23T19:21:59.382Z,1692818519.382 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T19:21:59.383Z,1692818519.383 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-08-23T19:21:59.383Z,1692818519.383 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T19:21:59.406Z,1692818519.406 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-23T19:21:59.406Z,1692818519.406 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-23T19:21:59.815Z,1692818519.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-23T19:21:59.815Z,1692818519.815 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-08-23T19:21:59.815Z,1692818519.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-23T19:21:59.815Z,1692818519.815 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-08-23T19:22:00.193Z,1692818520.193 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T19:22:02.219Z,1692818522.219 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230823T181705/Courier0013.lzma
2023-08-23T19:22:03.222Z,1692818523.222 [DataOverHttps](INFO): Moved sent file to Logs/20230823T181705/Courier0013.lzma.bak
2023-08-23T19:22:03.222Z,1692818523.222 [DataOverHttps](INFO): SBD MOMSN=18584908
2023-08-23T19:22:18.864Z,1692818538.864 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20230823T191849/Courier0000.lzma
2023-08-23T19:22:19.866Z,1692818539.866 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Courier0000.lzma.bak
2023-08-23T19:22:19.866Z,1692818539.866 [DataOverHttps](INFO): SBD MOMSN=18584942
2023-08-23T19:22:36.608Z,1692818556.608 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20230823T181705/Express0014.lzma
2023-08-23T19:22:39.755Z,1692818559.755 [DataOverHttps](INFO): Moved sent file to Logs/20230823T181705/Express0014.lzma.bak
2023-08-23T19:22:39.755Z,1692818559.755 [DataOverHttps](INFO): SBD MOMSN=18584948
2023-08-23T19:22:53.558Z,1692818573.558 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-23T19:21:53.3Z
2023-08-23T19:22:53.558Z,1692818573.558 [Startup:StartupSatComms:B] Stopped
2023-08-23T19:22:53.558Z,1692818573.558 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-23T19:22:53.559Z,1692818573.559 [Startup:StartupSatComms] Stopped
2023-08-23T19:22:53.559Z,1692818573.559 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-23T19:22:53.559Z,1692818573.559 [Startup](INFO): Completed Startup
2023-08-23T19:22:53.560Z,1692818573.560 [MissionManager](INFO): Startup is completed.
2023-08-23T19:22:53.564Z,1692818573.564 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-23T19:22:53.564Z,1692818573.564 [Startup] Stopped
2023-08-23T19:22:53.564Z,1692818573.564 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-23T19:22:53.564Z,1692818573.564 [Startup:A.GoToSurface] Stopped
2023-08-23T19:22:53.564Z,1692818573.564 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-23T19:22:53.943Z,1692818573.943 [MissionManager](IMPORTANT): Started mission Default
2023-08-23T19:22:53.943Z,1692818573.943 [Default] Running Loop=1
2023-08-23T19:22:53.943Z,1692818573.943 [Default](DEBUG): Aggregate::initialize Default
2023-08-23T19:22:53.944Z,1692818573.944 [Default:B.GoToSurface] Running Loop=1
2023-08-23T19:22:53.944Z,1692818573.944 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-23T19:22:53.964Z,1692818573.964 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-23T19:22:53.964Z,1692818573.964 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-23T19:22:53.965Z,1692818573.965 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-23T19:22:53.965Z,1692818573.965 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-23T19:22:53.965Z,1692818573.965 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-23T19:22:53.966Z,1692818573.966 [Default:A.Wait] Running Loop=1
2023-08-23T19:22:53.966Z,1692818573.966 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-23T19:22:56.237Z,1692818576.237 [DataOverHttps](INFO): Sending 1150 bytes from file Logs/20230823T191849/Express0001.lzma
2023-08-23T19:22:57.238Z,1692818577.238 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Express0001.lzma.bak
2023-08-23T19:22:57.238Z,1692818577.238 [DataOverHttps](INFO): SBD MOMSN=18584966
2023-08-23T19:23:07.274Z,1692818587.274 [Default:A.Wait](INFO): Done Waiting.
2023-08-23T19:23:07.274Z,1692818587.274 [Default:A.Wait] Stopped
2023-08-23T19:23:07.275Z,1692818587.275 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-23T19:23:07.682Z,1692818587.682 [Default:CheckIn] Running Loop=1
2023-08-23T19:23:07.682Z,1692818587.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-23T19:23:07.682Z,1692818587.682 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-23T19:23:08.095Z,1692818588.095 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-23T19:24:41.421Z,1692818681.421 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-23T19:25:00.434Z,1692818700.434 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-08-23T19:25:00.434Z,1692818700.434 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T19:25:00.435Z,1692818700.435 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2023-08-23T19:25:00.435Z,1692818700.435 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T19:25:00.466Z,1692818700.466 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-23T19:25:00.466Z,1692818700.466 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-23T19:25:00.851Z,1692818700.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-23T19:25:00.851Z,1692818700.851 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-08-23T19:25:00.852Z,1692818700.852 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-23T19:25:00.852Z,1692818700.852 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2023-08-23T19:25:01.241Z,1692818701.241 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T19:26:23.232Z,1692818783.232 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-23T19:26:23.236Z,1692818783.236 [BPC1](INFO): Received data from all battery sticks.
2023-08-23T19:28:01.422Z,1692818881.422 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-08-23T19:28:01.422Z,1692818881.422 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T19:28:01.423Z,1692818881.423 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2023-08-23T19:28:01.423Z,1692818881.423 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T19:28:01.442Z,1692818881.442 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-23T19:28:01.442Z,1692818881.442 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-23T19:28:01.840Z,1692818881.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-23T19:28:01.840Z,1692818881.840 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-08-23T19:28:01.841Z,1692818881.841 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-23T19:28:01.841Z,1692818881.841 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2023-08-23T19:28:02.227Z,1692818882.227 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T19:28:07.879Z,1692818887.879 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-08-23T19:23:07.7Z
2023-08-23T19:28:07.879Z,1692818887.879 [Default:CheckIn:Read_GPS] Stopped
2023-08-23T19:28:07.879Z,1692818887.879 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-23T19:28:08.287Z,1692818888.287 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-23T19:28:14.711Z,1692818894.711 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230823T191849/Courier0004.lzma
2023-08-23T19:28:15.714Z,1692818895.714 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Courier0004.lzma.bak
2023-08-23T19:28:15.714Z,1692818895.714 [DataOverHttps](INFO): SBD MOMSN=18585224
2023-08-23T19:28:33.044Z,1692818913.044 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230823T191849/Express0005.lzma
2023-08-23T19:28:34.046Z,1692818914.046 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Express0005.lzma.bak
2023-08-23T19:28:34.046Z,1692818914.046 [DataOverHttps](INFO): SBD MOMSN=18585226
2023-08-23T19:28:36.999Z,1692818916.999 [Default:CheckIn:Read_Iridium] Stopped
2023-08-23T19:28:36.999Z,1692818916.999 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-23T19:28:36.999Z,1692818916.999 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-23T19:29:00.801Z,1692818940.801 [Power24vConverter](INFO): Powering down.
2023-08-23T19:29:40.386Z,1692818980.386 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-08-23T19:29:40.386Z,1692818980.386 [NAL9602] Data Fault, FailCount= 1
2023-08-23T19:29:40.386Z,1692818980.386 [NAL9602](ERROR): Data Fault
2023-08-23T19:29:40.405Z,1692818980.405 [CBIT](ERROR): Data Fault in component: NAL9602
2023-08-23T19:29:40.789Z,1692818980.789 [NAL9602](INFO): Powering down
2023-08-23T19:29:41.614Z,1692818981.614 [CBIT](INFO): Clearing failed state for component NAL9602
2023-08-23T19:29:41.614Z,1692818981.614 [NAL9602] No Fault, FailCount= 1
2023-08-23T19:30:11.089Z,1692819011.089 [NAL9602](INFO): Powering up NAL9602
2023-08-23T19:30:21.000Z,1692819022.000 [NAL9602](INFO): NAL9602 initialized
2023-08-23T19:31:02.405Z,1692819062.405 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-08-23T19:31:02.405Z,1692819062.405 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T19:31:02.406Z,1692819062.406 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2023-08-23T19:31:02.406Z,1692819062.406 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T19:31:02.417Z,1692819062.417 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-23T19:31:02.417Z,1692819062.417 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-23T19:31:02.832Z,1692819062.832 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-23T19:31:02.832Z,1692819062.832 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-08-23T19:31:02.833Z,1692819062.833 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-23T19:31:02.833Z,1692819062.833 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2023-08-23T19:31:03.233Z,1692819063.233 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T19:33:37.587Z,1692819217.587 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-23T19:33:37.587Z,1692819217.587 [Default:CheckIn:C.Wait] Stopped
2023-08-23T19:33:37.587Z,1692819217.587 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-23T19:33:37.587Z,1692819217.587 [Default:CheckIn:D] Running Loop=1
2023-08-23T19:33:37.987Z,1692819217.987 [Default:CheckIn:D] Stopped
2023-08-23T19:33:37.987Z,1692819217.987 [Default:CheckIn:E] Running Loop=1
2023-08-23T19:33:38.384Z,1692819218.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.734059 min 
2023-08-23T19:33:38.384Z,1692819218.384 [Default:CheckIn:E] Stopped
2023-08-23T19:33:38.384Z,1692819218.384 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-23T19:33:38.384Z,1692819218.384 [Default:CheckIn] Stopped
2023-08-23T19:33:38.384Z,1692819218.384 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-23T19:33:38.385Z,1692819218.385 [Default:CheckIn](INFO): Running loop #2
2023-08-23T19:33:38.385Z,1692819218.385 [Default:CheckIn] Running Loop=2
2023-08-23T19:33:38.385Z,1692819218.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-23T19:33:38.385Z,1692819218.385 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-23T19:34:00.244Z,1692819240.244 [CBIT](INFO): Clearing failed state for component DropWeight
2023-08-23T19:34:00.245Z,1692819240.245 [DropWeight] No Fault, FailCount= 1
2023-08-23T19:34:03.448Z,1692819243.448 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-08-23T19:34:03.448Z,1692819243.448 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T19:34:03.449Z,1692819243.449 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2023-08-23T19:34:03.450Z,1692819243.450 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T19:34:03.460Z,1692819243.460 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-23T19:34:03.461Z,1692819243.461 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-23T19:34:03.850Z,1692819243.850 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-23T19:34:03.850Z,1692819243.850 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-08-23T19:34:03.851Z,1692819243.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-23T19:34:03.851Z,1692819243.851 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2023-08-23T19:34:04.244Z,1692819244.244 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T19:37:04.453Z,1692819424.453 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-08-23T19:37:04.453Z,1692819424.453 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-23T19:37:04.454Z,1692819424.454 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2023-08-23T19:37:04.454Z,1692819424.454 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-23T19:37:04.473Z,1692819424.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-23T19:37:04.473Z,1692819424.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-23T19:37:04.875Z,1692819424.875 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-23T19:37:04.875Z,1692819424.875 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-08-23T19:37:04.876Z,1692819424.876 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-23T19:37:04.876Z,1692819424.876 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2023-08-23T19:37:05.254Z,1692819425.254 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-23T19:38:38.563Z,1692819518.563 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-08-23T19:33:38.4Z
2023-08-23T19:38:38.563Z,1692819518.563 [Default:CheckIn:Read_GPS] Stopped
2023-08-23T19:38:38.563Z,1692819518.563 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-23T19:38:46.387Z,1692819526.387 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230823T191849/Courier0007.lzma
2023-08-23T19:38:47.390Z,1692819527.390 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Courier0007.lzma.bak
2023-08-23T19:38:47.390Z,1692819527.390 [DataOverHttps](INFO): SBD MOMSN=18585350
2023-08-23T19:39:04.711Z,1692819544.711 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20230823T191849/Express0008.lzma
2023-08-23T19:39:05.714Z,1692819545.714 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Express0008.lzma.bak
2023-08-23T19:39:05.714Z,1692819545.714 [DataOverHttps](INFO): SBD MOMSN=18585358
2023-08-23T19:39:09.283Z,1692819549.283 [Default:CheckIn:Read_Iridium] Stopped
2023-08-23T19:39:09.283Z,1692819549.283 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-23T19:39:09.283Z,1692819549.283 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-23T19:39:19.768Z,1692819559.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193918.00,A,3648.17354,N,12147.28709,W,0.253,0.00,230823,,,A*73
2023-08-23T19:39:19.771Z,1692819559.771 [NAL9602](INFO): GPS fix at 20230823T193918: (36.802892, -121.788118)
2023-08-23T19:39:52.489Z,1692819592.489 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-23T19:40:01.441Z,1692819601.441 [CommandExec](IMPORTANT): got command ibit
2023-08-23T19:40:01.823Z,1692819601.823 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-08-23T19:40:01.823Z,1692819601.823 [IBIT](IMPORTANT): Beginning control surface checks.
2023-08-23T19:40:01.831Z,1692819601.831 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-23T19:40:03.399Z,1692819603.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194001.00,A,3648.17480,N,12147.28959,W,0.078,0.00,230823,,,A*7B
2023-08-23T19:40:03.401Z,1692819603.401 [NAL9602](INFO): GPS fix at 20230823T194001: (36.802913, -121.788160)
2023-08-23T19:40:04.693Z,1692819604.693 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-08-23T19:40:12.710Z,1692819612.710 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006415
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.005214
CHAN A3 (5V): -0.003113
CHAN B0 (3.3V): -0.000798
CHAN B1 (3.15aV): -0.001684
CHAN B2 (3.15bV): -0.001721
CHAN B3 (GND): -0.000389
OPEN: 0.004874
Full Scale: +/- 1 mA
2023-08-23T19:40:17.130Z,1692819617.130 [NAL9602](INFO): SBD MO Status=0, MOMSN=13497, MT Status=0, MTMSN=0
2023-08-23T19:40:17.130Z,1692819617.130 [NAL9602](INFO): No messages in MT queue
2023-08-23T19:40:18.347Z,1692819618.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194016.00,A,3648.17529,N,12147.28897,W,0.039,0.00,230823,,,A*79
2023-08-23T19:40:18.349Z,1692819618.349 [NAL9602](INFO): GPS fix at 20230823T194016: (36.802921, -121.788150)
2023-08-23T19:40:20.769Z,1692819620.769 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194019.00,A,3648.17541,N,12147.28882,W,0.019,0.00,230823,,,A*7E
2023-08-23T19:40:20.771Z,1692819620.771 [NAL9602](INFO): GPS fix at 20230823T194019: (36.802923, -121.788147)
2023-08-23T19:40:23.998Z,1692819623.998 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194022.00,A,3648.17546,N,12147.28866,W,0.019,0.00,230823,,,A*7B
2023-08-23T19:40:23.000Z,1692819624.000 [NAL9602](INFO): GPS fix at 20230823T194022: (36.802924, -121.788144)
2023-08-23T19:40:26.835Z,1692819626.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194025.00,A,3648.17546,N,12147.28856,W,0.039,0.00,230823,,,A*7D
2023-08-23T19:40:26.837Z,1692819626.837 [NAL9602](INFO): GPS fix at 20230823T194025: (36.802924, -121.788143)
2023-08-23T19:40:30.065Z,1692819630.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194028.00,A,3648.17542,N,12147.28845,W,0.019,0.00,230823,,,A*74
2023-08-23T19:40:30.067Z,1692819630.067 [NAL9602](INFO): GPS fix at 20230823T194028: (36.802924, -121.788141)
2023-08-23T19:40:32.891Z,1692819632.891 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194031.00,A,3648.17534,N,12147.28839,W,0.019,0.00,230823,,,A*76
2023-08-23T19:40:32.893Z,1692819632.893 [NAL9602](INFO): GPS fix at 20230823T194031: (36.802922, -121.788140)
2023-08-23T19:40:36.123Z,1692819636.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194034.00,A,3648.17521,N,12147.28833,W,0.019,0.00,230823,,,A*7D
2023-08-23T19:40:36.134Z,1692819636.134 [NAL9602](INFO): GPS fix at 20230823T194034: (36.802920, -121.788139)
2023-08-23T19:40:38.948Z,1692819638.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194037.00,A,3648.17513,N,12147.28826,W,0.039,0.00,230823,,,A*79
2023-08-23T19:40:38.950Z,1692819638.950 [NAL9602](INFO): GPS fix at 20230823T194037: (36.802919, -121.788138)
2023-08-23T19:40:41.774Z,1692819641.774 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194040.00,A,3648.17506,N,12147.28820,W,0.019,0.00,230823,,,A*79
2023-08-23T19:40:41.776Z,1692819641.776 [NAL9602](INFO): GPS fix at 20230823T194040: (36.802918, -121.788137)
2023-08-23T19:40:45.011Z,1692819645.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194043.00,A,3648.17502,N,12147.28818,W,0.097,0.00,230823,,,A*73
2023-08-23T19:40:45.013Z,1692819645.013 [NAL9602](INFO): GPS fix at 20230823T194043: (36.802917, -121.788136)
2023-08-23T19:40:47.834Z,1692819647.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194046.00,A,3648.17499,N,12147.28818,W,0.019,0.00,230823,,,A*73
2023-08-23T19:40:47.844Z,1692819647.844 [NAL9602](INFO): GPS fix at 20230823T194046: (36.802917, -121.788136)
2023-08-23T19:40:48.280Z,1692819648.280 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802917 Longitude: -121.788139
2023-08-23T19:40:48.658Z,1692819648.658 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.343000
2023-08-23T19:40:48.658Z,1692819648.658 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-08-23T19:40:48.659Z,1692819648.659 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-08-23T19:40:49.063Z,1692819649.063 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-08-23T19:40:49.063Z,1692819649.063 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-08-23T19:40:49.063Z,1692819649.063 [IBIT](IMPORTANT): Pressure:10.024822 PSI
2023-08-23T19:40:49.064Z,1692819649.064 [IBIT](IMPORTANT): Humidity:22.878769 %
2023-08-23T19:40:49.483Z,1692819649.483 [IBIT](IMPORTANT): Vehicle Pitch:-0.427350 degrees
2023-08-23T19:40:49.483Z,1692819649.483 [IBIT](IMPORTANT): Vehicle  Roll:-0.459660 degrees
2023-08-23T19:40:49.484Z,1692819649.484 [IBIT](IMPORTANT): Vehicle  Heading:122.070503 degrees
2023-08-23T19:40:49.898Z,1692819649.898 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-08-23T19:40:49.898Z,1692819649.898 [IBIT](IMPORTANT): buoyancyNeutral: 381.902679 cc
2023-08-23T19:40:49.898Z,1692819649.898 [IBIT](IMPORTANT): massDefault: -0.996935 cm
2023-08-23T19:40:49.899Z,1692819649.899 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2023-08-23T19:40:49.899Z,1692819649.899 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2023-08-23T19:40:49.899Z,1692819649.899 [IBIT](IMPORTANT): IBIT PASSED
2023-08-23T19:41:04.284Z,1692819664.284 [CommandLine](INFO): End of History
2023-08-23T19:41:04.907Z,1692819664.907 [CommandLine](INFO): End of History
2023-08-23T19:41:05.480Z,1692819665.480 [CommandLine](INFO): End of History
2023-08-23T19:41:12.306Z,1692819672.306 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2023-08-23T19:41:12.307Z,1692819672.307 [CommandExec](IMPORTANT): Onboard.Pressure 10.003768 psi
2023-08-23T19:41:19.112Z,1692819679.112 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2023-08-23T19:41:19.112Z,1692819679.112 [CommandExec](IMPORTANT): Onboard.Humidity 22.866560 %
2023-08-23T19:41:20.566Z,1692819680.566 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-23T19:44:09.880Z,1692819849.880 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-23T19:44:09.880Z,1692819849.880 [Default:CheckIn:C.Wait] Stopped
2023-08-23T19:44:09.880Z,1692819849.880 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-23T19:44:09.880Z,1692819849.880 [Default:CheckIn:D] Running Loop=1
2023-08-23T19:44:10.294Z,1692819850.294 [Default:CheckIn:D] Stopped
2023-08-23T19:44:10.294Z,1692819850.294 [Default:CheckIn:E] Running Loop=1
2023-08-23T19:44:10.701Z,1692819850.701 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.272510 min 
2023-08-23T19:44:10.701Z,1692819850.701 [Default:CheckIn:E] Stopped
2023-08-23T19:44:10.701Z,1692819850.701 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-23T19:44:10.701Z,1692819850.701 [Default:CheckIn] Stopped
2023-08-23T19:44:10.702Z,1692819850.702 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-23T19:44:10.702Z,1692819850.702 [Default:CheckIn](INFO): Running loop #3
2023-08-23T19:44:10.702Z,1692819850.702 [Default:CheckIn] Running Loop=3
2023-08-23T19:44:10.702Z,1692819850.702 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-23T19:44:10.702Z,1692819850.702 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-23T19:44:12.699Z,1692819852.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194410.00,A,3648.17518,N,12147.28827,W,0.039,0.00,230823,,,A*72
2023-08-23T19:44:12.701Z,1692819852.701 [NAL9602](INFO): GPS fix at 20230823T194410: (36.802920, -121.788138)
2023-08-23T19:44:12.719Z,1692819852.719 [Default:CheckIn:Read_GPS] Stopped
2023-08-23T19:44:12.719Z,1692819852.719 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-23T19:44:20.804Z,1692819860.804 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20230823T191849/Courier0010.lzma
2023-08-23T19:44:21.806Z,1692819861.806 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Courier0010.lzma.bak
2023-08-23T19:44:21.806Z,1692819861.806 [DataOverHttps](INFO): SBD MOMSN=18585398
2023-08-23T19:44:30.279Z,1692819870.279 [NAL9602](INFO): SBD MO Status=0, MOMSN=13498, MT Status=0, MTMSN=0
2023-08-23T19:44:30.279Z,1692819870.279 [NAL9602](INFO): No messages in MT queue
2023-08-23T19:44:39.325Z,1692819879.325 [DataOverHttps](INFO): Sending 966 bytes from file Logs/20230823T191849/Express0011.lzma
2023-08-23T19:44:40.326Z,1692819880.326 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Express0011.lzma.bak
2023-08-23T19:44:40.326Z,1692819880.326 [DataOverHttps](INFO): SBD MOMSN=18585401
2023-08-23T19:44:43.241Z,1692819883.241 [Default:CheckIn:Read_Iridium] Stopped
2023-08-23T19:44:43.241Z,1692819883.241 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-23T19:44:43.241Z,1692819883.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-23T19:45:00.985Z,1692819900.985 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-23T19:49:43.824Z,1692820183.824 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-23T19:49:43.824Z,1692820183.824 [Default:CheckIn:C.Wait] Stopped
2023-08-23T19:49:43.824Z,1692820183.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-23T19:49:43.825Z,1692820183.825 [Default:CheckIn:D] Running Loop=1
2023-08-23T19:49:44.233Z,1692820184.233 [Default:CheckIn:D] Stopped
2023-08-23T19:49:44.233Z,1692820184.233 [Default:CheckIn:E] Running Loop=1
2023-08-23T19:49:44.645Z,1692820184.645 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.838155 min 
2023-08-23T19:49:44.645Z,1692820184.645 [Default:CheckIn:E] Stopped
2023-08-23T19:49:44.645Z,1692820184.645 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-23T19:49:44.645Z,1692820184.645 [Default:CheckIn] Stopped
2023-08-23T19:49:44.645Z,1692820184.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-23T19:49:44.646Z,1692820184.646 [Default:CheckIn](INFO): Running loop #4
2023-08-23T19:49:44.646Z,1692820184.646 [Default:CheckIn] Running Loop=4
2023-08-23T19:49:44.646Z,1692820184.646 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-23T19:49:44.646Z,1692820184.646 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-23T19:49:46.635Z,1692820186.635 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194945.00,A,3648.16748,N,12147.28298,W,0.214,139.84,230823,,,A*7D
2023-08-23T19:49:46.637Z,1692820186.637 [NAL9602](INFO): GPS fix at 20230823T194945: (36.802791, -121.788050)
2023-08-23T19:49:46.697Z,1692820186.697 [Default:CheckIn:Read_GPS] Stopped
2023-08-23T19:49:46.697Z,1692820186.697 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-23T19:49:58.863Z,1692820198.863 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230823T191849/Courier0013.lzma
2023-08-23T19:49:59.866Z,1692820199.866 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Courier0013.lzma.bak
2023-08-23T19:49:59.866Z,1692820199.866 [DataOverHttps](INFO): SBD MOMSN=18585522
2023-08-23T19:50:18.663Z,1692820218.663 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230823T191849/Express0014.lzma
2023-08-23T19:50:19.666Z,1692820219.666 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Express0014.lzma.bak
2023-08-23T19:50:19.666Z,1692820219.666 [DataOverHttps](INFO): SBD MOMSN=18585525
2023-08-23T19:50:22.622Z,1692820222.622 [Default:CheckIn:Read_Iridium] Stopped
2023-08-23T19:50:22.622Z,1692820222.622 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-23T19:50:22.622Z,1692820222.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-23T19:54:49.296Z,1692820489.296 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-23T19:55:20.001Z,1692820520.001 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-23T19:55:23.243Z,1692820523.243 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-23T19:55:23.243Z,1692820523.243 [Default:CheckIn:C.Wait] Stopped
2023-08-23T19:55:23.243Z,1692820523.243 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-23T19:55:23.244Z,1692820523.244 [Default:CheckIn:D] Running Loop=1
2023-08-23T19:55:23.654Z,1692820523.654 [Default:CheckIn:D] Stopped
2023-08-23T19:55:23.654Z,1692820523.654 [Default:CheckIn:E] Running Loop=1
2023-08-23T19:55:24.060Z,1692820524.060 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.495176 min 
2023-08-23T19:55:24.061Z,1692820524.061 [Default:CheckIn:E] Stopped
2023-08-23T19:55:24.061Z,1692820524.061 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-23T19:55:24.061Z,1692820524.061 [Default:CheckIn] Stopped
2023-08-23T19:55:24.061Z,1692820524.061 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-23T19:55:24.061Z,1692820524.061 [Default:CheckIn](INFO): Running loop #5
2023-08-23T19:55:24.061Z,1692820524.061 [Default:CheckIn] Running Loop=5
2023-08-23T19:55:24.061Z,1692820524.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-23T19:55:24.061Z,1692820524.061 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-23T19:55:26.063Z,1692820526.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195525.00,A,3648.16979,N,12147.28405,W,0.428,139.84,230823,,,A*71
2023-08-23T19:55:26.065Z,1692820526.065 [NAL9602](INFO): GPS fix at 20230823T195525: (36.802830, -121.788067)
2023-08-23T19:55:26.093Z,1692820526.093 [Default:CheckIn:Read_GPS] Stopped
2023-08-23T19:55:26.093Z,1692820526.093 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-23T19:55:33.835Z,1692820533.835 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230823T191849/Courier0016.lzma
2023-08-23T19:55:34.838Z,1692820534.838 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Courier0016.lzma.bak
2023-08-23T19:55:34.838Z,1692820534.838 [DataOverHttps](INFO): SBD MOMSN=18585528
2023-08-23T19:55:56.488Z,1692820556.488 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230823T191849/Express0017.lzma
2023-08-23T19:55:57.490Z,1692820557.490 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Express0017.lzma.bak
2023-08-23T19:55:57.490Z,1692820557.490 [DataOverHttps](INFO): SBD MOMSN=18585531
2023-08-23T19:56:00.853Z,1692820560.853 [Default:CheckIn:Read_Iridium] Stopped
2023-08-23T19:56:00.853Z,1692820560.853 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-23T19:56:00.853Z,1692820560.853 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-23T20:00:28.349Z,1692820828.349 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-23T20:00:59.049Z,1692820859.049 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-23T20:01:01.487Z,1692820861.487 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-23T20:01:01.487Z,1692820861.487 [Default:CheckIn:C.Wait] Stopped
2023-08-23T20:01:01.487Z,1692820861.487 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-23T20:01:01.487Z,1692820861.487 [Default:CheckIn:D] Running Loop=1
2023-08-23T20:01:01.898Z,1692820861.898 [Default:CheckIn:D] Stopped
2023-08-23T20:01:01.898Z,1692820861.898 [Default:CheckIn:E] Running Loop=1
2023-08-23T20:01:02.308Z,1692820862.308 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.132576 min 
2023-08-23T20:01:02.309Z,1692820862.309 [Default:CheckIn:E] Stopped
2023-08-23T20:01:02.309Z,1692820862.309 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-23T20:01:02.309Z,1692820862.309 [Default:CheckIn] Stopped
2023-08-23T20:01:02.309Z,1692820862.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-23T20:01:02.309Z,1692820862.309 [Default:CheckIn](INFO): Running loop #6
2023-08-23T20:01:02.309Z,1692820862.309 [Default:CheckIn] Running Loop=6
2023-08-23T20:01:02.309Z,1692820862.309 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-23T20:01:02.309Z,1692820862.309 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-23T20:01:04.307Z,1692820864.307 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200103.00,A,3648.16380,N,12147.28440,W,0.175,0.00,230823,,,A*79
2023-08-23T20:01:04.309Z,1692820864.309 [NAL9602](INFO): GPS fix at 20230823T200103: (36.802730, -121.788073)
2023-08-23T20:01:04.350Z,1692820864.350 [Default:CheckIn:Read_GPS] Stopped
2023-08-23T20:01:04.350Z,1692820864.350 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-23T20:01:12.511Z,1692820872.511 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230823T191849/Courier0019.lzma
2023-08-23T20:01:13.514Z,1692820873.514 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Courier0019.lzma.bak
2023-08-23T20:01:13.514Z,1692820873.514 [DataOverHttps](INFO): SBD MOMSN=18585615
2023-08-23T20:01:32.672Z,1692820892.672 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230823T191849/Express0020.lzma
2023-08-23T20:01:33.674Z,1692820893.674 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Express0020.lzma.bak
2023-08-23T20:01:33.674Z,1692820893.674 [DataOverHttps](INFO): SBD MOMSN=18585619
2023-08-23T20:01:35.005Z,1692820895.005 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-23T20:01:35.088Z,1692820895.088 [NAL9602](ERROR): received: 
+CSQ:0

OK498, 0, 0, 0, 0

OK
2023-08-23T20:01:36.650Z,1692820896.650 [Default:CheckIn:Read_Iridium] Stopped
2023-08-23T20:01:36.650Z,1692820896.650 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-23T20:01:36.650Z,1692820896.650 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-23T20:04:01.737Z,1692821041.737 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-08-23T20:04:01.737Z,1692821041.737 [DropWeight] Hardware Fault, FailCount= 1
2023-08-23T20:04:01.737Z,1692821041.737 [DropWeight](ERROR): Hardware Fault
2023-08-23T20:04:01.785Z,1692821041.785 [CBIT](INFO): Critical error at 20230823T200401
2023-08-23T20:04:01.787Z,1692821041.787 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-08-23T20:04:01.787Z,1692821041.787 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-08-23T20:04:02.156Z,1692821042.156 [CBIT](INFO): Critical error at 20230823T200401
2023-08-23T20:06:06.577Z,1692821166.577 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-23T20:06:37.273Z,1692821197.273 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-23T20:06:37.287Z,1692821197.287 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-23T20:06:37.287Z,1692821197.287 [Default:CheckIn:C.Wait] Stopped
2023-08-23T20:06:37.287Z,1692821197.287 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-23T20:06:37.287Z,1692821197.287 [Default:CheckIn:D] Running Loop=1
2023-08-23T20:06:37.701Z,1692821197.701 [Default:CheckIn:D] Stopped
2023-08-23T20:06:37.701Z,1692821197.701 [Default:CheckIn:E] Running Loop=1
2023-08-23T20:06:38.096Z,1692821198.096 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.729289 min 
2023-08-23T20:06:38.096Z,1692821198.096 [Default:CheckIn:E] Stopped
2023-08-23T20:06:38.097Z,1692821198.097 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-23T20:06:38.097Z,1692821198.097 [Default:CheckIn] Stopped
2023-08-23T20:06:38.097Z,1692821198.097 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-23T20:06:38.097Z,1692821198.097 [Default:CheckIn](INFO): Running loop #7
2023-08-23T20:06:38.097Z,1692821198.097 [Default:CheckIn] Running Loop=7
2023-08-23T20:06:38.097Z,1692821198.097 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-23T20:06:38.097Z,1692821198.097 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-23T20:06:40.107Z,1692821200.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200639.00,A,3648.18545,N,12147.27531,W,0.583,37.85,230823,,,A*4A
2023-08-23T20:06:40.109Z,1692821200.109 [NAL9602](INFO): GPS fix at 20230823T200639: (36.803091, -121.787922)
2023-08-23T20:06:40.121Z,1692821200.121 [Default:CheckIn:Read_GPS] Stopped
2023-08-23T20:06:40.121Z,1692821200.121 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-23T20:06:48.563Z,1692821208.563 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230823T191849/Courier0022.lzma
2023-08-23T20:06:49.566Z,1692821209.566 [DataOverHttps](INFO): Moved sent file to Logs/20230823T191849/Courier0022.lzma.bak
2023-08-23T20:06:49.566Z,1692821209.566 [DataOverHttps](INFO): SBD MOMSN=18585623
2023-08-23T20:06:59.493Z,1692821219.493 [CommandExec](IMPORTANT): got command quit
2023-08-23T20:07:00.496Z,1692821220.496 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:00.496Z,1692821220.496 [CommandExec](INFO): Uninitializing the command executive.
2023-08-23T20:07:00.496Z,1692821220.496 [CommandExec](INFO): Uninitializing the command scheduler.
2023-08-23T20:07:00.497Z,1692821220.497 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:00.525Z,1692821220.525 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-08-23T20:07:00.525Z,1692821220.525 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-08-23T20:07:00.525Z,1692821220.525 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:00.526Z,1692821220.526 [NavChartDb](INFO): Join timeout helper Thread ID is 4034
2023-08-23T20:07:00.828Z,1692821220.828 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:00.828Z,1692821220.828 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:00.840Z,1692821220.840 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-08-23T20:07:00.840Z,1692821220.840 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:00.841Z,1692821220.841 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4035
2023-08-23T20:07:01.308Z,1692821221.308 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:01.308Z,1692821221.308 [WetLabsBB2FL](INFO): Powering down
2023-08-23T20:07:01.309Z,1692821221.309 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:01.312Z,1692821221.312 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-08-23T20:07:01.312Z,1692821221.312 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:01.312Z,1692821221.312 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4036
2023-08-23T20:07:01.548Z,1692821221.548 [CTD_Seabird](INFO): Powering down
2023-08-23T20:07:01.568Z,1692821221.568 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:01.569Z,1692821221.569 [CTD_Seabird](INFO): Powering down
2023-08-23T20:07:01.580Z,1692821221.580 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:01.596Z,1692821221.596 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-08-23T20:07:01.596Z,1692821221.596 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:01.597Z,1692821221.597 [Radio_Surface](INFO): Join timeout helper Thread ID is 4037
2023-08-23T20:07:01.644Z,1692821221.644 [Radio_Surface](INFO): Powering down
2023-08-23T20:07:01.645Z,1692821221.645 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:01.645Z,1692821221.645 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:01.656Z,1692821221.656 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-08-23T20:07:01.656Z,1692821221.656 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:01.657Z,1692821221.657 [Onboard](INFO): Join timeout helper Thread ID is 4038
2023-08-23T20:07:01.720Z,1692821221.720 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:01.721Z,1692821221.721 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:01.741Z,1692821221.741 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-08-23T20:07:01.741Z,1692821221.741 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:01.741Z,1692821221.741 [DataOverHttps](INFO): Join timeout helper Thread ID is 4039
2023-08-23T20:07:01.992Z,1692821221.992 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:01.993Z,1692821221.993 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.009Z,1692821222.009 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-08-23T20:07:02.009Z,1692821222.009 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.010Z,1692821222.010 [BackseatComponent](INFO): Join timeout helper Thread ID is 4040
2023-08-23T20:07:02.028Z,1692821222.028 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:02.028Z,1692821222.028 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.037Z,1692821222.037 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-08-23T20:07:02.037Z,1692821222.037 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.037Z,1692821222.037 [logger](INFO): Join timeout helper Thread ID is 4041
2023-08-23T20:07:02.037Z,1692821222.037 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:02.038Z,1692821222.038 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.039Z,1692821222.039 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-08-23T20:07:02.039Z,1692821222.039 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.039Z,1692821222.039 [CommandLine](INFO): Join timeout helper Thread ID is 4042
2023-08-23T20:07:02.080Z,1692821222.080 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:02.080Z,1692821222.080 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.097Z,1692821222.097 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-08-23T20:07:02.097Z,1692821222.097 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.097Z,1692821222.097 [CommandExec](INFO): Join timeout helper Thread ID is 4043
2023-08-23T20:07:02.098Z,1692821222.098 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-08-23T20:07:02.098Z,1692821222.098 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.099Z,1692821222.099 [controlThread](INFO): Join timeout helper Thread ID is 4044
2023-08-23T20:07:02.304Z,1692821222.304 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-23T20:07:02.304Z,1692821222.304 [controlThread](DEBUG): Uninitializing ControlThread
2023-08-23T20:07:02.305Z,1692821222.305 [AHRS_M2](INFO): Powering down
2023-08-23T20:07:02.377Z,1692821222.377 [NAL9602](INFO): Powering down
2023-08-23T20:07:02.379Z,1692821222.379 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-08-23T20:07:02.379Z,1692821222.379 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-08-23T20:07:02.380Z,1692821222.380 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-08-23T20:07:02.381Z,1692821222.381 [MissionManager](INFO): Uninitializing Mission Default
2023-08-23T20:07:02.381Z,1692821222.381 [Default] Stopped
2023-08-23T20:07:02.381Z,1692821222.381 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-23T20:07:02.381Z,1692821222.381 [Default:B.GoToSurface] Stopped
2023-08-23T20:07:02.381Z,1692821222.381 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-23T20:07:02.381Z,1692821222.381 [Default:CheckIn] Stopped
2023-08-23T20:07:02.382Z,1692821222.382 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-23T20:07:02.382Z,1692821222.382 [Default:CheckIn:Read_Iridium] Stopped
2023-08-23T20:07:02.384Z,1692821222.384 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-08-23T20:07:02.385Z,1692821222.385 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-08-23T20:07:02.385Z,1692821222.385 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-08-23T20:07:02.385Z,1692821222.385 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-08-23T20:07:02.385Z,1692821222.385 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-08-23T20:07:02.386Z,1692821222.386 [BuoyancyServo](INFO): Powering down
2023-08-23T20:07:02.400Z,1692821222.400 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-08-23T20:07:02.400Z,1692821222.400 [ElevatorServo](INFO): Powering down
2023-08-23T20:07:02.401Z,1692821222.401 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-08-23T20:07:02.401Z,1692821222.401 [MassServo](INFO): Powering down
2023-08-23T20:07:02.402Z,1692821222.402 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-08-23T20:07:02.402Z,1692821222.402 [RudderServo](INFO): Powering down
2023-08-23T20:07:02.403Z,1692821222.403 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-08-23T20:07:02.403Z,1692821222.403 [ThrusterHE](INFO): Powering down
2023-08-23T20:07:02.404Z,1692821222.404 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-08-23T20:07:02.404Z,1692821222.404 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-08-23T20:07:02.405Z,1692821222.405 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-08-23T20:07:02.405Z,1692821222.405 [CBIT](DEBUG): Powering off loads.
2023-08-23T20:07:02.416Z,1692821222.416 [CBIT](DEBUG): Disabling WDT.
2023-08-23T20:07:02.428Z,1692821222.428 [CBIT](DEBUG): Opening all GF detection circuits.
2023-08-23T20:07:02.429Z,1692821222.429 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.449Z,1692821222.449 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.453Z,1692821222.453 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.459Z,1692821222.459 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.502Z,1692821222.502 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.550Z,1692821222.550 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.554Z,1692821222.554 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.604Z,1692821222.604 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-23T20:07:02.650Z,1692821222.650 [logger ThreadHandler](INFO): Thread cancelled.