2017-10-23T23:14:17.121Z,1508800457.121 [Supervisor](DEBUG): Initializing supervisor. 2017-10-23T23:14:17.123Z,1508800457.123 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-10-23T23:14:17.124Z,1508800457.124 [SyncHandler](INFO): Protected caller Thread ID is 5067 2017-10-23T23:14:17.124Z,1508800457.124 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-10-23T23:14:17.125Z,1508800457.125 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-10-23T23:14:17.126Z,1508800457.126 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5068 2017-10-23T23:14:17.128Z,1508800457.128 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-10-23T23:14:17.140Z,1508800457.140 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-10-23T23:14:17.141Z,1508800457.141 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-10-23T23:14:17.142Z,1508800457.142 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5069 2017-10-23T23:14:17.143Z,1508800457.143 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-10-23T23:14:17.144Z,1508800457.144 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-10-23T23:14:17.144Z,1508800457.144 [logger ThreadHandler](INFO): Protected caller Thread ID is 5070 2017-10-23T23:14:17.146Z,1508800457.146 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-10-23T23:14:17.147Z,1508800457.147 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-10-23T23:14:17.654Z,1508800457.654 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-10-23T23:14:17.955Z,1508800457.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-10-23T23:14:17.956Z,1508800457.956 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-10-23T23:14:18.086Z,1508800458.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-10-23T23:14:18.087Z,1508800458.087 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-10-23T23:14:18.411Z,1508800458.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-10-23T23:14:18.412Z,1508800458.412 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-10-23T23:14:18.550Z,1508800458.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-10-23T23:14:18.551Z,1508800458.551 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-10-23T23:14:18.635Z,1508800458.635 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-10-23T23:14:18.829Z,1508800458.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-10-23T23:14:18.830Z,1508800458.830 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-10-23T23:14:18.931Z,1508800458.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-10-23T23:14:18.931Z,1508800458.931 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-10-23T23:14:19.214Z,1508800459.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-10-23T23:14:19.215Z,1508800459.215 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-10-23T23:14:19.574Z,1508800459.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-10-23T23:14:19.575Z,1508800459.575 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-10-23T23:14:19.873Z,1508800459.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-10-23T23:14:19.874Z,1508800459.874 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-10-23T23:14:20.369Z,1508800460.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-10-23T23:14:20.371Z,1508800460.371 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-10-23T23:14:20.563Z,1508800460.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-10-23T23:14:20.564Z,1508800460.564 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-10-23T23:14:20.665Z,1508800460.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-10-23T23:14:20.666Z,1508800460.666 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-10-23T23:14:21.126Z,1508800461.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-10-23T23:14:21.126Z,1508800461.126 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-10-23T23:14:21.237Z,1508800461.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-10-23T23:14:21.239Z,1508800461.239 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2017-10-23T23:14:21.241Z,1508800461.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2017-10-23T23:14:21.481Z,1508800461.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-10-23T23:14:21.483Z,1508800461.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2017-10-23T23:14:21.586Z,1508800461.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2017-10-23T23:14:21.680Z,1508800461.680 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2017-10-23T23:14:21.785Z,1508800461.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2017-10-23T23:14:21.874Z,1508800461.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2017-10-23T23:14:22.004Z,1508800462.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2017-10-23T23:14:22.198Z,1508800462.198 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2017-10-23T23:14:23.908Z,1508800463.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2017-10-23T23:14:26.259Z,1508800466.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2017-10-23T23:14:26.348Z,1508800466.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2017-10-23T23:14:26.525Z,1508800466.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2017-10-23T23:14:26.606Z,1508800466.606 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2017-10-23T23:14:26.618Z,1508800466.618 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-10-23T23:14:26.746Z,1508800466.746 [SBIT](DEBUG): Construct Startup Built In Test. 2017-10-23T23:14:26.778Z,1508800466.778 [SBIT] Loaded 2017-10-23T23:14:26.778Z,1508800466.778 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-10-23T23:14:26.779Z,1508800466.779 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-10-23T23:14:26.808Z,1508800466.808 [IBIT] Loaded 2017-10-23T23:14:26.808Z,1508800466.808 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-10-23T23:14:26.812Z,1508800466.812 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-10-23T23:14:26.970Z,1508800466.970 [CBIT] Loaded 2017-10-23T23:14:26.971Z,1508800466.971 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-10-23T23:14:26.971Z,1508800466.971 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-10-23T23:14:26.972Z,1508800466.972 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-10-23T23:14:27.039Z,1508800467.039 [VerticalControl](DEBUG): Construct VerticalControl. 2017-10-23T23:14:27.141Z,1508800467.141 [VerticalControl] Loaded 2017-10-23T23:14:27.142Z,1508800467.142 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-10-23T23:14:27.142Z,1508800467.142 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-10-23T23:14:27.207Z,1508800467.207 [HorizontalControl] Loaded 2017-10-23T23:14:27.207Z,1508800467.207 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-10-23T23:14:27.208Z,1508800467.208 [SpeedControl](DEBUG): Construct SpeedControl. 2017-10-23T23:14:27.214Z,1508800467.214 [SpeedControl] Loaded 2017-10-23T23:14:27.214Z,1508800467.214 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-10-23T23:14:27.215Z,1508800467.215 [LoopControl](DEBUG): Construct LoopControl. 2017-10-23T23:14:27.215Z,1508800467.215 [LoopControl] Loaded 2017-10-23T23:14:27.215Z,1508800467.215 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-10-23T23:14:27.216Z,1508800467.216 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-10-23T23:14:27.216Z,1508800467.216 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-10-23T23:14:27.280Z,1508800467.280 [DepthRateCalculator] Loaded 2017-10-23T23:14:27.280Z,1508800467.280 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-10-23T23:14:27.294Z,1508800467.294 [PitchRateCalculator] Loaded 2017-10-23T23:14:27.294Z,1508800467.294 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-10-23T23:14:27.325Z,1508800467.325 [SpeedCalculator] Loaded 2017-10-23T23:14:27.326Z,1508800467.326 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-10-23T23:14:27.370Z,1508800467.370 [TempGradientCalculator] Loaded 2017-10-23T23:14:27.370Z,1508800467.370 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-10-23T23:14:27.387Z,1508800467.387 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-10-23T23:14:27.387Z,1508800467.387 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-10-23T23:14:27.392Z,1508800467.392 [YawRateCalculator] Loaded 2017-10-23T23:14:27.392Z,1508800467.392 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-10-23T23:14:27.393Z,1508800467.393 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-10-23T23:14:27.393Z,1508800467.393 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-10-23T23:14:27.475Z,1508800467.475 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-10-23T23:14:27.475Z,1508800467.475 [StratificationFrontDetector](DEBUG): (re)initializing 2017-10-23T23:14:27.475Z,1508800467.475 [StratificationFrontDetector] Loaded 2017-10-23T23:14:27.475Z,1508800467.475 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-10-23T23:14:27.476Z,1508800467.476 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-10-23T23:14:27.477Z,1508800467.477 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-10-23T23:14:27.712Z,1508800467.712 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-10-23T23:14:27.713Z,1508800467.713 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-10-23T23:14:27.805Z,1508800467.805 [DeadReckonUsingSpeedCalculator] Loaded 2017-10-23T23:14:27.806Z,1508800467.806 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-10-23T23:14:27.863Z,1508800467.863 [DeadReckonWithRespectToSeafloor] Loaded 2017-10-23T23:14:27.863Z,1508800467.863 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-10-23T23:14:27.881Z,1508800467.881 [NavChart] Loaded 2017-10-23T23:14:27.881Z,1508800467.881 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-10-23T23:14:27.886Z,1508800467.886 [UniversalFixResidualReporter] Loaded 2017-10-23T23:14:27.886Z,1508800467.886 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-10-23T23:14:27.886Z,1508800467.886 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-10-23T23:14:27.887Z,1508800467.887 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-10-23T23:14:27.900Z,1508800467.900 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-10-23T23:14:27.900Z,1508800467.901 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-10-23T23:14:28.047Z,1508800468.047 [Aanderaa_O2] Loaded 2017-10-23T23:14:28.047Z,1508800468.047 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-10-23T23:14:28.060Z,1508800468.060 [CANONSampler] Loaded 2017-10-23T23:14:28.060Z,1508800468.060 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2017-10-23T23:14:28.143Z,1508800468.143 [CTD_NeilBrown] Loaded 2017-10-23T23:14:28.143Z,1508800468.143 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-10-23T23:14:28.144Z,1508800468.144 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407864E0 2017-10-23T23:14:28.145Z,1508800468.145 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5153 2017-10-23T23:14:28.180Z,1508800468.180 [CTD_Seabird] Loaded 2017-10-23T23:14:28.180Z,1508800468.180 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-10-23T23:14:28.181Z,1508800468.181 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407B64E0 2017-10-23T23:14:28.181Z,1508800468.181 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5154 2017-10-23T23:14:28.197Z,1508800468.197 [PAR_Licor] Loaded 2017-10-23T23:14:28.197Z,1508800468.197 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-10-23T23:14:28.249Z,1508800468.249 [WetLabsBB2FL] Loaded 2017-10-23T23:14:28.249Z,1508800468.249 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-10-23T23:14:28.250Z,1508800468.250 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407E64E0 2017-10-23T23:14:28.251Z,1508800468.251 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5155 2017-10-23T23:14:28.251Z,1508800468.251 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-10-23T23:14:28.252Z,1508800468.252 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-10-23T23:14:28.516Z,1508800468.516 [DataOverHttps] Loaded 2017-10-23T23:14:28.517Z,1508800468.517 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-10-23T23:14:28.530Z,1508800468.530 [Depth_Keller] Loaded 2017-10-23T23:14:28.531Z,1508800468.531 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-10-23T23:14:28.626Z,1508800468.626 [NAL9602] Loaded 2017-10-23T23:14:28.626Z,1508800468.626 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-10-23T23:14:28.636Z,1508800468.636 [Onboard] Loaded 2017-10-23T23:14:28.636Z,1508800468.636 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-10-23T23:14:28.643Z,1508800468.643 [Radio_Surface] Loaded 2017-10-23T23:14:28.644Z,1508800468.644 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-10-23T23:14:28.645Z,1508800468.645 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0 2017-10-23T23:14:28.645Z,1508800468.645 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5156 2017-10-23T23:14:28.695Z,1508800468.695 [PNI_TCM] Loaded 2017-10-23T23:14:28.696Z,1508800468.696 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-10-23T23:14:30.570Z,1508800470.570 [BPC1] Loaded 2017-10-23T23:14:30.571Z,1508800470.571 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-10-23T23:14:30.571Z,1508800470.571 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-10-23T23:14:30.572Z,1508800470.572 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-10-23T23:14:30.669Z,1508800470.669 [BuoyancyServo] Loaded 2017-10-23T23:14:30.669Z,1508800470.669 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-10-23T23:14:30.682Z,1508800470.682 [ElevatorServo] Loaded 2017-10-23T23:14:30.682Z,1508800470.682 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-10-23T23:14:30.694Z,1508800470.694 [MassServo] Loaded 2017-10-23T23:14:30.694Z,1508800470.694 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-10-23T23:14:30.706Z,1508800470.706 [RudderServo] Loaded 2017-10-23T23:14:30.707Z,1508800470.707 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-10-23T23:14:30.718Z,1508800470.718 [ThrusterServo] Loaded 2017-10-23T23:14:30.719Z,1508800470.719 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-10-23T23:14:30.719Z,1508800470.719 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-10-23T23:14:30.720Z,1508800470.720 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-10-23T23:14:30.820Z,1508800470.820 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-10-23T23:14:30.821Z,1508800470.821 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-10-23T23:14:30.845Z,1508800470.845 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-10-23T23:14:30.849Z,1508800470.849 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-10-23T23:14:30.850Z,1508800470.850 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-10-23T23:14:30.857Z,1508800470.857 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-10-23T23:14:30.858Z,1508800470.858 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AE24E0 2017-10-23T23:14:30.859Z,1508800470.859 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5157 2017-10-23T23:14:30.863Z,1508800470.863 [Supervisor](INFO): Main Thread ID is 5066 2017-10-23T23:14:30.863Z,1508800470.863 [Supervisor](DEBUG): Running supervisor. 2017-10-23T23:14:30.864Z,1508800470.864 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5158 2017-10-23T23:14:30.867Z,1508800470.867 [controlThread ThreadHandler](INFO): Handler Thread ID is 5159 2017-10-23T23:14:30.868Z,1508800470.868 [controlThread](DEBUG): Initializing ControlThread 2017-10-23T23:14:30.869Z,1508800470.869 [SBIT](INFO): Initialize SBIT Component. 2017-10-23T23:14:30.869Z,1508800470.869 [SBIT](IMPORTANT): git: 2017-10-16 2017-10-23T23:14:30.869Z,1508800470.869 [SBIT](INFO): git hash: 5e637d88ea6653172d5095372d78c21c0064ca6c 2017-10-23T23:14:30.871Z,1508800470.871 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-10-23T23:14:30.872Z,1508800470.872 [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-10-23T23:14:30.873Z,1508800470.872 [SBIT](INFO): Beginning SBIT in 160.000000 seconds. 2017-10-23T23:14:30.873Z,1508800470.873 [IBIT](INFO): Initialize IBIT Component. 2017-10-23T23:14:30.874Z,1508800470.874 [CBIT](DEBUG): Initialize CBIT Component. 2017-10-23T23:14:30.874Z,1508800470.874 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-10-23T23:14:30.875Z,1508800470.875 [logger ThreadHandler](INFO): Handler Thread ID is 5160 2017-10-23T23:14:30.892Z,1508800470.892 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5161 2017-10-23T23:14:30.893Z,1508800470.893 [CTD_NeilBrown](INFO): Powering down 2017-10-23T23:14:30.923Z,1508800470.923 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5162 2017-10-23T23:14:30.923Z,1508800470.923 [CTD_Seabird](INFO): Initializing 2017-10-23T23:14:30.956Z,1508800470.956 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-10-23T23:14:30.958Z,1508800470.958 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-10-23T23:14:30.959Z,1508800470.959 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-10-23T23:14:30.960Z,1508800470.960 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-10-23T23:14:30.960Z,1508800470.960 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-10-23T23:14:30.961Z,1508800470.961 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-10-23T23:14:30.961Z,1508800470.961 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-10-23T23:14:30.961Z,1508800470.961 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-10-23T23:14:30.962Z,1508800470.962 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-10-23T23:14:30.963Z,1508800470.963 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-10-23T23:14:30.964Z,1508800470.964 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-10-23T23:14:30.965Z,1508800470.965 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:14:30.965Z,1508800470.965 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:14:30.966Z,1508800470.966 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-10-23T23:14:30.966Z,1508800470.966 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:14:30.967Z,1508800470.967 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:14:30.967Z,1508800470.967 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-10-23T23:14:30.967Z,1508800470.967 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-10-23T23:14:30.979Z,1508800470.979 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-10-23T23:14:30.985Z,1508800470.985 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5164 2017-10-23T23:14:30.987Z,1508800470.987 [WetLabsBB2FL](INFO): Powering down 2017-10-23T23:14:31.025Z,1508800471.025 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5165 2017-10-23T23:14:31.031Z,1508800471.031 [Radio_Surface](INFO): Powering up 2017-10-23T23:14:31.048Z,1508800471.048 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5166 2017-10-23T23:14:31.050Z,1508800471.050 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-10-23T23:14:31.054Z,1508800471.054 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-10-23T23:14:31.054Z,1508800471.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-10-23T23:14:31.055Z,1508800471.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-10-23T23:14:31.055Z,1508800471.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-10-23T23:14:31.055Z,1508800471.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-10-23T23:14:31.055Z,1508800471.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-10-23T23:14:31.056Z,1508800471.056 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-10-23T23:14:31.056Z,1508800471.056 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-10-23T23:14:31.056Z,1508800471.056 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-10-23T23:14:31.056Z,1508800471.056 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-10-23T23:14:31.057Z,1508800471.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-10-23T23:14:31.057Z,1508800471.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-10-23T23:14:31.057Z,1508800471.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-10-23T23:14:31.057Z,1508800471.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-10-23T23:14:31.057Z,1508800471.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-10-23T23:14:31.058Z,1508800471.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-10-23T23:14:31.130Z,1508800471.130 [MissionManager](DEBUG): 2017-10-23T23:14:31.131Z,1508800471.131 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-10-23T23:14:31.212Z,1508800471.212 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-10-23T23:14:31.237Z,1508800471.237 [Default:A.Wait](DEBUG): Construct Wait. 2017-10-23T23:14:31.239Z,1508800471.239 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-10-23T23:14:31.270Z,1508800471.270 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-10-23T23:14:31.286Z,1508800471.286 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-10-23T23:14:31.286Z,1508800471.286 [CTD_Seabird](INFO): Powering down 2017-10-23T23:14:31.312Z,1508800471.312 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-10-23T23:14:31.318Z,1508800471.318 [Default:E.Execute](DEBUG): Construct Execute. 2017-10-23T23:14:31.338Z,1508800471.338 [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 2017-10-23T23:14:31.343Z,1508800471.343 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-10-23T23:14:31.391Z,1508800471.391 [CANONSampler](INFO): Powering down 2017-10-23T23:14:31.616Z,1508800471.616 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-10-23T23:14:31.662Z,1508800471.662 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2017-10-23T23:14:31.890Z,1508800471.890 [DepthRateCalculator](ERROR): Depth measurement is not active 2017-10-23T23:14:31.983Z,1508800471.983 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:31.999Z,1508800471.999 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:32.126Z,1508800472.126 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-10-23T23:14:32.135Z,1508800472.135 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-10-23T23:14:32.157Z,1508800472.157 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-10-23T23:14:32.166Z,1508800472.166 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-10-23T23:14:32.188Z,1508800472.188 [MassServo](DEBUG): Initializing EZServoServo. 2017-10-23T23:14:32.194Z,1508800472.194 [MassServo](DEBUG): Initializing MassServo. 2017-10-23T23:14:32.226Z,1508800472.226 [RudderServo](DEBUG): Initializing EZServoServo. 2017-10-23T23:14:32.235Z,1508800472.235 [RudderServo](DEBUG): Initializing RudderServo. 2017-10-23T23:14:32.241Z,1508800472.241 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-10-23T23:14:32.254Z,1508800472.254 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-10-23T23:14:32.659Z,1508800472.659 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:32.661Z,1508800472.661 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:33.003Z,1508800473.003 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-10-23T23:14:33.003Z,1508800473.003 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-10-23T23:14:33.003Z,1508800473.003 [BuoyancyServo] Communications Fault, FailCount= 1 2017-10-23T23:14:33.003Z,1508800473.003 [BuoyancyServo](ERROR): Communications Fault 2017-10-23T23:14:33.211Z,1508800473.211 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-10-23T23:14:42.319Z,1508800482.319 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:42.320Z,1508800482.320 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:42.342Z,1508800482.342 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-10-23T23:14:42.343Z,1508800482.343 [BuoyancyServo](INFO): Powering down 2017-10-23T23:14:42.553Z,1508800482.553 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:42.554Z,1508800482.554 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:42.890Z,1508800482.890 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:42.907Z,1508800482.907 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:43.355Z,1508800483.355 [Aanderaa_O2](INFO): Powering down 2017-10-23T23:14:43.441Z,1508800483.441 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003907 2017-10-23T23:14:43.520Z,1508800483.520 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:43.521Z,1508800483.521 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:43.731Z,1508800483.731 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:43.742Z,1508800483.742 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-10-23T23:14:44.634Z,1508800484.634 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-10-23T23:14:44.634Z,1508800484.634 [BuoyancyServo] No Fault, FailCount= 1 2017-10-23T23:14:44.988Z,1508800484.988 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-10-23T23:14:45.107Z,1508800485.107 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-10-23T23:14:58.877Z,1508800498.877 [NAL9602](INFO): Powering up NAL9602 2017-10-23T23:15:01.545Z,1508800501.545 [CommandLine](IMPORTANT): got command failComponent 2017-10-23T23:15:01.545Z,1508800501.545 [CommandLine](IMPORTANT): Failed components: 2017-10-23T23:15:01.545Z,1508800501.545 [CommandLine](IMPORTANT): No failed Components. 2017-10-23T23:15:10.075Z,1508800510.075 [NAL9602](INFO): NAL9602 initialized 2017-10-23T23:15:24.449Z,1508800524.449 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute 2017-10-23T23:15:24.451Z,1508800524.451 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2017-10-23T23:15:24.599Z,1508800524.599 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-10-23T23:15:36.576Z,1508800536.576 [CommandLine](IMPORTANT): got command failComponent 2017-10-23T23:15:36.576Z,1508800536.576 [CommandLine](IMPORTANT): Failed components: 2017-10-23T23:15:36.576Z,1508800536.576 [CommandLine](IMPORTANT): No failed Components. 2017-10-23T23:16:53.173Z,1508800613.173 [BPC1](ERROR): Failed to parse bank B battery data 2017-10-23T23:16:53.173Z,1508800613.173 [BPC1] Data Fault, FailCount= 1 2017-10-23T23:16:53.173Z,1508800613.173 [BPC1](ERROR): Data Fault 2017-10-23T23:16:53.225Z,1508800613.225 [CBIT](ERROR): Data Fault in component: BPC1 2017-10-23T23:16:54.430Z,1508800614.430 [CBIT](INFO): Clearing failed state for component BPC1 2017-10-23T23:16:54.430Z,1508800614.430 [BPC1] No Fault, FailCount= 1 2017-10-23T23:17:10.518Z,1508800630.518 [CommandLine](IMPORTANT): got command maintain clear 2017-10-23T23:17:10.850Z,1508800630.850 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-10-23T23:17:11.732Z,1508800631.732 [SBIT](IMPORTANT): Beginning Startup BIT 2017-10-23T23:17:11.736Z,1508800631.736 [CBIT](IMPORTANT): Beginning ground fault scan 2017-10-23T23:17:22.121Z,1508800642.121 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 4.657929 CHAN A1 (24V): 0.027965 CHAN A2 (12V): -0.002303 CHAN A3 (5V): 4.753929 CHAN B0 (3.3V): 0.001732 CHAN B1 (3.15aV): 4.767929 CHAN B2 (3.15bV): 0.002338 CHAN B3 (GND): 0.000102 OPEN: -0.000274 Full Scale Calc: 4.765 mA, -1.589 mA 2017-10-23T23:17:31.110Z,1508800651.110 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-10-23T23:17:31.110Z,1508800651.110 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2017-10-23T23:17:31.110Z,1508800651.110 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-10-23T23:17:31.112Z,1508800651.112 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-10-23T23:17:31.112Z,1508800651.112 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2017-10-23T23:17:31.112Z,1508800651.112 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2017-10-23T23:17:31.225Z,1508800651.225 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-10-23T23:17:31.226Z,1508800651.226 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2017-10-23T23:17:31.618Z,1508800651.618 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-10-23T23:17:31.618Z,1508800651.618 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2017-10-23T23:17:31.619Z,1508800651.619 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2017-10-23T23:17:31.619Z,1508800651.619 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2017-10-23T23:17:31.917Z,1508800651.917 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-10-23T23:17:31.917Z,1508800651.917 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:17:31.918Z,1508800651.918 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:17:31.918Z,1508800651.918 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-10-23T23:17:31.919Z,1508800651.919 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:17:31.919Z,1508800651.919 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:18:05.548Z,1508800685.548 [SBIT](IMPORTANT): SBIT PASSED 2017-10-23T23:18:05.976Z,1508800685.976 [MissionManager](IMPORTANT): Started mission Startup 2017-10-23T23:18:05.976Z,1508800685.976 [Startup] Running Loop=1 2017-10-23T23:18:05.976Z,1508800685.976 [Startup](DEBUG): Aggregate::initialize Startup 2017-10-23T23:18:05.976Z,1508800685.976 [Startup:A.GoToSurface] Running Loop=1 2017-10-23T23:18:05.976Z,1508800685.976 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-10-23T23:18:05.977Z,1508800685.977 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-10-23T23:18:05.977Z,1508800685.977 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-10-23T23:18:05.978Z,1508800685.978 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-10-23T23:18:05.978Z,1508800685.978 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-10-23T23:18:05.979Z,1508800685.979 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-10-23T23:18:05.985Z,1508800685.985 [Startup:StartupSatComms] Running Loop=1 2017-10-23T23:18:05.985Z,1508800685.985 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2017-10-23T23:18:05.985Z,1508800685.985 [Startup:StartupSatComms:A] Running Loop=1 2017-10-23T23:18:06.340Z,1508800686.340 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2017-10-23T23:18:22.351Z,1508800702.351 [BPC1](ERROR): Failed to receive battery data 2017-10-23T23:18:22.351Z,1508800702.351 [BPC1] Communications Fault, FailCount= 1 2017-10-23T23:18:22.351Z,1508800702.351 [BPC1](ERROR): Communications Fault 2017-10-23T23:18:22.396Z,1508800702.396 [CBIT](ERROR): Communications Fault in component: BPC1 2017-10-23T23:18:23.562Z,1508800703.562 [CBIT](INFO): Clearing failed state for component BPC1 2017-10-23T23:18:23.562Z,1508800703.562 [BPC1] No Fault, FailCount= 1 2017-10-23T23:19:06.175Z,1508800746.175 [Startup:StartupSatComms:A](INFO): Timed out from 2017-10-23T23:18:05.0Z 2017-10-23T23:19:06.175Z,1508800746.175 [Startup:StartupSatComms:A] Stopped 2017-10-23T23:19:06.175Z,1508800746.175 [Startup:StartupSatComms:B] Running Loop=1 2017-10-23T23:19:06.563Z,1508800746.563 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2017-10-23T23:19:10.777Z,1508800750.777 [DataOverHttps](INFO): Sending 46 bytes from file Logs/20171023T171754/Courier0128.lzma 2017-10-23T23:19:11.518Z,1508800751.518 [DataOverHttps](INFO): Moved sent file to Logs/20171023T171754/Courier0128.lzma.bak 2017-10-23T23:19:11.518Z,1508800751.518 [DataOverHttps](INFO): SBD MOMSN=5150268 2017-10-23T23:19:20.916Z,1508800760.916 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20171023T171754/Express0129.lzma 2017-10-23T23:19:21.683Z,1508800761.683 [DataOverHttps](INFO): Moved sent file to Logs/20171023T171754/Express0129.lzma.bak 2017-10-23T23:19:21.683Z,1508800761.683 [DataOverHttps](INFO): SBD MOMSN=5150270 2017-10-23T23:19:31.024Z,1508800771.024 [DataOverHttps](INFO): Sending 688 bytes from file Logs/20171023T231417/Express0001.lzma 2017-10-23T23:19:31.770Z,1508800771.770 [DataOverHttps](INFO): Moved sent file to Logs/20171023T231417/Express0001.lzma.bak 2017-10-23T23:19:31.770Z,1508800771.770 [DataOverHttps](INFO): SBD MOMSN=5150274 2017-10-23T23:19:32.379Z,1508800772.379 [Startup:StartupSatComms:B] Stopped 2017-10-23T23:19:32.379Z,1508800772.379 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2017-10-23T23:19:32.379Z,1508800772.379 [Startup:StartupSatComms] Stopped 2017-10-23T23:19:32.379Z,1508800772.379 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2017-10-23T23:19:32.380Z,1508800772.380 [Startup](INFO): Completed Startup 2017-10-23T23:19:32.381Z,1508800772.381 [MissionManager](INFO): Startup is completed. 2017-10-23T23:19:32.381Z,1508800772.381 [MissionManager](INFO): Uninitializing Mission Startup 2017-10-23T23:19:32.381Z,1508800772.381 [Startup] Stopped 2017-10-23T23:19:32.381Z,1508800772.381 [Startup](DEBUG): Aggregate::uninitialize Startup 2017-10-23T23:19:32.381Z,1508800772.381 [Startup:A.GoToSurface] Stopped 2017-10-23T23:19:32.381Z,1508800772.381 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-10-23T23:19:32.650Z,1508800772.650 [MissionManager](IMPORTANT): Started mission Default 2017-10-23T23:19:32.650Z,1508800772.650 [Default] Running Loop=1 2017-10-23T23:19:32.651Z,1508800772.651 [Default](DEBUG): Aggregate::initialize Default 2017-10-23T23:19:32.651Z,1508800772.651 [Default:B.GoToSurface] Running Loop=1 2017-10-23T23:19:32.651Z,1508800772.651 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-10-23T23:19:32.651Z,1508800772.651 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-10-23T23:19:32.651Z,1508800772.651 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-10-23T23:19:32.652Z,1508800772.652 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-10-23T23:19:32.652Z,1508800772.652 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-10-23T23:19:32.652Z,1508800772.652 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-10-23T23:19:32.653Z,1508800772.653 [Default:A.Wait] Running Loop=1 2017-10-23T23:19:32.653Z,1508800772.653 [Default:A.Wait](DEBUG): Initialize Wait Component. 2017-10-23T23:19:45.708Z,1508800785.708 [Default:A.Wait](INFO): Done Waiting. 2017-10-23T23:19:45.708Z,1508800785.708 [Default:A.Wait] Stopped 2017-10-23T23:19:45.708Z,1508800785.708 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2017-10-23T23:19:46.117Z,1508800786.117 [Default:CheckIn] Running Loop=1 2017-10-23T23:19:46.117Z,1508800786.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-10-23T23:19:46.117Z,1508800786.117 [Default:CheckIn:Read_GPS] Running Loop=1 2017-10-23T23:19:46.520Z,1508800786.520 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2017-10-23T23:19:51.698Z,1508800791.698 [BPC1](ERROR): Failed to parse bank A battery data 2017-10-23T23:19:51.699Z,1508800791.699 [BPC1] Data Fault, FailCount= 1 2017-10-23T23:19:51.699Z,1508800791.699 [BPC1](ERROR): Data Fault 2017-10-23T23:19:51.739Z,1508800791.739 [CBIT](ERROR): Data Fault in component: BPC1 2017-10-23T23:19:52.937Z,1508800792.937 [CBIT](INFO): Clearing failed state for component BPC1 2017-10-23T23:19:52.937Z,1508800792.937 [BPC1] No Fault, FailCount= 1 2017-10-23T23:20:12.866Z,1508800812.866 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2017-10-23T23:20:32.125Z,1508800832.125 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-10-23T23:20:32.126Z,1508800832.126 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2017-10-23T23:20:32.126Z,1508800832.126 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-10-23T23:20:32.128Z,1508800832.128 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-10-23T23:20:32.128Z,1508800832.128 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2017-10-23T23:20:32.128Z,1508800832.128 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2017-10-23T23:20:32.149Z,1508800832.149 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-10-23T23:20:32.149Z,1508800832.149 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2017-10-23T23:20:32.545Z,1508800832.545 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-10-23T23:20:32.546Z,1508800832.546 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2017-10-23T23:20:32.546Z,1508800832.546 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2017-10-23T23:20:32.546Z,1508800832.546 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2017-10-23T23:20:32.911Z,1508800832.911 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-10-23T23:20:32.911Z,1508800832.911 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:20:32.912Z,1508800832.912 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:20:32.912Z,1508800832.912 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-10-23T23:20:32.913Z,1508800832.913 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:20:32.913Z,1508800832.913 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:23:32.944Z,1508801012.944 [BPC1](ERROR): Failed to parse bank A battery data 2017-10-23T23:23:32.944Z,1508801012.944 [BPC1] Data Fault, FailCount= 1 2017-10-23T23:23:32.945Z,1508801012.945 [BPC1](ERROR): Data Fault 2017-10-23T23:23:32.959Z,1508801012.959 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-10-23T23:23:32.959Z,1508801012.959 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2017-10-23T23:23:32.959Z,1508801012.959 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-10-23T23:23:32.961Z,1508801012.961 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-10-23T23:23:32.961Z,1508801012.961 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2017-10-23T23:23:32.961Z,1508801012.961 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2017-10-23T23:23:33.016Z,1508801013.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-10-23T23:23:33.016Z,1508801013.016 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2017-10-23T23:23:33.016Z,1508801013.016 [CBIT](ERROR): Data Fault in component: BPC1 2017-10-23T23:23:33.401Z,1508801013.401 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-10-23T23:23:33.402Z,1508801013.402 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2017-10-23T23:23:33.402Z,1508801013.402 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2017-10-23T23:23:33.402Z,1508801013.402 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2017-10-23T23:23:33.729Z,1508801013.729 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-10-23T23:23:33.730Z,1508801013.730 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:23:33.730Z,1508801013.730 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:23:33.730Z,1508801013.730 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-10-23T23:23:33.731Z,1508801013.731 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:23:33.731Z,1508801013.731 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:23:37.093Z,1508801017.093 [CBIT](INFO): Clearing failed state for component BPC1 2017-10-23T23:23:37.093Z,1508801017.093 [BPC1] No Fault, FailCount= 1 2017-10-23T23:23:53.173Z,1508801033.173 [BPC1](ERROR): Failed to receive battery data 2017-10-23T23:23:53.173Z,1508801033.173 [BPC1] Communications Fault, FailCount= 2 2017-10-23T23:23:53.173Z,1508801033.173 [BPC1](ERROR): Communications Fault 2017-10-23T23:23:53.247Z,1508801033.247 [CBIT](ERROR): Communications Fault in component: BPC1 2017-10-23T23:23:54.422Z,1508801034.422 [CBIT](INFO): Clearing failed state for component BPC1 2017-10-23T23:23:54.422Z,1508801034.422 [BPC1] No Fault, FailCount= 2 2017-10-23T23:24:19.594Z,1508801059.594 [BPC1](ERROR): Failed to parse message. 2017-10-23T23:24:19.594Z,1508801059.594 [BPC1](ERROR): Failed to parse bank A battery data 2017-10-23T23:24:19.594Z,1508801059.594 [BPC1] Data Fault, FailCount= 1 2017-10-23T23:24:19.595Z,1508801059.595 [BPC1](ERROR): Data Fault 2017-10-23T23:24:19.645Z,1508801059.645 [CBIT](ERROR): Data Fault in component: BPC1 2017-10-23T23:24:20.811Z,1508801060.811 [CBIT](INFO): Clearing failed state for component BPC1 2017-10-23T23:24:20.811Z,1508801060.811 [BPC1] No Fault, FailCount= 1 2017-10-23T23:24:31.212Z,1508801071.212 [CBIT](DEBUG): EFC running - data check-sum false 2017-10-23T23:24:46.329Z,1508801086.329 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-10-23T23:19:46.1Z 2017-10-23T23:24:46.329Z,1508801086.329 [Default:CheckIn:Read_GPS] Stopped 2017-10-23T23:24:46.329Z,1508801086.329 [Default:CheckIn:Read_Iridium] Running Loop=1 2017-10-23T23:24:46.699Z,1508801086.699 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2017-10-23T23:24:50.973Z,1508801090.973 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20171023T231417/Courier0004.lzma 2017-10-23T23:24:51.723Z,1508801091.723 [DataOverHttps](INFO): Moved sent file to Logs/20171023T231417/Courier0004.lzma.bak 2017-10-23T23:24:51.724Z,1508801091.724 [DataOverHttps](INFO): SBD MOMSN=5150302 2017-10-23T23:25:01.051Z,1508801101.051 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20171023T231417/Express0005.lzma 2017-10-23T23:25:01.861Z,1508801101.861 [DataOverHttps](INFO): Moved sent file to Logs/20171023T231417/Express0005.lzma.bak 2017-10-23T23:25:01.861Z,1508801101.861 [DataOverHttps](INFO): SBD MOMSN=5150304 2017-10-23T23:25:02.443Z,1508801102.443 [Default:CheckIn:Read_Iridium] Stopped 2017-10-23T23:25:02.443Z,1508801102.443 [Default:CheckIn:C.Wait] Running Loop=1 2017-10-23T23:25:02.443Z,1508801102.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2017-10-23T23:25:10.913Z,1508801110.913 [NAL9602](FAULT): GPS failed to acquire within timeout. 2017-10-23T23:25:10.913Z,1508801110.913 [NAL9602] Data Fault, FailCount= 1 2017-10-23T23:25:10.913Z,1508801110.913 [NAL9602](ERROR): Data Fault 2017-10-23T23:25:11.018Z,1508801111.018 [CBIT](ERROR): Data Fault in component: NAL9602 2017-10-23T23:25:11.313Z,1508801111.313 [NAL9602](INFO): Powering down 2017-10-23T23:25:12.196Z,1508801112.196 [CBIT](INFO): Clearing failed state for component NAL9602 2017-10-23T23:25:12.196Z,1508801112.196 [NAL9602] No Fault, FailCount= 1 2017-10-23T23:25:41.317Z,1508801141.317 [NAL9602](INFO): Powering up NAL9602 2017-10-23T23:25:51.349Z,1508801151.349 [BPC1](ERROR): Failed to parse bank B battery data 2017-10-23T23:25:51.349Z,1508801151.349 [BPC1] Data Fault, FailCount= 1 2017-10-23T23:25:51.349Z,1508801151.349 [BPC1](ERROR): Data Fault 2017-10-23T23:25:51.380Z,1508801151.380 [CBIT](ERROR): Data Fault in component: BPC1 2017-10-23T23:25:52.511Z,1508801152.511 [NAL9602](INFO): NAL9602 initialized 2017-10-23T23:25:52.598Z,1508801152.598 [CBIT](INFO): Clearing failed state for component BPC1 2017-10-23T23:25:52.598Z,1508801152.598 [BPC1] No Fault, FailCount= 1 2017-10-23T23:26:24.072Z,1508801184.072 [BPC1](ERROR): Failed to receive battery data 2017-10-23T23:26:24.073Z,1508801184.073 [BPC1] Communications Fault, FailCount= 1 2017-10-23T23:26:24.073Z,1508801184.073 [BPC1](ERROR): Communications Fault 2017-10-23T23:26:24.130Z,1508801184.130 [CBIT](ERROR): Communications Fault in component: BPC1 2017-10-23T23:26:25.291Z,1508801185.291 [CBIT](INFO): Clearing failed state for component BPC1 2017-10-23T23:26:25.291Z,1508801185.291 [BPC1] No Fault, FailCount= 1 2017-10-23T23:26:34.078Z,1508801194.078 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-10-23T23:26:34.078Z,1508801194.078 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2017-10-23T23:26:34.078Z,1508801194.078 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-10-23T23:26:34.080Z,1508801194.080 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-10-23T23:26:34.081Z,1508801194.081 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2017-10-23T23:26:34.081Z,1508801194.081 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2017-10-23T23:26:34.094Z,1508801194.094 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-10-23T23:26:34.095Z,1508801194.095 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2017-10-23T23:26:34.492Z,1508801194.492 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-10-23T23:26:34.493Z,1508801194.493 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2017-10-23T23:26:34.493Z,1508801194.493 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2017-10-23T23:26:34.493Z,1508801194.493 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2017-10-23T23:26:34.878Z,1508801194.878 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-10-23T23:26:34.878Z,1508801194.878 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:26:34.878Z,1508801194.878 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:26:34.879Z,1508801194.879 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-10-23T23:26:34.879Z,1508801194.879 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-10-23T23:26:34.880Z,1508801194.880 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-10-23T23:28:08.595Z,1508801288.595 [PNI_TCM](ERROR): CRC does not match. Expected:0x29987 got:0x26119 2017-10-23T23:28:11.391Z,1508801291.391 [PNI_TCM](ERROR): CRC does not match. Expected:0x55761 got:0x32836 2017-10-23T23:28:13.031Z,1508801293.031 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.411646 seconds old. 2017-10-23T23:28:13.032Z,1508801293.032 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 0.412252 seconds old. 2017-10-23T23:28:13.419Z,1508801293.419 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.799460 seconds old. 2017-10-23T23:28:13.420Z,1508801293.420 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 0.799867 seconds old. 2017-10-23T23:28:13.797Z,1508801293.797 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.177962 seconds old. 2017-10-23T23:28:13.799Z,1508801293.799 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 1.178362 seconds old. 2017-10-23T23:28:14.204Z,1508801294.204 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.584402 seconds old. 2017-10-23T23:28:14.206Z,1508801294.206 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 1.584802 seconds old. 2017-10-23T23:28:14.602Z,1508801294.602 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.982936 seconds old. 2017-10-23T23:28:14.604Z,1508801294.604 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 1.983336 seconds old. 2017-10-23T23:28:15.003Z,1508801295.003 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.383369 seconds old. 2017-10-23T23:28:15.004Z,1508801295.004 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 2.383769 seconds old. 2017-10-23T23:28:15.407Z,1508801295.407 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.787402 seconds old. 2017-10-23T23:28:15.408Z,1508801295.408 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 2.788009 seconds old. 2017-10-23T23:28:15.812Z,1508801295.812 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.192849 seconds old. 2017-10-23T23:28:15.813Z,1508801295.813 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 3.193249 seconds old. 2017-10-23T23:28:16.217Z,1508801296.217 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.597788 seconds old. 2017-10-23T23:28:16.218Z,1508801296.218 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 3.598188 seconds old. 2017-10-23T23:28:16.607Z,1508801296.607 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.987103 seconds old. 2017-10-23T23:28:16.608Z,1508801296.608 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 3.987403 seconds old. 2017-10-23T23:28:17.007Z,1508801297.007 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.387436 seconds old. 2017-10-23T23:28:17.008Z,1508801297.008 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 4.387836 seconds old. 2017-10-23T23:28:17.419Z,1508801297.419 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.799689 seconds old. 2017-10-23T23:28:17.420Z,1508801297.420 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 4.800089 seconds old. 2017-10-23T23:28:17.805Z,1508801297.805 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.185297 seconds old. 2017-10-23T23:28:17.806Z,1508801297.806 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 5.185597 seconds old. 2017-10-23T23:28:18.209Z,1508801298.209 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.589337 seconds old. 2017-10-23T23:28:18.210Z,1508801298.210 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 5.589737 seconds old. 2017-10-23T23:28:18.613Z,1508801298.613 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.993876 seconds old. 2017-10-23T23:28:18.614Z,1508801298.614 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 5.994176 seconds old. 2017-10-23T23:28:19.022Z,1508801299.022 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.402423 seconds old. 2017-10-23T23:28:19.023Z,1508801299.023 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 6.402823 seconds old. 2017-10-23T23:28:19.414Z,1508801299.414 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.794943 seconds old. 2017-10-23T23:28:19.416Z,1508801299.416 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 6.795343 seconds old. 2017-10-23T23:28:19.851Z,1508801299.851 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.231634 seconds old. 2017-10-23T23:28:19.852Z,1508801299.852 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 7.232034 seconds old. 2017-10-23T23:28:20.214Z,1508801300.214 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.594404 seconds old. 2017-10-23T23:28:20.215Z,1508801300.215 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 7.594910 seconds old. 2017-10-23T23:28:20.629Z,1508801300.629 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.009863 seconds old. 2017-10-23T23:28:20.630Z,1508801300.630 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 8.010163 seconds old. 2017-10-23T23:28:21.008Z,1508801301.008 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.388358 seconds old. 2017-10-23T23:28:21.009Z,1508801301.009 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 8.388965 seconds old. 2017-10-23T23:28:21.415Z,1508801301.415 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.795104 seconds old. 2017-10-23T23:28:21.416Z,1508801301.416 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 8.795504 seconds old. 2017-10-23T23:28:21.851Z,1508801301.851 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.230995 seconds old. 2017-10-23T23:28:21.852Z,1508801301.852 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 9.231395 seconds old. 2017-10-23T23:28:22.218Z,1508801302.218 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.599078 seconds old. 2017-10-23T23:28:22.220Z,1508801302.220 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 9.599378 seconds old. 2017-10-23T23:28:22.651Z,1508801302.651 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.031662 seconds old. 2017-10-23T23:28:22.652Z,1508801302.652 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 10.032062 seconds old. 2017-10-23T23:28:23.052Z,1508801303.052 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.432895 seconds old. 2017-10-23T23:28:23.054Z,1508801303.054 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 10.433395 seconds old. 2017-10-23T23:28:23.407Z,1508801303.407 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.788053 seconds old. 2017-10-23T23:28:23.409Z,1508801303.409 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 10.788559 seconds old. 2017-10-23T23:28:23.807Z,1508801303.807 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.187986 seconds old. 2017-10-23T23:28:23.809Z,1508801303.809 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 11.188592 seconds old. 2017-10-23T23:28:24.219Z,1508801304.219 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.599239 seconds old. 2017-10-23T23:28:24.220Z,1508801304.220 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 11.599839 seconds old. 2017-10-23T23:28:24.628Z,1508801304.628 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.008185 seconds old. 2017-10-23T23:28:24.629Z,1508801304.629 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 12.008685 seconds old. 2017-10-23T23:28:25.017Z,1508801305.017 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.397599 seconds old. 2017-10-23T23:28:25.019Z,1508801305.019 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 12.397999 seconds old. 2017-10-23T23:28:25.420Z,1508801305.420 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.800639 seconds old. 2017-10-23T23:28:25.421Z,1508801305.421 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 12.801039 seconds old. 2017-10-23T23:28:25.815Z,1508801305.815 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.195660 seconds old. 2017-10-23T23:28:25.817Z,1508801305.817 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 13.196366 seconds old. 2017-10-23T23:28:26.214Z,1508801306.214 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.594593 seconds old. 2017-10-23T23:28:26.215Z,1508801306.215 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 13.594993 seconds old. 2017-10-23T23:28:26.614Z,1508801306.614 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.994727 seconds old. 2017-10-23T23:28:26.615Z,1508801306.615 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 13.995127 seconds old. 2017-10-23T23:28:27.011Z,1508801307.011 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.391454 seconds old. 2017-10-23T23:28:27.012Z,1508801307.012 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 14.391854 seconds old. 2017-10-23T23:28:35.396Z,1508801315.396 [PNI_TCM](ERROR): CRC does not match. Expected:0x40798 got:0x6335 2017-10-23T23:28:36.990Z,1508801316.990 [PNI_TCM](ERROR): CRC does not match. Expected:0x48771 got:0x1287 2017-10-23T23:28:38.613Z,1508801318.613 [PNI_TCM](ERROR): CRC does not match. Expected:0x39820 got:0x32841 2017-10-23T23:28:40.197Z,1508801320.197 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-10-23T23:28:43.396Z,1508801323.396 [PNI_TCM](ERROR): CRC does not match. Expected:0x26506 got:0x49038 2017-10-23T23:28:45.295Z,1508801325.295 [CommandLine](IMPORTANT): got command show variable ThrusterServo.accel 2017-10-23T23:28:45.549Z,1508801325.549 [CommandLine](IMPORTANT): ThrusterServo.accel (none) 2017-10-23T23:28:46.598Z,1508801326.598 [PNI_TCM](ERROR): CRC does not match. Expected:0x25900 got:0x1347 2017-10-23T23:28:49.796Z,1508801329.796 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-10-23T23:28:51.396Z,1508801331.396 [PNI_TCM](ERROR): CRC does not match. Expected:0x738 got:0x30237 2017-10-23T23:28:53.021Z,1508801333.021 [PNI_TCM](ERROR): CRC does not match. Expected:0x57060 got:0x64337 2017-10-23T23:28:56.607Z,1508801336.607 [PNI_TCM](ERROR): CRC does not match. Expected:0x17129 got:0x27 2017-10-23T23:28:56.753Z,1508801336.753 [CommandLine](IMPORTANT): got command configSet ThrusterServo.accel 0.800000 none 2017-10-23T23:28:59.409Z,1508801339.409 [PNI_TCM](ERROR): CRC does not match. Expected:0x4040 got:0x0 2017-10-23T23:29:05.800Z,1508801345.800 [CommandLine](IMPORTANT): got command configSet ThrusterServo.accel 0.800000 none persist 2017-10-23T23:29:07.150Z,1508801347.150 [PNI_TCM](ERROR): CRC does not match. Expected:0x46154 got:0x19110 2017-10-23T23:29:07.150Z,1508801347.150 [PNI_TCM](ERROR): CRC does not match. Expected:0x34860 got:0x19091 2017-10-23T23:29:07.151Z,1508801347.151 [PNI_TCM](ERROR): CRC does not match. Expected:0x48825 got:0x19126 2017-10-23T23:29:07.151Z,1508801347.151 [PNI_TCM](ERROR): CRC does not match. Expected:0x186 got:0x19131 2017-10-23T23:29:07.152Z,1508801347.152 [PNI_TCM](ERROR): CRC does not match. Expected:0x15348 got:0x19106 2017-10-23T23:29:07.152Z,1508801347.152 [PNI_TCM](ERROR): CRC does not match. Expected:0x17695 got:0x19041 2017-10-23T23:29:07.152Z,1508801347.152 [PNI_TCM](ERROR): CRC does not match. Expected:0x11132 got:0x19099 2017-10-23T23:29:07.153Z,1508801347.153 [PNI_TCM](ERROR): CRC does not match. Expected:0x52722 got:0x19097 2017-10-23T23:29:10.793Z,1508801350.793 [CommandLine](IMPORTANT): got command restart application 2017-10-23T23:29:11.800Z,1508801351.800 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2017-10-23T23:29:11.804Z,1508801351.804 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2017-10-23T23:29:11.804Z,1508801351.804 [CommandLine ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:11.912Z,1508801351.912 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2017-10-23T23:29:11.912Z,1508801351.912 [CommandLine ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:11.913Z,1508801351.913 [CommandLine](INFO): Join timeout helper Thread ID is 5186 2017-10-23T23:29:11.914Z,1508801351.914 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2017-10-23T23:29:11.914Z,1508801351.914 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:11.914Z,1508801351.914 [NavChartDb](INFO): Join timeout helper Thread ID is 5187 2017-10-23T23:29:12.242Z,1508801352.242 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2017-10-23T23:29:12.242Z,1508801352.242 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:12.257Z,1508801352.257 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2017-10-23T23:29:12.257Z,1508801352.257 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:12.258Z,1508801352.258 [Radio_Surface](INFO): Join timeout helper Thread ID is 5188 2017-10-23T23:29:12.430Z,1508801352.430 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2017-10-23T23:29:12.430Z,1508801352.430 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:12.439Z,1508801352.439 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2017-10-23T23:29:12.439Z,1508801352.439 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:12.440Z,1508801352.440 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5189 2017-10-23T23:29:12.818Z,1508801352.818 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2017-10-23T23:29:12.818Z,1508801352.818 [WetLabsBB2FL](INFO): Powering down 2017-10-23T23:29:12.819Z,1508801352.819 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:12.843Z,1508801352.843 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2017-10-23T23:29:12.843Z,1508801352.843 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:12.844Z,1508801352.844 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5190 2017-10-23T23:29:13.042Z,1508801353.042 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2017-10-23T23:29:13.128Z,1508801353.128 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-10-23T23:29:13.128Z,1508801353.128 [CTD_Seabird](INFO): Powering down 2017-10-23T23:29:13.129Z,1508801353.129 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.132Z,1508801353.132 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2017-10-23T23:29:13.132Z,1508801353.132 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.132Z,1508801353.132 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5192 2017-10-23T23:29:13.227Z,1508801353.227 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2017-10-23T23:29:13.227Z,1508801353.227 [CTD_NeilBrown](INFO): Powering down 2017-10-23T23:29:13.228Z,1508801353.228 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.239Z,1508801353.239 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2017-10-23T23:29:13.240Z,1508801353.240 [logger ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.240Z,1508801353.240 [logger](INFO): Join timeout helper Thread ID is 5193 2017-10-23T23:29:13.247Z,1508801353.247 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2017-10-23T23:29:13.247Z,1508801353.247 [logger ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.259Z,1508801353.259 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2017-10-23T23:29:13.260Z,1508801353.260 [CommandLine ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.260Z,1508801353.260 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2017-10-23T23:29:13.260Z,1508801353.260 [controlThread ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.260Z,1508801353.260 [controlThread](INFO): Join timeout helper Thread ID is 5194 2017-10-23T23:29:13.275Z,1508801353.275 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2017-10-23T23:29:13.275Z,1508801353.275 [controlThread](DEBUG): Uninitializing ControlThread 2017-10-23T23:29:13.276Z,1508801353.276 [Aanderaa_O2](INFO): Powering down 2017-10-23T23:29:13.278Z,1508801353.278 [NAL9602](INFO): Powering down 2017-10-23T23:29:13.280Z,1508801353.280 [PNI_TCM](INFO): Powering down 2017-10-23T23:29:13.368Z,1508801353.368 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-10-23T23:29:13.370Z,1508801353.370 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2017-10-23T23:29:13.370Z,1508801353.370 [MissionManager](INFO): Uninitializing Mission Default 2017-10-23T23:29:13.370Z,1508801353.370 [Default] Stopped 2017-10-23T23:29:13.371Z,1508801353.371 [Default](DEBUG): Aggregate::uninitialize Default 2017-10-23T23:29:13.371Z,1508801353.371 [Default:B.GoToSurface] Stopped 2017-10-23T23:29:13.371Z,1508801353.371 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-10-23T23:29:13.371Z,1508801353.371 [Default:CheckIn] Stopped 2017-10-23T23:29:13.371Z,1508801353.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2017-10-23T23:29:13.371Z,1508801353.371 [Default:CheckIn:C.Wait] Stopped 2017-10-23T23:29:13.371Z,1508801353.371 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2017-10-23T23:29:13.375Z,1508801353.375 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2017-10-23T23:29:13.375Z,1508801353.375 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2017-10-23T23:29:13.375Z,1508801353.375 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2017-10-23T23:29:13.376Z,1508801353.376 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2017-10-23T23:29:13.376Z,1508801353.376 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-10-23T23:29:13.376Z,1508801353.376 [BuoyancyServo](INFO): Powering down 2017-10-23T23:29:13.391Z,1508801353.391 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2017-10-23T23:29:13.391Z,1508801353.391 [ElevatorServo](INFO): Powering down 2017-10-23T23:29:13.392Z,1508801353.392 [MassServo](DEBUG): Uninitialize Mass Servo. 2017-10-23T23:29:13.392Z,1508801353.392 [MassServo](INFO): Powering down 2017-10-23T23:29:13.393Z,1508801353.393 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2017-10-23T23:29:13.393Z,1508801353.393 [RudderServo](INFO): Powering down 2017-10-23T23:29:13.394Z,1508801353.394 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-10-23T23:29:13.394Z,1508801353.394 [ThrusterServo](INFO): Powering down 2017-10-23T23:29:13.395Z,1508801353.395 [SBIT](DEBUG): Uninitialize SBIT Component. 2017-10-23T23:29:13.396Z,1508801353.396 [IBIT](DEBUG): Uninitialize IBIT Component. 2017-10-23T23:29:13.396Z,1508801353.396 [CBIT](DEBUG): Uninitialize CBIT Component. 2017-10-23T23:29:13.397Z,1508801353.397 [controlThread ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.451Z,1508801353.451 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.455Z,1508801353.455 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.458Z,1508801353.458 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.526Z,1508801353.526 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.569Z,1508801353.569 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2017-10-23T23:29:13.628Z,1508801353.628 [logger ThreadHandler](INFO): Thread cancelled.