2020-01-27T17:03:08.545Z,1580144588.545 [Supervisor](DEBUG): Initializing supervisor.
2020-01-27T17:03:08.548Z,1580144588.548 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-27T17:03:08.549Z,1580144588.549 [SyncHandler](INFO): Protected caller Thread ID is 6760
2020-01-27T17:03:08.550Z,1580144588.550 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-27T17:03:08.551Z,1580144588.551 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-27T17:03:08.551Z,1580144588.551 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6761
2020-01-27T17:03:08.554Z,1580144588.554 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-27T17:03:08.566Z,1580144588.566 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-27T17:03:08.567Z,1580144588.567 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-27T17:03:08.568Z,1580144588.568 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6762
2020-01-27T17:03:08.568Z,1580144588.568 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-27T17:03:08.569Z,1580144588.569 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-27T17:03:08.570Z,1580144588.570 [logger ThreadHandler](INFO): Protected caller Thread ID is 6763
2020-01-27T17:03:08.571Z,1580144588.571 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-27T17:03:08.572Z,1580144588.572 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-27T17:03:08.573Z,1580144588.573 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-27T17:03:09.003Z,1580144589.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-27T17:03:09.003Z,1580144589.003 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-27T17:03:09.099Z,1580144589.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-27T17:03:09.099Z,1580144589.099 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-27T17:03:09.408Z,1580144589.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-27T17:03:09.409Z,1580144589.409 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-27T17:03:09.544Z,1580144589.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-27T17:03:09.545Z,1580144589.545 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-27T17:03:09.749Z,1580144589.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-27T17:03:09.750Z,1580144589.750 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-27T17:03:10.322Z,1580144590.322 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-27T17:03:10.323Z,1580144590.323 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-27T17:03:10.528Z,1580144590.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-27T17:03:10.529Z,1580144590.529 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-27T17:03:10.670Z,1580144590.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-27T17:03:10.670Z,1580144590.670 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-27T17:03:10.856Z,1580144590.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-27T17:03:10.856Z,1580144590.856 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-27T17:03:10.950Z,1580144590.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-27T17:03:10.951Z,1580144590.951 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-27T17:03:11.244Z,1580144591.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-27T17:03:11.245Z,1580144591.245 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-27T17:03:11.323Z,1580144591.323 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-27T17:03:11.424Z,1580144591.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-27T17:03:11.424Z,1580144591.424 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-27T17:03:12.053Z,1580144592.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-27T17:03:12.054Z,1580144592.054 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-27T17:03:12.456Z,1580144592.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-27T17:03:12.458Z,1580144592.458 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-27T17:03:12.458Z,1580144592.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-27T17:03:12.666Z,1580144592.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-27T17:03:12.765Z,1580144592.765 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-27T17:03:12.862Z,1580144592.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-27T17:03:13.086Z,1580144593.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-27T17:03:13.087Z,1580144593.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-27T17:03:13.170Z,1580144593.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-27T17:03:13.261Z,1580144593.261 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-27T17:03:13.356Z,1580144593.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-27T17:03:13.437Z,1580144593.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-27T17:03:13.542Z,1580144593.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-01-27T17:03:13.632Z,1580144593.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-27T17:03:13.812Z,1580144593.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-27T17:03:13.941Z,1580144593.941 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-27T17:03:13.941Z,1580144593.941 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-27T17:03:13.948Z,1580144593.948 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-27T17:03:14.248Z,1580144594.248 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-27T17:03:14.253Z,1580144594.253 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-27T17:03:14.255Z,1580144594.255 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-27T17:03:14.260Z,1580144594.260 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-27T17:03:14.260Z,1580144594.260 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-27T17:03:14.265Z,1580144594.265 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-27T17:03:14.266Z,1580144594.266 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-27T17:03:14.271Z,1580144594.271 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-27T17:03:14.339Z,1580144594.339 [AHRS_M2] Loaded
2020-01-27T17:03:14.339Z,1580144594.339 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-27T17:03:14.418Z,1580144594.418 [DataOverHttps] Loaded
2020-01-27T17:03:14.418Z,1580144594.418 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-27T17:03:14.419Z,1580144594.419 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-27T17:03:14.420Z,1580144594.420 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6845
2020-01-27T17:03:14.443Z,1580144594.443 [DDM] Loaded
2020-01-27T17:03:14.443Z,1580144594.443 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-27T17:03:14.455Z,1580144594.455 [Depth_Keller] Loaded
2020-01-27T17:03:14.456Z,1580144594.456 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-27T17:03:14.515Z,1580144594.515 [DUSBL_Hydroid] Loaded
2020-01-27T17:03:14.515Z,1580144594.515 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-27T17:03:14.560Z,1580144594.560 [Micromodem] Loaded
2020-01-27T17:03:14.560Z,1580144594.560 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-27T17:03:14.654Z,1580144594.654 [NAL9602] Loaded
2020-01-27T17:03:14.654Z,1580144594.654 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-27T17:03:14.692Z,1580144594.692 [Onboard] Loaded
2020-01-27T17:03:14.693Z,1580144594.693 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-27T17:03:14.698Z,1580144594.698 [PowerOnly] Loaded
2020-01-27T17:03:14.699Z,1580144594.699 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-27T17:03:14.705Z,1580144594.705 [Radio_Surface] Loaded
2020-01-27T17:03:14.705Z,1580144594.705 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-27T17:03:14.706Z,1580144594.706 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-27T17:03:14.706Z,1580144594.706 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6846
2020-01-27T17:03:14.748Z,1580144594.748 [RDI_Pathfinder] Loaded
2020-01-27T17:03:14.748Z,1580144594.748 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-27T17:03:16.172Z,1580144596.172 [BPC1] Loaded
2020-01-27T17:03:16.172Z,1580144596.172 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-27T17:03:16.172Z,1580144596.172 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-27T17:03:16.173Z,1580144596.173 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-27T17:03:16.199Z,1580144596.199 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-27T17:03:16.209Z,1580144596.209 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-27T17:03:16.508Z,1580144596.508 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-27T17:03:16.509Z,1580144596.509 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-27T17:03:16.529Z,1580144596.529 [NavChart] Loaded
2020-01-27T17:03:16.530Z,1580144596.530 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-27T17:03:16.555Z,1580144596.555 [UniversalFixResidualReporter] Loaded
2020-01-27T17:03:16.556Z,1580144596.556 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-27T17:03:16.556Z,1580144596.556 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-27T17:03:16.557Z,1580144596.557 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-27T17:03:16.704Z,1580144596.704 [BuoyancyServo] Loaded
2020-01-27T17:03:16.705Z,1580144596.705 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-27T17:03:16.775Z,1580144596.775 [ElevatorServo] Loaded
2020-01-27T17:03:16.775Z,1580144596.775 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-27T17:03:16.820Z,1580144596.820 [MassServo] Loaded
2020-01-27T17:03:16.820Z,1580144596.820 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-27T17:03:16.835Z,1580144596.835 [RudderServo] Loaded
2020-01-27T17:03:16.835Z,1580144596.835 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-27T17:03:16.849Z,1580144596.849 [ThrusterServo] Loaded
2020-01-27T17:03:16.849Z,1580144596.849 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-27T17:03:16.850Z,1580144596.850 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-27T17:03:16.850Z,1580144596.850 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-27T17:03:17.124Z,1580144597.124 [PAR_Licor] Loaded
2020-01-27T17:03:17.124Z,1580144597.124 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-27T17:03:17.166Z,1580144597.166 [WetLabsSeaOWL_UV_A] Loaded
2020-01-27T17:03:17.167Z,1580144597.167 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-27T17:03:17.168Z,1580144597.168 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-27T17:03:17.168Z,1580144597.168 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 6847
2020-01-27T17:03:17.169Z,1580144597.169 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-27T17:03:17.169Z,1580144597.169 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-27T17:03:17.490Z,1580144597.490 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-27T17:03:17.491Z,1580144597.491 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-27T17:03:17.531Z,1580144597.531 [DepthRateCalculator] Loaded
2020-01-27T17:03:17.532Z,1580144597.532 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-27T17:03:17.537Z,1580144597.537 [PitchRateCalculator] Loaded
2020-01-27T17:03:17.537Z,1580144597.537 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-27T17:03:17.549Z,1580144597.549 [SpeedCalculator] Loaded
2020-01-27T17:03:17.549Z,1580144597.549 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-27T17:03:17.570Z,1580144597.570 [TempGradientCalculator] Loaded
2020-01-27T17:03:17.570Z,1580144597.570 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-27T17:03:17.575Z,1580144597.575 [YawRateCalculator] Loaded
2020-01-27T17:03:17.576Z,1580144597.576 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-27T17:03:17.615Z,1580144597.615 [ElevatorOffsetCalculator] Loaded
2020-01-27T17:03:17.615Z,1580144597.615 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-27T17:03:17.615Z,1580144597.615 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-27T17:03:17.616Z,1580144597.616 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-27T17:03:17.751Z,1580144597.751 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-27T17:03:17.772Z,1580144597.772 [SBIT] Loaded
2020-01-27T17:03:17.773Z,1580144597.773 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-27T17:03:17.773Z,1580144597.773 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-27T17:03:17.786Z,1580144597.786 [IBIT] Loaded
2020-01-27T17:03:17.786Z,1580144597.786 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-27T17:03:17.789Z,1580144597.789 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-27T17:03:17.923Z,1580144597.923 [CBIT] Loaded
2020-01-27T17:03:17.923Z,1580144597.923 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-27T17:03:17.923Z,1580144597.923 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-27T17:03:17.924Z,1580144597.924 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-27T17:03:18.003Z,1580144598.003 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-27T17:03:18.004Z,1580144598.004 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-27T17:03:18.099Z,1580144598.099 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-27T17:03:18.100Z,1580144598.100 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-27T17:03:18.148Z,1580144598.148 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-27T17:03:18.227Z,1580144598.227 [VerticalControl] Loaded
2020-01-27T17:03:18.228Z,1580144598.228 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-27T17:03:18.229Z,1580144598.229 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-27T17:03:18.296Z,1580144598.296 [HorizontalControl] Loaded
2020-01-27T17:03:18.296Z,1580144598.296 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-27T17:03:18.297Z,1580144598.297 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-27T17:03:18.299Z,1580144598.299 [SpeedControl] Loaded
2020-01-27T17:03:18.299Z,1580144598.299 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-27T17:03:18.299Z,1580144598.299 [LoopControl](DEBUG): Construct LoopControl.
2020-01-27T17:03:18.300Z,1580144598.300 [LoopControl] Loaded
2020-01-27T17:03:18.300Z,1580144598.300 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-27T17:03:18.301Z,1580144598.301 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-27T17:03:18.301Z,1580144598.301 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-27T17:03:18.332Z,1580144598.332 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-27T17:03:18.336Z,1580144598.336 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-27T17:03:18.337Z,1580144598.337 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-27T17:03:18.343Z,1580144598.343 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-27T17:03:18.344Z,1580144598.344 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACC4E0
2020-01-27T17:03:18.345Z,1580144598.345 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6848
2020-01-27T17:03:18.349Z,1580144598.349 [Supervisor](INFO): Main Thread ID is 6618
2020-01-27T17:03:18.349Z,1580144598.349 [Supervisor](DEBUG): Running supervisor.
2020-01-27T17:03:18.350Z,1580144598.350 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6849
2020-01-27T17:03:18.352Z,1580144598.352 [controlThread ThreadHandler](INFO): Handler Thread ID is 6850
2020-01-27T17:03:18.353Z,1580144598.353 [controlThread](DEBUG): Initializing ControlThread
2020-01-27T17:03:18.359Z,1580144598.359 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-27T17:03:18.360Z,1580144598.360 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-27T17:03:18.365Z,1580144598.365 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-27T17:03:18.365Z,1580144598.365 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-27T17:03:18.366Z,1580144598.366 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-27T17:03:18.366Z,1580144598.366 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-27T17:03:18.366Z,1580144598.366 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-27T17:03:18.367Z,1580144598.367 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-27T17:03:18.367Z,1580144598.367 [SBIT](INFO): Initialize SBIT Component.
2020-01-27T17:03:18.368Z,1580144598.368 [SBIT](IMPORTANT): git: 2020-01-26-2-gfd51465
2020-01-27T17:03:18.368Z,1580144598.368 [SBIT](INFO): git hash: fd51465a4be0f2458cfa55fa910451fabf321855
2020-01-27T17:03:18.368Z,1580144598.368 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-27T17:03:18.369Z,1580144598.369 [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 Nov 13 12:38:10 PST 2019
2020-01-27T17:03:18.370Z,1580144598.370 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-27T17:03:18.371Z,1580144598.371 [IBIT](INFO): Initialize IBIT Component.
2020-01-27T17:03:18.372Z,1580144598.372 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-27T17:03:18.373Z,1580144598.373 [logger ThreadHandler](INFO): Handler Thread ID is 6851
2020-01-27T17:03:18.385Z,1580144598.385 [CBIT](DEBUG): Initialized mux pins.
2020-01-27T17:03:18.385Z,1580144598.385 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-27T17:03:18.393Z,1580144598.393 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6852
2020-01-27T17:03:18.394Z,1580144598.394 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-27T17:03:18.405Z,1580144598.405 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6853
2020-01-27T17:03:18.409Z,1580144598.409 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-27T17:03:18.409Z,1580144598.409 [CBIT](DEBUG): Initializing heartbeat.
2020-01-27T17:03:18.417Z,1580144598.417 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 6854
2020-01-27T17:03:18.418Z,1580144598.418 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-27T17:03:18.445Z,1580144598.445 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6855
2020-01-27T17:03:18.448Z,1580144598.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-27T17:03:18.449Z,1580144598.449 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-27T17:03:18.449Z,1580144598.449 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-27T17:03:18.449Z,1580144598.449 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-27T17:03:18.449Z,1580144598.449 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-27T17:03:18.450Z,1580144598.450 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-27T17:03:18.450Z,1580144598.450 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-27T17:03:18.450Z,1580144598.450 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-27T17:03:18.450Z,1580144598.450 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-27T17:03:18.450Z,1580144598.450 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-27T17:03:18.451Z,1580144598.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-27T17:03:18.451Z,1580144598.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-27T17:03:18.451Z,1580144598.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-27T17:03:18.451Z,1580144598.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-27T17:03:18.451Z,1580144598.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-27T17:03:18.451Z,1580144598.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-27T17:03:18.481Z,1580144598.481 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-27T17:03:18.481Z,1580144598.481 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-27T17:03:18.517Z,1580144598.517 [CBIT](DEBUG): Backplane powered.
2020-01-27T17:03:18.517Z,1580144598.517 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-27T17:03:18.519Z,1580144598.519 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-27T17:03:18.520Z,1580144598.520 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-27T17:03:18.520Z,1580144598.520 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-27T17:03:18.521Z,1580144598.521 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-27T17:03:18.534Z,1580144598.534 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-27T17:03:18.560Z,1580144598.560 [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>


2020-01-27T17:03:18.561Z,1580144598.561 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-27T17:03:18.623Z,1580144598.623 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-27T17:03:18.624Z,1580144598.624 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-27T17:03:18.641Z,1580144598.641 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-27T17:03:18.675Z,1580144598.675 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-27T17:03:18.677Z,1580144598.677 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-27T17:03:18.706Z,1580144598.706 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-27T17:03:18.710Z,1580144598.710 [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>


2020-01-27T17:03:18.729Z,1580144598.729 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-27T17:03:18.734Z,1580144598.734 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-27T17:03:18.769Z,1580144598.769 [DDM](INFO): Powering up
2020-01-27T17:03:18.769Z,1580144598.769 [DDM](DEBUG): Initializing DDM.
2020-01-27T17:03:18.798Z,1580144598.798 [Depth_Keller](ERROR): Pressure reading out of range: 957.962097 decibar
2020-01-27T17:03:18.798Z,1580144598.798 [DUSBL_Hydroid](INFO): Powering up
2020-01-27T17:03:18.798Z,1580144598.798 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-27T17:03:18.809Z,1580144598.809 [Radio_Surface](INFO): Powering up
2020-01-27T17:03:18.988Z,1580144598.988 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-27T17:03:19.024Z,1580144599.024 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-27T17:03:19.030Z,1580144599.030 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-27T17:03:19.031Z,1580144599.031 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-27T17:03:19.037Z,1580144599.037 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-27T17:03:19.038Z,1580144599.038 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-27T17:03:19.045Z,1580144599.045 [MassServo](DEBUG): Initializing MassServo.
2020-01-27T17:03:19.046Z,1580144599.046 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-27T17:03:19.053Z,1580144599.053 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-27T17:03:19.054Z,1580144599.054 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-27T17:03:19.061Z,1580144599.061 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-27T17:03:19.159Z,1580144599.159 [Micromodem](INFO): Powering up
2020-01-27T17:03:19.159Z,1580144599.159 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-27T17:03:19.233Z,1580144599.233 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-27T17:03:19.909Z,1580144599.909 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-27T17:03:19.909Z,1580144599.909 [RudderServo](FAULT): Rudder failed to initialize
2020-01-27T17:03:19.909Z,1580144599.909 [RudderServo] Communications Fault, FailCount= 1
2020-01-27T17:03:19.909Z,1580144599.909 [RudderServo](ERROR): Communications Fault
2020-01-27T17:03:20.020Z,1580144600.020 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-27T17:03:20.214Z,1580144600.214 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-27T17:03:20.214Z,1580144600.214 [RudderServo](INFO): Powering down
2020-01-27T17:03:20.904Z,1580144600.904 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-27T17:03:21.022Z,1580144601.022 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-27T17:03:21.026Z,1580144601.026 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-27T17:03:21.026Z,1580144601.026 [RudderServo] No Fault, FailCount= 1
2020-01-27T17:03:24.109Z,1580144604.109 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-27T17:03:24.512Z,1580144604.512 [Micromodem](INFO): Nmea in: $CATMG,2020-01-27T17:03:23.386353Z,RTC,RTC*58

2020-01-27T17:03:24.512Z,1580144604.512 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-27T17:03:23.386353Z,RTC,RTC*58

2020-01-27T17:03:24.915Z,1580144604.915 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-27T17:03:24.915Z,1580144604.915 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-27T17:03:25.319Z,1580144605.319 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-27T17:03:25.319Z,1580144605.319 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-27T17:03:25.727Z,1580144605.727 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-27T17:03:25.727Z,1580144605.727 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-27T17:03:26.131Z,1580144606.131 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-27T17:03:26.132Z,1580144606.132 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-27T17:03:26.539Z,1580144606.539 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-27T17:03:26.539Z,1580144606.539 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-27T17:03:26.935Z,1580144606.935 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-27T17:03:26.936Z,1580144606.936 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-27T17:03:27.363Z,1580144607.363 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-27T17:03:27.364Z,1580144607.364 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-27T17:03:27.721Z,1580144607.721 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-27T17:03:27.722Z,1580144607.722 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,27,17,03,28*4F

2020-01-27T17:03:28.132Z,1580144608.132 [Micromodem](INFO): Nmea in: $CACLK,2020,1,27,17,3,28*4D

2020-01-27T17:03:28.942Z,1580144608.942 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-27T17:03:29Z*7C

2020-01-27T17:03:28.982Z,1580144608.982 [Micromodem](INFO): Nmea in: $CATMG,2020-01-27T17:03:29.026501Z,USER_CMD,RTC*1B

2020-01-27T17:03:29.336Z,1580144609.336 [DDM](INFO): Dynamic Docking Module:
2020-01-27T17:03:32.179Z,1580144612.179 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-27T17:03:37.010Z,1580144617.010 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-27T17:03:44.679Z,1580144624.679 [NAL9602](INFO): Powering up NAL9602
2020-01-27T17:03:55.600Z,1580144635.600 [NAL9602](INFO): NAL9602 initialized
2020-01-27T17:03:58.087Z,1580144638.087 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-27T17:03:58.113Z,1580144638.113 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-27T17:04:09.366Z,1580144649.366 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008771
CHAN A1 (24V): -0.028867
CHAN A2 (12V): -0.007338
CHAN A3 (5V): -0.002057
CHAN B0 (3.3V): 0.000284
CHAN B1 (3.15aV): 0.000077
CHAN B2 (3.15bV): 0.000260
CHAN B3 (GND): 0.001921
OPEN: 0.005492
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-27T17:04:51.890Z,1580144691.890 [SBIT](IMPORTANT): SBIT PASSED
2020-01-27T17:04:51.890Z,1580144691.890 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-27T17:04:51.891Z,1580144691.891 [SBIT](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-27T17:04:51.891Z,1580144691.891 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-01-27T17:04:51.891Z,1580144691.891 [SBIT](IMPORTANT): Express none DDM.cablePresent;

2020-01-27T17:04:51.892Z,1580144691.892 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-27T17:04:51.892Z,1580144691.892 [SBIT](IMPORTANT): VerticalControl.massDefault=0.5 centimeter;

2020-01-27T17:04:52.267Z,1580144692.267 [MissionManager](IMPORTANT): Started mission Startup
2020-01-27T17:04:52.267Z,1580144692.267 [Startup] Running Loop=1
2020-01-27T17:04:52.268Z,1580144692.268 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-27T17:04:52.268Z,1580144692.268 [Startup:A.GoToSurface] Running Loop=1
2020-01-27T17:04:52.268Z,1580144692.268 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-27T17:04:52.268Z,1580144692.268 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-27T17:04:52.269Z,1580144692.269 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-27T17:04:52.269Z,1580144692.269 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-27T17:04:52.270Z,1580144692.270 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-27T17:04:52.270Z,1580144692.270 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-27T17:04:52.271Z,1580144692.271 [Startup:StartupSatComms] Running Loop=1
2020-01-27T17:04:52.271Z,1580144692.271 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-27T17:04:52.272Z,1580144692.272 [Startup:StartupSatComms:A] Running Loop=1
2020-01-27T17:04:52.790Z,1580144692.790 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-27T17:04:53.098Z,1580144693.098 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-27T17:04:53.098Z,1580144693.098 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0    +0.00,   0.00, 81.00
2020-01-27T17:05:47.599Z,1580144747.599 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004863
2020-01-27T17:05:52.481Z,1580144752.481 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-27T17:04:52.3Z
2020-01-27T17:05:52.481Z,1580144752.481 [Startup:StartupSatComms:A] Stopped
2020-01-27T17:05:52.481Z,1580144752.481 [Startup:StartupSatComms:B] Running Loop=1
2020-01-27T17:05:52.867Z,1580144752.867 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-27T17:06:00.177Z,1580144760.177 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200127T165408/Courier0004.lzma
2020-01-27T17:06:02.183Z,1580144762.183 [DataOverHttps](INFO): Moved sent file to Logs/20200127T165408/Courier0004.lzma.bak
2020-01-27T17:06:02.183Z,1580144762.183 [DataOverHttps](INFO): SBD MOMSN=12205048
2020-01-27T17:06:18.724Z,1580144778.724 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-27T17:06:18.724Z,1580144778.724 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:06:18.733Z,1580144778.733 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:06:19.146Z,1580144779.146 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:06:19.146Z,1580144779.146 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-27T17:06:19.237Z,1580144779.237 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200127T170308/Courier0000.lzma
2020-01-27T17:06:21.238Z,1580144781.238 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0000.lzma.bak
2020-01-27T17:06:21.238Z,1580144781.238 [DataOverHttps](INFO): SBD MOMSN=12205050
2020-01-27T17:06:38.605Z,1580144798.605 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20200127T165408/Express0005.lzma
2020-01-27T17:06:40.610Z,1580144800.610 [DataOverHttps](INFO): Moved sent file to Logs/20200127T165408/Express0005.lzma.bak
2020-01-27T17:06:40.611Z,1580144800.611 [DataOverHttps](INFO): SBD MOMSN=12205053
2020-01-27T17:06:52.718Z,1580144812.718 [Startup:StartupSatComms:B](INFO): Timed out from 2020-01-27T17:05:52.5Z
2020-01-27T17:06:52.718Z,1580144812.718 [Startup:StartupSatComms:B] Stopped
2020-01-27T17:06:52.718Z,1580144812.718 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-27T17:06:52.718Z,1580144812.718 [Startup:StartupSatComms] Stopped
2020-01-27T17:06:52.719Z,1580144812.719 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-27T17:06:52.719Z,1580144812.719 [Startup](INFO): Completed Startup
2020-01-27T17:06:52.719Z,1580144812.719 [MissionManager](INFO): Startup is completed.
2020-01-27T17:06:52.720Z,1580144812.720 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-27T17:06:52.720Z,1580144812.720 [Startup] Stopped
2020-01-27T17:06:52.720Z,1580144812.720 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-27T17:06:52.720Z,1580144812.720 [Startup:A.GoToSurface] Stopped
2020-01-27T17:06:52.720Z,1580144812.720 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-27T17:06:53.126Z,1580144813.126 [MissionManager](IMPORTANT): Started mission Default
2020-01-27T17:06:53.126Z,1580144813.126 [Default] Running Loop=1
2020-01-27T17:06:53.126Z,1580144813.126 [Default](DEBUG): Aggregate::initialize Default
2020-01-27T17:06:53.126Z,1580144813.126 [Default:B.GoToSurface] Running Loop=1
2020-01-27T17:06:53.126Z,1580144813.126 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-27T17:06:53.127Z,1580144813.127 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-27T17:06:53.127Z,1580144813.127 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-27T17:06:53.127Z,1580144813.127 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-27T17:06:53.128Z,1580144813.128 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-27T17:06:53.128Z,1580144813.128 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-27T17:06:53.128Z,1580144813.128 [Default:A.Wait] Running Loop=1
2020-01-27T17:06:53.128Z,1580144813.128 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-27T17:07:00.801Z,1580144820.801 [DataOverHttps](INFO): Sending 732 bytes from file Logs/20200127T170308/Express0001.lzma
2020-01-27T17:07:02.806Z,1580144822.806 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0001.lzma.bak
2020-01-27T17:07:02.806Z,1580144822.806 [DataOverHttps](INFO): SBD MOMSN=12205058
2020-01-27T17:07:06.414Z,1580144826.414 [Default:A.Wait](INFO): Done Waiting.
2020-01-27T17:07:06.414Z,1580144826.414 [Default:A.Wait] Stopped
2020-01-27T17:07:06.414Z,1580144826.414 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T17:07:06.861Z,1580144826.861 [Default:CheckIn] Running Loop=1
2020-01-27T17:07:06.861Z,1580144826.861 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T17:07:06.861Z,1580144826.861 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T17:07:07.259Z,1580144827.259 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-27T17:07:18.516Z,1580144838.516 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-27T17:07:18.517Z,1580144838.517 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,  BI,-32768,-32768,-32768,-32768,V
2020-01-27T17:08:24.066Z,1580144904.066 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:08:24.067Z,1580144904.067 [RDI_Pathfinder](ERROR): Failed to parse:
:TS32768,V
2020-01-27T17:08:47.471Z,1580144927.471 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-27T17:08:47.471Z,1580144927.471 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,32768,-32768,V
2020-01-27T17:08:59.151Z,1580144939.151 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-27T17:09:19.801Z,1580144959.801 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-27T17:09:19.801Z,1580144959.801 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:09:19.816Z,1580144959.816 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:09:20.328Z,1580144960.328 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:09:20.328Z,1580144960.328 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-27T17:09:27.447Z,1580144967.447 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:09:27.447Z,1580144967.447 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012710222405,35.0, 0.0,1448.9,  0
2020-01-27T17:09:44.426Z,1580144984.426 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-01-27T17:10:07.465Z,1580145007.465 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-01-27T17:10:07.465Z,1580145007.465 [RDI_Pathfinder](ERROR): Failed to parse:
:RA   0.00,   0.00,   0.00,   0.00
2020-01-27T17:11:27.872Z,1580145087.872 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-27T17:11:27.876Z,1580145087.876 [BPC1](INFO): Received data from all battery sticks.
2020-01-27T17:12:01.497Z,1580145121.497 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-27T17:12:01.497Z,1580145121.497 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-27T17:12:01.497Z,1580145121.497 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-27T17:12:01.497Z,1580145121.497 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-27T17:12:01.552Z,1580145121.552 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-27T17:12:01.881Z,1580145121.881 [RDI_Pathfinder](INFO): Powering down
2020-01-27T17:12:02.635Z,1580145122.635 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-27T17:12:02.636Z,1580145122.636 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-27T17:12:07.055Z,1580145127.055 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T17:07:06.9Z
2020-01-27T17:12:07.055Z,1580145127.055 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T17:12:07.055Z,1580145127.055 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T17:12:07.472Z,1580145127.472 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-27T17:12:14.228Z,1580145134.228 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200127T170308/Courier0004.lzma
2020-01-27T17:12:15.944Z,1580145135.944 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-27T17:12:16.234Z,1580145136.234 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0004.lzma.bak
2020-01-27T17:12:16.234Z,1580145136.234 [DataOverHttps](INFO): SBD MOMSN=12205075
2020-01-27T17:12:20.805Z,1580145140.805 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-27T17:12:20.805Z,1580145140.805 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:12:20.848Z,1580145140.848 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:12:21.238Z,1580145141.238 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:12:21.238Z,1580145141.238 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-27T17:12:33.340Z,1580145153.340 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20200127T170308/Express0005.lzma
2020-01-27T17:12:35.351Z,1580145155.351 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0005.lzma.bak
2020-01-27T17:12:35.351Z,1580145155.351 [DataOverHttps](INFO): SBD MOMSN=12205077
2020-01-27T17:12:36.544Z,1580145156.544 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T17:12:36.544Z,1580145156.544 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T17:12:36.545Z,1580145156.545 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T17:13:56.507Z,1580145236.507 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T17:13:56.507Z,1580145236.507 [NAL9602] Data Fault, FailCount= 1
2020-01-27T17:13:56.507Z,1580145236.507 [NAL9602](ERROR): Data Fault
2020-01-27T17:13:56.567Z,1580145236.567 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T17:13:56.915Z,1580145236.915 [NAL9602](INFO): Powering down
2020-01-27T17:13:57.759Z,1580145237.759 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T17:13:57.759Z,1580145237.759 [NAL9602] No Fault, FailCount= 1
2020-01-27T17:14:27.211Z,1580145267.211 [NAL9602](INFO): Powering up NAL9602
2020-01-27T17:14:38.123Z,1580145278.123 [NAL9602](INFO): NAL9602 initialized
2020-01-27T17:15:21.805Z,1580145321.805 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-27T17:15:21.805Z,1580145321.805 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:15:21.815Z,1580145321.815 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:15:22.209Z,1580145322.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:15:22.209Z,1580145322.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-27T17:17:37.128Z,1580145457.128 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T17:17:37.128Z,1580145457.128 [Default:CheckIn:C.Wait] Stopped
2020-01-27T17:17:37.128Z,1580145457.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T17:17:37.129Z,1580145457.129 [Default:CheckIn:D] Running Loop=1
2020-01-27T17:17:37.539Z,1580145457.539 [Default:CheckIn:D] Stopped
2020-01-27T17:17:37.539Z,1580145457.539 [Default:CheckIn:E] Running Loop=1
2020-01-27T17:17:37.959Z,1580145457.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.740213 min 
2020-01-27T17:17:37.959Z,1580145457.959 [Default:CheckIn:E] Stopped
2020-01-27T17:17:37.960Z,1580145457.960 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T17:17:37.960Z,1580145457.960 [Default:CheckIn] Stopped
2020-01-27T17:17:37.960Z,1580145457.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T17:17:37.960Z,1580145457.960 [Default:CheckIn](INFO): Running loop #2
2020-01-27T17:17:37.960Z,1580145457.960 [Default:CheckIn] Running Loop=2
2020-01-27T17:17:37.960Z,1580145457.960 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T17:17:37.960Z,1580145457.960 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T17:18:22.769Z,1580145502.769 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-01-27T17:18:22.769Z,1580145502.769 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:18:22.779Z,1580145502.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:18:23.190Z,1580145503.190 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:18:23.190Z,1580145503.190 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-01-27T17:19:45.594Z,1580145585.594 [RDI_Pathfinder](ERROR): Failed to parse:
:0,   0.00,   0.00,   0.00,   0.00
2020-01-27T17:19:48.876Z,1580145588.876 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-27T17:19:48.876Z,1580145588.876 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     ,        +0.00,        +0.00,   0.00,452.99
2020-01-27T17:21:23.774Z,1580145683.774 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-01-27T17:21:23.774Z,1580145683.774 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:21:23.784Z,1580145683.784 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:21:24.230Z,1580145684.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:21:24.230Z,1580145684.230 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-01-27T17:22:38.129Z,1580145758.129 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T17:17:37.0Z
2020-01-27T17:22:38.129Z,1580145758.129 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T17:22:38.129Z,1580145758.129 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T17:22:45.916Z,1580145765.916 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200127T170308/Courier0007.lzma
2020-01-27T17:22:47.922Z,1580145767.922 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0007.lzma.bak
2020-01-27T17:22:47.923Z,1580145767.923 [DataOverHttps](INFO): SBD MOMSN=12205081
2020-01-27T17:23:05.084Z,1580145785.084 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20200127T170308/Express0008.lzma
2020-01-27T17:23:07.090Z,1580145787.090 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0008.lzma.bak
2020-01-27T17:23:07.090Z,1580145787.090 [DataOverHttps](INFO): SBD MOMSN=12205083
2020-01-27T17:23:08.419Z,1580145788.419 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T17:23:08.419Z,1580145788.419 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T17:23:08.419Z,1580145788.419 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T17:24:24.783Z,1580145864.783 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-01-27T17:24:24.783Z,1580145864.783 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:24:24.801Z,1580145864.801 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:24:25.202Z,1580145865.202 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:24:25.203Z,1580145865.203 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-01-27T17:24:40.899Z,1580145880.899 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T17:24:40.899Z,1580145880.899 [NAL9602] Data Fault, FailCount= 2
2020-01-27T17:24:40.899Z,1580145880.899 [NAL9602](ERROR): Data Fault
2020-01-27T17:24:40.988Z,1580145880.988 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T17:24:41.300Z,1580145881.300 [NAL9602](INFO): Powering down
2020-01-27T17:24:42.158Z,1580145882.158 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T17:24:42.158Z,1580145882.158 [NAL9602] No Fault, FailCount= 2
2020-01-27T17:24:55.883Z,1580145895.883 [RDI_Pathfinder](ERROR): Failed to parse:
:8,-32768,-32768,V
2020-01-27T17:25:11.599Z,1580145911.599 [NAL9602](INFO): Powering up NAL9602
2020-01-27T17:25:22.503Z,1580145922.503 [NAL9602](INFO): NAL9602 initialized
2020-01-27T17:25:37.952Z,1580145937.952 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-27T17:25:37.952Z,1580145937.952 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        1.97
2020-01-27T17:27:05.603Z,1580146025.603 [RDI_Pathfinder](ERROR): Failed to parse:
:R,   0.00,   0.00,   0.00,   0.00
2020-01-27T17:27:25.798Z,1580146045.798 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-01-27T17:27:25.798Z,1580146045.798 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:27:25.808Z,1580146045.808 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:27:26.228Z,1580146046.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:27:26.228Z,1580146046.228 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-01-27T17:28:09.023Z,1580146089.023 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T17:28:09.023Z,1580146089.023 [Default:CheckIn:C.Wait] Stopped
2020-01-27T17:28:09.023Z,1580146089.023 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T17:28:09.023Z,1580146089.023 [Default:CheckIn:D] Running Loop=1
2020-01-27T17:28:09.439Z,1580146089.439 [Default:CheckIn:D] Stopped
2020-01-27T17:28:09.439Z,1580146089.439 [Default:CheckIn:E] Running Loop=1
2020-01-27T17:28:09.866Z,1580146089.866 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.271879 min 
2020-01-27T17:28:09.866Z,1580146089.866 [Default:CheckIn:E] Stopped
2020-01-27T17:28:09.866Z,1580146089.866 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T17:28:09.866Z,1580146089.866 [Default:CheckIn] Stopped
2020-01-27T17:28:09.866Z,1580146089.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T17:28:09.867Z,1580146089.867 [Default:CheckIn](INFO): Running loop #3
2020-01-27T17:28:09.867Z,1580146089.867 [Default:CheckIn] Running Loop=3
2020-01-27T17:28:09.867Z,1580146089.867 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T17:28:09.867Z,1580146089.867 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T17:28:25.228Z,1580146105.228 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-27T17:28:25.228Z,1580146105.228 [RDI_Pathfinder](ERROR): Failed to parse:
:BD +0.00,        +0.00,        +0.00,   0.00,968.96
2020-01-27T17:30:26.780Z,1580146226.780 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-01-27T17:30:26.780Z,1580146226.780 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:30:26.807Z,1580146226.807 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:30:27.217Z,1580146227.217 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:30:27.217Z,1580146227.217 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-01-27T17:33:10.031Z,1580146390.031 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T17:28:09.9Z
2020-01-27T17:33:10.031Z,1580146390.031 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T17:33:10.031Z,1580146390.031 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T17:33:17.020Z,1580146397.020 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200127T170308/Courier0010.lzma
2020-01-27T17:33:19.026Z,1580146399.026 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0010.lzma.bak
2020-01-27T17:33:19.026Z,1580146399.026 [DataOverHttps](INFO): SBD MOMSN=12205087
2020-01-27T17:33:27.790Z,1580146407.790 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-01-27T17:33:27.790Z,1580146407.790 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:33:27.804Z,1580146407.804 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:33:28.208Z,1580146408.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:33:28.208Z,1580146408.208 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-01-27T17:33:36.372Z,1580146416.372 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200127T170308/Express0011.lzma
2020-01-27T17:33:38.379Z,1580146418.379 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0011.lzma.bak
2020-01-27T17:33:38.379Z,1580146418.379 [DataOverHttps](INFO): SBD MOMSN=12205089
2020-01-27T17:33:39.899Z,1580146419.899 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T17:33:39.899Z,1580146419.899 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T17:33:39.899Z,1580146419.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T17:35:24.107Z,1580146524.107 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T17:35:24.107Z,1580146524.107 [NAL9602] Data Fault, FailCount= 3
2020-01-27T17:35:24.107Z,1580146524.107 [NAL9602](ERROR): Data Fault
2020-01-27T17:35:24.143Z,1580146524.143 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T17:35:24.503Z,1580146524.503 [NAL9602](INFO): Powering down
2020-01-27T17:35:25.383Z,1580146525.383 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T17:35:25.383Z,1580146525.383 [NAL9602] No Fault, FailCount= 3
2020-01-27T17:35:54.803Z,1580146554.803 [NAL9602](INFO): Powering up NAL9602
2020-01-27T17:36:05.711Z,1580146565.711 [NAL9602](INFO): NAL9602 initialized
2020-01-27T17:36:28.777Z,1580146588.777 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-01-27T17:36:28.777Z,1580146588.777 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:36:28.797Z,1580146588.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:36:29.190Z,1580146589.190 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:36:29.190Z,1580146589.190 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-01-27T17:36:53.040Z,1580146613.040 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-27T17:36:53.040Z,1580146613.040 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,1476.90
2020-01-27T17:37:41.993Z,1580146661.993 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-27T17:37:41.993Z,1580146661.993 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-27T17:37:41.993Z,1580146661.993 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-27T17:37:41.993Z,1580146661.993 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-27T17:37:42.015Z,1580146662.015 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-27T17:37:42.373Z,1580146662.373 [RDI_Pathfinder](INFO): Powering down
2020-01-27T17:37:43.145Z,1580146663.145 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-27T17:37:43.145Z,1580146663.145 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-27T17:37:56.451Z,1580146676.451 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-27T17:38:40.519Z,1580146720.519 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T17:38:40.519Z,1580146720.519 [Default:CheckIn:C.Wait] Stopped
2020-01-27T17:38:40.519Z,1580146720.519 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T17:38:40.519Z,1580146720.519 [Default:CheckIn:D] Running Loop=1
2020-01-27T17:38:40.892Z,1580146720.892 [Default:CheckIn:D] Stopped
2020-01-27T17:38:40.892Z,1580146720.892 [Default:CheckIn:E] Running Loop=1
2020-01-27T17:38:41.302Z,1580146721.302 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.796088 min 
2020-01-27T17:38:41.302Z,1580146721.302 [Default:CheckIn:E] Stopped
2020-01-27T17:38:41.302Z,1580146721.302 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T17:38:41.302Z,1580146721.302 [Default:CheckIn] Stopped
2020-01-27T17:38:41.302Z,1580146721.302 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T17:38:41.303Z,1580146721.303 [Default:CheckIn](INFO): Running loop #4
2020-01-27T17:38:41.303Z,1580146721.303 [Default:CheckIn] Running Loop=4
2020-01-27T17:38:41.303Z,1580146721.303 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T17:38:41.303Z,1580146721.303 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T17:39:29.782Z,1580146769.782 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-01-27T17:39:29.782Z,1580146769.782 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:39:29.792Z,1580146769.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:39:30.198Z,1580146770.198 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:39:30.199Z,1580146770.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-01-27T17:39:48.769Z,1580146788.769 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:39:48.770Z,1580146788.770 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012710524540,35.0, 0.0,1448.9,  0
2020-01-27T17:41:50.395Z,1580146910.395 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-27T17:41:50.395Z,1580146910.395 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   I,-32768,-32768,-32768,-32768,V
2020-01-27T17:42:30.785Z,1580146950.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-01-27T17:42:30.785Z,1580146950.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:42:30.795Z,1580146950.795 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:42:31.197Z,1580146951.197 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:42:31.197Z,1580146951.197 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-01-27T17:43:41.508Z,1580147021.508 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T17:38:41.3Z
2020-01-27T17:43:41.508Z,1580147021.508 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T17:43:41.508Z,1580147021.508 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T17:43:49.128Z,1580147029.128 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200127T170308/Courier0013.lzma
2020-01-27T17:43:51.134Z,1580147031.134 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0013.lzma.bak
2020-01-27T17:43:51.134Z,1580147031.134 [DataOverHttps](INFO): SBD MOMSN=12205093
2020-01-27T17:44:08.232Z,1580147048.232 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200127T170308/Express0014.lzma
2020-01-27T17:44:10.238Z,1580147050.238 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0014.lzma.bak
2020-01-27T17:44:10.239Z,1580147050.239 [DataOverHttps](INFO): SBD MOMSN=12205095
2020-01-27T17:44:11.795Z,1580147051.795 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T17:44:11.795Z,1580147051.795 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T17:44:11.795Z,1580147051.795 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T17:44:23.569Z,1580147063.569 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:44:23.570Z,1580147063.570 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012710572040,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-27T17:45:25.748Z,1580147125.748 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-01-27T17:45:25.761Z,1580147125.761 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768-32768,V
2020-01-27T17:45:31.784Z,1580147131.784 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-01-27T17:45:31.784Z,1580147131.784 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:45:31.814Z,1580147131.814 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:45:32.208Z,1580147132.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:45:32.208Z,1580147132.208 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-01-27T17:46:07.315Z,1580147167.315 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T17:46:07.315Z,1580147167.315 [NAL9602] Data Fault, FailCount= 4
2020-01-27T17:46:07.315Z,1580147167.315 [NAL9602](ERROR): Data Fault
2020-01-27T17:46:07.411Z,1580147167.411 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T17:46:07.715Z,1580147167.715 [NAL9602](INFO): Powering down
2020-01-27T17:46:08.576Z,1580147168.576 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T17:46:08.576Z,1580147168.576 [NAL9602] No Fault, FailCount= 4
2020-01-27T17:46:38.011Z,1580147198.011 [NAL9602](INFO): Powering up NAL9602
2020-01-27T17:46:48.932Z,1580147208.932 [NAL9602](INFO): NAL9602 initialized
2020-01-27T17:48:32.802Z,1580147312.802 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-01-27T17:48:32.802Z,1580147312.802 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:48:32.812Z,1580147312.812 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:48:33.227Z,1580147313.227 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:48:33.227Z,1580147313.227 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-01-27T17:48:46.134Z,1580147326.134 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-01-27T17:48:46.134Z,1580147326.134 [RDI_Pathfinder](ERROR): Failed to parse:
:RA768,-32768,-32768,-32768,V
2020-01-27T17:49:12.375Z,1580147352.375 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T17:49:12.375Z,1580147352.375 [Default:CheckIn:C.Wait] Stopped
2020-01-27T17:49:12.375Z,1580147352.375 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T17:49:12.375Z,1580147352.375 [Default:CheckIn:D] Running Loop=1
2020-01-27T17:49:12.797Z,1580147352.797 [Default:CheckIn:D] Stopped
2020-01-27T17:49:12.797Z,1580147352.797 [Default:CheckIn:E] Running Loop=1
2020-01-27T17:49:13.203Z,1580147353.203 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.327840 min 
2020-01-27T17:49:13.203Z,1580147353.203 [Default:CheckIn:E] Stopped
2020-01-27T17:49:13.203Z,1580147353.203 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T17:49:13.204Z,1580147353.204 [Default:CheckIn] Stopped
2020-01-27T17:49:13.204Z,1580147353.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T17:49:13.204Z,1580147353.204 [Default:CheckIn](INFO): Running loop #5
2020-01-27T17:49:13.204Z,1580147353.204 [Default:CheckIn] Running Loop=5
2020-01-27T17:49:13.204Z,1580147353.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T17:49:13.204Z,1580147353.204 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T17:49:50.753Z,1580147390.753 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:49:50.754Z,1580147390.754 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711024740,35.0, 0.0,1448.9,  0
2020-01-27T17:51:33.794Z,1580147493.794 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-01-27T17:51:33.795Z,1580147493.795 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:51:33.804Z,1580147493.804 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:51:34.212Z,1580147494.212 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:51:34.212Z,1580147494.212 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-01-27T17:54:13.357Z,1580147653.357 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T17:49:13.2Z
2020-01-27T17:54:13.357Z,1580147653.357 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T17:54:13.357Z,1580147653.357 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T17:54:21.116Z,1580147661.116 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200127T170308/Courier0016.lzma
2020-01-27T17:54:23.122Z,1580147663.122 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0016.lzma.bak
2020-01-27T17:54:23.122Z,1580147663.122 [DataOverHttps](INFO): SBD MOMSN=12205099
2020-01-27T17:54:34.787Z,1580147674.787 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-01-27T17:54:34.787Z,1580147674.787 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:54:34.797Z,1580147674.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:54:35.175Z,1580147675.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:54:35.175Z,1580147675.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-01-27T17:54:36.792Z,1580147676.792 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:54:36.792Z,1580147676.792 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711073340,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-27T17:54:40.316Z,1580147680.316 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200127T170308/Express0017.lzma
2020-01-27T17:54:42.322Z,1580147682.322 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0017.lzma.bak
2020-01-27T17:54:42.323Z,1580147682.323 [DataOverHttps](INFO): SBD MOMSN=12205101
2020-01-27T17:54:43.679Z,1580147683.679 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T17:54:43.679Z,1580147683.679 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T17:54:43.679Z,1580147683.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T17:55:13.572Z,1580147713.572 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-27T17:55:13.572Z,1580147713.572 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.0  +0.00,   0.00,1036.96
2020-01-27T17:56:42.854Z,1580147802.854 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:56:42.854Z,1580147802.854 [RDI_Pathfinder](ERROR): Failed to parse:
:TS1093940,35.0, -0.1,   0.0,1448.9,  0
2020-01-27T17:56:51.695Z,1580147811.695 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T17:56:51.695Z,1580147811.695 [NAL9602] Data Fault, FailCount= 5
2020-01-27T17:56:51.695Z,1580147811.695 [NAL9602](ERROR): Data Fault
2020-01-27T17:56:51.771Z,1580147811.771 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T17:56:51.771Z,1580147811.771 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-01-27T17:56:52.095Z,1580147812.095 [NAL9602](INFO): Powering down
2020-01-27T17:56:52.148Z,1580147812.148 [CommandLine](FAULT): Scheduling is paused
2020-01-27T17:56:52.153Z,1580147812.153 [CBIT](INFO): Critical error at 20200127T175651
2020-01-27T17:56:52.154Z,1580147812.154 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-27T17:57:22.835Z,1580147842.835 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-27T17:57:22.835Z,1580147842.835 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-27T17:57:35.776Z,1580147855.776 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-01-27T17:57:35.776Z,1580147855.776 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T17:57:35.786Z,1580147855.786 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T17:57:36.184Z,1580147856.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T17:57:36.185Z,1580147856.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-01-27T17:57:53.937Z,1580147873.937 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:57:53.938Z,1580147873.938 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711105040,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-27T17:58:51.748Z,1580147931.748 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T17:58:51.748Z,1580147931.748 [NAL9602] No Fault, FailCount= 5
2020-01-27T17:58:52.087Z,1580147932.087 [NAL9602](INFO): Powering up NAL9602
2020-01-27T17:59:02.991Z,1580147942.991 [NAL9602](INFO): NAL9602 initialized
2020-01-27T17:59:44.230Z,1580147984.230 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T17:59:44.230Z,1580147984.230 [Default:CheckIn:C.Wait] Stopped
2020-01-27T17:59:44.230Z,1580147984.230 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T17:59:44.230Z,1580147984.230 [Default:CheckIn:D] Running Loop=1
2020-01-27T17:59:44.659Z,1580147984.659 [Default:CheckIn:D] Stopped
2020-01-27T17:59:44.659Z,1580147984.659 [Default:CheckIn:E] Running Loop=1
2020-01-27T17:59:45.060Z,1580147985.060 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.858883 min 
2020-01-27T17:59:45.060Z,1580147985.060 [Default:CheckIn:E] Stopped
2020-01-27T17:59:45.061Z,1580147985.061 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T17:59:45.061Z,1580147985.061 [Default:CheckIn] Stopped
2020-01-27T17:59:45.061Z,1580147985.061 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T17:59:45.061Z,1580147985.061 [Default:CheckIn](INFO): Running loop #6
2020-01-27T17:59:45.061Z,1580147985.061 [Default:CheckIn] Running Loop=6
2020-01-27T17:59:45.061Z,1580147985.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T17:59:45.061Z,1580147985.061 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T17:59:58.402Z,1580147998.402 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T17:59:58.402Z,1580147998.402 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711125540,35.0, -0.1,   0.0,1-32768,V
2020-01-27T18:00:36.758Z,1580148036.758 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-01-27T18:00:36.758Z,1580148036.758 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:00:36.788Z,1580148036.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:00:37.165Z,1580148037.165 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:00:37.165Z,1580148037.165 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-01-27T18:00:39.575Z,1580148039.575 [RDI_Pathfinder](ERROR): Failed to parse:
:   +0.00,        +0.00,        +0.00,1361.92
2020-01-27T18:00:57.829Z,1580148057.829 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-27T18:00:57.829Z,1580148057.829 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-27T18:00:57.829Z,1580148057.829 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-27T18:00:57.829Z,1580148057.829 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-27T18:00:57.852Z,1580148057.852 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-27T18:00:58.221Z,1580148058.221 [RDI_Pathfinder](INFO): Powering down
2020-01-27T18:00:58.000Z,1580148059.000 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-27T18:00:58.000Z,1580148059.000 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-27T18:01:12.334Z,1580148072.334 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-27T18:03:27.642Z,1580148207.642 [RDI_Pathfinder](ERROR): Failed to parse:
:8,-32768,-32768,V
2020-01-27T18:03:37.754Z,1580148217.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2020-01-27T18:03:37.754Z,1580148217.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:03:37.764Z,1580148217.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:03:38.168Z,1580148218.168 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:03:38.168Z,1580148218.168 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2020-01-27T18:04:45.261Z,1580148285.261 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T17:59:45.1Z
2020-01-27T18:04:45.261Z,1580148285.261 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T18:04:45.261Z,1580148285.261 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T18:04:52.194Z,1580148292.194 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20200127T170308/Courier0019.lzma
2020-01-27T18:04:54.198Z,1580148294.198 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0019.lzma.bak
2020-01-27T18:04:54.198Z,1580148294.198 [DataOverHttps](INFO): SBD MOMSN=12205105
2020-01-27T18:05:11.613Z,1580148311.613 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20200127T170308/Express0020.lzma
2020-01-27T18:05:13.618Z,1580148313.618 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0020.lzma.bak
2020-01-27T18:05:13.619Z,1580148313.619 [DataOverHttps](INFO): SBD MOMSN=12205108
2020-01-27T18:05:15.159Z,1580148315.159 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T18:05:15.159Z,1580148315.159 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T18:05:15.159Z,1580148315.159 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T18:05:23.240Z,1580148323.240 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-27T18:05:23.240Z,1580148323.240 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,251.00
2020-01-27T18:06:38.782Z,1580148398.782 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2020-01-27T18:06:38.782Z,1580148398.782 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:06:38.792Z,1580148398.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:06:39.203Z,1580148399.203 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:06:39.203Z,1580148399.203 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2020-01-27T18:07:42.620Z,1580148462.620 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-01-27T18:08:59.771Z,1580148539.771 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-01-27T18:08:59.771Z,1580148539.771 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0-32768,-32768,-32768,V
2020-01-27T18:09:04.195Z,1580148544.195 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T18:09:04.195Z,1580148544.195 [NAL9602] Data Fault, FailCount= 1
2020-01-27T18:09:04.195Z,1580148544.195 [NAL9602](ERROR): Data Fault
2020-01-27T18:09:04.289Z,1580148544.289 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T18:09:04.596Z,1580148544.596 [NAL9602](INFO): Powering down
2020-01-27T18:09:05.456Z,1580148545.456 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T18:09:05.456Z,1580148545.456 [NAL9602] No Fault, FailCount= 1
2020-01-27T18:09:34.887Z,1580148574.887 [NAL9602](INFO): Powering up NAL9602
2020-01-27T18:09:39.779Z,1580148579.779 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2020-01-27T18:09:39.779Z,1580148579.779 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:09:39.788Z,1580148579.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:09:40.194Z,1580148580.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:09:40.194Z,1580148580.194 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2020-01-27T18:09:45.447Z,1580148585.447 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711224225,35.0, -0.1,   0.0,1448.9,  0
2020-01-27T18:09:45.813Z,1580148585.813 [NAL9602](INFO): NAL9602 initialized
2020-01-27T18:10:15.739Z,1580148615.739 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T18:10:15.739Z,1580148615.739 [Default:CheckIn:C.Wait] Stopped
2020-01-27T18:10:15.739Z,1580148615.739 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T18:10:15.739Z,1580148615.739 [Default:CheckIn:D] Running Loop=1
2020-01-27T18:10:16.139Z,1580148616.139 [Default:CheckIn:D] Stopped
2020-01-27T18:10:16.139Z,1580148616.139 [Default:CheckIn:E] Running Loop=1
2020-01-27T18:10:16.555Z,1580148616.555 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.383545 min 
2020-01-27T18:10:16.556Z,1580148616.556 [Default:CheckIn:E] Stopped
2020-01-27T18:10:16.556Z,1580148616.556 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T18:10:16.556Z,1580148616.556 [Default:CheckIn] Stopped
2020-01-27T18:10:16.556Z,1580148616.556 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T18:10:16.556Z,1580148616.556 [Default:CheckIn](INFO): Running loop #7
2020-01-27T18:10:16.556Z,1580148616.556 [Default:CheckIn] Running Loop=7
2020-01-27T18:10:16.556Z,1580148616.556 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T18:10:16.556Z,1580148616.556 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T18:12:20.583Z,1580148740.583 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-27T18:12:20.584Z,1580148740.584 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3768,V
2020-01-27T18:12:40.776Z,1580148760.776 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2020-01-27T18:12:40.776Z,1580148760.776 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:12:40.818Z,1580148760.818 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:12:41.184Z,1580148761.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:12:41.184Z,1580148761.184 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2020-01-27T18:12:47.627Z,1580148767.627 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T18:12:47.627Z,1580148767.627 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711254425,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-27T18:13:27.620Z,1580148807.620 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-27T18:13:27.620Z,1580148807.620 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-27T18:13:44.603Z,1580148824.603 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T18:13:44.603Z,1580148824.603 [RDI_Pathfinder](ERROR): Failed to parse:
:TS1264125,35.0, -0.1,   0.0,1448.9,  0
2020-01-27T18:15:06.611Z,1580148906.611 [RDI_Pathfinder](ERROR): Failed to parse:
:R,   0.00,   0.00,   0.00,   0.00
2020-01-27T18:15:16.718Z,1580148916.718 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T18:10:16.6Z
2020-01-27T18:15:16.718Z,1580148916.718 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T18:15:16.718Z,1580148916.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T18:15:24.020Z,1580148924.020 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200127T170308/Courier0022.lzma
2020-01-27T18:15:26.026Z,1580148926.026 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0022.lzma.bak
2020-01-27T18:15:26.027Z,1580148926.027 [DataOverHttps](INFO): SBD MOMSN=12205113
2020-01-27T18:15:41.783Z,1580148941.783 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2020-01-27T18:15:41.783Z,1580148941.783 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:15:41.825Z,1580148941.825 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:15:42.252Z,1580148942.252 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:15:42.253Z,1580148942.253 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2020-01-27T18:15:43.781Z,1580148943.781 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200127T170308/Express0023.lzma
2020-01-27T18:15:45.787Z,1580148945.787 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0023.lzma.bak
2020-01-27T18:15:45.787Z,1580148945.787 [DataOverHttps](INFO): SBD MOMSN=12205115
2020-01-27T18:15:47.036Z,1580148947.036 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T18:15:47.036Z,1580148947.036 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T18:15:47.037Z,1580148947.037 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T18:16:01.956Z,1580148961.956 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T18:16:01.956Z,1580148961.956 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711285825,35.0, -0.1,   .9,  0
2020-01-27T18:16:26.620Z,1580148986.620 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-27T18:16:26.620Z,1580148986.620 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-27T18:16:47.624Z,1580149007.624 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T18:16:47.625Z,1580149007.625 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711294425,35.0, -00,1448.9,  0
2020-01-27T18:18:42.763Z,1580149122.763 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2020-01-27T18:18:42.763Z,1580149122.763 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:18:42.773Z,1580149122.773 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:18:43.193Z,1580149123.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:18:43.194Z,1580149123.194 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2020-01-27T18:18:45.585Z,1580149125.585 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T18:18:45.585Z,1580149125.585 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711314225,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00
2020-01-27T18:18:52.052Z,1580149132.052 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-27T18:18:52.055Z,1580149132.055 [BPC1](INFO): Received data from all battery sticks.
2020-01-27T18:19:46.220Z,1580149186.220 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-27T18:19:46.220Z,1580149186.220 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,  BI,-32768,-32768,-32768,-32768,V
2020-01-27T18:19:46.980Z,1580149186.980 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T18:19:46.980Z,1580149186.980 [NAL9602] Data Fault, FailCount= 2
2020-01-27T18:19:46.980Z,1580149186.980 [NAL9602](ERROR): Data Fault
2020-01-27T18:19:47.044Z,1580149187.044 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T18:19:47.379Z,1580149187.379 [NAL9602](INFO): Powering down
2020-01-27T18:19:48.218Z,1580149188.218 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T18:19:48.218Z,1580149188.218 [NAL9602] No Fault, FailCount= 2
2020-01-27T18:20:17.675Z,1580149217.675 [NAL9602](INFO): Powering up NAL9602
2020-01-27T18:20:28.588Z,1580149228.588 [NAL9602](INFO): NAL9602 initialized
2020-01-27T18:20:47.619Z,1580149247.619 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T18:20:47.619Z,1580149247.619 [Default:CheckIn:C.Wait] Stopped
2020-01-27T18:20:47.620Z,1580149247.620 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T18:20:47.620Z,1580149247.620 [Default:CheckIn:D] Running Loop=1
2020-01-27T18:20:48.007Z,1580149248.007 [Default:CheckIn:D] Stopped
2020-01-27T18:20:48.007Z,1580149248.007 [Default:CheckIn:E] Running Loop=1
2020-01-27T18:20:48.420Z,1580149248.420 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.914681 min 
2020-01-27T18:20:48.420Z,1580149248.420 [Default:CheckIn:E] Stopped
2020-01-27T18:20:48.420Z,1580149248.420 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T18:20:48.420Z,1580149248.420 [Default:CheckIn] Stopped
2020-01-27T18:20:48.420Z,1580149248.420 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T18:20:48.420Z,1580149248.420 [Default:CheckIn](INFO): Running loop #8
2020-01-27T18:20:48.420Z,1580149248.420 [Default:CheckIn] Running Loop=8
2020-01-27T18:20:48.420Z,1580149248.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T18:20:48.421Z,1580149248.421 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T18:21:06.589Z,1580149266.589 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-27T18:21:06.589Z,1580149266.589 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-27T18:21:43.773Z,1580149303.773 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2020-01-27T18:21:43.773Z,1580149303.773 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:21:43.788Z,1580149303.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:21:44.236Z,1580149304.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:21:44.236Z,1580149304.236 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2020-01-27T18:24:44.757Z,1580149484.757 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2020-01-27T18:24:44.757Z,1580149484.757 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:24:44.771Z,1580149484.771 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:24:45.228Z,1580149485.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:24:45.228Z,1580149485.228 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2020-01-27T18:25:12.239Z,1580149512.239 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-27T18:25:12.239Z,1580149512.239 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,32768,-32768,V
2020-01-27T18:25:48.591Z,1580149548.591 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T18:20:48.4Z
2020-01-27T18:25:48.591Z,1580149548.591 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T18:25:48.591Z,1580149548.591 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T18:25:56.564Z,1580149556.564 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200127T170308/Courier0025.lzma
2020-01-27T18:25:58.570Z,1580149558.570 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0025.lzma.bak
2020-01-27T18:25:58.570Z,1580149558.570 [DataOverHttps](INFO): SBD MOMSN=12205119
2020-01-27T18:26:15.650Z,1580149575.650 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200127T170308/Express0026.lzma
2020-01-27T18:26:17.657Z,1580149577.657 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0026.lzma.bak
2020-01-27T18:26:17.657Z,1580149577.657 [DataOverHttps](INFO): SBD MOMSN=12205121
2020-01-27T18:26:18.891Z,1580149578.891 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T18:26:18.891Z,1580149578.891 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T18:26:18.891Z,1580149578.891 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T18:27:18.299Z,1580149638.299 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-27T18:27:18.299Z,1580149638.299 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,1565.89
2020-01-27T18:27:45.753Z,1580149665.753 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2020-01-27T18:27:45.753Z,1580149665.753 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:27:45.762Z,1580149665.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:27:46.184Z,1580149666.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:27:46.185Z,1580149666.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2020-01-27T18:30:31.379Z,1580149831.379 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T18:30:31.379Z,1580149831.379 [NAL9602] Data Fault, FailCount= 3
2020-01-27T18:30:31.379Z,1580149831.379 [NAL9602](ERROR): Data Fault
2020-01-27T18:30:31.455Z,1580149831.455 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T18:30:31.771Z,1580149831.771 [NAL9602](INFO): Powering down
2020-01-27T18:30:32.671Z,1580149832.671 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T18:30:32.671Z,1580149832.671 [NAL9602] No Fault, FailCount= 3
2020-01-27T18:30:46.753Z,1580149846.753 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2020-01-27T18:30:46.753Z,1580149846.753 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:30:46.763Z,1580149846.763 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:30:47.156Z,1580149847.156 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:30:47.156Z,1580149847.156 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2020-01-27T18:31:02.076Z,1580149862.076 [NAL9602](INFO): Powering up NAL9602
2020-01-27T18:31:12.980Z,1580149872.980 [NAL9602](INFO): NAL9602 initialized
2020-01-27T18:31:19.475Z,1580149879.475 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T18:31:19.475Z,1580149879.475 [Default:CheckIn:C.Wait] Stopped
2020-01-27T18:31:19.475Z,1580149879.475 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T18:31:19.475Z,1580149879.475 [Default:CheckIn:D] Running Loop=1
2020-01-27T18:31:19.891Z,1580149879.891 [Default:CheckIn:D] Stopped
2020-01-27T18:31:19.891Z,1580149879.891 [Default:CheckIn:E] Running Loop=1
2020-01-27T18:31:20.291Z,1580149880.291 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.446077 min 
2020-01-27T18:31:20.291Z,1580149880.291 [Default:CheckIn:E] Stopped
2020-01-27T18:31:20.292Z,1580149880.292 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T18:31:20.292Z,1580149880.292 [Default:CheckIn] Stopped
2020-01-27T18:31:20.292Z,1580149880.292 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T18:31:20.292Z,1580149880.292 [Default:CheckIn](INFO): Running loop #9
2020-01-27T18:31:20.292Z,1580149880.292 [Default:CheckIn] Running Loop=9
2020-01-27T18:31:20.292Z,1580149880.292 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T18:31:20.292Z,1580149880.292 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T18:31:33.305Z,1580149893.305 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-27T18:31:33.305Z,1580149893.305 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-27T18:31:33.305Z,1580149893.305 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-27T18:31:33.305Z,1580149893.305 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-27T18:31:33.328Z,1580149893.328 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-27T18:31:33.689Z,1580149893.689 [RDI_Pathfinder](INFO): Powering down
2020-01-27T18:31:34.456Z,1580149894.456 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-27T18:31:34.456Z,1580149894.456 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-27T18:31:47.745Z,1580149907.745 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-27T18:33:47.748Z,1580150027.748 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30
2020-01-27T18:33:47.748Z,1580150027.748 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:33:47.758Z,1580150027.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:33:48.162Z,1580150028.162 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:33:48.162Z,1580150028.162 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30
2020-01-27T18:34:01.875Z,1580150041.875 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-27T18:34:01.875Z,1580150041.875 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,  133.00
2020-01-27T18:35:21.871Z,1580150121.871 [RDI_Pathfinder](ERROR): Failed to parse:
    +0.00,        +0.00,        +0.00,   0.00,213.00
2020-01-27T18:36:20.458Z,1580150180.458 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-27T18:31:20.3Z
2020-01-27T18:36:20.458Z,1580150180.458 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T18:36:20.459Z,1580150180.459 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-27T18:36:24.099Z,1580150184.099 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-27T18:36:27.768Z,1580150187.768 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200127T170308/Courier0028.lzma
2020-01-27T18:36:29.786Z,1580150189.786 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Courier0028.lzma.bak
2020-01-27T18:36:29.787Z,1580150189.787 [DataOverHttps](INFO): SBD MOMSN=12205125
2020-01-27T18:36:48.804Z,1580150208.804 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31
2020-01-27T18:36:48.804Z,1580150208.804 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:36:48.878Z,1580150208.878 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:36:49.207Z,1580150209.207 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:36:49.207Z,1580150209.207 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31
2020-01-27T18:36:50.981Z,1580150210.981 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200127T170308/Express0029.lzma
2020-01-27T18:36:52.986Z,1580150212.986 [DataOverHttps](INFO): Moved sent file to Logs/20200127T170308/Express0029.lzma.bak
2020-01-27T18:36:52.987Z,1580150212.987 [DataOverHttps](INFO): SBD MOMSN=12205127
2020-01-27T18:36:54.414Z,1580150214.414 [Default:CheckIn:Read_Iridium] Stopped
2020-01-27T18:36:54.414Z,1580150214.414 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-27T18:36:54.414Z,1580150214.414 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-27T18:37:20.685Z,1580150240.685 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-27T18:37:20.685Z,1580150240.685 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768-32768,-32768,-32768,V
2020-01-27T18:39:17.044Z,1580150357.044 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-27T18:39:49.778Z,1580150389.778 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32
2020-01-27T18:39:49.778Z,1580150389.778 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:39:49.788Z,1580150389.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:39:50.166Z,1580150390.166 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:39:50.166Z,1580150390.166 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32
2020-01-27T18:40:03.065Z,1580150403.065 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-27T18:40:03.065Z,1580150403.065 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012711525972,35.0, -0.1,448.9,  0
2020-01-27T18:41:15.764Z,1580150475.764 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-27T18:41:15.764Z,1580150475.764 [NAL9602] Data Fault, FailCount= 4
2020-01-27T18:41:15.764Z,1580150475.764 [NAL9602](ERROR): Data Fault
2020-01-27T18:41:15.796Z,1580150475.796 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-27T18:41:16.163Z,1580150476.163 [NAL9602](INFO): Powering down
2020-01-27T18:41:17.053Z,1580150477.053 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-27T18:41:17.053Z,1580150477.053 [NAL9602] No Fault, FailCount= 4
2020-01-27T18:41:46.475Z,1580150506.475 [NAL9602](INFO): Powering up NAL9602
2020-01-27T18:41:54.983Z,1580150514.983 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-27T18:41:54.983Z,1580150514.983 [Default:CheckIn:C.Wait] Stopped
2020-01-27T18:41:54.983Z,1580150514.983 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-27T18:41:54.984Z,1580150514.984 [Default:CheckIn:D] Running Loop=1
2020-01-27T18:41:55.387Z,1580150515.387 [Default:CheckIn:D] Stopped
2020-01-27T18:41:55.387Z,1580150515.387 [Default:CheckIn:E] Running Loop=1
2020-01-27T18:41:55.790Z,1580150515.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.037671 min 
2020-01-27T18:41:55.790Z,1580150515.790 [Default:CheckIn:E] Stopped
2020-01-27T18:41:55.791Z,1580150515.791 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-27T18:41:55.791Z,1580150515.791 [Default:CheckIn] Stopped
2020-01-27T18:41:55.791Z,1580150515.791 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T18:41:55.791Z,1580150515.791 [Default:CheckIn](INFO): Running loop #10
2020-01-27T18:41:55.791Z,1580150515.791 [Default:CheckIn] Running Loop=10
2020-01-27T18:41:55.791Z,1580150515.791 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-27T18:41:55.791Z,1580150515.791 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-27T18:41:57.375Z,1580150517.375 [NAL9602](INFO): NAL9602 initialized
2020-01-27T18:42:50.754Z,1580150570.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33
2020-01-27T18:42:50.754Z,1580150570.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:42:50.764Z,1580150570.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:42:51.159Z,1580150571.159 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:42:51.159Z,1580150571.159 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33
2020-01-27T18:45:51.746Z,1580150751.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34
2020-01-27T18:45:51.746Z,1580150751.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-27T18:45:51.764Z,1580150751.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-27T18:45:52.174Z,1580150752.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-27T18:45:52.174Z,1580150752.174 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34
2020-01-27T18:46:27.694Z,1580150787.694 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-27T18:46:27.694Z,1580150787.694 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3278,V
2020-01-27T18:46:41.334Z,1580150801.334 [CommandLine](IMPORTANT): got command quit
2020-01-27T18:46:42.343Z,1580150802.343 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-27T18:46:42.344Z,1580150802.344 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:42.441Z,1580150802.441 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-27T18:46:42.441Z,1580150802.441 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:42.442Z,1580150802.442 [CommandLine](INFO): Join timeout helper Thread ID is 6983
2020-01-27T18:46:42.449Z,1580150802.449 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-27T18:46:42.449Z,1580150802.449 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:42.450Z,1580150802.450 [NavChartDb](INFO): Join timeout helper Thread ID is 6984
2020-01-27T18:46:42.749Z,1580150802.749 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-27T18:46:42.749Z,1580150802.749 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:42.757Z,1580150802.757 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-27T18:46:42.757Z,1580150802.757 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:42.757Z,1580150802.757 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 6985
2020-01-27T18:46:42.829Z,1580150802.829 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-27T18:46:42.829Z,1580150802.829 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-27T18:46:42.830Z,1580150802.830 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:42.849Z,1580150802.849 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-27T18:46:42.849Z,1580150802.849 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:42.849Z,1580150802.849 [Radio_Surface](INFO): Join timeout helper Thread ID is 6986
2020-01-27T18:46:43.211Z,1580150803.211 [Radio_Surface](INFO): Powering down
2020-01-27T18:46:43.211Z,1580150803.211 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-27T18:46:43.212Z,1580150803.212 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:43.229Z,1580150803.229 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-27T18:46:43.229Z,1580150803.229 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:43.230Z,1580150803.230 [DataOverHttps](INFO): Join timeout helper Thread ID is 6989
2020-01-27T18:46:44.105Z,1580150804.105 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-27T18:46:44.105Z,1580150804.105 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.110Z,1580150804.110 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-27T18:46:44.110Z,1580150804.110 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.110Z,1580150804.110 [logger](INFO): Join timeout helper Thread ID is 6990
2020-01-27T18:46:44.137Z,1580150804.137 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-27T18:46:44.137Z,1580150804.137 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.150Z,1580150804.150 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-27T18:46:44.150Z,1580150804.150 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.150Z,1580150804.150 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-27T18:46:44.150Z,1580150804.150 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.150Z,1580150804.150 [controlThread](INFO): Join timeout helper Thread ID is 6991
2020-01-27T18:46:44.213Z,1580150804.213 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-27T18:46:44.213Z,1580150804.213 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-27T18:46:44.214Z,1580150804.214 [AHRS_M2](INFO): Powering down
2020-01-27T18:46:44.285Z,1580150804.285 [DDM](INFO): Powering down
2020-01-27T18:46:44.373Z,1580150804.373 [DUSBL_Hydroid](INFO): Powering down
2020-01-27T18:46:44.461Z,1580150804.461 [Micromodem](INFO): Powering down
2020-01-27T18:46:44.557Z,1580150804.557 [NAL9602](INFO): Powering down
2020-01-27T18:46:44.629Z,1580150804.629 [RDI_Pathfinder](INFO): Powering down
2020-01-27T18:46:44.630Z,1580150804.630 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-27T18:46:44.631Z,1580150804.631 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-27T18:46:44.632Z,1580150804.632 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-27T18:46:44.632Z,1580150804.632 [MissionManager](INFO): Uninitializing Mission Default
2020-01-27T18:46:44.632Z,1580150804.632 [Default] Stopped
2020-01-27T18:46:44.632Z,1580150804.632 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-27T18:46:44.632Z,1580150804.632 [Default:B.GoToSurface] Stopped
2020-01-27T18:46:44.633Z,1580150804.633 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-27T18:46:44.633Z,1580150804.633 [Default:CheckIn] Stopped
2020-01-27T18:46:44.633Z,1580150804.633 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-27T18:46:44.633Z,1580150804.633 [Default:CheckIn:Read_GPS] Stopped
2020-01-27T18:46:44.635Z,1580150804.635 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-27T18:46:44.636Z,1580150804.636 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-27T18:46:44.636Z,1580150804.636 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-27T18:46:44.636Z,1580150804.636 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-27T18:46:44.636Z,1580150804.636 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-27T18:46:44.637Z,1580150804.637 [BuoyancyServo](INFO): Powering down
2020-01-27T18:46:44.649Z,1580150804.649 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-27T18:46:44.649Z,1580150804.649 [ElevatorServo](INFO): Powering down
2020-01-27T18:46:44.650Z,1580150804.650 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-27T18:46:44.650Z,1580150804.650 [MassServo](INFO): Powering down
2020-01-27T18:46:44.651Z,1580150804.651 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-27T18:46:44.651Z,1580150804.651 [RudderServo](INFO): Powering down
2020-01-27T18:46:44.652Z,1580150804.652 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-27T18:46:44.652Z,1580150804.652 [ThrusterServo](INFO): Powering down
2020-01-27T18:46:44.652Z,1580150804.652 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-27T18:46:44.653Z,1580150804.653 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-27T18:46:44.653Z,1580150804.653 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-27T18:46:44.653Z,1580150804.653 [CBIT](DEBUG): Powering off loads.
2020-01-27T18:46:44.665Z,1580150804.665 [CBIT](DEBUG): Disabling WDT.
2020-01-27T18:46:44.677Z,1580150804.677 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-27T18:46:44.678Z,1580150804.678 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.733Z,1580150804.733 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.743Z,1580150804.743 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.791Z,1580150804.791 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.849Z,1580150804.849 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-27T18:46:44.910Z,1580150804.910 [logger ThreadHandler](INFO): Thread cancelled.