2016-06-28T16:52:00.994Z,1467132720.994 [Supervisor](DEBUG): Initializing supervisor. 2016-06-28T16:52:00.997Z,1467132720.997 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2016-06-28T16:52:00.998Z,1467132720.997 [SyncHandler](INFO): Protected caller Thread ID is 768 2016-06-28T16:52:00.998Z,1467132720.998 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2016-06-28T16:52:00.999Z,1467132720.999 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2016-06-28T16:52:00.999Z,1467132721.000 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 769 2016-06-28T16:52:01.002Z,1467132721.002 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2016-06-28T16:52:01.014Z,1467132721.014 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2016-06-28T16:52:01.015Z,1467132721.015 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2016-06-28T16:52:01.016Z,1467132721.016 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 770 2016-06-28T16:52:01.017Z,1467132721.017 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2016-06-28T16:52:01.018Z,1467132721.018 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2016-06-28T16:52:01.018Z,1467132721.018 [logger ThreadHandler](INFO): Protected caller Thread ID is 771 2016-06-28T16:52:01.020Z,1467132721.020 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2016-06-28T16:52:01.021Z,1467132721.020 [Supervisor](INFO): Looking for Config files in directory: Config/ 2016-06-28T16:52:01.026Z,1467132721.026 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2016-06-28T16:52:01.111Z,1467132721.111 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2016-06-28T16:52:01.424Z,1467132721.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2016-06-28T16:52:01.425Z,1467132721.425 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2016-06-28T16:52:01.547Z,1467132721.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2016-06-28T16:52:01.548Z,1467132721.548 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2016-06-28T16:52:01.730Z,1467132721.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2016-06-28T16:52:01.732Z,1467132721.732 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2016-06-28T16:52:01.834Z,1467132721.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2016-06-28T16:52:01.835Z,1467132721.835 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2016-06-28T16:52:02.006Z,1467132722.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2016-06-28T16:52:02.008Z,1467132722.008 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2016-06-28T16:52:02.460Z,1467132722.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2016-06-28T16:52:02.461Z,1467132722.461 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2016-06-28T16:52:02.731Z,1467132722.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2016-06-28T16:52:02.731Z,1467132722.731 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2016-06-28T16:52:02.817Z,1467132722.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2016-06-28T16:52:02.819Z,1467132722.819 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2016-06-28T16:52:02.948Z,1467132722.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2016-06-28T16:52:02.949Z,1467132722.949 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2016-06-28T16:52:03.137Z,1467132723.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2016-06-28T16:52:03.137Z,1467132723.137 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2016-06-28T16:52:03.547Z,1467132723.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2016-06-28T16:52:03.548Z,1467132723.548 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2016-06-28T16:52:03.897Z,1467132723.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2016-06-28T16:52:03.898Z,1467132723.898 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2016-06-28T16:52:09.273Z,1467132729.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2016-06-28T16:52:09.274Z,1467132729.274 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2016-06-28T16:52:09.383Z,1467132729.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2016-06-28T16:52:09.385Z,1467132729.385 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2016-06-28T16:52:09.389Z,1467132729.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2016-06-28T16:52:09.487Z,1467132729.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2016-06-28T16:52:09.582Z,1467132729.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2016-06-28T16:52:09.671Z,1467132729.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2016-06-28T16:52:09.771Z,1467132729.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2016-06-28T16:52:09.860Z,1467132729.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2016-06-28T16:52:10.002Z,1467132730.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2016-06-28T16:52:10.100Z,1467132730.100 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2016-06-28T16:52:10.262Z,1467132730.261 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2016-06-28T16:52:10.490Z,1467132730.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2016-06-28T16:52:10.491Z,1467132730.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2016-06-28T16:52:10.660Z,1467132730.660 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2016-06-28T16:52:10.764Z,1467132730.764 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2016-06-28T16:52:10.767Z,1467132730.767 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2016-06-28T16:52:10.809Z,1467132730.809 [DepthRateCalculator] Loaded 2016-06-28T16:52:10.809Z,1467132730.809 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2016-06-28T16:52:10.815Z,1467132730.815 [PitchRateCalculator] Loaded 2016-06-28T16:52:10.816Z,1467132730.816 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2016-06-28T16:52:10.827Z,1467132730.827 [SpeedCalculator] Loaded 2016-06-28T16:52:10.827Z,1467132730.828 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2016-06-28T16:52:10.844Z,1467132730.844 [TempGradientCalculator] Loaded 2016-06-28T16:52:10.844Z,1467132730.844 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2016-06-28T16:52:10.860Z,1467132730.860 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2016-06-28T16:52:10.861Z,1467132730.861 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2016-06-28T16:52:10.867Z,1467132730.867 [YawRateCalculator] Loaded 2016-06-28T16:52:10.867Z,1467132730.867 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2016-06-28T16:52:10.868Z,1467132730.868 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2016-06-28T16:52:10.868Z,1467132730.868 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2016-06-28T16:52:10.992Z,1467132730.992 [DeadReckonUsingMultipleVelocitySources] Loaded 2016-06-28T16:52:10.992Z,1467132730.992 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2016-06-28T16:52:11.047Z,1467132731.047 [DeadReckonUsingSpeedCalculator] Loaded 2016-06-28T16:52:11.048Z,1467132731.048 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2016-06-28T16:52:11.065Z,1467132731.065 [NavChart] Loaded 2016-06-28T16:52:11.065Z,1467132731.066 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2016-06-28T16:52:11.070Z,1467132731.070 [UniversalFixResidualReporter] Loaded 2016-06-28T16:52:11.070Z,1467132731.070 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2016-06-28T16:52:11.071Z,1467132731.071 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2016-06-28T16:52:11.071Z,1467132731.071 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2016-06-28T16:52:11.211Z,1467132731.211 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2016-06-28T16:52:11.212Z,1467132731.212 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2016-06-28T16:52:11.252Z,1467132731.252 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2016-06-28T16:52:11.253Z,1467132731.253 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2016-06-28T16:52:11.262Z,1467132731.263 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2016-06-28T16:52:11.263Z,1467132731.263 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2016-06-28T16:52:11.540Z,1467132731.540 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2016-06-28T16:52:11.541Z,1467132731.541 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2016-06-28T16:52:11.690Z,1467132731.690 [SBIT](DEBUG): Construct Startup Built In Test. 2016-06-28T16:52:11.722Z,1467132731.722 [SBIT] Loaded 2016-06-28T16:52:11.723Z,1467132731.723 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2016-06-28T16:52:11.723Z,1467132731.723 [IBIT](DEBUG): Construct Initiated Built In Test. 2016-06-28T16:52:11.752Z,1467132731.752 [IBIT] Loaded 2016-06-28T16:52:11.752Z,1467132731.752 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2016-06-28T16:52:11.756Z,1467132731.756 [CBIT](DEBUG): Construct Continuous Built In Test. 2016-06-28T16:52:11.911Z,1467132731.911 [CBIT] Loaded 2016-06-28T16:52:11.911Z,1467132731.911 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2016-06-28T16:52:11.911Z,1467132731.911 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2016-06-28T16:52:11.912Z,1467132731.912 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2016-06-28T16:52:12.531Z,1467132732.531 [DataOverHttps] Loaded 2016-06-28T16:52:12.531Z,1467132732.532 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2016-06-28T16:52:12.559Z,1467132732.559 [Depth_Keller] Loaded 2016-06-28T16:52:12.559Z,1467132732.559 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2016-06-28T16:52:12.653Z,1467132732.653 [NAL9602] Loaded 2016-06-28T16:52:12.653Z,1467132732.653 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2016-06-28T16:52:12.663Z,1467132732.663 [Onboard] Loaded 2016-06-28T16:52:12.664Z,1467132732.664 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2016-06-28T16:52:12.671Z,1467132732.671 [Radio_Surface] Loaded 2016-06-28T16:52:12.671Z,1467132732.671 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2016-06-28T16:52:12.672Z,1467132732.672 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409234E0 2016-06-28T16:52:12.673Z,1467132732.673 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 851 2016-06-28T16:52:12.867Z,1467132732.867 [PNI_TCM] Loaded 2016-06-28T16:52:12.867Z,1467132732.867 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2016-06-28T16:52:13.226Z,1467132733.226 [Rowe_600LCM] Loaded 2016-06-28T16:52:13.227Z,1467132733.227 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2016-06-28T16:52:13.228Z,1467132733.228 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409534E0 2016-06-28T16:52:13.228Z,1467132733.228 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 852 2016-06-28T16:52:15.075Z,1467132735.075 [BPC1] Loaded 2016-06-28T16:52:15.075Z,1467132735.075 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2016-06-28T16:52:15.075Z,1467132735.075 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2016-06-28T16:52:15.076Z,1467132735.076 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2016-06-28T16:52:17.489Z,1467132737.489 [BuoyancyServo] Loaded 2016-06-28T16:52:17.489Z,1467132737.489 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2016-06-28T16:52:17.506Z,1467132737.506 [ElevatorServo] Loaded 2016-06-28T16:52:17.506Z,1467132737.506 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2016-06-28T16:52:17.522Z,1467132737.522 [MassServo] Loaded 2016-06-28T16:52:17.522Z,1467132737.522 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2016-06-28T16:52:17.539Z,1467132737.539 [RudderServo] Loaded 2016-06-28T16:52:17.539Z,1467132737.539 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2016-06-28T16:52:17.555Z,1467132737.555 [ThrusterServo] Loaded 2016-06-28T16:52:17.555Z,1467132737.555 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2016-06-28T16:52:17.556Z,1467132737.556 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2016-06-28T16:52:17.556Z,1467132737.556 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2016-06-28T16:52:17.626Z,1467132737.626 [VerticalControl](DEBUG): Construct VerticalControl. 2016-06-28T16:52:17.722Z,1467132737.722 [VerticalControl] Loaded 2016-06-28T16:52:17.722Z,1467132737.722 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2016-06-28T16:52:17.723Z,1467132737.723 [HorizontalControl](DEBUG): Construct HorizontalControl. 2016-06-28T16:52:17.785Z,1467132737.785 [HorizontalControl] Loaded 2016-06-28T16:52:17.785Z,1467132737.785 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2016-06-28T16:52:17.786Z,1467132737.786 [SpeedControl](DEBUG): Construct SpeedControl. 2016-06-28T16:52:17.788Z,1467132737.788 [SpeedControl] Loaded 2016-06-28T16:52:17.788Z,1467132737.788 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2016-06-28T16:52:17.789Z,1467132737.789 [LoopControl](DEBUG): Construct LoopControl. 2016-06-28T16:52:17.790Z,1467132737.790 [LoopControl] Loaded 2016-06-28T16:52:17.790Z,1467132737.790 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2016-06-28T16:52:17.790Z,1467132737.790 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2016-06-28T16:52:17.791Z,1467132737.791 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2016-06-28T16:52:17.909Z,1467132737.909 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2016-06-28T16:52:17.909Z,1467132737.909 [StratificationFrontDetector](DEBUG): (re)initializing 2016-06-28T16:52:17.909Z,1467132737.909 [StratificationFrontDetector] Loaded 2016-06-28T16:52:17.910Z,1467132737.910 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2016-06-28T16:52:17.910Z,1467132737.910 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2016-06-28T16:52:17.911Z,1467132737.911 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2016-06-28T16:52:18.044Z,1467132738.044 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2016-06-28T16:52:18.048Z,1467132738.048 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2016-06-28T16:52:18.049Z,1467132738.049 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2016-06-28T16:52:18.056Z,1467132738.056 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2016-06-28T16:52:18.057Z,1467132738.057 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A5D4E0 2016-06-28T16:52:18.057Z,1467132738.057 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 853 2016-06-28T16:52:18.062Z,1467132738.062 [Supervisor](INFO): Main Thread ID is 764 2016-06-28T16:52:18.062Z,1467132738.062 [Supervisor](DEBUG): Running supervisor. 2016-06-28T16:52:18.063Z,1467132738.063 [CommandLine ThreadHandler](INFO): Handler Thread ID is 854 2016-06-28T16:52:18.066Z,1467132738.066 [controlThread ThreadHandler](INFO): Handler Thread ID is 855 2016-06-28T16:52:18.066Z,1467132738.066 [controlThread](DEBUG): Initializing ControlThread 2016-06-28T16:52:18.067Z,1467132738.067 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-06-28T16:52:18.067Z,1467132738.067 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2016-06-28T16:52:18.068Z,1467132738.068 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2016-06-28T16:52:18.068Z,1467132738.068 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2016-06-28T16:52:18.069Z,1467132738.069 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2016-06-28T16:52:18.069Z,1467132738.069 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2016-06-28T16:52:18.070Z,1467132738.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-06-28T16:52:18.071Z,1467132738.071 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-06-28T16:52:18.071Z,1467132738.071 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-06-28T16:52:18.072Z,1467132738.072 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-06-28T16:52:18.072Z,1467132738.072 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-06-28T16:52:18.072Z,1467132738.072 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-06-28T16:52:18.073Z,1467132738.073 [NavChart](DEBUG): Initialize NavChart Navigation. 2016-06-28T16:52:18.073Z,1467132738.073 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2016-06-28T16:52:18.074Z,1467132738.074 [SBIT](INFO): Initialize SBIT Component. 2016-06-28T16:52:18.074Z,1467132738.074 [SBIT](IMPORTANT): git: 2015-12-17-108-g5bef090 2016-06-28T16:52:18.074Z,1467132738.074 [SBIT](INFO): git hash: 5bef0906e748577e5d4ac431bf22a986aa172b48 2016-06-28T16:52:18.076Z,1467132738.076 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2016-06-28T16:52:18.076Z,1467132738.076 [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 2016-06-28T16:52:18.077Z,1467132738.077 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2016-06-28T16:52:18.078Z,1467132738.078 [IBIT](INFO): Initialize IBIT Component. 2016-06-28T16:52:18.079Z,1467132738.079 [CBIT](DEBUG): Initialize CBIT Component. 2016-06-28T16:52:18.079Z,1467132738.079 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2016-06-28T16:52:18.079Z,1467132738.079 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2016-06-28T16:52:18.080Z,1467132738.080 [logger ThreadHandler](INFO): Handler Thread ID is 856 2016-06-28T16:52:18.108Z,1467132738.108 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 857 2016-06-28T16:52:18.114Z,1467132738.114 [Radio_Surface](INFO): Powering up 2016-06-28T16:52:18.132Z,1467132738.132 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 858 2016-06-28T16:52:18.132Z,1467132738.132 [Rowe_600LCM](INFO): Initializing 2016-06-28T16:52:18.133Z,1467132738.133 [Rowe_600LCM](INFO): Checking LCM 2016-06-28T16:52:18.133Z,1467132738.133 [Rowe_600LCM](INFO): LCM OK 2016-06-28T16:52:18.133Z,1467132738.133 [Rowe_600LCM](INFO): Powering up 2016-06-28T16:52:18.152Z,1467132738.152 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 859 2016-06-28T16:52:18.163Z,1467132738.163 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources/ElectronicNavigationCharts 2016-06-28T16:52:18.170Z,1467132738.170 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2016-06-28T16:52:18.172Z,1467132738.172 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2016-06-28T16:52:18.173Z,1467132738.173 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2016-06-28T16:52:18.174Z,1467132738.174 [LoopControl](DEBUG): Initialize LoopControlComponent. 2016-06-28T16:52:18.175Z,1467132738.175 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2016-06-28T16:52:18.202Z,1467132738.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2016-06-28T16:52:18.202Z,1467132738.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000 2016-06-28T16:52:18.202Z,1467132738.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000 2016-06-28T16:52:18.203Z,1467132738.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2016-06-28T16:52:18.203Z,1467132738.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2016-06-28T16:52:18.203Z,1467132738.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2016-06-28T16:52:18.203Z,1467132738.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4IN01M.000 2016-06-28T16:52:18.204Z,1467132738.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000 2016-06-28T16:52:18.204Z,1467132738.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000 2016-06-28T16:52:18.204Z,1467132738.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI59M.000 2016-06-28T16:52:18.204Z,1467132738.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000 2016-06-28T16:52:18.205Z,1467132738.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000 2016-06-28T16:52:18.205Z,1467132738.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000 2016-06-28T16:52:18.205Z,1467132738.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000 2016-06-28T16:52:18.205Z,1467132738.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI33M.000 2016-06-28T16:52:18.206Z,1467132738.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI34M.000 2016-06-28T16:52:18.206Z,1467132738.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2016-06-28T16:52:18.206Z,1467132738.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2016-06-28T16:52:18.206Z,1467132738.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2016-06-28T16:52:18.206Z,1467132738.207 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2016-06-28T16:52:18.226Z,1467132738.226 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2016-06-28T16:52:18.265Z,1467132738.265 [MissionManager](DEBUG): 2016-06-28T16:52:18.265Z,1467132738.265 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2016-06-28T16:52:18.334Z,1467132738.334 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2016-06-28T16:52:18.335Z,1467132738.335 [Default:A.Wait](DEBUG): Construct Wait. 2016-06-28T16:52:18.337Z,1467132738.337 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2016-06-28T16:52:18.371Z,1467132738.371 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2016-06-28T16:52:18.394Z,1467132738.394 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2016-06-28T16:52:18.416Z,1467132738.416 [Default:E.Execute](DEBUG): Construct Execute. 2016-06-28T16:52:18.419Z,1467132738.419 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2016-06-28T16:52:18.431Z,1467132738.431 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2016-06-28T16:52:18.584Z,1467132738.584 [NAL9602](INFO): Powering up NAL9602 2016-06-28T16:52:18.832Z,1467132738.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:18.986Z,1467132738.986 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:19.108Z,1467132739.108 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-06-28T16:52:19.116Z,1467132739.116 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-06-28T16:52:19.122Z,1467132739.122 [ElevatorServo](DEBUG): Initializing EZServoServo. 2016-06-28T16:52:19.128Z,1467132739.128 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2016-06-28T16:52:19.150Z,1467132739.150 [MassServo](DEBUG): Initializing EZServoServo. 2016-06-28T16:52:19.156Z,1467132739.156 [MassServo](DEBUG): Initializing MassServo. 2016-06-28T16:52:19.188Z,1467132739.188 [RudderServo](DEBUG): Initializing EZServoServo. 2016-06-28T16:52:19.196Z,1467132739.196 [RudderServo](DEBUG): Initializing RudderServo. 2016-06-28T16:52:19.201Z,1467132739.201 [ThrusterServo](DEBUG): Initializing EZServoServo. 2016-06-28T16:52:19.208Z,1467132739.208 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2016-06-28T16:52:19.478Z,1467132739.478 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:19.479Z,1467132739.479 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:19.739Z,1467132739.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:19.740Z,1467132739.740 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:20.087Z,1467132740.088 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2016-06-28T16:52:20.088Z,1467132740.088 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2016-06-28T16:52:20.088Z,1467132740.088 [BuoyancyServo] Communications Fault, FailCount= 1 2016-06-28T16:52:20.088Z,1467132740.088 [BuoyancyServo](ERROR): Communications Fault 2016-06-28T16:52:20.315Z,1467132740.315 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2016-06-28T16:52:20.369Z,1467132740.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:20.370Z,1467132740.370 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:20.383Z,1467132740.383 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2016-06-28T16:52:20.384Z,1467132740.385 [BuoyancyServo](INFO): Powering down 2016-06-28T16:52:20.767Z,1467132740.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:20.776Z,1467132740.776 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:21.151Z,1467132741.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:21.152Z,1467132741.152 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:21.551Z,1467132741.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:21.552Z,1467132741.552 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:21.964Z,1467132741.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:21.965Z,1467132741.965 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-06-28T16:52:22.498Z,1467132742.498 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-06-28T16:52:22.650Z,1467132742.650 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2016-06-28T16:52:22.650Z,1467132742.650 [BuoyancyServo] No Fault, FailCount= 1 2016-06-28T16:52:22.691Z,1467132742.691 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyB4 -b 38400 >& /dev/null & 2016-06-28T16:52:22.862Z,1467132742.862 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-06-28T16:52:22.862Z,1467132742.862 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-06-28T16:52:23.485Z,1467132743.485 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-06-28T16:52:23.486Z,1467132743.486 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-06-28T16:52:29.657Z,1467132749.657 [NAL9602](INFO): NAL9602 initialized 2016-06-28T16:52:38.589Z,1467132758.589 [SBIT](IMPORTANT): Beginning Startup BIT 2016-06-28T16:52:38.601Z,1467132758.601 [CBIT](IMPORTANT): Beginning GF scan 2016-06-28T16:52:39.052Z,1467132759.052 [CBIT](IMPORTANT): Open Volts:2.500541 Open Current:0.000862 2016-06-28T16:52:40.609Z,1467132760.609 [CBIT](IMPORTANT): A0 Volts:2.669990 A0 Current:0.107658 2016-06-28T16:52:42.217Z,1467132762.217 [CBIT](IMPORTANT): A1 Volts:2.022557 A1 Current:-0.304291 2016-06-28T16:52:42.513Z,1467132762.513 [PNI_TCM](ERROR): CRC does not match. Expected:0x49153 got:0x62485 2016-06-28T16:52:42.513Z,1467132762.513 [PNI_TCM](ERROR): CRC does not match. Expected:0x53461 got:0x62544 2016-06-28T16:52:43.081Z,1467132763.081 [PNI_TCM](ERROR): CRC does not match. Expected:0x2847 got:0x6464 2016-06-28T16:52:43.429Z,1467132763.429 [CBIT](IMPORTANT): A2 Volts:2.669866 A2 Current:0.107785 2016-06-28T16:52:43.632Z,1467132763.632 [PNI_TCM](ERROR): CRC does not match. Expected:0x25319 got:0x6464 2016-06-28T16:52:43.633Z,1467132763.633 [PNI_TCM](ERROR): CRC does not match. Expected:0x45041 got:0x6464 2016-06-28T16:52:44.112Z,1467132764.112 [PNI_TCM](ERROR): CRC does not match. Expected:0x34785 got:0x6464 2016-06-28T16:52:44.493Z,1467132764.493 [PNI_TCM](ERROR): CRC does not match. Expected:0x34585 got:0x6464 2016-06-28T16:52:44.844Z,1467132764.844 [CBIT](IMPORTANT): A3 Volts:2.618265 A3 Current:0.074852 2016-06-28T16:52:44.894Z,1467132764.894 [PNI_TCM](ERROR): CRC does not match. Expected:0x61056 got:0x6464 2016-06-28T16:52:45.281Z,1467132765.281 [PNI_TCM](ERROR): CRC does not match. Expected:0x5847 got:0x6464 2016-06-28T16:52:45.833Z,1467132765.833 [PNI_TCM](ERROR): CRC does not match. Expected:0x63157 got:0x6464 2016-06-28T16:52:46.461Z,1467132766.461 [CBIT](IMPORTANT): B0 Volts:2.389941 B0 Current:-0.069464 2016-06-28T16:52:46.539Z,1467132766.539 [PNI_TCM](ERROR): CRC does not match. Expected:0x61312 got:0x6464 2016-06-28T16:52:46.540Z,1467132766.540 [PNI_TCM](ERROR): CRC does not match. Expected:0x42792 got:0x6464 2016-06-28T16:52:46.917Z,1467132766.917 [PNI_TCM](ERROR): CRC does not match. Expected:0x49230 got:0x6464 2016-06-28T16:52:46.917Z,1467132766.917 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2016-06-28T16:52:46.917Z,1467132766.917 [PNI_TCM] Data Fault, FailCount= 1 2016-06-28T16:52:46.917Z,1467132766.917 [PNI_TCM](ERROR): Data Fault 2016-06-28T16:52:46.940Z,1467132766.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.351600 seconds old. 2016-06-28T16:52:46.941Z,1467132766.941 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.352200 seconds old. 2016-06-28T16:52:47.069Z,1467132767.069 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-06-28T16:52:47.310Z,1467132767.309 [PNI_TCM](INFO): Powering down 2016-06-28T16:52:47.316Z,1467132767.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.727800 seconds old. 2016-06-28T16:52:47.317Z,1467132767.317 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.728300 seconds old. 2016-06-28T16:52:47.767Z,1467132767.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.177800 seconds old. 2016-06-28T16:52:47.770Z,1467132767.770 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.180600 seconds old. 2016-06-28T16:52:48.129Z,1467132768.129 [CBIT](IMPORTANT): B1 Volts:2.285340 B1 Current:-0.136533 2016-06-28T16:52:48.268Z,1467132768.268 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.671100 seconds old. 2016-06-28T16:52:48.273Z,1467132768.273 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.683200 seconds old. 2016-06-28T16:52:48.616Z,1467132768.616 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.027600 seconds old. 2016-06-28T16:52:48.617Z,1467132768.617 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.028100 seconds old. 2016-06-28T16:52:48.736Z,1467132768.736 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-06-28T16:52:48.736Z,1467132768.736 [PNI_TCM] No Fault, FailCount= 1 2016-06-28T16:52:49.004Z,1467132769.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.415700 seconds old. 2016-06-28T16:52:49.005Z,1467132769.005 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.416200 seconds old. 2016-06-28T16:52:49.376Z,1467132769.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.787700 seconds old. 2016-06-28T16:52:49.377Z,1467132769.377 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.788200 seconds old. 2016-06-28T16:52:49.665Z,1467132769.665 [CBIT](IMPORTANT): B2 Volts:2.282622 B2 Current:-0.137577 2016-06-28T16:52:49.865Z,1467132769.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.276100 seconds old. 2016-06-28T16:52:49.868Z,1467132769.868 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.279000 seconds old. 2016-06-28T16:52:50.227Z,1467132770.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.638300 seconds old. 2016-06-28T16:52:50.252Z,1467132770.253 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.662800 seconds old. 2016-06-28T16:52:50.577Z,1467132770.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.989000 seconds old. 2016-06-28T16:52:50.578Z,1467132770.579 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.989500 seconds old. 2016-06-28T16:52:50.988Z,1467132770.988 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.398700 seconds old. 2016-06-28T16:52:50.989Z,1467132770.989 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.400200 seconds old. 2016-06-28T16:52:51.272Z,1467132771.272 [CBIT](IMPORTANT): B3 Volts:1.777029 B3 Current:-0.459228 2016-06-28T16:52:51.376Z,1467132771.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.787500 seconds old. 2016-06-28T16:52:51.377Z,1467132771.377 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.788100 seconds old. 2016-06-28T16:52:51.456Z,1467132771.455 [CBIT](FAULT): Low side GF detected mA: CHAN A0 (Batt): 0.107658 CHAN A1 (24V): -0.304291 CHAN A2 (12V): 0.107785 CHAN A3 (5V): 0.074852 CHAN B0 (3.3V): -0.069464 CHAN B1 (3.15aV): -0.136533 CHAN B2 (3.15bV): -0.137577 CHAN B3 (GND): -0.459228 OPEN: 0.000862 Full Scale Calc: 4.765 mA, -1.589 mA 2016-06-28T16:52:51.776Z,1467132771.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.187500 seconds old. 2016-06-28T16:52:51.777Z,1467132771.777 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.188100 seconds old. 2016-06-28T16:53:02.379Z,1467132782.379 [NAL9602](INFO): SBD MO Status=0, MOMSN=538, MT Status=0, MTMSN=0 2016-06-28T16:53:02.385Z,1467132782.385 [NAL9602](INFO): No messages in MT queue 2016-06-28T16:53:15.335Z,1467132795.335 [BPC1](ERROR): Failed to parse bank B battery data 2016-06-28T16:53:15.335Z,1467132795.335 [BPC1] Data Fault, FailCount= 1 2016-06-28T16:53:15.335Z,1467132795.335 [BPC1](ERROR): Data Fault 2016-06-28T16:53:15.417Z,1467132795.417 [CBIT](ERROR): Data Fault in component: BPC1 2016-06-28T16:53:16.786Z,1467132796.786 [CBIT](INFO): Clearing failed state for component BPC1 2016-06-28T16:53:16.786Z,1467132796.786 [BPC1] No Fault, FailCount= 1 2016-06-28T16:53:25.932Z,1467132805.932 [PNI_TCM](ERROR): CRC does not match. Expected:0x13372 got:0x6464 2016-06-28T16:53:26.335Z,1467132806.335 [PNI_TCM](ERROR): CRC does not match. Expected:0x26131 got:0x23391 2016-06-28T16:53:26.604Z,1467132806.604 [PNI_TCM](ERROR): CRC does not match. Expected:0x50944 got:0x15283 2016-06-28T16:53:27.019Z,1467132807.019 [PNI_TCM](ERROR): CRC does not match. Expected:0x42449 got:0x15708 2016-06-28T16:53:27.426Z,1467132807.425 [PNI_TCM](ERROR): CRC does not match. Expected:0x20712 got:0x18345 2016-06-28T16:53:27.822Z,1467132807.822 [PNI_TCM](ERROR): CRC does not match. Expected:0x64016 got:0x64035 2016-06-28T16:53:28.337Z,1467132808.337 [PNI_TCM](ERROR): CRC does not match. Expected:0x58583 got:0x62450 2016-06-28T16:53:28.901Z,1467132808.901 [PNI_TCM](ERROR): CRC does not match. Expected:0x60299 got:0x16150 2016-06-28T16:53:29.193Z,1467132809.193 [PNI_TCM](ERROR): CRC does not match. Expected:0x21519 got:0x12515 2016-06-28T16:53:29.671Z,1467132809.671 [PNI_TCM](ERROR): CRC does not match. Expected:0x17396 got:0x13360 2016-06-28T16:53:30.091Z,1467132810.091 [PNI_TCM](ERROR): CRC does not match. Expected:0x39047 got:0x18091 2016-06-28T16:53:30.423Z,1467132810.423 [PNI_TCM](ERROR): CRC does not match. Expected:0x49825 got:0x18572 2016-06-28T16:53:30.780Z,1467132810.780 [PNI_TCM](ERROR): CRC does not match. Expected:0x62835 got:0x21856 2016-06-28T16:53:30.781Z,1467132810.781 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2016-06-28T16:53:30.781Z,1467132810.781 [PNI_TCM] Data Fault, FailCount= 1 2016-06-28T16:53:30.781Z,1467132810.781 [PNI_TCM](ERROR): Data Fault 2016-06-28T16:53:30.828Z,1467132810.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.383400 seconds old. 2016-06-28T16:53:30.829Z,1467132810.829 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.383900 seconds old. 2016-06-28T16:53:30.865Z,1467132810.865 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-06-28T16:53:31.173Z,1467132811.173 [PNI_TCM](INFO): Powering down 2016-06-28T16:53:31.180Z,1467132811.180 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.735600 seconds old. 2016-06-28T16:53:31.181Z,1467132811.181 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.736100 seconds old. 2016-06-28T16:53:31.708Z,1467132811.708 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.254300 seconds old. 2016-06-28T16:53:31.720Z,1467132811.720 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.266100 seconds old. 2016-06-28T16:53:32.074Z,1467132812.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.629200 seconds old. 2016-06-28T16:53:32.081Z,1467132812.081 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.635500 seconds old. 2016-06-28T16:53:32.412Z,1467132812.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.967100 seconds old. 2016-06-28T16:53:32.428Z,1467132812.428 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.982600 seconds old. 2016-06-28T16:53:32.452Z,1467132812.452 [SBIT](IMPORTANT): SBIT PASSED 2016-06-28T16:53:32.455Z,1467132812.455 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-06-28T16:53:32.455Z,1467132812.455 [PNI_TCM] No Fault, FailCount= 1 2016-06-28T16:53:32.812Z,1467132812.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.367900 seconds old. 2016-06-28T16:53:32.814Z,1467132812.814 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.368400 seconds old. 2016-06-28T16:53:32.820Z,1467132812.820 [MissionManager](IMPORTANT): Started mission Startup 2016-06-28T16:53:32.820Z,1467132812.820 [Startup] Running Loop=1 2016-06-28T16:53:32.820Z,1467132812.820 [Startup](DEBUG): Aggregate::initialize Startup 2016-06-28T16:53:32.820Z,1467132812.820 [Startup:A.GoToSurface] Running Loop=1 2016-06-28T16:53:32.820Z,1467132812.820 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2016-06-28T16:53:32.821Z,1467132812.821 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2016-06-28T16:53:32.821Z,1467132812.821 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2016-06-28T16:53:32.822Z,1467132812.822 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2016-06-28T16:53:32.822Z,1467132812.822 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2016-06-28T16:53:32.822Z,1467132812.822 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2016-06-28T16:53:32.857Z,1467132812.857 [Startup:StartupSatComms] Running Loop=1 2016-06-28T16:53:32.857Z,1467132812.857 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2016-06-28T16:53:32.857Z,1467132812.857 [Startup:StartupSatComms:A] Running Loop=1 2016-06-28T16:53:33.200Z,1467132813.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.755100 seconds old. 2016-06-28T16:53:33.201Z,1467132813.201 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.755700 seconds old. 2016-06-28T16:53:33.207Z,1467132813.207 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2016-06-28T16:53:33.674Z,1467132813.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.229700 seconds old. 2016-06-28T16:53:33.678Z,1467132813.678 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.232700 seconds old. 2016-06-28T16:53:34.057Z,1467132814.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.598600 seconds old. 2016-06-28T16:53:34.061Z,1467132814.061 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.615100 seconds old. 2016-06-28T16:53:34.410Z,1467132814.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.966100 seconds old. 2016-06-28T16:53:34.420Z,1467132814.420 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.974700 seconds old. 2016-06-28T16:53:34.824Z,1467132814.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.379800 seconds old. 2016-06-28T16:53:34.826Z,1467132814.826 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.380300 seconds old. 2016-06-28T16:53:35.188Z,1467132815.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.743200 seconds old. 2016-06-28T16:53:35.189Z,1467132815.189 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.743800 seconds old. 2016-06-28T16:53:35.626Z,1467132815.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.181300 seconds old. 2016-06-28T16:53:35.645Z,1467132815.645 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.199300 seconds old. 2016-06-28T16:53:40.162Z,1467132820.162 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:53:40.574Z,1467132820.574 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:53:40.984Z,1467132820.984 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:53:50.105Z,1467132830.105 [PNI_TCM](ERROR): CRC does not match. Expected:0x35585 got:0x62465 2016-06-28T16:53:50.806Z,1467132830.806 [PNI_TCM](ERROR): CRC does not match. Expected:0x20904 got:0x62387 2016-06-28T16:53:51.195Z,1467132831.195 [PNI_TCM](ERROR): CRC does not match. Expected:0x50912 got:0x62424 2016-06-28T16:53:51.584Z,1467132831.584 [PNI_TCM](ERROR): CRC does not match. Expected:0x55758 got:0x62429 2016-06-28T16:53:52.096Z,1467132832.096 [PNI_TCM](ERROR): CRC does not match. Expected:0x48537 got:0x62441 2016-06-28T16:53:52.097Z,1467132832.097 [PNI_TCM](ERROR): CRC does not match. Expected:0x11256 got:0x62354 2016-06-28T16:53:52.493Z,1467132832.493 [PNI_TCM](ERROR): CRC does not match. Expected:0x16216 got:0x62351 2016-06-28T16:53:52.858Z,1467132832.858 [PNI_TCM](ERROR): CRC does not match. Expected:0x21830 got:0x62442 2016-06-28T16:53:53.744Z,1467132833.744 [PNI_TCM](ERROR): CRC does not match. Expected:0x2101 got:0x62319 2016-06-28T16:53:53.746Z,1467132833.746 [PNI_TCM](ERROR): CRC does not match. Expected:0x12306 got:0x62343 2016-06-28T16:53:54.196Z,1467132834.196 [PNI_TCM](ERROR): CRC does not match. Expected:0x20609 got:0x62421 2016-06-28T16:53:54.444Z,1467132834.444 [PNI_TCM](ERROR): CRC does not match. Expected:0x47198 got:0x62485 2016-06-28T16:53:54.836Z,1467132834.836 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2016-06-28T16:53:54.836Z,1467132834.836 [PNI_TCM] Data Fault, FailCount= 1 2016-06-28T16:53:54.837Z,1467132834.837 [PNI_TCM](ERROR): Data Fault 2016-06-28T16:53:54.886Z,1467132834.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.421200 seconds old. 2016-06-28T16:53:54.887Z,1467132834.887 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.421700 seconds old. 2016-06-28T16:53:54.924Z,1467132834.924 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-06-28T16:53:55.229Z,1467132835.229 [PNI_TCM](INFO): Powering down 2016-06-28T16:53:55.236Z,1467132835.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.771400 seconds old. 2016-06-28T16:53:55.237Z,1467132835.237 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.771900 seconds old. 2016-06-28T16:53:55.737Z,1467132835.737 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.271600 seconds old. 2016-06-28T16:53:55.750Z,1467132835.750 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.284400 seconds old. 2016-06-28T16:53:56.097Z,1467132836.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.632300 seconds old. 2016-06-28T16:53:56.110Z,1467132836.110 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.643800 seconds old. 2016-06-28T16:53:56.459Z,1467132836.459 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.994300 seconds old. 2016-06-28T16:53:56.472Z,1467132836.472 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.006900 seconds old. 2016-06-28T16:53:56.484Z,1467132836.484 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-06-28T16:53:56.485Z,1467132836.485 [PNI_TCM] No Fault, FailCount= 1 2016-06-28T16:53:56.846Z,1467132836.846 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.380900 seconds old. 2016-06-28T16:53:56.865Z,1467132836.865 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.399200 seconds old. 2016-06-28T16:53:57.264Z,1467132837.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.799300 seconds old. 2016-06-28T16:53:57.265Z,1467132837.265 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.799800 seconds old. 2016-06-28T16:53:57.691Z,1467132837.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.226200 seconds old. 2016-06-28T16:53:57.695Z,1467132837.695 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.229000 seconds old. 2016-06-28T16:53:58.179Z,1467132838.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.713700 seconds old. 2016-06-28T16:53:58.199Z,1467132838.200 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.725400 seconds old. 2016-06-28T16:53:58.441Z,1467132838.441 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.976700 seconds old. 2016-06-28T16:53:58.442Z,1467132838.443 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.977200 seconds old. 2016-06-28T16:53:58.872Z,1467132838.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.407000 seconds old. 2016-06-28T16:53:58.873Z,1467132838.873 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.407600 seconds old. 2016-06-28T16:53:59.228Z,1467132839.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.763200 seconds old. 2016-06-28T16:53:59.229Z,1467132839.229 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.763800 seconds old. 2016-06-28T16:53:59.773Z,1467132839.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.307800 seconds old. 2016-06-28T16:53:59.790Z,1467132839.790 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.324000 seconds old. 2016-06-28T16:54:33.163Z,1467132873.163 [Startup:StartupSatComms:A](INFO): Timed out from 2016-06-28T16:53:32.9Z 2016-06-28T16:54:33.167Z,1467132873.167 [Startup:StartupSatComms:A] Stopped 2016-06-28T16:54:33.167Z,1467132873.167 [Startup:StartupSatComms:B] Running Loop=1 2016-06-28T16:54:33.730Z,1467132873.730 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2016-06-28T16:54:59.110Z,1467132899.110 [NAL9602](INFO): SBD MO Status=2, MOMSN=539, MT Status=2, MTMSN=0 2016-06-28T16:54:59.111Z,1467132899.111 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2016-06-28T16:55:18.878Z,1467132918.878 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-06-28T16:55:18.878Z,1467132918.878 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2016-06-28T16:55:18.878Z,1467132918.878 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-06-28T16:55:18.884Z,1467132918.884 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-06-28T16:55:18.884Z,1467132918.884 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2016-06-28T16:55:18.884Z,1467132918.884 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-06-28T16:55:18.929Z,1467132918.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-06-28T16:55:18.929Z,1467132918.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-06-28T16:55:19.029Z,1467132919.029 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-06-28T16:55:19.030Z,1467132919.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2016-06-28T16:55:19.030Z,1467132919.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-06-28T16:55:19.030Z,1467132919.030 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2016-06-28T16:55:19.511Z,1467132919.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-06-28T16:55:19.513Z,1467132919.513 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-06-28T16:55:19.514Z,1467132919.514 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-06-28T16:55:19.516Z,1467132919.516 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-06-28T16:55:19.518Z,1467132919.518 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-06-28T16:55:19.528Z,1467132919.528 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-06-28T16:55:33.517Z,1467132933.517 [Startup:StartupSatComms:B](INFO): Timed out from 2016-06-28T16:54:33.2Z 2016-06-28T16:55:33.518Z,1467132933.518 [Startup:StartupSatComms:B] Stopped 2016-06-28T16:55:33.518Z,1467132933.518 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2016-06-28T16:55:33.518Z,1467132933.518 [Startup:StartupSatComms] Stopped 2016-06-28T16:55:33.519Z,1467132933.519 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2016-06-28T16:55:33.521Z,1467132933.521 [Startup](INFO): Completed Startup 2016-06-28T16:55:33.521Z,1467132933.521 [Startup] Stopped 2016-06-28T16:55:33.522Z,1467132933.522 [Startup](DEBUG): Aggregate::uninitialize Startup 2016-06-28T16:55:33.522Z,1467132933.522 [Startup:A.GoToSurface] Stopped 2016-06-28T16:55:33.522Z,1467132933.522 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2016-06-28T16:55:33.938Z,1467132933.938 [MissionManager](IMPORTANT): Started mission Default 2016-06-28T16:55:33.938Z,1467132933.938 [Default] Running Loop=1 2016-06-28T16:55:33.938Z,1467132933.938 [Default](DEBUG): Aggregate::initialize Default 2016-06-28T16:55:33.939Z,1467132933.939 [Default:B.GoToSurface] Running Loop=1 2016-06-28T16:55:33.939Z,1467132933.939 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2016-06-28T16:55:33.940Z,1467132933.940 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2016-06-28T16:55:33.941Z,1467132933.941 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2016-06-28T16:55:33.941Z,1467132933.941 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2016-06-28T16:55:33.942Z,1467132933.942 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2016-06-28T16:55:33.960Z,1467132933.960 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2016-06-28T16:55:33.960Z,1467132933.960 [Default:A.Wait] Running Loop=1 2016-06-28T16:55:33.960Z,1467132933.960 [Default:A.Wait](DEBUG): Initialize Wait Component. 2016-06-28T16:55:35.774Z,1467132935.774 [NAL9602](INFO): SBD MO Status=2, MOMSN=539, MT Status=2, MTMSN=0 2016-06-28T16:55:35.775Z,1467132935.775 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2016-06-28T16:55:47.025Z,1467132947.026 [Default:A.Wait](INFO): Done Waiting. 2016-06-28T16:55:47.026Z,1467132947.026 [Default:A.Wait] Stopped 2016-06-28T16:55:47.026Z,1467132947.026 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2016-06-28T16:55:47.588Z,1467132947.588 [Default:CheckIn] Running Loop=1 2016-06-28T16:55:47.588Z,1467132947.588 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2016-06-28T16:55:47.589Z,1467132947.589 [Default:CheckIn:Read_GPS] Running Loop=1 2016-06-28T16:55:48.005Z,1467132948.005 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2016-06-28T16:56:29.128Z,1467132989.128 [PNI_TCM](ERROR): CRC does not match. Expected:0x33340 got:0x24617 2016-06-28T16:56:45.928Z,1467133005.928 [PNI_TCM](ERROR): CRC does not match. Expected:0x58331 got:0x62275 2016-06-28T16:56:46.652Z,1467133006.652 [PNI_TCM](ERROR): CRC does not match. Expected:0x43066 got:0x62304 2016-06-28T16:56:47.177Z,1467133007.177 [PNI_TCM](ERROR): CRC does not match. Expected:0x40171 got:0x62307 2016-06-28T16:56:47.178Z,1467133007.178 [PNI_TCM](ERROR): CRC does not match. Expected:0x48934 got:0x62279 2016-06-28T16:56:47.603Z,1467133007.603 [PNI_TCM](ERROR): CRC does not match. Expected:0x8905 got:0x62271 2016-06-28T16:56:47.932Z,1467133007.932 [PNI_TCM](ERROR): CRC does not match. Expected:0x5540 got:0x62300 2016-06-28T16:56:48.667Z,1467133008.667 [PNI_TCM](ERROR): CRC does not match. Expected:0x3917 got:0x62382 2016-06-28T16:56:49.146Z,1467133009.146 [PNI_TCM](ERROR): CRC does not match. Expected:0x44286 got:0x62332 2016-06-28T16:56:49.152Z,1467133009.152 [PNI_TCM](ERROR): CRC does not match. Expected:0x6225 got:0x62381 2016-06-28T16:56:49.519Z,1467133009.519 [PNI_TCM](ERROR): CRC does not match. Expected:0x36659 got:0x62316 2016-06-28T16:56:49.955Z,1467133009.955 [PNI_TCM](ERROR): CRC does not match. Expected:0x3820 got:0x6464 2016-06-28T16:56:50.673Z,1467133010.673 [PNI_TCM](ERROR): CRC does not match. Expected:0x14013 got:0x6464 2016-06-28T16:56:50.673Z,1467133010.673 [PNI_TCM](ERROR): CRC does not match. Expected:0x24888 got:0x6464 2016-06-28T16:56:50.673Z,1467133010.673 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2016-06-28T16:56:50.673Z,1467133010.673 [PNI_TCM] Data Fault, FailCount= 1 2016-06-28T16:56:50.673Z,1467133010.673 [PNI_TCM](ERROR): Data Fault 2016-06-28T16:56:50.680Z,1467133010.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.414700 seconds old. 2016-06-28T16:56:50.681Z,1467133010.681 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.413900 seconds old. 2016-06-28T16:56:50.723Z,1467133010.723 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-06-28T16:56:51.051Z,1467133011.051 [PNI_TCM](INFO): Powering down 2016-06-28T16:56:51.064Z,1467133011.064 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.798700 seconds old. 2016-06-28T16:56:51.065Z,1467133011.065 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.797900 seconds old. 2016-06-28T16:56:51.465Z,1467133011.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.199400 seconds old. 2016-06-28T16:56:51.466Z,1467133011.466 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.198600 seconds old. 2016-06-28T16:56:51.883Z,1467133011.883 [BPC1](ERROR): Failed to parse bank A battery data 2016-06-28T16:56:51.884Z,1467133011.884 [BPC1] Data Fault, FailCount= 1 2016-06-28T16:56:51.884Z,1467133011.884 [BPC1](ERROR): Data Fault 2016-06-28T16:56:51.929Z,1467133011.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.663000 seconds old. 2016-06-28T16:56:51.937Z,1467133011.937 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.669200 seconds old. 2016-06-28T16:56:52.035Z,1467133012.035 [CBIT](ERROR): Data Fault in component: BPC1 2016-06-28T16:56:52.342Z,1467133012.342 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.075900 seconds old. 2016-06-28T16:56:52.350Z,1467133012.350 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.082100 seconds old. 2016-06-28T16:56:52.434Z,1467133012.434 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-06-28T16:56:52.434Z,1467133012.434 [PNI_TCM] No Fault, FailCount= 1 2016-06-28T16:56:52.660Z,1467133012.660 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.394300 seconds old. 2016-06-28T16:56:52.661Z,1467133012.661 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.393700 seconds old. 2016-06-28T16:56:53.124Z,1467133013.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.849800 seconds old. 2016-06-28T16:56:53.149Z,1467133013.149 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.880800 seconds old. 2016-06-28T16:56:53.242Z,1467133013.241 [CBIT](INFO): Clearing failed state for component BPC1 2016-06-28T16:56:53.242Z,1467133013.242 [BPC1] No Fault, FailCount= 1 2016-06-28T16:56:53.640Z,1467133013.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.370100 seconds old. 2016-06-28T16:56:53.649Z,1467133013.649 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.380900 seconds old. 2016-06-28T16:56:53.908Z,1467133013.908 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.641800 seconds old. 2016-06-28T16:56:53.911Z,1467133013.911 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.643100 seconds old. 2016-06-28T16:56:54.268Z,1467133014.268 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.002300 seconds old. 2016-06-28T16:56:54.269Z,1467133014.269 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.001700 seconds old. 2016-06-28T16:56:54.691Z,1467133014.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.426100 seconds old. 2016-06-28T16:56:54.693Z,1467133014.693 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.425400 seconds old. 2016-06-28T16:56:55.145Z,1467133015.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.878700 seconds old. 2016-06-28T16:56:55.148Z,1467133015.148 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.880300 seconds old. 2016-06-28T16:56:55.510Z,1467133015.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.244100 seconds old. 2016-06-28T16:56:55.519Z,1467133015.519 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.250800 seconds old. 2016-06-28T16:57:19.229Z,1467133039.229 [BPC1](ERROR): Failed to parse bank B battery data 2016-06-28T16:57:19.229Z,1467133039.229 [BPC1] Data Fault, FailCount= 1 2016-06-28T16:57:19.229Z,1467133039.229 [BPC1](ERROR): Data Fault 2016-06-28T16:57:19.401Z,1467133039.401 [CBIT](ERROR): Data Fault in component: BPC1 2016-06-28T16:57:20.506Z,1467133040.506 [CBIT](INFO): Clearing failed state for component BPC1 2016-06-28T16:57:20.506Z,1467133040.506 [BPC1] No Fault, FailCount= 1 2016-06-28T16:57:43.928Z,1467133063.928 [BPC1](ERROR): Failed to receive battery data 2016-06-28T16:57:43.929Z,1467133063.929 [BPC1] Communications Fault, FailCount= 2 2016-06-28T16:57:43.929Z,1467133063.929 [BPC1](ERROR): Communications Fault 2016-06-28T16:57:44.019Z,1467133064.019 [CBIT](ERROR): Communications Fault in component: BPC1 2016-06-28T16:57:44.831Z,1467133064.831 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003500 2016-06-28T16:57:44.904Z,1467133064.904 [CBIT](INFO): Clearing failed state for component BPC1 2016-06-28T16:57:44.904Z,1467133064.904 [BPC1] No Fault, FailCount= 2 2016-06-28T16:58:00.919Z,1467133080.919 [BPC1](ERROR): Failed to receive battery data 2016-06-28T16:58:00.919Z,1467133080.919 [BPC1] Communications Fault, FailCount= 3 2016-06-28T16:58:00.919Z,1467133080.919 [BPC1](ERROR): Communications Fault 2016-06-28T16:58:00.947Z,1467133080.947 [CBIT](ERROR): Communications Fault in component: BPC1 2016-06-28T16:58:02.147Z,1467133082.147 [CBIT](INFO): Clearing failed state for component BPC1 2016-06-28T16:58:02.147Z,1467133082.147 [BPC1] No Fault, FailCount= 3 2016-06-28T16:58:13.331Z,1467133093.331 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:13.742Z,1467133093.742 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:14.156Z,1467133094.156 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:19.824Z,1467133099.824 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-06-28T16:58:19.824Z,1467133099.824 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2016-06-28T16:58:19.824Z,1467133099.824 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-06-28T16:58:19.838Z,1467133099.838 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-06-28T16:58:19.839Z,1467133099.839 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2016-06-28T16:58:19.839Z,1467133099.839 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-06-28T16:58:19.882Z,1467133099.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-06-28T16:58:19.883Z,1467133099.883 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-06-28T16:58:20.157Z,1467133100.157 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-06-28T16:58:20.157Z,1467133100.157 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2016-06-28T16:58:20.157Z,1467133100.157 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-06-28T16:58:20.157Z,1467133100.157 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2016-06-28T16:58:20.539Z,1467133100.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-06-28T16:58:20.539Z,1467133100.539 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-06-28T16:58:20.540Z,1467133100.540 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-06-28T16:58:20.540Z,1467133100.540 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-06-28T16:58:20.541Z,1467133100.541 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-06-28T16:58:20.541Z,1467133100.541 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-06-28T16:58:39.475Z,1467133119.475 [NAL9602](INFO): GPS fix at 20160628T165808: (36.803009, -121.788196) 2016-06-28T16:58:39.704Z,1467133119.704 [Default:CheckIn:Read_GPS] Stopped 2016-06-28T16:58:39.704Z,1467133119.704 [Default:CheckIn:Read_Iridium] Running Loop=1 2016-06-28T16:58:39.714Z,1467133119.714 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:39.958Z,1467133119.958 [DeadReckonUsingMultipleVelocitySources](FAULT): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-06-28T16:58:39.959Z,1467133119.959 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2016-06-28T16:58:39.959Z,1467133119.959 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-06-28T16:58:39.989Z,1467133119.989 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2016-06-28T16:58:40.119Z,1467133120.119 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-06-28T16:58:40.125Z,1467133120.125 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:40.536Z,1467133120.536 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:41.651Z,1467133121.651 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-06-28T16:58:41.664Z,1467133121.664 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2016-06-28T16:58:41.943Z,1467133121.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-06-28T16:58:41.943Z,1467133121.943 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-06-28T16:58:41.943Z,1467133121.944 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-06-28T16:58:51.690Z,1467133131.690 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:52.102Z,1467133132.102 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:52.512Z,1467133132.512 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:58:52.803Z,1467133132.803 [PNI_TCM](ERROR): CRC does not match. Expected:0x24617 got:0x62293 2016-06-28T16:58:52.803Z,1467133132.803 [PNI_TCM](ERROR): CRC does not match. Expected:0x58414 got:0x62241 2016-06-28T16:58:52.804Z,1467133132.804 [PNI_TCM](ERROR): CRC does not match. Expected:0x23671 got:0x62216 2016-06-28T16:58:52.820Z,1467133132.820 [PNI_TCM](ERROR): CRC does not match. Expected:0x40968 got:0x62304 2016-06-28T16:58:52.821Z,1467133132.821 [PNI_TCM](ERROR): CRC does not match. Expected:0x40171 got:0x62261 2016-06-28T16:58:52.823Z,1467133132.823 [PNI_TCM](ERROR): CRC does not match. Expected:0x56586 got:0x62325 2016-06-28T16:58:52.825Z,1467133132.825 [PNI_TCM](ERROR): CRC does not match. Expected:0x62258 got:0x62285 2016-06-28T16:58:52.826Z,1467133132.826 [PNI_TCM](ERROR): CRC does not match. Expected:0x60007 got:0x62155 2016-06-28T16:58:52.827Z,1467133132.827 [PNI_TCM](ERROR): CRC does not match. Expected:0x18797 got:0x62195 2016-06-28T16:58:52.829Z,1467133132.829 [PNI_TCM](ERROR): CRC does not match. Expected:0x20536 got:0x62278 2016-06-28T16:58:52.831Z,1467133132.830 [PNI_TCM](ERROR): CRC does not match. Expected:0x52333 got:0x62245 2016-06-28T16:58:52.832Z,1467133132.832 [PNI_TCM](ERROR): CRC does not match. Expected:0x54916 got:0x62274 2016-06-28T16:58:52.834Z,1467133132.834 [PNI_TCM](ERROR): CRC does not match. Expected:0x18078 got:0x62258 2016-06-28T16:58:52.835Z,1467133132.835 [PNI_TCM](ERROR): CRC does not match. Expected:0x29748 got:0x62242 2016-06-28T16:58:52.837Z,1467133132.837 [PNI_TCM](ERROR): CRC does not match. Expected:0x32698 got:0x62241 2016-06-28T16:58:52.838Z,1467133132.838 [PNI_TCM](ERROR): CRC does not match. Expected:0x23671 got:0x62205 2016-06-28T16:58:52.840Z,1467133132.840 [PNI_TCM](ERROR): CRC does not match. Expected:0x4709 got:0x62247 2016-06-28T16:58:52.841Z,1467133132.841 [PNI_TCM](ERROR): CRC does not match. Expected:0x7149 got:0x62334 2016-06-28T16:58:52.843Z,1467133132.843 [PNI_TCM](ERROR): CRC does not match. Expected:0x54584 got:0x62449 2016-06-28T16:58:52.948Z,1467133132.948 [PNI_TCM](ERROR): CRC does not match. Expected:0x9649 got:0x62358 2016-06-28T16:58:52.949Z,1467133132.949 [PNI_TCM](ERROR): CRC does not match. Expected:0x46507 got:0x62215 2016-06-28T16:58:52.951Z,1467133132.951 [PNI_TCM](ERROR): CRC does not match. Expected:0x3313 got:0x62232 2016-06-28T16:58:52.964Z,1467133132.964 [PNI_TCM](ERROR): CRC does not match. Expected:0x43910 got:0x62257 2016-06-28T16:58:52.966Z,1467133132.966 [PNI_TCM](ERROR): CRC does not match. Expected:0x22521 got:0x62266 2016-06-28T16:58:52.967Z,1467133132.967 [PNI_TCM](ERROR): CRC does not match. Expected:0x29171 got:0x62345 2016-06-28T16:58:52.981Z,1467133132.981 [PNI_TCM](ERROR): CRC does not match. Expected:0x4828 got:0x62360 2016-06-28T16:58:52.982Z,1467133132.982 [PNI_TCM](ERROR): CRC does not match. Expected:0x63478 got:0x62321 2016-06-28T16:58:52.983Z,1467133132.983 [PNI_TCM](ERROR): CRC does not match. Expected:0x31169 got:0x62366 2016-06-28T16:58:52.996Z,1467133132.996 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2016-06-28T16:58:52.996Z,1467133132.996 [PNI_TCM] Data Fault, FailCount= 1 2016-06-28T16:58:52.997Z,1467133132.997 [PNI_TCM](ERROR): Data Fault 2016-06-28T16:58:53.017Z,1467133133.017 [BPC1](ERROR): Failed to receive battery data 2016-06-28T16:58:53.017Z,1467133133.017 [BPC1] Communications Fault, FailCount= 1 2016-06-28T16:58:53.017Z,1467133133.017 [BPC1](ERROR): Communications Fault 2016-06-28T16:58:53.109Z,1467133133.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.157900 seconds old. 2016-06-28T16:58:53.130Z,1467133133.130 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.168800 seconds old. 2016-06-28T16:58:53.377Z,1467133133.376 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-06-28T16:58:53.378Z,1467133133.378 [CBIT](ERROR): Communications Fault in component: BPC1 2016-06-28T16:58:53.505Z,1467133133.505 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20160628T164157/Courier0008.lzma 2016-06-28T16:58:53.544Z,1467133133.544 [NAL9602](INFO): Powering down 2016-06-28T16:58:53.633Z,1467133133.633 [PNI_TCM](INFO): Powering down 2016-06-28T16:58:53.729Z,1467133133.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.778100 seconds old. 2016-06-28T16:58:53.746Z,1467133133.746 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.784800 seconds old. 2016-06-28T16:58:54.004Z,1467133134.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.053300 seconds old. 2016-06-28T16:58:54.028Z,1467133134.028 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.067900 seconds old. 2016-06-28T16:58:54.341Z,1467133134.341 [DataOverHttps](INFO): Moved sent file to Logs/20160628T164157/Courier0008.lzma.bak 2016-06-28T16:58:54.342Z,1467133134.342 [DataOverHttps](INFO): SBD MOMSN=4318693 2016-06-28T16:58:54.381Z,1467133134.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.430700 seconds old. 2016-06-28T16:58:54.383Z,1467133134.383 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.422700 seconds old. 2016-06-28T16:58:54.440Z,1467133134.440 [CBIT](INFO): Clearing failed state for component BPC1 2016-06-28T16:58:54.440Z,1467133134.440 [BPC1] No Fault, FailCount= 1 2016-06-28T16:58:55.237Z,1467133135.237 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.286200 seconds old. 2016-06-28T16:58:55.241Z,1467133135.241 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.279800 seconds old. 2016-06-28T16:58:55.382Z,1467133135.382 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-06-28T16:58:55.382Z,1467133135.382 [PNI_TCM] No Fault, FailCount= 1 2016-06-28T16:58:55.575Z,1467133135.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.624300 seconds old. 2016-06-28T16:58:55.590Z,1467133135.589 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.628700 seconds old. 2016-06-28T16:59:02.064Z,1467133142.064 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 22.113600 seconds old. 2016-06-28T16:59:02.065Z,1467133142.065 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 20.105000 seconds old. 2016-06-28T16:59:02.104Z,1467133142.104 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:02.164Z,1467133142.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 22.213900 seconds old. 2016-06-28T16:59:02.166Z,1467133142.166 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 20.206200 seconds old. 2016-06-28T16:59:02.564Z,1467133142.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 22.613500 seconds old. 2016-06-28T16:59:02.565Z,1467133142.565 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 20.604900 seconds old. 2016-06-28T16:59:02.900Z,1467133142.900 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:02.990Z,1467133142.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 23.040200 seconds old. 2016-06-28T16:59:02.992Z,1467133142.992 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.031900 seconds old. 2016-06-28T16:59:03.421Z,1467133143.421 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 23.470500 seconds old. 2016-06-28T16:59:03.425Z,1467133143.425 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.464300 seconds old. 2016-06-28T16:59:03.808Z,1467133143.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 23.856800 seconds old. 2016-06-28T16:59:03.813Z,1467133143.813 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.852200 seconds old. 2016-06-28T16:59:10.724Z,1467133150.724 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20160628T165200/Courier0004.lzma 2016-06-28T16:59:11.542Z,1467133151.542 [DataOverHttps](INFO): Moved sent file to Logs/20160628T165200/Courier0004.lzma.bak 2016-06-28T16:59:11.543Z,1467133151.543 [DataOverHttps](INFO): SBD MOMSN=4318695 2016-06-28T16:59:25.690Z,1467133165.690 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:26.484Z,1467133166.484 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:26.594Z,1467133166.594 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20160628T164157/Express0009.lzma 2016-06-28T16:59:27.281Z,1467133167.281 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:27.406Z,1467133167.406 [DataOverHttps](INFO): Moved sent file to Logs/20160628T164157/Express0009.lzma.bak 2016-06-28T16:59:27.406Z,1467133167.406 [DataOverHttps](INFO): SBD MOMSN=4318699 2016-06-28T16:59:27.695Z,1467133167.694 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:28.106Z,1467133168.106 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:28.902Z,1467133168.902 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:29.313Z,1467133169.313 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:29.746Z,1467133169.746 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2016-06-28T16:59:35.940Z,1467133175.940 [BPC1](ERROR): Failed to receive battery data 2016-06-28T16:59:35.940Z,1467133175.940 [BPC1] Communications Fault, FailCount= 1 2016-06-28T16:59:35.940Z,1467133175.940 [BPC1](ERROR): Communications Fault 2016-06-28T16:59:36.033Z,1467133176.033 [CBIT](ERROR): Communications Fault in component: BPC1 2016-06-28T16:59:37.275Z,1467133177.275 [CBIT](INFO): Clearing failed state for component BPC1 2016-06-28T16:59:37.275Z,1467133177.275 [BPC1] No Fault, FailCount= 1 2016-06-28T16:59:43.927Z,1467133183.927 [DataOverHttps](INFO): Sending 825 bytes from file Logs/20160628T165200/Express0001.lzma 2016-06-28T16:59:44.725Z,1467133184.725 [DataOverHttps](INFO): Moved sent file to Logs/20160628T165200/Express0001.lzma.bak 2016-06-28T16:59:44.725Z,1467133184.725 [DataOverHttps](INFO): SBD MOMSN=4318701 2016-06-28T16:59:58.502Z,1467133198.502 [DataOverHttps](INFO): Sending 459 bytes from file Logs/20160628T165200/Express0005.lzma 2016-06-28T16:59:59.314Z,1467133199.314 [DataOverHttps](INFO): Moved sent file to Logs/20160628T165200/Express0005.lzma.bak 2016-06-28T16:59:59.315Z,1467133199.315 [DataOverHttps](INFO): SBD MOMSN=4318724 2016-06-28T16:59:59.998Z,1467133199.998 [Default:CheckIn:Read_Iridium] Stopped 2016-06-28T16:59:59.998Z,1467133199.998 [Default:CheckIn:C.Wait] Running Loop=1 2016-06-28T16:59:59.998Z,1467133199.998 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2016-06-28T17:01:42.243Z,1467133302.243 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-06-28T17:01:42.243Z,1467133302.243 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2016-06-28T17:01:42.248Z,1467133302.247 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-06-28T17:01:42.304Z,1467133302.304 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-06-28T17:01:42.877Z,1467133302.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-06-28T17:01:42.878Z,1467133302.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2016-06-28T17:01:43.166Z,1467133303.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-06-28T17:01:43.168Z,1467133303.168 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-06-28T17:01:43.169Z,1467133303.168 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-06-28T17:02:07.345Z,1467133327.345 [DataOverHttps](IMPORTANT): SBD MTMSN=20160628T170136 2016-06-28T17:02:13.967Z,1467133333.967 [DataOverHttps](INFO): Received command:restart app 2016-06-28T17:02:14.064Z,1467133334.064 [CommandLine](IMPORTANT): got command restart application 2016-06-28T17:02:15.079Z,1467133335.079 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2016-06-28T17:02:15.080Z,1467133335.080 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.091Z,1467133335.092 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2016-06-28T17:02:15.092Z,1467133335.092 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.094Z,1467133335.094 [CommandLine](INFO): Join timeout helper Thread ID is 916 2016-06-28T17:02:15.100Z,1467133335.100 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2016-06-28T17:02:15.100Z,1467133335.100 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.102Z,1467133335.102 [NavChartDb](INFO): Join timeout helper Thread ID is 917 2016-06-28T17:02:15.232Z,1467133335.232 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2016-06-28T17:02:15.233Z,1467133335.233 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.240Z,1467133335.240 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2016-06-28T17:02:15.240Z,1467133335.240 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.241Z,1467133335.241 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 918 2016-06-28T17:02:15.432Z,1467133335.432 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2016-06-28T17:02:15.432Z,1467133335.432 [Rowe_600LCM](INFO): Powering down 2016-06-28T17:02:15.435Z,1467133335.434 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.436Z,1467133335.436 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2016-06-28T17:02:15.436Z,1467133335.436 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.437Z,1467133335.437 [Radio_Surface](INFO): Join timeout helper Thread ID is 919 2016-06-28T17:02:15.520Z,1467133335.520 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2016-06-28T17:02:15.520Z,1467133335.520 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.535Z,1467133335.535 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2016-06-28T17:02:15.536Z,1467133335.536 [logger ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.536Z,1467133335.536 [logger](INFO): Join timeout helper Thread ID is 920 2016-06-28T17:02:15.580Z,1467133335.579 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2016-06-28T17:02:15.580Z,1467133335.580 [logger ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.596Z,1467133335.596 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2016-06-28T17:02:15.597Z,1467133335.597 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.597Z,1467133335.597 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2016-06-28T17:02:15.597Z,1467133335.597 [controlThread ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.597Z,1467133335.597 [controlThread](INFO): Join timeout helper Thread ID is 921 2016-06-28T17:02:15.611Z,1467133335.611 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2016-06-28T17:02:15.611Z,1467133335.611 [controlThread](DEBUG): Uninitializing ControlThread 2016-06-28T17:02:15.612Z,1467133335.612 [NAL9602](INFO): Powering down 2016-06-28T17:02:15.613Z,1467133335.613 [PNI_TCM](INFO): Powering down 2016-06-28T17:02:15.614Z,1467133335.614 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-06-28T17:02:15.616Z,1467133335.616 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2016-06-28T17:02:15.616Z,1467133335.616 [Default] Stopped 2016-06-28T17:02:15.617Z,1467133335.617 [Default](DEBUG): Aggregate::uninitialize Default 2016-06-28T17:02:15.617Z,1467133335.617 [Default:B.GoToSurface] Stopped 2016-06-28T17:02:15.617Z,1467133335.617 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2016-06-28T17:02:15.617Z,1467133335.617 [Default:CheckIn] Stopped 2016-06-28T17:02:15.617Z,1467133335.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2016-06-28T17:02:15.617Z,1467133335.617 [Default:CheckIn:C.Wait] Stopped 2016-06-28T17:02:15.617Z,1467133335.617 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2016-06-28T17:02:15.620Z,1467133335.620 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2016-06-28T17:02:15.620Z,1467133335.620 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2016-06-28T17:02:15.620Z,1467133335.620 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2016-06-28T17:02:15.621Z,1467133335.621 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2016-06-28T17:02:15.621Z,1467133335.621 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2016-06-28T17:02:15.621Z,1467133335.621 [BuoyancyServo](INFO): Powering down 2016-06-28T17:02:15.636Z,1467133335.636 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2016-06-28T17:02:15.636Z,1467133335.636 [ElevatorServo](INFO): Powering down 2016-06-28T17:02:15.637Z,1467133335.636 [MassServo](DEBUG): Uninitialize Mass Servo. 2016-06-28T17:02:15.637Z,1467133335.637 [MassServo](INFO): Powering down 2016-06-28T17:02:15.637Z,1467133335.637 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2016-06-28T17:02:15.637Z,1467133335.638 [RudderServo](INFO): Powering down 2016-06-28T17:02:15.638Z,1467133335.638 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2016-06-28T17:02:15.638Z,1467133335.638 [ThrusterServo](INFO): Powering down 2016-06-28T17:02:15.639Z,1467133335.639 [SBIT](DEBUG): Uninitialize SBIT Component. 2016-06-28T17:02:15.640Z,1467133335.640 [IBIT](DEBUG): Uninitialize IBIT Component. 2016-06-28T17:02:15.640Z,1467133335.640 [CBIT](DEBUG): Uninitialize CBIT Component. 2016-06-28T17:02:15.641Z,1467133335.641 [controlThread ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.746Z,1467133335.746 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.774Z,1467133335.774 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.829Z,1467133335.829 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-06-28T17:02:15.906Z,1467133335.906 [logger ThreadHandler](INFO): Thread cancelled.