2018-07-26T18:01:55.089Z,1532628115.089 [Supervisor](DEBUG): Initializing supervisor.
2018-07-26T18:01:55.092Z,1532628115.092 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-26T18:01:55.093Z,1532628115.093 [SyncHandler](INFO): Protected caller Thread ID is 9418
2018-07-26T18:01:55.094Z,1532628115.094 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-26T18:01:55.095Z,1532628115.095 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-26T18:01:55.095Z,1532628115.095 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9419
2018-07-26T18:01:55.099Z,1532628115.099 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-26T18:01:55.111Z,1532628115.111 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-26T18:01:55.112Z,1532628115.112 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-26T18:01:55.113Z,1532628115.113 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9420
2018-07-26T18:01:55.114Z,1532628115.114 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-26T18:01:55.115Z,1532628115.115 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-26T18:01:55.115Z,1532628115.115 [logger ThreadHandler](INFO): Protected caller Thread ID is 9421
2018-07-26T18:01:55.117Z,1532628115.117 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-26T18:01:55.117Z,1532628115.117 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-26T18:01:55.119Z,1532628115.119 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-26T18:01:55.382Z,1532628115.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-26T18:01:55.383Z,1532628115.383 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-26T18:01:55.527Z,1532628115.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-26T18:01:55.527Z,1532628115.527 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-26T18:01:55.878Z,1532628115.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-26T18:01:55.879Z,1532628115.879 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-26T18:01:57.618Z,1532628117.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-26T18:01:57.619Z,1532628117.619 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-26T18:01:57.701Z,1532628117.701 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-26T18:01:57.904Z,1532628117.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-26T18:01:57.905Z,1532628117.905 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-26T18:01:58.005Z,1532628118.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-26T18:01:58.006Z,1532628118.006 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-26T18:01:58.346Z,1532628118.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-26T18:01:58.347Z,1532628118.347 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-26T18:01:58.783Z,1532628118.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-26T18:01:58.783Z,1532628118.783 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-26T18:01:59.091Z,1532628119.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-26T18:01:59.091Z,1532628119.091 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-26T18:01:59.596Z,1532628119.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-26T18:01:59.596Z,1532628119.596 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-26T18:01:59.805Z,1532628119.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-26T18:01:59.805Z,1532628119.805 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-26T18:01:59.905Z,1532628119.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-26T18:01:59.906Z,1532628119.906 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-26T18:02:00.391Z,1532628120.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-26T18:02:00.392Z,1532628120.392 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-26T18:02:00.500Z,1532628120.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-26T18:02:00.502Z,1532628120.502 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-07-26T18:02:00.503Z,1532628120.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-07-26T18:02:00.737Z,1532628120.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-26T18:02:00.738Z,1532628120.738 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-07-26T18:02:00.837Z,1532628120.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-07-26T18:02:01.005Z,1532628121.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-07-26T18:02:06.926Z,1532628126.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-07-26T18:02:07.014Z,1532628127.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-07-26T18:02:07.147Z,1532628127.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-07-26T18:02:07.314Z,1532628127.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-07-26T18:02:07.426Z,1532628127.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-07-26T18:02:07.512Z,1532628127.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-07-26T18:02:07.597Z,1532628127.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-07-26T18:02:07.786Z,1532628127.786 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-07-26T18:02:07.786Z,1532628127.786 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-07-26T18:02:07.793Z,1532628127.793 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-26T18:02:07.947Z,1532628127.947 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-26T18:02:07.977Z,1532628127.977 [SBIT] Loaded
2018-07-26T18:02:07.977Z,1532628127.977 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-26T18:02:07.978Z,1532628127.978 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-26T18:02:08.006Z,1532628128.006 [IBIT] Loaded
2018-07-26T18:02:08.006Z,1532628128.006 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-26T18:02:08.009Z,1532628128.009 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-26T18:02:08.164Z,1532628128.164 [CBIT] Loaded
2018-07-26T18:02:08.164Z,1532628128.164 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-26T18:02:08.165Z,1532628128.165 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-26T18:02:08.165Z,1532628128.165 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-26T18:02:08.252Z,1532628128.252 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-26T18:02:08.351Z,1532628128.351 [VerticalControl] Loaded
2018-07-26T18:02:08.351Z,1532628128.351 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-26T18:02:08.352Z,1532628128.352 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-26T18:02:08.412Z,1532628128.412 [HorizontalControl] Loaded
2018-07-26T18:02:08.413Z,1532628128.413 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-26T18:02:08.413Z,1532628128.413 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-26T18:02:08.419Z,1532628128.419 [SpeedControl] Loaded
2018-07-26T18:02:08.419Z,1532628128.419 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-26T18:02:08.420Z,1532628128.420 [LoopControl](DEBUG): Construct LoopControl.
2018-07-26T18:02:08.420Z,1532628128.420 [LoopControl] Loaded
2018-07-26T18:02:08.421Z,1532628128.421 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-26T18:02:08.421Z,1532628128.421 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-26T18:02:08.422Z,1532628128.422 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-26T18:02:08.477Z,1532628128.477 [DepthRateCalculator] Loaded
2018-07-26T18:02:08.477Z,1532628128.477 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-26T18:02:08.482Z,1532628128.482 [PitchRateCalculator] Loaded
2018-07-26T18:02:08.483Z,1532628128.483 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-26T18:02:08.499Z,1532628128.499 [SpeedCalculator] Loaded
2018-07-26T18:02:08.499Z,1532628128.499 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-26T18:02:08.520Z,1532628128.520 [TempGradientCalculator] Loaded
2018-07-26T18:02:08.520Z,1532628128.520 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-26T18:02:08.536Z,1532628128.536 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-26T18:02:08.536Z,1532628128.536 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-26T18:02:08.541Z,1532628128.541 [YawRateCalculator] Loaded
2018-07-26T18:02:08.542Z,1532628128.542 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-26T18:02:08.571Z,1532628128.571 [ElevatorOffsetCalculator] Loaded
2018-07-26T18:02:08.571Z,1532628128.571 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-26T18:02:08.571Z,1532628128.571 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-26T18:02:08.572Z,1532628128.572 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-26T18:02:08.694Z,1532628128.694 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-26T18:02:08.694Z,1532628128.694 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-26T18:02:08.694Z,1532628128.694 [StratificationFrontDetector] Loaded
2018-07-26T18:02:08.695Z,1532628128.695 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-26T18:02:08.695Z,1532628128.695 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-26T18:02:08.696Z,1532628128.696 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-26T18:02:08.979Z,1532628128.979 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-26T18:02:08.980Z,1532628128.980 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-26T18:02:09.092Z,1532628129.092 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-07-26T18:02:09.093Z,1532628129.093 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-07-26T18:02:09.147Z,1532628129.147 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-26T18:02:09.147Z,1532628129.147 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-26T18:02:09.164Z,1532628129.164 [NavChart] Loaded
2018-07-26T18:02:09.164Z,1532628129.164 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-26T18:02:09.168Z,1532628129.168 [UniversalFixResidualReporter] Loaded
2018-07-26T18:02:09.168Z,1532628129.168 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-26T18:02:09.169Z,1532628129.169 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-26T18:02:09.170Z,1532628129.170 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-26T18:02:09.194Z,1532628129.194 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-26T18:02:09.194Z,1532628129.194 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-26T18:02:09.396Z,1532628129.396 [Aanderaa_O2] Loaded
2018-07-26T18:02:09.396Z,1532628129.396 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-07-26T18:02:09.446Z,1532628129.446 [WetLabsBB2FL] Loaded
2018-07-26T18:02:09.447Z,1532628129.447 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-26T18:02:09.448Z,1532628129.448 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406D24E0
2018-07-26T18:02:09.448Z,1532628129.448 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9500
2018-07-26T18:02:09.449Z,1532628129.449 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-26T18:02:09.449Z,1532628129.449 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-26T18:02:09.840Z,1532628129.840 [AHRS_M2] Loaded
2018-07-26T18:02:09.840Z,1532628129.840 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-07-26T18:02:09.913Z,1532628129.913 [DataOverHttps] Loaded
2018-07-26T18:02:09.913Z,1532628129.913 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-26T18:02:09.927Z,1532628129.927 [Depth_Keller] Loaded
2018-07-26T18:02:09.927Z,1532628129.927 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-26T18:02:09.932Z,1532628129.932 [DropWeight] Loaded
2018-07-26T18:02:09.932Z,1532628129.932 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-07-26T18:02:10.022Z,1532628130.022 [NAL9602] Loaded
2018-07-26T18:02:10.022Z,1532628130.022 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-26T18:02:10.028Z,1532628130.028 [Onboard] Loaded
2018-07-26T18:02:10.028Z,1532628130.028 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-26T18:02:10.035Z,1532628130.035 [Radio_Surface] Loaded
2018-07-26T18:02:10.035Z,1532628130.035 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-26T18:02:10.036Z,1532628130.036 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409284E0
2018-07-26T18:02:10.037Z,1532628130.037 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9501
2018-07-26T18:02:10.065Z,1532628130.065 [RDI_Pathfinder] Loaded
2018-07-26T18:02:10.066Z,1532628130.066 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-07-26T18:02:10.072Z,1532628130.072 [SCPI] Loaded
2018-07-26T18:02:10.072Z,1532628130.072 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-07-26T18:02:11.879Z,1532628131.879 [BPC1] Loaded
2018-07-26T18:02:11.880Z,1532628131.880 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-26T18:02:11.880Z,1532628131.880 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-26T18:02:11.881Z,1532628131.881 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-26T18:02:12.000Z,1532628132.000 [BuoyancyServo] Loaded
2018-07-26T18:02:12.001Z,1532628132.001 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-26T18:02:12.013Z,1532628132.013 [ElevatorServo] Loaded
2018-07-26T18:02:12.013Z,1532628132.013 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-26T18:02:12.024Z,1532628132.024 [MassServo] Loaded
2018-07-26T18:02:12.024Z,1532628132.024 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-26T18:02:12.035Z,1532628132.035 [RudderServo] Loaded
2018-07-26T18:02:12.036Z,1532628132.036 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-26T18:02:12.047Z,1532628132.047 [ThrusterServo] Loaded
2018-07-26T18:02:12.047Z,1532628132.047 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-26T18:02:12.047Z,1532628132.047 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-26T18:02:12.048Z,1532628132.048 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-26T18:02:12.338Z,1532628132.338 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-26T18:02:12.339Z,1532628132.339 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-26T18:02:12.376Z,1532628132.376 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-26T18:02:12.380Z,1532628132.380 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-26T18:02:12.381Z,1532628132.381 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-26T18:02:12.388Z,1532628132.388 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-26T18:02:12.389Z,1532628132.389 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D14E0
2018-07-26T18:02:12.389Z,1532628132.389 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9502
2018-07-26T18:02:12.394Z,1532628132.394 [Supervisor](INFO): Main Thread ID is 796
2018-07-26T18:02:12.394Z,1532628132.394 [Supervisor](DEBUG): Running supervisor.
2018-07-26T18:02:12.395Z,1532628132.395 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9503
2018-07-26T18:02:12.397Z,1532628132.397 [controlThread ThreadHandler](INFO): Handler Thread ID is 9504
2018-07-26T18:02:12.398Z,1532628132.398 [controlThread](DEBUG): Initializing ControlThread
2018-07-26T18:02:12.398Z,1532628132.398 [SBIT](INFO): Initialize SBIT Component.
2018-07-26T18:02:12.399Z,1532628132.399 [SBIT](IMPORTANT): git: 2018-07-25
2018-07-26T18:02:12.399Z,1532628132.399 [SBIT](INFO): git hash: 2912d14d75d7acdc47d6eebe250915c9e75a6268
2018-07-26T18:02:12.400Z,1532628132.400 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-26T18:02:12.401Z,1532628132.401 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-26T18:02:12.402Z,1532628132.402 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2018-07-26T18:02:12.403Z,1532628132.403 [IBIT](INFO): Initialize IBIT Component.
2018-07-26T18:02:12.404Z,1532628132.404 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-26T18:02:12.404Z,1532628132.404 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-07-26T18:02:12.405Z,1532628132.405 [logger ThreadHandler](INFO): Handler Thread ID is 9505
2018-07-26T18:02:12.433Z,1532628132.433 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9506
2018-07-26T18:02:12.449Z,1532628132.449 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9507
2018-07-26T18:02:12.455Z,1532628132.455 [Radio_Surface](INFO): Powering up
2018-07-26T18:02:12.473Z,1532628132.473 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9508
2018-07-26T18:02:12.482Z,1532628132.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-26T18:02:12.483Z,1532628132.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-26T18:02:12.483Z,1532628132.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-26T18:02:12.483Z,1532628132.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-26T18:02:12.483Z,1532628132.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-26T18:02:12.483Z,1532628132.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-26T18:02:12.484Z,1532628132.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-26T18:02:12.484Z,1532628132.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-26T18:02:12.484Z,1532628132.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-26T18:02:12.484Z,1532628132.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-26T18:02:12.484Z,1532628132.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-26T18:02:12.485Z,1532628132.485 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-26T18:02:12.485Z,1532628132.485 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-26T18:02:12.485Z,1532628132.485 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-26T18:02:12.486Z,1532628132.486 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-26T18:02:12.486Z,1532628132.486 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-26T18:02:12.509Z,1532628132.509 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-26T18:02:12.511Z,1532628132.511 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-26T18:02:12.512Z,1532628132.512 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-26T18:02:12.512Z,1532628132.512 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-26T18:02:12.512Z,1532628132.512 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-26T18:02:12.513Z,1532628132.513 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-26T18:02:12.513Z,1532628132.513 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-26T18:02:12.514Z,1532628132.514 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-26T18:02:12.514Z,1532628132.514 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-26T18:02:12.515Z,1532628132.515 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-26T18:02:12.515Z,1532628132.515 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-26T18:02:12.516Z,1532628132.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-26T18:02:12.517Z,1532628132.517 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:02:12.517Z,1532628132.517 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:02:12.518Z,1532628132.518 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-26T18:02:12.518Z,1532628132.518 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:02:12.519Z,1532628132.519 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:02:12.519Z,1532628132.519 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-26T18:02:12.520Z,1532628132.520 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-26T18:02:12.538Z,1532628132.538 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-26T18:02:12.568Z,1532628132.568 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-26T18:02:12.600Z,1532628132.600 [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>


2018-07-26T18:02:12.625Z,1532628132.625 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-26T18:02:12.701Z,1532628132.701 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-26T18:02:12.702Z,1532628132.702 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-26T18:02:12.704Z,1532628132.704 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-26T18:02:12.774Z,1532628132.774 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-26T18:02:12.793Z,1532628132.793 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-26T18:02:12.799Z,1532628132.799 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-26T18:02:12.818Z,1532628132.818 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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>


2018-07-26T18:02:12.823Z,1532628132.823 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-07-26T18:02:12.908Z,1532628132.908 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-26T18:02:12.929Z,1532628132.929 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-07-26T18:02:12.965Z,1532628132.965 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-07-26T18:02:13.179Z,1532628133.179 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-07-26T18:02:13.279Z,1532628133.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:13.303Z,1532628133.303 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:13.425Z,1532628133.425 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-26T18:02:13.433Z,1532628133.433 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-26T18:02:13.456Z,1532628133.456 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-07-26T18:02:13.465Z,1532628133.465 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-07-26T18:02:13.471Z,1532628133.471 [MassServo](DEBUG): Initializing EZServoServo.
2018-07-26T18:02:13.477Z,1532628133.477 [MassServo](DEBUG): Initializing MassServo.
2018-07-26T18:02:13.499Z,1532628133.499 [RudderServo](DEBUG): Initializing EZServoServo.
2018-07-26T18:02:13.505Z,1532628133.505 [RudderServo](DEBUG): Initializing RudderServo.
2018-07-26T18:02:13.523Z,1532628133.523 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-26T18:02:13.529Z,1532628133.529 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-26T18:02:13.566Z,1532628133.566 [CommandLine](FAULT): Scheduling is paused
2018-07-26T18:02:13.566Z,1532628133.566 [CBIT](INFO): Critical error at 20180726T180212
2018-07-26T18:02:13.567Z,1532628133.567 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-07-26T18:02:13.693Z,1532628133.693 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-07-26T18:02:13.693Z,1532628133.693 [DropWeight] Hardware Fault, FailCount= 1
2018-07-26T18:02:13.694Z,1532628133.694 [DropWeight](ERROR): Hardware Fault
2018-07-26T18:02:13.733Z,1532628133.733 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:13.734Z,1532628133.734 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:13.758Z,1532628133.758 [CBIT](INFO): Critical error at 20180726T180213
2018-07-26T18:02:13.761Z,1532628133.761 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-07-26T18:02:13.762Z,1532628133.762 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-07-26T18:02:14.042Z,1532628134.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:14.043Z,1532628134.043 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:14.377Z,1532628134.377 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-07-26T18:02:14.377Z,1532628134.377 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-07-26T18:02:14.377Z,1532628134.377 [BuoyancyServo] Communications Fault, FailCount= 1
2018-07-26T18:02:14.377Z,1532628134.377 [BuoyancyServo](ERROR): Communications Fault
2018-07-26T18:02:14.587Z,1532628134.587 [CBIT](INFO): Critical error at 20180726T180213
2018-07-26T18:02:14.589Z,1532628134.589 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-26T18:02:14.645Z,1532628134.645 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:14.646Z,1532628134.646 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:14.652Z,1532628134.652 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-26T18:02:14.669Z,1532628134.669 [BuoyancyServo](INFO): Powering down
2018-07-26T18:02:15.065Z,1532628135.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:15.066Z,1532628135.066 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:15.497Z,1532628135.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:15.498Z,1532628135.498 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:15.853Z,1532628135.853 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:15.854Z,1532628135.854 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:16.317Z,1532628136.317 [Aanderaa_O2](INFO): Powering down
2018-07-26T18:02:16.501Z,1532628136.501 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:16.502Z,1532628136.502 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:16.677Z,1532628136.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:16.678Z,1532628136.678 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:16.794Z,1532628136.794 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-26T18:02:16.794Z,1532628136.794 [BuoyancyServo] No Fault, FailCount= 1
2018-07-26T18:02:17.093Z,1532628137.093 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-26T18:02:17.121Z,1532628137.121 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:17.122Z,1532628137.122 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:17.135Z,1532628137.135 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-26T18:02:17.254Z,1532628137.254 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-26T18:02:17.793Z,1532628137.793 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-26T18:02:17.821Z,1532628137.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:17.822Z,1532628137.822 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:18.421Z,1532628138.421 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-26T18:02:18.451Z,1532628138.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:18.452Z,1532628138.452 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:18.845Z,1532628138.845 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-26T18:02:18.874Z,1532628138.874 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:18.875Z,1532628138.875 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:19.258Z,1532628139.258 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-26T18:02:19.294Z,1532628139.294 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:19.295Z,1532628139.295 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:19.677Z,1532628139.677 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-26T18:02:19.718Z,1532628139.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:19.719Z,1532628139.719 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:19.879Z,1532628139.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:19.880Z,1532628139.880 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:20.250Z,1532628140.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:20.251Z,1532628140.251 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:20.626Z,1532628140.626 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-07-26T18:02:20.626Z,1532628140.626 [AHRS_M2] Communications Fault, FailCount= 1
2018-07-26T18:02:20.626Z,1532628140.626 [AHRS_M2](ERROR): Communications Fault
2018-07-26T18:02:20.654Z,1532628140.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:20.655Z,1532628140.655 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:20.814Z,1532628140.814 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-07-26T18:02:20.998Z,1532628140.998 [AHRS_M2](INFO): Powering down
2018-07-26T18:02:21.122Z,1532628141.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:21.123Z,1532628141.123 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:21.426Z,1532628141.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:21.427Z,1532628141.427 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:21.826Z,1532628141.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:21.827Z,1532628141.827 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:22.226Z,1532628142.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:22.227Z,1532628142.227 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:22.626Z,1532628142.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:22.628Z,1532628142.628 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:23.026Z,1532628143.026 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:23.027Z,1532628143.027 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:23.426Z,1532628143.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:23.427Z,1532628143.427 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:23.826Z,1532628143.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:23.827Z,1532628143.827 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:23.866Z,1532628143.866 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-07-26T18:02:23.866Z,1532628143.866 [AHRS_M2] No Fault, FailCount= 1
2018-07-26T18:02:24.199Z,1532628144.199 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-26T18:02:24.238Z,1532628144.238 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:24.239Z,1532628144.239 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:24.626Z,1532628144.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:24.627Z,1532628144.627 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:25.037Z,1532628145.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:25.038Z,1532628145.038 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:25.430Z,1532628145.430 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:25.431Z,1532628145.431 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:25.830Z,1532628145.830 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:25.831Z,1532628145.831 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:26.230Z,1532628146.230 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:26.231Z,1532628146.231 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:26.659Z,1532628146.659 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-26T18:02:26.688Z,1532628146.688 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:26.702Z,1532628146.702 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:27.083Z,1532628147.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:27.084Z,1532628147.084 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:27.485Z,1532628147.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:27.486Z,1532628147.486 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:27.934Z,1532628147.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:27.935Z,1532628147.935 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:28.262Z,1532628148.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:28.263Z,1532628148.263 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:28.678Z,1532628148.678 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:28.679Z,1532628148.679 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:29.062Z,1532628149.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:29.063Z,1532628149.063 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:29.486Z,1532628149.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:29.487Z,1532628149.487 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:29.894Z,1532628149.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:29.895Z,1532628149.895 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:30.230Z,1532628150.230 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:30.231Z,1532628150.231 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:30.646Z,1532628150.646 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:30.647Z,1532628150.647 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:31.063Z,1532628151.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:31.064Z,1532628151.064 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-26T18:02:40.227Z,1532628160.227 [NAL9602](INFO): Powering up NAL9602
2018-07-26T18:02:51.406Z,1532628171.406 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:03:11.907Z,1532628191.907 [SBIT](IMPORTANT): Beginning Startup BIT
2018-07-26T18:03:11.919Z,1532628191.919 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-26T18:03:22.927Z,1532628202.927 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006373
CHAN A1 (24V): -0.011508
CHAN A2 (12V): -0.007445
CHAN A3 (5V): -0.003481
CHAN B0 (3.3V): -0.000313
CHAN B1 (3.15aV): 0.000124
CHAN B2 (3.15bV): 0.000181
CHAN B3 (GND): 0.001721
OPEN: 0.005185
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-26T18:04:05.961Z,1532628245.961 [SBIT](IMPORTANT): SBIT PASSED
2018-07-26T18:04:06.034Z,1532628246.034 [CommandLine](IMPORTANT): got command configSet list
2018-07-26T18:04:06.034Z,1532628246.034 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-07-26T18:04:06.035Z,1532628246.035 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-07-26T18:04:06.035Z,1532628246.035 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter;

2018-07-26T18:04:06.036Z,1532628246.036 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 centimeter;

2018-07-26T18:04:06.345Z,1532628246.345 [MissionManager](IMPORTANT): Started mission Startup
2018-07-26T18:04:06.346Z,1532628246.346 [Startup] Running Loop=1
2018-07-26T18:04:06.346Z,1532628246.346 [Startup](DEBUG): Aggregate::initialize Startup
2018-07-26T18:04:06.346Z,1532628246.346 [Startup:A.GoToSurface] Running Loop=1
2018-07-26T18:04:06.346Z,1532628246.346 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-26T18:04:06.347Z,1532628246.347 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-26T18:04:06.347Z,1532628246.347 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-26T18:04:06.348Z,1532628246.348 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-26T18:04:06.348Z,1532628246.348 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-26T18:04:06.348Z,1532628246.348 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-26T18:04:06.356Z,1532628246.356 [Startup:StartupSatComms] Running Loop=1
2018-07-26T18:04:06.356Z,1532628246.356 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-07-26T18:04:06.356Z,1532628246.356 [Startup:StartupSatComms:A] Running Loop=1
2018-07-26T18:04:06.757Z,1532628246.757 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-07-26T18:05:05.904Z,1532628305.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:05:05.904Z,1532628305.904 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:05:06.368Z,1532628306.368 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-26T18:04:06.4Z
2018-07-26T18:05:06.368Z,1532628306.368 [Startup:StartupSatComms:A] Stopped
2018-07-26T18:05:06.368Z,1532628306.368 [Startup:StartupSatComms:B] Running Loop=1
2018-07-26T18:05:06.761Z,1532628306.761 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-26T18:05:12.764Z,1532628312.764 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:05:12.769Z,1532628312.769 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-07-26T18:05:12.769Z,1532628312.769 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-26T18:05:12.771Z,1532628312.771 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:05:12.771Z,1532628312.771 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-07-26T18:05:12.771Z,1532628312.771 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-26T18:05:12.813Z,1532628312.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-26T18:05:12.813Z,1532628312.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-26T18:05:13.173Z,1532628313.173 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-26T18:05:13.173Z,1532628313.173 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-07-26T18:05:13.174Z,1532628313.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-26T18:05:13.174Z,1532628313.174 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-07-26T18:05:13.573Z,1532628313.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-26T18:05:13.573Z,1532628313.573 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:05:13.574Z,1532628313.574 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:05:13.574Z,1532628313.574 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-26T18:05:13.574Z,1532628313.574 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:05:13.575Z,1532628313.575 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:05:16.768Z,1532628316.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-26T18:05:16.891Z,1532628316.891 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2018-07-26T18:05:17.239Z,1532628317.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-07-26T18:05:17.598Z,1532628317.598 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004226
2018-07-26T18:05:17.664Z,1532628317.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2018-07-26T18:05:18.059Z,1532628318.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-07-26T18:05:18.458Z,1532628318.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-07-26T18:05:21.647Z,1532628321.647 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2018-07-26T18:05:21.675Z,1532628321.675 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20180726T023329/Courier0276.lzma
2018-07-26T18:05:21.767Z,1532628321.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.0 s old, using for 20.0 s.
2018-07-26T18:05:22.119Z,1532628322.119 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.4 s old, using for 20.0 s.
2018-07-26T18:05:22.475Z,1532628322.475 [DataOverHttps](INFO): Moved sent file to Logs/20180726T023329/Courier0276.lzma.bak
2018-07-26T18:05:22.476Z,1532628322.476 [DataOverHttps](INFO): SBD MOMSN=8378037
2018-07-26T18:05:22.620Z,1532628322.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s.
2018-07-26T18:05:22.943Z,1532628322.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2018-07-26T18:05:23.319Z,1532628323.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2018-07-26T18:05:23.768Z,1532628323.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2018-07-26T18:05:24.135Z,1532628324.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2018-07-26T18:05:27.312Z,1532628327.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2018-07-26T18:05:27.412Z,1532628327.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.6 s old, using for 20.0 s.
2018-07-26T18:05:27.819Z,1532628327.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.1 s old, using for 20.0 s.
2018-07-26T18:05:28.203Z,1532628328.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2018-07-26T18:05:28.618Z,1532628328.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2018-07-26T18:05:28.000Z,1532628329.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.2 s old, using for 20.0 s.
2018-07-26T18:05:32.203Z,1532628332.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.4 s old, using for 20.0 s.
2018-07-26T18:05:32.260Z,1532628332.260 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180726T180155/Courier0000.lzma
2018-07-26T18:05:32.295Z,1532628332.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2018-07-26T18:05:32.711Z,1532628332.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2018-07-26T18:05:33.046Z,1532628333.046 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0000.lzma.bak
2018-07-26T18:05:33.046Z,1532628333.046 [DataOverHttps](INFO): SBD MOMSN=8378040
2018-07-26T18:05:33.084Z,1532628333.084 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:05:33.085Z,1532628333.085 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:05:44.832Z,1532628344.832 [DataOverHttps](INFO): Sending 383 bytes from file Logs/20180726T023329/Express0277.lzma
2018-07-26T18:05:45.600Z,1532628345.600 [DataOverHttps](INFO): Moved sent file to Logs/20180726T023329/Express0277.lzma.bak
2018-07-26T18:05:45.600Z,1532628345.600 [DataOverHttps](INFO): SBD MOMSN=8378046
2018-07-26T18:05:50.501Z,1532628350.501 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:05:50.501Z,1532628350.501 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:05:54.527Z,1532628354.527 [DataOverHttps](INFO): Sending 777 bytes from file Logs/20180726T180155/Express0001.lzma
2018-07-26T18:05:55.299Z,1532628355.299 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0001.lzma.bak
2018-07-26T18:05:55.299Z,1532628355.299 [DataOverHttps](INFO): SBD MOMSN=8378059
2018-07-26T18:05:55.804Z,1532628355.804 [Startup:StartupSatComms:B] Stopped
2018-07-26T18:05:55.805Z,1532628355.805 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-07-26T18:05:55.805Z,1532628355.805 [Startup:StartupSatComms] Stopped
2018-07-26T18:05:55.805Z,1532628355.805 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-07-26T18:05:55.807Z,1532628355.807 [Startup](INFO): Completed Startup
2018-07-26T18:05:55.808Z,1532628355.808 [MissionManager](INFO): Startup is completed.
2018-07-26T18:05:55.808Z,1532628355.808 [MissionManager](INFO): Uninitializing Mission Startup
2018-07-26T18:05:55.808Z,1532628355.808 [Startup] Stopped
2018-07-26T18:05:55.808Z,1532628355.808 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-07-26T18:05:55.809Z,1532628355.809 [Startup:A.GoToSurface] Stopped
2018-07-26T18:05:55.809Z,1532628355.809 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-26T18:05:56.153Z,1532628356.153 [MissionManager](IMPORTANT): Started mission Default
2018-07-26T18:05:56.153Z,1532628356.153 [Default] Running Loop=1
2018-07-26T18:05:56.153Z,1532628356.153 [Default](DEBUG): Aggregate::initialize Default
2018-07-26T18:05:56.154Z,1532628356.154 [Default:B.GoToSurface] Running Loop=1
2018-07-26T18:05:56.154Z,1532628356.154 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-26T18:05:56.154Z,1532628356.154 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-26T18:05:56.154Z,1532628356.154 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-26T18:05:56.155Z,1532628356.155 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-26T18:05:56.155Z,1532628356.155 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-26T18:05:56.155Z,1532628356.155 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-26T18:05:56.156Z,1532628356.156 [Default:A.Wait] Running Loop=1
2018-07-26T18:05:56.156Z,1532628356.156 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-26T18:06:09.426Z,1532628369.426 [Default:A.Wait](INFO): Done Waiting.
2018-07-26T18:06:09.426Z,1532628369.426 [Default:A.Wait] Stopped
2018-07-26T18:06:09.426Z,1532628369.426 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T18:06:09.835Z,1532628369.835 [Default:CheckIn] Running Loop=1
2018-07-26T18:06:09.835Z,1532628369.835 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T18:06:09.835Z,1532628369.835 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T18:06:10.229Z,1532628370.229 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-26T18:06:36.184Z,1532628396.184 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:06:36.184Z,1532628396.184 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:06:53.787Z,1532628413.787 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:06:53.788Z,1532628413.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:07:25.448Z,1532628445.448 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:07:25.448Z,1532628445.448 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:07:54.650Z,1532628474.650 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T18:08:00.734Z,1532628480.734 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-26T18:08:13.972Z,1532628493.972 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:08:13.972Z,1532628493.972 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-07-26T18:08:13.972Z,1532628493.972 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-26T18:08:13.974Z,1532628493.974 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:08:13.974Z,1532628493.974 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-07-26T18:08:13.974Z,1532628493.974 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-26T18:08:13.987Z,1532628493.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-26T18:08:13.988Z,1532628493.988 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-26T18:08:14.378Z,1532628494.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-26T18:08:14.378Z,1532628494.378 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-07-26T18:08:14.378Z,1532628494.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-26T18:08:14.378Z,1532628494.378 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-07-26T18:08:14.739Z,1532628494.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-26T18:08:14.740Z,1532628494.740 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:08:14.740Z,1532628494.740 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:08:14.740Z,1532628494.740 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-26T18:08:14.761Z,1532628494.761 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:08:14.761Z,1532628494.761 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:08:15.169Z,1532628495.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-26T18:08:15.560Z,1532628495.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-26T18:08:15.972Z,1532628495.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-26T18:08:16.350Z,1532628496.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-26T18:08:16.789Z,1532628496.789 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-26T18:08:17.176Z,1532628497.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-26T18:08:17.586Z,1532628497.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-26T18:08:17.956Z,1532628497.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-26T18:08:18.359Z,1532628498.359 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-26T18:08:18.791Z,1532628498.791 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-26T18:08:19.145Z,1532628499.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-26T18:08:19.583Z,1532628499.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-26T18:08:19.972Z,1532628499.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-26T18:08:20.368Z,1532628500.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-26T18:08:20.770Z,1532628500.770 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-26T18:08:21.139Z,1532628501.139 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-26T18:08:21.567Z,1532628501.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-26T18:08:21.968Z,1532628501.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-26T18:08:22.346Z,1532628502.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-26T18:08:22.774Z,1532628502.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-26T18:08:23.171Z,1532628503.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-26T18:08:23.551Z,1532628503.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-26T18:08:23.952Z,1532628503.952 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-26T18:08:24.356Z,1532628504.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-26T18:08:24.748Z,1532628504.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-26T18:08:25.155Z,1532628505.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-26T18:08:25.551Z,1532628505.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-26T18:08:25.975Z,1532628505.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-26T18:08:26.368Z,1532628506.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-26T18:08:26.767Z,1532628506.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-26T18:08:27.155Z,1532628507.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-26T18:08:27.563Z,1532628507.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-26T18:08:27.955Z,1532628507.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-26T18:08:28.359Z,1532628508.359 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-26T18:08:28.777Z,1532628508.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-26T18:08:29.171Z,1532628509.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-26T18:08:29.562Z,1532628509.562 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-07-26T18:08:29.959Z,1532628509.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-07-26T18:08:30.375Z,1532628510.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-07-26T18:08:30.792Z,1532628510.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-26T18:08:31.171Z,1532628511.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-26T18:08:31.611Z,1532628511.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-07-26T18:08:31.966Z,1532628511.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-07-26T18:08:32.370Z,1532628512.370 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-26T18:08:32.775Z,1532628512.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-26T18:08:33.152Z,1532628513.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-26T18:08:33.544Z,1532628513.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-26T18:08:33.955Z,1532628513.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-07-26T18:11:10.170Z,1532628670.170 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T18:06:09.8Z
2018-07-26T18:11:10.170Z,1532628670.170 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T18:11:10.170Z,1532628670.170 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T18:11:10.582Z,1532628670.582 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-26T18:11:14.624Z,1532628674.624 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180726T180155/Courier0004.lzma
2018-07-26T18:11:15.056Z,1532628675.056 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:11:15.057Z,1532628675.057 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-07-26T18:11:15.057Z,1532628675.057 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-26T18:11:15.059Z,1532628675.059 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:11:15.059Z,1532628675.059 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-07-26T18:11:15.059Z,1532628675.059 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-26T18:11:15.088Z,1532628675.088 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-26T18:11:15.093Z,1532628675.093 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-26T18:11:15.402Z,1532628675.402 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0004.lzma.bak
2018-07-26T18:11:15.402Z,1532628675.402 [DataOverHttps](INFO): SBD MOMSN=8378077
2018-07-26T18:11:15.471Z,1532628675.471 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-26T18:11:15.471Z,1532628675.471 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-07-26T18:11:15.472Z,1532628675.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-26T18:11:15.472Z,1532628675.472 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-07-26T18:11:15.916Z,1532628675.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-26T18:11:15.916Z,1532628675.916 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:11:15.917Z,1532628675.917 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:11:15.917Z,1532628675.917 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-26T18:11:15.918Z,1532628675.918 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:11:15.918Z,1532628675.918 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:11:16.256Z,1532628676.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-26T18:11:16.670Z,1532628676.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-26T18:11:17.061Z,1532628677.061 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-26T18:11:20.261Z,1532628680.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-26T18:11:20.356Z,1532628680.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-07-26T18:11:20.746Z,1532628680.746 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-07-26T18:11:21.127Z,1532628681.127 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2018-07-26T18:11:21.556Z,1532628681.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2018-07-26T18:11:21.939Z,1532628681.939 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2018-07-26T18:11:25.120Z,1532628685.120 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2018-07-26T18:11:25.177Z,1532628685.177 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180726T180155/Express0005.lzma
2018-07-26T18:11:25.239Z,1532628685.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2018-07-26T18:11:25.618Z,1532628685.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2018-07-26T18:11:26.093Z,1532628686.093 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0005.lzma.bak
2018-07-26T18:11:26.093Z,1532628686.093 [DataOverHttps](INFO): SBD MOMSN=8378079
2018-07-26T18:11:26.196Z,1532628686.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.1 s old, using for 20.0 s.
2018-07-26T18:11:26.441Z,1532628686.441 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2018-07-26T18:11:26.444Z,1532628686.444 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T18:11:26.444Z,1532628686.444 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T18:11:26.444Z,1532628686.444 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T18:11:26.803Z,1532628686.803 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-07-26T18:11:29.994Z,1532628689.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-07-26T18:11:30.079Z,1532628690.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2018-07-26T18:11:30.500Z,1532628690.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2018-07-26T18:11:30.871Z,1532628690.871 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.8 s old, using for 20.0 s.
2018-07-26T18:11:31.291Z,1532628691.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.2 s old, using for 20.0 s.
2018-07-26T18:11:31.688Z,1532628691.688 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.6 s old, using for 20.0 s.
2018-07-26T18:11:32.071Z,1532628692.071 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.0 s old, using for 20.0 s.
2018-07-26T18:11:32.462Z,1532628692.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.4 s old, using for 20.0 s.
2018-07-26T18:11:32.880Z,1532628692.880 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s.
2018-07-26T18:11:33.269Z,1532628693.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.2 s old, using for 20.0 s.
2018-07-26T18:11:33.692Z,1532628693.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.6 s old, using for 20.0 s.
2018-07-26T18:11:34.087Z,1532628694.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s.
2018-07-26T18:11:34.476Z,1532628694.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.4 s old, using for 20.0 s.
2018-07-26T18:11:34.882Z,1532628694.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2018-07-26T18:12:53.742Z,1532628773.742 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T18:12:53.742Z,1532628773.742 [NAL9602] Data Fault, FailCount= 1
2018-07-26T18:12:53.743Z,1532628773.743 [NAL9602](ERROR): Data Fault
2018-07-26T18:12:53.829Z,1532628773.829 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T18:12:54.146Z,1532628774.146 [NAL9602](INFO): Powering down
2018-07-26T18:12:55.024Z,1532628775.024 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T18:12:55.024Z,1532628775.024 [NAL9602] No Fault, FailCount= 1
2018-07-26T18:13:24.150Z,1532628804.150 [NAL9602](INFO): Powering up NAL9602
2018-07-26T18:13:35.038Z,1532628815.038 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:14:16.280Z,1532628856.280 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:14:16.280Z,1532628856.280 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-07-26T18:14:16.280Z,1532628856.280 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-26T18:14:16.282Z,1532628856.282 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:14:16.282Z,1532628856.282 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-07-26T18:14:16.282Z,1532628856.282 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-26T18:14:16.324Z,1532628856.324 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-26T18:14:16.324Z,1532628856.324 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-26T18:14:16.720Z,1532628856.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-26T18:14:16.720Z,1532628856.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-07-26T18:14:16.720Z,1532628856.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-26T18:14:16.720Z,1532628856.720 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-07-26T18:14:17.081Z,1532628857.081 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-26T18:14:17.081Z,1532628857.081 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:14:17.082Z,1532628857.082 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:14:17.082Z,1532628857.082 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-26T18:14:17.083Z,1532628857.083 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:14:17.083Z,1532628857.083 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:14:17.514Z,1532628857.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-26T18:14:17.882Z,1532628857.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-26T18:14:18.280Z,1532628858.280 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-26T18:14:18.699Z,1532628858.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-26T18:14:19.084Z,1532628859.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-26T18:14:19.501Z,1532628859.501 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-26T18:14:19.895Z,1532628859.895 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-26T18:14:20.292Z,1532628860.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-26T18:14:20.691Z,1532628860.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-26T18:14:21.075Z,1532628861.075 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-26T18:14:21.499Z,1532628861.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-26T18:14:21.885Z,1532628861.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-26T18:14:22.283Z,1532628862.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-26T18:14:22.695Z,1532628862.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-26T18:14:23.083Z,1532628863.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-26T18:14:23.495Z,1532628863.495 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-26T18:14:23.889Z,1532628863.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-26T18:14:24.282Z,1532628864.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-26T18:14:24.695Z,1532628864.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-26T18:14:25.099Z,1532628865.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-26T18:14:25.491Z,1532628865.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-26T18:14:25.899Z,1532628865.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-26T18:14:26.283Z,1532628866.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-26T18:14:26.690Z,1532628866.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-26T18:14:27.082Z,1532628867.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-26T18:14:30.281Z,1532628870.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-26T18:14:30.387Z,1532628870.387 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2018-07-26T18:14:30.805Z,1532628870.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2018-07-26T18:14:31.168Z,1532628871.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-07-26T18:14:31.588Z,1532628871.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2018-07-26T18:14:31.971Z,1532628871.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-07-26T18:14:32.392Z,1532628872.392 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-07-26T18:14:32.791Z,1532628872.791 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2018-07-26T18:14:33.194Z,1532628873.194 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2018-07-26T18:14:33.564Z,1532628873.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2018-07-26T18:14:33.977Z,1532628873.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2018-07-26T18:14:34.369Z,1532628874.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2018-07-26T18:14:34.777Z,1532628874.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2018-07-26T18:14:35.176Z,1532628875.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2018-07-26T18:14:35.579Z,1532628875.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2018-07-26T18:14:35.983Z,1532628875.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2018-07-26T18:16:26.827Z,1532628986.827 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T18:16:26.827Z,1532628986.827 [Default:CheckIn:C.Wait] Stopped
2018-07-26T18:16:26.827Z,1532628986.827 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T18:16:26.828Z,1532628986.828 [Default:CheckIn:D] Running Loop=1
2018-07-26T18:16:27.220Z,1532628987.220 [Default:CheckIn:D] Stopped
2018-07-26T18:16:27.220Z,1532628987.220 [Default:CheckIn:E] Running Loop=1
2018-07-26T18:16:30.407Z,1532628990.407 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.517740 min 
2018-07-26T18:16:30.410Z,1532628990.410 [Default:CheckIn:E] Stopped
2018-07-26T18:16:30.411Z,1532628990.411 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T18:16:30.411Z,1532628990.411 [Default:CheckIn] Stopped
2018-07-26T18:16:30.411Z,1532628990.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T18:16:30.412Z,1532628990.412 [Default:CheckIn](INFO): Running loop #2
2018-07-26T18:16:30.412Z,1532628990.412 [Default:CheckIn] Running Loop=2
2018-07-26T18:16:30.412Z,1532628990.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T18:16:30.412Z,1532628990.412 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T18:17:13.729Z,1532629033.729 [CBIT](INFO): Clearing failed state for component DropWeight
2018-07-26T18:17:13.729Z,1532629033.729 [DropWeight] No Fault, FailCount= 1
2018-07-26T18:17:17.276Z,1532629037.276 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:17:17.276Z,1532629037.276 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2018-07-26T18:17:17.276Z,1532629037.276 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-26T18:17:17.294Z,1532629037.294 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:17:17.294Z,1532629037.294 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-07-26T18:17:17.294Z,1532629037.294 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-26T18:17:17.311Z,1532629037.311 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-26T18:17:17.312Z,1532629037.312 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-26T18:17:17.710Z,1532629037.710 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-26T18:17:17.711Z,1532629037.711 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2018-07-26T18:17:17.711Z,1532629037.711 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-26T18:17:17.711Z,1532629037.711 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-07-26T18:17:18.100Z,1532629038.100 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-26T18:17:18.105Z,1532629038.105 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:17:18.105Z,1532629038.105 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:17:18.106Z,1532629038.106 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-26T18:17:18.106Z,1532629038.106 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:17:18.107Z,1532629038.107 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:17:18.521Z,1532629038.521 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-26T18:17:18.883Z,1532629038.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-26T18:17:19.285Z,1532629039.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-26T18:17:19.691Z,1532629039.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-26T18:17:20.079Z,1532629040.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-26T18:17:20.524Z,1532629040.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-26T18:17:20.885Z,1532629040.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-26T18:17:21.283Z,1532629041.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-26T18:17:21.690Z,1532629041.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-26T18:17:22.083Z,1532629042.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-26T18:17:22.486Z,1532629042.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-26T18:17:22.893Z,1532629042.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-26T18:17:23.279Z,1532629043.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-26T18:17:23.704Z,1532629043.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-26T18:17:24.097Z,1532629044.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-26T18:17:24.499Z,1532629044.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-26T18:17:24.889Z,1532629044.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-26T18:17:25.283Z,1532629045.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-26T18:17:25.689Z,1532629045.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-26T18:17:26.091Z,1532629046.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-26T18:17:26.539Z,1532629046.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2018-07-26T18:17:26.899Z,1532629046.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-26T18:17:27.292Z,1532629047.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-26T18:17:30.485Z,1532629050.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-26T18:17:30.571Z,1532629050.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2018-07-26T18:17:30.991Z,1532629050.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2018-07-26T18:17:31.393Z,1532629051.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2018-07-26T18:17:31.785Z,1532629051.785 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 16.061, 0.029, 65.535
2018-07-26T18:17:31.815Z,1532629051.815 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2018-07-26T18:17:32.171Z,1532629052.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-07-26T18:17:32.593Z,1532629052.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2018-07-26T18:17:32.967Z,1532629052.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-07-26T18:17:33.373Z,1532629053.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-07-26T18:17:33.783Z,1532629053.783 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2018-07-26T18:17:34.175Z,1532629054.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2018-07-26T18:17:34.575Z,1532629054.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2018-07-26T18:17:34.983Z,1532629054.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2018-07-26T18:17:35.379Z,1532629055.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2018-07-26T18:17:35.817Z,1532629055.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2018-07-26T18:17:36.192Z,1532629056.192 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2018-07-26T18:17:36.590Z,1532629056.590 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2018-07-26T18:17:36.975Z,1532629056.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2018-07-26T18:20:18.367Z,1532629218.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:20:18.367Z,1532629218.367 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2018-07-26T18:20:18.368Z,1532629218.368 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-26T18:20:18.370Z,1532629218.370 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-26T18:20:18.370Z,1532629218.370 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-07-26T18:20:18.370Z,1532629218.370 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-26T18:20:18.391Z,1532629218.391 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-26T18:20:18.391Z,1532629218.391 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-26T18:20:18.805Z,1532629218.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-26T18:20:18.805Z,1532629218.805 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2018-07-26T18:20:18.806Z,1532629218.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-26T18:20:18.806Z,1532629218.806 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-07-26T18:20:19.168Z,1532629219.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-26T18:20:19.168Z,1532629219.168 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:20:19.168Z,1532629219.168 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:20:19.169Z,1532629219.169 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-26T18:20:19.169Z,1532629219.169 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-26T18:20:19.170Z,1532629219.170 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-26T18:20:19.563Z,1532629219.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-26T18:20:19.944Z,1532629219.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-26T18:20:20.351Z,1532629220.351 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-26T18:20:20.775Z,1532629220.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-26T18:20:21.151Z,1532629221.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-26T18:20:21.587Z,1532629221.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-26T18:20:21.920Z,1532629221.920 [NAL9602](INFO): GPS fix at 20180726T182023: (36.803001, -121.789632)
2018-07-26T18:20:21.971Z,1532629221.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-26T18:20:21.991Z,1532629221.991 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T18:20:21.991Z,1532629221.991 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T18:20:22.399Z,1532629222.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-26T18:20:22.836Z,1532629222.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-07-26T18:20:23.145Z,1532629223.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-26T18:20:28.245Z,1532629228.245 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s.
2018-07-26T18:20:28.360Z,1532629228.360 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180726T180155/Courier0007.lzma
2018-07-26T18:20:28.420Z,1532629228.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2018-07-26T18:20:28.780Z,1532629228.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-26T18:20:29.115Z,1532629229.115 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0007.lzma.bak
2018-07-26T18:20:29.116Z,1532629229.116 [DataOverHttps](INFO): SBD MOMSN=8378084
2018-07-26T18:20:29.183Z,1532629229.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-26T18:20:29.675Z,1532629229.675 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2018-07-26T18:20:29.947Z,1532629229.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-26T18:20:30.371Z,1532629230.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-26T18:20:30.766Z,1532629230.766 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-26T18:20:31.159Z,1532629231.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-26T18:20:31.578Z,1532629231.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-26T18:20:31.963Z,1532629231.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-26T18:20:32.344Z,1532629232.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-26T18:20:35.837Z,1532629235.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2018-07-26T18:20:35.951Z,1532629235.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-07-26T18:20:36.379Z,1532629236.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-07-26T18:20:36.788Z,1532629236.788 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-26T18:20:37.163Z,1532629237.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-26T18:20:37.602Z,1532629237.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-26T18:20:41.406Z,1532629241.406 [DataOverHttps](INFO): Sending 298 bytes from file Logs/20180726T180155/Express0008.lzma
2018-07-26T18:20:42.180Z,1532629242.180 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0008.lzma.bak
2018-07-26T18:20:42.180Z,1532629242.180 [DataOverHttps](INFO): SBD MOMSN=8378087
2018-07-26T18:20:42.769Z,1532629242.769 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T18:20:42.769Z,1532629242.769 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T18:20:42.769Z,1532629242.769 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T18:20:58.587Z,1532629258.587 [NAL9602](INFO): Powering down
2018-07-26T18:22:59.481Z,1532629379.481 [CommandLine](IMPORTANT): got command failComponent
2018-07-26T18:22:59.482Z,1532629379.482 [CommandLine](IMPORTANT): Failed components:
2018-07-26T18:22:59.482Z,1532629379.482 [CommandLine](IMPORTANT): No failed Components.
2018-07-26T18:25:43.137Z,1532629543.137 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T18:25:43.137Z,1532629543.137 [Default:CheckIn:C.Wait] Stopped
2018-07-26T18:25:43.138Z,1532629543.138 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T18:25:43.138Z,1532629543.138 [Default:CheckIn:D] Running Loop=1
2018-07-26T18:25:43.534Z,1532629543.534 [Default:CheckIn:D] Stopped
2018-07-26T18:25:43.534Z,1532629543.534 [Default:CheckIn:E] Running Loop=1
2018-07-26T18:25:43.961Z,1532629543.961 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.789669 min 
2018-07-26T18:25:43.961Z,1532629543.961 [Default:CheckIn:E] Stopped
2018-07-26T18:25:43.962Z,1532629543.962 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T18:25:43.962Z,1532629543.962 [Default:CheckIn] Stopped
2018-07-26T18:25:43.962Z,1532629543.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T18:25:43.962Z,1532629543.962 [Default:CheckIn](INFO): Running loop #3
2018-07-26T18:25:43.962Z,1532629543.962 [Default:CheckIn] Running Loop=3
2018-07-26T18:25:43.962Z,1532629543.962 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T18:25:43.962Z,1532629543.962 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T18:25:44.686Z,1532629544.686 [NAL9602](INFO): Powering up
2018-07-26T18:25:55.764Z,1532629555.764 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:27:34.292Z,1532629654.292 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:27:34.292Z,1532629654.292 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:28:11.712Z,1532629691.712 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:28:11.713Z,1532629691.713 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:30:44.125Z,1532629844.125 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T18:25:43.0Z
2018-07-26T18:30:44.126Z,1532629844.126 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T18:30:44.126Z,1532629844.126 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T18:30:48.236Z,1532629848.236 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180726T180155/Courier0010.lzma
2018-07-26T18:30:49.008Z,1532629849.008 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0010.lzma.bak
2018-07-26T18:30:49.008Z,1532629849.008 [DataOverHttps](INFO): SBD MOMSN=8378091
2018-07-26T18:31:00.268Z,1532629860.268 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T18:31:00.530Z,1532629860.530 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20180726T180155/Express0011.lzma
2018-07-26T18:31:01.316Z,1532629861.316 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0011.lzma.bak
2018-07-26T18:31:01.316Z,1532629861.316 [DataOverHttps](INFO): SBD MOMSN=8378093
2018-07-26T18:31:01.790Z,1532629861.790 [RDI_Pathfinder](ERROR): Failed to parse:

2018-07-26T18:31:01.831Z,1532629861.831 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T18:31:01.831Z,1532629861.831 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T18:31:01.831Z,1532629861.831 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T18:31:30.656Z,1532629890.656 [NAL9602](INFO): Powering down
2018-07-26T18:33:27.839Z,1532630007.839 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-07-26T18:33:27.839Z,1532630007.839 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18072618343780,35.0, -0.1,   0.0, 0
2018-07-26T18:34:10.744Z,1532630050.744 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-26T18:36:02.543Z,1532630162.543 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T18:36:02.543Z,1532630162.543 [Default:CheckIn:C.Wait] Stopped
2018-07-26T18:36:02.543Z,1532630162.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T18:36:02.544Z,1532630162.544 [Default:CheckIn:D] Running Loop=1
2018-07-26T18:36:02.922Z,1532630162.922 [Default:CheckIn:D] Stopped
2018-07-26T18:36:02.922Z,1532630162.922 [Default:CheckIn:E] Running Loop=1
2018-07-26T18:36:03.313Z,1532630163.313 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.112813 min 
2018-07-26T18:36:03.313Z,1532630163.313 [Default:CheckIn:E] Stopped
2018-07-26T18:36:03.313Z,1532630163.313 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T18:36:03.313Z,1532630163.313 [Default:CheckIn] Stopped
2018-07-26T18:36:03.314Z,1532630163.314 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T18:36:03.314Z,1532630163.314 [Default:CheckIn](INFO): Running loop #4
2018-07-26T18:36:03.314Z,1532630163.314 [Default:CheckIn] Running Loop=4
2018-07-26T18:36:03.314Z,1532630163.314 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T18:36:03.314Z,1532630163.314 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T18:36:04.062Z,1532630164.062 [NAL9602](INFO): Powering up
2018-07-26T18:36:14.974Z,1532630174.974 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:36:15.784Z,1532630175.784 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T18:36:15.784Z,1532630175.784 [NAL9602] Data Fault, FailCount= 2
2018-07-26T18:36:15.784Z,1532630175.784 [NAL9602](ERROR): Data Fault
2018-07-26T18:36:15.906Z,1532630175.906 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T18:36:16.182Z,1532630176.182 [NAL9602](INFO): Powering down
2018-07-26T18:36:17.055Z,1532630177.055 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T18:36:17.055Z,1532630177.055 [NAL9602] No Fault, FailCount= 2
2018-07-26T18:36:46.573Z,1532630206.573 [NAL9602](INFO): Powering up NAL9602
2018-07-26T18:36:57.378Z,1532630217.378 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:37:19.872Z,1532630239.872 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T18:37:19.872Z,1532630239.872 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T18:41:03.665Z,1532630463.665 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T18:36:03.3Z
2018-07-26T18:41:03.665Z,1532630463.665 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T18:41:03.665Z,1532630463.665 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T18:41:07.236Z,1532630467.236 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180726T180155/Courier0013.lzma
2018-07-26T18:41:08.004Z,1532630468.004 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0013.lzma.bak
2018-07-26T18:41:08.004Z,1532630468.004 [DataOverHttps](INFO): SBD MOMSN=8378099
2018-07-26T18:41:21.120Z,1532630481.120 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20180726T180155/Express0014.lzma
2018-07-26T18:41:21.880Z,1532630481.880 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0014.lzma.bak
2018-07-26T18:41:21.880Z,1532630481.880 [DataOverHttps](INFO): SBD MOMSN=8378101
2018-07-26T18:41:22.409Z,1532630482.409 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T18:41:22.409Z,1532630482.409 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T18:41:22.410Z,1532630482.410 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T18:42:01.222Z,1532630521.222 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T18:42:31.838Z,1532630551.838 [NAL9602](INFO): Powering down
2018-07-26T18:45:22.532Z,1532630722.532 [RDI_Pathfinder](ERROR): only read 1 of 1 data item for altitude
2018-07-26T18:45:22.533Z,1532630722.533 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G0e88, 16.102, 0.033, 65.535
2018-07-26T18:46:23.082Z,1532630783.082 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T18:46:23.082Z,1532630783.082 [Default:CheckIn:C.Wait] Stopped
2018-07-26T18:46:23.082Z,1532630783.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T18:46:23.082Z,1532630783.082 [Default:CheckIn:D] Running Loop=1
2018-07-26T18:46:23.482Z,1532630783.482 [Default:CheckIn:D] Stopped
2018-07-26T18:46:23.482Z,1532630783.482 [Default:CheckIn:E] Running Loop=1
2018-07-26T18:46:26.873Z,1532630786.873 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.455477 min 
2018-07-26T18:46:26.873Z,1532630786.873 [Default:CheckIn:E] Stopped
2018-07-26T18:46:26.874Z,1532630786.874 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T18:46:26.874Z,1532630786.874 [Default:CheckIn] Stopped
2018-07-26T18:46:26.874Z,1532630786.874 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T18:46:26.874Z,1532630786.874 [Default:CheckIn](INFO): Running loop #5
2018-07-26T18:46:26.874Z,1532630786.874 [Default:CheckIn] Running Loop=5
2018-07-26T18:46:26.874Z,1532630786.874 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T18:46:26.874Z,1532630786.874 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T18:46:27.314Z,1532630787.314 [NAL9602](INFO): Powering up
2018-07-26T18:46:38.114Z,1532630798.114 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:47:10.116Z,1532630830.116 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2018-07-26T18:47:10.193Z,1532630830.193 [NAL9602](FAULT): received: 
+CSQ:0

OK0, 2, 0, 0, 0

OK
2018-07-26T18:47:10.193Z,1532630830.193 [NAL9602] Data Fault, FailCount= 3
2018-07-26T18:47:10.193Z,1532630830.193 [NAL9602](ERROR): Data Fault
2018-07-26T18:47:10.313Z,1532630830.313 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T18:47:10.522Z,1532630830.522 [NAL9602](INFO): Powering down
2018-07-26T18:47:11.384Z,1532630831.384 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T18:47:11.384Z,1532630831.384 [NAL9602] No Fault, FailCount= 3
2018-07-26T18:47:14.918Z,1532630834.918 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-07-26T18:47:14.918Z,1532630834.918 [DropWeight] Hardware Fault, FailCount= 1
2018-07-26T18:47:14.918Z,1532630834.918 [DropWeight](ERROR): Hardware Fault
2018-07-26T18:47:15.025Z,1532630835.025 [CBIT](INFO): Critical error at 20180726T184714
2018-07-26T18:47:15.028Z,1532630835.028 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-07-26T18:47:15.028Z,1532630835.028 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-07-26T18:47:15.377Z,1532630835.377 [CBIT](INFO): Critical error at 20180726T184715
2018-07-26T18:47:40.618Z,1532630860.618 [NAL9602](INFO): Powering up NAL9602
2018-07-26T18:47:51.818Z,1532630871.818 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:47:52.622Z,1532630872.622 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T18:47:52.622Z,1532630872.622 [NAL9602] Data Fault, FailCount= 4
2018-07-26T18:47:52.622Z,1532630872.622 [NAL9602](ERROR): Data Fault
2018-07-26T18:47:52.740Z,1532630872.740 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T18:47:53.018Z,1532630873.018 [NAL9602](INFO): Powering down
2018-07-26T18:47:53.907Z,1532630873.907 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T18:47:53.907Z,1532630873.907 [NAL9602] No Fault, FailCount= 4
2018-07-26T18:48:23.422Z,1532630903.422 [NAL9602](INFO): Powering up NAL9602
2018-07-26T18:48:34.310Z,1532630914.310 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:50:28.733Z,1532631028.733 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2018-07-26T18:51:27.173Z,1532631087.173 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T18:46:26.9Z
2018-07-26T18:51:27.174Z,1532631087.174 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T18:51:27.174Z,1532631087.174 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T18:51:30.880Z,1532631090.880 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20180726T180155/Courier0016.lzma
2018-07-26T18:51:31.660Z,1532631091.660 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0016.lzma.bak
2018-07-26T18:51:31.660Z,1532631091.660 [DataOverHttps](INFO): SBD MOMSN=8378106
2018-07-26T18:51:40.716Z,1532631100.716 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20180726T180155/Express0017.lzma
2018-07-26T18:51:41.101Z,1532631101.101 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T18:51:41.488Z,1532631101.488 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0017.lzma.bak
2018-07-26T18:51:41.488Z,1532631101.488 [DataOverHttps](INFO): SBD MOMSN=8378110
2018-07-26T18:51:41.986Z,1532631101.986 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T18:51:41.986Z,1532631101.986 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T18:51:41.986Z,1532631101.986 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T18:52:11.568Z,1532631131.568 [NAL9602](INFO): Powering down
2018-07-26T18:53:46.682Z,1532631226.682 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-26T18:56:42.629Z,1532631402.629 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T18:56:42.630Z,1532631402.630 [Default:CheckIn:C.Wait] Stopped
2018-07-26T18:56:42.630Z,1532631402.630 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T18:56:42.630Z,1532631402.630 [Default:CheckIn:D] Running Loop=1
2018-07-26T18:56:43.014Z,1532631403.014 [Default:CheckIn:D] Stopped
2018-07-26T18:56:43.014Z,1532631403.014 [Default:CheckIn:E] Running Loop=1
2018-07-26T18:56:43.401Z,1532631403.401 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.781002 min 
2018-07-26T18:56:43.402Z,1532631403.402 [Default:CheckIn:E] Stopped
2018-07-26T18:56:43.402Z,1532631403.402 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T18:56:43.402Z,1532631403.402 [Default:CheckIn] Stopped
2018-07-26T18:56:43.402Z,1532631403.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T18:56:43.402Z,1532631403.402 [Default:CheckIn](INFO): Running loop #6
2018-07-26T18:56:43.402Z,1532631403.402 [Default:CheckIn] Running Loop=6
2018-07-26T18:56:43.403Z,1532631403.403 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T18:56:43.403Z,1532631403.403 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T18:56:44.170Z,1532631404.170 [NAL9602](INFO): Powering up
2018-07-26T18:56:55.074Z,1532631415.074 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:57:27.075Z,1532631447.075 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2018-07-26T18:57:27.154Z,1532631447.154 [NAL9602](FAULT): received: 
+CSQ:0

OK0, 2, 0, 0, 0

OK
2018-07-26T18:57:27.155Z,1532631447.155 [NAL9602] Data Fault, FailCount= 5
2018-07-26T18:57:27.155Z,1532631447.155 [NAL9602](ERROR): Data Fault
2018-07-26T18:57:27.259Z,1532631447.259 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T18:57:27.259Z,1532631447.259 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-07-26T18:57:27.474Z,1532631447.474 [NAL9602](INFO): Powering down
2018-07-26T18:57:27.595Z,1532631447.595 [CBIT](INFO): Critical error at 20180726T185727
2018-07-26T18:59:27.591Z,1532631567.591 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T18:59:27.591Z,1532631567.591 [NAL9602] No Fault, FailCount= 5
2018-07-26T18:59:27.894Z,1532631567.894 [NAL9602](INFO): Powering up NAL9602
2018-07-26T18:59:39.094Z,1532631579.094 [NAL9602](INFO): NAL9602 initialized
2018-07-26T18:59:39.895Z,1532631579.895 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T18:59:39.895Z,1532631579.895 [NAL9602] Data Fault, FailCount= 1
2018-07-26T18:59:39.895Z,1532631579.895 [NAL9602](ERROR): Data Fault
2018-07-26T18:59:39.991Z,1532631579.991 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T18:59:40.294Z,1532631580.294 [NAL9602](INFO): Powering down
2018-07-26T18:59:41.199Z,1532631581.199 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T18:59:41.199Z,1532631581.199 [NAL9602] No Fault, FailCount= 1
2018-07-26T19:00:10.383Z,1532631610.383 [NAL9602](INFO): Powering up NAL9602
2018-07-26T19:00:21.186Z,1532631621.186 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:01:43.743Z,1532631703.743 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T18:56:43.4Z
2018-07-26T19:01:43.743Z,1532631703.743 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T19:01:43.743Z,1532631703.743 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T19:01:47.232Z,1532631707.232 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20180726T180155/Courier0019.lzma
2018-07-26T19:01:48.001Z,1532631708.001 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0019.lzma.bak
2018-07-26T19:01:48.001Z,1532631708.001 [DataOverHttps](INFO): SBD MOMSN=8378116
2018-07-26T19:02:00.178Z,1532631720.178 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T19:02:00.318Z,1532631720.318 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20180726T180155/Express0020.lzma
2018-07-26T19:02:01.116Z,1532631721.116 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0020.lzma.bak
2018-07-26T19:02:01.116Z,1532631721.116 [DataOverHttps](INFO): SBD MOMSN=8378119
2018-07-26T19:02:01.622Z,1532631721.622 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T19:02:01.622Z,1532631721.622 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T19:02:01.622Z,1532631721.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T19:02:15.042Z,1532631735.042 [CBIT](INFO): Clearing failed state for component DropWeight
2018-07-26T19:02:15.042Z,1532631735.042 [DropWeight] No Fault, FailCount= 1
2018-07-26T19:02:30.562Z,1532631750.562 [NAL9602](INFO): Powering down
2018-07-26T19:06:26.836Z,1532631986.836 [RDI_Pathfinder](ERROR): Failed to parse:
    +0.00,        +0.00,        +0.00,   0.00,3839.40
2018-07-26T19:07:02.049Z,1532632022.049 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T19:07:02.050Z,1532632022.050 [Default:CheckIn:C.Wait] Stopped
2018-07-26T19:07:02.050Z,1532632022.050 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T19:07:02.050Z,1532632022.050 [Default:CheckIn:D] Running Loop=1
2018-07-26T19:07:02.446Z,1532632022.446 [Default:CheckIn:D] Stopped
2018-07-26T19:07:02.446Z,1532632022.446 [Default:CheckIn:E] Running Loop=1
2018-07-26T19:07:02.885Z,1532632022.885 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.104879 min 
2018-07-26T19:07:02.885Z,1532632022.885 [Default:CheckIn:E] Stopped
2018-07-26T19:07:02.886Z,1532632022.886 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T19:07:02.886Z,1532632022.886 [Default:CheckIn] Stopped
2018-07-26T19:07:02.886Z,1532632022.886 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T19:07:02.886Z,1532632022.886 [Default:CheckIn](INFO): Running loop #7
2018-07-26T19:07:02.886Z,1532632022.886 [Default:CheckIn] Running Loop=7
2018-07-26T19:07:02.886Z,1532632022.886 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T19:07:02.886Z,1532632022.886 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T19:07:03.618Z,1532632023.618 [NAL9602](INFO): Powering up
2018-07-26T19:07:14.478Z,1532632034.478 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:12:03.218Z,1532632323.218 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T19:07:02.9Z
2018-07-26T19:12:03.218Z,1532632323.218 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T19:12:03.218Z,1532632323.218 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T19:12:07.124Z,1532632327.124 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180726T180155/Courier0022.lzma
2018-07-26T19:12:07.904Z,1532632327.904 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0022.lzma.bak
2018-07-26T19:12:07.904Z,1532632327.904 [DataOverHttps](INFO): SBD MOMSN=8378125
2018-07-26T19:12:17.104Z,1532632337.104 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20180726T180155/Express0023.lzma
2018-07-26T19:12:17.888Z,1532632337.888 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0023.lzma.bak
2018-07-26T19:12:17.888Z,1532632337.888 [DataOverHttps](INFO): SBD MOMSN=8378127
2018-07-26T19:12:17.895Z,1532632337.895 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T19:12:18.402Z,1532632338.402 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T19:12:18.402Z,1532632338.402 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T19:12:18.402Z,1532632338.402 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T19:12:48.550Z,1532632368.550 [NAL9602](INFO): Powering down
2018-07-26T19:17:19.157Z,1532632639.157 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T19:17:19.157Z,1532632639.157 [Default:CheckIn:C.Wait] Stopped
2018-07-26T19:17:19.157Z,1532632639.157 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T19:17:19.158Z,1532632639.158 [Default:CheckIn:D] Running Loop=1
2018-07-26T19:17:19.571Z,1532632639.571 [Default:CheckIn:D] Stopped
2018-07-26T19:17:19.571Z,1532632639.571 [Default:CheckIn:E] Running Loop=1
2018-07-26T19:17:19.930Z,1532632639.930 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.390291 min 
2018-07-26T19:17:19.930Z,1532632639.930 [Default:CheckIn:E] Stopped
2018-07-26T19:17:19.930Z,1532632639.930 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T19:17:19.930Z,1532632639.930 [Default:CheckIn] Stopped
2018-07-26T19:17:19.930Z,1532632639.930 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T19:17:19.931Z,1532632639.931 [Default:CheckIn](INFO): Running loop #8
2018-07-26T19:17:19.931Z,1532632639.931 [Default:CheckIn] Running Loop=8
2018-07-26T19:17:19.931Z,1532632639.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T19:17:19.931Z,1532632639.931 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T19:17:20.690Z,1532632640.690 [NAL9602](INFO): Powering up
2018-07-26T19:17:31.590Z,1532632651.590 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:17:32.417Z,1532632652.417 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T19:17:32.417Z,1532632652.417 [NAL9602] Data Fault, FailCount= 2
2018-07-26T19:17:32.417Z,1532632652.417 [NAL9602](ERROR): Data Fault
2018-07-26T19:17:32.522Z,1532632652.522 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T19:17:32.790Z,1532632652.790 [NAL9602](INFO): Powering down
2018-07-26T19:17:33.689Z,1532632653.689 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T19:17:33.689Z,1532632653.689 [NAL9602] No Fault, FailCount= 2
2018-07-26T19:18:03.186Z,1532632683.186 [NAL9602](INFO): Powering up NAL9602
2018-07-26T19:18:14.386Z,1532632694.386 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:22:20.312Z,1532632940.312 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T19:17:19.9Z
2018-07-26T19:22:20.312Z,1532632940.312 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T19:22:20.312Z,1532632940.312 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T19:22:24.011Z,1532632944.011 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180726T180155/Courier0025.lzma
2018-07-26T19:22:24.776Z,1532632944.776 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0025.lzma.bak
2018-07-26T19:22:24.777Z,1532632944.777 [DataOverHttps](INFO): SBD MOMSN=8378131
2018-07-26T19:22:37.989Z,1532632957.989 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20180726T180155/Express0026.lzma
2018-07-26T19:22:38.776Z,1532632958.776 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0026.lzma.bak
2018-07-26T19:22:38.776Z,1532632958.776 [DataOverHttps](INFO): SBD MOMSN=8378133
2018-07-26T19:22:39.269Z,1532632959.269 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T19:22:39.270Z,1532632959.270 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T19:22:39.270Z,1532632959.270 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T19:23:17.678Z,1532632997.678 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T19:23:48.346Z,1532633028.346 [NAL9602](INFO): Powering down
2018-07-26T19:27:39.933Z,1532633259.933 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T19:27:39.933Z,1532633259.933 [Default:CheckIn:C.Wait] Stopped
2018-07-26T19:27:39.934Z,1532633259.934 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T19:27:39.934Z,1532633259.934 [Default:CheckIn:D] Running Loop=1
2018-07-26T19:27:40.336Z,1532633260.336 [Default:CheckIn:D] Stopped
2018-07-26T19:27:40.337Z,1532633260.337 [Default:CheckIn:E] Running Loop=1
2018-07-26T19:27:43.515Z,1532633263.515 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.736385 min 
2018-07-26T19:27:43.516Z,1532633263.516 [Default:CheckIn:E] Stopped
2018-07-26T19:27:43.516Z,1532633263.516 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T19:27:43.516Z,1532633263.516 [Default:CheckIn] Stopped
2018-07-26T19:27:43.516Z,1532633263.516 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T19:27:43.516Z,1532633263.516 [Default:CheckIn](INFO): Running loop #9
2018-07-26T19:27:43.516Z,1532633263.516 [Default:CheckIn] Running Loop=9
2018-07-26T19:27:43.516Z,1532633263.516 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T19:27:43.521Z,1532633263.521 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T19:27:43.966Z,1532633263.966 [NAL9602](INFO): Powering up
2018-07-26T19:27:55.162Z,1532633275.162 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:32:16.179Z,1532633536.179 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-07-26T19:32:16.179Z,1532633536.179 [DropWeight] Hardware Fault, FailCount= 1
2018-07-26T19:32:16.179Z,1532633536.179 [DropWeight](ERROR): Hardware Fault
2018-07-26T19:32:16.274Z,1532633536.274 [CBIT](INFO): Critical error at 20180726T193216
2018-07-26T19:32:16.276Z,1532633536.276 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-07-26T19:32:16.276Z,1532633536.276 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-07-26T19:32:16.661Z,1532633536.661 [CBIT](INFO): Critical error at 20180726T193216
2018-07-26T19:32:44.303Z,1532633564.303 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T19:27:43.5Z
2018-07-26T19:32:44.303Z,1532633564.303 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T19:32:44.303Z,1532633564.303 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T19:32:52.485Z,1532633572.485 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20180726T180155/Courier0028.lzma
2018-07-26T19:32:53.253Z,1532633573.253 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0028.lzma.bak
2018-07-26T19:32:53.253Z,1532633573.253 [DataOverHttps](INFO): SBD MOMSN=8378138
2018-07-26T19:32:58.714Z,1532633578.714 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T19:33:05.610Z,1532633585.610 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20180726T180155/Express0029.lzma
2018-07-26T19:33:06.387Z,1532633586.387 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0029.lzma.bak
2018-07-26T19:33:06.387Z,1532633586.387 [DataOverHttps](INFO): SBD MOMSN=8378142
2018-07-26T19:33:06.878Z,1532633586.878 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T19:33:06.878Z,1532633586.878 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T19:33:06.878Z,1532633586.878 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T19:33:11.681Z,1532633591.681 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-26T19:33:29.254Z,1532633609.254 [NAL9602](INFO): Powering down
2018-07-26T19:37:16.569Z,1532633836.569 [RDI_Pathfinder](ERROR): Failed to parse:
0e87,0e87,*16.095,*0.031,*65.535
2018-07-26T19:38:07.386Z,1532633887.386 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T19:38:07.386Z,1532633887.386 [Default:CheckIn:C.Wait] Stopped
2018-07-26T19:38:07.387Z,1532633887.387 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T19:38:07.387Z,1532633887.387 [Default:CheckIn:D] Running Loop=1
2018-07-26T19:38:07.791Z,1532633887.791 [Default:CheckIn:D] Stopped
2018-07-26T19:38:07.791Z,1532633887.791 [Default:CheckIn:E] Running Loop=1
2018-07-26T19:38:10.995Z,1532633890.995 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.193962 min 
2018-07-26T19:38:10.996Z,1532633890.996 [Default:CheckIn:E] Stopped
2018-07-26T19:38:10.996Z,1532633890.996 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T19:38:10.996Z,1532633890.996 [Default:CheckIn] Stopped
2018-07-26T19:38:10.996Z,1532633890.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T19:38:10.996Z,1532633890.996 [Default:CheckIn](INFO): Running loop #10
2018-07-26T19:38:10.996Z,1532633890.996 [Default:CheckIn] Running Loop=10
2018-07-26T19:38:10.996Z,1532633890.996 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T19:38:10.997Z,1532633890.997 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T19:38:11.450Z,1532633891.450 [NAL9602](INFO): Powering up
2018-07-26T19:38:22.242Z,1532633902.242 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:38:23.046Z,1532633903.046 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T19:38:23.046Z,1532633903.046 [NAL9602] Data Fault, FailCount= 3
2018-07-26T19:38:23.046Z,1532633903.046 [NAL9602](ERROR): Data Fault
2018-07-26T19:38:23.125Z,1532633903.125 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T19:38:23.438Z,1532633903.438 [NAL9602](INFO): Powering down
2018-07-26T19:38:24.325Z,1532633904.325 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T19:38:24.325Z,1532633904.325 [NAL9602] No Fault, FailCount= 3
2018-07-26T19:38:53.450Z,1532633933.450 [NAL9602](INFO): Powering up NAL9602
2018-07-26T19:39:04.638Z,1532633944.638 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:43:11.641Z,1532634191.641 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T19:38:10.0Z
2018-07-26T19:43:11.641Z,1532634191.641 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T19:43:11.642Z,1532634191.642 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T19:43:19.798Z,1532634199.798 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180726T180155/Courier0031.lzma
2018-07-26T19:43:21.124Z,1532634201.124 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0031.lzma.bak
2018-07-26T19:43:21.124Z,1532634201.124 [DataOverHttps](INFO): SBD MOMSN=8378146
2018-07-26T19:43:33.876Z,1532634213.876 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20180726T180155/Express0032.lzma
2018-07-26T19:43:34.640Z,1532634214.640 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0032.lzma.bak
2018-07-26T19:43:34.640Z,1532634214.640 [DataOverHttps](INFO): SBD MOMSN=8378148
2018-07-26T19:43:35.145Z,1532634215.145 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T19:43:35.145Z,1532634215.145 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T19:43:35.145Z,1532634215.145 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T19:44:07.955Z,1532634247.955 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T19:44:38.848Z,1532634278.848 [NAL9602](INFO): Powering down
2018-07-26T19:45:50.764Z,1532634350.764 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-07-26T19:45:50.765Z,1532634350.765 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18072619470080,35.0, -0.1,   0.,  0
2018-07-26T19:47:16.504Z,1532634436.504 [CBIT](INFO): Clearing failed state for component DropWeight
2018-07-26T19:47:16.504Z,1532634436.504 [DropWeight] No Fault, FailCount= 1
2018-07-26T19:47:43.759Z,1532634463.759 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-26T19:48:35.798Z,1532634515.798 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T19:48:35.799Z,1532634515.799 [Default:CheckIn:C.Wait] Stopped
2018-07-26T19:48:35.799Z,1532634515.799 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T19:48:35.799Z,1532634515.799 [Default:CheckIn:D] Running Loop=1
2018-07-26T19:48:36.191Z,1532634516.191 [Default:CheckIn:D] Stopped
2018-07-26T19:48:36.191Z,1532634516.191 [Default:CheckIn:E] Running Loop=1
2018-07-26T19:48:36.592Z,1532634516.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.667293 min 
2018-07-26T19:48:36.592Z,1532634516.592 [Default:CheckIn:E] Stopped
2018-07-26T19:48:36.593Z,1532634516.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T19:48:36.593Z,1532634516.593 [Default:CheckIn] Stopped
2018-07-26T19:48:36.593Z,1532634516.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T19:48:36.593Z,1532634516.593 [Default:CheckIn](INFO): Running loop #11
2018-07-26T19:48:36.593Z,1532634516.593 [Default:CheckIn] Running Loop=11
2018-07-26T19:48:36.594Z,1532634516.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T19:48:36.594Z,1532634516.594 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T19:48:37.330Z,1532634517.330 [NAL9602](INFO): Powering up
2018-07-26T19:48:48.214Z,1532634528.214 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:49:20.219Z,1532634560.219 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2018-07-26T19:49:20.294Z,1532634560.294 [NAL9602](FAULT): received: 
+CSQ:0

OK0, 2, 0, 0, 0

OK
2018-07-26T19:49:20.294Z,1532634560.294 [NAL9602] Data Fault, FailCount= 4
2018-07-26T19:49:20.294Z,1532634560.294 [NAL9602](ERROR): Data Fault
2018-07-26T19:49:20.390Z,1532634560.390 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T19:49:20.622Z,1532634560.622 [NAL9602](INFO): Powering down
2018-07-26T19:49:21.508Z,1532634561.508 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T19:49:21.508Z,1532634561.508 [NAL9602] No Fault, FailCount= 4
2018-07-26T19:49:50.855Z,1532634590.855 [NAL9602](INFO): Powering up NAL9602
2018-07-26T19:50:02.050Z,1532634602.050 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:50:02.850Z,1532634602.850 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T19:50:02.851Z,1532634602.851 [NAL9602] Data Fault, FailCount= 5
2018-07-26T19:50:02.851Z,1532634602.851 [NAL9602](ERROR): Data Fault
2018-07-26T19:50:02.944Z,1532634602.944 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T19:50:02.944Z,1532634602.944 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-07-26T19:50:03.260Z,1532634603.260 [NAL9602](INFO): Powering down
2018-07-26T19:50:03.326Z,1532634603.326 [CBIT](INFO): Critical error at 20180726T195002
2018-07-26T19:52:03.084Z,1532634723.084 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T19:52:03.084Z,1532634723.084 [NAL9602] No Fault, FailCount= 5
2018-07-26T19:52:03.406Z,1532634723.406 [NAL9602](INFO): Powering up NAL9602
2018-07-26T19:52:14.206Z,1532634734.206 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:53:33.148Z,1532634813.148 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T19:53:33.148Z,1532634813.148 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T19:53:36.809Z,1532634816.809 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T19:48:36.6Z
2018-07-26T19:53:36.809Z,1532634816.809 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T19:53:36.809Z,1532634816.809 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T19:53:43.436Z,1532634823.436 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20180726T180155/Courier0034.lzma
2018-07-26T19:53:44.227Z,1532634824.227 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0034.lzma.bak
2018-07-26T19:53:44.228Z,1532634824.228 [DataOverHttps](INFO): SBD MOMSN=8378153
2018-07-26T19:53:53.986Z,1532634833.986 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T19:53:54.138Z,1532634834.138 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20180726T180155/Express0035.lzma
2018-07-26T19:53:54.916Z,1532634834.916 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0035.lzma.bak
2018-07-26T19:53:54.916Z,1532634834.916 [DataOverHttps](INFO): SBD MOMSN=8378156
2018-07-26T19:53:55.421Z,1532634835.421 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T19:53:55.421Z,1532634835.421 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T19:53:55.421Z,1532634835.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T19:54:24.346Z,1532634864.346 [NAL9602](INFO): Powering down
2018-07-26T19:56:03.766Z,1532634963.766 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2018-07-26T19:58:55.889Z,1532635135.889 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T19:58:55.889Z,1532635135.889 [Default:CheckIn:C.Wait] Stopped
2018-07-26T19:58:55.890Z,1532635135.890 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T19:58:55.890Z,1532635135.890 [Default:CheckIn:D] Running Loop=1
2018-07-26T19:58:56.265Z,1532635136.265 [Default:CheckIn:D] Stopped
2018-07-26T19:58:56.265Z,1532635136.265 [Default:CheckIn:E] Running Loop=1
2018-07-26T19:58:56.691Z,1532635136.691 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.001864 min 
2018-07-26T19:58:56.692Z,1532635136.692 [Default:CheckIn:E] Stopped
2018-07-26T19:58:56.692Z,1532635136.692 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T19:58:56.692Z,1532635136.692 [Default:CheckIn] Stopped
2018-07-26T19:58:56.692Z,1532635136.692 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T19:58:56.692Z,1532635136.692 [Default:CheckIn](INFO): Running loop #12
2018-07-26T19:58:56.692Z,1532635136.692 [Default:CheckIn] Running Loop=12
2018-07-26T19:58:56.692Z,1532635136.692 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T19:58:56.693Z,1532635136.693 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T19:58:59.934Z,1532635139.934 [NAL9602](INFO): Powering up
2018-07-26T19:59:11.095Z,1532635151.095 [NAL9602](INFO): NAL9602 initialized
2018-07-26T19:59:43.094Z,1532635183.094 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2018-07-26T19:59:43.169Z,1532635183.169 [NAL9602](FAULT): received: 
+CSQ:0

OK0, 2, 0, 0, 0

OK
2018-07-26T19:59:43.169Z,1532635183.169 [NAL9602] Data Fault, FailCount= 1
2018-07-26T19:59:43.169Z,1532635183.169 [NAL9602](ERROR): Data Fault
2018-07-26T19:59:43.249Z,1532635183.249 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T19:59:43.494Z,1532635183.494 [NAL9602](INFO): Powering down
2018-07-26T19:59:44.369Z,1532635184.369 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T19:59:44.369Z,1532635184.369 [NAL9602] No Fault, FailCount= 1
2018-07-26T20:00:13.650Z,1532635213.650 [NAL9602](INFO): Powering up NAL9602
2018-07-26T20:00:24.846Z,1532635224.846 [NAL9602](INFO): NAL9602 initialized
2018-07-26T20:01:54.311Z,1532635314.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T20:01:54.312Z,1532635314.312 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T20:02:27.192Z,1532635347.192 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T20:02:27.192Z,1532635347.192 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T20:02:41.584Z,1532635361.584 [NAL9602](INFO): SBD MO Status=2, MOMSN=450, MT Status=2, MTMSN=0
2018-07-26T20:02:41.584Z,1532635361.584 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T20:03:29.389Z,1532635409.389 [NAL9602](INFO): SBD MO Status=0, MOMSN=450, MT Status=0, MTMSN=0
2018-07-26T20:03:29.389Z,1532635409.389 [NAL9602](INFO): No messages in MT queue
2018-07-26T20:03:30.166Z,1532635410.166 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T20:03:30.166Z,1532635410.166 [NAL9602] Data Fault, FailCount= 1
2018-07-26T20:03:30.167Z,1532635410.167 [NAL9602](ERROR): Data Fault
2018-07-26T20:03:30.238Z,1532635410.238 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T20:03:30.570Z,1532635410.570 [NAL9602](INFO): Powering down
2018-07-26T20:03:31.452Z,1532635411.452 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T20:03:31.452Z,1532635411.452 [NAL9602] No Fault, FailCount= 1
2018-07-26T20:03:57.011Z,1532635437.011 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T19:58:56.7Z
2018-07-26T20:03:57.011Z,1532635437.011 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T20:03:57.011Z,1532635437.011 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T20:04:00.698Z,1532635440.698 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180726T180155/Courier0037.lzma
2018-07-26T20:04:00.702Z,1532635440.702 [NAL9602](INFO): Powering up NAL9602
2018-07-26T20:04:01.477Z,1532635441.477 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0037.lzma.bak
2018-07-26T20:04:01.477Z,1532635441.477 [DataOverHttps](INFO): SBD MOMSN=8378162
2018-07-26T20:04:10.554Z,1532635450.554 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20180726T180155/Express0038.lzma
2018-07-26T20:04:11.300Z,1532635451.300 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0038.lzma.bak
2018-07-26T20:04:11.300Z,1532635451.300 [DataOverHttps](INFO): SBD MOMSN=8378164
2018-07-26T20:04:11.760Z,1532635451.760 [NAL9602](INFO): NAL9602 initialized
2018-07-26T20:04:11.832Z,1532635451.832 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T20:04:11.832Z,1532635451.832 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T20:04:11.837Z,1532635451.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T20:04:42.775Z,1532635482.775 [NAL9602](INFO): Powering down
2018-07-26T20:09:12.548Z,1532635752.548 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T20:09:12.548Z,1532635752.548 [Default:CheckIn:C.Wait] Stopped
2018-07-26T20:09:12.549Z,1532635752.549 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T20:09:12.549Z,1532635752.549 [Default:CheckIn:D] Running Loop=1
2018-07-26T20:09:12.937Z,1532635752.937 [Default:CheckIn:D] Stopped
2018-07-26T20:09:12.937Z,1532635752.937 [Default:CheckIn:E] Running Loop=1
2018-07-26T20:09:13.346Z,1532635753.346 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.279728 min 
2018-07-26T20:09:13.347Z,1532635753.347 [Default:CheckIn:E] Stopped
2018-07-26T20:09:13.347Z,1532635753.347 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T20:09:13.347Z,1532635753.347 [Default:CheckIn] Stopped
2018-07-26T20:09:13.347Z,1532635753.347 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T20:09:13.347Z,1532635753.347 [Default:CheckIn](INFO): Running loop #13
2018-07-26T20:09:13.347Z,1532635753.347 [Default:CheckIn] Running Loop=13
2018-07-26T20:09:13.348Z,1532635753.348 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T20:09:13.348Z,1532635753.348 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T20:09:14.093Z,1532635754.093 [NAL9602](INFO): Powering up
2018-07-26T20:09:25.075Z,1532635765.075 [NAL9602](INFO): NAL9602 initialized
2018-07-26T20:09:53.464Z,1532635793.464 [NAL9602](INFO): SBD MO Status=2, MOMSN=451, MT Status=2, MTMSN=0
2018-07-26T20:09:53.464Z,1532635793.464 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T20:12:58.289Z,1532635978.289 [NAL9602](INFO): SBD MO Status=2, MOMSN=451, MT Status=2, MTMSN=0
2018-07-26T20:12:58.289Z,1532635978.289 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T20:14:13.671Z,1532636053.671 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-26T20:09:13.3Z
2018-07-26T20:14:13.672Z,1532636053.672 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T20:14:13.672Z,1532636053.672 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-26T20:14:17.159Z,1532636057.159 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180726T180155/Courier0040.lzma
2018-07-26T20:14:17.924Z,1532636057.924 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Courier0040.lzma.bak
2018-07-26T20:14:17.924Z,1532636057.924 [DataOverHttps](INFO): SBD MOMSN=8378172
2018-07-26T20:14:29.489Z,1532636069.489 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-26T20:14:29.625Z,1532636069.625 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20180726T180155/Express0041.lzma
2018-07-26T20:14:30.421Z,1532636070.421 [DataOverHttps](INFO): Moved sent file to Logs/20180726T180155/Express0041.lzma.bak
2018-07-26T20:14:30.421Z,1532636070.421 [DataOverHttps](INFO): SBD MOMSN=8378174
2018-07-26T20:14:30.899Z,1532636070.899 [Default:CheckIn:Read_Iridium] Stopped
2018-07-26T20:14:30.899Z,1532636070.899 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-26T20:14:30.899Z,1532636070.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-26T20:14:59.702Z,1532636099.702 [NAL9602](INFO): Powering down
2018-07-26T20:15:06.127Z,1532636106.127 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-07-26T20:15:06.127Z,1532636106.127 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-3278,V
2018-07-26T20:17:17.630Z,1532636237.630 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-07-26T20:17:17.630Z,1532636237.630 [DropWeight] Hardware Fault, FailCount= 1
2018-07-26T20:17:17.630Z,1532636237.630 [DropWeight](ERROR): Hardware Fault
2018-07-26T20:17:17.854Z,1532636237.854 [CBIT](INFO): Critical error at 20180726T201717
2018-07-26T20:17:17.856Z,1532636237.856 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-07-26T20:17:17.856Z,1532636237.856 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-07-26T20:17:18.161Z,1532636238.161 [CBIT](INFO): Critical error at 20180726T201717
2018-07-26T20:19:31.572Z,1532636371.572 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-26T20:19:31.572Z,1532636371.572 [Default:CheckIn:C.Wait] Stopped
2018-07-26T20:19:31.572Z,1532636371.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-26T20:19:31.572Z,1532636371.572 [Default:CheckIn:D] Running Loop=1
2018-07-26T20:19:31.992Z,1532636371.992 [Default:CheckIn:D] Stopped
2018-07-26T20:19:31.992Z,1532636371.992 [Default:CheckIn:E] Running Loop=1
2018-07-26T20:19:32.360Z,1532636372.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.597306 min 
2018-07-26T20:19:32.360Z,1532636372.360 [Default:CheckIn:E] Stopped
2018-07-26T20:19:32.361Z,1532636372.361 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-26T20:19:32.361Z,1532636372.361 [Default:CheckIn] Stopped
2018-07-26T20:19:32.361Z,1532636372.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T20:19:32.361Z,1532636372.361 [Default:CheckIn](INFO): Running loop #14
2018-07-26T20:19:32.361Z,1532636372.361 [Default:CheckIn] Running Loop=14
2018-07-26T20:19:32.361Z,1532636372.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-26T20:19:32.362Z,1532636372.362 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-26T20:19:33.126Z,1532636373.126 [NAL9602](INFO): Powering up
2018-07-26T20:19:44.194Z,1532636384.194 [NAL9602](INFO): NAL9602 initialized
2018-07-26T20:19:44.994Z,1532636384.994 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-26T20:19:44.994Z,1532636384.994 [NAL9602] Data Fault, FailCount= 1
2018-07-26T20:19:44.994Z,1532636384.994 [NAL9602](ERROR): Data Fault
2018-07-26T20:19:45.103Z,1532636385.103 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-26T20:19:45.390Z,1532636385.390 [NAL9602](INFO): Powering down
2018-07-26T20:19:46.262Z,1532636386.262 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-26T20:19:46.262Z,1532636386.262 [NAL9602] No Fault, FailCount= 1
2018-07-26T20:20:15.394Z,1532636415.394 [NAL9602](INFO): Powering up NAL9602
2018-07-26T20:20:26.198Z,1532636426.198 [NAL9602](INFO): NAL9602 initialized
2018-07-26T20:21:07.099Z,1532636467.099 [NAL9602](INFO): SBD MO Status=2, MOMSN=451, MT Status=2, MTMSN=0
2018-07-26T20:21:07.100Z,1532636467.100 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T20:21:19.904Z,1532636479.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=451, MT Status=2, MTMSN=0
2018-07-26T20:21:19.904Z,1532636479.904 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T20:22:54.364Z,1532636574.364 [NAL9602](INFO): SBD MO Status=2, MOMSN=451, MT Status=2, MTMSN=0
2018-07-26T20:22:54.364Z,1532636574.364 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-26T20:23:51.150Z,1532636631.150 [CommandLine](IMPORTANT): got command restart application
2018-07-26T20:23:52.156Z,1532636632.156 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-26T20:23:52.156Z,1532636632.156 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.305Z,1532636632.305 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-07-26T20:23:52.305Z,1532636632.305 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.306Z,1532636632.306 [CommandLine](INFO): Join timeout helper Thread ID is 9540
2018-07-26T20:23:52.317Z,1532636632.317 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-07-26T20:23:52.317Z,1532636632.317 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.318Z,1532636632.318 [NavChartDb](INFO): Join timeout helper Thread ID is 9541
2018-07-26T20:23:52.645Z,1532636632.645 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-26T20:23:52.646Z,1532636632.646 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.646Z,1532636632.646 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-07-26T20:23:52.646Z,1532636632.646 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.647Z,1532636632.647 [Radio_Surface](INFO): Join timeout helper Thread ID is 9542
2018-07-26T20:23:52.769Z,1532636632.769 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-26T20:23:52.769Z,1532636632.769 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.777Z,1532636632.777 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-07-26T20:23:52.777Z,1532636632.777 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.777Z,1532636632.777 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9543
2018-07-26T20:23:52.917Z,1532636632.917 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-26T20:23:52.917Z,1532636632.917 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.925Z,1532636632.925 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-07-26T20:23:52.925Z,1532636632.925 [logger ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.925Z,1532636632.925 [logger](INFO): Join timeout helper Thread ID is 9544
2018-07-26T20:23:52.945Z,1532636632.945 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-26T20:23:52.945Z,1532636632.945 [logger ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.966Z,1532636632.966 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-07-26T20:23:52.966Z,1532636632.966 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.966Z,1532636632.966 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-07-26T20:23:52.966Z,1532636632.966 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:52.967Z,1532636632.967 [controlThread](INFO): Join timeout helper Thread ID is 9545
2018-07-26T20:23:53.029Z,1532636633.029 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-26T20:23:53.029Z,1532636633.029 [controlThread](DEBUG): Uninitializing ControlThread
2018-07-26T20:23:53.030Z,1532636633.030 [Aanderaa_O2](INFO): Powering down
2018-07-26T20:23:53.031Z,1532636633.031 [AHRS_M2](INFO): Powering down
2018-07-26T20:23:53.102Z,1532636633.102 [NAL9602](INFO): Powering down
2018-07-26T20:23:53.173Z,1532636633.173 [RDI_Pathfinder](INFO): Powering down
2018-07-26T20:23:53.174Z,1532636633.174 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-26T20:23:53.176Z,1532636633.176 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-07-26T20:23:53.176Z,1532636633.176 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-07-26T20:23:53.177Z,1532636633.177 [MissionManager](INFO): Uninitializing Mission Default
2018-07-26T20:23:53.177Z,1532636633.177 [Default] Stopped
2018-07-26T20:23:53.177Z,1532636633.177 [Default](DEBUG): Aggregate::uninitialize Default
2018-07-26T20:23:53.178Z,1532636633.178 [Default:B.GoToSurface] Stopped
2018-07-26T20:23:53.178Z,1532636633.178 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-26T20:23:53.178Z,1532636633.178 [Default:CheckIn] Stopped
2018-07-26T20:23:53.178Z,1532636633.178 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-26T20:23:53.178Z,1532636633.178 [Default:CheckIn:Read_GPS] Stopped
2018-07-26T20:23:53.182Z,1532636633.182 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-07-26T20:23:53.182Z,1532636633.182 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-07-26T20:23:53.182Z,1532636633.182 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-07-26T20:23:53.183Z,1532636633.183 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-07-26T20:23:53.183Z,1532636633.183 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-26T20:23:53.183Z,1532636633.183 [BuoyancyServo](INFO): Powering down
2018-07-26T20:23:53.197Z,1532636633.197 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-07-26T20:23:53.197Z,1532636633.197 [ElevatorServo](INFO): Powering down
2018-07-26T20:23:53.198Z,1532636633.198 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-07-26T20:23:53.198Z,1532636633.198 [MassServo](INFO): Powering down
2018-07-26T20:23:53.199Z,1532636633.199 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-07-26T20:23:53.199Z,1532636633.199 [RudderServo](INFO): Powering down
2018-07-26T20:23:53.200Z,1532636633.200 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-07-26T20:23:53.200Z,1532636633.200 [ThrusterServo](INFO): Powering down
2018-07-26T20:23:53.201Z,1532636633.201 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-07-26T20:23:53.201Z,1532636633.201 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-07-26T20:23:53.202Z,1532636633.202 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-07-26T20:23:53.203Z,1532636633.203 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:53.262Z,1532636633.262 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:53.323Z,1532636633.323 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:53.379Z,1532636633.379 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-26T20:23:53.447Z,1532636633.447 [logger ThreadHandler](INFO): Thread cancelled.