2020-06-02T15:51:11.940Z,1591113071.940 [Supervisor](DEBUG): Initializing supervisor.
2020-06-02T15:51:11.943Z,1591113071.943 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-06-02T15:51:11.943Z,1591113071.943 [SyncHandler](INFO): Protected caller Thread ID is 9401
2020-06-02T15:51:11.944Z,1591113071.944 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-06-02T15:51:11.945Z,1591113071.945 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-06-02T15:51:11.945Z,1591113071.945 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9402
2020-06-02T15:51:11.948Z,1591113071.948 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-06-02T15:51:11.960Z,1591113071.960 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-06-02T15:51:11.961Z,1591113071.961 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-06-02T15:51:11.962Z,1591113071.962 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9403
2020-06-02T15:51:11.962Z,1591113071.963 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-06-02T15:51:11.963Z,1591113071.963 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-06-02T15:51:11.964Z,1591113071.964 [logger ThreadHandler](INFO): Protected caller Thread ID is 9404
2020-06-02T15:51:11.966Z,1591113071.966 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-06-02T15:51:11.966Z,1591113071.966 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-06-02T15:51:11.967Z,1591113071.967 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-06-02T15:51:12.388Z,1591113072.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-06-02T15:51:12.389Z,1591113072.389 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-06-02T15:51:12.484Z,1591113072.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-06-02T15:51:12.485Z,1591113072.485 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-06-02T15:51:12.796Z,1591113072.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-06-02T15:51:12.797Z,1591113072.797 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-06-02T15:51:12.934Z,1591113072.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-06-02T15:51:12.935Z,1591113072.935 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-06-02T15:51:13.121Z,1591113073.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-06-02T15:51:13.121Z,1591113073.121 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-06-02T15:51:13.560Z,1591113073.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-06-02T15:51:13.561Z,1591113073.561 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-06-02T15:51:13.770Z,1591113073.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-06-02T15:51:13.771Z,1591113073.771 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-06-02T15:51:13.914Z,1591113073.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-06-02T15:51:13.915Z,1591113073.915 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-06-02T15:51:14.108Z,1591113074.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-06-02T15:51:14.108Z,1591113074.108 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-06-02T15:51:14.203Z,1591113074.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-06-02T15:51:14.204Z,1591113074.204 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-06-02T15:51:14.399Z,1591113074.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-06-02T15:51:14.399Z,1591113074.399 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-06-02T15:51:14.697Z,1591113074.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-06-02T15:51:14.697Z,1591113074.697 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-06-02T15:51:14.776Z,1591113074.776 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-06-02T15:51:14.878Z,1591113074.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-06-02T15:51:14.879Z,1591113074.879 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-06-02T15:51:15.516Z,1591113075.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-06-02T15:51:15.516Z,1591113075.516 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-06-02T15:51:15.935Z,1591113075.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-06-02T15:51:15.937Z,1591113075.937 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-06-02T15:51:15.938Z,1591113075.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-06-02T15:51:16.141Z,1591113076.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-06-02T15:51:16.239Z,1591113076.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-06-02T15:51:16.336Z,1591113076.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-06-02T15:51:16.568Z,1591113076.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-06-02T15:51:16.568Z,1591113076.568 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-06-02T15:51:16.860Z,1591113076.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-06-02T15:51:17.050Z,1591113077.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-06-02T15:51:17.329Z,1591113077.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-06-02T15:51:17.467Z,1591113077.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Dock.cfg
2020-06-02T15:51:17.551Z,1591113077.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-06-02T15:51:17.661Z,1591113077.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-06-02T15:51:17.753Z,1591113077.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-06-02T15:51:17.933Z,1591113077.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-06-02T15:51:18.063Z,1591113078.063 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-06-02T15:51:18.063Z,1591113078.063 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-06-02T15:51:18.066Z,1591113078.066 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-06-02T15:51:18.369Z,1591113078.369 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-06-02T15:51:18.375Z,1591113078.375 [AHRS_M2](INFO): created writer for : platform_orientation
2020-06-02T15:51:18.377Z,1591113078.377 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-06-02T15:51:18.382Z,1591113078.382 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-06-02T15:51:18.382Z,1591113078.382 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-06-02T15:51:18.387Z,1591113078.387 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-06-02T15:51:18.388Z,1591113078.388 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-06-02T15:51:18.393Z,1591113078.393 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-06-02T15:51:18.461Z,1591113078.461 [AHRS_M2] Loaded
2020-06-02T15:51:18.462Z,1591113078.462 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-06-02T15:51:18.538Z,1591113078.538 [DataOverHttps] Loaded
2020-06-02T15:51:18.539Z,1591113078.539 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-06-02T15:51:18.540Z,1591113078.540 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407734E0
2020-06-02T15:51:18.540Z,1591113078.540 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9492
2020-06-02T15:51:18.563Z,1591113078.563 [DDM] Loaded
2020-06-02T15:51:18.563Z,1591113078.563 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-06-02T15:51:18.576Z,1591113078.576 [Depth_Keller] Loaded
2020-06-02T15:51:18.576Z,1591113078.576 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-06-02T15:51:18.580Z,1591113078.580 [DropWeight] Loaded
2020-06-02T15:51:18.581Z,1591113078.581 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-06-02T15:51:18.639Z,1591113078.639 [DUSBL_Hydroid] Loaded
2020-06-02T15:51:18.640Z,1591113078.640 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-06-02T15:51:18.684Z,1591113078.684 [Micromodem] Loaded
2020-06-02T15:51:18.685Z,1591113078.685 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-06-02T15:51:18.778Z,1591113078.778 [NAL9602] Loaded
2020-06-02T15:51:18.779Z,1591113078.779 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-06-02T15:51:18.816Z,1591113078.816 [Onboard] Loaded
2020-06-02T15:51:18.816Z,1591113078.816 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-06-02T15:51:18.823Z,1591113078.823 [PowerOnly] Loaded
2020-06-02T15:51:18.823Z,1591113078.823 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-06-02T15:51:18.829Z,1591113078.829 [Radio_Surface] Loaded
2020-06-02T15:51:18.829Z,1591113078.829 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-06-02T15:51:18.830Z,1591113078.830 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A34E0
2020-06-02T15:51:18.831Z,1591113078.831 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9493
2020-06-02T15:51:18.872Z,1591113078.872 [RDI_Pathfinder] Loaded
2020-06-02T15:51:18.873Z,1591113078.873 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-06-02T15:51:18.909Z,1591113078.909 [RDI_PathfinderUp] Loaded
2020-06-02T15:51:18.909Z,1591113078.909 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2020-06-02T15:51:20.348Z,1591113080.348 [BPC1] Loaded
2020-06-02T15:51:20.348Z,1591113080.348 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-06-02T15:51:20.348Z,1591113080.348 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-06-02T15:51:20.349Z,1591113080.349 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-06-02T15:51:20.367Z,1591113080.367 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-06-02T15:51:20.368Z,1591113080.368 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-06-02T15:51:20.483Z,1591113080.483 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-06-02T15:51:20.484Z,1591113080.484 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-06-02T15:51:20.503Z,1591113080.503 [NavChart] Loaded
2020-06-02T15:51:20.503Z,1591113080.503 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-06-02T15:51:20.507Z,1591113080.507 [UniversalFixResidualReporter] Loaded
2020-06-02T15:51:20.507Z,1591113080.507 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-06-02T15:51:20.525Z,1591113080.525 [WorkSite] Loaded
2020-06-02T15:51:20.525Z,1591113080.525 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2020-06-02T15:51:20.525Z,1591113080.525 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-06-02T15:51:20.526Z,1591113080.526 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-06-02T15:51:20.601Z,1591113080.601 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-06-02T15:51:20.601Z,1591113080.601 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-06-02T15:51:20.706Z,1591113080.706 [BuoyancyServo] Loaded
2020-06-02T15:51:20.706Z,1591113080.706 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-06-02T15:51:20.721Z,1591113080.721 [ElevatorServo] Loaded
2020-06-02T15:51:20.721Z,1591113080.721 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-06-02T15:51:20.735Z,1591113080.735 [MassServo] Loaded
2020-06-02T15:51:20.735Z,1591113080.735 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-06-02T15:51:20.750Z,1591113080.750 [RudderServo] Loaded
2020-06-02T15:51:20.750Z,1591113080.750 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-06-02T15:51:20.764Z,1591113080.764 [ThrusterServo] Loaded
2020-06-02T15:51:20.764Z,1591113080.764 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-06-02T15:51:20.765Z,1591113080.765 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-06-02T15:51:20.765Z,1591113080.765 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-06-02T15:51:20.953Z,1591113080.953 [PAR_Licor] Loaded
2020-06-02T15:51:20.954Z,1591113080.954 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-06-02T15:51:20.996Z,1591113080.996 [WetLabsSeaOWL_UV_A] Loaded
2020-06-02T15:51:20.996Z,1591113080.996 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-06-02T15:51:20.997Z,1591113080.997 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409274E0
2020-06-02T15:51:20.998Z,1591113080.998 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 9494
2020-06-02T15:51:20.999Z,1591113080.999 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-06-02T15:51:20.999Z,1591113080.999 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-06-02T15:51:21.277Z,1591113081.277 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-06-02T15:51:21.278Z,1591113081.278 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-06-02T15:51:21.317Z,1591113081.317 [DepthRateCalculator] Loaded
2020-06-02T15:51:21.317Z,1591113081.317 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-06-02T15:51:21.323Z,1591113081.323 [PitchRateCalculator] Loaded
2020-06-02T15:51:21.323Z,1591113081.323 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-06-02T15:51:21.335Z,1591113081.335 [SpeedCalculator] Loaded
2020-06-02T15:51:21.335Z,1591113081.335 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-06-02T15:51:21.355Z,1591113081.355 [TempGradientCalculator] Loaded
2020-06-02T15:51:21.356Z,1591113081.356 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-06-02T15:51:21.361Z,1591113081.361 [YawRateCalculator] Loaded
2020-06-02T15:51:21.361Z,1591113081.361 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-06-02T15:51:21.399Z,1591113081.399 [ElevatorOffsetCalculator] Loaded
2020-06-02T15:51:21.400Z,1591113081.400 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-06-02T15:51:21.400Z,1591113081.400 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-06-02T15:51:21.401Z,1591113081.401 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-06-02T15:51:21.531Z,1591113081.531 [SBIT](DEBUG): Construct Startup Built In Test.
2020-06-02T15:51:21.549Z,1591113081.549 [SBIT] Loaded
2020-06-02T15:51:21.549Z,1591113081.549 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-06-02T15:51:21.550Z,1591113081.550 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-06-02T15:51:21.562Z,1591113081.562 [IBIT] Loaded
2020-06-02T15:51:21.562Z,1591113081.562 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-06-02T15:51:21.565Z,1591113081.565 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-06-02T15:51:21.700Z,1591113081.700 [CBIT] Loaded
2020-06-02T15:51:21.700Z,1591113081.700 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-06-02T15:51:21.700Z,1591113081.700 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-06-02T15:51:21.701Z,1591113081.701 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-06-02T15:51:21.970Z,1591113081.970 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-06-02T15:51:21.971Z,1591113081.971 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-06-02T15:51:22.146Z,1591113082.146 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-06-02T15:51:22.147Z,1591113082.147 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-06-02T15:51:22.243Z,1591113082.243 [VerticalControl](DEBUG): Construct VerticalControl.
2020-06-02T15:51:22.323Z,1591113082.323 [VerticalControl] Loaded
2020-06-02T15:51:22.323Z,1591113082.323 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-06-02T15:51:22.324Z,1591113082.324 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-06-02T15:51:22.392Z,1591113082.392 [HorizontalControl] Loaded
2020-06-02T15:51:22.392Z,1591113082.392 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-06-02T15:51:22.393Z,1591113082.393 [SpeedControl](DEBUG): Construct SpeedControl.
2020-06-02T15:51:22.394Z,1591113082.394 [SpeedControl] Loaded
2020-06-02T15:51:22.395Z,1591113082.395 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-06-02T15:51:22.395Z,1591113082.395 [LoopControl](DEBUG): Construct LoopControl.
2020-06-02T15:51:22.396Z,1591113082.396 [LoopControl] Loaded
2020-06-02T15:51:22.396Z,1591113082.396 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-06-02T15:51:22.396Z,1591113082.396 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-06-02T15:51:22.397Z,1591113082.397 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-06-02T15:51:22.423Z,1591113082.423 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-06-02T15:51:22.427Z,1591113082.427 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-06-02T15:51:22.427Z,1591113082.427 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-06-02T15:51:22.434Z,1591113082.434 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-06-02T15:51:22.435Z,1591113082.435 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0
2020-06-02T15:51:22.436Z,1591113082.436 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9495
2020-06-02T15:51:22.440Z,1591113082.440 [Supervisor](INFO): Main Thread ID is 9112
2020-06-02T15:51:22.440Z,1591113082.440 [Supervisor](DEBUG): Running supervisor.
2020-06-02T15:51:22.441Z,1591113082.441 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9496
2020-06-02T15:51:22.444Z,1591113082.444 [controlThread ThreadHandler](INFO): Handler Thread ID is 9497
2020-06-02T15:51:22.444Z,1591113082.444 [controlThread](DEBUG): Initializing ControlThread
2020-06-02T15:51:22.450Z,1591113082.450 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-06-02T15:51:22.450Z,1591113082.450 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-06-02T15:51:22.450Z,1591113082.450 [WorkSite](DEBUG): Initializing WorkSite component.
2020-06-02T15:51:22.455Z,1591113082.455 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-06-02T15:51:22.455Z,1591113082.455 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-06-02T15:51:22.455Z,1591113082.455 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-06-02T15:51:22.456Z,1591113082.456 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-06-02T15:51:22.456Z,1591113082.456 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-06-02T15:51:22.457Z,1591113082.457 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-06-02T15:51:22.457Z,1591113082.457 [SBIT](INFO): Initialize SBIT Component.
2020-06-02T15:51:22.458Z,1591113082.458 [SBIT](IMPORTANT): git: 2020-06-01
2020-06-02T15:51:22.458Z,1591113082.458 [SBIT](INFO): git hash: e027a2dac774308c41d8b30ec58d773e2978dc9a
2020-06-02T15:51:22.458Z,1591113082.458 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-06-02T15:51:22.460Z,1591113082.460 [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-06-02T15:51:22.462Z,1591113082.462 [SBIT](INFO): Beginning SBIT in 31.000000 seconds.
2020-06-02T15:51:22.462Z,1591113082.462 [IBIT](INFO): Initialize IBIT Component.
2020-06-02T15:51:22.463Z,1591113082.463 [CBIT](DEBUG): Initialize CBIT Component.
2020-06-02T15:51:22.464Z,1591113082.464 [logger ThreadHandler](INFO): Handler Thread ID is 9498
2020-06-02T15:51:22.474Z,1591113082.474 [CBIT](DEBUG): Initialized mux pins.
2020-06-02T15:51:22.474Z,1591113082.474 [CBIT](DEBUG): Initializing the watchdog timer.
2020-06-02T15:51:22.483Z,1591113082.483 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9499
2020-06-02T15:51:22.484Z,1591113082.484 [DataOverHttps](DEBUG): dashIP=128.128.181.155 starts with a digit so assuming it is a numeric IP
2020-06-02T15:51:22.495Z,1591113082.495 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9500
2020-06-02T15:51:22.498Z,1591113082.498 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-06-02T15:51:22.498Z,1591113082.498 [CBIT](DEBUG): Initializing heartbeat.
2020-06-02T15:51:22.507Z,1591113082.507 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 9501
2020-06-02T15:51:22.507Z,1591113082.507 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-06-02T15:51:22.535Z,1591113082.535 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9502
2020-06-02T15:51:22.538Z,1591113082.538 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-06-02T15:51:22.538Z,1591113082.538 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-06-02T15:51:22.538Z,1591113082.538 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-06-02T15:51:22.538Z,1591113082.538 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-06-02T15:51:22.539Z,1591113082.539 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-06-02T15:51:22.539Z,1591113082.539 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-06-02T15:51:22.539Z,1591113082.539 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-06-02T15:51:22.539Z,1591113082.539 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-06-02T15:51:22.539Z,1591113082.539 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-06-02T15:51:22.539Z,1591113082.539 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-06-02T15:51:22.540Z,1591113082.540 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-06-02T15:51:22.540Z,1591113082.540 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-06-02T15:51:22.540Z,1591113082.540 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-06-02T15:51:22.540Z,1591113082.540 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-06-02T15:51:22.540Z,1591113082.540 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-06-02T15:51:22.540Z,1591113082.540 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-06-02T15:51:22.570Z,1591113082.570 [CBIT](DEBUG): Deactivating GF circuits.
2020-06-02T15:51:22.570Z,1591113082.570 [CBIT](DEBUG): Deactivating emergency mode.
2020-06-02T15:51:22.606Z,1591113082.606 [CBIT](DEBUG): Backplane powered.
2020-06-02T15:51:22.607Z,1591113082.607 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-06-02T15:51:22.608Z,1591113082.608 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-06-02T15:51:22.609Z,1591113082.609 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-06-02T15:51:22.609Z,1591113082.609 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-06-02T15:51:22.610Z,1591113082.610 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-06-02T15:51:22.620Z,1591113082.620 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-02T15:51:22.643Z,1591113082.643 [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-06-02T15:51:22.644Z,1591113082.644 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2020-06-02T15:51:22.721Z,1591113082.721 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2020-06-02T15:51:22.736Z,1591113082.736 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2020-06-02T15:51:22.740Z,1591113082.740 [MissionManager](INFO): DefineArg DefaultWithUndock.MicromodemCommsInterval = 2.000000 min
2020-06-02T15:51:22.741Z,1591113082.741 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2020-06-02T15:51:22.775Z,1591113082.775 [DefaultWithUndock:B.Undock](DEBUG): Construct.
2020-06-02T15:51:22.775Z,1591113082.775 [DefaultWithUndock:B.Undock](DEBUG): Initializing internal variables to default values.
2020-06-02T15:51:22.807Z,1591113082.807 [DefaultWithUndock:C.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-02T15:51:22.861Z,1591113082.861 [DefaultWithUndock:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-06-02T15:51:22.863Z,1591113082.863 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-06-02T15:51:22.881Z,1591113082.881 [DefaultWithUndock:F.Execute](DEBUG): Construct Execute.
2020-06-02T15:51:22.885Z,1591113082.885 [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="DefaultWithUndock">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to reach the approach depth after detaching form the dock.
        The vehicle will switch over to midcourse guidance after the timeout is expired.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="MicromodemCommsInterval"><Description>
        How often to trigger Umodem communications
    </Description><Units:minute/><Value>2</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>30</Value></Setting>
    </Guidance:Wait>

    <!-- Leave the dock if necessary --> 
    <Guidance:Undock>
        <Sequence/>
        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>
    </Guidance:Undock>

    <!-- This mission assumes open water so from here on it's the same old default that's always used -->
    <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-06-02T15:51:22.898Z,1591113082.898 [Radio_Surface](INFO): Powering up
2020-06-02T15:51:22.901Z,1591113082.901 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-06-02T15:51:22.918Z,1591113082.918 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-06-02T15:51:22.955Z,1591113082.955 [DDM](INFO): Powering up
2020-06-02T15:51:22.955Z,1591113082.955 [DDM](DEBUG): Initializing DDM.
2020-06-02T15:51:22.979Z,1591113082.979 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2020-06-02T15:51:22.980Z,1591113082.980 [DUSBL_Hydroid](INFO): Powering up
2020-06-02T15:51:22.980Z,1591113082.980 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-06-02T15:51:23.152Z,1591113083.152 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-06-02T15:51:23.176Z,1591113083.176 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-06-02T15:51:23.183Z,1591113083.183 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-06-02T15:51:23.184Z,1591113083.184 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-06-02T15:51:23.191Z,1591113083.191 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-06-02T15:51:23.191Z,1591113083.191 [MassServo](DEBUG): Initializing EZServoServo.
2020-06-02T15:51:23.199Z,1591113083.199 [MassServo](DEBUG): Initializing MassServo.
2020-06-02T15:51:23.199Z,1591113083.199 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-02T15:51:23.207Z,1591113083.207 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-02T15:51:23.207Z,1591113083.207 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-06-02T15:51:23.215Z,1591113083.215 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-06-02T15:51:23.329Z,1591113083.329 [Micromodem](INFO): Powering up
2020-06-02T15:51:23.329Z,1591113083.329 [Micromodem](DEBUG): Initializing Micromodem.
2020-06-02T15:51:23.406Z,1591113083.406 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-06-02T15:51:24.086Z,1591113084.086 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-06-02T15:51:24.086Z,1591113084.086 [RudderServo](FAULT): Rudder failed to initialize
2020-06-02T15:51:24.086Z,1591113084.086 [RudderServo] Communications Fault, FailCount= 1
2020-06-02T15:51:24.087Z,1591113084.087 [RudderServo](ERROR): Communications Fault
2020-06-02T15:51:24.198Z,1591113084.198 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-06-02T15:51:24.399Z,1591113084.399 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-02T15:51:24.399Z,1591113084.399 [RudderServo](INFO): Powering down
2020-06-02T15:51:25.073Z,1591113085.073 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-02T15:51:25.193Z,1591113085.193 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-02T15:51:25.197Z,1591113085.197 [CBIT](INFO): Clearing failed state for component RudderServo
2020-06-02T15:51:25.197Z,1591113085.197 [RudderServo] No Fault, FailCount= 1
2020-06-02T15:51:28.291Z,1591113088.291 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-06-02T15:51:28.690Z,1591113088.690 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,15128,INIT,2.0.32731*49
 expected 7C
2020-06-02T15:51:28.690Z,1591113088.690 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-06-02T15:51:30.309Z,1591113090.309 [Micromodem](INFO): Nmea in: $CATMG,2020-06-02T15:51:28.708255Z,RTC,RTC*53

2020-06-02T15:51:30.309Z,1591113090.309 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-06-02T15:51:28.708255Z,RTC,RTC*53

2020-06-02T15:51:30.712Z,1591113090.712 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-06-02T15:51:30.713Z,1591113090.713 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-06-02T15:51:31.113Z,1591113091.113 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-06-02T15:51:31.113Z,1591113091.113 [Micromodem](ERROR): CFG response from modem unexpected: $CACFG,ALL,0*31

2020-06-02T15:51:31.539Z,1591113091.539 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-06-02T15:51:31.539Z,1591113091.539 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-06-02T15:51:31.902Z,1591113091.902 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-06-02T15:51:31.902Z,1591113091.902 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-06-02T15:51:32.309Z,1591113092.309 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-06-02T15:51:32.309Z,1591113092.309 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-06-02T15:51:32.705Z,1591113092.705 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-06-02T15:51:32.705Z,1591113092.705 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2020-06-02T15:51:33.104Z,1591113093.104 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2020-06-02T15:51:33.105Z,1591113093.105 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-06-02T15:51:33.521Z,1591113093.521 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-06-02T15:51:33.521Z,1591113093.521 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-06-02T15:51:33.912Z,1591113093.912 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-06-02T15:51:33.913Z,1591113093.913 [Micromodem](INFO): Nmea out: $CCCLK,2020,06,02,15,51,34*47

2020-06-02T15:51:34.313Z,1591113094.313 [Micromodem](INFO): Nmea in: $CACLK,2020,6,2,15,51,34*45

2020-06-02T15:51:34.725Z,1591113094.725 [Micromodem](INFO): Nmea in: $CATMS,0,2020-06-02T15:51:35Z*74

2020-06-02T15:51:34.726Z,1591113094.726 [Micromodem](INFO): Nmea in: $CATMG,2020-06-02T15:51:35.026115Z,USER_CMD,RTC*12

2020-06-02T15:51:38.763Z,1591113098.763 [DDM](INFO): Dynamic Docking Module:
2020-06-02T15:51:41.204Z,1591113101.204 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-06-02T15:51:48.869Z,1591113108.869 [NAL9602](INFO): Powering up NAL9602
2020-06-02T15:51:53.426Z,1591113113.426 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout
2020-06-02T15:51:53.426Z,1591113113.426 [RDI_PathfinderUp] Communications Fault, FailCount= 1
2020-06-02T15:51:53.426Z,1591113113.426 [RDI_PathfinderUp](ERROR): Communications Fault
2020-06-02T15:51:53.452Z,1591113113.452 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp
2020-06-02T15:51:53.814Z,1591113113.814 [RDI_PathfinderUp](INFO): Powering down
2020-06-02T15:51:54.173Z,1591113114.173 [SBIT](IMPORTANT): Beginning Startup BIT
2020-06-02T15:51:54.181Z,1591113114.181 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-02T15:51:54.672Z,1591113114.672 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp
2020-06-02T15:51:54.672Z,1591113114.672 [RDI_PathfinderUp] No Fault, FailCount= 1
2020-06-02T15:51:59.997Z,1591113119.997 [NAL9602](INFO): NAL9602 initialized
2020-06-02T15:52:01.261Z,1591113121.261 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T15:52:04.931Z,1591113124.931 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009692
CHAN A1 (24V): -0.028441
CHAN A2 (12V): -0.006821
CHAN A3 (5V): -0.002099
CHAN B0 (3.3V): -0.000168
CHAN B1 (3.15aV): 0.000323
CHAN B2 (3.15bV): 0.000502
CHAN B3 (GND): 0.002348
OPEN: 0.005236
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-02T15:52:47.686Z,1591113167.686 [SBIT](IMPORTANT): SBIT PASSED
2020-06-02T15:52:47.686Z,1591113167.686 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-06-02T15:52:47.688Z,1591113167.688 [SBIT](IMPORTANT): DDM.verbosity=1 count;

2020-06-02T15:52:48.108Z,1591113168.108 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2020-06-02T15:52:48.111Z,1591113168.111 [WorkSite](IMPORTANT): WorkSite fix at 20200602T155020: (41.572839, -70.737508)
2020-06-02T15:52:48.112Z,1591113168.112 [MissionManager](IMPORTANT): Started mission Startup
2020-06-02T15:52:48.113Z,1591113168.113 [Startup] Running Loop=1
2020-06-02T15:52:48.113Z,1591113168.113 [Startup](DEBUG): Aggregate::initialize Startup
2020-06-02T15:52:48.113Z,1591113168.113 [Startup:A.GoToSurface] Running Loop=1
2020-06-02T15:52:48.113Z,1591113168.113 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-02T15:52:48.114Z,1591113168.114 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-02T15:52:48.114Z,1591113168.114 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-02T15:52:48.114Z,1591113168.114 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-02T15:52:48.115Z,1591113168.115 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-02T15:52:48.115Z,1591113168.115 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-02T15:52:48.117Z,1591113168.117 [Startup:StartupSatComms] Running Loop=1
2020-06-02T15:52:48.117Z,1591113168.117 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-06-02T15:52:48.117Z,1591113168.117 [Startup:StartupSatComms:A] Running Loop=1
2020-06-02T15:52:48.516Z,1591113168.516 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-06-02T15:52:58.571Z,1591113178.571 [NAL9602](INFO): SBD MO Status=0, MOMSN=11248, MT Status=0, MTMSN=0
2020-06-02T15:52:58.571Z,1591113178.571 [NAL9602](INFO): No messages in MT queue
2020-06-02T15:53:00.236Z,1591113180.236 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-06-02T15:53:02.229Z,1591113182.229 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T15:53:15.958Z,1591113195.958 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T15:53:48.292Z,1591113228.292 [Startup:StartupSatComms:A](INFO): Timed out from 2020-06-02T15:52:48.1Z
2020-06-02T15:53:48.293Z,1591113228.293 [Startup:StartupSatComms:A] Stopped
2020-06-02T15:53:48.293Z,1591113228.293 [Startup:StartupSatComms:B] Running Loop=1
2020-06-02T15:53:48.695Z,1591113228.695 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-02T15:54:19.363Z,1591113259.363 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T15:54:48.489Z,1591113288.489 [Startup:StartupSatComms:B](INFO): Timed out from 2020-06-02T15:53:48.3Z
2020-06-02T15:54:48.489Z,1591113288.489 [Startup:StartupSatComms:B] Stopped
2020-06-02T15:54:48.489Z,1591113288.489 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-06-02T15:54:48.489Z,1591113288.489 [Startup:StartupSatComms] Stopped
2020-06-02T15:54:48.489Z,1591113288.489 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-06-02T15:54:48.492Z,1591113288.492 [Startup](INFO): Completed Startup
2020-06-02T15:54:48.492Z,1591113288.492 [MissionManager](INFO): Startup is completed.
2020-06-02T15:54:48.492Z,1591113288.492 [MissionManager](INFO): Uninitializing Mission Startup
2020-06-02T15:54:48.492Z,1591113288.492 [Startup] Stopped
2020-06-02T15:54:48.493Z,1591113288.493 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-06-02T15:54:48.493Z,1591113288.493 [Startup:A.GoToSurface] Stopped
2020-06-02T15:54:48.493Z,1591113288.493 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-02T15:54:48.902Z,1591113288.902 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-06-02T15:54:48.902Z,1591113288.902 [DefaultWithUndock] Running Loop=1
2020-06-02T15:54:48.902Z,1591113288.902 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-06-02T15:54:48.902Z,1591113288.902 [DefaultWithUndock:C.GoToSurface] Running Loop=1
2020-06-02T15:54:48.903Z,1591113288.903 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-02T15:54:48.903Z,1591113288.903 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-02T15:54:48.903Z,1591113288.903 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-02T15:54:48.903Z,1591113288.903 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-02T15:54:48.904Z,1591113288.904 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-02T15:54:48.904Z,1591113288.904 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-02T15:54:48.904Z,1591113288.904 [DefaultWithUndock:A.Wait] Running Loop=1
2020-06-02T15:54:48.904Z,1591113288.904 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-06-02T15:54:57.400Z,1591113297.400 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004432
2020-06-02T15:55:01.851Z,1591113301.851 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T15:55:19.231Z,1591113319.231 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-06-02T15:55:19.231Z,1591113319.231 [DefaultWithUndock:A.Wait] Stopped
2020-06-02T15:55:19.232Z,1591113319.232 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T15:55:19.232Z,1591113319.232 [DefaultWithUndock:B.Undock] Running Loop=1
2020-06-02T15:55:19.232Z,1591113319.232 [DefaultWithUndock:B.Undock](DEBUG): Initialize.
2020-06-02T15:55:19.232Z,1591113319.232 [DefaultWithUndock:B.Undock](DEBUG): Initializing internal variables to default values.
2020-06-02T15:55:19.978Z,1591113319.978 [PowerOnly](INFO): Powering up.
2020-06-02T15:55:20.768Z,1591113320.768 [DDM](INFO): Detaching. Opening Latch
2020-06-02T15:55:21.172Z,1591113321.172 [DDM](INFO): Detaching. Opening Latch
2020-06-02T15:55:21.576Z,1591113321.576 [DDM](INFO): Detaching. Opening Latch
2020-06-02T15:55:21.985Z,1591113321.985 [DDM](INFO): Detaching. Opening Latch
2020-06-02T15:55:23.279Z,1591113323.279 [DefaultWithUndock:B.Undock](INFO): Detaching from dock.
2020-06-02T15:55:23.672Z,1591113323.672 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer.
2020-06-02T15:55:36.203Z,1591113336.203 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T15:55:43.917Z,1591113343.917 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-06-02T15:55:44.605Z,1591113344.605 [DDM](INFO): Closing Latch
2020-06-02T15:55:45.025Z,1591113345.025 [DDM](INFO): Closing Latch
2020-06-02T15:55:45.412Z,1591113345.412 [DDM](INFO): Closing Latch
2020-06-02T15:55:45.816Z,1591113345.816 [DDM](INFO): Closing Latch
2020-06-02T15:55:47.120Z,1591113347.120 [DefaultWithUndock:B.Undock](INFO): DDM at standby.
2020-06-02T15:55:47.519Z,1591113347.519 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-06-02T15:55:47.520Z,1591113347.520 [DefaultWithUndock:B.Undock] Stopped
2020-06-02T15:55:47.904Z,1591113347.904 [DefaultWithUndock:CheckIn] Running Loop=1
2020-06-02T15:55:47.904Z,1591113347.904 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T15:55:47.905Z,1591113347.905 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T15:55:48.320Z,1591113348.320 [DefaultWithUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-06-02T15:55:49.863Z,1591113349.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155549.00,A,4131.46651,N,07040.27348,W,0.330,0.00,020620,,,A*7D
2020-06-02T15:55:49.867Z,1591113349.867 [NAL9602](INFO): GPS fix at 20200602T155549: (41.524442, -70.671225)
2020-06-02T15:55:49.904Z,1591113349.904 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T15:55:49.904Z,1591113349.904 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T15:55:50.321Z,1591113350.321 [DefaultWithUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-02T15:55:53.634Z,1591113353.634 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200602T154442/Courier0004.lzma
2020-06-02T15:55:54.636Z,1591113354.636 [DataOverHttps](INFO): Moved sent file to Logs/20200602T154442/Courier0004.lzma.bak
2020-06-02T15:55:54.636Z,1591113354.636 [DataOverHttps](INFO): SBD MOMSN=20307
2020-06-02T15:56:03.674Z,1591113363.674 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20200602T153915/Express0005.lzma
2020-06-02T15:56:04.676Z,1591113364.676 [DataOverHttps](INFO): Moved sent file to Logs/20200602T153915/Express0005.lzma.bak
2020-06-02T15:56:04.676Z,1591113364.676 [DataOverHttps](INFO): SBD MOMSN=20309
2020-06-02T15:56:13.715Z,1591113373.715 [DataOverHttps](INFO): Sending 763 bytes from file Logs/20200602T154442/Express0001.lzma
2020-06-02T15:56:14.716Z,1591113374.716 [DataOverHttps](INFO): Moved sent file to Logs/20200602T154442/Express0001.lzma.bak
2020-06-02T15:56:14.716Z,1591113374.716 [DataOverHttps](INFO): SBD MOMSN=20315
2020-06-02T15:56:23.754Z,1591113383.754 [DataOverHttps](INFO): Sending 292 bytes from file Logs/20200602T154442/Express0005.lzma
2020-06-02T15:56:24.756Z,1591113384.756 [DataOverHttps](INFO): Moved sent file to Logs/20200602T154442/Express0005.lzma.bak
2020-06-02T15:56:24.756Z,1591113384.756 [DataOverHttps](INFO): SBD MOMSN=20330
2020-06-02T15:56:29.861Z,1591113389.861 [NAL9602](INFO): SBD MO Status=2, MOMSN=11249, MT Status=2, MTMSN=0
2020-06-02T15:56:29.862Z,1591113389.862 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T15:56:33.794Z,1591113393.794 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20200602T155111/Express0001.lzma
2020-06-02T15:56:34.796Z,1591113394.796 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0001.lzma.bak
2020-06-02T15:56:34.796Z,1591113394.796 [DataOverHttps](INFO): SBD MOMSN=20336
2020-06-02T15:56:35.959Z,1591113395.959 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T15:56:35.959Z,1591113395.959 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T15:56:35.959Z,1591113395.959 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T15:56:51.283Z,1591113411.283 [PowerOnly](INFO): Powering down
2020-06-02T15:57:06.262Z,1591113426.262 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-06-02T15:58:46.462Z,1591113526.462 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-06-02T15:58:46.465Z,1591113526.465 [BPC1](INFO): Received data from all battery sticks.
2020-06-02T15:59:35.519Z,1591113575.519 [DataOverHttps](IMPORTANT): SBD MTMSN=20200602T155934
2020-06-02T15:59:39.538Z,1591113579.538 [DataOverHttps](INFO): Received command:burn on
2020-06-02T15:59:46.270Z,1591113586.270 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-06-02T15:59:46.270Z,1591113586.270 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-06-02T15:59:46.270Z,1591113586.270 [RDI_Pathfinder](ERROR): Communications Fault
2020-06-02T15:59:46.304Z,1591113586.304 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-06-02T15:59:46.698Z,1591113586.698 [RDI_Pathfinder](INFO): Powering down
2020-06-02T15:59:47.464Z,1591113587.464 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-06-02T15:59:47.464Z,1591113587.464 [RDI_Pathfinder] No Fault, FailCount= 1
2020-06-02T16:00:52.065Z,1591113652.065 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-02T16:01:08.257Z,1591113668.257 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:01:22.768Z,1591113682.768 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:01:36.540Z,1591113696.540 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:01:36.540Z,1591113696.540 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:01:36.540Z,1591113696.540 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:01:36.541Z,1591113696.541 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:01:36.940Z,1591113696.940 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:01:36.941Z,1591113696.941 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:01:37.349Z,1591113697.349 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 6.800640 min 
2020-06-02T16:01:37.349Z,1591113697.349 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:01:37.349Z,1591113697.349 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:01:37.349Z,1591113697.349 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:01:37.350Z,1591113697.350 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:01:37.350Z,1591113697.350 [DefaultWithUndock:CheckIn](INFO): Running loop #2
2020-06-02T16:01:37.350Z,1591113697.350 [DefaultWithUndock:CheckIn] Running Loop=2
2020-06-02T16:01:37.350Z,1591113697.350 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:01:37.350Z,1591113697.350 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:01:39.329Z,1591113699.329 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160138.00,A,4131.46586,N,07040.27824,W,0.175,193.55,020620,,,A*79
2020-06-02T16:01:39.331Z,1591113699.331 [NAL9602](INFO): GPS fix at 20200602T160138: (41.524431, -70.671304)
2020-06-02T16:01:39.381Z,1591113699.381 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:01:39.381Z,1591113699.381 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:01:58.321Z,1591113718.321 [NAL9602](INFO): SBD MO Status=0, MOMSN=11249, MT Status=0, MTMSN=0
2020-06-02T16:01:58.322Z,1591113718.322 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:02:25.036Z,1591113745.036 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T16:02:29.029Z,1591113749.029 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:03:45.024Z,1591113825.024 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:07:54.133Z,1591114074.133 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=498.613312
2020-06-02T16:07:54.239Z,1591114074.239 [CommandLine](IMPORTANT): got command burn on
2020-06-02T16:07:54.239Z,1591114074.239 [CommandLine](IMPORTANT): Activating dropweight wire
2020-06-02T16:07:58.154Z,1591114078.154 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002949
2020-06-02T16:08:00.752Z,1591114080.752 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T16:08:02.166Z,1591114082.166 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200602T155111/Courier0010.lzma
2020-06-02T16:08:03.170Z,1591114083.170 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0010.lzma.bak
2020-06-02T16:08:03.170Z,1591114083.170 [DataOverHttps](INFO): SBD MOMSN=20352
2020-06-02T16:08:12.206Z,1591114092.206 [DataOverHttps](INFO): Sending 276 bytes from file Logs/20200602T155111/Express0011.lzma
2020-06-02T16:08:12.974Z,1591114092.974 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout
2020-06-02T16:08:12.974Z,1591114092.974 [RDI_PathfinderUp] Communications Fault, FailCount= 1
2020-06-02T16:08:12.974Z,1591114092.974 [RDI_PathfinderUp](ERROR): Communications Fault
2020-06-02T16:08:13.015Z,1591114093.015 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp
2020-06-02T16:08:13.208Z,1591114093.208 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0011.lzma.bak
2020-06-02T16:08:13.208Z,1591114093.208 [DataOverHttps](INFO): SBD MOMSN=20354
2020-06-02T16:08:13.334Z,1591114093.334 [RDI_PathfinderUp](INFO): Powering down
2020-06-02T16:08:14.100Z,1591114094.100 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp
2020-06-02T16:08:14.100Z,1591114094.100 [RDI_PathfinderUp] No Fault, FailCount= 1
2020-06-02T16:08:14.499Z,1591114094.499 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:08:14.499Z,1591114094.499 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:08:14.499Z,1591114094.499 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:08:53.368Z,1591114133.368 [DataOverHttps](IMPORTANT): SBD MTMSN=20200602T160852
2020-06-02T16:08:56.105Z,1591114136.105 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T16:08:57.386Z,1591114137.386 [DataOverHttps](INFO): Received command:burn off
2020-06-02T16:08:57.399Z,1591114137.399 [CommandLine](IMPORTANT): got command burn off
2020-06-02T16:08:57.399Z,1591114137.399 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-06-02T16:09:21.125Z,1591114161.125 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-06-02T16:09:22.772Z,1591114162.772 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:09:36.091Z,1591114176.091 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:11:20.352Z,1591114280.352 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:12:00.351Z,1591114320.351 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:12:39.100Z,1591114359.100 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:13:15.060Z,1591114395.060 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:13:15.060Z,1591114395.060 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:13:15.060Z,1591114395.060 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:13:15.061Z,1591114395.061 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:13:15.466Z,1591114395.466 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:13:15.466Z,1591114395.466 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:13:15.868Z,1591114395.868 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 18.442468 min 
2020-06-02T16:13:15.868Z,1591114395.868 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:13:15.868Z,1591114395.868 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:13:15.869Z,1591114395.869 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:13:15.869Z,1591114395.869 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:13:15.869Z,1591114395.869 [DefaultWithUndock:CheckIn](INFO): Running loop #3
2020-06-02T16:13:15.869Z,1591114395.869 [DefaultWithUndock:CheckIn] Running Loop=3
2020-06-02T16:13:15.869Z,1591114395.869 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:13:15.869Z,1591114395.869 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:13:17.849Z,1591114397.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161317.00,A,4131.47140,N,07040.27257,W,0.972,1.46,020620,,,A*71
2020-06-02T16:13:17.864Z,1591114397.864 [NAL9602](INFO): GPS fix at 20200602T161317: (41.524523, -70.671210)
2020-06-02T16:13:17.911Z,1591114397.911 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-06-02T16:13:17.925Z,1591114397.925 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:13:17.925Z,1591114397.925 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:13:21.486Z,1591114401.486 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20200602T155111/Courier0013.lzma
2020-06-02T16:13:22.488Z,1591114402.488 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0013.lzma.bak
2020-06-02T16:13:22.488Z,1591114402.488 [DataOverHttps](INFO): SBD MOMSN=20361
2020-06-02T16:13:31.527Z,1591114411.527 [DataOverHttps](INFO): Sending 797 bytes from file Logs/20200602T155111/Express0014.lzma
2020-06-02T16:13:32.528Z,1591114412.528 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0014.lzma.bak
2020-06-02T16:13:32.528Z,1591114412.528 [DataOverHttps](INFO): SBD MOMSN=20364
2020-06-02T16:13:33.645Z,1591114413.645 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:13:33.645Z,1591114413.645 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:13:33.645Z,1591114413.645 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:13:40.098Z,1591114420.098 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=11250, MT Status=1, MTMSN=511
2020-06-02T16:13:40.098Z,1591114420.098 [NAL9602](INFO): Data available in MT queue
2020-06-02T16:13:40.559Z,1591114420.559 [NAL9602](INFO): Received command:burn off
2020-06-02T16:13:40.563Z,1591114420.563 [CommandLine](IMPORTANT): got command burn off
2020-06-02T16:13:40.563Z,1591114420.563 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-06-02T16:14:11.172Z,1591114451.172 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:14:21.727Z,1591114461.727 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T16:17:00.095Z,1591114620.095 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-06-02T16:18:33.803Z,1591114713.803 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T16:18:34.211Z,1591114714.211 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:18:34.211Z,1591114714.211 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:18:34.211Z,1591114714.211 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:18:34.211Z,1591114714.211 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:18:34.625Z,1591114714.625 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:18:34.625Z,1591114714.625 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 23.762050 min 
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn](INFO): Running loop #4
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn] Running Loop=4
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:18:35.033Z,1591114715.033 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:18:37.006Z,1591114717.006 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161836.00,A,4131.47391,N,07040.29565,W,0.194,89.29,020620,,,A*46
2020-06-02T16:18:37.008Z,1591114717.008 [NAL9602](INFO): GPS fix at 20200602T161836: (41.524565, -70.671594)
2020-06-02T16:18:37.073Z,1591114717.073 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:18:37.073Z,1591114717.073 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:18:40.790Z,1591114720.790 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0016.lzma
2020-06-02T16:18:41.792Z,1591114721.792 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0016.lzma.bak
2020-06-02T16:18:41.792Z,1591114721.792 [DataOverHttps](INFO): SBD MOMSN=20384
2020-06-02T16:18:50.831Z,1591114730.831 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20200602T155111/Express0018.lzma
2020-06-02T16:18:51.832Z,1591114731.832 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0018.lzma.bak
2020-06-02T16:18:51.832Z,1591114731.832 [DataOverHttps](INFO): SBD MOMSN=20387
2020-06-02T16:18:53.197Z,1591114733.197 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:18:53.197Z,1591114733.197 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:18:53.197Z,1591114733.197 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:19:53.795Z,1591114793.795 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:20:04.725Z,1591114804.725 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-06-02T16:20:17.198Z,1591114817.198 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=11251, MT Status=1, MTMSN=512
2020-06-02T16:20:17.198Z,1591114817.198 [NAL9602](INFO): Data available in MT queue
2020-06-02T16:20:17.687Z,1591114817.687 [NAL9602](INFO): Received command:burn on
2020-06-02T16:20:17.783Z,1591114817.783 [CommandLine](IMPORTANT): got command burn on
2020-06-02T16:20:17.783Z,1591114817.783 [CommandLine](IMPORTANT): Activating dropweight wire
2020-06-02T16:20:48.330Z,1591114848.330 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:21:04.906Z,1591114864.906 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T16:21:40.452Z,1591114900.452 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:21:51.644Z,1591114911.644 [CommandLine](IMPORTANT): got command ibit
2020-06-02T16:21:51.787Z,1591114911.787 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-06-02T16:21:51.788Z,1591114911.788 [IBIT](IMPORTANT): Beginning control surface checks.
2020-06-02T16:21:51.791Z,1591114911.791 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-02T16:21:53.350Z,1591114913.350 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162152.00,A,4131.46716,N,07040.28120,W,0.117,122.37,020620,,,A*74
2020-06-02T16:21:53.355Z,1591114913.355 [NAL9602](INFO): GPS fix at 20200602T162152: (41.524453, -70.671353)
2020-06-02T16:22:02.742Z,1591114922.742 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009096
CHAN A1 (24V): -0.028773
CHAN A2 (12V): -0.007582
CHAN A3 (5V): -0.001645
CHAN B0 (3.3V): 0.000096
CHAN B1 (3.15aV): 0.000445
CHAN B2 (3.15bV): 0.000390
CHAN B3 (GND): 0.002086
OPEN: 0.004979
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-02T16:22:17.592Z,1591114937.592 [NAL9602](INFO): SBD MO Status=2, MOMSN=11252, MT Status=2, MTMSN=0
2020-06-02T16:22:17.592Z,1591114937.592 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T16:22:20.453Z,1591114940.453 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T16:22:34.974Z,1591114954.974 [NAL9602](INFO): SBD MO Status=2, MOMSN=11252, MT Status=2, MTMSN=0
2020-06-02T16:22:34.974Z,1591114954.974 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T16:22:37.839Z,1591114957.839 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 41.524452 Longitude: -70.671356
2020-06-02T16:22:38.240Z,1591114958.240 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.501000
2020-06-02T16:22:38.241Z,1591114958.241 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-06-02T16:22:38.241Z,1591114958.241 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-06-02T16:22:38.683Z,1591114958.683 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-06-02T16:22:38.683Z,1591114958.683 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-06-02T16:22:38.684Z,1591114958.684 [IBIT](IMPORTANT): Pressure:10.358450 PSI
2020-06-02T16:22:38.684Z,1591114958.684 [IBIT](IMPORTANT): Humidity:5.725797 %
2020-06-02T16:22:39.083Z,1591114959.083 [IBIT](IMPORTANT): Vehicle Pitch:1.336183 degrees
2020-06-02T16:22:39.084Z,1591114959.084 [IBIT](IMPORTANT): Vehicle  Roll:-1.096212 degrees
2020-06-02T16:22:39.084Z,1591114959.084 [IBIT](IMPORTANT): Vehicle  Heading:315.480988 degrees
2020-06-02T16:22:39.504Z,1591114959.504 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-06-02T16:22:39.504Z,1591114959.504 [IBIT](IMPORTANT): buoyancyNeutral: 243.000000 cc
2020-06-02T16:22:39.504Z,1591114959.504 [IBIT](IMPORTANT): massDefault: 0.290000 cm
2020-06-02T16:22:39.504Z,1591114959.504 [IBIT](IMPORTANT): stopDepth: 11.000000 m
2020-06-02T16:22:39.505Z,1591114959.505 [IBIT](IMPORTANT): abortDepth: 15.000000 m
2020-06-02T16:22:39.505Z,1591114959.505 [IBIT](IMPORTANT): IBIT PASSED
2020-06-02T16:23:07.313Z,1591114987.313 [NAL9602](INFO): SBD MO Status=0, MOMSN=11252, MT Status=0, MTMSN=0
2020-06-02T16:23:07.313Z,1591114987.313 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:23:07.709Z,1591114987.709 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:23:37.987Z,1591115017.987 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:23:53.797Z,1591115033.797 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:23:53.797Z,1591115033.797 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:23:53.797Z,1591115033.797 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:23:53.797Z,1591115033.797 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:23:54.191Z,1591115034.191 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:23:54.191Z,1591115034.191 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 29.088143 min 
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn](INFO): Running loop #5
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn] Running Loop=5
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:23:54.609Z,1591115034.609 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:23:56.576Z,1591115036.576 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162355.00,A,4131.46488,N,07040.28086,W,0.117,122.37,020620,,,A*78
2020-06-02T16:23:56.578Z,1591115036.578 [NAL9602](INFO): GPS fix at 20200602T162355: (41.524415, -70.671348)
2020-06-02T16:23:56.602Z,1591115036.602 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:23:56.602Z,1591115036.602 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:23:57.428Z,1591115037.428 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:23:57.428Z,1591115037.428 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:23:57.428Z,1591115037.428 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:24:16.789Z,1591115056.789 [NAL9602](INFO): SBD MO Status=0, MOMSN=11253, MT Status=0, MTMSN=0
2020-06-02T16:24:16.789Z,1591115056.789 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:24:26.094Z,1591115066.094 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:24:47.476Z,1591115087.476 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:25:32.853Z,1591115132.853 [CommandLine](IMPORTANT): got command failComponent
2020-06-02T16:25:32.854Z,1591115132.854 [CommandLine](IMPORTANT): Failed components:
2020-06-02T16:25:32.854Z,1591115132.854 [CommandLine](IMPORTANT): No failed Components.
2020-06-02T16:27:27.091Z,1591115247.091 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T16:28:58.004Z,1591115338.004 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:28:58.004Z,1591115338.004 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:28:58.004Z,1591115338.004 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:28:58.004Z,1591115338.004 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:28:58.407Z,1591115338.407 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:28:58.408Z,1591115338.408 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 34.158423 min 
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn](INFO): Running loop #6
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn] Running Loop=6
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:28:58.811Z,1591115338.811 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:29:00.794Z,1591115340.794 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162900.00,A,4131.46984,N,07040.27858,W,0.039,122.37,020620,,,A*7A
2020-06-02T16:29:00.796Z,1591115340.796 [NAL9602](INFO): GPS fix at 20200602T162900: (41.524497, -70.671310)
2020-06-02T16:29:00.866Z,1591115340.866 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:29:00.866Z,1591115340.866 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:29:04.334Z,1591115344.334 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200602T155111/Courier0022.lzma
2020-06-02T16:29:05.336Z,1591115345.336 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0022.lzma.bak
2020-06-02T16:29:05.336Z,1591115345.336 [DataOverHttps](INFO): SBD MOMSN=20396
2020-06-02T16:29:14.374Z,1591115354.374 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200602T155111/Courier0025.lzma
2020-06-02T16:29:15.392Z,1591115355.392 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0025.lzma.bak
2020-06-02T16:29:15.392Z,1591115355.392 [DataOverHttps](INFO): SBD MOMSN=20399
2020-06-02T16:29:15.745Z,1591115355.745 [NAL9602](INFO): SBD MO Status=0, MOMSN=11254, MT Status=0, MTMSN=0
2020-06-02T16:29:15.746Z,1591115355.746 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:29:24.432Z,1591115364.432 [DataOverHttps](INFO): Sending 827 bytes from file Logs/20200602T155111/Express0023.lzma
2020-06-02T16:29:25.432Z,1591115365.432 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0023.lzma.bak
2020-06-02T16:29:25.432Z,1591115365.432 [DataOverHttps](INFO): SBD MOMSN=20402
2020-06-02T16:29:34.470Z,1591115374.470 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20200602T155111/Express0026.lzma
2020-06-02T16:29:35.472Z,1591115375.472 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0026.lzma.bak
2020-06-02T16:29:35.472Z,1591115375.472 [DataOverHttps](INFO): SBD MOMSN=20430
2020-06-02T16:29:36.833Z,1591115376.833 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:29:36.833Z,1591115376.833 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:29:36.833Z,1591115376.833 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:29:46.440Z,1591115386.440 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:33:52.089Z,1591115632.089 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:34:37.383Z,1591115677.383 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:34:37.383Z,1591115677.383 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:34:37.383Z,1591115677.383 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:34:37.384Z,1591115677.384 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:34:37.753Z,1591115677.753 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:34:37.753Z,1591115677.753 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:34:38.170Z,1591115678.170 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 39.814185 min 
2020-06-02T16:34:38.170Z,1591115678.170 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:34:38.170Z,1591115678.170 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:34:38.170Z,1591115678.170 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:34:38.170Z,1591115678.170 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:34:38.171Z,1591115678.171 [DefaultWithUndock:CheckIn](INFO): Running loop #7
2020-06-02T16:34:38.171Z,1591115678.171 [DefaultWithUndock:CheckIn] Running Loop=7
2020-06-02T16:34:38.171Z,1591115678.171 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:34:38.171Z,1591115678.171 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:34:40.145Z,1591115680.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163439.00,A,4131.46705,N,07040.28085,W,0.058,122.37,020620,,,D*7E
2020-06-02T16:34:40.147Z,1591115680.147 [NAL9602](INFO): GPS fix at 20200602T163439: (41.524451, -70.671347)
2020-06-02T16:34:40.212Z,1591115680.212 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:34:40.212Z,1591115680.212 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:34:44.718Z,1591115684.718 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0028.lzma
2020-06-02T16:34:45.723Z,1591115685.723 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0028.lzma.bak
2020-06-02T16:34:45.724Z,1591115685.724 [DataOverHttps](INFO): SBD MOMSN=20437
2020-06-02T16:34:54.758Z,1591115694.758 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200602T155111/Express0029.lzma
2020-06-02T16:34:55.760Z,1591115695.760 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0029.lzma.bak
2020-06-02T16:34:55.760Z,1591115695.760 [DataOverHttps](INFO): SBD MOMSN=20440
2020-06-02T16:34:56.826Z,1591115696.826 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:34:56.826Z,1591115696.826 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:34:56.827Z,1591115696.827 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:35:09.245Z,1591115709.245 [NAL9602](INFO): SBD MO Status=0, MOMSN=11255, MT Status=0, MTMSN=0
2020-06-02T16:35:09.245Z,1591115709.245 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:35:39.952Z,1591115739.952 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:37:32.719Z,1591115852.719 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T16:39:10.077Z,1591115950.077 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-06-02T16:39:23.806Z,1591115963.806 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T16:39:51.713Z,1591115991.713 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T16:39:57.381Z,1591115997.381 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:39:57.381Z,1591115997.381 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:39:57.381Z,1591115997.381 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:39:57.381Z,1591115997.381 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:39:57.759Z,1591115997.759 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:39:57.760Z,1591115997.760 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:39:58.170Z,1591115998.170 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 45.147624 min 
2020-06-02T16:39:58.170Z,1591115998.170 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:39:58.170Z,1591115998.170 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:39:58.170Z,1591115998.170 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:39:58.171Z,1591115998.171 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:39:58.171Z,1591115998.171 [DefaultWithUndock:CheckIn](INFO): Running loop #8
2020-06-02T16:39:58.171Z,1591115998.171 [DefaultWithUndock:CheckIn] Running Loop=8
2020-06-02T16:39:58.171Z,1591115998.171 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:39:58.171Z,1591115998.171 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:40:00.150Z,1591116000.150 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163959.00,A,4131.47125,N,07040.28229,W,0.039,122.37,020620,,,D*73
2020-06-02T16:40:00.152Z,1591116000.152 [NAL9602](INFO): GPS fix at 20200602T163959: (41.524521, -70.671372)
2020-06-02T16:40:00.208Z,1591116000.208 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:40:00.208Z,1591116000.208 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:40:06.014Z,1591116006.014 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200602T155111/Courier0031.lzma
2020-06-02T16:40:12.036Z,1591116012.036 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0031.lzma.bak
2020-06-02T16:40:12.036Z,1591116012.036 [DataOverHttps](INFO): SBD MOMSN=20445
2020-06-02T16:40:13.479Z,1591116013.479 [NAL9602](INFO): SBD MO Status=0, MOMSN=11256, MT Status=0, MTMSN=0
2020-06-02T16:40:13.479Z,1591116013.479 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:40:29.106Z,1591116029.106 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200602T155111/Express0032.lzma
2020-06-02T16:40:30.108Z,1591116030.108 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0032.lzma.bak
2020-06-02T16:40:30.108Z,1591116030.108 [DataOverHttps](INFO): SBD MOMSN=20448
2020-06-02T16:40:31.311Z,1591116031.311 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:40:31.311Z,1591116031.311 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:40:31.311Z,1591116031.311 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:40:44.181Z,1591116044.181 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:45:31.876Z,1591116331.876 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:45:31.876Z,1591116331.876 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:45:31.876Z,1591116331.876 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:45:31.876Z,1591116331.876 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:45:32.279Z,1591116332.279 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:45:32.279Z,1591116332.279 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:45:32.709Z,1591116332.709 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 50.722668 min 
2020-06-02T16:45:32.710Z,1591116332.710 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:45:32.710Z,1591116332.710 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:45:32.710Z,1591116332.710 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:45:32.710Z,1591116332.710 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:45:32.714Z,1591116332.714 [DefaultWithUndock:CheckIn](INFO): Running loop #9
2020-06-02T16:45:32.714Z,1591116332.714 [DefaultWithUndock:CheckIn] Running Loop=9
2020-06-02T16:45:32.714Z,1591116332.714 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:45:32.715Z,1591116332.715 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:45:34.689Z,1591116334.689 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164534.00,A,4131.46467,N,07040.30196,W,0.019,286.68,020620,,,D*7A
2020-06-02T16:45:34.703Z,1591116334.703 [NAL9602](INFO): GPS fix at 20200602T164534: (41.524411, -70.671699)
2020-06-02T16:45:34.754Z,1591116334.754 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:45:34.754Z,1591116334.754 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:45:41.434Z,1591116341.434 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0034.lzma
2020-06-02T16:45:42.436Z,1591116342.436 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0034.lzma.bak
2020-06-02T16:45:42.436Z,1591116342.436 [DataOverHttps](INFO): SBD MOMSN=20452
2020-06-02T16:45:43.550Z,1591116343.550 [NAL9602](INFO): SBD MO Status=0, MOMSN=11257, MT Status=0, MTMSN=0
2020-06-02T16:45:43.550Z,1591116343.550 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:45:51.474Z,1591116351.474 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200602T155111/Express0035.lzma
2020-06-02T16:45:52.476Z,1591116352.476 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0035.lzma.bak
2020-06-02T16:45:52.476Z,1591116352.476 [DataOverHttps](INFO): SBD MOMSN=20455
2020-06-02T16:45:53.725Z,1591116353.725 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:45:53.725Z,1591116353.725 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:45:53.725Z,1591116353.725 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:45:59.430Z,1591116359.430 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout
2020-06-02T16:45:59.430Z,1591116359.430 [RDI_PathfinderUp] Communications Fault, FailCount= 1
2020-06-02T16:45:59.430Z,1591116359.430 [RDI_PathfinderUp](ERROR): Communications Fault
2020-06-02T16:45:59.475Z,1591116359.475 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp
2020-06-02T16:45:59.798Z,1591116359.798 [RDI_PathfinderUp](INFO): Powering down
2020-06-02T16:46:00.560Z,1591116360.560 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp
2020-06-02T16:46:00.560Z,1591116360.560 [RDI_PathfinderUp] No Fault, FailCount= 1
2020-06-02T16:46:14.258Z,1591116374.258 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:48:34.071Z,1591116514.071 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:50:54.270Z,1591116654.270 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:50:54.274Z,1591116654.274 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:50:54.274Z,1591116654.274 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:50:54.274Z,1591116654.274 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:50:54.704Z,1591116654.704 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:50:54.704Z,1591116654.704 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:50:55.099Z,1591116655.099 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 56.096696 min 
2020-06-02T16:50:55.099Z,1591116655.099 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:50:55.100Z,1591116655.100 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:50:55.100Z,1591116655.100 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:50:55.100Z,1591116655.100 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:50:55.100Z,1591116655.100 [DefaultWithUndock:CheckIn](INFO): Running loop #10
2020-06-02T16:50:55.100Z,1591116655.100 [DefaultWithUndock:CheckIn] Running Loop=10
2020-06-02T16:50:55.100Z,1591116655.100 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:50:55.100Z,1591116655.100 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:50:57.063Z,1591116657.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165056.00,A,4131.46377,N,07040.30386,W,0.019,286.68,020620,,,D*7F
2020-06-02T16:50:57.066Z,1591116657.066 [NAL9602](INFO): GPS fix at 20200602T165056: (41.524396, -70.671731)
2020-06-02T16:50:57.135Z,1591116657.135 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:50:57.135Z,1591116657.135 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:51:00.718Z,1591116660.718 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200602T155111/Courier0037.lzma
2020-06-02T16:51:01.716Z,1591116661.716 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0037.lzma.bak
2020-06-02T16:51:01.716Z,1591116661.716 [DataOverHttps](INFO): SBD MOMSN=20459
2020-06-02T16:51:10.758Z,1591116670.758 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200602T155111/Express0038.lzma
2020-06-02T16:51:11.760Z,1591116671.760 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0038.lzma.bak
2020-06-02T16:51:11.760Z,1591116671.760 [DataOverHttps](INFO): SBD MOMSN=20462
2020-06-02T16:51:12.850Z,1591116672.850 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:51:12.850Z,1591116672.850 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:51:12.850Z,1591116672.850 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:51:36.249Z,1591116696.249 [NAL9602](INFO): SBD MO Status=0, MOMSN=11258, MT Status=0, MTMSN=0
2020-06-02T16:51:36.250Z,1591116696.250 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:52:06.953Z,1591116726.953 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:53:40.742Z,1591116820.742 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-06-02T16:53:40.742Z,1591116820.742 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-06-02T16:53:40.742Z,1591116820.742 [RDI_Pathfinder](ERROR): Communications Fault
2020-06-02T16:53:40.767Z,1591116820.767 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-06-02T16:53:41.174Z,1591116821.174 [RDI_Pathfinder](INFO): Powering down
2020-06-02T16:53:41.941Z,1591116821.941 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-06-02T16:53:41.941Z,1591116821.941 [RDI_Pathfinder] No Fault, FailCount= 1
2020-06-02T16:55:02.727Z,1591116902.727 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T16:55:26.548Z,1591116926.548 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:55:42.715Z,1591116942.715 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:56:06.550Z,1591116966.550 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:56:11.024Z,1591116971.024 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T16:56:13.431Z,1591116973.431 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T16:56:13.432Z,1591116973.432 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T16:56:13.432Z,1591116973.432 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T16:56:13.432Z,1591116973.432 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T16:56:13.902Z,1591116973.902 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T16:56:13.902Z,1591116973.902 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T16:56:14.228Z,1591116974.228 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 61.416602 min 
2020-06-02T16:56:14.228Z,1591116974.228 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T16:56:14.228Z,1591116974.228 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T16:56:14.228Z,1591116974.228 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T16:56:14.228Z,1591116974.228 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T16:56:14.228Z,1591116974.228 [DefaultWithUndock:CheckIn](INFO): Running loop #11
2020-06-02T16:56:14.228Z,1591116974.228 [DefaultWithUndock:CheckIn] Running Loop=11
2020-06-02T16:56:14.229Z,1591116974.229 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T16:56:14.229Z,1591116974.229 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T16:56:16.223Z,1591116976.223 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165615.00,A,4131.46273,N,07040.30775,W,0.097,193.54,020620,,,A*78
2020-06-02T16:56:16.225Z,1591116976.225 [NAL9602](INFO): GPS fix at 20200602T165615: (41.524379, -70.671796)
2020-06-02T16:56:16.280Z,1591116976.280 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T16:56:16.280Z,1591116976.280 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T16:56:20.038Z,1591116980.038 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0040.lzma
2020-06-02T16:56:21.040Z,1591116981.040 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0040.lzma.bak
2020-06-02T16:56:21.040Z,1591116981.040 [DataOverHttps](INFO): SBD MOMSN=20466
2020-06-02T16:56:29.554Z,1591116989.554 [NAL9602](INFO): SBD MO Status=0, MOMSN=11259, MT Status=0, MTMSN=0
2020-06-02T16:56:29.554Z,1591116989.554 [NAL9602](INFO): No messages in MT queue
2020-06-02T16:56:30.078Z,1591116990.078 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20200602T155111/Express0041.lzma
2020-06-02T16:56:31.080Z,1591116991.080 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0041.lzma.bak
2020-06-02T16:56:31.081Z,1591116991.081 [DataOverHttps](INFO): SBD MOMSN=20469
2020-06-02T16:56:32.428Z,1591116992.428 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T16:56:32.428Z,1591116992.428 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T16:56:32.428Z,1591116992.428 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T16:57:00.252Z,1591117020.252 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T16:58:08.168Z,1591117088.168 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T16:58:53.418Z,1591117133.418 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T16:59:14.854Z,1591117154.854 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T16:59:24.532Z,1591117164.532 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-06-02T16:59:48.354Z,1591117188.354 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-06-02T17:00:23.909Z,1591117223.909 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:00:48.531Z,1591117248.531 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:00:56.217Z,1591117256.217 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:01:32.981Z,1591117292.981 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T17:01:32.982Z,1591117292.982 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T17:01:32.982Z,1591117292.982 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T17:01:32.982Z,1591117292.982 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T17:01:33.439Z,1591117293.439 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T17:01:33.439Z,1591117293.439 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T17:01:33.843Z,1591117293.843 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 66.742285 min 
2020-06-02T17:01:33.844Z,1591117293.844 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T17:01:33.844Z,1591117293.844 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T17:01:33.844Z,1591117293.844 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T17:01:33.844Z,1591117293.844 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T17:01:33.845Z,1591117293.845 [DefaultWithUndock:CheckIn](INFO): Running loop #12
2020-06-02T17:01:33.845Z,1591117293.845 [DefaultWithUndock:CheckIn] Running Loop=12
2020-06-02T17:01:33.845Z,1591117293.845 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T17:01:33.846Z,1591117293.846 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T17:01:35.791Z,1591117295.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170135.00,A,4131.46726,N,07040.31262,W,0.078,352.35,020620,,,A*77
2020-06-02T17:01:35.794Z,1591117295.794 [NAL9602](INFO): GPS fix at 20200602T170135: (41.524454, -70.671877)
2020-06-02T17:01:35.863Z,1591117295.863 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T17:01:35.863Z,1591117295.863 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T17:01:39.366Z,1591117299.366 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0043.lzma
2020-06-02T17:01:40.369Z,1591117300.369 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0043.lzma.bak
2020-06-02T17:01:40.369Z,1591117300.369 [DataOverHttps](INFO): SBD MOMSN=20473
2020-06-02T17:01:47.497Z,1591117307.497 [NAL9602](INFO): SBD MO Status=0, MOMSN=11260, MT Status=0, MTMSN=0
2020-06-02T17:01:47.497Z,1591117307.497 [NAL9602](INFO): No messages in MT queue
2020-06-02T17:01:49.406Z,1591117309.406 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200602T155111/Express0044.lzma
2020-06-02T17:01:50.408Z,1591117310.408 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0044.lzma.bak
2020-06-02T17:01:50.408Z,1591117310.408 [DataOverHttps](INFO): SBD MOMSN=20476
2020-06-02T17:01:51.582Z,1591117311.582 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T17:01:51.582Z,1591117311.582 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T17:01:51.583Z,1591117311.583 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T17:02:18.204Z,1591117338.204 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T17:02:29.531Z,1591117349.531 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:03:53.583Z,1591117433.583 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:03:57.233Z,1591117437.233 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:04:29.529Z,1591117469.529 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:04:33.585Z,1591117473.585 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:06:10.939Z,1591117570.939 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-06-02T17:06:10.942Z,1591117570.942 [BPC1](INFO): Received data from all battery sticks.
2020-06-02T17:06:52.147Z,1591117612.147 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T17:06:52.148Z,1591117612.148 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T17:06:52.148Z,1591117612.148 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T17:06:52.148Z,1591117612.148 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T17:06:52.575Z,1591117612.575 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T17:06:52.575Z,1591117612.575 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T17:06:52.949Z,1591117612.949 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 72.061214 min 
2020-06-02T17:06:52.950Z,1591117612.950 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T17:06:52.950Z,1591117612.950 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T17:06:52.950Z,1591117612.950 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T17:06:52.950Z,1591117612.950 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T17:06:52.967Z,1591117612.967 [DefaultWithUndock:CheckIn](INFO): Running loop #13
2020-06-02T17:06:52.967Z,1591117612.967 [DefaultWithUndock:CheckIn] Running Loop=13
2020-06-02T17:06:52.968Z,1591117612.968 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T17:06:52.968Z,1591117612.968 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T17:06:54.942Z,1591117614.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170654.00,A,4131.46386,N,07040.31177,W,0.097,14.78,020620,,,D*42
2020-06-02T17:06:54.944Z,1591117614.944 [NAL9602](INFO): GPS fix at 20200602T170654: (41.524398, -70.671863)
2020-06-02T17:06:54.982Z,1591117614.982 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T17:06:54.982Z,1591117614.982 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T17:06:58.698Z,1591117618.698 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200602T155111/Courier0046.lzma
2020-06-02T17:06:59.700Z,1591117619.700 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0046.lzma.bak
2020-06-02T17:06:59.701Z,1591117619.701 [DataOverHttps](INFO): SBD MOMSN=20480
2020-06-02T17:07:08.738Z,1591117628.738 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200602T155111/Express0047.lzma
2020-06-02T17:07:09.740Z,1591117629.740 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0047.lzma.bak
2020-06-02T17:07:09.740Z,1591117629.740 [DataOverHttps](INFO): SBD MOMSN=20483
2020-06-02T17:07:11.147Z,1591117631.147 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T17:07:11.147Z,1591117631.147 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T17:07:11.147Z,1591117631.147 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T17:07:12.722Z,1591117632.722 [NAL9602](INFO): SBD MO Status=0, MOMSN=11261, MT Status=0, MTMSN=0
2020-06-02T17:07:12.722Z,1591117632.722 [NAL9602](INFO): No messages in MT queue
2020-06-02T17:07:43.424Z,1591117663.424 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T17:09:15.556Z,1591117755.556 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:09:55.178Z,1591117795.178 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:10:29.920Z,1591117829.920 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2020-06-02T17:11:03.869Z,1591117863.869 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T17:11:41.023Z,1591117901.023 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T17:12:11.730Z,1591117931.730 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T17:12:11.730Z,1591117931.730 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T17:12:11.730Z,1591117931.730 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T17:12:11.731Z,1591117931.731 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T17:12:12.112Z,1591117932.112 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T17:12:12.112Z,1591117932.112 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T17:12:12.538Z,1591117932.538 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 77.386841 min 
2020-06-02T17:12:12.538Z,1591117932.538 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T17:12:12.539Z,1591117932.539 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T17:12:12.539Z,1591117932.539 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T17:12:12.539Z,1591117932.539 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T17:12:12.539Z,1591117932.539 [DefaultWithUndock:CheckIn](INFO): Running loop #14
2020-06-02T17:12:12.539Z,1591117932.539 [DefaultWithUndock:CheckIn] Running Loop=14
2020-06-02T17:12:12.539Z,1591117932.539 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T17:12:12.539Z,1591117932.539 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T17:12:14.520Z,1591117934.520 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171213.00,A,4131.46695,N,07040.31338,W,0.175,14.78,020620,,,D*47
2020-06-02T17:12:14.535Z,1591117934.535 [NAL9602](INFO): GPS fix at 20200602T171213: (41.524449, -70.671890)
2020-06-02T17:12:14.573Z,1591117934.573 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T17:12:14.573Z,1591117934.573 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T17:12:18.982Z,1591117938.982 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0049.lzma
2020-06-02T17:12:19.984Z,1591117939.984 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0049.lzma.bak
2020-06-02T17:12:19.984Z,1591117939.984 [DataOverHttps](INFO): SBD MOMSN=20487
2020-06-02T17:12:23.865Z,1591117943.865 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:12:29.030Z,1591117949.030 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200602T155111/Express0050.lzma
2020-06-02T17:12:30.032Z,1591117950.032 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0050.lzma.bak
2020-06-02T17:12:30.032Z,1591117950.032 [DataOverHttps](INFO): SBD MOMSN=20490
2020-06-02T17:12:30.664Z,1591117950.664 [NAL9602](INFO): SBD MO Status=0, MOMSN=11262, MT Status=0, MTMSN=0
2020-06-02T17:12:30.665Z,1591117950.665 [NAL9602](INFO): No messages in MT queue
2020-06-02T17:12:31.105Z,1591117951.105 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T17:12:31.105Z,1591117951.105 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T17:12:31.105Z,1591117951.105 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T17:13:01.372Z,1591117981.372 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T17:14:56.536Z,1591118096.536 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:15:50.295Z,1591118150.295 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T17:16:16.521Z,1591118176.521 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:16:48.934Z,1591118208.934 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout
2020-06-02T17:16:48.934Z,1591118208.934 [RDI_PathfinderUp] Communications Fault, FailCount= 1
2020-06-02T17:16:48.934Z,1591118208.934 [RDI_PathfinderUp](ERROR): Communications Fault
2020-06-02T17:16:48.959Z,1591118208.959 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp
2020-06-02T17:16:49.326Z,1591118209.326 [RDI_PathfinderUp](INFO): Powering down
2020-06-02T17:16:50.085Z,1591118210.085 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp
2020-06-02T17:16:50.086Z,1591118210.086 [RDI_PathfinderUp] No Fault, FailCount= 1
2020-06-02T17:17:00.186Z,1591118220.186 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-06-02T17:17:31.709Z,1591118251.709 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T17:17:31.709Z,1591118251.709 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T17:17:31.709Z,1591118251.709 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T17:17:31.709Z,1591118251.709 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T17:17:32.101Z,1591118252.101 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T17:17:32.102Z,1591118252.102 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T17:17:32.492Z,1591118252.492 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 82.719995 min 
2020-06-02T17:17:32.492Z,1591118252.492 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T17:17:32.492Z,1591118252.492 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T17:17:32.492Z,1591118252.492 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T17:17:32.492Z,1591118252.492 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T17:17:32.492Z,1591118252.492 [DefaultWithUndock:CheckIn](INFO): Running loop #15
2020-06-02T17:17:32.492Z,1591118252.492 [DefaultWithUndock:CheckIn] Running Loop=15
2020-06-02T17:17:32.492Z,1591118252.492 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T17:17:32.493Z,1591118252.493 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T17:17:34.498Z,1591118254.498 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171733.00,A,4131.16441,N,07041.03986,W,14.734,263.13,020620,,,D*48
2020-06-02T17:17:34.510Z,1591118254.510 [NAL9602](INFO): GPS fix at 20200602T171733: (41.519407, -70.683998)
2020-06-02T17:17:34.567Z,1591118254.567 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T17:17:34.567Z,1591118254.567 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T17:17:36.535Z,1591118256.535 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:17:38.294Z,1591118258.294 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0052.lzma
2020-06-02T17:17:39.298Z,1591118259.298 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0052.lzma.bak
2020-06-02T17:17:39.299Z,1591118259.299 [DataOverHttps](INFO): SBD MOMSN=20494
2020-06-02T17:17:48.337Z,1591118268.337 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20200602T155111/Express0053.lzma
2020-06-02T17:17:49.336Z,1591118269.336 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0053.lzma.bak
2020-06-02T17:17:49.336Z,1591118269.336 [DataOverHttps](INFO): SBD MOMSN=20497
2020-06-02T17:17:50.694Z,1591118270.694 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T17:17:50.695Z,1591118270.695 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T17:17:50.695Z,1591118270.695 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T17:18:02.351Z,1591118282.351 [NAL9602](INFO): SBD MO Status=2, MOMSN=11263, MT Status=2, MTMSN=0
2020-06-02T17:18:02.351Z,1591118282.351 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T17:18:12.448Z,1591118292.448 [NAL9602](INFO): SBD MO Status=0, MOMSN=11263, MT Status=0, MTMSN=0
2020-06-02T17:18:12.449Z,1591118292.449 [NAL9602](INFO): No messages in MT queue
2020-06-02T17:18:32.712Z,1591118312.712 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:18:43.156Z,1591118323.156 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T17:19:59.160Z,1591118399.160 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-06-02T17:20:20.175Z,1591118420.175 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-06-02T17:20:57.364Z,1591118457.364 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:21:17.154Z,1591118477.154 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:22:51.264Z,1591118571.264 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T17:22:51.264Z,1591118571.264 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T17:22:51.264Z,1591118571.264 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T17:22:51.264Z,1591118571.264 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T17:22:51.657Z,1591118571.657 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T17:22:51.657Z,1591118571.657 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T17:22:52.078Z,1591118572.078 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 88.045915 min 
2020-06-02T17:22:52.078Z,1591118572.078 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T17:22:52.078Z,1591118572.078 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T17:22:52.079Z,1591118572.079 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T17:22:52.079Z,1591118572.079 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T17:22:52.079Z,1591118572.079 [DefaultWithUndock:CheckIn](INFO): Running loop #16
2020-06-02T17:22:52.079Z,1591118572.079 [DefaultWithUndock:CheckIn] Running Loop=16
2020-06-02T17:22:52.079Z,1591118572.079 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T17:22:52.079Z,1591118572.079 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T17:22:54.050Z,1591118574.050 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172253.00,A,4132.58147,N,07042.02676,W,15.045,331.10,020620,,,A*40
2020-06-02T17:22:54.052Z,1591118574.052 [NAL9602](INFO): GPS fix at 20200602T172253: (41.543025, -70.700446)
2020-06-02T17:22:54.077Z,1591118574.077 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T17:22:54.077Z,1591118574.077 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T17:22:57.602Z,1591118577.602 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0055.lzma
2020-06-02T17:22:58.604Z,1591118578.604 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0055.lzma.bak
2020-06-02T17:22:58.604Z,1591118578.604 [DataOverHttps](INFO): SBD MOMSN=20501
2020-06-02T17:23:07.642Z,1591118587.642 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20200602T155111/Express0056.lzma
2020-06-02T17:23:08.656Z,1591118588.656 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0056.lzma.bak
2020-06-02T17:23:08.656Z,1591118588.656 [DataOverHttps](INFO): SBD MOMSN=20504
2020-06-02T17:23:09.830Z,1591118589.830 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T17:23:09.830Z,1591118589.830 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T17:23:09.830Z,1591118589.830 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T17:23:22.730Z,1591118602.730 [NAL9602](INFO): SBD MO Status=2, MOMSN=11264, MT Status=2, MTMSN=0
2020-06-02T17:23:22.730Z,1591118602.730 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T17:23:56.268Z,1591118636.268 [NAL9602](INFO): SBD MO Status=2, MOMSN=11264, MT Status=2, MTMSN=0
2020-06-02T17:23:56.268Z,1591118636.268 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T17:24:23.730Z,1591118663.730 [NAL9602](INFO): SBD MO Status=2, MOMSN=11264, MT Status=2, MTMSN=0
2020-06-02T17:24:23.730Z,1591118663.730 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T17:24:52.011Z,1591118692.011 [NAL9602](INFO): SBD MO Status=2, MOMSN=11264, MT Status=2, MTMSN=0
2020-06-02T17:24:52.011Z,1591118692.011 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T17:25:29.587Z,1591118729.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=11264, MT Status=2, MTMSN=0
2020-06-02T17:25:29.587Z,1591118729.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T17:25:43.756Z,1591118743.756 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T17:25:45.359Z,1591118745.359 [NAL9602](INFO): SBD MO Status=0, MOMSN=11264, MT Status=0, MTMSN=0
2020-06-02T17:25:45.359Z,1591118745.359 [NAL9602](INFO): No messages in MT queue
2020-06-02T17:25:49.514Z,1591118749.514 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout
2020-06-02T17:25:49.514Z,1591118749.514 [RDI_PathfinderUp] Communications Fault, FailCount= 1
2020-06-02T17:25:49.514Z,1591118749.514 [RDI_PathfinderUp](ERROR): Communications Fault
2020-06-02T17:25:49.541Z,1591118749.541 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp
2020-06-02T17:25:49.874Z,1591118749.874 [RDI_PathfinderUp](INFO): Powering down
2020-06-02T17:25:50.636Z,1591118750.636 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp
2020-06-02T17:25:50.636Z,1591118750.636 [RDI_PathfinderUp] No Fault, FailCount= 1
2020-06-02T17:26:00.362Z,1591118760.362 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:26:02.371Z,1591118762.371 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.246410
2020-06-02T17:26:16.050Z,1591118776.050 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T17:26:18.923Z,1591118778.923 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T17:26:20.510Z,1591118780.510 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:26:35.507Z,1591118795.507 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:27:10.647Z,1591118830.647 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:27:37.273Z,1591118857.273 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:27:45.786Z,1591118865.786 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:28:10.415Z,1591118890.415 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T17:28:10.415Z,1591118890.415 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T17:28:10.415Z,1591118890.415 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T17:28:10.416Z,1591118890.416 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T17:28:10.800Z,1591118890.800 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T17:28:10.800Z,1591118890.800 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T17:28:11.269Z,1591118891.269 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 93.364974 min 
2020-06-02T17:28:11.269Z,1591118891.269 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T17:28:11.269Z,1591118891.269 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T17:28:11.269Z,1591118891.269 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T17:28:11.269Z,1591118891.269 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T17:28:11.269Z,1591118891.269 [DefaultWithUndock:CheckIn](INFO): Running loop #17
2020-06-02T17:28:11.269Z,1591118891.269 [DefaultWithUndock:CheckIn] Running Loop=17
2020-06-02T17:28:11.269Z,1591118891.269 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T17:28:11.270Z,1591118891.270 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T17:28:13.232Z,1591118893.232 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172812.00,A,4133.15185,N,07042.48658,W,1.438,36.52,020620,,,D*47
2020-06-02T17:28:13.239Z,1591118893.239 [NAL9602](INFO): GPS fix at 20200602T172812: (41.552531, -70.708110)
2020-06-02T17:28:13.289Z,1591118893.289 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T17:28:13.289Z,1591118893.289 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T17:28:20.943Z,1591118900.943 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:28:43.920Z,1591118923.920 [NAL9602](INFO): SBD MO Status=2, MOMSN=11265, MT Status=2, MTMSN=0
2020-06-02T17:28:43.920Z,1591118923.920 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T17:28:56.099Z,1591118936.099 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:28:57.281Z,1591118937.281 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:29:02.090Z,1591118942.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=11265, MT Status=2, MTMSN=0
2020-06-02T17:29:02.090Z,1591118942.090 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-06-02T17:29:19.873Z,1591118959.873 [NAL9602](INFO): SBD MO Status=0, MOMSN=11265, MT Status=0, MTMSN=0
2020-06-02T17:29:19.873Z,1591118959.873 [NAL9602](INFO): No messages in MT queue
2020-06-02T17:29:31.239Z,1591118971.239 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:29:50.568Z,1591118990.568 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T17:30:05.539Z,1591119005.539 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:30:06.379Z,1591119006.379 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:30:41.518Z,1591119041.518 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-06-02T17:30:59.595Z,1591119059.595 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003923
2020-06-02T17:31:03.610Z,1591119063.610 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200602T155111/Courier0058.lzma
2020-06-02T17:31:04.612Z,1591119064.612 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0058.lzma.bak
2020-06-02T17:31:04.612Z,1591119064.612 [DataOverHttps](INFO): SBD MOMSN=20509
2020-06-02T17:31:06.154Z,1591119066.154 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-06-02T17:31:13.650Z,1591119073.650 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20200602T155111/Express0059.lzma
2020-06-02T17:31:14.652Z,1591119074.652 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0059.lzma.bak
2020-06-02T17:31:14.652Z,1591119074.652 [DataOverHttps](INFO): SBD MOMSN=20512
2020-06-02T17:31:15.847Z,1591119075.847 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T17:31:15.847Z,1591119075.847 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T17:31:15.847Z,1591119075.847 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T17:31:25.528Z,1591119085.528 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:34:07.153Z,1591119247.153 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:35:22.698Z,1591119322.698 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:36:16.437Z,1591119376.437 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T17:36:16.437Z,1591119376.437 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T17:36:16.437Z,1591119376.437 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T17:36:16.437Z,1591119376.437 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T17:36:16.837Z,1591119376.837 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T17:36:16.837Z,1591119376.837 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T17:36:17.273Z,1591119377.273 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 101.465576 min 
2020-06-02T17:36:17.273Z,1591119377.273 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T17:36:17.273Z,1591119377.273 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T17:36:17.273Z,1591119377.273 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T17:36:17.273Z,1591119377.273 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T17:36:17.273Z,1591119377.273 [DefaultWithUndock:CheckIn](INFO): Running loop #18
2020-06-02T17:36:17.274Z,1591119377.274 [DefaultWithUndock:CheckIn] Running Loop=18
2020-06-02T17:36:17.274Z,1591119377.274 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T17:36:17.274Z,1591119377.274 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T17:36:19.230Z,1591119379.230 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173618.00,A,4133.14767,N,07042.50885,W,3.596,356.69,020620,,,D*74
2020-06-02T17:36:19.232Z,1591119379.232 [NAL9602](INFO): GPS fix at 20200602T173618: (41.552461, -70.708481)
2020-06-02T17:36:19.310Z,1591119379.310 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T17:36:19.310Z,1591119379.310 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T17:36:22.914Z,1591119382.914 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0061.lzma
2020-06-02T17:36:23.916Z,1591119383.916 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0061.lzma.bak
2020-06-02T17:36:23.916Z,1591119383.916 [DataOverHttps](INFO): SBD MOMSN=20515
2020-06-02T17:36:32.962Z,1591119392.962 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200602T155111/Express0062.lzma
2020-06-02T17:36:33.964Z,1591119393.964 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0062.lzma.bak
2020-06-02T17:36:33.964Z,1591119393.964 [DataOverHttps](INFO): SBD MOMSN=20518
2020-06-02T17:36:35.038Z,1591119395.038 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T17:36:35.038Z,1591119395.038 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T17:36:35.038Z,1591119395.038 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T17:36:48.318Z,1591119408.318 [NAL9602](INFO): SBD MO Status=0, MOMSN=11266, MT Status=0, MTMSN=0
2020-06-02T17:36:48.318Z,1591119408.318 [NAL9602](INFO): No messages in MT queue
2020-06-02T17:37:19.016Z,1591119439.016 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T17:37:50.172Z,1591119470.172 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-06-02T17:39:10.166Z,1591119550.166 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:39:27.523Z,1591119567.523 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:39:50.167Z,1591119590.167 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:40:24.505Z,1591119624.505 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-06-02T17:40:30.547Z,1591119630.547 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:40:49.972Z,1591119649.972 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2020-06-02T17:41:23.497Z,1591119683.497 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:41:35.602Z,1591119695.602 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-02T17:41:35.602Z,1591119695.602 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-06-02T17:41:35.602Z,1591119695.602 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-02T17:41:35.602Z,1591119695.602 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-06-02T17:41:36.014Z,1591119696.014 [DefaultWithUndock:CheckIn:D] Stopped
2020-06-02T17:41:36.014Z,1591119696.014 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-06-02T17:41:36.412Z,1591119696.412 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 106.785197 min 
2020-06-02T17:41:36.413Z,1591119696.413 [DefaultWithUndock:CheckIn:E] Stopped
2020-06-02T17:41:36.413Z,1591119696.413 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-06-02T17:41:36.414Z,1591119696.414 [DefaultWithUndock:CheckIn] Stopped
2020-06-02T17:41:36.415Z,1591119696.415 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-06-02T17:41:36.416Z,1591119696.416 [DefaultWithUndock:CheckIn](INFO): Running loop #19
2020-06-02T17:41:36.416Z,1591119696.416 [DefaultWithUndock:CheckIn] Running Loop=19
2020-06-02T17:41:36.416Z,1591119696.416 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-06-02T17:41:36.416Z,1591119696.416 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-06-02T17:41:38.398Z,1591119698.398 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174137.00,A,4133.15674,N,07042.48699,W,1.166,62.25,020620,,,A*41
2020-06-02T17:41:38.400Z,1591119698.400 [NAL9602](INFO): GPS fix at 20200602T174137: (41.552612, -70.708117)
2020-06-02T17:41:38.448Z,1591119698.448 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-06-02T17:41:38.448Z,1591119698.448 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-06-02T17:41:42.263Z,1591119702.263 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200602T155111/Courier0064.lzma
2020-06-02T17:41:43.264Z,1591119703.264 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Courier0064.lzma.bak
2020-06-02T17:41:43.264Z,1591119703.264 [DataOverHttps](INFO): SBD MOMSN=20522
2020-06-02T17:41:44.497Z,1591119704.497 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-06-02T17:41:52.311Z,1591119712.311 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200602T155111/Express0065.lzma
2020-06-02T17:41:53.304Z,1591119713.304 [DataOverHttps](INFO): Moved sent file to Logs/20200602T155111/Express0065.lzma.bak
2020-06-02T17:41:53.304Z,1591119713.304 [DataOverHttps](INFO): SBD MOMSN=20525
2020-06-02T17:41:54.588Z,1591119714.588 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-06-02T17:41:54.588Z,1591119714.588 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-06-02T17:41:54.588Z,1591119714.588 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-02T17:41:55.766Z,1591119715.766 [NAL9602](INFO): SBD MO Status=0, MOMSN=11267, MT Status=0, MTMSN=0
2020-06-02T17:41:55.766Z,1591119715.766 [NAL9602](INFO): No messages in MT queue
2020-06-02T17:42:26.468Z,1591119746.468 [NAL9602](INFO): Not Powering down - fast GPS
2020-06-02T17:43:04.910Z,1591119784.910 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-06-02T17:43:25.905Z,1591119805.905 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T17:44:41.443Z,1591119881.443 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T17:44:42.262Z,1591119882.262 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-06-02T17:45:05.273Z,1591119905.273 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-06-02T17:45:29.186Z,1591119929.186 [DataOverHttps](IMPORTANT): SBD MTMSN=20200602T174528