2017-08-28T17:20:32.144Z,1503940832.144 [Supervisor](DEBUG): Initializing supervisor.
2017-08-28T17:20:32.146Z,1503940832.146 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-08-28T17:20:32.147Z,1503940832.147 [SyncHandler](INFO): Protected caller Thread ID is 769
2017-08-28T17:20:32.147Z,1503940832.147 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-08-28T17:20:32.148Z,1503940832.148 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-08-28T17:20:32.149Z,1503940832.149 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770
2017-08-28T17:20:32.152Z,1503940832.152 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-08-28T17:20:32.164Z,1503940832.164 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-08-28T17:20:32.165Z,1503940832.165 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-08-28T17:20:32.165Z,1503940832.165 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771
2017-08-28T17:20:32.166Z,1503940832.166 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-08-28T17:20:32.167Z,1503940832.167 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-08-28T17:20:32.168Z,1503940832.168 [logger ThreadHandler](INFO): Protected caller Thread ID is 772
2017-08-28T17:20:32.170Z,1503940832.170 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-08-28T17:20:32.170Z,1503940832.170 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-08-28T17:20:32.173Z,1503940832.173 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-08-28T17:20:32.365Z,1503940832.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-08-28T17:20:32.365Z,1503940832.365 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-08-28T17:20:32.500Z,1503940832.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-08-28T17:20:32.501Z,1503940832.501 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-08-28T17:20:32.845Z,1503940832.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-08-28T17:20:32.846Z,1503940832.846 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-08-28T17:20:32.987Z,1503940832.987 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-08-28T17:20:32.988Z,1503940832.988 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-08-28T17:20:33.075Z,1503940833.075 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-08-28T17:20:33.278Z,1503940833.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-08-28T17:20:33.278Z,1503940833.278 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-08-28T17:20:33.365Z,1503940833.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-08-28T17:20:33.366Z,1503940833.366 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-08-28T17:20:33.657Z,1503940833.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-08-28T17:20:33.658Z,1503940833.658 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-08-28T17:20:34.044Z,1503940834.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-08-28T17:20:34.045Z,1503940834.045 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-08-28T17:20:34.361Z,1503940834.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-08-28T17:20:34.361Z,1503940834.361 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-08-28T17:20:47.366Z,1503940847.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-08-28T17:20:48.342Z,1503940848.342 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-08-28T17:20:48.567Z,1503940848.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-08-28T17:20:48.568Z,1503940848.568 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-08-28T17:20:48.673Z,1503940848.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-08-28T17:20:48.673Z,1503940848.673 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-08-28T17:20:49.135Z,1503940849.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-08-28T17:20:49.136Z,1503940849.136 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-08-28T17:20:49.249Z,1503940849.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-08-28T17:20:49.251Z,1503940849.251 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-08-28T17:20:49.255Z,1503940849.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-08-28T17:20:49.495Z,1503940849.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-08-28T17:20:49.496Z,1503940849.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-08-28T17:20:49.602Z,1503940849.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-08-28T17:20:49.697Z,1503940849.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-08-28T17:20:49.805Z,1503940849.805 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-08-28T17:20:49.896Z,1503940849.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-08-28T17:20:50.028Z,1503940850.028 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-08-28T17:20:50.187Z,1503940850.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-08-28T17:20:50.289Z,1503940850.289 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-08-28T17:20:50.386Z,1503940850.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-08-28T17:20:50.475Z,1503940850.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-08-28T17:20:50.658Z,1503940850.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-08-28T17:20:50.739Z,1503940850.739 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-08-28T17:20:50.756Z,1503940850.756 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-08-28T17:20:50.909Z,1503940850.909 [SBIT](DEBUG): Construct Startup Built In Test.
2017-08-28T17:20:50.939Z,1503940850.939 [SBIT] Loaded
2017-08-28T17:20:50.939Z,1503940850.939 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-08-28T17:20:50.940Z,1503940850.940 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-08-28T17:20:50.968Z,1503940850.968 [IBIT] Loaded
2017-08-28T17:20:50.968Z,1503940850.968 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-08-28T17:20:50.972Z,1503940850.972 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-08-28T17:20:51.165Z,1503940851.165 [CBIT] Loaded
2017-08-28T17:20:51.165Z,1503940851.165 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-08-28T17:20:51.165Z,1503940851.165 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-08-28T17:20:51.166Z,1503940851.166 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-08-28T17:20:51.255Z,1503940851.255 [VerticalControl](DEBUG): Construct VerticalControl.
2017-08-28T17:20:51.353Z,1503940851.353 [VerticalControl] Loaded
2017-08-28T17:20:51.354Z,1503940851.354 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-08-28T17:20:51.354Z,1503940851.354 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-08-28T17:20:51.416Z,1503940851.416 [HorizontalControl] Loaded
2017-08-28T17:20:51.416Z,1503940851.416 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-08-28T17:20:51.417Z,1503940851.417 [SpeedControl](DEBUG): Construct SpeedControl.
2017-08-28T17:20:51.423Z,1503940851.423 [SpeedControl] Loaded
2017-08-28T17:20:51.423Z,1503940851.423 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-08-28T17:20:51.424Z,1503940851.424 [BackSeatDriver](DEBUG): Construct BackSeatDriver.
2017-08-28T17:20:51.426Z,1503940851.426 [BackSeatDriver] Loaded
2017-08-28T17:20:51.427Z,1503940851.427 [ComponentRegistry](DEBUG): SyncComponent "BackSeatDriver" handled in the control thread.
2017-08-28T17:20:51.427Z,1503940851.427 [LoopControl](DEBUG): Construct LoopControl.
2017-08-28T17:20:51.428Z,1503940851.428 [LoopControl] Loaded
2017-08-28T17:20:51.428Z,1503940851.428 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-08-28T17:20:51.429Z,1503940851.429 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-08-28T17:20:51.429Z,1503940851.429 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-08-28T17:20:51.469Z,1503940851.469 [DepthRateCalculator] Loaded
2017-08-28T17:20:51.469Z,1503940851.469 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-08-28T17:20:51.474Z,1503940851.474 [PitchRateCalculator] Loaded
2017-08-28T17:20:51.475Z,1503940851.475 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-08-28T17:20:51.490Z,1503940851.490 [SpeedCalculator] Loaded
2017-08-28T17:20:51.490Z,1503940851.490 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-08-28T17:20:51.512Z,1503940851.512 [TempGradientCalculator] Loaded
2017-08-28T17:20:51.512Z,1503940851.512 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-08-28T17:20:51.528Z,1503940851.528 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-08-28T17:20:51.528Z,1503940851.528 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-08-28T17:20:51.533Z,1503940851.533 [YawRateCalculator] Loaded
2017-08-28T17:20:51.533Z,1503940851.533 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-08-28T17:20:51.533Z,1503940851.533 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-08-28T17:20:51.534Z,1503940851.534 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-08-28T17:20:51.652Z,1503940851.652 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-08-28T17:20:51.652Z,1503940851.652 [StratificationFrontDetector](DEBUG): (re)initializing
2017-08-28T17:20:51.652Z,1503940851.652 [StratificationFrontDetector] Loaded
2017-08-28T17:20:51.653Z,1503940851.653 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-08-28T17:20:51.653Z,1503940851.653 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-08-28T17:20:51.654Z,1503940851.654 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-08-28T17:20:51.922Z,1503940851.922 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-08-28T17:20:51.923Z,1503940851.923 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-08-28T17:20:52.040Z,1503940852.040 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-08-28T17:20:52.040Z,1503940852.040 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-08-28T17:20:52.093Z,1503940852.093 [DeadReckonUsingSpeedCalculator] Loaded
2017-08-28T17:20:52.094Z,1503940852.094 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-08-28T17:20:52.111Z,1503940852.111 [NavChart] Loaded
2017-08-28T17:20:52.111Z,1503940852.111 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-08-28T17:20:52.115Z,1503940852.115 [UniversalFixResidualReporter] Loaded
2017-08-28T17:20:52.116Z,1503940852.116 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-08-28T17:20:52.116Z,1503940852.116 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-08-28T17:20:52.117Z,1503940852.117 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-08-28T17:20:52.127Z,1503940852.127 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-08-28T17:20:52.128Z,1503940852.128 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-08-28T17:21:04.397Z,1503940864.397 [Aanderaa_O2] Loaded
2017-08-28T17:21:04.398Z,1503940864.398 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-08-28T17:21:04.409Z,1503940864.409 [CANONSampler] Loaded
2017-08-28T17:21:04.410Z,1503940864.410 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2017-08-28T17:21:04.488Z,1503940864.488 [CTD_NeilBrown] Loaded
2017-08-28T17:21:04.488Z,1503940864.488 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-08-28T17:21:04.489Z,1503940864.489 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2017-08-28T17:21:04.490Z,1503940864.490 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 855
2017-08-28T17:21:04.524Z,1503940864.524 [CTD_Seabird] Loaded
2017-08-28T17:21:04.524Z,1503940864.524 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-08-28T17:21:04.525Z,1503940864.525 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2017-08-28T17:21:04.525Z,1503940864.525 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 856
2017-08-28T17:21:04.540Z,1503940864.540 [PAR_Licor] Loaded
2017-08-28T17:21:04.541Z,1503940864.541 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-08-28T17:21:04.589Z,1503940864.589 [WetLabsBB2FL] Loaded
2017-08-28T17:21:04.589Z,1503940864.589 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-08-28T17:21:04.590Z,1503940864.590 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0
2017-08-28T17:21:04.591Z,1503940864.591 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 857
2017-08-28T17:21:04.591Z,1503940864.591 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-08-28T17:21:04.592Z,1503940864.592 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-08-28T17:21:05.198Z,1503940865.198 [DataOverHttps] Loaded
2017-08-28T17:21:05.198Z,1503940865.198 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-08-28T17:21:05.212Z,1503940865.212 [Depth_Keller] Loaded
2017-08-28T17:21:05.212Z,1503940865.212 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-08-28T17:21:05.217Z,1503940865.217 [DropWeight] Loaded
2017-08-28T17:21:05.217Z,1503940865.217 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-08-28T17:21:05.307Z,1503940865.307 [NAL9602] Loaded
2017-08-28T17:21:05.307Z,1503940865.307 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-08-28T17:21:05.316Z,1503940865.316 [Onboard] Loaded
2017-08-28T17:21:05.317Z,1503940865.317 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-08-28T17:21:05.324Z,1503940865.324 [Radio_Surface] Loaded
2017-08-28T17:21:05.324Z,1503940865.324 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-08-28T17:21:05.325Z,1503940865.325 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A254E0
2017-08-28T17:21:05.326Z,1503940865.326 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 858
2017-08-28T17:21:05.518Z,1503940865.518 [PNI_TCM] Loaded
2017-08-28T17:21:05.518Z,1503940865.518 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-08-28T17:21:07.288Z,1503940867.288 [BPC1] Loaded
2017-08-28T17:21:07.288Z,1503940867.288 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-08-28T17:21:07.288Z,1503940867.288 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-08-28T17:21:07.289Z,1503940867.289 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-08-28T17:21:07.406Z,1503940867.406 [BuoyancyServo] Loaded
2017-08-28T17:21:07.406Z,1503940867.406 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-08-28T17:21:07.418Z,1503940867.418 [ElevatorServo] Loaded
2017-08-28T17:21:07.419Z,1503940867.419 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-08-28T17:21:07.430Z,1503940867.430 [MassServo] Loaded
2017-08-28T17:21:07.431Z,1503940867.431 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-08-28T17:21:07.442Z,1503940867.442 [RudderServo] Loaded
2017-08-28T17:21:07.443Z,1503940867.443 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-08-28T17:21:07.454Z,1503940867.454 [ThrusterServo] Loaded
2017-08-28T17:21:07.455Z,1503940867.455 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-08-28T17:21:07.455Z,1503940867.455 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-08-28T17:21:07.456Z,1503940867.456 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-08-28T17:21:07.584Z,1503940867.584 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-08-28T17:21:07.585Z,1503940867.585 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-08-28T17:21:07.624Z,1503940867.624 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-08-28T17:21:07.628Z,1503940867.628 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-08-28T17:21:07.629Z,1503940867.629 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-08-28T17:21:07.636Z,1503940867.636 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-08-28T17:21:07.637Z,1503940867.637 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD64E0
2017-08-28T17:21:07.637Z,1503940867.637 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 859
2017-08-28T17:21:07.643Z,1503940867.643 [Supervisor](INFO): Main Thread ID is 764
2017-08-28T17:21:07.643Z,1503940867.643 [Supervisor](DEBUG): Running supervisor.
2017-08-28T17:21:07.644Z,1503940867.644 [CommandLine ThreadHandler](INFO): Handler Thread ID is 860
2017-08-28T17:21:07.647Z,1503940867.647 [controlThread ThreadHandler](INFO): Handler Thread ID is 861
2017-08-28T17:21:07.647Z,1503940867.647 [controlThread](DEBUG): Initializing ControlThread
2017-08-28T17:21:07.648Z,1503940867.648 [SBIT](INFO): Initialize SBIT Component.
2017-08-28T17:21:07.649Z,1503940867.649 [SBIT](IMPORTANT): git: 2017-08-02
2017-08-28T17:21:07.649Z,1503940867.649 [SBIT](INFO): git hash: b8bd19726fcc84d3133bb9e1cbad184cc93bb52c
2017-08-28T17:21:07.651Z,1503940867.651 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-08-28T17:21:07.651Z,1503940867.651 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-08-28T17:21:07.652Z,1503940867.652 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2017-08-28T17:21:07.653Z,1503940867.653 [IBIT](INFO): Initialize IBIT Component.
2017-08-28T17:21:07.654Z,1503940867.654 [CBIT](DEBUG): Initialize CBIT Component.
2017-08-28T17:21:07.654Z,1503940867.654 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-08-28T17:21:07.655Z,1503940867.655 [logger ThreadHandler](INFO): Handler Thread ID is 862
2017-08-28T17:21:07.672Z,1503940867.672 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 863
2017-08-28T17:21:07.673Z,1503940867.673 [CTD_NeilBrown](INFO): Powering down
2017-08-28T17:21:07.704Z,1503940867.704 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 864
2017-08-28T17:21:07.704Z,1503940867.704 [CTD_Seabird](INFO): Initializing
2017-08-28T17:21:07.736Z,1503940867.736 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-08-28T17:21:07.738Z,1503940867.738 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-08-28T17:21:07.739Z,1503940867.739 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-08-28T17:21:07.740Z,1503940867.740 [BackSeatDriver](DEBUG): Initialize BackSeatDriver Component.
2017-08-28T17:21:07.798Z,1503940867.798 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 866
2017-08-28T17:21:07.800Z,1503940867.800 [WetLabsBB2FL](INFO): Powering down
2017-08-28T17:21:07.964Z,1503940867.964 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-08-28T17:21:07.964Z,1503940867.964 [CTD_Seabird](INFO): Powering down
2017-08-28T17:21:07.988Z,1503940867.988 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 867
2017-08-28T17:21:07.995Z,1503940867.995 [Radio_Surface](INFO): Powering up
2017-08-28T17:21:08.001Z,1503940868.001 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 868
2017-08-28T17:21:08.007Z,1503940868.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-08-28T17:21:08.007Z,1503940868.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-08-28T17:21:08.007Z,1503940868.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-08-28T17:21:08.007Z,1503940868.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-08-28T17:21:08.007Z,1503940868.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-08-28T17:21:08.008Z,1503940868.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-08-28T17:21:08.008Z,1503940868.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-08-28T17:21:08.008Z,1503940868.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-08-28T17:21:08.008Z,1503940868.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-08-28T17:21:08.009Z,1503940868.009 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-08-28T17:21:08.009Z,1503940868.009 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-08-28T17:21:08.009Z,1503940868.009 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-08-28T17:21:08.009Z,1503940868.009 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-08-28T17:21:08.009Z,1503940868.009 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-08-28T17:21:08.010Z,1503940868.010 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-08-28T17:21:08.010Z,1503940868.010 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-08-28T17:21:08.996Z,1503940868.996 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-08-28T17:21:08.996Z,1503940868.996 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-08-28T17:21:08.997Z,1503940868.997 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-08-28T17:21:08.997Z,1503940868.997 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-08-28T17:21:08.997Z,1503940868.997 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-08-28T17:21:08.998Z,1503940868.998 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-08-28T17:21:08.999Z,1503940868.999 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-08-28T17:21:08.000Z,1503940869.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:21:09.001Z,1503940869.001 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:21:09.001Z,1503940869.001 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:21:09.002Z,1503940869.002 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:21:09.002Z,1503940869.002 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:21:09.003Z,1503940869.003 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:21:09.003Z,1503940869.003 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-08-28T17:21:09.004Z,1503940869.004 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-08-28T17:21:09.016Z,1503940869.016 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-08-28T17:21:09.136Z,1503940869.136 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-08-28T17:21:09.147Z,1503940869.147 [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>


2017-08-28T17:21:09.147Z,1503940869.147 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-08-28T17:21:09.203Z,1503940869.203 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-08-28T17:21:09.205Z,1503940869.205 [Default:A.Wait](DEBUG): Construct Wait.
2017-08-28T17:21:09.222Z,1503940869.222 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-08-28T17:21:09.249Z,1503940869.249 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-08-28T17:21:09.251Z,1503940869.251 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-08-28T17:21:09.277Z,1503940869.277 [Default:E.Execute](DEBUG): Construct Execute.
2017-08-28T17:21:09.280Z,1503940869.280 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <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>


2017-08-28T17:21:09.295Z,1503940869.295 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,BackSeatDriver,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-08-28T17:21:09.333Z,1503940869.333 [CANONSampler](INFO): Powering down
2017-08-28T17:21:09.444Z,1503940869.444 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-08-28T17:21:09.819Z,1503940869.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:09.979Z,1503940869.979 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:10.178Z,1503940870.178 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-08-28T17:21:10.184Z,1503940870.184 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-08-28T17:21:10.190Z,1503940870.190 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-08-28T17:21:10.196Z,1503940870.196 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-08-28T17:21:10.224Z,1503940870.224 [MassServo](DEBUG): Initializing EZServoServo.
2017-08-28T17:21:10.232Z,1503940870.232 [MassServo](DEBUG): Initializing MassServo.
2017-08-28T17:21:10.245Z,1503940870.245 [RudderServo](DEBUG): Initializing EZServoServo.
2017-08-28T17:21:10.253Z,1503940870.253 [RudderServo](DEBUG): Initializing RudderServo.
2017-08-28T17:21:10.258Z,1503940870.258 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-08-28T17:21:10.264Z,1503940870.264 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-08-28T17:21:10.615Z,1503940870.615 [NAL9602](INFO): Powering up NAL9602
2017-08-28T17:21:10.698Z,1503940870.698 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:10.699Z,1503940870.699 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:11.129Z,1503940871.129 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-08-28T17:21:11.129Z,1503940871.129 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-08-28T17:21:11.129Z,1503940871.129 [BuoyancyServo] Communications Fault, FailCount= 1
2017-08-28T17:21:11.129Z,1503940871.129 [BuoyancyServo](ERROR): Communications Fault
2017-08-28T17:21:11.337Z,1503940871.337 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-08-28T17:21:11.410Z,1503940871.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:11.411Z,1503940871.411 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:11.522Z,1503940871.522 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-08-28T17:21:11.523Z,1503940871.523 [BuoyancyServo](INFO): Powering down
2017-08-28T17:21:11.834Z,1503940871.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:11.835Z,1503940871.835 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:12.214Z,1503940872.214 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:12.215Z,1503940872.215 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:12.663Z,1503940872.663 [Aanderaa_O2](INFO): Powering down
2017-08-28T17:21:12.807Z,1503940872.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:12.816Z,1503940872.816 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:13.125Z,1503940873.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:13.126Z,1503940873.126 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-08-28T17:21:13.826Z,1503940873.826 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-08-28T17:21:13.826Z,1503940873.826 [BuoyancyServo] No Fault, FailCount= 1
2017-08-28T17:21:14.050Z,1503940874.050 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-08-28T17:21:14.171Z,1503940874.171 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-08-28T17:21:21.639Z,1503940881.639 [NAL9602](INFO): NAL9602 initialized
2017-08-28T17:22:19.408Z,1503940939.408 [SBIT](IMPORTANT): Beginning Startup BIT
2017-08-28T17:22:19.412Z,1503940939.412 [CBIT](IMPORTANT): Beginning ground fault scan
2017-08-28T17:22:29.827Z,1503940949.827 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.027518
CHAN A2 (12V): -0.002010
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001368
CHAN B1 (3.15aV): 4.767929
CHAN B2 (3.15bV): 0.002570
CHAN B3 (GND): -0.000569
OPEN: -0.000386
Full Scale Calc: 4.765 mA, -1.589 mA
2017-08-28T17:22:48.460Z,1503940968.460 [BPC1](ERROR): Failed to parse bank B battery data
2017-08-28T17:22:48.460Z,1503940968.460 [BPC1] Data Fault, FailCount= 1
2017-08-28T17:22:48.460Z,1503940968.460 [BPC1](ERROR): Data Fault
2017-08-28T17:22:48.649Z,1503940968.649 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T17:22:49.835Z,1503940969.835 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:22:49.835Z,1503940969.835 [BPC1] No Fault, FailCount= 1
2017-08-28T17:23:06.013Z,1503940986.013 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:23:06.013Z,1503940986.013 [BPC1] Communications Fault, FailCount= 2
2017-08-28T17:23:06.013Z,1503940986.013 [BPC1](ERROR): Communications Fault
2017-08-28T17:23:06.225Z,1503940986.225 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:23:07.410Z,1503940987.410 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:23:07.410Z,1503940987.410 [BPC1] No Fault, FailCount= 2
2017-08-28T17:23:13.365Z,1503940993.365 [SBIT](IMPORTANT): SBIT PASSED
2017-08-28T17:23:13.625Z,1503940993.625 [MissionManager](IMPORTANT): Started mission Startup
2017-08-28T17:23:13.625Z,1503940993.625 [Startup] Running Loop=1
2017-08-28T17:23:13.625Z,1503940993.625 [Startup](DEBUG): Aggregate::initialize Startup
2017-08-28T17:23:13.625Z,1503940993.625 [Startup:A.GoToSurface] Running Loop=1
2017-08-28T17:23:13.626Z,1503940993.626 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-08-28T17:23:13.626Z,1503940993.626 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-08-28T17:23:13.635Z,1503940993.635 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-08-28T17:23:13.635Z,1503940993.635 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-08-28T17:23:13.635Z,1503940993.635 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-08-28T17:23:13.636Z,1503940993.636 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-08-28T17:23:13.645Z,1503940993.645 [Startup:StartupSatComms] Running Loop=1
2017-08-28T17:23:13.646Z,1503940993.646 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-08-28T17:23:13.646Z,1503940993.646 [Startup:StartupSatComms:A] Running Loop=1
2017-08-28T17:23:14.058Z,1503940994.058 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-08-28T17:23:23.619Z,1503941003.619 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:23:23.619Z,1503941003.619 [BPC1] Communications Fault, FailCount= 3
2017-08-28T17:23:23.619Z,1503941003.619 [BPC1](ERROR): Communications Fault
2017-08-28T17:23:23.768Z,1503941003.768 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:23:24.974Z,1503941004.974 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:23:24.974Z,1503941004.974 [BPC1] No Fault, FailCount= 3
2017-08-28T17:23:31.393Z,1503941011.393 [CommandLine](IMPORTANT): got command get CBIT.loadAtStartup
2017-08-28T17:23:31.393Z,1503941011.393 [CommandLine](IMPORTANT): CBIT.loadAtStartup 1 bool
2017-08-28T17:23:41.227Z,1503941021.227 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:23:41.227Z,1503941021.227 [BPC1] Communications Fault, FailCount= 4
2017-08-28T17:23:41.227Z,1503941021.227 [BPC1](ERROR): Communications Fault
2017-08-28T17:23:41.412Z,1503941021.412 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:23:42.582Z,1503941022.582 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:23:42.582Z,1503941022.582 [BPC1] No Fault, FailCount= 4
2017-08-28T17:23:49.052Z,1503941029.052 [CommandLine](IMPORTANT): got command 
2017-08-28T17:23:49.052Z,1503941029.052 [CommandLine](FAULT): Incomplete syntax. Try: help
2017-08-28T17:23:56.156Z,1503941036.156 [CommandLine](IMPORTANT): got command configSet list
2017-08-28T17:23:56.156Z,1503941036.156 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2017-08-28T17:23:56.157Z,1503941036.157 [CommandLine](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2017-08-28T17:23:56.157Z,1503941036.157 [CommandLine](IMPORTANT): CANONSampler.simulateHardware=0 bool;

2017-08-28T17:23:56.157Z,1503941036.157 [CommandLine](IMPORTANT): CBIT.abortDepth=250 meter;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): CBIT.runFaultClassifier=1 bool;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): CBIT.stopDepth=205 meter;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): Normal linearApproximation Rowe_600LCM.height_above_sea_floor 5.000000 meter;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): ThrusterServo.currLimit=80 percent;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=315 milliliter;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1.3 centimeter;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-35 millimeter;

