2020-01-24T18:10:22.751Z,1579889422.751 [Supervisor](DEBUG): Initializing supervisor.
2020-01-24T18:10:22.753Z,1579889422.753 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-24T18:10:22.754Z,1579889422.754 [SyncHandler](INFO): Protected caller Thread ID is 820
2020-01-24T18:10:22.754Z,1579889422.754 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-24T18:10:22.755Z,1579889422.755 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-24T18:10:22.755Z,1579889422.755 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 821
2020-01-24T18:10:22.758Z,1579889422.758 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-24T18:10:22.770Z,1579889422.770 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-24T18:10:22.771Z,1579889422.771 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-24T18:10:22.771Z,1579889422.771 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 822
2020-01-24T18:10:22.772Z,1579889422.772 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-24T18:10:22.772Z,1579889422.772 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-24T18:10:22.773Z,1579889422.773 [logger ThreadHandler](INFO): Protected caller Thread ID is 823
2020-01-24T18:10:22.775Z,1579889422.775 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-24T18:10:22.775Z,1579889422.775 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-24T18:10:22.779Z,1579889422.779 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-24T18:10:23.212Z,1579889423.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-24T18:10:23.213Z,1579889423.213 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-24T18:10:23.310Z,1579889423.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-24T18:10:23.312Z,1579889423.312 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-24T18:10:23.625Z,1579889423.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-24T18:10:23.627Z,1579889423.627 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-24T18:10:23.764Z,1579889423.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-24T18:10:23.766Z,1579889423.766 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-24T18:10:23.957Z,1579889423.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-24T18:10:23.957Z,1579889423.957 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-24T18:10:24.418Z,1579889424.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-24T18:10:24.420Z,1579889424.420 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-24T18:10:24.637Z,1579889424.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-24T18:10:24.638Z,1579889424.638 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-24T18:10:24.780Z,1579889424.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-24T18:10:24.782Z,1579889424.782 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-24T18:10:24.971Z,1579889424.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-24T18:10:24.972Z,1579889424.972 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-24T18:10:25.069Z,1579889425.069 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-24T18:10:25.071Z,1579889425.071 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-24T18:10:25.369Z,1579889425.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-24T18:10:25.371Z,1579889425.371 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-24T18:10:25.451Z,1579889425.451 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-24T18:10:25.559Z,1579889425.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-24T18:10:26.137Z,1579889426.137 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-24T18:10:26.967Z,1579889426.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-24T18:10:26.969Z,1579889426.969 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-24T18:10:27.375Z,1579889427.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-24T18:10:27.377Z,1579889427.377 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-24T18:10:27.380Z,1579889427.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-24T18:10:27.590Z,1579889427.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-24T18:10:27.691Z,1579889427.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-24T18:10:27.790Z,1579889427.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-24T18:10:28.018Z,1579889428.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-24T18:10:28.019Z,1579889428.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-24T18:10:28.104Z,1579889428.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-24T18:10:28.200Z,1579889428.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-24T18:10:28.298Z,1579889428.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-24T18:10:28.380Z,1579889428.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-24T18:10:28.489Z,1579889428.489 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-01-24T18:10:28.581Z,1579889428.581 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-24T18:10:28.763Z,1579889428.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-24T18:10:28.893Z,1579889428.893 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-24T18:10:28.894Z,1579889428.894 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-24T18:10:28.904Z,1579889428.904 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-24T18:10:29.437Z,1579889429.437 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-24T18:10:29.442Z,1579889429.442 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-24T18:10:29.444Z,1579889429.444 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-24T18:10:29.448Z,1579889429.448 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-24T18:10:29.449Z,1579889429.449 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-24T18:10:29.454Z,1579889429.454 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-24T18:10:29.454Z,1579889429.454 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-24T18:10:29.459Z,1579889429.459 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-24T18:10:29.632Z,1579889429.632 [AHRS_M2] Loaded
2020-01-24T18:10:29.632Z,1579889429.632 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-24T18:10:29.779Z,1579889429.779 [DataOverHttps] Loaded
2020-01-24T18:10:29.779Z,1579889429.779 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-24T18:10:29.780Z,1579889429.780 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-24T18:10:29.780Z,1579889429.780 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 906
2020-01-24T18:10:29.803Z,1579889429.803 [DDM] Loaded
2020-01-24T18:10:29.804Z,1579889429.804 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-24T18:10:29.816Z,1579889429.816 [Depth_Keller] Loaded
2020-01-24T18:10:29.816Z,1579889429.816 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-24T18:10:29.821Z,1579889429.821 [DropWeight] Loaded
2020-01-24T18:10:29.821Z,1579889429.821 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-24T18:10:29.879Z,1579889429.879 [DUSBL_Hydroid] Loaded
2020-01-24T18:10:29.880Z,1579889429.880 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-24T18:10:29.924Z,1579889429.924 [Micromodem] Loaded
2020-01-24T18:10:29.924Z,1579889429.924 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-24T18:10:30.017Z,1579889430.017 [NAL9602] Loaded
2020-01-24T18:10:30.017Z,1579889430.017 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-24T18:10:30.055Z,1579889430.055 [Onboard] Loaded
2020-01-24T18:10:30.055Z,1579889430.055 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-24T18:10:30.061Z,1579889430.061 [PowerOnly] Loaded
2020-01-24T18:10:30.061Z,1579889430.061 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-24T18:10:30.067Z,1579889430.067 [Radio_Surface] Loaded
2020-01-24T18:10:30.067Z,1579889430.067 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-24T18:10:30.068Z,1579889430.068 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-24T18:10:30.069Z,1579889430.069 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 907
2020-01-24T18:10:30.110Z,1579889430.110 [RDI_Pathfinder] Loaded
2020-01-24T18:10:30.110Z,1579889430.110 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-24T18:10:31.521Z,1579889431.521 [BPC1] Loaded
2020-01-24T18:10:31.521Z,1579889431.521 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-24T18:10:31.522Z,1579889431.522 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-24T18:10:31.522Z,1579889431.522 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-24T18:10:31.558Z,1579889431.558 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-24T18:10:31.559Z,1579889431.559 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-24T18:10:32.469Z,1579889432.469 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-24T18:10:32.469Z,1579889432.469 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-24T18:10:32.489Z,1579889432.489 [NavChart] Loaded
2020-01-24T18:10:32.489Z,1579889432.489 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-24T18:10:32.493Z,1579889432.493 [UniversalFixResidualReporter] Loaded
2020-01-24T18:10:32.493Z,1579889432.493 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-24T18:10:32.493Z,1579889432.493 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-24T18:10:32.494Z,1579889432.494 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-24T18:10:32.621Z,1579889432.621 [BuoyancyServo] Loaded
2020-01-24T18:10:32.622Z,1579889432.622 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-24T18:10:32.636Z,1579889432.636 [ElevatorServo] Loaded
2020-01-24T18:10:32.636Z,1579889432.636 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-24T18:10:32.650Z,1579889432.650 [MassServo] Loaded
2020-01-24T18:10:32.651Z,1579889432.651 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-24T18:10:32.665Z,1579889432.665 [RudderServo] Loaded
2020-01-24T18:10:32.665Z,1579889432.665 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-24T18:10:32.679Z,1579889432.679 [ThrusterServo] Loaded
2020-01-24T18:10:32.680Z,1579889432.680 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-24T18:10:32.680Z,1579889432.680 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-24T18:10:32.681Z,1579889432.681 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-24T18:10:32.944Z,1579889432.944 [PAR_Licor] Loaded
2020-01-24T18:10:32.945Z,1579889432.945 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-24T18:10:32.987Z,1579889432.987 [WetLabsSeaOWL_UV_A] Loaded
2020-01-24T18:10:32.987Z,1579889432.987 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-24T18:10:32.988Z,1579889432.988 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-24T18:10:32.988Z,1579889432.988 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 908
2020-01-24T18:10:32.989Z,1579889432.989 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-24T18:10:32.991Z,1579889432.991 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-24T18:10:33.367Z,1579889433.367 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-24T18:10:33.368Z,1579889433.368 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-24T18:10:33.422Z,1579889433.422 [DepthRateCalculator] Loaded
2020-01-24T18:10:33.422Z,1579889433.422 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-24T18:10:33.427Z,1579889433.427 [PitchRateCalculator] Loaded
2020-01-24T18:10:33.427Z,1579889433.427 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-24T18:10:33.439Z,1579889433.439 [SpeedCalculator] Loaded
2020-01-24T18:10:33.439Z,1579889433.439 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-24T18:10:33.459Z,1579889433.459 [TempGradientCalculator] Loaded
2020-01-24T18:10:33.459Z,1579889433.459 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-24T18:10:33.465Z,1579889433.465 [YawRateCalculator] Loaded
2020-01-24T18:10:33.465Z,1579889433.465 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-24T18:10:33.503Z,1579889433.503 [ElevatorOffsetCalculator] Loaded
2020-01-24T18:10:33.503Z,1579889433.503 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-24T18:10:33.504Z,1579889433.504 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-24T18:10:33.504Z,1579889433.504 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-24T18:10:33.662Z,1579889433.662 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-24T18:10:33.683Z,1579889433.683 [SBIT] Loaded
2020-01-24T18:10:33.683Z,1579889433.683 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-24T18:10:33.684Z,1579889433.684 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-24T18:10:33.696Z,1579889433.696 [IBIT] Loaded
2020-01-24T18:10:33.696Z,1579889433.696 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-24T18:10:33.699Z,1579889433.699 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-24T18:10:33.831Z,1579889433.831 [CBIT] Loaded
2020-01-24T18:10:33.832Z,1579889433.832 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-24T18:10:33.832Z,1579889433.832 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-24T18:10:33.833Z,1579889433.833 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-24T18:10:33.957Z,1579889433.957 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-24T18:10:33.958Z,1579889433.958 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-24T18:10:34.078Z,1579889434.078 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-24T18:10:34.079Z,1579889434.079 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-24T18:10:34.142Z,1579889434.142 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-24T18:10:34.221Z,1579889434.221 [VerticalControl] Loaded
2020-01-24T18:10:34.221Z,1579889434.221 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-24T18:10:34.222Z,1579889434.222 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-24T18:10:34.289Z,1579889434.289 [HorizontalControl] Loaded
2020-01-24T18:10:34.289Z,1579889434.289 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-24T18:10:34.290Z,1579889434.290 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-24T18:10:34.291Z,1579889434.291 [SpeedControl] Loaded
2020-01-24T18:10:34.292Z,1579889434.292 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-24T18:10:34.292Z,1579889434.292 [LoopControl](DEBUG): Construct LoopControl.
2020-01-24T18:10:34.293Z,1579889434.293 [LoopControl] Loaded
2020-01-24T18:10:34.293Z,1579889434.293 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-24T18:10:34.293Z,1579889434.293 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-24T18:10:34.294Z,1579889434.294 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-24T18:10:34.342Z,1579889434.342 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-24T18:10:34.346Z,1579889434.346 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-24T18:10:34.347Z,1579889434.347 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-24T18:10:34.353Z,1579889434.353 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-24T18:10:34.354Z,1579889434.354 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACC4E0
2020-01-24T18:10:34.354Z,1579889434.354 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 909
2020-01-24T18:10:34.360Z,1579889434.360 [Supervisor](INFO): Main Thread ID is 816
2020-01-24T18:10:34.360Z,1579889434.360 [Supervisor](DEBUG): Running supervisor.
2020-01-24T18:10:34.361Z,1579889434.361 [CommandLine ThreadHandler](INFO): Handler Thread ID is 910
2020-01-24T18:10:34.363Z,1579889434.363 [controlThread ThreadHandler](INFO): Handler Thread ID is 911
2020-01-24T18:10:34.363Z,1579889434.363 [controlThread](DEBUG): Initializing ControlThread
2020-01-24T18:10:34.369Z,1579889434.369 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-24T18:10:34.369Z,1579889434.369 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-24T18:10:34.374Z,1579889434.374 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-24T18:10:34.374Z,1579889434.374 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-24T18:10:34.375Z,1579889434.375 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-24T18:10:34.375Z,1579889434.375 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-24T18:10:34.376Z,1579889434.376 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-24T18:10:34.376Z,1579889434.376 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-24T18:10:34.377Z,1579889434.377 [SBIT](INFO): Initialize SBIT Component.
2020-01-24T18:10:34.377Z,1579889434.377 [SBIT](IMPORTANT): git: 2020-01-24-A
2020-01-24T18:10:34.377Z,1579889434.377 [SBIT](INFO): git hash: 637c65bcbd6d0880dfbd56c4760c0ba5e48e98aa
2020-01-24T18:10:34.378Z,1579889434.378 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-24T18:10:34.379Z,1579889434.379 [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-24T18:10:34.380Z,1579889434.380 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-24T18:10:34.380Z,1579889434.380 [IBIT](INFO): Initialize IBIT Component.
2020-01-24T18:10:34.381Z,1579889434.381 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-24T18:10:34.382Z,1579889434.382 [logger ThreadHandler](INFO): Handler Thread ID is 912
2020-01-24T18:10:34.385Z,1579889434.385 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 913
2020-01-24T18:10:34.394Z,1579889434.394 [CBIT](DEBUG): Initialized mux pins.
2020-01-24T18:10:34.394Z,1579889434.394 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-24T18:10:34.395Z,1579889434.395 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-24T18:10:34.402Z,1579889434.402 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 914
2020-01-24T18:10:34.414Z,1579889434.414 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 915
2020-01-24T18:10:34.415Z,1579889434.415 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-24T18:10:34.422Z,1579889434.422 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-24T18:10:34.422Z,1579889434.422 [CBIT](DEBUG): Initializing heartbeat.
2020-01-24T18:10:34.441Z,1579889434.441 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 916
2020-01-24T18:10:34.455Z,1579889434.455 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-24T18:10:34.455Z,1579889434.455 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-24T18:10:34.456Z,1579889434.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-24T18:10:34.456Z,1579889434.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-24T18:10:34.456Z,1579889434.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-24T18:10:34.456Z,1579889434.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-24T18:10:34.456Z,1579889434.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-24T18:10:34.456Z,1579889434.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-24T18:10:34.456Z,1579889434.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-24T18:10:34.457Z,1579889434.457 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-24T18:10:34.457Z,1579889434.457 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-24T18:10:34.457Z,1579889434.457 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-24T18:10:34.457Z,1579889434.457 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-24T18:10:34.457Z,1579889434.457 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-24T18:10:34.457Z,1579889434.457 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-24T18:10:34.458Z,1579889434.458 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-24T18:10:34.494Z,1579889434.494 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-24T18:10:34.494Z,1579889434.494 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-24T18:10:34.530Z,1579889434.530 [CBIT](DEBUG): Backplane powered.
2020-01-24T18:10:34.530Z,1579889434.530 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-24T18:10:34.532Z,1579889434.532 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-24T18:10:34.532Z,1579889434.532 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-24T18:10:34.533Z,1579889434.533 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-24T18:10:34.534Z,1579889434.534 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-24T18:10:34.557Z,1579889434.557 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-24T18:10:34.599Z,1579889434.599 [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-24T18:10:34.600Z,1579889434.600 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-24T18:10:34.670Z,1579889434.670 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-24T18:10:34.671Z,1579889434.671 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-24T18:10:34.672Z,1579889434.672 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-24T18:10:34.726Z,1579889434.726 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-24T18:10:34.729Z,1579889434.729 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-24T18:10:34.748Z,1579889434.748 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-24T18:10:34.751Z,1579889434.751 [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-24T18:10:34.766Z,1579889434.766 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,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-24T18:10:34.786Z,1579889434.786 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-24T18:10:34.806Z,1579889434.806 [Radio_Surface](INFO): Powering up
2020-01-24T18:10:34.907Z,1579889434.907 [DDM](INFO): Powering up
2020-01-24T18:10:34.907Z,1579889434.907 [DDM](DEBUG): Initializing DDM.
2020-01-24T18:10:34.931Z,1579889434.931 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2020-01-24T18:10:34.931Z,1579889434.931 [DUSBL_Hydroid](INFO): Powering up
2020-01-24T18:10:34.932Z,1579889434.932 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-24T18:10:35.112Z,1579889435.112 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-24T18:10:35.172Z,1579889435.172 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-24T18:10:35.178Z,1579889435.178 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-24T18:10:35.179Z,1579889435.179 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-24T18:10:35.186Z,1579889435.186 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-24T18:10:35.187Z,1579889435.187 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-24T18:10:35.194Z,1579889435.194 [MassServo](DEBUG): Initializing MassServo.
2020-01-24T18:10:35.195Z,1579889435.195 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-24T18:10:35.202Z,1579889435.202 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-24T18:10:35.203Z,1579889435.203 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-24T18:10:35.210Z,1579889435.210 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-24T18:10:35.292Z,1579889435.292 [Micromodem](INFO): Powering up
2020-01-24T18:10:35.292Z,1579889435.292 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-24T18:10:35.330Z,1579889435.330 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-24T18:10:40.270Z,1579889440.270 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-24T18:10:40.673Z,1579889440.673 [Micromodem](INFO): Nmea in: $CATMG,2020-01-24T18:12:34.999624Z,RTC,RTC*53

2020-01-24T18:10:40.673Z,1579889440.673 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-24T18:12:34.999624Z,RTC,RTC*53

2020-01-24T18:10:41.076Z,1579889441.076 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-24T18:10:41.076Z,1579889441.076 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-24T18:10:41.480Z,1579889441.480 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-24T18:10:41.480Z,1579889441.480 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-24T18:10:41.884Z,1579889441.884 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-24T18:10:41.884Z,1579889441.884 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-24T18:10:42.292Z,1579889442.292 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-24T18:10:42.292Z,1579889442.292 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-24T18:10:42.696Z,1579889442.696 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-24T18:10:42.696Z,1579889442.696 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-24T18:10:43.096Z,1579889443.096 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-24T18:10:43.096Z,1579889443.096 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-24T18:10:43.977Z,1579889443.977 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-24T18:10:43.977Z,1579889443.977 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-24T18:10:44.146Z,1579889444.146 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-24T18:10:44.147Z,1579889444.147 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,24,18,10,45*4A

2020-01-24T18:10:44.516Z,1579889444.516 [Micromodem](INFO): Nmea in: $CACLK,2020,1,24,18,10,45*78

2020-01-24T18:10:44.518Z,1579889444.518 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-24T18:10:46Z*7B

2020-01-24T18:10:44.519Z,1579889444.519 [Micromodem](INFO): Nmea in: $CATMG,2020-01-24T18:10:46.027690Z,USER_CMD,RTC*16

2020-01-24T18:10:45.711Z,1579889445.711 [DDM](INFO): Dynamic Docking Module:ÿÿ!C REMUS Capture Rev 1.1
2020-01-24T18:10:48.609Z,1579889448.609 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-24T18:10:53.011Z,1579889453.011 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-24T18:11:00.322Z,1579889460.322 [NAL9602](INFO): Powering up NAL9602
2020-01-24T18:11:11.176Z,1579889471.176 [NAL9602](INFO): NAL9602 initialized
2020-01-24T18:11:14.070Z,1579889474.070 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-24T18:11:14.078Z,1579889474.078 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-24T18:11:25.330Z,1579889485.330 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009140
CHAN A1 (24V): -0.028584
CHAN A2 (12V): -0.007052
CHAN A3 (5V): -0.001921
CHAN B0 (3.3V): 0.000317
CHAN B1 (3.15aV): -0.000217
CHAN B2 (3.15bV): 0.000240
CHAN B3 (GND): 0.002060
OPEN: 0.006753
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-24T18:12:07.908Z,1579889527.908 [SBIT](IMPORTANT): SBIT PASSED
2020-01-24T18:12:07.909Z,1579889527.909 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-24T18:12:07.910Z,1579889527.910 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-24T18:12:07.910Z,1579889527.910 [SBIT](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-24T18:12:08.295Z,1579889528.295 [MissionManager](IMPORTANT): Started mission Startup
2020-01-24T18:12:08.296Z,1579889528.296 [Startup] Running Loop=1
2020-01-24T18:12:08.296Z,1579889528.296 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-24T18:12:08.296Z,1579889528.296 [Startup:A.GoToSurface] Running Loop=1
2020-01-24T18:12:08.296Z,1579889528.296 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-24T18:12:08.298Z,1579889528.298 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-24T18:12:08.299Z,1579889528.299 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-24T18:12:08.300Z,1579889528.300 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-24T18:12:08.301Z,1579889528.301 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-24T18:12:08.302Z,1579889528.302 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-24T18:12:08.306Z,1579889528.306 [Startup:StartupSatComms] Running Loop=1
2020-01-24T18:12:08.306Z,1579889528.306 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-24T18:12:08.307Z,1579889528.307 [Startup:StartupSatComms:A] Running Loop=1
2020-01-24T18:12:08.689Z,1579889528.689 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-24T18:12:50.700Z,1579889570.700 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:12:50.701Z,1579889570.701 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:13:05.611Z,1579889585.611 [DataOverHttps](IMPORTANT): SBD MTMSN=20200124T181303
2020-01-24T18:13:05.616Z,1579889585.616 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005294
2020-01-24T18:13:08.518Z,1579889588.518 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-24T18:12:08.3Z
2020-01-24T18:13:08.518Z,1579889588.518 [Startup:StartupSatComms:A] Stopped
2020-01-24T18:13:08.518Z,1579889588.518 [Startup:StartupSatComms:B] Running Loop=1
2020-01-24T18:13:08.920Z,1579889588.920 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-24T18:13:13.366Z,1579889593.366 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200124T181022/Courier0000.lzma
2020-01-24T18:13:13.368Z,1579889593.368 [DataOverHttps](INFO): Received command:failc
2020-01-24T18:13:13.426Z,1579889593.426 [CommandLine](IMPORTANT): got command failComponent
2020-01-24T18:13:13.426Z,1579889593.426 [CommandLine](IMPORTANT): Failed components:
2020-01-24T18:13:13.427Z,1579889593.427 [CommandLine](IMPORTANT): No failed Components.
2020-01-24T18:13:15.372Z,1579889595.372 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0000.lzma.bak
2020-01-24T18:13:15.372Z,1579889595.372 [DataOverHttps](INFO): SBD MOMSN=12202085
2020-01-24T18:13:16.770Z,1579889596.770 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-24T18:13:16.770Z,1579889596.770 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-24T18:13:16.770Z,1579889596.770 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-24T18:13:16.770Z,1579889596.770 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-24T18:13:16.854Z,1579889596.854 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-24T18:13:17.134Z,1579889597.134 [RDI_Pathfinder](INFO): Powering down
2020-01-24T18:13:17.896Z,1579889597.896 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-24T18:13:17.896Z,1579889597.896 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-24T18:13:31.225Z,1579889611.225 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-24T18:13:32.318Z,1579889612.318 [DataOverHttps](INFO): Sending 641 bytes from file Logs/20200124T181022/Express0001.lzma
2020-01-24T18:13:34.323Z,1579889614.323 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0001.lzma.bak
2020-01-24T18:13:34.323Z,1579889614.323 [DataOverHttps](INFO): SBD MOMSN=12202089
2020-01-24T18:13:34.465Z,1579889614.465 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-24T18:13:34.465Z,1579889614.465 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:13:34.475Z,1579889614.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:13:34.878Z,1579889614.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:13:34.878Z,1579889614.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-24T18:13:51.381Z,1579889631.381 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20200124T181022/Express0005.lzma
2020-01-24T18:13:53.387Z,1579889633.387 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0005.lzma.bak
2020-01-24T18:13:53.387Z,1579889633.387 [DataOverHttps](INFO): SBD MOMSN=12202103
2020-01-24T18:13:54.644Z,1579889634.644 [Startup:StartupSatComms:B] Stopped
2020-01-24T18:13:54.644Z,1579889634.644 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-24T18:13:54.645Z,1579889634.645 [Startup:StartupSatComms] Stopped
2020-01-24T18:13:54.645Z,1579889634.645 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-24T18:13:54.645Z,1579889634.645 [Startup](INFO): Completed Startup
2020-01-24T18:13:54.646Z,1579889634.646 [MissionManager](INFO): Startup is completed.
2020-01-24T18:13:54.646Z,1579889634.646 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-24T18:13:54.646Z,1579889634.646 [Startup] Stopped
2020-01-24T18:13:54.646Z,1579889634.646 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-24T18:13:54.646Z,1579889634.646 [Startup:A.GoToSurface] Stopped
2020-01-24T18:13:54.646Z,1579889634.646 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-24T18:13:55.065Z,1579889635.065 [MissionManager](IMPORTANT): Started mission Default
2020-01-24T18:13:55.065Z,1579889635.065 [Default] Running Loop=1
2020-01-24T18:13:55.065Z,1579889635.065 [Default](DEBUG): Aggregate::initialize Default
2020-01-24T18:13:55.065Z,1579889635.065 [Default:B.GoToSurface] Running Loop=1
2020-01-24T18:13:55.065Z,1579889635.065 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-24T18:13:55.065Z,1579889635.065 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-24T18:13:55.066Z,1579889635.066 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-24T18:13:55.066Z,1579889635.066 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-24T18:13:55.067Z,1579889635.067 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-24T18:13:55.067Z,1579889635.067 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-24T18:13:55.067Z,1579889635.067 [Default:A.Wait] Running Loop=1
2020-01-24T18:13:55.067Z,1579889635.067 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-24T18:14:08.418Z,1579889648.418 [Default:A.Wait](INFO): Done Waiting.
2020-01-24T18:14:08.418Z,1579889648.418 [Default:A.Wait] Stopped
2020-01-24T18:14:08.418Z,1579889648.418 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T18:14:08.816Z,1579889648.816 [Default:CheckIn] Running Loop=1
2020-01-24T18:14:08.816Z,1579889648.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T18:14:08.816Z,1579889648.816 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T18:14:09.195Z,1579889649.195 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-24T18:14:38.775Z,1579889678.775 [DataOverHttps](IMPORTANT): SBD MTMSN=20200124T181436
2020-01-24T18:14:46.361Z,1579889686.361 [DataOverHttps](INFO): Received command:failc
2020-01-24T18:14:46.367Z,1579889686.367 [CommandLine](IMPORTANT): got command failComponent
2020-01-24T18:14:46.367Z,1579889686.367 [CommandLine](IMPORTANT): Failed components:
2020-01-24T18:14:46.367Z,1579889686.367 [CommandLine](IMPORTANT): No failed Components.
2020-01-24T18:14:59.699Z,1579889699.699 [CommandLine](IMPORTANT): got command show stack
2020-01-24T18:14:59.700Z,1579889699.700 [CommandLine](IMPORTANT): Behavior Stack:

2020-01-24T18:14:59.700Z,1579889699.700 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2020-01-24T18:14:59.700Z,1579889699.700 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2020-01-24T18:15:03.584Z,1579889703.584 [CommandLine](IMPORTANT): got command configSet list
2020-01-24T18:15:03.584Z,1579889703.584 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-24T18:15:03.585Z,1579889703.585 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-24T18:15:03.585Z,1579889703.585 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-24T18:15:13.264Z,1579889713.264 [CommandLine](IMPORTANT): got command show variable detectionThresh
2020-01-24T18:15:13.326Z,1579889713.326 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2020-01-24T18:15:18.670Z,1579889718.670 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:15:18.670Z,1579889718.670 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012411282213,35.0, -0.1,   0.0  0
2020-01-24T18:15:19.719Z,1579889719.719 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.detectionThreshold
2020-01-24T18:15:19.719Z,1579889719.719 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold 75 count
2020-01-24T18:15:21.523Z,1579889721.523 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:15:21.523Z,1579889721.523 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:16:14.815Z,1579889774.815 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-24T18:16:35.464Z,1579889795.464 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-24T18:16:35.464Z,1579889795.464 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:16:35.486Z,1579889795.486 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:16:35.886Z,1579889795.886 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:16:35.886Z,1579889795.886 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-24T18:16:44.411Z,1579889804.411 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-24T18:16:44.420Z,1579889804.420 [BPC1](INFO): Received data from all battery sticks.
2020-01-24T18:17:42.134Z,1579889862.134 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-24T18:17:42.134Z,1579889862.134 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.0   0.00,251.00
2020-01-24T18:18:22.211Z,1579889902.211 [DataOverHttps](IMPORTANT): SBD MTMSN=20200124T181819
2020-01-24T18:18:22.506Z,1579889902.506 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:18:22.507Z,1579889902.507 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:18:29.754Z,1579889909.754 [DataOverHttps](INFO): Received command:stop
2020-01-24T18:18:29.806Z,1579889909.806 [CommandLine](IMPORTANT): got command stop
2020-01-24T18:18:29.806Z,1579889909.806 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-24T18:18:29.806Z,1579889909.806 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-01-24T18:19:08.976Z,1579889948.976 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-24T18:14:08.8Z
2020-01-24T18:19:08.976Z,1579889948.976 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T18:19:08.976Z,1579889948.976 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T18:19:09.383Z,1579889949.383 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-24T18:19:15.917Z,1579889955.917 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200124T181022/Courier0007.lzma
2020-01-24T18:19:17.925Z,1579889957.925 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0007.lzma.bak
2020-01-24T18:19:17.925Z,1579889957.925 [DataOverHttps](INFO): SBD MOMSN=12202112
2020-01-24T18:19:34.958Z,1579889974.958 [DataOverHttps](INFO): Sending 597 bytes from file Logs/20200124T181022/Express0008.lzma
2020-01-24T18:19:36.463Z,1579889976.463 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-24T18:19:36.463Z,1579889976.463 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:19:36.473Z,1579889976.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:19:36.867Z,1579889976.867 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:19:36.867Z,1579889976.867 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-24T18:19:36.963Z,1579889976.963 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0008.lzma.bak
2020-01-24T18:19:36.963Z,1579889976.963 [DataOverHttps](INFO): SBD MOMSN=12202114
2020-01-24T18:19:38.056Z,1579889978.056 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T18:19:38.056Z,1579889978.056 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T18:19:38.056Z,1579889978.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T18:20:22.484Z,1579890022.484 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:20:22.484Z,1579890022.484 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200124113326 -0.1,   0.0,1448.9,  0
2020-01-24T18:20:43.501Z,1579890043.501 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:20:43.501Z,1579890043.501 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200124113347130.1,   0.0,1448.9,  0
2020-01-24T18:21:12.148Z,1579890072.148 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-24T18:21:12.149Z,1579890072.149 [NAL9602] Data Fault, FailCount= 1
2020-01-24T18:21:12.149Z,1579890072.149 [NAL9602](ERROR): Data Fault
2020-01-24T18:21:12.227Z,1579890072.227 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-24T18:21:12.552Z,1579890072.552 [NAL9602](INFO): Powering down
2020-01-24T18:21:13.411Z,1579890073.411 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-24T18:21:13.411Z,1579890073.411 [NAL9602] No Fault, FailCount= 1
2020-01-24T18:21:42.852Z,1579890102.852 [NAL9602](INFO): Powering up NAL9602
2020-01-24T18:21:44.502Z,1579890104.502 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T18:21:53.776Z,1579890113.776 [NAL9602](INFO): NAL9602 initialized
2020-01-24T18:22:26.141Z,1579890146.141 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:22:26.142Z,1579890146.142 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:22:37.444Z,1579890157.444 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-24T18:22:37.444Z,1579890157.444 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:22:37.453Z,1579890157.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:22:37.846Z,1579890157.846 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:22:37.846Z,1579890157.846 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-24T18:22:43.116Z,1579890163.116 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:22:43.116Z,1579890163.116 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2020-01-24T18:23:44.112Z,1579890224.112 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T18:23:44.113Z,1579890224.113 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3768,-32768,V
2020-01-24T18:24:20.475Z,1579890260.475 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T18:24:20.475Z,1579890260.475 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3268,V
2020-01-24T18:24:38.642Z,1579890278.642 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T18:24:38.642Z,1579890278.642 [Default:CheckIn:C.Wait] Stopped
2020-01-24T18:24:38.642Z,1579890278.642 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T18:24:38.642Z,1579890278.642 [Default:CheckIn:D] Running Loop=1
2020-01-24T18:24:39.052Z,1579890279.052 [Default:CheckIn:D] Stopped
2020-01-24T18:24:39.052Z,1579890279.052 [Default:CheckIn:E] Running Loop=1
2020-01-24T18:24:39.478Z,1579890279.478 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.733120 min 
2020-01-24T18:24:39.478Z,1579890279.478 [Default:CheckIn:E] Stopped
2020-01-24T18:24:39.478Z,1579890279.478 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T18:24:39.478Z,1579890279.478 [Default:CheckIn] Stopped
2020-01-24T18:24:39.478Z,1579890279.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T18:24:39.479Z,1579890279.479 [Default:CheckIn](INFO): Running loop #2
2020-01-24T18:24:39.479Z,1579890279.479 [Default:CheckIn] Running Loop=2
2020-01-24T18:24:39.479Z,1579890279.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T18:24:39.479Z,1579890279.479 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T18:24:39.829Z,1579890279.829 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:24:39.830Z,1579890279.830 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012411374313,35.0, -0.1,   0.0,144

2020-01-24T18:25:00.470Z,1579890300.470 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:25:00.470Z,1579890300.470 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012411380413,35.0, -0.1,   0.,  0
2020-01-24T18:25:19.823Z,1579890319.823 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:25:19.823Z,1579890319.823 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012411382313,35,   0.0,1448.9,  0
2020-01-24T18:25:36.862Z,1579890336.862 [CommandLine](IMPORTANT): got command show variable stopDepth
2020-01-24T18:25:36.901Z,1579890336.901 [CommandLine](IMPORTANT): VerticalControl.stopDepthExcursion (meter)
2020-01-24T18:25:36.908Z,1579890336.908 [CommandLine](IMPORTANT): CBIT.stopDepth (meter)
2020-01-24T18:25:38.452Z,1579890338.452 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-01-24T18:25:38.452Z,1579890338.452 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:25:38.494Z,1579890338.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:25:38.847Z,1579890338.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:25:38.847Z,1579890338.847 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-01-24T18:25:46.748Z,1579890346.748 [CommandLine](IMPORTANT): got command get CBIT.stopDepth
2020-01-24T18:25:46.748Z,1579890346.748 [CommandLine](IMPORTANT): CBIT.stopDepth 215.000000 m
2020-01-24T18:25:46.749Z,1579890346.749 [CommandLine](IMPORTANT): got command get CBIT.abortDepth
2020-01-24T18:25:46.749Z,1579890346.749 [CommandLine](IMPORTANT): CBIT.abortDepth 255.000000 m
2020-01-24T18:25:59.822Z,1579890359.822 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:25:59.822Z,1579890359.822 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:26:24.513Z,1579890384.513 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:26:24.514Z,1579890384.514 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012411392813,35.0, -0.1,   0.0,14
2020-01-24T18:26:39.478Z,1579890399.478 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T18:26:39.478Z,1579890399.478 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3276,-32768,V
2020-01-24T18:27:46.210Z,1579890466.210 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-24T18:27:46.210Z,1579890466.210 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-24T18:27:46.210Z,1579890466.210 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-24T18:27:46.210Z,1579890466.210 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-24T18:27:46.233Z,1579890466.233 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-24T18:27:46.582Z,1579890466.582 [RDI_Pathfinder](INFO): Powering down
2020-01-24T18:27:47.355Z,1579890467.355 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-24T18:27:47.355Z,1579890467.355 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-24T18:28:00.640Z,1579890480.640 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-24T18:28:11.977Z,1579890491.977 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:28:11.977Z,1579890491.977 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:28:39.451Z,1579890519.451 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-01-24T18:28:39.451Z,1579890519.451 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:28:39.463Z,1579890519.463 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:28:39.848Z,1579890519.848 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:28:39.849Z,1579890519.849 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-01-24T18:29:39.640Z,1579890579.640 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-24T18:24:39.5Z
2020-01-24T18:29:39.640Z,1579890579.640 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T18:29:39.640Z,1579890579.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T18:29:47.185Z,1579890587.185 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200124T181022/Courier0010.lzma
2020-01-24T18:29:49.191Z,1579890589.191 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0010.lzma.bak
2020-01-24T18:29:49.191Z,1579890589.191 [DataOverHttps](INFO): SBD MOMSN=12202137
2020-01-24T18:30:06.246Z,1579890606.246 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20200124T181022/Express0012.lzma
2020-01-24T18:30:08.251Z,1579890608.251 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0012.lzma.bak
2020-01-24T18:30:08.251Z,1579890608.251 [DataOverHttps](INFO): SBD MOMSN=12202139
2020-01-24T18:30:09.549Z,1579890609.549 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T18:30:09.549Z,1579890609.549 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T18:30:09.549Z,1579890609.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T18:30:32.593Z,1579890632.593 [RDI_Pathfinder](ERROR): Failed to parse:
:B,-32768,-32768,V
2020-01-24T18:31:33.594Z,1579890693.594 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T18:31:35.996Z,1579890695.996 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:31:35.997Z,1579890695.997 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:31:40.447Z,1579890700.447 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-01-24T18:31:40.447Z,1579890700.447 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:31:40.476Z,1579890700.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:31:40.861Z,1579890700.861 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:31:40.861Z,1579890700.861 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-01-24T18:31:55.357Z,1579890715.357 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-24T18:31:55.357Z,1579890715.357 [NAL9602] Data Fault, FailCount= 2
2020-01-24T18:31:55.357Z,1579890715.357 [NAL9602](ERROR): Data Fault
2020-01-24T18:31:55.423Z,1579890715.423 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-24T18:31:55.764Z,1579890715.764 [NAL9602](INFO): Powering down
2020-01-24T18:31:56.612Z,1579890716.612 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-24T18:31:56.613Z,1579890716.613 [NAL9602] No Fault, FailCount= 2
2020-01-24T18:32:26.056Z,1579890746.056 [NAL9602](INFO): Powering up NAL9602
2020-01-24T18:32:36.972Z,1579890756.972 [NAL9602](INFO): NAL9602 initialized
2020-01-24T18:33:35.982Z,1579890815.982 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:33:35.983Z,1579890815.983 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,  BI,-32768,-32768,-32768,-32768,V
2020-01-24T18:33:40.744Z,1579890820.744 [CommandLine](IMPORTANT): got command burn on
2020-01-24T18:33:40.744Z,1579890820.744 [CommandLine](IMPORTANT): Activating dropweight wire
2020-01-24T18:34:32.384Z,1579890872.384 [CommandLine](IMPORTANT): got command burn off
2020-01-24T18:34:32.384Z,1579890872.384 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-01-24T18:34:41.434Z,1579890881.434 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-01-24T18:34:41.434Z,1579890881.434 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:34:41.444Z,1579890881.444 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:34:41.878Z,1579890881.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:34:41.878Z,1579890881.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-01-24T18:35:10.134Z,1579890910.134 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T18:35:10.134Z,1579890910.134 [Default:CheckIn:C.Wait] Stopped
2020-01-24T18:35:10.134Z,1579890910.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T18:35:10.134Z,1579890910.134 [Default:CheckIn:D] Running Loop=1
2020-01-24T18:35:10.537Z,1579890910.537 [Default:CheckIn:D] Stopped
2020-01-24T18:35:10.537Z,1579890910.537 [Default:CheckIn:E] Running Loop=1
2020-01-24T18:35:10.935Z,1579890910.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.257869 min 
2020-01-24T18:35:10.935Z,1579890910.935 [Default:CheckIn:E] Stopped
2020-01-24T18:35:10.935Z,1579890910.935 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T18:35:10.935Z,1579890910.935 [Default:CheckIn] Stopped
2020-01-24T18:35:10.935Z,1579890910.935 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T18:35:10.935Z,1579890910.935 [Default:CheckIn](INFO): Running loop #3
2020-01-24T18:35:10.935Z,1579890910.935 [Default:CheckIn] Running Loop=3
2020-01-24T18:35:10.935Z,1579890910.935 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T18:35:10.936Z,1579890910.936 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T18:35:29.949Z,1579890929.949 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:35:29.949Z,1579890929.949 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200124115.0, -0.1,   0.0,1448.9,  0
2020-01-24T18:36:13.951Z,1579890973.951 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:36:13.951Z,1579890973.951 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,2000.00,   0.00,   0.00,   0.00
2020-01-24T18:37:42.424Z,1579891062.424 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-01-24T18:37:42.424Z,1579891062.424 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:37:42.434Z,1579891062.434 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:37:42.872Z,1579891062.872 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:37:42.872Z,1579891062.872 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-01-24T18:38:10.311Z,1579891090.311 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:38:10.311Z,1579891090.311 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200124135.0, -0.1,   0.0,1448.9,  0
2020-01-24T18:40:11.101Z,1579891211.101 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-24T18:35:10.9Z
2020-01-24T18:40:11.101Z,1579891211.101 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T18:40:11.101Z,1579891211.101 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T18:40:18.801Z,1579891218.801 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200124T181022/Courier0016.lzma
2020-01-24T18:40:20.807Z,1579891220.807 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0016.lzma.bak
2020-01-24T18:40:20.807Z,1579891220.807 [DataOverHttps](INFO): SBD MOMSN=12202150
2020-01-24T18:40:37.833Z,1579891237.833 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20200124T181022/Express0017.lzma
2020-01-24T18:40:39.839Z,1579891239.839 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0017.lzma.bak
2020-01-24T18:40:39.839Z,1579891239.839 [DataOverHttps](INFO): SBD MOMSN=12202152
2020-01-24T18:40:40.996Z,1579891240.996 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T18:40:40.996Z,1579891240.996 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T18:40:40.996Z,1579891240.996 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T18:40:43.426Z,1579891243.426 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-01-24T18:40:43.426Z,1579891243.426 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:40:43.444Z,1579891243.444 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:40:43.871Z,1579891243.871 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:40:43.871Z,1579891243.871 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-01-24T18:42:00.670Z,1579891320.670 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-24T18:42:00.670Z,1579891320.670 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-24T18:42:00.670Z,1579891320.670 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-24T18:42:00.670Z,1579891320.670 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-24T18:42:00.718Z,1579891320.718 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-24T18:42:01.054Z,1579891321.054 [RDI_Pathfinder](INFO): Powering down
2020-01-24T18:42:01.815Z,1579891321.815 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-24T18:42:01.815Z,1579891321.815 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-24T18:42:15.111Z,1579891335.111 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-24T18:42:39.745Z,1579891359.745 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-24T18:42:39.746Z,1579891359.746 [NAL9602] Data Fault, FailCount= 3
2020-01-24T18:42:39.746Z,1579891359.746 [NAL9602](ERROR): Data Fault
2020-01-24T18:42:39.804Z,1579891359.804 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-24T18:42:40.144Z,1579891360.144 [NAL9602](INFO): Powering down
2020-01-24T18:42:40.989Z,1579891360.989 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-24T18:42:40.989Z,1579891360.989 [NAL9602] No Fault, FailCount= 3
2020-01-24T18:42:43.419Z,1579891363.419 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T18:43:10.440Z,1579891390.440 [NAL9602](INFO): Powering up NAL9602
2020-01-24T18:43:21.356Z,1579891401.356 [NAL9602](INFO): NAL9602 initialized
2020-01-24T18:43:44.459Z,1579891424.459 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-01-24T18:43:44.459Z,1579891424.459 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:43:44.469Z,1579891424.469 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:43:44.826Z,1579891424.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:43:44.826Z,1579891424.826 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-01-24T18:43:46.425Z,1579891426.425 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:43:46.425Z,1579891426.425 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200124115.0, -0.1,   0.0,1448.9,  0
2020-01-24T18:44:24.498Z,1579891464.498 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-24T18:44:24.498Z,1579891464.498 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-24T18:44:24.498Z,1579891464.498 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-24T18:44:24.498Z,1579891464.498 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-24T18:44:24.535Z,1579891464.535 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-24T18:44:24.878Z,1579891464.878 [RDI_Pathfinder](INFO): Powering down
2020-01-24T18:44:25.635Z,1579891465.635 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-24T18:44:25.635Z,1579891465.635 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-24T18:44:38.955Z,1579891478.955 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-24T18:45:30.242Z,1579891530.242 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:45:30.242Z,1579891530.242 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200124115833 -0.1,   0.0,1448.9,  0
2020-01-24T18:45:41.579Z,1579891541.579 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T18:45:41.579Z,1579891541.579 [Default:CheckIn:C.Wait] Stopped
2020-01-24T18:45:41.579Z,1579891541.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T18:45:41.579Z,1579891541.579 [Default:CheckIn:D] Running Loop=1
2020-01-24T18:45:42.006Z,1579891542.006 [Default:CheckIn:D] Stopped
2020-01-24T18:45:42.006Z,1579891542.006 [Default:CheckIn:E] Running Loop=1
2020-01-24T18:45:42.373Z,1579891542.373 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.782353 min 
2020-01-24T18:45:42.373Z,1579891542.373 [Default:CheckIn:E] Stopped
2020-01-24T18:45:42.373Z,1579891542.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T18:45:42.374Z,1579891542.374 [Default:CheckIn] Stopped
2020-01-24T18:45:42.374Z,1579891542.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T18:45:42.374Z,1579891542.374 [Default:CheckIn](INFO): Running loop #4
2020-01-24T18:45:42.374Z,1579891542.374 [Default:CheckIn] Running Loop=4
2020-01-24T18:45:42.374Z,1579891542.374 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T18:45:42.374Z,1579891542.374 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T18:46:32.896Z,1579891592.896 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:46:32.896Z,1579891592.896 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:46:45.398Z,1579891605.398 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-01-24T18:46:45.399Z,1579891605.399 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:46:45.413Z,1579891605.413 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:46:45.827Z,1579891605.827 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:46:45.827Z,1579891605.827 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-01-24T18:48:08.628Z,1579891688.628 [RDI_Pathfinder](ERROR): Failed to parse:
:0,   0.00,   0.00,   0.00,   0.00
2020-01-24T18:48:13.931Z,1579891693.931 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T18:48:13.931Z,1579891693.931 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412011792RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-24T18:49:38.515Z,1579891778.515 [CommandLine](IMPORTANT): got command strobe off
2020-01-24T18:49:38.515Z,1579891778.515 [CommandLine](IMPORTANT): Deactivating strobe
2020-01-24T18:49:41.798Z,1579891781.798 [CommandLine](IMPORTANT): got command burn off
2020-01-24T18:49:41.798Z,1579891781.798 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-01-24T18:49:46.451Z,1579891786.451 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-01-24T18:49:46.451Z,1579891786.451 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:49:46.481Z,1579891786.481 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:49:46.863Z,1579891786.863 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:49:46.863Z,1579891786.863 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-01-24T18:50:42.619Z,1579891842.619 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-24T18:45:42.4Z
2020-01-24T18:50:42.620Z,1579891842.620 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T18:50:42.620Z,1579891842.620 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T18:50:49.825Z,1579891849.825 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200124T181022/Courier0019.lzma
2020-01-24T18:50:51.832Z,1579891851.832 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0019.lzma.bak
2020-01-24T18:50:51.832Z,1579891851.832 [DataOverHttps](INFO): SBD MOMSN=12202161
2020-01-24T18:51:08.841Z,1579891868.841 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20200124T181022/Express0020.lzma
2020-01-24T18:51:10.850Z,1579891870.850 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0020.lzma.bak
2020-01-24T18:51:10.850Z,1579891870.850 [DataOverHttps](INFO): SBD MOMSN=12202163
2020-01-24T18:51:12.122Z,1579891872.122 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T18:51:12.122Z,1579891872.122 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T18:51:12.122Z,1579891872.122 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T18:52:47.480Z,1579891967.480 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-01-24T18:52:47.480Z,1579891967.480 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:52:47.490Z,1579891967.490 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:52:47.895Z,1579891967.895 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:52:47.895Z,1579891967.895 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-01-24T18:53:06.070Z,1579891986.070 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T18:53:06.071Z,1579891986.071 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3276,-32768,V
2020-01-24T18:53:22.180Z,1579892002.180 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-24T18:53:22.180Z,1579892002.180 [NAL9602] Data Fault, FailCount= 4
2020-01-24T18:53:22.181Z,1579892002.181 [NAL9602](ERROR): Data Fault
2020-01-24T18:53:22.250Z,1579892002.250 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-24T18:53:22.584Z,1579892002.584 [NAL9602](INFO): Powering down
2020-01-24T18:53:23.432Z,1579892003.432 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-24T18:53:23.432Z,1579892003.432 [NAL9602] No Fault, FailCount= 4
2020-01-24T18:53:52.892Z,1579892032.892 [NAL9602](INFO): Powering up NAL9602
2020-01-24T18:53:55.332Z,1579892035.332 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T18:53:55.333Z,1579892035.333 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T18:54:03.796Z,1579892043.796 [NAL9602](INFO): NAL9602 initialized
2020-01-24T18:55:48.494Z,1579892148.494 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-01-24T18:55:48.494Z,1579892148.494 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-24T18:55:48.504Z,1579892148.504 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-24T18:55:48.911Z,1579892148.911 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T18:55:48.926Z,1579892148.926 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-24T18:55:48.926Z,1579892148.926 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-01-24T18:56:12.786Z,1579892172.786 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T18:56:12.786Z,1579892172.786 [Default:CheckIn:C.Wait] Stopped
2020-01-24T18:56:12.786Z,1579892172.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T18:56:12.786Z,1579892172.786 [Default:CheckIn:D] Running Loop=1
2020-01-24T18:56:13.211Z,1579892173.211 [Default:CheckIn:D] Stopped
2020-01-24T18:56:13.211Z,1579892173.211 [Default:CheckIn:E] Running Loop=1
2020-01-24T18:56:13.592Z,1579892173.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.302437 min 
2020-01-24T18:56:13.593Z,1579892173.593 [Default:CheckIn:E] Stopped
2020-01-24T18:56:13.593Z,1579892173.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T18:56:13.593Z,1579892173.593 [Default:CheckIn] Stopped
2020-01-24T18:56:13.593Z,1579892173.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T18:56:13.593Z,1579892173.593 [Default:CheckIn](INFO): Running loop #5
2020-01-24T18:56:13.593Z,1579892173.593 [Default:CheckIn] Running Loop=5
2020-01-24T18:56:13.593Z,1579892173.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T18:56:13.593Z,1579892173.593 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T18:57:01.444Z,1579892221.444 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2020-01-24T18:57:01.645Z,1579892221.645 [Reporter](INFO): platform_buoyancy_position 953.637085 cc
2020-01-24T18:57:19.070Z,1579892239.070 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2020-01-24T18:57:19.072Z,1579892239.072 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2020-01-24T18:57:19.429Z,1579892239.429 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,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,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-24T18:57:24.320Z,1579892244.320 [Reporter](INFO): platform_buoyancy_position 960.352051 cc
2020-01-24T18:57:24.692Z,1579892244.692 [Reporter](INFO): platform_buoyancy_position 960.217773 cc
2020-01-24T18:57:25.525Z,1579892245.525 [Reporter](INFO): platform_buoyancy_position 951.756836 cc
2020-01-24T18:57:25.933Z,1579892245.933 [Reporter](INFO): platform_buoyancy_position 949.608032 cc
2020-01-24T18:57:26.311Z,1579892246.311 [Reporter](INFO): platform_buoyancy_position 945.444824 cc
2020-01-24T18:57:26.704Z,1579892246.704 [Reporter](INFO): platform_buoyancy_position 941.012939 cc
2020-01-24T18:57:27.119Z,1579892247.119 [Reporter](INFO): platform_buoyancy_position 936.580933 cc
2020-01-24T18:57:27.511Z,1579892247.511 [Reporter](INFO): platform_buoyancy_position 930.403198 cc
2020-01-24T18:57:27.937Z,1579892247.937 [Reporter](INFO): platform_buoyancy_position 925.837036 cc
2020-01-24T18:57:28.343Z,1579892248.343 [Reporter](INFO): platform_buoyancy_position 919.390625 cc
2020-01-24T18:57:28.740Z,1579892248.740 [Reporter](INFO): platform_buoyancy_position 914.958740 cc
2020-01-24T18:57:29.140Z,1579892249.140 [Reporter](INFO): platform_buoyancy_position 908.915161 cc
2020-01-24T18:57:29.528Z,1579892249.528 [Reporter](INFO): platform_buoyancy_position 904.483276 cc
2020-01-24T18:57:29.954Z,1579892249.954 [Reporter](INFO): platform_buoyancy_position 898.036865 cc
2020-01-24T18:57:30.344Z,1579892250.344 [Reporter](INFO): platform_buoyancy_position 893.604980 cc
2020-01-24T18:57:30.759Z,1579892250.759 [Reporter](INFO): platform_buoyancy_position 887.695801 cc
2020-01-24T18:57:31.166Z,1579892251.166 [Reporter](INFO): platform_buoyancy_position 882.995239 cc
2020-01-24T18:57:31.571Z,1579892251.571 [Reporter](INFO): platform_buoyancy_position 878.563354 cc
2020-01-24T18:57:31.984Z,1579892251.984 [Reporter](INFO): platform_buoyancy_position 872.116943 cc
2020-01-24T18:57:32.372Z,1579892252.372 [Reporter](INFO): platform_buoyancy_position 867.819336 cc
2020-01-24T18:57:32.768Z,1579892252.768 [Reporter](INFO): platform_buoyancy_position 861.641602 cc
2020-01-24T18:57:33.207Z,1579892253.207 [Reporter](INFO): platform_buoyancy_position 857.209717 cc
2020-01-24T18:57:33.575Z,1579892253.575 [Reporter](INFO): platform_buoyancy_position 851.971924 cc
2020-01-24T18:57:33.995Z,1579892253.995 [Reporter](INFO): platform_buoyancy_position 846.465698 cc
2020-01-24T18:57:34.395Z,1579892254.395 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2020-01-24T18:57:34.816Z,1579892254.816 [Reporter](INFO): platform_buoyancy_position 835.721680 cc
2020-01-24T18:57:35.203Z,1579892255.203 [Reporter](INFO): platform_buoyancy_position 830.349731 cc
2020-01-24T18:57:35.592Z,1579892255.592 [Reporter](INFO): platform_buoyancy_position 824.977661 cc
2020-01-24T18:57:36.015Z,1579892256.015 [Reporter](INFO): platform_buoyancy_position 819.471375 cc
2020-01-24T18:57:36.408Z,1579892256.408 [Reporter](INFO): platform_buoyancy_position 814.233704 cc
2020-01-24T18:57:36.823Z,1579892256.823 [Reporter](INFO): platform_buoyancy_position 809.533203 cc
2020-01-24T18:57:37.223Z,1579892257.223 [Reporter](INFO): platform_buoyancy_position 803.355347 cc
2020-01-24T18:57:37.629Z,1579892257.629 [Reporter](INFO): platform_buoyancy_position 798.923462 cc
2020-01-24T18:57:38.040Z,1579892258.040 [Reporter](INFO): platform_buoyancy_position 792.745667 cc
2020-01-24T18:57:38.412Z,1579892258.412 [Reporter](INFO): platform_buoyancy_position 788.313782 cc
2020-01-24T18:57:38.828Z,1579892258.828 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2020-01-24T18:57:39.255Z,1579892259.255 [Reporter](INFO): platform_buoyancy_position 777.569763 cc
2020-01-24T18:57:39.655Z,1579892259.655 [Reporter](INFO): platform_buoyancy_position 771.257690 cc
2020-01-24T18:57:40.056Z,1579892260.056 [Reporter](INFO): platform_buoyancy_position 766.825745 cc
2020-01-24T18:57:40.370Z,1579892260.370 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185738.00,A,3648.16551,N,12147.29519,W,0.136,0.00,240120,,,A*75
2020-01-24T18:57:40.373Z,1579892260.373 [NAL9602](INFO): GPS fix at 20200124T185738: (36.802759, -121.788253)
2020-01-24T18:57:40.447Z,1579892260.447 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T18:57:40.447Z,1579892260.447 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T18:57:40.488Z,1579892260.488 [Reporter](INFO): platform_buoyancy_position 760.782288 cc
2020-01-24T18:57:40.876Z,1579892260.876 [Reporter](INFO): platform_buoyancy_position 756.081787 cc
2020-01-24T18:57:41.287Z,1579892261.287 [Reporter](INFO): platform_buoyancy_position 751.515564 cc
2020-01-24T18:57:41.664Z,1579892261.664 [Reporter](INFO): platform_buoyancy_position 745.472046 cc
2020-01-24T18:57:42.072Z,1579892262.072 [Reporter](INFO): platform_buoyancy_position 740.771545 cc
2020-01-24T18:57:42.480Z,1579892262.480 [Reporter](INFO): platform_buoyancy_position 734.728088 cc
2020-01-24T18:57:42.916Z,1579892262.916 [Reporter](INFO): platform_buoyancy_position 728.281677 cc
2020-01-24T18:57:43.342Z,1579892263.342 [Reporter](INFO): platform_buoyancy_position 723.849792 cc
2020-01-24T18:57:43.695Z,1579892263.695 [Reporter](INFO): platform_buoyancy_position 719.552185 cc
2020-01-24T18:57:44.139Z,1579892264.139 [Reporter](INFO): platform_buoyancy_position 713.105774 cc
2020-01-24T18:57:44.543Z,1579892264.543 [Reporter](INFO): platform_buoyancy_position 706.927979 cc
2020-01-24T18:57:44.947Z,1579892264.947 [Reporter](INFO): platform_buoyancy_position 702.496094 cc
2020-01-24T18:57:45.353Z,1579892265.353 [Reporter](INFO): platform_buoyancy_position 696.183960 cc
2020-01-24T18:57:45.744Z,1579892265.744 [Reporter](INFO): platform_buoyancy_position 691.752075 cc
2020-01-24T18:57:46.160Z,1579892266.160 [Reporter](INFO): platform_buoyancy_position 685.708557 cc
2020-01-24T18:57:46.553Z,1579892266.553 [Reporter](INFO): platform_buoyancy_position 681.142395 cc
2020-01-24T18:57:46.972Z,1579892266.972 [Reporter](INFO): platform_buoyancy_position 676.441895 cc
2020-01-24T18:57:47.403Z,1579892267.403 [Reporter](INFO): platform_buoyancy_position 670.129761 cc
2020-01-24T18:57:47.752Z,1579892267.752 [Reporter](INFO): platform_buoyancy_position 665.832153 cc
2020-01-24T18:57:48.239Z,1579892268.239 [Reporter](INFO): platform_buoyancy_position 659.385742 cc
2020-01-24T18:57:48.553Z,1579892268.553 [Reporter](INFO): platform_buoyancy_position 655.088135 cc
2020-01-24T18:57:48.964Z,1579892268.964 [Reporter](INFO): platform_buoyancy_position 648.776062 cc
2020-01-24T18:57:49.364Z,1579892269.364 [Reporter](INFO): platform_buoyancy_position 644.344177 cc
2020-01-24T18:57:49.755Z,1579892269.755 [Reporter](INFO): platform_buoyancy_position 640.046570 cc
2020-01-24T18:57:50.160Z,1579892270.160 [Reporter](INFO): platform_buoyancy_position 633.734436 cc
2020-01-24T18:57:50.547Z,1579892270.547 [Reporter](INFO): platform_buoyancy_position 629.302551 cc
2020-01-24T18:57:50.955Z,1579892270.955 [Reporter](INFO): platform_buoyancy_position 622.990479 cc
2020-01-24T18:57:51.045Z,1579892271.045 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20200124T181022/Courier0022.lzma
2020-01-24T18:57:51.368Z,1579892271.368 [Reporter](INFO): platform_buoyancy_position 618.558533 cc
2020-01-24T18:57:51.769Z,1579892271.769 [Reporter](INFO): platform_buoyancy_position 612.380737 cc
2020-01-24T18:57:52.180Z,1579892272.180 [Reporter](INFO): platform_buoyancy_position 607.680237 cc
2020-01-24T18:57:52.568Z,1579892272.568 [Reporter](INFO): platform_buoyancy_position 601.502441 cc
2020-01-24T18:57:53.043Z,1579892273.043 [Reporter](INFO): platform_buoyancy_position 597.070557 cc
2020-01-24T18:57:53.051Z,1579892273.051 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0022.lzma.bak
2020-01-24T18:57:53.051Z,1579892273.051 [DataOverHttps](INFO): SBD MOMSN=12202171
2020-01-24T18:57:53.404Z,1579892273.404 [Reporter](INFO): platform_buoyancy_position 591.698547 cc
2020-01-24T18:57:53.792Z,1579892273.792 [Reporter](INFO): platform_buoyancy_position 586.460876 cc
2020-01-24T18:57:54.192Z,1579892274.192 [Reporter](INFO): platform_buoyancy_position 580.148743 cc
2020-01-24T18:57:54.607Z,1579892274.607 [Reporter](INFO): platform_buoyancy_position 575.716858 cc
2020-01-24T18:57:55.027Z,1579892275.027 [Reporter](INFO): platform_buoyancy_position 571.150635 cc
2020-01-24T18:57:55.400Z,1579892275.400 [Reporter](INFO): platform_buoyancy_position 564.972839 cc
2020-01-24T18:57:55.811Z,1579892275.811 [Reporter](INFO): platform_buoyancy_position 560.540955 cc
2020-01-24T18:57:56.235Z,1579892276.235 [Reporter](INFO): platform_buoyancy_position 554.228882 cc
2020-01-24T18:57:56.643Z,1579892276.643 [Reporter](INFO): platform_buoyancy_position 549.796936 cc
2020-01-24T18:57:57.044Z,1579892277.044 [Reporter](INFO): platform_buoyancy_position 543.484863 cc
2020-01-24T18:57:57.436Z,1579892277.436 [Reporter](INFO): platform_buoyancy_position 539.187256 cc
2020-01-24T18:57:57.847Z,1579892277.847 [Reporter](INFO): platform_buoyancy_position 532.740845 cc
2020-01-24T18:57:58.276Z,1579892278.276 [Reporter](INFO): platform_buoyancy_position 527.503174 cc
2020-01-24T18:57:58.651Z,1579892278.651 [Reporter](INFO): platform_buoyancy_position 521.996826 cc
2020-01-24T18:57:59.092Z,1579892279.092 [Reporter](INFO): platform_buoyancy_position 517.430664 cc
2020-01-24T18:57:59.483Z,1579892279.483 [Reporter](INFO): platform_buoyancy_position 511.252838 cc
2020-01-24T18:57:59.919Z,1579892279.919 [Reporter](INFO): platform_buoyancy_position 506.283783 cc
2020-01-24T18:58:00.296Z,1579892280.296 [Reporter](INFO): platform_buoyancy_position 500.508881 cc
2020-01-24T18:58:00.680Z,1579892280.680 [Reporter](INFO): platform_buoyancy_position 496.076935 cc
2020-01-24T18:58:01.080Z,1579892281.080 [Reporter](INFO): platform_buoyancy_position 491.510742 cc
2020-01-24T18:58:01.472Z,1579892281.472 [Reporter](INFO): platform_buoyancy_position 485.332947 cc
2020-01-24T18:58:01.879Z,1579892281.879 [Reporter](INFO): platform_buoyancy_position 481.035339 cc
2020-01-24T18:58:02.272Z,1579892282.272 [Reporter](INFO): platform_buoyancy_position 474.723267 cc
2020-01-24T18:58:02.672Z,1579892282.672 [Reporter](INFO): platform_buoyancy_position 470.425659 cc
2020-01-24T18:58:03.092Z,1579892283.092 [Reporter](INFO): platform_buoyancy_position 463.979248 cc
2020-01-24T18:58:03.511Z,1579892283.511 [Reporter](INFO): platform_buoyancy_position 459.547363 cc
2020-01-24T18:58:03.887Z,1579892283.887 [Reporter](INFO): platform_buoyancy_position 453.503845 cc
2020-01-24T18:58:04.291Z,1579892284.291 [Reporter](INFO): platform_buoyancy_position 448.803345 cc
2020-01-24T18:58:04.707Z,1579892284.707 [Reporter](INFO): platform_buoyancy_position 442.491272 cc
2020-01-24T18:58:05.089Z,1579892285.089 [Reporter](INFO): platform_buoyancy_position 438.193665 cc
2020-01-24T18:58:05.511Z,1579892285.511 [Reporter](INFO): platform_buoyancy_position 433.224548 cc
2020-01-24T18:58:05.888Z,1579892285.888 [Reporter](INFO): platform_buoyancy_position 427.315369 cc
2020-01-24T18:58:06.332Z,1579892286.332 [Reporter](INFO): platform_buoyancy_position 421.137573 cc
2020-01-24T18:58:06.723Z,1579892286.723 [Reporter](INFO): platform_buoyancy_position 416.705627 cc
2020-01-24T18:58:07.140Z,1579892287.140 [Reporter](INFO): platform_buoyancy_position 410.259216 cc
2020-01-24T18:58:07.507Z,1579892287.507 [Reporter](INFO): platform_buoyancy_position 405.961670 cc
2020-01-24T18:58:07.979Z,1579892287.979 [Reporter](INFO): platform_buoyancy_position 399.649536 cc
2020-01-24T18:58:08.344Z,1579892288.344 [Reporter](INFO): platform_buoyancy_position 395.217651 cc
2020-01-24T18:58:08.747Z,1579892288.747 [Reporter](INFO): platform_buoyancy_position 390.651428 cc
2020-01-24T18:58:09.168Z,1579892289.168 [Reporter](INFO): platform_buoyancy_position 384.339355 cc
2020-01-24T18:58:09.576Z,1579892289.576 [Reporter](INFO): platform_buoyancy_position 378.027222 cc
2020-01-24T18:58:10.016Z,1579892290.016 [Reporter](INFO): platform_buoyancy_position 373.729614 cc
2020-01-24T18:58:10.371Z,1579892290.371 [Reporter](INFO): platform_buoyancy_position 367.283264 cc
2020-01-24T18:58:10.776Z,1579892290.776 [Reporter](INFO): platform_buoyancy_position 362.985657 cc
2020-01-24T18:58:11.196Z,1579892291.196 [Reporter](INFO): platform_buoyancy_position 356.673523 cc
2020-01-24T18:58:11.568Z,1579892291.568 [Reporter](INFO): platform_buoyancy_position 352.375916 cc
2020-01-24T18:58:12.036Z,1579892292.036 [Reporter](INFO): platform_buoyancy_position 347.809753 cc
2020-01-24T18:58:12.407Z,1579892292.407 [Reporter](INFO): platform_buoyancy_position 342.840637 cc
2020-01-24T18:58:12.662Z,1579892292.662 [DataOverHttps](INFO): Sending 381 bytes from file Logs/20200124T181022/Express0023.lzma
2020-01-24T18:58:12.736Z,1579892292.736 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T18:58:12.914Z,1579892292.914 [Reporter](INFO): platform_buoyancy_position 336.662842 cc
2020-01-24T18:58:13.341Z,1579892293.341 [Reporter](INFO): platform_buoyancy_position 330.619324 cc
2020-01-24T18:58:13.749Z,1579892293.749 [Reporter](INFO): platform_buoyancy_position 324.307251 cc
2020-01-24T18:58:14.168Z,1579892294.168 [Reporter](INFO): platform_buoyancy_position 319.875366 cc
2020-01-24T18:58:14.484Z,1579892294.484 [Reporter](INFO): platform_buoyancy_position 315.577759 cc
2020-01-24T18:58:14.667Z,1579892294.667 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0023.lzma.bak
2020-01-24T18:58:14.667Z,1579892294.667 [DataOverHttps](INFO): SBD MOMSN=12202174
2020-01-24T18:58:14.914Z,1579892294.914 [Reporter](INFO): platform_buoyancy_position 309.534241 cc
2020-01-24T18:58:15.275Z,1579892295.275 [Reporter](INFO): platform_buoyancy_position 304.833740 cc
2020-01-24T18:58:15.702Z,1579892295.702 [Reporter](INFO): platform_buoyancy_position 298.924530 cc
2020-01-24T18:58:16.003Z,1579892296.003 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T18:58:16.003Z,1579892296.003 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T18:58:16.003Z,1579892296.003 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T18:58:16.048Z,1579892296.048 [Reporter](INFO): platform_buoyancy_position 294.224030 cc
2020-01-24T18:58:16.428Z,1579892296.428 [Reporter](INFO): platform_buoyancy_position 289.792145 cc
2020-01-24T18:58:16.848Z,1579892296.848 [Reporter](INFO): platform_buoyancy_position 283.480042 cc
2020-01-24T18:58:17.248Z,1579892297.248 [Reporter](INFO): platform_buoyancy_position 279.048126 cc
2020-01-24T18:58:17.647Z,1579892297.647 [Reporter](INFO): platform_buoyancy_position 272.601746 cc
2020-01-24T18:58:18.079Z,1579892298.079 [Reporter](INFO): platform_buoyancy_position 268.438446 cc
2020-01-24T18:58:18.499Z,1579892298.499 [Reporter](INFO): platform_buoyancy_position 261.992035 cc
2020-01-24T18:58:18.927Z,1579892298.927 [Reporter](INFO): platform_buoyancy_position 257.694427 cc
2020-01-24T18:58:19.280Z,1579892299.280 [Reporter](INFO): platform_buoyancy_position 252.859604 cc
2020-01-24T18:58:19.695Z,1579892299.695 [Reporter](INFO): platform_buoyancy_position 246.816147 cc
2020-01-24T18:58:20.100Z,1579892300.100 [Reporter](INFO): platform_buoyancy_position 240.504028 cc
2020-01-24T18:58:20.479Z,1579892300.479 [Reporter](INFO): platform_buoyancy_position 236.072144 cc
2020-01-24T18:58:20.913Z,1579892300.913 [Reporter](INFO): platform_buoyancy_position 231.371643 cc
2020-01-24T18:58:21.316Z,1579892301.316 [Reporter](INFO): platform_buoyancy_position 225.462433 cc
2020-01-24T18:58:21.708Z,1579892301.708 [Reporter](INFO): platform_buoyancy_position 219.687531 cc
2020-01-24T18:58:22.099Z,1579892302.099 [Reporter](INFO): platform_buoyancy_position 214.987030 cc
2020-01-24T18:58:26.156Z,1579892306.156 [Reporter](INFO): platform_buoyancy_position 203.840118 cc
2020-01-24T18:58:50.634Z,1579892330.634 [CommandLine](IMPORTANT): got command get platform_mass_position
2020-01-24T18:58:50.635Z,1579892330.635 [CommandLine](IMPORTANT): platform_mass_position -0.005000 m
2020-01-24T18:58:53.129Z,1579892333.129 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter
2020-01-24T18:58:53.130Z,1579892333.130 [CommandLine](IMPORTANT): platform_mass_position -0.500018 cm
2020-01-24T18:59:11.203Z,1579892351.203 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault 0.000000 centimeter persist
2020-01-24T18:59:18.630Z,1579892358.630 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter
2020-01-24T18:59:18.631Z,1579892358.631 [CommandLine](IMPORTANT): platform_mass_position -0.111202 cm
2020-01-24T18:59:48.229Z,1579892388.229 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault 0.500000 centimeter persist
2020-01-24T19:00:00.361Z,1579892400.361 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 125.000000 cubic_centimeter
2020-01-24T19:00:01.623Z,1579892401.623 [Reporter](INFO): platform_buoyancy_position 201.959930 cc
2020-01-24T19:00:01.979Z,1579892401.979 [Reporter](INFO): platform_buoyancy_position 203.974426 cc
2020-01-24T19:00:02.388Z,1579892402.388 [Reporter](INFO): platform_buoyancy_position 203.705841 cc
2020-01-24T19:00:02.807Z,1579892402.807 [Reporter](INFO): platform_buoyancy_position 201.691315 cc
2020-01-24T19:00:03.224Z,1579892403.224 [Reporter](INFO): platform_buoyancy_position 199.542542 cc
2020-01-24T19:00:03.596Z,1579892403.596 [Reporter](INFO): platform_buoyancy_position 199.005341 cc
2020-01-24T19:00:04.048Z,1579892404.048 [Reporter](INFO): platform_buoyancy_position 197.393738 cc
2020-01-24T19:00:04.828Z,1579892404.828 [Reporter](INFO): platform_buoyancy_position 195.513519 cc
2020-01-24T19:00:05.311Z,1579892405.311 [Reporter](INFO): platform_buoyancy_position 193.230438 cc
2020-01-24T19:00:05.652Z,1579892405.652 [Reporter](INFO): platform_buoyancy_position 193.096130 cc
2020-01-24T19:00:06.040Z,1579892406.040 [Reporter](INFO): platform_buoyancy_position 191.081635 cc
2020-01-24T19:00:06.466Z,1579892406.466 [Reporter](INFO): platform_buoyancy_position 190.141541 cc
2020-01-24T19:00:06.836Z,1579892406.836 [Reporter](INFO): platform_buoyancy_position 188.798523 cc
2020-01-24T19:00:07.280Z,1579892407.280 [Reporter](INFO): platform_buoyancy_position 186.784027 cc
2020-01-24T19:00:07.680Z,1579892407.680 [Reporter](INFO): platform_buoyancy_position 186.649719 cc
2020-01-24T19:00:08.088Z,1579892408.088 [Reporter](INFO): platform_buoyancy_position 185.172424 cc
2020-01-24T19:00:08.460Z,1579892408.460 [Reporter](INFO): platform_buoyancy_position 182.620728 cc
2020-01-24T19:00:08.907Z,1579892408.907 [Reporter](INFO): platform_buoyancy_position 182.486420 cc
2020-01-24T19:00:09.263Z,1579892409.263 [Reporter](INFO): platform_buoyancy_position 180.606232 cc
2020-01-24T19:00:09.652Z,1579892409.652 [Reporter](INFO): platform_buoyancy_position 180.203339 cc
2020-01-24T19:00:10.071Z,1579892410.071 [Reporter](INFO): platform_buoyancy_position 178.188843 cc
2020-01-24T19:00:10.469Z,1579892410.469 [Reporter](INFO): platform_buoyancy_position 176.040039 cc
2020-01-24T19:00:10.852Z,1579892410.852 [Reporter](INFO): platform_buoyancy_position 175.905731 cc
2020-01-24T19:00:11.267Z,1579892411.267 [Reporter](INFO): platform_buoyancy_position 173.891235 cc
2020-01-24T19:00:12.121Z,1579892412.121 [Reporter](INFO): platform_buoyancy_position 171.742432 cc
2020-01-24T19:00:12.668Z,1579892412.668 [Reporter](INFO): platform_buoyancy_position 169.593628 cc
2020-01-24T19:00:12.915Z,1579892412.915 [Reporter](INFO): platform_buoyancy_position 169.325012 cc
2020-01-24T19:00:13.391Z,1579892413.391 [Reporter](INFO): platform_buoyancy_position 167.444824 cc
2020-01-24T19:00:13.815Z,1579892413.815 [Reporter](INFO): platform_buoyancy_position 165.161713 cc
2020-01-24T19:00:14.588Z,1579892414.588 [Reporter](INFO): platform_buoyancy_position 163.147217 cc
2020-01-24T19:00:15.008Z,1579892415.008 [Reporter](INFO): platform_buoyancy_position 162.744324 cc
2020-01-24T19:00:15.396Z,1579892415.396 [Reporter](INFO): platform_buoyancy_position 160.998413 cc
2020-01-24T19:00:15.768Z,1579892415.768 [Reporter](INFO): platform_buoyancy_position 159.386841 cc
2020-01-24T19:00:16.159Z,1579892416.159 [Reporter](INFO): platform_buoyancy_position 158.715332 cc
2020-01-24T19:00:16.528Z,1579892416.528 [Reporter](INFO): platform_buoyancy_position 156.700836 cc
2020-01-24T19:00:16.979Z,1579892416.979 [Reporter](INFO): platform_buoyancy_position 156.297913 cc
2020-01-24T19:00:17.346Z,1579892417.346 [Reporter](INFO): platform_buoyancy_position 154.417725 cc
2020-01-24T19:00:17.727Z,1579892417.727 [Reporter](INFO): platform_buoyancy_position 152.671814 cc
2020-01-24T19:00:18.159Z,1579892418.159 [Reporter](INFO): platform_buoyancy_position 152.268921 cc
2020-01-24T19:00:18.551Z,1579892418.551 [Reporter](INFO): platform_buoyancy_position 150.120117 cc
2020-01-24T19:00:18.964Z,1579892418.964 [Reporter](INFO): platform_buoyancy_position 148.105621 cc
2020-01-24T19:00:19.751Z,1579892419.751 [Reporter](INFO): platform_buoyancy_position 145.956818 cc
2020-01-24T19:00:20.168Z,1579892420.168 [Reporter](INFO): platform_buoyancy_position 145.688232 cc
2020-01-24T19:00:20.579Z,1579892420.579 [Reporter](INFO): platform_buoyancy_position 143.808014 cc
2020-01-24T19:00:21.000Z,1579892421.000 [Reporter](INFO): platform_buoyancy_position 141.659210 cc
2020-01-24T19:00:21.780Z,1579892421.780 [Reporter](INFO): platform_buoyancy_position 139.644714 cc
2020-01-24T19:00:22.208Z,1579892422.208 [Reporter](INFO): platform_buoyancy_position 137.361633 cc
2020-01-24T19:00:22.980Z,1579892422.980 [Reporter](INFO): platform_buoyancy_position 135.212830 cc
2020-01-24T19:00:23.399Z,1579892423.399 [Reporter](INFO): platform_buoyancy_position 134.004120 cc
2020-01-24T19:00:51.763Z,1579892451.763 [CommandLine](IMPORTANT): got command maintain clear
2020-01-24T19:00:52.073Z,1579892452.073 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,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-24T19:00:54.963Z,1579892454.963 [CommandLine](IMPORTANT): got command report clear
2020-01-24T19:02:54.057Z,1579892574.057 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:02:54.058Z,1579892574.058 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412155792,35.0, -0.1,   0.,  0
2020-01-24T19:03:03.333Z,1579892583.333 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:03:03.333Z,1579892583.333 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412160692,35.0, 0.0,1448.9,  0
2020-01-24T19:03:16.669Z,1579892596.669 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:03:16.669Z,1579892596.669 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:03:16.669Z,1579892596.669 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:03:16.670Z,1579892596.670 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:03:17.090Z,1579892597.090 [Default:CheckIn:D] Stopped
2020-01-24T19:03:17.090Z,1579892597.090 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:03:17.488Z,1579892597.488 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.367086 min 
2020-01-24T19:03:17.488Z,1579892597.488 [Default:CheckIn:E] Stopped
2020-01-24T19:03:17.488Z,1579892597.488 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:03:17.488Z,1579892597.488 [Default:CheckIn] Stopped
2020-01-24T19:03:17.488Z,1579892597.488 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:03:17.489Z,1579892597.489 [Default:CheckIn](INFO): Running loop #6
2020-01-24T19:03:17.489Z,1579892597.489 [Default:CheckIn] Running Loop=6
2020-01-24T19:03:17.489Z,1579892597.489 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:03:17.489Z,1579892597.489 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:03:19.466Z,1579892599.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190317.00,A,3648.16862,N,12147.27946,W,0.117,0.00,240120,,,A*7E
2020-01-24T19:03:19.468Z,1579892599.468 [NAL9602](INFO): GPS fix at 20200124T190317: (36.802810, -121.787991)
2020-01-24T19:03:19.492Z,1579892599.492 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:03:19.492Z,1579892599.492 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:03:27.045Z,1579892607.045 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0025.lzma
2020-01-24T19:03:29.051Z,1579892609.051 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0025.lzma.bak
2020-01-24T19:03:29.051Z,1579892609.051 [DataOverHttps](INFO): SBD MOMSN=12202180
2020-01-24T19:03:46.510Z,1579892626.510 [DataOverHttps](INFO): Sending 342 bytes from file Logs/20200124T181022/Express0026.lzma
2020-01-24T19:03:48.516Z,1579892628.516 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0026.lzma.bak
2020-01-24T19:03:48.516Z,1579892628.516 [DataOverHttps](INFO): SBD MOMSN=12202183
2020-01-24T19:03:49.809Z,1579892629.809 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:03:49.809Z,1579892629.809 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:03:49.809Z,1579892629.809 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:05:08.961Z,1579892708.961 [NAL9602](INFO): SBD MO Status=2, MOMSN=10169, MT Status=2, MTMSN=0
2020-01-24T19:05:08.961Z,1579892708.961 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T19:05:17.897Z,1579892717.897 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T19:05:17.897Z,1579892717.897 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2020-01-24T19:05:37.236Z,1579892737.236 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=10169, MT Status=1, MTMSN=446
2020-01-24T19:05:37.236Z,1579892737.236 [NAL9602](INFO): Data available in MT queue
2020-01-24T19:05:37.726Z,1579892737.726 [NAL9602](INFO): Received command:failc
2020-01-24T19:05:37.780Z,1579892737.780 [CommandLine](IMPORTANT): got command failComponent
2020-01-24T19:05:37.780Z,1579892737.780 [CommandLine](IMPORTANT): Failed components:
2020-01-24T19:05:37.780Z,1579892737.780 [CommandLine](IMPORTANT): No failed Components.
2020-01-24T19:06:08.347Z,1579892768.347 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:08:50.434Z,1579892930.434 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:08:50.434Z,1579892930.434 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:08:50.434Z,1579892930.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:08:50.434Z,1579892930.434 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:08:50.826Z,1579892930.826 [Default:CheckIn:D] Stopped
2020-01-24T19:08:50.826Z,1579892930.826 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:08:51.261Z,1579892931.261 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.929346 min 
2020-01-24T19:08:51.262Z,1579892931.262 [Default:CheckIn:E] Stopped
2020-01-24T19:08:51.262Z,1579892931.262 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:08:51.262Z,1579892931.262 [Default:CheckIn] Stopped
2020-01-24T19:08:51.262Z,1579892931.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:08:51.262Z,1579892931.262 [Default:CheckIn](INFO): Running loop #7
2020-01-24T19:08:51.262Z,1579892931.262 [Default:CheckIn] Running Loop=7
2020-01-24T19:08:51.262Z,1579892931.262 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:08:51.262Z,1579892931.262 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:08:53.218Z,1579892933.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190852.00,A,3648.14017,N,12147.23238,W,0.019,99.31,240120,,,A*47
2020-01-24T19:08:53.220Z,1579892933.220 [NAL9602](INFO): GPS fix at 20200124T190852: (36.802336, -121.787206)
2020-01-24T19:08:53.287Z,1579892933.287 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:08:53.287Z,1579892933.287 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:09:00.589Z,1579892940.589 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200124T181022/Courier0028.lzma
2020-01-24T19:09:02.595Z,1579892942.595 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0028.lzma.bak
2020-01-24T19:09:02.596Z,1579892942.596 [DataOverHttps](INFO): SBD MOMSN=12202199
2020-01-24T19:09:17.453Z,1579892957.453 [NAL9602](INFO): SBD MO Status=0, MOMSN=10170, MT Status=0, MTMSN=0
2020-01-24T19:09:17.453Z,1579892957.453 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:09:20.213Z,1579892960.213 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20200124T181022/Express0029.lzma
2020-01-24T19:09:22.219Z,1579892962.219 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0029.lzma.bak
2020-01-24T19:09:22.219Z,1579892962.219 [DataOverHttps](INFO): SBD MOMSN=12202202
2020-01-24T19:09:23.540Z,1579892963.540 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:09:23.540Z,1579892963.540 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:09:23.540Z,1579892963.540 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:09:48.164Z,1579892988.164 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:10:23.398Z,1579893023.398 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-24T19:10:23.398Z,1579893023.398 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-24T19:10:23.398Z,1579893023.398 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-24T19:10:23.398Z,1579893023.398 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-24T19:10:23.456Z,1579893023.456 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-24T19:10:23.802Z,1579893023.802 [RDI_Pathfinder](INFO): Powering down
2020-01-24T19:10:24.551Z,1579893024.551 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-24T19:10:24.551Z,1579893024.551 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-24T19:10:37.873Z,1579893037.873 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-24T19:11:51.001Z,1579893111.001 [RDI_Pathfinder](ERROR): Failed to parse:
.00
2020-01-24T19:12:31.415Z,1579893151.415 [DataOverHttps](IMPORTANT): SBD MTMSN=20200124T191229
2020-01-24T19:12:39.081Z,1579893159.081 [DataOverHttps](INFO): Received command:ibit
2020-01-24T19:12:39.144Z,1579893159.144 [CommandLine](IMPORTANT): got command ibit
2020-01-24T19:12:39.496Z,1579893159.496 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-01-24T19:12:39.496Z,1579893159.496 [IBIT](IMPORTANT): Beginning control surface checks.
2020-01-24T19:12:39.499Z,1579893159.499 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-24T19:12:41.068Z,1579893161.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191240.00,A,3648.14208,N,12147.23115,W,0.058,99.31,240120,,,A*4A
2020-01-24T19:12:41.070Z,1579893161.070 [NAL9602](INFO): GPS fix at 20200124T191240: (36.802368, -121.787186)
2020-01-24T19:12:50.403Z,1579893170.403 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008881
CHAN A1 (24V): -0.027752
CHAN A2 (12V): -0.006800
CHAN A3 (5V): -0.001824
CHAN B0 (3.3V): 0.000300
CHAN B1 (3.15aV): 0.000164
CHAN B2 (3.15bV): -0.000084
CHAN B3 (GND): 0.002459
OPEN: 0.005910
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-24T19:13:16.221Z,1579893196.221 [NAL9602](INFO): SBD MO Status=0, MOMSN=10171, MT Status=0, MTMSN=0
2020-01-24T19:13:16.221Z,1579893196.221 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:13:17.436Z,1579893197.436 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191316.00,A,3648.14203,N,12147.23042,W,0.058,99.31,240120,,,A*40
2020-01-24T19:13:17.438Z,1579893197.438 [NAL9602](INFO): GPS fix at 20200124T191316: (36.802367, -121.787174)
2020-01-24T19:13:20.260Z,1579893200.260 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191319.00,A,3648.14213,N,12147.23037,W,0.058,99.31,240120,,,A*4C
2020-01-24T19:13:20.262Z,1579893200.262 [NAL9602](INFO): GPS fix at 20200124T191319: (36.802369, -121.787173)
2020-01-24T19:13:23.493Z,1579893203.493 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191322.00,A,3648.14230,N,12147.23042,W,0.058,99.31,240120,,,A*47
2020-01-24T19:13:23.496Z,1579893203.496 [NAL9602](INFO): GPS fix at 20200124T191322: (36.802372, -121.787174)
2020-01-24T19:13:25.968Z,1579893205.968 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802372 Longitude: -121.787170
2020-01-24T19:13:26.322Z,1579893206.322 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191325.00,A,3648.14245,N,12147.23047,W,0.058,99.31,240120,,,A*47
2020-01-24T19:13:26.324Z,1579893206.324 [NAL9602](INFO): GPS fix at 20200124T191325: (36.802374, -121.787175)
2020-01-24T19:13:26.389Z,1579893206.389 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.739000
2020-01-24T19:13:26.390Z,1579893206.390 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-01-24T19:13:26.390Z,1579893206.390 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-01-24T19:13:26.787Z,1579893206.787 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-01-24T19:13:26.787Z,1579893206.787 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-01-24T19:13:26.788Z,1579893206.788 [IBIT](IMPORTANT): Pressure:7.850539 PSI
2020-01-24T19:13:26.788Z,1579893206.788 [IBIT](IMPORTANT): Humidity:0.000000 %
2020-01-24T19:13:27.189Z,1579893207.189 [IBIT](IMPORTANT): Vehicle Pitch:0.511294 degrees
2020-01-24T19:13:27.189Z,1579893207.189 [IBIT](IMPORTANT): Vehicle  Roll:12.347032 degrees
2020-01-24T19:13:27.190Z,1579893207.190 [IBIT](IMPORTANT): Vehicle  Heading:179.087708 degrees
2020-01-24T19:13:27.587Z,1579893207.587 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-01-24T19:13:27.587Z,1579893207.587 [IBIT](IMPORTANT): buoyancyNeutral: 105.000000 cc
2020-01-24T19:13:27.588Z,1579893207.588 [IBIT](IMPORTANT): massDefault: 0.500000 cm
2020-01-24T19:13:27.588Z,1579893207.588 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2020-01-24T19:13:27.588Z,1579893207.588 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2020-01-24T19:13:27.588Z,1579893207.588 [IBIT](IMPORTANT): IBIT PASSED
2020-01-24T19:13:46.533Z,1579893226.533 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:13:46.533Z,1579893226.533 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412264985,35.0, -0.1,   0.,  0
2020-01-24T19:13:58.639Z,1579893238.639 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:14:24.162Z,1579893264.162 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:14:24.162Z,1579893264.162 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:14:24.162Z,1579893264.162 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:14:24.162Z,1579893264.162 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:14:24.559Z,1579893264.559 [Default:CheckIn:D] Stopped
2020-01-24T19:14:24.559Z,1579893264.559 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:14:24.974Z,1579893264.974 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.491573 min 
2020-01-24T19:14:24.975Z,1579893264.975 [Default:CheckIn:E] Stopped
2020-01-24T19:14:24.975Z,1579893264.975 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:14:24.975Z,1579893264.975 [Default:CheckIn] Stopped
2020-01-24T19:14:24.975Z,1579893264.975 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:14:24.976Z,1579893264.976 [Default:CheckIn](INFO): Running loop #8
2020-01-24T19:14:24.976Z,1579893264.976 [Default:CheckIn] Running Loop=8
2020-01-24T19:14:24.976Z,1579893264.976 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:14:24.976Z,1579893264.976 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:14:26.916Z,1579893266.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191425.00,A,3648.14159,N,12147.22941,W,0.019,99.31,240120,,,A*45
2020-01-24T19:14:26.918Z,1579893266.918 [NAL9602](INFO): GPS fix at 20200124T191425: (36.802360, -121.787157)
2020-01-24T19:14:26.970Z,1579893266.970 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:14:26.970Z,1579893266.970 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:14:36.753Z,1579893276.753 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0031.lzma
2020-01-24T19:14:38.759Z,1579893278.759 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0031.lzma.bak
2020-01-24T19:14:38.759Z,1579893278.759 [DataOverHttps](INFO): SBD MOMSN=12202213
2020-01-24T19:14:41.061Z,1579893281.061 [NAL9602](INFO): SBD MO Status=0, MOMSN=10172, MT Status=0, MTMSN=0
2020-01-24T19:14:41.061Z,1579893281.061 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:14:47.550Z,1579893287.550 [RDI_Pathfinder](ERROR): Failed to parse:
68,-32768,-32768,V
2020-01-24T19:14:56.415Z,1579893296.415 [DataOverHttps](INFO): Sending 777 bytes from file Logs/20200124T181022/Express0032.lzma
2020-01-24T19:14:58.419Z,1579893298.419 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0032.lzma.bak
2020-01-24T19:14:58.419Z,1579893298.419 [DataOverHttps](INFO): SBD MOMSN=12202217
2020-01-24T19:15:11.802Z,1579893311.802 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:15:15.885Z,1579893315.885 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200124T181022/Express0035.lzma
2020-01-24T19:15:17.891Z,1579893317.891 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0035.lzma.bak
2020-01-24T19:15:17.891Z,1579893317.891 [DataOverHttps](INFO): SBD MOMSN=12202243
2020-01-24T19:15:19.082Z,1579893319.082 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:15:19.082Z,1579893319.082 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:15:19.082Z,1579893319.082 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:15:33.193Z,1579893333.193 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:15:33.193Z,1579893333.193 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412283685,35.0, -0.1,   0.0,14
2020-01-24T19:16:11.171Z,1579893371.171 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T19:16:11.171Z,1579893371.171 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T19:16:30.176Z,1579893390.176 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:16:30.176Z,1579893390.176 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412293385,35.0, -0.1,   0.0,14
2020-01-24T19:16:51.183Z,1579893411.183 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T19:16:51.183Z,1579893411.183 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T19:17:49.802Z,1579893469.802 [RDI_Pathfinder](ERROR): Failed to parse:
2768,V
2020-01-24T19:18:47.530Z,1579893527.530 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:18:47.530Z,1579893527.530 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412315085,35.0, -00,1448.9,  0
2020-01-24T19:19:31.224Z,1579893571.224 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T19:19:31.224Z,1579893571.224 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T19:20:19.829Z,1579893619.829 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:20:19.829Z,1579893619.829 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:20:19.829Z,1579893619.829 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:20:19.829Z,1579893619.829 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:20:20.112Z,1579893620.112 [Default:CheckIn:D] Stopped
2020-01-24T19:20:20.112Z,1579893620.112 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:20:20.497Z,1579893620.497 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.417456 min 
2020-01-24T19:20:20.497Z,1579893620.497 [Default:CheckIn:E] Stopped
2020-01-24T19:20:20.497Z,1579893620.497 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:20:20.498Z,1579893620.498 [Default:CheckIn] Stopped
2020-01-24T19:20:20.498Z,1579893620.498 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:20:20.498Z,1579893620.498 [Default:CheckIn](INFO): Running loop #9
2020-01-24T19:20:20.498Z,1579893620.498 [Default:CheckIn] Running Loop=9
2020-01-24T19:20:20.498Z,1579893620.498 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:20:20.498Z,1579893620.498 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:20:22.471Z,1579893622.471 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192021.00,A,3648.14455,N,12147.23259,W,0.039,99.31,240120,,,D*4B
2020-01-24T19:20:22.473Z,1579893622.473 [NAL9602](INFO): GPS fix at 20200124T192021: (36.802409, -121.787210)
2020-01-24T19:20:22.498Z,1579893622.498 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:20:22.498Z,1579893622.498 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:20:29.771Z,1579893629.771 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0037.lzma
2020-01-24T19:20:31.771Z,1579893631.771 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0037.lzma.bak
2020-01-24T19:20:31.771Z,1579893631.771 [DataOverHttps](INFO): SBD MOMSN=12202248
2020-01-24T19:20:46.710Z,1579893646.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=10173, MT Status=0, MTMSN=0
2020-01-24T19:20:46.710Z,1579893646.710 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:20:49.598Z,1579893649.598 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200124T181022/Express0038.lzma
2020-01-24T19:20:51.603Z,1579893651.603 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0038.lzma.bak
2020-01-24T19:20:51.603Z,1579893651.603 [DataOverHttps](INFO): SBD MOMSN=12202251
2020-01-24T19:20:52.819Z,1579893652.819 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:20:52.819Z,1579893652.819 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:20:52.819Z,1579893652.819 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:21:06.512Z,1579893666.512 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-24T19:21:17.404Z,1579893677.404 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:21:27.523Z,1579893687.523 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T19:21:27.524Z,1579893687.524 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T19:24:09.550Z,1579893849.550 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-24T19:24:09.552Z,1579893849.552 [BPC1](INFO): Received data from all battery sticks.
2020-01-24T19:25:53.362Z,1579893953.362 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:25:53.362Z,1579893953.362 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:25:53.363Z,1579893953.363 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:25:53.363Z,1579893953.363 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:25:53.778Z,1579893953.778 [Default:CheckIn:D] Stopped
2020-01-24T19:25:53.778Z,1579893953.778 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:25:54.192Z,1579893954.192 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.978548 min 
2020-01-24T19:25:54.192Z,1579893954.192 [Default:CheckIn:E] Stopped
2020-01-24T19:25:54.192Z,1579893954.192 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:25:54.192Z,1579893954.192 [Default:CheckIn] Stopped
2020-01-24T19:25:54.193Z,1579893954.193 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:25:54.193Z,1579893954.193 [Default:CheckIn](INFO): Running loop #10
2020-01-24T19:25:54.193Z,1579893954.193 [Default:CheckIn] Running Loop=10
2020-01-24T19:25:54.193Z,1579893954.193 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:25:54.193Z,1579893954.193 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:25:56.173Z,1579893956.173 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192555.00,A,3648.14214,N,12147.23216,W,0.019,99.31,240120,,,D*47
2020-01-24T19:25:56.176Z,1579893956.176 [NAL9602](INFO): GPS fix at 20200124T192555: (36.802369, -121.787203)
2020-01-24T19:25:56.231Z,1579893956.231 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:25:56.232Z,1579893956.232 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:26:04.449Z,1579893964.449 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20200124T181022/Courier0040.lzma
2020-01-24T19:26:06.455Z,1579893966.455 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0040.lzma.bak
2020-01-24T19:26:06.455Z,1579893966.455 [DataOverHttps](INFO): SBD MOMSN=12202256
2020-01-24T19:26:13.969Z,1579893973.969 [NAL9602](INFO): SBD MO Status=0, MOMSN=10174, MT Status=0, MTMSN=0
2020-01-24T19:26:13.992Z,1579893973.992 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:26:24.370Z,1579893984.370 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200124T181022/Express0041.lzma
2020-01-24T19:26:26.375Z,1579893986.375 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0041.lzma.bak
2020-01-24T19:26:26.375Z,1579893986.375 [DataOverHttps](INFO): SBD MOMSN=12202259
2020-01-24T19:26:27.720Z,1579893987.720 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:26:27.720Z,1579893987.720 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:26:27.721Z,1579893987.721 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:26:44.654Z,1579894004.654 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:31:28.285Z,1579894288.285 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:31:28.285Z,1579894288.285 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:31:28.285Z,1579894288.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:31:28.285Z,1579894288.285 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:31:28.698Z,1579894288.698 [Default:CheckIn:D] Stopped
2020-01-24T19:31:28.698Z,1579894288.698 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:31:29.116Z,1579894289.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.560547 min 
2020-01-24T19:31:29.116Z,1579894289.116 [Default:CheckIn:E] Stopped
2020-01-24T19:31:29.117Z,1579894289.117 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:31:29.117Z,1579894289.117 [Default:CheckIn] Stopped
2020-01-24T19:31:29.117Z,1579894289.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:31:29.117Z,1579894289.117 [Default:CheckIn](INFO): Running loop #11
2020-01-24T19:31:29.117Z,1579894289.117 [Default:CheckIn] Running Loop=11
2020-01-24T19:31:29.117Z,1579894289.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:31:29.117Z,1579894289.117 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:31:31.093Z,1579894291.093 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193130.00,A,3648.14174,N,12147.23020,W,0.039,99.31,240120,,,D*41
2020-01-24T19:31:31.095Z,1579894291.095 [NAL9602](INFO): GPS fix at 20200124T193130: (36.802362, -121.787170)
2020-01-24T19:31:31.171Z,1579894291.171 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:31:31.171Z,1579894291.171 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:31:39.121Z,1579894299.121 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200124T181022/Courier0043.lzma
2020-01-24T19:31:41.127Z,1579894301.127 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0043.lzma.bak
2020-01-24T19:31:41.127Z,1579894301.127 [DataOverHttps](INFO): SBD MOMSN=12202264
2020-01-24T19:31:44.426Z,1579894304.426 [NAL9602](INFO): SBD MO Status=0, MOMSN=10175, MT Status=0, MTMSN=0
2020-01-24T19:31:44.426Z,1579894304.426 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:31:57.817Z,1579894317.817 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T19:31:58.489Z,1579894318.489 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200124T181022/Express0044.lzma
2020-01-24T19:32:00.495Z,1579894320.495 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0044.lzma.bak
2020-01-24T19:32:00.495Z,1579894320.496 [DataOverHttps](INFO): SBD MOMSN=12202267
2020-01-24T19:32:01.842Z,1579894321.842 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:32:01.842Z,1579894321.842 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:32:01.842Z,1579894321.842 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:32:15.136Z,1579894335.136 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:34:18.808Z,1579894458.808 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T19:34:18.809Z,1579894458.809 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3768,-32768,V
2020-01-24T19:34:34.512Z,1579894474.512 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-24T19:34:34.513Z,1579894474.513 [DropWeight] Hardware Fault, FailCount= 1
2020-01-24T19:34:34.513Z,1579894474.513 [DropWeight](ERROR): Hardware Fault
2020-01-24T19:34:34.590Z,1579894474.590 [CommandLine](FAULT): Scheduling is paused
2020-01-24T19:34:34.590Z,1579894474.590 [CBIT](INFO): Critical error at 20200124T193434
2020-01-24T19:34:34.592Z,1579894474.592 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-24T19:34:34.593Z,1579894474.593 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-24T19:34:34.984Z,1579894474.984 [CBIT](INFO): Critical error at 20200124T193434
2020-01-24T19:37:02.418Z,1579894622.418 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:37:02.418Z,1579894622.418 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:37:02.418Z,1579894622.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:37:02.418Z,1579894622.418 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:37:02.845Z,1579894622.845 [Default:CheckIn:D] Stopped
2020-01-24T19:37:02.845Z,1579894622.845 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.129671 min 
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn:E] Stopped
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn] Stopped
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn](INFO): Running loop #12
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn] Running Loop=12
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:37:03.235Z,1579894623.235 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:37:05.213Z,1579894625.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193704.00,A,3648.14183,N,12147.22877,W,0.194,216.68,240120,,,D*7C
2020-01-24T19:37:05.216Z,1579894625.216 [NAL9602](INFO): GPS fix at 20200124T193704: (36.802364, -121.787146)
2020-01-24T19:37:05.274Z,1579894625.274 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:37:05.274Z,1579894625.274 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:37:12.912Z,1579894632.912 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20200124T181022/Courier0046.lzma
2020-01-24T19:37:14.916Z,1579894634.916 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0046.lzma.bak
2020-01-24T19:37:14.916Z,1579894634.916 [DataOverHttps](INFO): SBD MOMSN=12202272
2020-01-24T19:37:32.357Z,1579894652.357 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20200124T181022/Express0047.lzma
2020-01-24T19:37:34.363Z,1579894654.363 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0047.lzma.bak
2020-01-24T19:37:34.364Z,1579894654.364 [DataOverHttps](INFO): SBD MOMSN=12202277
2020-01-24T19:37:35.546Z,1579894655.546 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:37:35.546Z,1579894655.546 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:37:35.546Z,1579894655.546 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:38:28.460Z,1579894708.460 [NAL9602](INFO): SBD MO Status=2, MOMSN=10176, MT Status=2, MTMSN=0
2020-01-24T19:38:28.460Z,1579894708.460 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T19:38:58.803Z,1579894738.803 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T19:38:58.803Z,1579894738.803 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3278,-32768,V
2020-01-24T19:39:14.505Z,1579894754.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=10176, MT Status=2, MTMSN=0
2020-01-24T19:39:14.505Z,1579894754.505 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T19:39:14.968Z,1579894754.968 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T19:39:14.968Z,1579894754.968 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T19:39:38.799Z,1579894778.799 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:39:38.799Z,1579894778.799 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412524285,35.0, -0.1,   0.0  0
2020-01-24T19:40:09.445Z,1579894809.445 [NAL9602](INFO): SBD MO Status=2, MOMSN=10176, MT Status=2, MTMSN=0
2020-01-24T19:40:09.446Z,1579894809.446 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T19:40:19.171Z,1579894819.171 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:40:19.171Z,1579894819.171 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012412532285,35.0, -0.1,448.9,  0
2020-01-24T19:40:30.057Z,1579894830.057 [NAL9602](INFO): SBD MO Status=0, MOMSN=10176, MT Status=0, MTMSN=0
2020-01-24T19:40:30.057Z,1579894830.057 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:40:39.797Z,1579894839.797 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T19:40:39.797Z,1579894839.797 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,32768,-32768,V
2020-01-24T19:41:00.783Z,1579894860.783 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:42:36.179Z,1579894956.179 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:42:36.179Z,1579894956.179 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:42:36.179Z,1579894956.179 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:42:36.180Z,1579894956.180 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:42:36.550Z,1579894956.550 [Default:CheckIn:D] Stopped
2020-01-24T19:42:36.550Z,1579894956.550 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.691414 min 
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn:E] Stopped
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn] Stopped
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn](INFO): Running loop #13
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn] Running Loop=13
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:42:36.964Z,1579894956.964 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:42:38.942Z,1579894958.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194237.00,A,3648.14089,N,12147.22516,W,0.117,216.68,240120,,,A*71
2020-01-24T19:42:38.944Z,1579894958.944 [NAL9602](INFO): GPS fix at 20200124T194237: (36.802348, -121.787086)
2020-01-24T19:42:39.022Z,1579894959.022 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:42:39.022Z,1579894959.022 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:42:46.654Z,1579894966.654 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0049.lzma
2020-01-24T19:42:48.659Z,1579894968.659 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0049.lzma.bak
2020-01-24T19:42:48.660Z,1579894968.660 [DataOverHttps](INFO): SBD MOMSN=12202283
2020-01-24T19:42:57.146Z,1579894977.146 [NAL9602](INFO): SBD MO Status=0, MOMSN=10177, MT Status=0, MTMSN=0
2020-01-24T19:42:57.146Z,1579894977.146 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:43:06.074Z,1579894986.074 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20200124T181022/Express0050.lzma
2020-01-24T19:43:08.079Z,1579894988.079 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0050.lzma.bak
2020-01-24T19:43:08.080Z,1579894988.080 [DataOverHttps](INFO): SBD MOMSN=12202286
2020-01-24T19:43:09.267Z,1579894989.267 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:43:09.267Z,1579894989.267 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:43:09.267Z,1579894989.267 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:43:27.833Z,1579895007.833 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:44:19.177Z,1579895059.177 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T19:44:19.177Z,1579895059.177 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T19:48:09.914Z,1579895289.914 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:48:09.914Z,1579895289.914 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:48:09.914Z,1579895289.914 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:48:09.914Z,1579895289.914 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:48:10.317Z,1579895290.317 [Default:CheckIn:D] Stopped
2020-01-24T19:48:10.317Z,1579895290.317 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:48:10.730Z,1579895290.730 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.254191 min 
2020-01-24T19:48:10.730Z,1579895290.730 [Default:CheckIn:E] Stopped
2020-01-24T19:48:10.730Z,1579895290.730 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:48:10.730Z,1579895290.730 [Default:CheckIn] Stopped
2020-01-24T19:48:10.730Z,1579895290.730 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:48:10.730Z,1579895290.730 [Default:CheckIn](INFO): Running loop #14
2020-01-24T19:48:10.730Z,1579895290.730 [Default:CheckIn] Running Loop=14
2020-01-24T19:48:10.731Z,1579895290.731 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:48:10.731Z,1579895290.731 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:48:12.709Z,1579895292.709 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194811.00,A,3648.14258,N,12147.23152,W,0.058,216.68,240120,,,D*7B
2020-01-24T19:48:12.711Z,1579895292.711 [NAL9602](INFO): GPS fix at 20200124T194811: (36.802376, -121.787192)
2020-01-24T19:48:12.841Z,1579895292.841 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:48:12.841Z,1579895292.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:48:20.597Z,1579895300.597 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0052.lzma
2020-01-24T19:48:21.146Z,1579895301.146 [NAL9602](INFO): SBD MO Status=0, MOMSN=10178, MT Status=0, MTMSN=0
2020-01-24T19:48:21.146Z,1579895301.146 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:48:22.603Z,1579895302.603 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0052.lzma.bak
2020-01-24T19:48:22.603Z,1579895302.603 [DataOverHttps](INFO): SBD MOMSN=12202291
2020-01-24T19:48:36.515Z,1579895316.515 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T19:48:36.515Z,1579895316.515 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T19:48:40.142Z,1579895320.142 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20200124T181022/Express0053.lzma
2020-01-24T19:48:42.147Z,1579895322.147 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0053.lzma.bak
2020-01-24T19:48:42.147Z,1579895322.147 [DataOverHttps](INFO): SBD MOMSN=12202294
2020-01-24T19:48:43.392Z,1579895323.392 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:48:43.392Z,1579895323.392 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:48:43.392Z,1579895323.392 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:48:51.848Z,1579895331.848 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:49:34.718Z,1579895374.718 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-24T19:49:34.718Z,1579895374.718 [DropWeight] No Fault, FailCount= 1
2020-01-24T19:49:37.148Z,1579895377.148 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T19:49:37.148Z,1579895377.148 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3276,-32768,V
2020-01-24T19:49:41.160Z,1579895381.160 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T19:49:41.160Z,1579895381.160 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T19:51:02.815Z,1579895462.815 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-24T19:51:02.815Z,1579895462.815 [RDI_Pathfinder](ERROR): Failed to parse:
:BD +0.00,        +0.00,        +0.00,   0.00,2424.74
2020-01-24T19:51:16.537Z,1579895476.537 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T19:51:16.537Z,1579895476.537 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3768,V
2020-01-24T19:51:20.149Z,1579895480.149 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:51:20.149Z,1579895480.149 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012413042385,35.0, -0.1,  8.9,  0
2020-01-24T19:51:40.787Z,1579895500.787 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:51:40.787Z,1579895500.787 [RDI_Pathfinder](ERROR): Failed to parse:
:TS68,-32768,V
2020-01-24T19:52:22.826Z,1579895542.826 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T19:52:22.826Z,1579895542.826 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3276,V
2020-01-24T19:53:44.011Z,1579895624.011 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:53:44.011Z,1579895624.011 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:53:44.011Z,1579895624.011 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:53:44.011Z,1579895624.011 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:53:44.406Z,1579895624.406 [Default:CheckIn:D] Stopped
2020-01-24T19:53:44.406Z,1579895624.406 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:53:44.824Z,1579895624.824 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.822339 min 
2020-01-24T19:53:44.824Z,1579895624.824 [Default:CheckIn:E] Stopped
2020-01-24T19:53:44.824Z,1579895624.824 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:53:44.824Z,1579895624.824 [Default:CheckIn] Stopped
2020-01-24T19:53:44.824Z,1579895624.824 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:53:44.824Z,1579895624.824 [Default:CheckIn](INFO): Running loop #15
2020-01-24T19:53:44.825Z,1579895624.825 [Default:CheckIn] Running Loop=15
2020-01-24T19:53:44.825Z,1579895624.825 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:53:44.825Z,1579895624.825 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:53:46.805Z,1579895626.805 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195345.00,A,3648.14136,N,12147.22875,W,0.136,256.14,240120,,,D*70
2020-01-24T19:53:46.807Z,1579895626.807 [NAL9602](INFO): GPS fix at 20200124T195345: (36.802356, -121.787146)
2020-01-24T19:53:46.832Z,1579895626.832 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:53:46.832Z,1579895626.832 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:53:54.873Z,1579895634.873 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0055.lzma
2020-01-24T19:53:56.879Z,1579895636.879 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0055.lzma.bak
2020-01-24T19:53:56.879Z,1579895636.879 [DataOverHttps](INFO): SBD MOMSN=12202299
2020-01-24T19:54:11.058Z,1579895651.058 [NAL9602](INFO): SBD MO Status=2, MOMSN=10179, MT Status=2, MTMSN=0
2020-01-24T19:54:11.058Z,1579895651.058 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T19:54:14.330Z,1579895654.330 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200124T181022/Express0056.lzma
2020-01-24T19:54:16.347Z,1579895656.347 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0056.lzma.bak
2020-01-24T19:54:16.348Z,1579895656.348 [DataOverHttps](INFO): SBD MOMSN=12202302
2020-01-24T19:54:17.573Z,1579895657.573 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:54:17.573Z,1579895657.573 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:54:17.597Z,1579895657.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T19:54:20.803Z,1579895660.803 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T19:54:20.803Z,1579895660.803 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3278,-32768,V
2020-01-24T19:54:36.901Z,1579895676.901 [NAL9602](INFO): SBD MO Status=0, MOMSN=10179, MT Status=0, MTMSN=0
2020-01-24T19:54:36.901Z,1579895676.901 [NAL9602](INFO): No messages in MT queue
2020-01-24T19:55:03.189Z,1579895703.189 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:55:03.189Z,1579895703.189 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012413080685,35.0, -0.1,   0.0,14
2020-01-24T19:55:07.604Z,1579895707.604 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T19:57:59.776Z,1579895879.776 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:57:59.776Z,1579895879.776 [RDI_Pathfinder](ERROR): Failed to parse:
:TS3110385,35.0, -0.1,   0.0,1448.9,  0
2020-01-24T19:58:44.213Z,1579895924.213 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T19:58:44.213Z,1579895924.213 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012413114785,35.0, -0.1,   0.,  0
2020-01-24T19:59:18.159Z,1579895958.159 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T19:59:18.159Z,1579895958.159 [Default:CheckIn:C.Wait] Stopped
2020-01-24T19:59:18.159Z,1579895958.159 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T19:59:18.159Z,1579895958.159 [Default:CheckIn:D] Running Loop=1
2020-01-24T19:59:18.562Z,1579895958.562 [Default:CheckIn:D] Stopped
2020-01-24T19:59:18.562Z,1579895958.562 [Default:CheckIn:E] Running Loop=1
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.391626 min 
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn:E] Stopped
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn] Stopped
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn](INFO): Running loop #16
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn] Running Loop=16
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T19:59:18.975Z,1579895958.975 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T19:59:20.954Z,1579895960.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195919.00,A,3648.14261,N,12147.23012,W,0.019,256.14,240120,,,D*76
2020-01-24T19:59:20.956Z,1579895960.956 [NAL9602](INFO): GPS fix at 20200124T195919: (36.802377, -121.787169)
2020-01-24T19:59:21.017Z,1579895961.017 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T19:59:21.017Z,1579895961.017 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T19:59:28.565Z,1579895968.565 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0058.lzma
2020-01-24T19:59:30.572Z,1579895970.572 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0058.lzma.bak
2020-01-24T19:59:30.572Z,1579895970.572 [DataOverHttps](INFO): SBD MOMSN=12202307
2020-01-24T19:59:44.381Z,1579895984.381 [NAL9602](INFO): SBD MO Status=2, MOMSN=10180, MT Status=2, MTMSN=0
2020-01-24T19:59:44.402Z,1579895984.402 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T19:59:48.022Z,1579895988.022 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200124T181022/Express0059.lzma
2020-01-24T19:59:50.027Z,1579895990.027 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0059.lzma.bak
2020-01-24T19:59:50.027Z,1579895990.027 [DataOverHttps](INFO): SBD MOMSN=12202310
2020-01-24T19:59:51.294Z,1579895991.294 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T19:59:51.294Z,1579895991.294 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T19:59:51.294Z,1579895991.294 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T20:00:11.049Z,1579896011.049 [NAL9602](INFO): SBD MO Status=0, MOMSN=10180, MT Status=0, MTMSN=0
2020-01-24T20:00:11.049Z,1579896011.049 [NAL9602](INFO): No messages in MT queue
2020-01-24T20:00:34.951Z,1579896034.951 [RDI_Pathfinder](ERROR): Failed to parse:
:0,   0.00,   0.00,   0.00,   0.00
2020-01-24T20:00:41.776Z,1579896041.776 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T20:01:57.347Z,1579896117.347 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-24T20:02:00.157Z,1579896120.157 [RDI_Pathfinder](ERROR): Failed to parse:768,-32768,-32768,V
2020-01-24T20:04:20.779Z,1579896260.779 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T20:04:51.871Z,1579896291.871 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T20:04:51.871Z,1579896291.871 [Default:CheckIn:C.Wait] Stopped
2020-01-24T20:04:51.871Z,1579896291.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T20:04:51.871Z,1579896291.871 [Default:CheckIn:D] Running Loop=1
2020-01-24T20:04:52.274Z,1579896292.274 [Default:CheckIn:D] Stopped
2020-01-24T20:04:52.274Z,1579896292.274 [Default:CheckIn:E] Running Loop=1
2020-01-24T20:04:52.679Z,1579896292.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.953483 min 
2020-01-24T20:04:52.679Z,1579896292.679 [Default:CheckIn:E] Stopped
2020-01-24T20:04:52.679Z,1579896292.679 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T20:04:52.679Z,1579896292.679 [Default:CheckIn] Stopped
2020-01-24T20:04:52.679Z,1579896292.679 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T20:04:52.679Z,1579896292.679 [Default:CheckIn](INFO): Running loop #17
2020-01-24T20:04:52.680Z,1579896292.680 [Default:CheckIn] Running Loop=17
2020-01-24T20:04:52.680Z,1579896292.680 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T20:04:52.680Z,1579896292.680 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T20:04:54.664Z,1579896294.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200453.00,A,3648.14224,N,12147.23139,W,0.019,256.14,240120,,,D*73
2020-01-24T20:04:54.666Z,1579896294.666 [NAL9602](INFO): GPS fix at 20200124T200453: (36.802371, -121.787190)
2020-01-24T20:04:54.722Z,1579896294.722 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T20:04:54.722Z,1579896294.722 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T20:05:02.313Z,1579896302.313 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200124T181022/Courier0061.lzma
2020-01-24T20:05:04.319Z,1579896304.319 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0061.lzma.bak
2020-01-24T20:05:04.319Z,1579896304.319 [DataOverHttps](INFO): SBD MOMSN=12202315
2020-01-24T20:05:19.783Z,1579896319.783 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-24T20:05:19.784Z,1579896319.784 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3276,V
2020-01-24T20:05:22.057Z,1579896322.057 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200124T181022/Express0062.lzma
2020-01-24T20:05:24.063Z,1579896324.063 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0062.lzma.bak
2020-01-24T20:05:24.064Z,1579896324.064 [DataOverHttps](INFO): SBD MOMSN=12202318
2020-01-24T20:05:25.412Z,1579896325.412 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T20:05:25.412Z,1579896325.412 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T20:05:25.412Z,1579896325.412 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T20:05:37.918Z,1579896337.918 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-01-24T20:05:37.918Z,1579896337.918 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.8.53
2020-01-24T20:05:41.161Z,1579896341.161 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T20:05:41.161Z,1579896341.161 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   I,-32768,-32768,-32768,-32768,V
2020-01-24T20:06:17.928Z,1579896377.928 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T20:06:17.928Z,1579896377.928 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.8.52
2020-01-24T20:06:20.736Z,1579896380.736 [NAL9602](INFO): SBD MO Status=2, MOMSN=10181, MT Status=2, MTMSN=0
2020-01-24T20:06:20.737Z,1579896380.737 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T20:06:38.101Z,1579896398.101 [NAL9602](INFO): SBD MO Status=2, MOMSN=10181, MT Status=2, MTMSN=0
2020-01-24T20:06:38.102Z,1579896398.102 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T20:07:19.773Z,1579896439.773 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T20:07:19.773Z,1579896439.773 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T20:07:41.941Z,1579896461.941 [NAL9602](INFO): SBD MO Status=0, MOMSN=10181, MT Status=0, MTMSN=0
2020-01-24T20:07:41.941Z,1579896461.941 [NAL9602](INFO): No messages in MT queue
2020-01-24T20:08:12.640Z,1579896492.640 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T20:09:16.507Z,1579896556.507 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-01-24T20:09:37.516Z,1579896577.516 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T20:10:21.128Z,1579896621.128 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T20:10:21.128Z,1579896621.128 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-24T20:10:26.005Z,1579896626.005 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T20:10:26.005Z,1579896626.005 [Default:CheckIn:C.Wait] Stopped
2020-01-24T20:10:26.005Z,1579896626.005 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T20:10:26.005Z,1579896626.005 [Default:CheckIn:D] Running Loop=1
2020-01-24T20:10:26.411Z,1579896626.411 [Default:CheckIn:D] Stopped
2020-01-24T20:10:26.412Z,1579896626.412 [Default:CheckIn:E] Running Loop=1
2020-01-24T20:10:26.817Z,1579896626.817 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.522445 min 
2020-01-24T20:10:26.817Z,1579896626.817 [Default:CheckIn:E] Stopped
2020-01-24T20:10:26.818Z,1579896626.818 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T20:10:26.818Z,1579896626.818 [Default:CheckIn] Stopped
2020-01-24T20:10:26.818Z,1579896626.818 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T20:10:26.818Z,1579896626.818 [Default:CheckIn](INFO): Running loop #18
2020-01-24T20:10:26.818Z,1579896626.818 [Default:CheckIn] Running Loop=18
2020-01-24T20:10:26.818Z,1579896626.818 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T20:10:26.818Z,1579896626.818 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T20:10:28.806Z,1579896628.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201027.00,A,3648.16423,N,12147.21375,W,0.078,0.98,240120,,,D*7C
2020-01-24T20:10:28.808Z,1579896628.808 [NAL9602](INFO): GPS fix at 20200124T201027: (36.802737, -121.786896)
2020-01-24T20:10:28.870Z,1579896628.870 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T20:10:28.870Z,1579896628.870 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T20:10:36.127Z,1579896636.127 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0064.lzma
2020-01-24T20:10:38.123Z,1579896638.123 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0064.lzma.bak
2020-01-24T20:10:38.124Z,1579896638.124 [DataOverHttps](INFO): SBD MOMSN=12202323
2020-01-24T20:10:55.470Z,1579896655.470 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200124T181022/Express0065.lzma
2020-01-24T20:10:56.269Z,1579896656.269 [NAL9602](INFO): SBD MO Status=0, MOMSN=10182, MT Status=0, MTMSN=0
2020-01-24T20:10:56.269Z,1579896656.269 [NAL9602](INFO): No messages in MT queue
2020-01-24T20:10:57.475Z,1579896657.475 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0065.lzma.bak
2020-01-24T20:10:57.475Z,1579896657.475 [DataOverHttps](INFO): SBD MOMSN=12202326
2020-01-24T20:10:58.722Z,1579896658.722 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T20:10:58.722Z,1579896658.722 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T20:10:58.722Z,1579896658.722 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T20:11:22.137Z,1579896682.137 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T20:11:22.137Z,1579896682.137 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012413242585,35.0, -.0,1448.9,  0
2020-01-24T20:11:26.966Z,1579896686.966 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T20:11:37.510Z,1579896697.510 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T20:15:41.154Z,1579896941.154 [RDI_Pathfinder](ERROR): Failed to parse:
:B,-32768,-32768,V
2020-01-24T20:15:59.314Z,1579896959.314 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T20:15:59.314Z,1579896959.314 [Default:CheckIn:C.Wait] Stopped
2020-01-24T20:15:59.314Z,1579896959.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T20:15:59.314Z,1579896959.314 [Default:CheckIn:D] Running Loop=1
2020-01-24T20:15:59.766Z,1579896959.766 [Default:CheckIn:D] Stopped
2020-01-24T20:15:59.766Z,1579896959.766 [Default:CheckIn:E] Running Loop=1
2020-01-24T20:16:00.143Z,1579896960.143 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.078353 min 
2020-01-24T20:16:00.143Z,1579896960.143 [Default:CheckIn:E] Stopped
2020-01-24T20:16:00.143Z,1579896960.143 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T20:16:00.143Z,1579896960.143 [Default:CheckIn] Stopped
2020-01-24T20:16:00.144Z,1579896960.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T20:16:00.144Z,1579896960.144 [Default:CheckIn](INFO): Running loop #19
2020-01-24T20:16:00.144Z,1579896960.144 [Default:CheckIn] Running Loop=19
2020-01-24T20:16:00.144Z,1579896960.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T20:16:00.144Z,1579896960.144 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T20:16:02.120Z,1579896962.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201601.00,A,3648.16471,N,12147.21457,W,0.058,0.98,240120,,,D*7C
2020-01-24T20:16:02.122Z,1579896962.122 [NAL9602](INFO): GPS fix at 20200124T201601: (36.802745, -121.786909)
2020-01-24T20:16:02.179Z,1579896962.179 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T20:16:02.179Z,1579896962.179 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T20:16:10.201Z,1579896970.201 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200124T181022/Courier0067.lzma
2020-01-24T20:16:12.207Z,1579896972.207 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0067.lzma.bak
2020-01-24T20:16:12.207Z,1579896972.207 [DataOverHttps](INFO): SBD MOMSN=12202331
2020-01-24T20:16:26.365Z,1579896986.365 [NAL9602](INFO): SBD MO Status=2, MOMSN=10183, MT Status=2, MTMSN=0
2020-01-24T20:16:26.366Z,1579896986.366 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T20:16:30.418Z,1579896990.418 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200124T181022/Express0068.lzma
2020-01-24T20:16:32.423Z,1579896992.423 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0068.lzma.bak
2020-01-24T20:16:32.424Z,1579896992.424 [DataOverHttps](INFO): SBD MOMSN=12202334
2020-01-24T20:16:33.664Z,1579896993.664 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T20:16:33.664Z,1579896993.664 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T20:16:33.664Z,1579896993.664 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T20:16:36.057Z,1579896996.057 [NAL9602](INFO): SBD MO Status=0, MOMSN=10183, MT Status=0, MTMSN=0
2020-01-24T20:16:36.057Z,1579896996.057 [NAL9602](INFO): No messages in MT queue
2020-01-24T20:16:58.107Z,1579897018.107 [CommandLine](IMPORTANT): got command burn on
2020-01-24T20:16:58.107Z,1579897018.107 [CommandLine](IMPORTANT): Activating dropweight wire
2020-01-24T20:16:59.086Z,1579897019.086 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-24T20:16:59.087Z,1579897019.087 [DropWeight] Hardware Fault, FailCount= 1
2020-01-24T20:16:59.087Z,1579897019.087 [DropWeight](ERROR): Hardware Fault
2020-01-24T20:16:59.191Z,1579897019.191 [CBIT](INFO): Critical error at 20200124T201659
2020-01-24T20:16:59.198Z,1579897019.198 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-24T20:16:59.198Z,1579897019.198 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-24T20:16:59.525Z,1579897019.525 [CBIT](INFO): Critical error at 20200124T201659
2020-01-24T20:17:00.765Z,1579897020.765 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T20:17:00.766Z,1579897020.766 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012413300485,35.0, -0.1,  8.9,  0
2020-01-24T20:17:01.670Z,1579897021.670 [CommandLine](IMPORTANT): got command burn off
2020-01-24T20:17:01.670Z,1579897021.670 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-01-24T20:17:06.763Z,1579897026.763 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T20:21:34.251Z,1579897294.251 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T20:21:34.251Z,1579897294.251 [Default:CheckIn:C.Wait] Stopped
2020-01-24T20:21:34.251Z,1579897294.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T20:21:34.251Z,1579897294.251 [Default:CheckIn:D] Running Loop=1
2020-01-24T20:21:34.666Z,1579897294.666 [Default:CheckIn:D] Stopped
2020-01-24T20:21:34.666Z,1579897294.666 [Default:CheckIn:E] Running Loop=1
2020-01-24T20:21:35.066Z,1579897295.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.660018 min 
2020-01-24T20:21:35.066Z,1579897295.066 [Default:CheckIn:E] Stopped
2020-01-24T20:21:35.066Z,1579897295.066 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T20:21:35.066Z,1579897295.066 [Default:CheckIn] Stopped
2020-01-24T20:21:35.066Z,1579897295.066 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T20:21:35.066Z,1579897295.066 [Default:CheckIn](INFO): Running loop #20
2020-01-24T20:21:35.066Z,1579897295.066 [Default:CheckIn] Running Loop=20
2020-01-24T20:21:35.067Z,1579897295.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T20:21:35.067Z,1579897295.067 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T20:21:37.053Z,1579897297.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202136.00,A,3648.16315,N,12147.20091,W,1.050,66.50,240120,,,D*4B
2020-01-24T20:21:37.055Z,1579897297.055 [NAL9602](INFO): GPS fix at 20200124T202136: (36.802719, -121.786682)
2020-01-24T20:21:37.125Z,1579897297.125 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T20:21:37.125Z,1579897297.125 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T20:21:45.077Z,1579897305.077 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20200124T181022/Courier0070.lzma
2020-01-24T20:21:47.087Z,1579897307.087 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0070.lzma.bak
2020-01-24T20:21:47.088Z,1579897307.088 [DataOverHttps](INFO): SBD MOMSN=12202339
2020-01-24T20:21:59.269Z,1579897319.269 [NAL9602](INFO): SBD MO Status=0, MOMSN=10184, MT Status=0, MTMSN=0
2020-01-24T20:21:59.269Z,1579897319.269 [NAL9602](INFO): No messages in MT queue
2020-01-24T20:22:04.429Z,1579897324.429 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20200124T181022/Express0071.lzma
2020-01-24T20:22:06.436Z,1579897326.436 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0071.lzma.bak
2020-01-24T20:22:06.436Z,1579897326.436 [DataOverHttps](INFO): SBD MOMSN=12202344
2020-01-24T20:22:07.818Z,1579897327.818 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T20:22:07.818Z,1579897327.818 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T20:22:07.818Z,1579897327.818 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T20:22:23.161Z,1579897343.161 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T20:22:23.161Z,1579897343.161 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200124133.0, -0.1,   0.0,1448.9,  0
2020-01-24T20:22:30.018Z,1579897350.018 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T20:24:23.162Z,1579897463.162 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T20:24:23.162Z,1579897463.162 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012413372685,35.0, -00,1448.9,  0
2020-01-24T20:24:40.125Z,1579897480.125 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-24T20:26:42.131Z,1579897602.131 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T20:26:42.132Z,1579897602.132 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2020-01-24T20:27:08.408Z,1579897628.408 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T20:27:08.408Z,1579897628.408 [Default:CheckIn:C.Wait] Stopped
2020-01-24T20:27:08.408Z,1579897628.408 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T20:27:08.408Z,1579897628.408 [Default:CheckIn:D] Running Loop=1
2020-01-24T20:27:08.822Z,1579897628.822 [Default:CheckIn:D] Stopped
2020-01-24T20:27:08.822Z,1579897628.822 [Default:CheckIn:E] Running Loop=1
2020-01-24T20:27:09.210Z,1579897629.210 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.229281 min 
2020-01-24T20:27:09.210Z,1579897629.210 [Default:CheckIn:E] Stopped
2020-01-24T20:27:09.211Z,1579897629.211 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T20:27:09.211Z,1579897629.211 [Default:CheckIn] Stopped
2020-01-24T20:27:09.211Z,1579897629.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T20:27:09.211Z,1579897629.211 [Default:CheckIn](INFO): Running loop #21
2020-01-24T20:27:09.211Z,1579897629.211 [Default:CheckIn] Running Loop=21
2020-01-24T20:27:09.211Z,1579897629.211 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T20:27:09.211Z,1579897629.211 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T20:27:11.213Z,1579897631.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202710.00,A,3648.42175,N,12147.36969,W,10.283,244.89,240120,,,D*4F
2020-01-24T20:27:11.215Z,1579897631.215 [NAL9602](INFO): GPS fix at 20200124T202710: (36.807029, -121.789495)
2020-01-24T20:27:11.240Z,1579897631.240 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T20:27:11.240Z,1579897631.240 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T20:27:19.445Z,1579897639.445 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200124T181022/Courier0073.lzma
2020-01-24T20:27:21.451Z,1579897641.451 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0073.lzma.bak
2020-01-24T20:27:21.452Z,1579897641.452 [DataOverHttps](INFO): SBD MOMSN=12202353
2020-01-24T20:27:38.822Z,1579897658.822 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200124T181022/Express0074.lzma
2020-01-24T20:27:39.488Z,1579897659.488 [NAL9602](INFO): SBD MO Status=2, MOMSN=10185, MT Status=2, MTMSN=0
2020-01-24T20:27:39.488Z,1579897659.488 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-24T20:27:40.827Z,1579897660.827 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0074.lzma.bak
2020-01-24T20:27:40.828Z,1579897660.828 [DataOverHttps](INFO): SBD MOMSN=12202356
2020-01-24T20:27:41.947Z,1579897661.947 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T20:27:41.947Z,1579897661.947 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T20:27:41.947Z,1579897661.947 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T20:28:07.361Z,1579897687.361 [NAL9602](INFO): SBD MO Status=0, MOMSN=10185, MT Status=0, MTMSN=0
2020-01-24T20:28:07.361Z,1579897687.361 [NAL9602](INFO): No messages in MT queue
2020-01-24T20:28:38.059Z,1579897718.059 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T20:31:33.882Z,1579897893.882 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-24T20:31:33.885Z,1579897893.885 [BPC1](INFO): Received data from all battery sticks.
2020-01-24T20:31:59.300Z,1579897919.300 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-24T20:31:59.300Z,1579897919.300 [DropWeight] No Fault, FailCount= 1
2020-01-24T20:32:42.562Z,1579897962.562 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-24T20:32:42.562Z,1579897962.562 [Default:CheckIn:C.Wait] Stopped
2020-01-24T20:32:42.562Z,1579897962.562 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-24T20:32:42.562Z,1579897962.562 [Default:CheckIn:D] Running Loop=1
2020-01-24T20:32:42.950Z,1579897962.950 [Default:CheckIn:D] Stopped
2020-01-24T20:32:42.950Z,1579897962.950 [Default:CheckIn:E] Running Loop=1
2020-01-24T20:32:43.357Z,1579897963.357 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.798079 min 
2020-01-24T20:32:43.357Z,1579897963.357 [Default:CheckIn:E] Stopped
2020-01-24T20:32:43.357Z,1579897963.357 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-24T20:32:43.357Z,1579897963.357 [Default:CheckIn] Stopped
2020-01-24T20:32:43.357Z,1579897963.357 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-24T20:32:43.357Z,1579897963.357 [Default:CheckIn](INFO): Running loop #22
2020-01-24T20:32:43.357Z,1579897963.357 [Default:CheckIn] Running Loop=22
2020-01-24T20:32:43.358Z,1579897963.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-24T20:32:43.358Z,1579897963.358 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-24T20:32:45.341Z,1579897965.341 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203244.00,A,3648.62800,N,12148.71894,W,0.797,278.13,240120,,,D*71
2020-01-24T20:32:45.343Z,1579897965.343 [NAL9602](INFO): GPS fix at 20200124T203244: (36.810467, -121.811982)
2020-01-24T20:32:45.377Z,1579897965.377 [Default:CheckIn:Read_GPS] Stopped
2020-01-24T20:32:45.377Z,1579897965.377 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-24T20:32:53.506Z,1579897973.506 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200124T181022/Courier0076.lzma
2020-01-24T20:32:55.511Z,1579897975.511 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Courier0076.lzma.bak
2020-01-24T20:32:55.512Z,1579897975.512 [DataOverHttps](INFO): SBD MOMSN=12202361
2020-01-24T20:33:12.938Z,1579897992.938 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200124T181022/Express0077.lzma
2020-01-24T20:33:14.946Z,1579897994.946 [DataOverHttps](INFO): Moved sent file to Logs/20200124T181022/Express0077.lzma.bak
2020-01-24T20:33:14.946Z,1579897994.946 [DataOverHttps](INFO): SBD MOMSN=12202363
2020-01-24T20:33:16.165Z,1579897996.165 [Default:CheckIn:Read_Iridium] Stopped
2020-01-24T20:33:16.166Z,1579897996.166 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-24T20:33:16.166Z,1579897996.166 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-24T20:33:18.546Z,1579897998.546 [NAL9602](INFO): SBD MO Status=0, MOMSN=10186, MT Status=0, MTMSN=0
2020-01-24T20:33:18.546Z,1579897998.546 [NAL9602](INFO): No messages in MT queue
2020-01-24T20:33:49.254Z,1579898029.254 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-24T20:33:58.978Z,1579898038.978 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-24T20:33:58.978Z,1579898038.978 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768        +0.00,   0.00,4998.89
2020-01-24T20:34:48.262Z,1579898088.262 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T20:34:48.262Z,1579898088.262 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012413475185,35.   0.0,1448.9,  0
2020-01-24T20:35:03.205Z,1579898103.205 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-24T20:35:03.206Z,1579898103.206 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20012413480685,35,   0.0,1448.9,  0
2020-01-24T20:35:27.880Z,1579898127.880 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-01-24T20:35:27.881Z,1579898127.881 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -79,    +,A
2020-01-24T20:36:11.093Z,1579898171.093 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-24T20:36:58.763Z,1579898218.763 [DataOverHttps](IMPORTANT): SBD MTMSN=20200124T203656
2020-01-24T20:37:06.433Z,1579898226.433 [DataOverHttps](INFO): Received command:restart logs