2022-03-29T18:58:48.277Z,1648580328.277 [Supervisor](DEBUG): Initializing supervisor.
2022-03-29T18:58:48.283Z,1648580328.283 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-03-29T18:58:48.284Z,1648580328.284 [SyncHandler](INFO): Protected caller Thread ID is 6590
2022-03-29T18:58:48.284Z,1648580328.284 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-03-29T18:58:48.285Z,1648580328.285 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-03-29T18:58:48.285Z,1648580328.285 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6591
2022-03-29T18:58:48.290Z,1648580328.290 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-03-29T18:58:48.308Z,1648580328.308 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-03-29T18:58:48.309Z,1648580328.309 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-03-29T18:58:48.310Z,1648580328.310 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6592
2022-03-29T18:58:48.314Z,1648580328.314 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-03-29T18:58:48.315Z,1648580328.315 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-03-29T18:58:48.315Z,1648580328.315 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6593
2022-03-29T18:58:48.317Z,1648580328.317 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-03-29T18:58:48.318Z,1648580328.318 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-03-29T18:58:48.319Z,1648580328.319 [logger ThreadHandler](INFO): Protected caller Thread ID is 6594
2022-03-29T18:58:48.322Z,1648580328.322 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-03-29T18:58:48.323Z,1648580328.323 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-03-29T18:58:48.325Z,1648580328.325 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-03-29T18:58:48.744Z,1648580328.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-03-29T18:58:48.745Z,1648580328.745 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-03-29T18:58:48.951Z,1648580328.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-03-29T18:58:48.951Z,1648580328.951 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-03-29T18:58:49.030Z,1648580329.030 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-03-29T18:58:49.241Z,1648580329.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-03-29T18:58:49.242Z,1648580329.242 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-03-29T18:58:49.319Z,1648580329.319 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-03-29T18:58:49.412Z,1648580329.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-03-29T18:58:49.413Z,1648580329.413 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-03-29T18:58:49.759Z,1648580329.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-03-29T18:58:49.759Z,1648580329.759 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-03-29T18:58:49.882Z,1648580329.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-03-29T18:58:49.883Z,1648580329.883 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-03-29T18:58:50.435Z,1648580330.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-03-29T18:58:50.436Z,1648580330.436 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-03-29T18:58:50.625Z,1648580330.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-03-29T18:58:50.626Z,1648580330.626 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-03-29T18:58:51.001Z,1648580331.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-03-29T18:58:51.001Z,1648580331.001 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-03-29T18:58:51.269Z,1648580331.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-03-29T18:58:51.270Z,1648580331.270 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-03-29T18:58:51.378Z,1648580331.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-03-29T18:58:51.379Z,1648580331.379 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-03-29T18:58:52.065Z,1648580332.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-03-29T18:58:52.066Z,1648580332.066 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-03-29T18:58:52.406Z,1648580332.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-03-29T18:58:52.407Z,1648580332.407 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-03-29T18:58:52.709Z,1648580332.709 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-03-29T18:58:52.712Z,1648580332.712 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-03-29T18:58:52.712Z,1648580332.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-03-29T18:58:53.256Z,1648580333.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-03-29T18:58:53.399Z,1648580333.399 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-03-29T18:58:53.643Z,1648580333.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-03-29T18:58:53.728Z,1648580333.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-03-29T18:58:53.990Z,1648580333.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-03-29T18:58:53.991Z,1648580333.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-03-29T18:58:54.092Z,1648580334.092 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-03-29T18:58:54.202Z,1648580334.202 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-03-29T18:58:54.293Z,1648580334.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-03-29T18:58:54.435Z,1648580334.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-03-29T18:58:54.532Z,1648580334.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-03-29T18:58:54.623Z,1648580334.623 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2022-03-29T18:58:54.624Z,1648580334.624 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-03-29T18:58:54.643Z,1648580334.643 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-03-29T18:58:54.654Z,1648580334.654 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-03-29T18:58:54.655Z,1648580334.655 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-03-29T18:58:54.751Z,1648580334.751 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-03-29T18:58:54.752Z,1648580334.752 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-03-29T18:58:54.805Z,1648580334.805 [VerticalControl](DEBUG): Construct VerticalControl.
2022-03-29T18:58:54.864Z,1648580334.864 [VerticalControl] Loaded
2022-03-29T18:58:54.865Z,1648580334.865 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-03-29T18:58:54.868Z,1648580334.868 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-03-29T18:58:54.906Z,1648580334.906 [HorizontalControl] Loaded
2022-03-29T18:58:54.907Z,1648580334.907 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-03-29T18:58:54.909Z,1648580334.909 [SpeedControl](DEBUG): Construct SpeedControl.
2022-03-29T18:58:54.912Z,1648580334.912 [SpeedControl] Loaded
2022-03-29T18:58:54.912Z,1648580334.912 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-03-29T18:58:54.915Z,1648580334.915 [LoopControl](DEBUG): Construct LoopControl.
2022-03-29T18:58:54.915Z,1648580334.915 [LoopControl] Loaded
2022-03-29T18:58:54.916Z,1648580334.916 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-03-29T18:58:54.916Z,1648580334.916 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-03-29T18:58:54.917Z,1648580334.917 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-03-29T18:58:55.065Z,1648580335.065 [BuoyancyServo] Loaded
2022-03-29T18:58:55.065Z,1648580335.065 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-03-29T18:58:55.084Z,1648580335.084 [ElevatorServo] Loaded
2022-03-29T18:58:55.085Z,1648580335.085 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-03-29T18:58:55.103Z,1648580335.103 [MassServo] Loaded
2022-03-29T18:58:55.104Z,1648580335.104 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-03-29T18:58:55.121Z,1648580335.121 [RudderServo] Loaded
2022-03-29T18:58:55.121Z,1648580335.121 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-03-29T18:58:55.136Z,1648580335.136 [ThrusterHE] Loaded
2022-03-29T18:58:55.136Z,1648580335.136 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-03-29T18:58:55.137Z,1648580335.137 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-03-29T18:58:55.137Z,1648580335.137 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-03-29T18:58:55.188Z,1648580335.188 [DepthRateCalculator] Loaded
2022-03-29T18:58:55.189Z,1648580335.189 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-03-29T18:58:55.193Z,1648580335.193 [PitchRateCalculator] Loaded
2022-03-29T18:58:55.194Z,1648580335.194 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-03-29T18:58:55.203Z,1648580335.203 [SpeedCalculator] Loaded
2022-03-29T18:58:55.203Z,1648580335.203 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-03-29T18:58:55.219Z,1648580335.219 [VerticalHomogeneityIndexCalculator] Loaded
2022-03-29T18:58:55.219Z,1648580335.219 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2022-03-29T18:58:55.223Z,1648580335.223 [YawRateCalculator] Loaded
2022-03-29T18:58:55.224Z,1648580335.224 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-03-29T18:58:55.241Z,1648580335.241 [ElevatorOffsetCalculator] Loaded
2022-03-29T18:58:55.241Z,1648580335.241 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-03-29T18:58:55.242Z,1648580335.242 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-03-29T18:58:55.242Z,1648580335.242 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-03-29T18:58:55.353Z,1648580335.353 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-03-29T18:58:55.354Z,1648580335.354 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-03-29T18:58:55.367Z,1648580335.367 [NavChart] Loaded
2022-03-29T18:58:55.367Z,1648580335.367 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-03-29T18:58:55.372Z,1648580335.372 [UniversalFixResidualReporter] Loaded
2022-03-29T18:58:55.373Z,1648580335.373 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-03-29T18:58:55.373Z,1648580335.373 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-03-29T18:58:55.374Z,1648580335.374 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-03-29T18:58:55.779Z,1648580335.779 [AHRS_M2] Loaded
2022-03-29T18:58:55.780Z,1648580335.780 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-03-29T18:58:55.815Z,1648580335.815 [BackseatComponent] Loaded
2022-03-29T18:58:55.816Z,1648580335.816 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-03-29T18:58:55.817Z,1648580335.817 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408CC4E0
2022-03-29T18:58:55.817Z,1648580335.817 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6676
2022-03-29T18:58:55.820Z,1648580335.820 [LcmUniversalReporter] Loaded
2022-03-29T18:58:55.820Z,1648580335.820 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-03-29T18:58:56.641Z,1648580336.641 [BPC1] Loaded
2022-03-29T18:58:56.641Z,1648580336.641 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-03-29T18:58:56.712Z,1648580336.712 [DataOverHttps] Loaded
2022-03-29T18:58:56.713Z,1648580336.713 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-03-29T18:58:56.714Z,1648580336.714 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FC4E0
2022-03-29T18:58:56.714Z,1648580336.714 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6677
2022-03-29T18:58:56.734Z,1648580336.734 [Depth_Keller] Loaded
2022-03-29T18:58:56.734Z,1648580336.734 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-03-29T18:58:56.739Z,1648580336.739 [DropWeight] Loaded
2022-03-29T18:58:56.739Z,1648580336.739 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-03-29T18:58:56.798Z,1648580336.798 [NAL9602] Loaded
2022-03-29T18:58:56.798Z,1648580336.798 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-03-29T18:58:56.827Z,1648580336.827 [Onboard] Loaded
2022-03-29T18:58:56.827Z,1648580336.827 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-03-29T18:58:56.828Z,1648580336.828 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092C4E0
2022-03-29T18:58:56.828Z,1648580336.828 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6678
2022-03-29T18:58:56.840Z,1648580336.840 [Power24vConverter] Loaded
2022-03-29T18:58:56.840Z,1648580336.840 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-03-29T18:58:56.853Z,1648580336.853 [Radio_Surface] Loaded
2022-03-29T18:58:56.853Z,1648580336.853 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-03-29T18:58:56.855Z,1648580336.855 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0
2022-03-29T18:58:56.855Z,1648580336.855 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6679
2022-03-29T18:58:56.856Z,1648580336.856 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-03-29T18:58:56.856Z,1648580336.856 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-03-29T18:58:56.929Z,1648580336.929 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC
2022-03-29T18:58:56.929Z,1648580336.929 [StratificationFrontDetector](DEBUG): (re)initializing
2022-03-29T18:58:56.929Z,1648580336.929 [StratificationFrontDetector] Loaded
2022-03-29T18:58:56.930Z,1648580336.930 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2022-03-29T18:58:56.930Z,1648580336.930 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-03-29T18:58:56.931Z,1648580336.931 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-03-29T18:58:57.038Z,1648580337.038 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-03-29T18:58:57.039Z,1648580337.039 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-03-29T18:58:57.066Z,1648580337.066 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-03-29T18:58:57.066Z,1648580337.066 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-03-29T18:58:57.239Z,1648580337.239 [CTD_Seabird] Loaded
2022-03-29T18:58:57.239Z,1648580337.239 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-03-29T18:58:57.240Z,1648580337.240 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A8B4E0
2022-03-29T18:58:57.241Z,1648580337.241 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6680
2022-03-29T18:58:57.260Z,1648580337.260 [PAR_Licor] Loaded
2022-03-29T18:58:57.261Z,1648580337.261 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-03-29T18:58:57.291Z,1648580337.291 [WetLabsBB2FL] Loaded
2022-03-29T18:58:57.291Z,1648580337.291 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-03-29T18:58:57.292Z,1648580337.292 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ABB4E0
2022-03-29T18:58:57.293Z,1648580337.293 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6681
2022-03-29T18:58:57.294Z,1648580337.294 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-03-29T18:58:57.294Z,1648580337.294 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-03-29T18:58:57.789Z,1648580337.789 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-03-29T18:58:57.790Z,1648580337.790 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-03-29T18:58:58.104Z,1648580338.104 [SBIT](DEBUG): Construct Startup Built In Test.
2022-03-29T18:58:58.177Z,1648580338.177 [SBIT] Loaded
2022-03-29T18:58:58.177Z,1648580338.177 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-03-29T18:58:58.180Z,1648580338.180 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-03-29T18:58:58.206Z,1648580338.206 [IBIT] Loaded
2022-03-29T18:58:58.207Z,1648580338.207 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-03-29T18:58:58.212Z,1648580338.212 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-03-29T18:58:58.314Z,1648580338.314 [CBIT] Loaded
2022-03-29T18:58:58.315Z,1648580338.315 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-03-29T18:58:58.315Z,1648580338.315 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-03-29T18:58:58.321Z,1648580338.321 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-03-29T18:58:58.324Z,1648580338.324 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-03-29T18:58:58.335Z,1648580338.335 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-03-29T18:58:58.336Z,1648580338.336 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA54E0
2022-03-29T18:58:58.336Z,1648580338.336 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6682
2022-03-29T18:58:58.341Z,1648580338.341 [Supervisor](INFO): Main Thread ID is 6417
2022-03-29T18:58:58.341Z,1648580338.341 [Supervisor](DEBUG): Running supervisor.
2022-03-29T18:58:58.342Z,1648580338.342 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6683
2022-03-29T18:58:58.342Z,1648580338.342 [CommandExec](INFO): Initializing the command executive.
2022-03-29T18:58:58.344Z,1648580338.344 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6684
2022-03-29T18:58:58.346Z,1648580338.346 [controlThread ThreadHandler](INFO): Handler Thread ID is 6685
2022-03-29T18:58:58.346Z,1648580338.346 [controlThread](DEBUG): Initializing ControlThread
2022-03-29T18:58:58.347Z,1648580338.347 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-03-29T18:58:58.349Z,1648580338.349 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-03-29T18:58:58.350Z,1648580338.350 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-03-29T18:58:58.350Z,1648580338.350 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-03-29T18:58:58.352Z,1648580338.352 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-03-29T18:58:58.352Z,1648580338.352 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-03-29T18:58:58.352Z,1648580338.352 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-03-29T18:58:58.353Z,1648580338.353 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2022-03-29T18:58:58.353Z,1648580338.353 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-03-29T18:58:58.354Z,1648580338.354 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-03-29T18:58:58.355Z,1648580338.355 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-03-29T18:58:58.355Z,1648580338.355 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-03-29T18:58:58.362Z,1648580338.362 [SBIT](INFO): Initialize SBIT Component.
2022-03-29T18:58:58.363Z,1648580338.363 [SBIT](IMPORTANT): git: 2022-03-09-22-g95795f62b
2022-03-29T18:58:58.363Z,1648580338.363 [SBIT](INFO): git hash: 95795f62b7f9634d3632c5cbad46641087bc1b35
2022-03-29T18:58:58.363Z,1648580338.363 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-03-29T18:58:58.364Z,1648580338.364 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-03-29T18:58:58.365Z,1648580338.365 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2022-03-29T18:58:58.366Z,1648580338.366 [IBIT](INFO): Initialize IBIT Component.
2022-03-29T18:58:58.367Z,1648580338.367 [CBIT](DEBUG): Initialize CBIT Component.
2022-03-29T18:58:58.368Z,1648580338.368 [logger ThreadHandler](INFO): Handler Thread ID is 6686
2022-03-29T18:58:58.378Z,1648580338.378 [CBIT](DEBUG): Initialized mux pins.
2022-03-29T18:58:58.378Z,1648580338.378 [CBIT](DEBUG): Initializing the watchdog timer.
2022-03-29T18:58:58.386Z,1648580338.386 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6687
2022-03-29T18:58:58.398Z,1648580338.398 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6688
2022-03-29T18:58:58.399Z,1648580338.399 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-03-29T18:58:58.402Z,1648580338.402 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-03-29T18:58:58.402Z,1648580338.402 [CBIT](DEBUG): Initializing heartbeat.
2022-03-29T18:58:58.410Z,1648580338.410 [Onboard ThreadHandler](INFO): Handler Thread ID is 6689
2022-03-29T18:58:58.428Z,1648580338.428 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6690
2022-03-29T18:58:58.447Z,1648580338.447 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6691
2022-03-29T18:58:58.447Z,1648580338.447 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-29T18:58:58.451Z,1648580338.451 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6693
2022-03-29T18:58:58.454Z,1648580338.454 [WetLabsBB2FL](INFO): Powering up
2022-03-29T18:58:58.455Z,1648580338.455 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6695
2022-03-29T18:58:58.459Z,1648580338.459 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-03-29T18:58:58.459Z,1648580338.459 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-03-29T18:58:58.459Z,1648580338.459 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-03-29T18:58:58.459Z,1648580338.459 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-03-29T18:58:58.460Z,1648580338.460 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-03-29T18:58:58.460Z,1648580338.460 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-03-29T18:58:58.460Z,1648580338.460 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-03-29T18:58:58.460Z,1648580338.460 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-03-29T18:58:58.460Z,1648580338.460 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-03-29T18:58:58.460Z,1648580338.460 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-03-29T18:58:58.461Z,1648580338.461 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-03-29T18:58:58.461Z,1648580338.461 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-03-29T18:58:58.461Z,1648580338.461 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-03-29T18:58:58.461Z,1648580338.461 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-03-29T18:58:58.462Z,1648580338.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-03-29T18:58:58.462Z,1648580338.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-03-29T18:58:58.474Z,1648580338.474 [CBIT](DEBUG): Deactivating GF circuits.
2022-03-29T18:58:58.474Z,1648580338.474 [CBIT](DEBUG): Deactivating emergency mode.
2022-03-29T18:58:58.510Z,1648580338.510 [CBIT](DEBUG): Backplane powered.
2022-03-29T18:58:58.511Z,1648580338.511 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-03-29T18:58:58.524Z,1648580338.524 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-03-29T18:58:58.544Z,1648580338.544 [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>


2022-03-29T18:58:58.545Z,1648580338.545 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-03-29T18:58:58.618Z,1648580338.618 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-03-29T18:58:58.619Z,1648580338.619 [Default:A.Wait](DEBUG): Construct Wait.
2022-03-29T18:58:58.650Z,1648580338.650 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-03-29T18:58:58.703Z,1648580338.703 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-03-29T18:58:58.705Z,1648580338.705 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-03-29T18:58:58.727Z,1648580338.727 [Default:E.Execute](DEBUG): Construct Execute.
2022-03-29T18:58:58.731Z,1648580338.731 [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="P2H">
                <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>


2022-03-29T18:58:58.736Z,1648580338.736 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,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,
2022-03-29T18:58:58.750Z,1648580338.750 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-03-29T18:58:58.832Z,1648580338.832 [Power24vConverter](INFO): Powering up.
2022-03-29T18:58:58.834Z,1648580338.834 [Radio_Surface](INFO): Powering up
2022-03-29T18:58:58.879Z,1648580338.879 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-03-29T18:58:58.890Z,1648580338.890 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-03-29T18:58:58.891Z,1648580338.891 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-03-29T18:58:58.898Z,1648580338.898 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-03-29T18:58:58.899Z,1648580338.899 [MassServo](DEBUG): Initializing EZServoServo.
2022-03-29T18:58:58.906Z,1648580338.906 [MassServo](DEBUG): Initializing MassServo.
2022-03-29T18:58:58.907Z,1648580338.907 [RudderServo](DEBUG): Initializing EZServoServo.
2022-03-29T18:58:58.914Z,1648580338.914 [RudderServo](DEBUG): Initializing RudderServo.
2022-03-29T18:58:58.915Z,1648580338.915 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-03-29T18:58:58.922Z,1648580338.922 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-03-29T18:58:59.167Z,1648580339.167 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-03-29T18:58:59.167Z,1648580339.167 [DropWeight] Hardware Fault, FailCount= 1
2022-03-29T18:58:59.167Z,1648580339.167 [DropWeight](ERROR): Hardware Fault
2022-03-29T18:58:59.214Z,1648580339.214 [CommandExec](FAULT): Scheduling is paused
2022-03-29T18:58:59.214Z,1648580339.214 [CBIT](INFO): Critical error at 20220329T185859
2022-03-29T18:58:59.214Z,1648580339.214 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-03-29T18:58:59.217Z,1648580339.217 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-03-29T18:58:59.217Z,1648580339.217 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-03-29T18:58:59.639Z,1648580339.639 [CBIT](INFO): Critical error at 20220329T185859
2022-03-29T18:59:00.562Z,1648580340.562 [WetLabsBB2FL](INFO): Powering down
2022-03-29T18:59:04.719Z,1648580344.719 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-03-29T18:59:27.023Z,1648580367.023 [NAL9602](INFO): Powering up NAL9602
2022-03-29T18:59:37.931Z,1648580377.931 [NAL9602](INFO): NAL9602 initialized
2022-03-29T18:59:50.931Z,1648580390.931 [SBIT](IMPORTANT): Beginning Startup BIT
2022-03-29T18:59:50.935Z,1648580390.935 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-29T19:00:01.866Z,1648580401.866 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.017704
CHAN A1 (24V): 0.168487
CHAN A2 (12V): -0.004160
CHAN A3 (5V): -0.003340
CHAN B0 (3.3V): -0.000849
CHAN B1 (3.15aV): -0.000835
CHAN B2 (3.15bV): -0.001350
CHAN B3 (GND): -0.000853
OPEN: 0.004731
Full Scale: +/- 1 mA
2022-03-29T19:00:44.747Z,1648580444.747 [SBIT](IMPORTANT): SBIT PASSED
2022-03-29T19:00:44.747Z,1648580444.747 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-03-29T19:00:44.748Z,1648580444.748 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=32 count;

2022-03-29T19:00:44.748Z,1648580444.748 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 hour;

2022-03-29T19:00:44.748Z,1648580444.748 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2022-03-29T19:00:44.748Z,1648580444.748 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2022-03-29T19:00:44.748Z,1648580444.748 [SBIT](IMPORTANT): CBIT.gf24Offset=-18.106 microampere;

2022-03-29T19:00:44.748Z,1648580444.748 [SBIT](IMPORTANT): CBIT.stopDepth=295 meter;

2022-03-29T19:00:44.748Z,1648580444.748 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;

2022-03-29T19:00:44.749Z,1648580444.749 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-03-29T19:00:44.749Z,1648580444.749 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius;

2022-03-29T19:00:44.749Z,1648580444.749 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-03-29T19:00:44.749Z,1648580444.749 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2022-03-29T19:00:44.749Z,1648580444.749 [SBIT](IMPORTANT): HorizontalControl.rudDeadband=0.001 degree;

2022-03-29T19:00:44.749Z,1648580444.749 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2022-03-29T19:00:44.749Z,1648580444.749 [SBIT](IMPORTANT): PAR_Licor.parCal=1.38207 none;

2022-03-29T19:00:44.749Z,1648580444.749 [SBIT](IMPORTANT): RudderServo.deviationAngle=0.1 degree;

2022-03-29T19:00:44.750Z,1648580444.750 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2022-03-29T19:00:44.750Z,1648580444.750 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=255.152614 cubic_centimeter;

2022-03-29T19:00:44.750Z,1648580444.750 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.001 degree;

2022-03-29T19:00:44.750Z,1648580444.750 [SBIT](IMPORTANT): VerticalControl.massDefault=8.108730 millimeter;

2022-03-29T19:00:44.750Z,1648580444.750 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2022-03-29T19:00:44.750Z,1648580444.750 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool;

2022-03-29T19:00:45.025Z,1648580445.025 [MissionManager](IMPORTANT): Started mission Startup
2022-03-29T19:00:45.025Z,1648580445.025 [Startup] Running Loop=1
2022-03-29T19:00:45.025Z,1648580445.025 [Startup](DEBUG): Aggregate::initialize Startup
2022-03-29T19:00:45.025Z,1648580445.025 [Startup:A.GoToSurface] Running Loop=1
2022-03-29T19:00:45.025Z,1648580445.025 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-03-29T19:00:45.026Z,1648580445.026 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-03-29T19:00:45.027Z,1648580445.027 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-03-29T19:00:45.027Z,1648580445.027 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-03-29T19:00:45.027Z,1648580445.027 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-03-29T19:00:45.028Z,1648580445.028 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-03-29T19:00:45.029Z,1648580445.029 [Startup:StartupSatComms] Running Loop=1
2022-03-29T19:00:45.029Z,1648580445.029 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-03-29T19:00:45.029Z,1648580445.029 [Startup:StartupSatComms:A] Running Loop=1
2022-03-29T19:00:45.478Z,1648580445.478 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-03-29T19:01:27.062Z,1648580487.062 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:01:28.532Z,1648580488.532 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004535
2022-03-29T19:01:41.183Z,1648580501.183 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:01:45.239Z,1648580505.239 [Startup:StartupSatComms:A](INFO): Timed out from 2022-03-29T19:00:45.0Z
2022-03-29T19:01:45.239Z,1648580505.239 [Startup:StartupSatComms:A] Stopped
2022-03-29T19:01:45.239Z,1648580505.239 [Startup:StartupSatComms:B] Running Loop=1
2022-03-29T19:01:45.649Z,1648580505.649 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-03-29T19:01:52.813Z,1648580512.813 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220329T183549/Courier0010.lzma
2022-03-29T19:01:53.816Z,1648580513.816 [DataOverHttps](INFO): Moved sent file to Logs/20220329T183549/Courier0010.lzma.bak
2022-03-29T19:01:53.816Z,1648580513.816 [DataOverHttps](INFO): SBD MOMSN=16608712
2022-03-29T19:01:54.919Z,1648580514.919 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:01:58.607Z,1648580518.607 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-03-29T19:01:58.607Z,1648580518.607 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-29T19:01:58.626Z,1648580518.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-29T19:01:59.031Z,1648580519.031 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-29T19:01:59.031Z,1648580519.031 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-03-29T19:02:09.059Z,1648580529.059 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:02:09.570Z,1648580529.570 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20220329T185848/Courier0000.lzma
2022-03-29T19:02:10.572Z,1648580530.572 [DataOverHttps](INFO): Moved sent file to Logs/20220329T185848/Courier0000.lzma.bak
2022-03-29T19:02:10.572Z,1648580530.572 [DataOverHttps](INFO): SBD MOMSN=16608714
2022-03-29T19:02:22.391Z,1648580542.391 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:02:27.149Z,1648580547.149 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220329T183549/Express0011.lzma
2022-03-29T19:02:28.152Z,1648580548.152 [DataOverHttps](INFO): Moved sent file to Logs/20220329T183549/Express0011.lzma.bak
2022-03-29T19:02:28.152Z,1648580548.152 [DataOverHttps](INFO): SBD MOMSN=16608721
2022-03-29T19:02:35.724Z,1648580555.724 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:02:44.872Z,1648580564.872 [DataOverHttps](INFO): Sending 1183 bytes from file Logs/20220329T185848/Express0001.lzma
2022-03-29T19:02:45.491Z,1648580565.491 [Startup:StartupSatComms:B](INFO): Timed out from 2022-03-29T19:01:45.2Z
2022-03-29T19:02:45.491Z,1648580565.491 [Startup:StartupSatComms:B] Stopped
2022-03-29T19:02:45.491Z,1648580565.491 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-03-29T19:02:45.491Z,1648580565.491 [Startup:StartupSatComms] Stopped
2022-03-29T19:02:45.492Z,1648580565.492 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-03-29T19:02:45.492Z,1648580565.492 [Startup](INFO): Completed Startup
2022-03-29T19:02:45.492Z,1648580565.492 [MissionManager](INFO): Startup is completed.
2022-03-29T19:02:45.493Z,1648580565.493 [MissionManager](INFO): Uninitializing Mission Startup
2022-03-29T19:02:45.493Z,1648580565.493 [Startup] Stopped
2022-03-29T19:02:45.493Z,1648580565.493 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-03-29T19:02:45.493Z,1648580565.493 [Startup:A.GoToSurface] Stopped
2022-03-29T19:02:45.493Z,1648580565.493 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-03-29T19:02:45.847Z,1648580565.847 [MissionManager](IMPORTANT): Started mission Default
2022-03-29T19:02:45.847Z,1648580565.847 [Default] Running Loop=1
2022-03-29T19:02:45.847Z,1648580565.847 [Default](DEBUG): Aggregate::initialize Default
2022-03-29T19:02:45.847Z,1648580565.847 [Default:B.GoToSurface] Running Loop=1
2022-03-29T19:02:45.847Z,1648580565.847 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-03-29T19:02:45.848Z,1648580565.848 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-03-29T19:02:45.848Z,1648580565.848 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-03-29T19:02:45.848Z,1648580565.848 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-03-29T19:02:45.849Z,1648580565.849 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-03-29T19:02:45.849Z,1648580565.849 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-03-29T19:02:45.849Z,1648580565.849 [Default:A.Wait] Running Loop=1
2022-03-29T19:02:45.849Z,1648580565.849 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-03-29T19:02:45.872Z,1648580565.872 [DataOverHttps](INFO): Moved sent file to Logs/20220329T185848/Express0001.lzma.bak
2022-03-29T19:02:45.872Z,1648580565.872 [DataOverHttps](INFO): SBD MOMSN=16608729
2022-03-29T19:02:49.056Z,1648580569.056 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:02:59.226Z,1648580579.226 [Default:A.Wait](INFO): Done Waiting.
2022-03-29T19:02:59.226Z,1648580579.226 [Default:A.Wait] Stopped
2022-03-29T19:02:59.226Z,1648580579.226 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-03-29T19:02:59.562Z,1648580579.562 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-03-29T19:02:59.580Z,1648580579.580 [Default:CheckIn] Running Loop=1
2022-03-29T19:02:59.580Z,1648580579.580 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-29T19:02:59.580Z,1648580579.580 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-29T19:02:59.992Z,1648580579.992 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-03-29T19:03:02.388Z,1648580582.388 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:03:15.315Z,1648580595.315 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:03:28.669Z,1648580608.669 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:03:41.979Z,1648580621.979 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-03-29T19:03:53.314Z,1648580633.314 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 4.
2022-03-29T19:03:53.317Z,1648580633.317 [BPC1](INFO): Received data from all battery sticks.
2022-03-29T19:04:41.371Z,1648580681.371 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-03-29T19:04:59.556Z,1648580699.556 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-03-29T19:04:59.556Z,1648580699.556 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-29T19:04:59.591Z,1648580699.591 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-29T19:04:59.995Z,1648580699.995 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-29T19:04:59.995Z,1648580699.995 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-03-29T19:06:23.315Z,1648580783.315 [CommandExec](IMPORTANT): got command ibit
2022-03-29T19:06:23.634Z,1648580783.634 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-03-29T19:06:23.634Z,1648580783.634 [IBIT](IMPORTANT): Beginning control surface checks.
2022-03-29T19:06:23.637Z,1648580783.637 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-29T19:06:30.861Z,1648580790.861 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,190629.00,29,03,2022,00,00*69

2022-03-29T19:06:33.684Z,1648580793.684 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,190632.00,29,03,2022,00,00*63

2022-03-29T19:06:34.573Z,1648580794.573 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.020164
CHAN A1 (24V): 0.167978
CHAN A2 (12V): -0.004361
CHAN A3 (5V): -0.002511
CHAN B0 (3.3V): -0.000502
CHAN B1 (3.15aV): -0.000460
CHAN B2 (3.15bV): -0.002083
CHAN B3 (GND): -0.000650
OPEN: 0.004737
Full Scale: +/- 1 mA
2022-03-29T19:07:15.696Z,1648580835.696 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,190714.00,29,03,2022,00,00*66

2022-03-29T19:08:00.572Z,1648580880.572 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-03-29T19:08:00.572Z,1648580880.572 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-29T19:08:00.638Z,1648580880.638 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-29T19:08:01.034Z,1648580881.034 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-29T19:08:01.034Z,1648580881.034 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-03-29T19:08:14.711Z,1648580894.711 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-03-29T19:08:15.128Z,1648580895.128 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.660000
2022-03-29T19:08:15.128Z,1648580895.128 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-03-29T19:08:15.128Z,1648580895.128 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-03-29T19:08:15.518Z,1648580895.518 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-03-29T19:08:15.518Z,1648580895.518 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-03-29T19:08:15.519Z,1648580895.519 [IBIT](IMPORTANT): Pressure:7.806720 PSI
2022-03-29T19:08:15.519Z,1648580895.519 [IBIT](IMPORTANT): Humidity:16.157978 %
2022-03-29T19:08:15.923Z,1648580895.923 [IBIT](IMPORTANT): Vehicle Pitch:-1.762096 degrees
2022-03-29T19:08:15.923Z,1648580895.923 [IBIT](IMPORTANT): Vehicle  Roll:-3.340655 degrees
2022-03-29T19:08:15.923Z,1648580895.923 [IBIT](IMPORTANT): Vehicle  Heading:197.259933 degrees
2022-03-29T19:08:16.333Z,1648580896.333 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-03-29T19:08:16.333Z,1648580896.333 [IBIT](IMPORTANT): buoyancyNeutral: 255.152618 cc
2022-03-29T19:08:16.333Z,1648580896.333 [IBIT](IMPORTANT): massDefault: 0.810873 cm
2022-03-29T19:08:16.334Z,1648580896.334 [IBIT](IMPORTANT): stopDepth: 295.000000 m
2022-03-29T19:08:16.334Z,1648580896.334 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2022-03-29T19:08:16.334Z,1648580896.334 [IBIT](IMPORTANT): IBIT FAILED
2022-03-29T19:08:16.719Z,1648580896.719 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-29T19:02:59.6Z
2022-03-29T19:08:16.720Z,1648580896.720 [Default:CheckIn:Read_GPS] Stopped
2022-03-29T19:08:16.720Z,1648580896.720 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-29T19:08:17.113Z,1648580897.113 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-03-29T19:08:24.281Z,1648580904.281 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20220329T185848/Courier0004.lzma
2022-03-29T19:08:25.283Z,1648580905.283 [DataOverHttps](INFO): Moved sent file to Logs/20220329T185848/Courier0004.lzma.bak
2022-03-29T19:08:25.284Z,1648580905.284 [DataOverHttps](INFO): SBD MOMSN=16608829
2022-03-29T19:08:30.840Z,1648580910.840 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,190829.00,29,03,2022,00,00*67

2022-03-29T19:08:41.875Z,1648580921.875 [DataOverHttps](INFO): Sending 787 bytes from file Logs/20220329T185848/Express0005.lzma
2022-03-29T19:08:42.876Z,1648580922.876 [DataOverHttps](INFO): Moved sent file to Logs/20220329T185848/Express0005.lzma.bak
2022-03-29T19:08:42.876Z,1648580922.876 [DataOverHttps](INFO): SBD MOMSN=16608831
2022-03-29T19:08:45.444Z,1648580925.444 [Default:CheckIn:Read_Iridium] Stopped
2022-03-29T19:08:45.444Z,1648580925.444 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-29T19:08:45.444Z,1648580925.444 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-29T19:08:59.976Z,1648580939.976 [Power24vConverter](INFO): Powering down.
2022-03-29T19:09:02.450Z,1648580942.450 [NAL9602](ERROR): getFix uart error: serial timeout
2022-03-29T19:09:03.670Z,1648580943.670 [NAL9602](ERROR): parseGSV uart error: serial timeout
2022-03-29T19:09:40.363Z,1648580980.363 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-03-29T19:09:40.363Z,1648580980.363 [NAL9602] Data Fault, FailCount= 1
2022-03-29T19:09:40.363Z,1648580980.363 [NAL9602](ERROR): Data Fault
2022-03-29T19:09:40.406Z,1648580980.406 [CBIT](ERROR): Data Fault in component: NAL9602
2022-03-29T19:09:40.767Z,1648580980.767 [NAL9602](INFO): Powering down
2022-03-29T19:09:41.595Z,1648580981.595 [CBIT](INFO): Clearing failed state for component NAL9602
2022-03-29T19:09:41.595Z,1648580981.595 [NAL9602] No Fault, FailCount= 1
2022-03-29T19:10:11.063Z,1648581011.063 [NAL9602](INFO): Powering up NAL9602
2022-03-29T19:10:21.979Z,1648581021.979 [NAL9602](INFO): NAL9602 initialized
2022-03-29T19:10:26.020Z,1648581026.020 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:11:01.591Z,1648581061.591 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-03-29T19:11:01.591Z,1648581061.591 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-29T19:11:01.628Z,1648581061.628 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-29T19:11:02.016Z,1648581062.016 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-29T19:11:02.016Z,1648581062.016 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-03-29T19:11:27.025Z,1648581087.025 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:13:46.004Z,1648581226.004 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-29T19:13:46.004Z,1648581226.004 [Default:CheckIn:C.Wait] Stopped
2022-03-29T19:13:46.004Z,1648581226.004 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-29T19:13:46.004Z,1648581226.004 [Default:CheckIn:D] Running Loop=1
2022-03-29T19:13:46.420Z,1648581226.420 [Default:CheckIn:D] Stopped
2022-03-29T19:13:46.421Z,1648581226.421 [Default:CheckIn:E] Running Loop=1
2022-03-29T19:13:46.833Z,1648581226.833 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.009553 min 
2022-03-29T19:13:46.833Z,1648581226.833 [Default:CheckIn:E] Stopped
2022-03-29T19:13:46.833Z,1648581226.833 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-29T19:13:46.833Z,1648581226.833 [Default:CheckIn] Stopped
2022-03-29T19:13:46.833Z,1648581226.833 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-29T19:13:46.833Z,1648581226.833 [Default:CheckIn](INFO): Running loop #2
2022-03-29T19:13:46.834Z,1648581226.834 [Default:CheckIn] Running Loop=2
2022-03-29T19:13:46.834Z,1648581226.834 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-29T19:13:46.834Z,1648581226.834 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-29T19:13:59.365Z,1648581239.365 [CBIT](INFO): Clearing failed state for component DropWeight
2022-03-29T19:13:59.366Z,1648581239.366 [DropWeight] No Fault, FailCount= 1
2022-03-29T19:14:02.588Z,1648581242.588 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-03-29T19:14:02.588Z,1648581242.588 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-29T19:14:02.598Z,1648581242.598 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-29T19:14:02.000Z,1648581243.000 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-29T19:14:02.000Z,1648581243.000 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-03-29T19:15:29.016Z,1648581329.016 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:16:09.020Z,1648581369.020 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:17:03.579Z,1648581423.579 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-03-29T19:17:03.579Z,1648581423.579 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-29T19:17:03.614Z,1648581423.614 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-29T19:17:03.975Z,1648581423.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-29T19:17:03.975Z,1648581423.975 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-03-29T19:17:50.016Z,1648581470.016 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:18:46.985Z,1648581526.985 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-29T19:13:46.8Z
2022-03-29T19:18:46.985Z,1648581526.985 [Default:CheckIn:Read_GPS] Stopped
2022-03-29T19:18:46.985Z,1648581526.985 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-29T19:18:51.017Z,1648581531.017 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:18:53.957Z,1648581533.957 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220329T185848/Courier0007.lzma
2022-03-29T19:18:54.959Z,1648581534.959 [DataOverHttps](INFO): Moved sent file to Logs/20220329T185848/Courier0007.lzma.bak
2022-03-29T19:18:54.960Z,1648581534.960 [DataOverHttps](INFO): SBD MOMSN=16608900
2022-03-29T19:19:11.389Z,1648581551.389 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20220329T185848/Express0008.lzma
2022-03-29T19:19:12.392Z,1648581552.392 [DataOverHttps](INFO): Moved sent file to Logs/20220329T185848/Express0008.lzma.bak
2022-03-29T19:19:12.392Z,1648581552.392 [DataOverHttps](INFO): SBD MOMSN=16608902
2022-03-29T19:19:14.876Z,1648581554.876 [Default:CheckIn:Read_Iridium] Stopped
2022-03-29T19:19:14.876Z,1648581554.876 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-29T19:19:14.876Z,1648581554.876 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-29T19:20:04.567Z,1648581604.567 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-03-29T19:20:04.567Z,1648581604.567 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-29T19:20:04.602Z,1648581604.602 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-29T19:20:04.971Z,1648581604.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-29T19:20:04.971Z,1648581604.971 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-03-29T19:20:23.124Z,1648581623.124 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-03-29T19:20:23.124Z,1648581623.124 [NAL9602] Data Fault, FailCount= 2
2022-03-29T19:20:23.124Z,1648581623.124 [NAL9602](ERROR): Data Fault
2022-03-29T19:20:23.141Z,1648581623.141 [CBIT](ERROR): Data Fault in component: NAL9602
2022-03-29T19:20:23.536Z,1648581623.536 [NAL9602](INFO): Powering down
2022-03-29T19:20:24.351Z,1648581624.351 [CBIT](INFO): Clearing failed state for component NAL9602
2022-03-29T19:20:24.351Z,1648581624.351 [NAL9602] No Fault, FailCount= 2
2022-03-29T19:20:53.835Z,1648581653.835 [NAL9602](INFO): Powering up NAL9602
2022-03-29T19:21:04.784Z,1648581664.784 [NAL9602](INFO): NAL9602 initialized
2022-03-29T19:21:19.782Z,1648581679.782 [NAL9602](ERROR): getFix uart error: serial timeout
2022-03-29T19:21:21.806Z,1648581681.806 [NAL9602](ERROR): parseGSV uart error: serial timeout
2022-03-29T19:21:25.780Z,1648581685.780 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:23:05.580Z,1648581785.580 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-03-29T19:23:05.580Z,1648581785.580 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-29T19:23:05.612Z,1648581785.612 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-29T19:23:06.015Z,1648581786.015 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-29T19:23:06.015Z,1648581786.015 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-03-29T19:23:06.776Z,1648581786.776 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:23:27.788Z,1648581807.788 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,,,,,00,00*48

2022-03-29T19:24:15.481Z,1648581855.481 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-29T19:24:15.481Z,1648581855.481 [Default:CheckIn:C.Wait] Stopped
2022-03-29T19:24:15.481Z,1648581855.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-29T19:24:15.481Z,1648581855.481 [Default:CheckIn:D] Running Loop=1
2022-03-29T19:24:15.893Z,1648581855.893 [Default:CheckIn:D] Stopped
2022-03-29T19:24:15.893Z,1648581855.893 [Default:CheckIn:E] Running Loop=1
2022-03-29T19:24:16.293Z,1648581856.293 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.500763 min 
2022-03-29T19:24:16.293Z,1648581856.293 [Default:CheckIn:E] Stopped
2022-03-29T19:24:16.293Z,1648581856.293 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-29T19:24:16.293Z,1648581856.293 [Default:CheckIn] Stopped
2022-03-29T19:24:16.293Z,1648581856.293 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-29T19:24:16.293Z,1648581856.293 [Default:CheckIn](INFO): Running loop #3
2022-03-29T19:24:16.294Z,1648581856.294 [Default:CheckIn] Running Loop=3
2022-03-29T19:24:16.294Z,1648581856.294 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-29T19:24:16.294Z,1648581856.294 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-29T19:25:38.688Z,1648581938.688 [NAL9602](ERROR): Not a RMC sentence: $GPZDA,192537.00,29,03,2022,00,00*67

2022-03-29T19:26:00.119Z,1648581960.119 [CommandExec](IMPORTANT): got command restart application
2022-03-29T19:26:01.122Z,1648581961.122 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:01.122Z,1648581961.122 [CommandExec](INFO): Uninitializing the command executive.
2022-03-29T19:26:01.122Z,1648581961.122 [CommandExec](INFO): Uninitializing the command scheduler.
2022-03-29T19:26:01.122Z,1648581961.122 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:01.302Z,1648581961.302 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-03-29T19:26:01.302Z,1648581961.302 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-03-29T19:26:01.302Z,1648581961.302 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:01.303Z,1648581961.303 [NavChartDb](INFO): Join timeout helper Thread ID is 6754
2022-03-29T19:26:01.582Z,1648581961.582 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:01.582Z,1648581961.582 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:01.594Z,1648581961.594 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-03-29T19:26:01.594Z,1648581961.594 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:01.594Z,1648581961.594 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6755
2022-03-29T19:26:01.838Z,1648581961.838 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:01.838Z,1648581961.838 [WetLabsBB2FL](INFO): Powering down
2022-03-29T19:26:01.839Z,1648581961.839 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:01.854Z,1648581961.854 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-03-29T19:26:01.854Z,1648581961.854 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:01.854Z,1648581961.854 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6756
2022-03-29T19:26:02.290Z,1648581962.290 [CTD_Seabird](INFO): Powering down
2022-03-29T19:26:02.303Z,1648581962.303 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:02.303Z,1648581962.303 [CTD_Seabird](INFO): Powering down
2022-03-29T19:26:02.318Z,1648581962.318 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:02.318Z,1648581962.318 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-03-29T19:26:02.319Z,1648581962.319 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:02.319Z,1648581962.319 [Radio_Surface](INFO): Join timeout helper Thread ID is 6757
2022-03-29T19:26:02.650Z,1648581962.650 [Radio_Surface](INFO): Powering down
2022-03-29T19:26:02.651Z,1648581962.651 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:02.651Z,1648581962.651 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:02.663Z,1648581962.663 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-03-29T19:26:02.663Z,1648581962.663 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:02.663Z,1648581962.663 [Onboard](INFO): Join timeout helper Thread ID is 6758
2022-03-29T19:26:03.262Z,1648581963.262 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-03-29T19:26:04.758Z,1648581964.758 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:04.758Z,1648581964.758 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:04.763Z,1648581964.763 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-03-29T19:26:04.763Z,1648581964.763 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:04.763Z,1648581964.763 [DataOverHttps](INFO): Join timeout helper Thread ID is 6759
2022-03-29T19:26:05.270Z,1648581965.270 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:05.270Z,1648581965.270 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.274Z,1648581965.274 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-03-29T19:26:05.275Z,1648581965.275 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.275Z,1648581965.275 [BackseatComponent](INFO): Join timeout helper Thread ID is 6760
2022-03-29T19:26:05.470Z,1648581965.470 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:05.471Z,1648581965.471 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.472Z,1648581965.472 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-03-29T19:26:05.472Z,1648581965.472 [logger ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.472Z,1648581965.472 [logger](INFO): Join timeout helper Thread ID is 6761
2022-03-29T19:26:05.474Z,1648581965.474 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:05.474Z,1648581965.474 [logger ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.476Z,1648581965.476 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-03-29T19:26:05.476Z,1648581965.476 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.476Z,1648581965.476 [CommandLine](INFO): Join timeout helper Thread ID is 6762
2022-03-29T19:26:05.542Z,1648581965.542 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:05.542Z,1648581965.542 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.554Z,1648581965.554 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-03-29T19:26:05.555Z,1648581965.555 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.555Z,1648581965.555 [CommandExec](INFO): Join timeout helper Thread ID is 6763
2022-03-29T19:26:05.556Z,1648581965.556 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-03-29T19:26:05.556Z,1648581965.556 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.557Z,1648581965.557 [controlThread](INFO): Join timeout helper Thread ID is 6764
2022-03-29T19:26:05.738Z,1648581965.738 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-29T19:26:05.738Z,1648581965.738 [controlThread](DEBUG): Uninitializing ControlThread
2022-03-29T19:26:05.739Z,1648581965.739 [AHRS_M2](INFO): Powering down
2022-03-29T19:26:05.811Z,1648581965.811 [NAL9602](INFO): Powering down
2022-03-29T19:26:05.812Z,1648581965.812 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-03-29T19:26:05.813Z,1648581965.813 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-03-29T19:26:05.814Z,1648581965.814 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-03-29T19:26:05.815Z,1648581965.815 [MissionManager](INFO): Uninitializing Mission Default
2022-03-29T19:26:05.815Z,1648581965.815 [Default] Stopped
2022-03-29T19:26:05.815Z,1648581965.815 [Default](DEBUG): Aggregate::uninitialize Default
2022-03-29T19:26:05.815Z,1648581965.815 [Default:B.GoToSurface] Stopped
2022-03-29T19:26:05.815Z,1648581965.815 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-03-29T19:26:05.815Z,1648581965.815 [Default:CheckIn] Stopped
2022-03-29T19:26:05.815Z,1648581965.815 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-29T19:26:05.815Z,1648581965.815 [Default:CheckIn:Read_GPS] Stopped
2022-03-29T19:26:05.818Z,1648581965.818 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-03-29T19:26:05.819Z,1648581965.819 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-03-29T19:26:05.819Z,1648581965.819 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-03-29T19:26:05.819Z,1648581965.819 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-03-29T19:26:05.819Z,1648581965.819 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-03-29T19:26:05.820Z,1648581965.820 [BuoyancyServo](INFO): Powering down
2022-03-29T19:26:05.834Z,1648581965.834 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-03-29T19:26:05.834Z,1648581965.834 [ElevatorServo](INFO): Powering down
2022-03-29T19:26:05.835Z,1648581965.835 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-03-29T19:26:05.835Z,1648581965.835 [MassServo](INFO): Powering down
2022-03-29T19:26:05.836Z,1648581965.836 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-03-29T19:26:05.836Z,1648581965.836 [RudderServo](INFO): Powering down
2022-03-29T19:26:05.837Z,1648581965.837 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-03-29T19:26:05.837Z,1648581965.837 [ThrusterHE](INFO): Powering down
2022-03-29T19:26:05.838Z,1648581965.838 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-03-29T19:26:05.838Z,1648581965.838 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-03-29T19:26:05.838Z,1648581965.838 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-03-29T19:26:05.839Z,1648581965.839 [CBIT](DEBUG): Powering off loads.
2022-03-29T19:26:05.850Z,1648581965.850 [CBIT](DEBUG): Disabling WDT.
2022-03-29T19:26:05.862Z,1648581965.862 [CBIT](DEBUG): Opening all GF detection circuits.
2022-03-29T19:26:05.863Z,1648581965.863 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.913Z,1648581965.913 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.916Z,1648581965.916 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.922Z,1648581965.922 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:05.966Z,1648581965.966 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:06.007Z,1648581966.007 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:06.011Z,1648581966.011 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:06.042Z,1648581966.042 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-03-29T19:26:06.110Z,1648581966.110 [logger ThreadHandler](INFO): Thread cancelled.