2017-08-28T17:23:56.158Z,1503941036.158 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=26 millimeter;

2017-08-28T17:23:58.820Z,1503941038.820 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:23:58.820Z,1503941038.820 [BPC1] Communications Fault, FailCount= 5
2017-08-28T17:23:58.820Z,1503941038.820 [BPC1](ERROR): Communications Fault
2017-08-28T17:23:58.968Z,1503941038.968 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:24:00.166Z,1503941040.166 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:24:00.166Z,1503941040.166 [BPC1] No Fault, FailCount= 5
2017-08-28T17:24:09.219Z,1503941049.219 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:24:09.219Z,1503941049.219 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-08-28T17:24:09.219Z,1503941049.219 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:24:09.221Z,1503941049.221 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:24:09.221Z,1503941049.221 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-08-28T17:24:09.221Z,1503941049.221 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:24:09.389Z,1503941049.389 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:24:09.389Z,1503941049.389 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:24:09.765Z,1503941049.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:24:09.765Z,1503941049.765 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-08-28T17:24:09.766Z,1503941049.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:24:09.766Z,1503941049.766 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-08-28T17:24:10.023Z,1503941050.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:24:10.024Z,1503941050.024 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:24:10.024Z,1503941050.024 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:24:10.024Z,1503941050.024 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:24:10.025Z,1503941050.025 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:24:10.025Z,1503941050.025 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:24:10.424Z,1503941050.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-08-28T17:24:10.820Z,1503941050.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-08-28T17:24:11.248Z,1503941051.248 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-08-28T17:24:11.636Z,1503941051.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-08-28T17:24:12.030Z,1503941052.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-08-28T17:24:12.459Z,1503941052.459 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-08-28T17:24:12.825Z,1503941052.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-08-28T17:24:13.266Z,1503941053.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-08-28T17:24:13.629Z,1503941053.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-08-28T17:24:14.024Z,1503941054.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-08-28T17:24:14.027Z,1503941054.027 [Startup:StartupSatComms:A](INFO): Timed out from 2017-08-28T17:23:13.6Z
2017-08-28T17:24:14.027Z,1503941054.027 [Startup:StartupSatComms:A] Stopped
2017-08-28T17:24:14.027Z,1503941054.027 [Startup:StartupSatComms:B] Running Loop=1
2017-08-28T17:24:14.429Z,1503941054.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-08-28T17:24:14.432Z,1503941054.432 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-08-28T17:24:14.825Z,1503941054.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-08-28T17:24:15.222Z,1503941055.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-08-28T17:24:15.627Z,1503941055.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-08-28T17:24:16.029Z,1503941056.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-08-28T17:24:16.429Z,1503941056.429 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:24:16.429Z,1503941056.429 [BPC1] Communications Fault, FailCount= 6
2017-08-28T17:24:16.430Z,1503941056.430 [BPC1](ERROR): Communications Fault
2017-08-28T17:24:16.437Z,1503941056.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-08-28T17:24:16.576Z,1503941056.576 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:24:16.837Z,1503941056.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-08-28T17:24:17.250Z,1503941057.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-08-28T17:24:17.641Z,1503941057.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-08-28T17:24:17.782Z,1503941057.782 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:24:17.782Z,1503941057.782 [BPC1] No Fault, FailCount= 6
2017-08-28T17:24:18.125Z,1503941058.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2017-08-28T17:24:18.432Z,1503941058.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-08-28T17:24:18.852Z,1503941058.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-08-28T17:24:19.232Z,1503941059.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-08-28T17:24:19.635Z,1503941059.635 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-08-28T17:24:20.031Z,1503941060.031 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-08-28T17:24:20.409Z,1503941060.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-08-28T17:24:20.838Z,1503941060.838 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-08-28T17:24:21.235Z,1503941061.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-08-28T17:24:21.641Z,1503941061.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-08-28T17:24:22.049Z,1503941062.049 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-08-28T17:24:22.443Z,1503941062.443 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-08-28T17:24:22.843Z,1503941062.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-08-28T17:24:23.222Z,1503941063.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-08-28T17:24:23.616Z,1503941063.616 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-08-28T17:24:24.023Z,1503941064.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-08-28T17:24:24.424Z,1503941064.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-08-28T17:24:24.820Z,1503941064.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-08-28T17:24:25.220Z,1503941065.220 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-08-28T17:24:25.625Z,1503941065.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-08-28T17:24:26.028Z,1503941066.028 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-08-28T17:24:26.422Z,1503941066.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-08-28T17:24:26.851Z,1503941066.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-08-28T17:24:27.247Z,1503941067.247 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-08-28T17:24:27.627Z,1503941067.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-08-28T17:24:28.054Z,1503941068.054 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-08-28T17:24:28.422Z,1503941068.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-08-28T17:24:28.831Z,1503941068.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-08-28T17:24:34.016Z,1503941074.016 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:24:34.016Z,1503941074.016 [BPC1] Communications Fault, FailCount= 7
2017-08-28T17:24:34.016Z,1503941074.016 [BPC1](ERROR): Communications Fault
2017-08-28T17:24:34.195Z,1503941074.195 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:24:35.365Z,1503941075.365 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:24:35.365Z,1503941075.365 [BPC1] No Fault, FailCount= 7
2017-08-28T17:24:51.615Z,1503941091.615 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:24:51.616Z,1503941091.616 [BPC1] Communications Fault, FailCount= 8
2017-08-28T17:24:51.616Z,1503941091.616 [BPC1](ERROR): Communications Fault
2017-08-28T17:24:51.771Z,1503941091.771 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:24:52.977Z,1503941092.977 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:24:52.977Z,1503941092.977 [BPC1] No Fault, FailCount= 8
2017-08-28T17:25:09.232Z,1503941109.232 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:25:09.232Z,1503941109.232 [BPC1] Communications Fault, FailCount= 9
2017-08-28T17:25:09.232Z,1503941109.232 [BPC1](ERROR): Communications Fault
2017-08-28T17:25:09.380Z,1503941109.380 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:25:10.561Z,1503941110.561 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:25:10.561Z,1503941110.561 [BPC1] No Fault, FailCount= 9
2017-08-28T17:25:14.038Z,1503941114.038 [Startup:StartupSatComms:B](INFO): Timed out from 2017-08-28T17:24:14.0Z
2017-08-28T17:25:14.038Z,1503941114.038 [Startup:StartupSatComms:B] Stopped
2017-08-28T17:25:14.038Z,1503941114.038 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-08-28T17:25:14.038Z,1503941114.038 [Startup:StartupSatComms] Stopped
2017-08-28T17:25:14.038Z,1503941114.038 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-08-28T17:25:14.039Z,1503941114.039 [Startup](INFO): Completed Startup
2017-08-28T17:25:14.039Z,1503941114.039 [MissionManager](INFO): Startup is completed.
2017-08-28T17:25:14.039Z,1503941114.039 [MissionManager](INFO): Uninitializing Mission Startup
2017-08-28T17:25:14.040Z,1503941114.040 [Startup] Stopped
2017-08-28T17:25:14.040Z,1503941114.040 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-08-28T17:25:14.040Z,1503941114.040 [Startup:A.GoToSurface] Stopped
2017-08-28T17:25:14.040Z,1503941114.040 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-08-28T17:25:14.438Z,1503941114.438 [MissionManager](IMPORTANT): Started mission Default
2017-08-28T17:25:14.438Z,1503941114.438 [Default] Running Loop=1
2017-08-28T17:25:14.438Z,1503941114.438 [Default](DEBUG): Aggregate::initialize Default
2017-08-28T17:25:14.439Z,1503941114.439 [Default:B.GoToSurface] Running Loop=1
2017-08-28T17:25:14.439Z,1503941114.439 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-08-28T17:25:14.439Z,1503941114.439 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-08-28T17:25:14.439Z,1503941114.439 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-08-28T17:25:14.439Z,1503941114.439 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-08-28T17:25:14.440Z,1503941114.440 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-08-28T17:25:14.440Z,1503941114.440 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-08-28T17:25:14.440Z,1503941114.440 [Default:A.Wait] Running Loop=1
2017-08-28T17:25:14.440Z,1503941114.440 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-08-28T17:25:26.817Z,1503941126.817 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:25:26.817Z,1503941126.817 [BPC1] Communications Fault, FailCount= 10
2017-08-28T17:25:26.817Z,1503941126.817 [BPC1](ERROR): Communications Fault
2017-08-28T17:25:26.991Z,1503941126.991 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:25:26.991Z,1503941126.991 [CBIT](CRITICAL): Communications Fault in component: BPC1
2017-08-28T17:25:27.362Z,1503941127.362 [CommandLine](FAULT): Scheduling is paused
2017-08-28T17:25:27.363Z,1503941127.363 [CBIT](INFO): Critical error at 20170828T172526
2017-08-28T17:25:27.363Z,1503941127.363 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-08-28T17:25:27.624Z,1503941127.624 [Default:A.Wait](INFO): Done Waiting.
2017-08-28T17:25:27.625Z,1503941127.625 [Default:A.Wait] Stopped
2017-08-28T17:25:27.625Z,1503941127.625 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T17:25:28.030Z,1503941128.030 [Default:CheckIn] Running Loop=1
2017-08-28T17:25:28.030Z,1503941128.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T17:25:28.030Z,1503941128.030 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T17:25:28.426Z,1503941128.426 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-08-28T17:26:10.392Z,1503941170.392 [NAL9602](INFO): SBD MO Status=2, MOMSN=65, MT Status=2, MTMSN=0
2017-08-28T17:26:10.392Z,1503941170.392 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-08-28T17:26:25.580Z,1503941185.580 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-08-28T17:26:46.818Z,1503941206.818 [PNI_TCM](ERROR): CRC does not match. Expected:0x7929 got:0x90
2017-08-28T17:27:12.512Z,1503941232.512 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:27:12.513Z,1503941232.513 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2017-08-28T17:27:12.513Z,1503941232.513 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:27:12.514Z,1503941232.514 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:27:12.515Z,1503941232.515 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2017-08-28T17:27:12.515Z,1503941232.515 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:27:12.647Z,1503941232.647 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:27:12.647Z,1503941232.647 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:27:12.891Z,1503941232.891 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:27:12.891Z,1503941232.891 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2017-08-28T17:27:12.892Z,1503941232.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:27:12.892Z,1503941232.892 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2017-08-28T17:27:13.135Z,1503941233.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:27:13.135Z,1503941233.135 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:27:13.135Z,1503941233.135 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:27:13.136Z,1503941233.136 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:27:13.136Z,1503941233.136 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:27:13.137Z,1503941233.137 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:27:13.484Z,1503941233.484 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003707
2017-08-28T17:27:13.575Z,1503941233.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.1 s old, using for 20.0 s.
2017-08-28T17:27:13.921Z,1503941233.921 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.4 s old, using for 20.0 s.
2017-08-28T17:27:14.304Z,1503941234.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.8 s old, using for 20.0 s.
2017-08-28T17:27:14.723Z,1503941234.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.2 s old, using for 20.0 s.
2017-08-28T17:27:15.133Z,1503941235.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.6 s old, using for 20.0 s.
2017-08-28T17:27:15.535Z,1503941235.535 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.0 s old, using for 20.0 s.
2017-08-28T17:27:15.903Z,1503941235.903 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.4 s old, using for 20.0 s.
2017-08-28T17:27:16.302Z,1503941236.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.8 s old, using for 20.0 s.
2017-08-28T17:27:16.702Z,1503941236.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.2 s old, using for 20.0 s.
2017-08-28T17:27:17.103Z,1503941237.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.6 s old, using for 20.0 s.
2017-08-28T17:27:17.531Z,1503941237.531 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.0 s old, using for 20.0 s.
2017-08-28T17:27:17.912Z,1503941237.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.4 s old, using for 20.0 s.
2017-08-28T17:27:18.344Z,1503941238.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.8 s old, using for 20.0 s.
2017-08-28T17:27:18.706Z,1503941238.706 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.2 s old, using for 20.0 s.
2017-08-28T17:27:19.104Z,1503941239.104 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.6 s old, using for 20.0 s.
2017-08-28T17:27:19.509Z,1503941239.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.0 s old, using for 20.0 s.
2017-08-28T17:27:19.910Z,1503941239.910 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.4 s old, using for 20.0 s.
2017-08-28T17:27:20.308Z,1503941240.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.8 s old, using for 20.0 s.
2017-08-28T17:27:20.734Z,1503941240.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.2 s old, using for 20.0 s.
2017-08-28T17:27:21.109Z,1503941241.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.6 s old, using for 20.0 s.
2017-08-28T17:27:21.506Z,1503941241.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.0 s old, using for 20.0 s.
2017-08-28T17:27:21.908Z,1503941241.908 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.4 s old, using for 20.0 s.
2017-08-28T17:27:22.311Z,1503941242.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.8 s old, using for 20.0 s.
2017-08-28T17:27:22.711Z,1503941242.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2017-08-28T17:27:23.112Z,1503941243.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2017-08-28T17:27:23.534Z,1503941243.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2017-08-28T17:27:23.909Z,1503941243.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2017-08-28T17:27:24.339Z,1503941244.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2017-08-28T17:27:24.709Z,1503941244.709 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.2 s old, using for 20.0 s.
2017-08-28T17:27:25.111Z,1503941245.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.6 s old, using for 20.0 s.
2017-08-28T17:27:25.506Z,1503941245.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2017-08-28T17:27:25.920Z,1503941245.920 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s.
2017-08-28T17:27:26.307Z,1503941246.307 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s.
2017-08-28T17:27:26.706Z,1503941246.706 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.2 s old, using for 20.0 s.
2017-08-28T17:27:27.122Z,1503941247.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.6 s old, using for 20.0 s.
2017-08-28T17:27:27.530Z,1503941247.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2017-08-28T17:27:27.915Z,1503941247.915 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2017-08-28T17:27:28.315Z,1503941248.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.8 s old, using for 20.0 s.
2017-08-28T17:27:28.715Z,1503941248.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.2 s old, using for 20.0 s.
2017-08-28T17:27:29.102Z,1503941249.102 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.6 s old, using for 20.0 s.
2017-08-28T17:27:29.521Z,1503941249.521 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.0 s old, using for 20.0 s.
2017-08-28T17:27:29.929Z,1503941249.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.4 s old, using for 20.0 s.
2017-08-28T17:27:30.328Z,1503941250.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s.
2017-08-28T17:27:30.716Z,1503941250.716 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.2 s old, using for 20.0 s.
2017-08-28T17:27:31.116Z,1503941251.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.6 s old, using for 20.0 s.
2017-08-28T17:27:31.503Z,1503941251.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s.
2017-08-28T17:27:31.922Z,1503941251.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.4 s old, using for 20.0 s.
2017-08-28T17:27:32.315Z,1503941252.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2017-08-28T17:29:12.401Z,1503941352.401 [PNI_TCM](ERROR): CRC does not match. Expected:0x43266 got:0x45781
2017-08-28T17:29:15.599Z,1503941355.599 [PNI_TCM](ERROR): CRC does not match. Expected:0x23369 got:0x60187
2017-08-28T17:29:16.208Z,1503941356.208 [CommandLine](IMPORTANT): got command burn 5.000000
2017-08-28T17:29:18.790Z,1503941358.790 [PNI_TCM](ERROR): CRC does not match. Expected:0x43664 got:0x49030
2017-08-28T17:29:19.199Z,1503941359.199 [PNI_TCM](ERROR): CRC does not match. Expected:0x33105 got:0x53520
2017-08-28T17:29:21.252Z,1503941361.252 [CommandLine](IMPORTANT): Deactivating dropweight wire
2017-08-28T17:29:25.200Z,1503941365.200 [PNI_TCM](ERROR): CRC does not match. Expected:0x37910 got:0x49331
2017-08-28T17:29:28.420Z,1503941368.420 [PNI_TCM](ERROR): CRC does not match. Expected:0x16556 got:0x3365
2017-08-28T17:29:30.004Z,1503941370.004 [PNI_TCM](ERROR): CRC does not match. Expected:0x6337 got:0x49947
2017-08-28T17:29:33.197Z,1503941373.197 [PNI_TCM](ERROR): CRC does not match. Expected:0x2237 got:0x7490
2017-08-28T17:29:42.804Z,1503941382.804 [PNI_TCM](ERROR): CRC does not match. Expected:0x37016 got:0x49152
2017-08-28T17:30:13.409Z,1503941413.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:30:13.409Z,1503941413.409 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2017-08-28T17:30:13.409Z,1503941413.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:30:13.411Z,1503941413.411 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:30:13.411Z,1503941413.411 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2017-08-28T17:30:13.411Z,1503941413.411 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:30:13.520Z,1503941413.520 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:30:13.521Z,1503941413.521 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:30:13.929Z,1503941413.929 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:30:13.929Z,1503941413.929 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2017-08-28T17:30:13.929Z,1503941413.929 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:30:13.929Z,1503941413.929 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2017-08-28T17:30:14.198Z,1503941414.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:30:14.199Z,1503941414.199 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:30:14.199Z,1503941414.199 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:30:14.199Z,1503941414.199 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:30:14.212Z,1503941414.212 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:30:14.212Z,1503941414.212 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:30:14.595Z,1503941414.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-08-28T17:30:14.986Z,1503941414.986 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-08-28T17:30:15.403Z,1503941415.403 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-08-28T17:30:15.804Z,1503941415.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-08-28T17:30:16.200Z,1503941416.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-08-28T17:30:16.598Z,1503941416.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-08-28T17:30:16.998Z,1503941416.998 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-08-28T17:30:17.397Z,1503941417.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-08-28T17:30:17.803Z,1503941417.803 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-08-28T17:30:18.204Z,1503941418.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-08-28T17:30:18.596Z,1503941418.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-08-28T17:30:18.997Z,1503941418.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-08-28T17:30:19.395Z,1503941419.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-08-28T17:30:19.799Z,1503941419.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-08-28T17:30:20.197Z,1503941420.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-08-28T17:30:20.604Z,1503941420.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-08-28T17:30:21.001Z,1503941421.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-08-28T17:30:21.409Z,1503941421.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-08-28T17:30:21.809Z,1503941421.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-08-28T17:30:22.198Z,1503941422.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-08-28T17:30:22.599Z,1503941422.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-08-28T17:30:22.000Z,1503941423.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-08-28T17:30:23.399Z,1503941423.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-08-28T17:30:23.809Z,1503941423.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-08-28T17:30:24.209Z,1503941424.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-08-28T17:30:24.593Z,1503941424.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-08-28T17:30:24.995Z,1503941424.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-08-28T17:30:25.411Z,1503941425.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-08-28T17:30:25.799Z,1503941425.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-08-28T17:30:26.203Z,1503941426.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-08-28T17:30:26.598Z,1503941426.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-08-28T17:30:27.001Z,1503941427.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-08-28T17:30:27.114Z,1503941427.114 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:30:27.114Z,1503941427.114 [BPC1] No Fault, FailCount= 10
2017-08-28T17:30:27.476Z,1503941427.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2017-08-28T17:30:27.843Z,1503941427.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-08-28T17:30:28.207Z,1503941428.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-08-28T17:30:28.211Z,1503941428.211 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-08-28T17:25:28.0Z
2017-08-28T17:30:28.211Z,1503941428.211 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T17:30:28.211Z,1503941428.211 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-08-28T17:30:28.605Z,1503941428.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-08-28T17:30:28.608Z,1503941428.608 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-08-28T17:30:28.000Z,1503941429.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-08-28T17:30:29.410Z,1503941429.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-08-28T17:30:32.695Z,1503941432.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2017-08-28T17:30:32.834Z,1503941432.834 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20170828T172032/Courier0004.lzma
2017-08-28T17:30:32.909Z,1503941432.909 [BPC1](INFO): Failure count cleared after critical for BPC1
2017-08-28T17:30:32.943Z,1503941432.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2017-08-28T17:30:33.262Z,1503941433.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2017-08-28T17:30:33.629Z,1503941433.629 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Courier0004.lzma.bak
2017-08-28T17:30:33.629Z,1503941433.629 [DataOverHttps](INFO): SBD MOMSN=5068779
2017-08-28T17:31:04.420Z,1503941464.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-08-28T17:31:07.815Z,1503941467.815 [CBIT](DEBUG): EFC running - data check-sum false
2017-08-28T17:31:22.828Z,1503941482.828 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-08-28T17:31:22.828Z,1503941482.828 [NAL9602] Data Fault, FailCount= 1
2017-08-28T17:31:22.828Z,1503941482.828 [NAL9602](ERROR): Data Fault
2017-08-28T17:31:22.994Z,1503941482.994 [CBIT](ERROR): Data Fault in component: NAL9602
2017-08-28T17:31:23.226Z,1503941483.226 [NAL9602](INFO): Powering down
2017-08-28T17:31:24.180Z,1503941484.180 [CBIT](INFO): Clearing failed state for component NAL9602
2017-08-28T17:31:24.180Z,1503941484.180 [NAL9602] No Fault, FailCount= 1
2017-08-28T17:31:28.427Z,1503941488.427 [NAL9602](INFO): Powering up NAL9602
2017-08-28T17:31:35.225Z,1503941495.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-08-28T17:31:39.630Z,1503941499.630 [NAL9602](INFO): NAL9602 initialized
2017-08-28T17:32:05.625Z,1503941525.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-08-28T17:32:36.422Z,1503941556.422 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-08-28T17:32:44.863Z,1503941564.863 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:32:44.863Z,1503941564.863 [BPC1] Communications Fault, FailCount= 1
2017-08-28T17:32:44.863Z,1503941564.863 [BPC1](ERROR): Communications Fault
2017-08-28T17:32:44.988Z,1503941564.988 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:32:46.186Z,1503941566.186 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:32:46.186Z,1503941566.186 [BPC1] No Fault, FailCount= 1
2017-08-28T17:33:02.048Z,1503941582.048 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:33:02.048Z,1503941582.048 [BPC1] Communications Fault, FailCount= 2
2017-08-28T17:33:02.048Z,1503941582.048 [BPC1](ERROR): Communications Fault
2017-08-28T17:33:02.207Z,1503941582.207 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:33:03.397Z,1503941583.397 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:33:03.397Z,1503941583.397 [BPC1] No Fault, FailCount= 2
2017-08-28T17:33:07.226Z,1503941587.226 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-08-28T17:33:14.462Z,1503941594.462 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:33:14.462Z,1503941594.462 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2017-08-28T17:33:14.462Z,1503941594.462 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:33:14.464Z,1503941594.464 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:33:14.464Z,1503941594.464 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2017-08-28T17:33:14.464Z,1503941594.464 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:33:14.591Z,1503941594.591 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:33:14.591Z,1503941594.591 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:33:14.999Z,1503941594.999 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:33:14.999Z,1503941594.999 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2017-08-28T17:33:14.000Z,1503941595.000 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:33:14.000Z,1503941595.000 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2017-08-28T17:33:15.303Z,1503941595.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:33:15.303Z,1503941595.303 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:33:15.304Z,1503941595.304 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:33:15.304Z,1503941595.304 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:33:15.305Z,1503941595.305 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:33:15.305Z,1503941595.305 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:33:15.690Z,1503941595.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-08-28T17:33:16.099Z,1503941596.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-08-28T17:33:16.469Z,1503941596.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-08-28T17:33:16.870Z,1503941596.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-08-28T17:33:17.271Z,1503941597.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-08-28T17:33:17.675Z,1503941597.675 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-08-28T17:33:18.076Z,1503941598.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-08-28T17:33:18.482Z,1503941598.482 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-08-28T17:33:18.893Z,1503941598.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-08-28T17:33:19.283Z,1503941599.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-08-28T17:33:19.687Z,1503941599.687 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-08-28T17:33:20.099Z,1503941600.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-08-28T17:33:20.479Z,1503941600.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-08-28T17:33:20.881Z,1503941600.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-08-28T17:33:21.284Z,1503941601.284 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-08-28T17:33:21.679Z,1503941601.679 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-08-28T17:33:22.084Z,1503941602.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-08-28T17:33:22.476Z,1503941602.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-08-28T17:33:22.870Z,1503941602.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-08-28T17:33:23.262Z,1503941603.262 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:33:23.262Z,1503941603.262 [BPC1] Communications Fault, FailCount= 3
2017-08-28T17:33:23.262Z,1503941603.262 [BPC1](ERROR): Communications Fault
2017-08-28T17:33:23.271Z,1503941603.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-08-28T17:33:23.405Z,1503941603.405 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:33:23.673Z,1503941603.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-08-28T17:33:24.073Z,1503941604.073 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-08-28T17:33:24.473Z,1503941604.473 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-08-28T17:33:24.586Z,1503941604.586 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:33:24.586Z,1503941604.586 [BPC1] No Fault, FailCount= 3
2017-08-28T17:33:24.928Z,1503941604.928 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2017-08-28T17:33:25.274Z,1503941605.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-08-28T17:33:25.696Z,1503941605.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-08-28T17:33:26.069Z,1503941606.069 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-08-28T17:33:26.472Z,1503941606.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-08-28T17:33:26.870Z,1503941606.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-08-28T17:33:27.279Z,1503941607.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-08-28T17:33:27.673Z,1503941607.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-08-28T17:33:28.080Z,1503941608.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-08-28T17:33:28.469Z,1503941608.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-08-28T17:33:28.877Z,1503941608.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-08-28T17:33:29.271Z,1503941609.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-08-28T17:33:29.672Z,1503941609.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-08-28T17:33:30.061Z,1503941610.061 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-08-28T17:33:30.480Z,1503941610.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-08-28T17:33:30.875Z,1503941610.875 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-08-28T17:33:31.277Z,1503941611.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-08-28T17:33:31.667Z,1503941611.667 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-08-28T17:33:32.070Z,1503941612.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-08-28T17:33:32.470Z,1503941612.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-08-28T17:33:32.870Z,1503941612.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-08-28T17:33:33.275Z,1503941613.275 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-08-28T17:33:33.681Z,1503941613.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-08-28T17:33:34.025Z,1503941614.025 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.397034
2017-08-28T17:33:34.077Z,1503941614.077 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-08-28T17:33:40.878Z,1503941620.878 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:33:40.879Z,1503941620.879 [BPC1] Communications Fault, FailCount= 4
2017-08-28T17:33:40.879Z,1503941620.879 [BPC1](ERROR): Communications Fault
2017-08-28T17:33:41.005Z,1503941621.005 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:33:42.182Z,1503941622.182 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:33:42.182Z,1503941622.182 [BPC1] No Fault, FailCount= 4
2017-08-28T17:33:58.047Z,1503941638.047 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:33:58.048Z,1503941638.048 [BPC1] Communications Fault, FailCount= 5
2017-08-28T17:33:58.048Z,1503941638.048 [BPC1](ERROR): Communications Fault
2017-08-28T17:33:58.211Z,1503941638.211 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:33:59.398Z,1503941639.398 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:33:59.398Z,1503941639.398 [BPC1] No Fault, FailCount= 5
2017-08-28T17:34:04.424Z,1503941644.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-08-28T17:34:15.269Z,1503941655.269 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:34:15.269Z,1503941655.269 [BPC1] Communications Fault, FailCount= 6
2017-08-28T17:34:15.269Z,1503941655.269 [BPC1](ERROR): Communications Fault
2017-08-28T17:34:15.387Z,1503941655.387 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:34:16.585Z,1503941656.585 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:34:16.585Z,1503941656.585 [BPC1] No Fault, FailCount= 6
2017-08-28T17:34:32.459Z,1503941672.459 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:34:32.459Z,1503941672.459 [BPC1] Communications Fault, FailCount= 7
2017-08-28T17:34:32.459Z,1503941672.459 [BPC1](ERROR): Communications Fault
2017-08-28T17:34:32.598Z,1503941672.598 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:34:33.800Z,1503941673.800 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:34:33.800Z,1503941673.800 [BPC1] No Fault, FailCount= 7
2017-08-28T17:34:48.329Z,1503941688.329 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002200
2017-08-28T17:34:52.429Z,1503941692.429 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:34:52.429Z,1503941692.429 [BPC1] Communications Fault, FailCount= 8
2017-08-28T17:34:52.429Z,1503941692.429 [BPC1](ERROR): Communications Fault
2017-08-28T17:34:52.586Z,1503941692.586 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:34:52.614Z,1503941692.614 [DataOverHttps](INFO): Sending 923 bytes from file Logs/20170828T172032/Express0001.lzma
2017-08-28T17:34:53.400Z,1503941693.400 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Express0001.lzma.bak
2017-08-28T17:34:53.400Z,1503941693.400 [DataOverHttps](INFO): SBD MOMSN=5068783
2017-08-28T17:34:53.575Z,1503941693.575 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:34:53.575Z,1503941693.575 [BPC1] No Fault, FailCount= 8
2017-08-28T17:35:02.685Z,1503941702.685 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20170828T172032/Express0005.lzma
2017-08-28T17:35:03.483Z,1503941703.483 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Express0005.lzma.bak
2017-08-28T17:35:03.483Z,1503941703.483 [DataOverHttps](INFO): SBD MOMSN=5068811
2017-08-28T17:35:03.944Z,1503941703.944 [Default:CheckIn:Read_Iridium] Stopped
2017-08-28T17:35:03.944Z,1503941703.944 [Default:CheckIn:C.Wait] Running Loop=1
2017-08-28T17:35:03.944Z,1503941703.944 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-08-28T17:35:12.605Z,1503941712.605 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:35:12.606Z,1503941712.606 [BPC1] Communications Fault, FailCount= 9
2017-08-28T17:35:12.606Z,1503941712.606 [BPC1](ERROR): Communications Fault
2017-08-28T17:35:12.757Z,1503941712.757 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:35:13.916Z,1503941713.916 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:35:13.916Z,1503941713.916 [BPC1] No Fault, FailCount= 9
2017-08-28T17:36:15.309Z,1503941775.309 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:36:15.310Z,1503941775.310 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2017-08-28T17:36:15.310Z,1503941775.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:36:15.313Z,1503941775.313 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:36:15.314Z,1503941775.314 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2017-08-28T17:36:15.314Z,1503941775.314 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:36:15.425Z,1503941775.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:36:15.425Z,1503941775.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:36:15.809Z,1503941775.809 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:36:15.809Z,1503941775.809 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2017-08-28T17:36:15.810Z,1503941775.810 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:36:15.810Z,1503941775.810 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2017-08-28T17:36:16.075Z,1503941776.075 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:36:16.075Z,1503941776.075 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:36:16.084Z,1503941776.084 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:36:16.084Z,1503941776.084 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:36:16.085Z,1503941776.085 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:36:16.085Z,1503941776.085 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:36:16.504Z,1503941776.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-08-28T17:36:16.900Z,1503941776.900 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-08-28T17:36:17.301Z,1503941777.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-08-28T17:36:17.700Z,1503941777.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-08-28T17:36:18.077Z,1503941778.077 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-08-28T17:36:18.502Z,1503941778.502 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-08-28T17:36:18.901Z,1503941778.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-08-28T17:36:19.306Z,1503941779.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-08-28T17:36:19.700Z,1503941779.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-08-28T17:36:20.083Z,1503941780.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-08-28T17:36:20.504Z,1503941780.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-08-28T17:36:20.909Z,1503941780.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-08-28T17:36:21.330Z,1503941781.330 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-08-28T17:36:21.681Z,1503941781.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-08-28T17:36:22.083Z,1503941782.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-08-28T17:36:22.558Z,1503941782.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-08-28T17:36:22.933Z,1503941782.933 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-08-28T17:36:23.309Z,1503941783.309 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-08-28T17:36:23.725Z,1503941783.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-08-28T17:36:24.128Z,1503941784.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-08-28T17:36:24.507Z,1503941784.507 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-08-28T17:36:24.944Z,1503941784.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-08-28T17:36:25.340Z,1503941785.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-08-28T17:36:25.690Z,1503941785.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-08-28T17:36:26.091Z,1503941786.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-08-28T17:36:26.505Z,1503941786.505 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-08-28T17:36:26.901Z,1503941786.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-08-28T17:36:27.302Z,1503941787.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-08-28T17:36:27.700Z,1503941787.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-08-28T17:36:28.080Z,1503941788.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-08-28T17:36:28.504Z,1503941788.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-08-28T17:36:28.903Z,1503941788.903 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-08-28T17:36:29.301Z,1503941789.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-08-28T17:36:29.704Z,1503941789.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-08-28T17:36:30.082Z,1503941790.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-08-28T17:36:30.510Z,1503941790.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-08-28T17:36:30.908Z,1503941790.908 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-08-28T17:36:31.305Z,1503941791.305 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-08-28T17:36:31.691Z,1503941791.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-08-28T17:36:32.085Z,1503941792.085 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-08-28T17:36:32.530Z,1503941792.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-08-28T17:36:32.893Z,1503941792.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-08-28T17:36:33.293Z,1503941793.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-08-28T17:36:33.698Z,1503941793.698 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-08-28T17:36:34.087Z,1503941794.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-08-28T17:36:34.490Z,1503941794.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-08-28T17:36:34.893Z,1503941794.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-08-28T17:36:35.291Z,1503941795.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2017-08-28T17:39:04.333Z,1503941944.333 [BPC1](ERROR): Failed to parse bank A battery data
2017-08-28T17:39:04.333Z,1503941944.333 [BPC1] Data Fault, FailCount= 1
2017-08-28T17:39:04.333Z,1503941944.333 [BPC1](ERROR): Data Fault
2017-08-28T17:39:04.480Z,1503941944.480 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T17:39:05.686Z,1503941945.686 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:39:05.686Z,1503941945.686 [BPC1] No Fault, FailCount= 1
2017-08-28T17:39:16.364Z,1503941956.364 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:39:16.364Z,1503941956.364 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2017-08-28T17:39:16.365Z,1503941956.365 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:39:16.367Z,1503941956.367 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:39:16.367Z,1503941956.367 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2017-08-28T17:39:16.367Z,1503941956.367 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:39:16.479Z,1503941956.479 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:39:16.479Z,1503941956.479 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:39:16.848Z,1503941956.848 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:39:16.848Z,1503941956.848 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2017-08-28T17:39:16.848Z,1503941956.848 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:39:16.848Z,1503941956.848 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2017-08-28T17:39:17.137Z,1503941957.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:39:17.138Z,1503941957.138 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:39:17.138Z,1503941957.138 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:39:17.138Z,1503941957.138 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:39:17.139Z,1503941957.139 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:39:17.139Z,1503941957.139 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:39:17.541Z,1503941957.541 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-08-28T17:39:17.944Z,1503941957.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-08-28T17:39:18.358Z,1503941958.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-08-28T17:39:18.753Z,1503941958.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-08-28T17:39:19.155Z,1503941959.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-08-28T17:39:19.550Z,1503941959.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-08-28T17:39:19.941Z,1503941959.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-08-28T17:39:20.349Z,1503941960.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-08-28T17:39:20.746Z,1503941960.746 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-08-28T17:39:21.150Z,1503941961.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-08-28T17:39:21.595Z,1503941961.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-08-28T17:39:21.000Z,1503941962.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-08-28T17:39:22.360Z,1503941962.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-08-28T17:39:22.765Z,1503941962.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-08-28T17:39:23.127Z,1503941963.127 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-08-28T17:39:23.570Z,1503941963.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-08-28T17:39:23.968Z,1503941963.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-08-28T17:39:24.352Z,1503941964.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-08-28T17:39:24.761Z,1503941964.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-08-28T17:39:25.155Z,1503941965.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-08-28T17:39:25.561Z,1503941965.561 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-08-28T17:39:25.955Z,1503941965.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-08-28T17:39:26.327Z,1503941966.327 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-08-28T17:39:26.731Z,1503941966.731 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-08-28T17:39:27.132Z,1503941967.132 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-08-28T17:39:27.569Z,1503941967.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-08-28T17:39:27.953Z,1503941967.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-08-28T17:39:28.362Z,1503941968.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-08-28T17:39:28.734Z,1503941968.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-08-28T17:39:29.125Z,1503941969.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-08-28T17:39:29.557Z,1503941969.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-08-28T17:39:29.927Z,1503941969.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-08-28T17:39:30.331Z,1503941970.331 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-08-28T17:39:30.762Z,1503941970.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-08-28T17:39:31.162Z,1503941971.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-08-28T17:39:31.573Z,1503941971.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-08-28T17:39:31.957Z,1503941971.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-08-28T17:39:32.339Z,1503941972.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-08-28T17:39:32.737Z,1503941972.737 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-08-28T17:39:33.141Z,1503941973.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-08-28T17:39:33.568Z,1503941973.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-08-28T17:39:33.955Z,1503941973.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-08-28T17:39:34.352Z,1503941974.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-08-28T17:39:34.747Z,1503941974.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-08-28T17:39:35.140Z,1503941975.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-08-28T17:39:35.538Z,1503941975.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-08-28T17:39:35.958Z,1503941975.958 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-08-28T17:40:04.395Z,1503942004.395 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-08-28T17:40:04.395Z,1503942004.395 [Default:CheckIn:C.Wait] Stopped
2017-08-28T17:40:04.395Z,1503942004.395 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T17:40:04.395Z,1503942004.395 [Default:CheckIn:D] Running Loop=1
2017-08-28T17:40:04.789Z,1503942004.789 [Default:CheckIn:D] Stopped
2017-08-28T17:40:04.789Z,1503942004.789 [Default:CheckIn:E] Running Loop=1
2017-08-28T17:40:05.137Z,1503942005.137 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.839143 min 
2017-08-28T17:40:05.143Z,1503942005.143 [Default:CheckIn:E] Stopped
2017-08-28T17:40:05.143Z,1503942005.143 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-08-28T17:40:05.143Z,1503942005.143 [Default:CheckIn] Stopped
2017-08-28T17:40:05.143Z,1503942005.143 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T17:40:05.143Z,1503942005.143 [Default:CheckIn](INFO): Running loop #2
2017-08-28T17:40:05.143Z,1503942005.143 [Default:CheckIn] Running Loop=2
2017-08-28T17:40:05.143Z,1503942005.143 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T17:40:05.143Z,1503942005.143 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T17:40:20.970Z,1503942020.970 [BPC1](ERROR): Failed to parse bank B battery data
2017-08-28T17:40:20.970Z,1503942020.970 [BPC1] Data Fault, FailCount= 1
2017-08-28T17:40:20.971Z,1503942020.971 [BPC1](ERROR): Data Fault
2017-08-28T17:40:21.089Z,1503942021.089 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T17:40:22.279Z,1503942022.279 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:40:22.279Z,1503942022.279 [BPC1] No Fault, FailCount= 1
2017-08-28T17:41:09.554Z,1503942069.554 [CBIT](DEBUG): EFC running - data check-sum false
2017-08-28T17:41:17.225Z,1503942077.225 [PNI_TCM](ERROR): CRC does not match. Expected:0x57814 got:0x42759
2017-08-28T17:41:20.423Z,1503942080.423 [PNI_TCM](ERROR): CRC does not match. Expected:0x45916 got:0x49028
2017-08-28T17:41:23.620Z,1503942083.620 [PNI_TCM](ERROR): CRC does not match. Expected:0x22411 got:0x47424
2017-08-28T17:41:26.823Z,1503942086.823 [PNI_TCM](ERROR): CRC does not match. Expected:0x55884 got:0x7105
2017-08-28T17:41:30.015Z,1503942090.015 [PNI_TCM](ERROR): CRC does not match. Expected:0x40048 got:0x49332
2017-08-28T17:41:30.416Z,1503942090.416 [PNI_TCM](ERROR): CRC does not match. Expected:0x40820 got:0x1857
2017-08-28T17:41:40.779Z,1503942100.779 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-08-28T17:41:40.779Z,1503942100.779 [NAL9602] Data Fault, FailCount= 2
2017-08-28T17:41:40.779Z,1503942100.779 [NAL9602](ERROR): Data Fault
2017-08-28T17:41:40.963Z,1503942100.963 [CBIT](ERROR): Data Fault in component: NAL9602
2017-08-28T17:41:41.177Z,1503942101.177 [NAL9602](INFO): Powering down
2017-08-28T17:41:42.121Z,1503942102.121 [CBIT](INFO): Clearing failed state for component NAL9602
2017-08-28T17:41:42.121Z,1503942102.121 [NAL9602] No Fault, FailCount= 2
2017-08-28T17:41:46.382Z,1503942106.382 [NAL9602](INFO): Powering up NAL9602
2017-08-28T17:41:57.574Z,1503942117.574 [NAL9602](INFO): NAL9602 initialized
2017-08-28T17:42:17.477Z,1503942137.477 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:42:17.477Z,1503942137.477 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2017-08-28T17:42:17.477Z,1503942137.477 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:42:17.479Z,1503942137.479 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:42:17.479Z,1503942137.479 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2017-08-28T17:42:17.479Z,1503942137.479 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:42:17.589Z,1503942137.589 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:42:17.589Z,1503942137.589 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:42:17.994Z,1503942137.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:42:17.994Z,1503942137.994 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2017-08-28T17:42:17.994Z,1503942137.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:42:17.994Z,1503942137.994 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2017-08-28T17:42:18.270Z,1503942138.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:42:18.270Z,1503942138.270 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:42:18.271Z,1503942138.271 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:42:18.271Z,1503942138.271 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:42:18.272Z,1503942138.272 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:42:18.272Z,1503942138.272 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:42:18.670Z,1503942138.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-08-28T17:42:19.087Z,1503942139.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-08-28T17:42:19.485Z,1503942139.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-08-28T17:42:19.878Z,1503942139.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-08-28T17:42:20.275Z,1503942140.275 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-08-28T17:42:20.674Z,1503942140.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-08-28T17:42:21.083Z,1503942141.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-08-28T17:42:21.485Z,1503942141.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-08-28T17:42:21.880Z,1503942141.880 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-08-28T17:42:22.273Z,1503942142.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-08-28T17:42:22.697Z,1503942142.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-08-28T17:42:23.069Z,1503942143.069 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-08-28T17:42:23.496Z,1503942143.496 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-08-28T17:42:23.896Z,1503942143.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-08-28T17:42:24.279Z,1503942144.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-08-28T17:42:24.681Z,1503942144.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-08-28T17:42:25.118Z,1503942145.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-08-28T17:42:25.483Z,1503942145.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-08-28T17:42:25.883Z,1503942145.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-08-28T17:42:26.275Z,1503942146.275 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-08-28T17:42:26.675Z,1503942146.675 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-08-28T17:42:27.081Z,1503942147.081 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-08-28T17:42:27.483Z,1503942147.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-08-28T17:42:27.930Z,1503942147.930 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2017-08-28T17:42:28.279Z,1503942148.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-08-28T17:42:28.677Z,1503942148.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-08-28T17:42:29.088Z,1503942149.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-08-28T17:42:29.484Z,1503942149.484 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-08-28T17:42:29.883Z,1503942149.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-08-28T17:42:30.277Z,1503942150.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-08-28T17:42:30.682Z,1503942150.682 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-08-28T17:42:31.091Z,1503942151.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-08-28T17:42:31.484Z,1503942151.484 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-08-28T17:42:31.873Z,1503942151.873 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:42:31.873Z,1503942151.873 [BPC1] Communications Fault, FailCount= 1
2017-08-28T17:42:31.874Z,1503942151.874 [BPC1](ERROR): Communications Fault
2017-08-28T17:42:31.881Z,1503942151.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-08-28T17:42:31.992Z,1503942151.992 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:42:32.278Z,1503942152.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-08-28T17:42:32.661Z,1503942152.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-08-28T17:42:33.090Z,1503942153.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-08-28T17:42:33.203Z,1503942153.203 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:42:33.203Z,1503942153.203 [BPC1] No Fault, FailCount= 1
2017-08-28T17:42:33.539Z,1503942153.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2017-08-28T17:42:33.887Z,1503942153.887 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-08-28T17:42:34.301Z,1503942154.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-08-28T17:42:34.684Z,1503942154.684 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-08-28T17:42:35.093Z,1503942155.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-08-28T17:42:35.485Z,1503942155.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-08-28T17:42:35.888Z,1503942155.888 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-08-28T17:42:36.259Z,1503942156.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-08-28T17:42:36.661Z,1503942156.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-08-28T17:42:37.096Z,1503942157.096 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-08-28T17:42:49.463Z,1503942169.463 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:42:49.463Z,1503942169.463 [BPC1] Communications Fault, FailCount= 2
2017-08-28T17:42:49.463Z,1503942169.463 [BPC1](ERROR): Communications Fault
2017-08-28T17:42:49.592Z,1503942169.592 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:42:50.778Z,1503942170.778 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:42:50.778Z,1503942170.778 [BPC1] No Fault, FailCount= 2
2017-08-28T17:43:09.920Z,1503942189.920 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:43:09.920Z,1503942189.920 [BPC1] Communications Fault, FailCount= 3
2017-08-28T17:43:09.920Z,1503942189.920 [BPC1](ERROR): Communications Fault
2017-08-28T17:43:10.040Z,1503942190.040 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:43:11.022Z,1503942191.022 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:43:11.022Z,1503942191.022 [BPC1] No Fault, FailCount= 3
2017-08-28T17:43:26.898Z,1503942206.898 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:43:26.899Z,1503942206.899 [BPC1] Communications Fault, FailCount= 4
2017-08-28T17:43:26.899Z,1503942206.899 [BPC1](ERROR): Communications Fault
2017-08-28T17:43:27.025Z,1503942207.025 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:43:28.241Z,1503942208.241 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:43:28.241Z,1503942208.241 [BPC1] No Fault, FailCount= 4
2017-08-28T17:43:44.107Z,1503942224.107 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:43:44.107Z,1503942224.107 [BPC1] Communications Fault, FailCount= 5
2017-08-28T17:43:44.107Z,1503942224.107 [BPC1](ERROR): Communications Fault
2017-08-28T17:43:44.226Z,1503942224.226 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:43:45.456Z,1503942225.456 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:43:45.456Z,1503942225.456 [BPC1] No Fault, FailCount= 5
2017-08-28T17:44:01.683Z,1503942241.683 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:44:01.683Z,1503942241.683 [BPC1] Communications Fault, FailCount= 6
2017-08-28T17:44:01.683Z,1503942241.683 [BPC1](ERROR): Communications Fault
2017-08-28T17:44:01.826Z,1503942241.826 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:44:03.044Z,1503942243.044 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:44:03.044Z,1503942243.044 [BPC1] No Fault, FailCount= 6
2017-08-28T17:44:15.102Z,1503942255.102 [BPC1](ERROR): Failed to parse bank A battery data
2017-08-28T17:44:15.102Z,1503942255.102 [BPC1] Data Fault, FailCount= 7
2017-08-28T17:44:15.102Z,1503942255.102 [BPC1](ERROR): Data Fault
2017-08-28T17:44:15.247Z,1503942255.247 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T17:44:16.445Z,1503942256.445 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:44:16.445Z,1503942256.445 [BPC1] No Fault, FailCount= 7
2017-08-28T17:44:32.691Z,1503942272.691 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:44:32.691Z,1503942272.691 [BPC1] Communications Fault, FailCount= 8
2017-08-28T17:44:32.691Z,1503942272.691 [BPC1](ERROR): Communications Fault
2017-08-28T17:44:32.821Z,1503942272.821 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:44:34.023Z,1503942274.023 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:44:34.023Z,1503942274.023 [BPC1] No Fault, FailCount= 8
2017-08-28T17:44:49.903Z,1503942289.903 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:44:49.903Z,1503942289.903 [BPC1] Communications Fault, FailCount= 9
2017-08-28T17:44:49.903Z,1503942289.903 [BPC1](ERROR): Communications Fault
2017-08-28T17:44:50.038Z,1503942290.038 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:44:51.244Z,1503942291.244 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:44:51.244Z,1503942291.244 [BPC1] No Fault, FailCount= 9
2017-08-28T17:45:05.157Z,1503942305.157 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-08-28T17:40:05.1Z
2017-08-28T17:45:05.157Z,1503942305.157 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T17:45:05.157Z,1503942305.157 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-08-28T17:45:09.714Z,1503942309.714 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20170828T172032/Courier0008.lzma
2017-08-28T17:45:10.495Z,1503942310.495 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Courier0008.lzma.bak
2017-08-28T17:45:10.495Z,1503942310.495 [DataOverHttps](INFO): SBD MOMSN=5068818
2017-08-28T17:45:19.686Z,1503942319.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:45:19.686Z,1503942319.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2017-08-28T17:45:19.686Z,1503942319.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:45:19.688Z,1503942319.688 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:45:19.688Z,1503942319.688 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2017-08-28T17:45:19.688Z,1503942319.688 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:45:19.813Z,1503942319.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:45:19.814Z,1503942319.814 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:45:19.832Z,1503942319.832 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20170828T172032/Express0009.lzma
2017-08-28T17:45:20.036Z,1503942320.036 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:45:20.036Z,1503942320.036 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2017-08-28T17:45:20.036Z,1503942320.036 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:45:20.036Z,1503942320.036 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2017-08-28T17:45:20.292Z,1503942320.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:45:20.292Z,1503942320.292 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:45:20.293Z,1503942320.293 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:45:20.293Z,1503942320.293 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:45:20.293Z,1503942320.293 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:45:20.294Z,1503942320.294 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:45:20.631Z,1503942320.631 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Express0009.lzma.bak
2017-08-28T17:45:20.631Z,1503942320.631 [DataOverHttps](INFO): SBD MOMSN=5068820
2017-08-28T17:45:20.726Z,1503942320.726 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.0 s old, using for 20.0 s.
2017-08-28T17:45:21.086Z,1503942321.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.4 s old, using for 20.0 s.
2017-08-28T17:45:21.089Z,1503942321.089 [Default:CheckIn:Read_Iridium] Stopped
2017-08-28T17:45:21.089Z,1503942321.089 [Default:CheckIn:C.Wait] Running Loop=1
2017-08-28T17:45:21.089Z,1503942321.089 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-08-28T17:45:21.514Z,1503942321.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.8 s old, using for 20.0 s.
2017-08-28T17:45:24.778Z,1503942324.778 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2017-08-28T17:45:24.973Z,1503942324.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2017-08-28T17:45:25.369Z,1503942325.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2017-08-28T17:45:25.748Z,1503942325.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2017-08-28T17:45:26.149Z,1503942326.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-08-28T17:45:26.547Z,1503942326.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-08-28T17:45:26.981Z,1503942326.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2017-08-28T17:45:27.372Z,1503942327.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2017-08-28T17:45:27.774Z,1503942327.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2017-08-28T17:45:28.144Z,1503942328.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2017-08-28T17:45:28.540Z,1503942328.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2017-08-28T17:45:28.949Z,1503942328.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2017-08-28T17:45:29.371Z,1503942329.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2017-08-28T17:45:29.771Z,1503942329.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2017-08-28T17:45:30.178Z,1503942330.178 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2017-08-28T17:45:30.581Z,1503942330.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2017-08-28T17:45:30.949Z,1503942330.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2017-08-28T17:45:31.351Z,1503942331.351 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2017-08-28T17:45:31.751Z,1503942331.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2017-08-28T17:45:32.179Z,1503942332.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2017-08-28T17:45:32.555Z,1503942332.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2017-08-28T17:45:32.957Z,1503942332.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2017-08-28T17:45:33.355Z,1503942333.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2017-08-28T17:45:33.753Z,1503942333.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2017-08-28T17:45:34.156Z,1503942334.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2017-08-28T17:45:34.551Z,1503942334.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2017-08-28T17:45:34.984Z,1503942334.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2017-08-28T17:45:35.358Z,1503942335.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2017-08-28T17:45:35.794Z,1503942335.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2017-08-28T17:45:36.149Z,1503942336.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2017-08-28T17:45:36.552Z,1503942336.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2017-08-28T17:45:37.000Z,1503942337.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2017-08-28T17:45:37.351Z,1503942337.351 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2017-08-28T17:45:37.762Z,1503942337.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2017-08-28T17:45:38.195Z,1503942338.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2017-08-28T17:45:38.563Z,1503942338.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2017-08-28T17:45:38.991Z,1503942338.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2017-08-28T17:45:39.388Z,1503942339.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2017-08-28T17:46:46.016Z,1503942406.016 [PNI_TCM](ERROR): CRC does not match. Expected:0x52381 got:0x47424
2017-08-28T17:46:51.622Z,1503942411.622 [PNI_TCM](ERROR): CRC does not match. Expected:0x48771 got:0x1287
2017-08-28T17:46:52.018Z,1503942412.018 [BPC1](ERROR): Failed to parse message.
2017-08-28T17:46:52.018Z,1503942412.018 [BPC1](ERROR): Failed to parse bank B battery data
2017-08-28T17:46:52.018Z,1503942412.018 [BPC1] Data Fault, FailCount= 1
2017-08-28T17:46:52.018Z,1503942412.018 [BPC1](ERROR): Data Fault
2017-08-28T17:46:52.167Z,1503942412.167 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T17:46:53.325Z,1503942413.325 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:46:53.325Z,1503942413.325 [BPC1] No Fault, FailCount= 1
2017-08-28T17:47:09.226Z,1503942429.226 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:47:09.226Z,1503942429.226 [BPC1] Communications Fault, FailCount= 2
2017-08-28T17:47:09.226Z,1503942429.226 [BPC1](ERROR): Communications Fault
2017-08-28T17:47:09.347Z,1503942429.347 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:47:10.524Z,1503942430.524 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:47:10.524Z,1503942430.524 [BPC1] No Fault, FailCount= 2
2017-08-28T17:48:20.683Z,1503942500.683 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:48:20.683Z,1503942500.683 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2017-08-28T17:48:20.684Z,1503942500.684 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-08-28T17:48:20.701Z,1503942500.701 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-08-28T17:48:20.702Z,1503942500.702 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2017-08-28T17:48:20.702Z,1503942500.702 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-08-28T17:48:20.828Z,1503942500.828 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-08-28T17:48:20.828Z,1503942500.828 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-08-28T17:48:21.180Z,1503942501.180 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-08-28T17:48:21.180Z,1503942501.180 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2017-08-28T17:48:21.181Z,1503942501.181 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-08-28T17:48:21.181Z,1503942501.181 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2017-08-28T17:48:21.478Z,1503942501.478 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-08-28T17:48:21.479Z,1503942501.479 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:48:21.479Z,1503942501.479 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:48:21.479Z,1503942501.479 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-08-28T17:48:21.480Z,1503942501.480 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-08-28T17:48:21.480Z,1503942501.480 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-08-28T17:48:21.885Z,1503942501.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-08-28T17:48:22.289Z,1503942502.289 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-08-28T17:48:26.604Z,1503942506.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2017-08-28T17:48:26.829Z,1503942506.829 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2017-08-28T17:48:27.197Z,1503942507.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-08-28T17:48:27.632Z,1503942507.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-08-28T17:48:27.994Z,1503942507.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2017-08-28T17:48:28.428Z,1503942508.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2017-08-28T17:48:28.804Z,1503942508.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2017-08-28T17:48:29.231Z,1503942509.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2017-08-28T17:48:29.605Z,1503942509.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2017-08-28T17:48:29.992Z,1503942509.992 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2017-08-28T17:48:30.390Z,1503942510.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2017-08-28T17:48:30.796Z,1503942510.796 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2017-08-28T17:48:31.200Z,1503942511.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2017-08-28T17:48:31.611Z,1503942511.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2017-08-28T17:48:32.003Z,1503942512.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2017-08-28T17:48:32.410Z,1503942512.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2017-08-28T17:48:32.789Z,1503942512.789 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2017-08-28T17:48:33.193Z,1503942513.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2017-08-28T17:48:33.594Z,1503942513.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2017-08-28T17:48:33.995Z,1503942513.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2017-08-28T17:48:34.387Z,1503942514.387 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2017-08-28T17:48:34.804Z,1503942514.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2017-08-28T17:48:35.198Z,1503942515.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2017-08-28T17:48:35.607Z,1503942515.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2017-08-28T17:48:35.997Z,1503942515.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2017-08-28T17:48:36.394Z,1503942516.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2017-08-28T17:48:36.794Z,1503942516.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2017-08-28T17:48:37.193Z,1503942517.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2017-08-28T17:48:37.603Z,1503942517.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2017-08-28T17:48:38.003Z,1503942518.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2017-08-28T17:48:38.408Z,1503942518.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2017-08-28T17:48:38.795Z,1503942518.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2017-08-28T17:48:39.191Z,1503942519.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2017-08-28T17:48:39.596Z,1503942519.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2017-08-28T17:48:40.002Z,1503942520.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2017-08-28T17:48:40.398Z,1503942520.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2017-08-28T17:49:05.150Z,1503942545.150 [NAL9602](INFO): GPS fix at 20170828T174903: (36.802946, -121.787892)
2017-08-28T17:49:12.745Z,1503942552.745 [NAL9602](INFO): Powering down
2017-08-28T17:49:54.215Z,1503942594.215 [BPC1](ERROR): Failed to parse bank B battery data
2017-08-28T17:49:54.215Z,1503942594.215 [BPC1] Data Fault, FailCount= 1
2017-08-28T17:49:54.215Z,1503942594.215 [BPC1](ERROR): Data Fault
2017-08-28T17:49:54.364Z,1503942594.364 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T17:49:55.545Z,1503942595.545 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:49:55.546Z,1503942595.546 [BPC1] No Fault, FailCount= 1
2017-08-28T17:50:21.841Z,1503942621.841 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-08-28T17:50:21.841Z,1503942621.841 [Default:CheckIn:C.Wait] Stopped
2017-08-28T17:50:21.841Z,1503942621.841 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T17:50:21.841Z,1503942621.841 [Default:CheckIn:D] Running Loop=1
2017-08-28T17:50:22.240Z,1503942622.240 [Default:CheckIn:D] Stopped
2017-08-28T17:50:22.240Z,1503942622.240 [Default:CheckIn:E] Running Loop=1
2017-08-28T17:50:22.750Z,1503942622.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.130021 min 
2017-08-28T17:50:22.750Z,1503942622.750 [Default:CheckIn:E] Stopped
2017-08-28T17:50:22.750Z,1503942622.750 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-08-28T17:50:22.750Z,1503942622.750 [Default:CheckIn] Stopped
2017-08-28T17:50:22.751Z,1503942622.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T17:50:22.751Z,1503942622.751 [Default:CheckIn](INFO): Running loop #3
2017-08-28T17:50:22.751Z,1503942622.751 [Default:CheckIn] Running Loop=3
2017-08-28T17:50:22.751Z,1503942622.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T17:50:22.751Z,1503942622.751 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T17:50:27.645Z,1503942627.645 [NAL9602](INFO): Powering up
2017-08-28T17:50:38.841Z,1503942638.841 [NAL9602](INFO): NAL9602 initialized
2017-08-28T17:50:55.241Z,1503942655.241 [NAL9602](INFO): SBD MO Status=0, MOMSN=65, MT Status=0, MTMSN=0
2017-08-28T17:50:55.241Z,1503942655.241 [NAL9602](INFO): No messages in MT queue
2017-08-28T17:51:09.867Z,1503942669.867 [CBIT](DEBUG): EFC running - data check-sum false
2017-08-28T17:51:35.232Z,1503942695.232 [NAL9602](INFO): GPS fix at 20170828T175133: (36.802405, -121.787722)
2017-08-28T17:51:35.278Z,1503942695.278 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T17:51:35.278Z,1503942695.278 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-08-28T17:51:39.985Z,1503942699.985 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20170828T172032/Courier0012.lzma
2017-08-28T17:51:40.756Z,1503942700.756 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Courier0012.lzma.bak
2017-08-28T17:51:40.757Z,1503942700.757 [DataOverHttps](INFO): SBD MOMSN=5068825
2017-08-28T17:51:45.075Z,1503942705.075 [NAL9602](INFO): Powering down
2017-08-28T17:51:50.110Z,1503942710.110 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20170828T172032/Express0013.lzma
2017-08-28T17:51:50.904Z,1503942710.904 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Express0013.lzma.bak
2017-08-28T17:51:50.904Z,1503942710.904 [DataOverHttps](INFO): SBD MOMSN=5068828
2017-08-28T17:51:51.350Z,1503942711.350 [Default:CheckIn:Read_Iridium] Stopped
2017-08-28T17:51:51.350Z,1503942711.350 [Default:CheckIn:C.Wait] Running Loop=1
2017-08-28T17:51:51.350Z,1503942711.350 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-08-28T17:52:15.386Z,1503942735.386 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:52:15.386Z,1503942735.386 [BPC1] Communications Fault, FailCount= 1
2017-08-28T17:52:15.386Z,1503942735.386 [BPC1](ERROR): Communications Fault
2017-08-28T17:52:15.543Z,1503942735.543 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:52:16.720Z,1503942736.720 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:52:16.721Z,1503942736.721 [BPC1] No Fault, FailCount= 1
2017-08-28T17:52:32.992Z,1503942752.992 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:52:32.992Z,1503942752.992 [BPC1] Communications Fault, FailCount= 2
2017-08-28T17:52:32.992Z,1503942752.992 [BPC1](ERROR): Communications Fault
2017-08-28T17:52:33.114Z,1503942753.114 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:52:34.328Z,1503942754.328 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:52:34.328Z,1503942754.328 [BPC1] No Fault, FailCount= 2
2017-08-28T17:52:50.590Z,1503942770.590 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:52:50.590Z,1503942770.590 [BPC1] Communications Fault, FailCount= 3
2017-08-28T17:52:50.590Z,1503942770.590 [BPC1](ERROR): Communications Fault
2017-08-28T17:52:50.714Z,1503942770.714 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:52:51.908Z,1503942771.908 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:52:51.908Z,1503942771.908 [BPC1] No Fault, FailCount= 3
2017-08-28T17:53:11.270Z,1503942791.270 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:53:11.270Z,1503942791.270 [BPC1] Communications Fault, FailCount= 4
2017-08-28T17:53:11.270Z,1503942791.270 [BPC1](ERROR): Communications Fault
2017-08-28T17:53:11.391Z,1503942791.391 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:53:12.601Z,1503942792.601 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:53:12.601Z,1503942792.601 [BPC1] No Fault, FailCount= 4
2017-08-28T17:53:28.862Z,1503942808.862 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:53:28.863Z,1503942808.863 [BPC1] Communications Fault, FailCount= 5
2017-08-28T17:53:28.863Z,1503942808.863 [BPC1](ERROR): Communications Fault
2017-08-28T17:53:28.999Z,1503942808.999 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:53:30.205Z,1503942810.205 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:53:30.205Z,1503942810.205 [BPC1] No Fault, FailCount= 5
2017-08-28T17:53:46.478Z,1503942826.478 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:53:46.478Z,1503942826.478 [BPC1] Communications Fault, FailCount= 6
2017-08-28T17:53:46.478Z,1503942826.478 [BPC1](ERROR): Communications Fault
2017-08-28T17:53:46.651Z,1503942826.651 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:53:47.812Z,1503942827.812 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:53:47.813Z,1503942827.813 [BPC1] No Fault, FailCount= 6
2017-08-28T17:54:03.923Z,1503942843.923 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:54:03.923Z,1503942843.923 [BPC1] Communications Fault, FailCount= 7
2017-08-28T17:54:03.923Z,1503942843.923 [BPC1](ERROR): Communications Fault
2017-08-28T17:54:04.070Z,1503942844.070 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:54:05.260Z,1503942845.260 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:54:05.260Z,1503942845.260 [BPC1] No Fault, FailCount= 7
2017-08-28T17:54:21.934Z,1503942861.934 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:54:21.934Z,1503942861.934 [BPC1] Communications Fault, FailCount= 8
2017-08-28T17:54:21.934Z,1503942861.934 [BPC1](ERROR): Communications Fault
2017-08-28T17:54:22.059Z,1503942862.059 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:54:23.305Z,1503942863.305 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:54:23.305Z,1503942863.305 [BPC1] No Fault, FailCount= 8
2017-08-28T17:56:37.026Z,1503942997.026 [BPC1](ERROR): Failed to parse message.
2017-08-28T17:56:37.026Z,1503942997.026 [BPC1](ERROR): Failed to parse bank B battery data
2017-08-28T17:56:37.026Z,1503942997.026 [BPC1] Data Fault, FailCount= 1
2017-08-28T17:56:37.026Z,1503942997.026 [BPC1](ERROR): Data Fault
2017-08-28T17:56:37.176Z,1503942997.176 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T17:56:38.378Z,1503942998.378 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:56:38.378Z,1503942998.378 [BPC1] No Fault, FailCount= 1
2017-08-28T17:56:51.853Z,1503943011.853 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-08-28T17:56:51.853Z,1503943011.853 [Default:CheckIn:C.Wait] Stopped
2017-08-28T17:56:51.853Z,1503943011.853 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T17:56:51.853Z,1503943011.853 [Default:CheckIn:D] Running Loop=1
2017-08-28T17:56:52.252Z,1503943012.252 [Default:CheckIn:D] Stopped
2017-08-28T17:56:52.253Z,1503943012.253 [Default:CheckIn:E] Running Loop=1
2017-08-28T17:56:52.661Z,1503943012.661 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.630233 min 
2017-08-28T17:56:52.661Z,1503943012.661 [Default:CheckIn:E] Stopped
2017-08-28T17:56:52.661Z,1503943012.661 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-08-28T17:56:52.661Z,1503943012.661 [Default:CheckIn] Stopped
2017-08-28T17:56:52.661Z,1503943012.661 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T17:56:52.661Z,1503943012.661 [Default:CheckIn](INFO): Running loop #4
2017-08-28T17:56:52.662Z,1503943012.662 [Default:CheckIn] Running Loop=4
2017-08-28T17:56:52.662Z,1503943012.662 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-08-28T17:56:52.662Z,1503943012.662 [Default:CheckIn:Read_GPS] Running Loop=1
2017-08-28T17:56:56.276Z,1503943016.276 [NAL9602](INFO): Powering up
2017-08-28T17:56:56.304Z,1503943016.304 [BPC1](ERROR): Failed to receive battery data
2017-08-28T17:56:56.304Z,1503943016.304 [BPC1] Communications Fault, FailCount= 2
2017-08-28T17:56:56.304Z,1503943016.304 [BPC1](ERROR): Communications Fault
2017-08-28T17:56:56.475Z,1503943016.475 [CBIT](ERROR): Communications Fault in component: BPC1
2017-08-28T17:56:57.460Z,1503943017.460 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:56:57.461Z,1503943017.461 [BPC1] No Fault, FailCount= 2
2017-08-28T17:57:07.318Z,1503943027.318 [NAL9602](INFO): NAL9602 initialized
2017-08-28T17:57:11.746Z,1503943031.746 [BPC1](ERROR): Failed to parse bank B battery data
2017-08-28T17:57:11.746Z,1503943031.746 [BPC1] Data Fault, FailCount= 3
2017-08-28T17:57:11.746Z,1503943031.746 [BPC1](ERROR): Data Fault
2017-08-28T17:57:11.867Z,1503943031.867 [CBIT](ERROR): Data Fault in component: BPC1
2017-08-28T17:57:13.069Z,1503943033.069 [CBIT](INFO): Clearing failed state for component BPC1
2017-08-28T17:57:13.069Z,1503943033.069 [BPC1] No Fault, FailCount= 3
2017-08-28T17:57:23.708Z,1503943043.708 [NAL9602](INFO): SBD MO Status=0, MOMSN=66, MT Status=0, MTMSN=0
2017-08-28T17:57:23.709Z,1503943043.709 [NAL9602](INFO): No messages in MT queue
2017-08-28T17:57:32.522Z,1503943052.522 [NAL9602](INFO): GPS fix at 20170828T175730: (36.802337, -121.787163)
2017-08-28T17:57:32.559Z,1503943052.559 [Default:CheckIn:Read_GPS] Stopped
2017-08-28T17:57:32.559Z,1503943052.559 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-08-28T17:57:37.266Z,1503943057.266 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20170828T172032/Courier0016.lzma
2017-08-28T17:57:38.057Z,1503943058.057 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Courier0016.lzma.bak
2017-08-28T17:57:38.057Z,1503943058.057 [DataOverHttps](INFO): SBD MOMSN=5068832
2017-08-28T17:57:42.372Z,1503943062.372 [NAL9602](INFO): Powering down
2017-08-28T17:57:47.422Z,1503943067.422 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20170828T172032/Express0017.lzma
2017-08-28T17:57:48.222Z,1503943068.222 [DataOverHttps](INFO): Moved sent file to Logs/20170828T172032/Express0017.lzma.bak
2017-08-28T17:57:48.222Z,1503943068.222 [DataOverHttps](INFO): SBD MOMSN=5068835
2017-08-28T17:57:48.684Z,1503943068.684 [Default:CheckIn:Read_Iridium] Stopped
2017-08-28T17:57:48.684Z,1503943068.684 [Default:CheckIn:C.Wait] Running Loop=1
2017-08-28T17:57:48.684Z,1503943068.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-08-28T17:59:53.641Z,1503943193.641 [DataOverHttps](IMPORTANT): SBD MTMSN=20170828T175952
2017-08-28T17:59:59.085Z,1503943199.085 [DataOverHttps](INFO): Received command:restart app
2017-08-28T17:59:59.142Z,1503943199.142 [CommandLine](IMPORTANT): got command restart application
2017-08-28T18:00:00.146Z,1503943200.146 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:00:00.147Z,1503943200.147 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.199Z,1503943200.199 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-08-28T18:00:00.199Z,1503943200.199 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.200Z,1503943200.200 [CommandLine](INFO): Join timeout helper Thread ID is 991
2017-08-28T18:00:00.201Z,1503943200.201 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-08-28T18:00:00.201Z,1503943200.201 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.201Z,1503943200.201 [NavChartDb](INFO): Join timeout helper Thread ID is 992
2017-08-28T18:00:00.436Z,1503943200.436 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:00:00.436Z,1503943200.436 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.448Z,1503943200.448 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-08-28T18:00:00.448Z,1503943200.448 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.449Z,1503943200.449 [Radio_Surface](INFO): Join timeout helper Thread ID is 993
2017-08-28T18:00:00.809Z,1503943200.809 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:00:00.809Z,1503943200.809 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.816Z,1503943200.816 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-08-28T18:00:00.817Z,1503943200.817 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.817Z,1503943200.817 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 994
2017-08-28T18:00:00.905Z,1503943200.905 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:00:00.905Z,1503943200.905 [WetLabsBB2FL](INFO): Powering down
2017-08-28T18:00:00.906Z,1503943200.906 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.921Z,1503943200.921 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2017-08-28T18:00:00.921Z,1503943200.921 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:00.921Z,1503943200.921 [CTD_Seabird](INFO): Join timeout helper Thread ID is 995
2017-08-28T18:00:01.197Z,1503943201.197 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:00:01.305Z,1503943201.305 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-08-28T18:00:01.305Z,1503943201.305 [CTD_Seabird](INFO): Powering down
2017-08-28T18:00:01.306Z,1503943201.306 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.311Z,1503943201.311 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-08-28T18:00:01.311Z,1503943201.311 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.311Z,1503943201.311 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 997
2017-08-28T18:00:01.610Z,1503943201.610 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:00:01.610Z,1503943201.610 [CTD_NeilBrown](INFO): Powering down
2017-08-28T18:00:01.611Z,1503943201.611 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.619Z,1503943201.619 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-08-28T18:00:01.619Z,1503943201.619 [logger ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.619Z,1503943201.619 [logger](INFO): Join timeout helper Thread ID is 998
2017-08-28T18:00:01.626Z,1503943201.626 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:00:01.626Z,1503943201.626 [logger ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.639Z,1503943201.639 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-08-28T18:00:01.639Z,1503943201.639 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.639Z,1503943201.639 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-08-28T18:00:01.639Z,1503943201.639 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.639Z,1503943201.639 [controlThread](INFO): Join timeout helper Thread ID is 999
2017-08-28T18:00:01.664Z,1503943201.664 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-08-28T18:00:01.664Z,1503943201.664 [controlThread](DEBUG): Uninitializing ControlThread
2017-08-28T18:00:01.665Z,1503943201.665 [Aanderaa_O2](INFO): Powering down
2017-08-28T18:00:01.667Z,1503943201.667 [NAL9602](INFO): Powering down
2017-08-28T18:00:01.669Z,1503943201.669 [PNI_TCM](INFO): Powering down
2017-08-28T18:00:01.755Z,1503943201.755 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-08-28T18:00:01.757Z,1503943201.757 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-08-28T18:00:01.757Z,1503943201.757 [MissionManager](INFO): Uninitializing Mission Default
2017-08-28T18:00:01.757Z,1503943201.757 [Default] Stopped
2017-08-28T18:00:01.758Z,1503943201.758 [Default](DEBUG): Aggregate::uninitialize Default
2017-08-28T18:00:01.758Z,1503943201.758 [Default:B.GoToSurface] Stopped
2017-08-28T18:00:01.758Z,1503943201.758 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-08-28T18:00:01.758Z,1503943201.758 [Default:CheckIn] Stopped
2017-08-28T18:00:01.758Z,1503943201.758 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-08-28T18:00:01.758Z,1503943201.758 [Default:CheckIn:C.Wait] Stopped
2017-08-28T18:00:01.758Z,1503943201.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-08-28T18:00:01.761Z,1503943201.761 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-08-28T18:00:01.762Z,1503943201.762 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-08-28T18:00:01.762Z,1503943201.762 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-08-28T18:00:01.762Z,1503943201.762 [BackSeatDriver](DEBUG): Uninitialize BackSeatDriver Component.
2017-08-28T18:00:01.763Z,1503943201.763 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-08-28T18:00:01.763Z,1503943201.763 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-08-28T18:00:01.763Z,1503943201.763 [BuoyancyServo](INFO): Powering down
2017-08-28T18:00:01.774Z,1503943201.774 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-08-28T18:00:01.774Z,1503943201.774 [ElevatorServo](INFO): Powering down
2017-08-28T18:00:01.775Z,1503943201.775 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-08-28T18:00:01.775Z,1503943201.775 [MassServo](INFO): Powering down
2017-08-28T18:00:01.776Z,1503943201.776 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-08-28T18:00:01.776Z,1503943201.776 [RudderServo](INFO): Powering down
2017-08-28T18:00:01.777Z,1503943201.777 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-08-28T18:00:01.777Z,1503943201.777 [ThrusterServo](INFO): Powering down
2017-08-28T18:00:01.778Z,1503943201.778 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-08-28T18:00:01.779Z,1503943201.779 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-08-28T18:00:01.779Z,1503943201.779 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-08-28T18:00:01.780Z,1503943201.780 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.849Z,1503943201.849 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.853Z,1503943201.853 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.856Z,1503943201.856 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.925Z,1503943201.925 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:01.968Z,1503943201.968 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-08-28T18:00:02.035Z,1503943202.035 [logger ThreadHandler](INFO): Thread cancelled.