2017-12-18T20:54:23.810Z,1513630463.810 [Supervisor](DEBUG): Initializing supervisor. 2017-12-18T20:54:23.813Z,1513630463.813 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2017-12-18T20:54:23.813Z,1513630463.813 [SyncHandler](INFO): Protected caller Thread ID is 801 2017-12-18T20:54:23.814Z,1513630463.814 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-12-18T20:54:23.815Z,1513630463.815 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2017-12-18T20:54:23.815Z,1513630463.815 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 802 2017-12-18T20:54:23.817Z,1513630463.817 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-12-18T20:54:23.828Z,1513630463.828 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-12-18T20:54:23.829Z,1513630463.829 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2017-12-18T20:54:23.830Z,1513630463.830 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 803 2017-12-18T20:54:23.830Z,1513630463.830 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-12-18T20:54:23.831Z,1513630463.831 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2017-12-18T20:54:23.832Z,1513630463.832 [logger ThreadHandler](INFO): Protected caller Thread ID is 804 2017-12-18T20:54:23.834Z,1513630463.834 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-12-18T20:54:23.834Z,1513630463.834 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-12-18T20:54:23.837Z,1513630463.837 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-12-18T20:54:24.006Z,1513630464.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-12-18T20:54:24.007Z,1513630464.007 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-12-18T20:54:24.138Z,1513630464.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-12-18T20:54:24.139Z,1513630464.139 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-12-18T20:54:24.436Z,1513630464.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-12-18T20:54:24.437Z,1513630464.437 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-12-18T20:54:24.565Z,1513630464.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-12-18T20:54:24.566Z,1513630464.566 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-12-18T20:54:24.646Z,1513630464.646 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-12-18T20:54:24.829Z,1513630464.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-12-18T20:54:24.830Z,1513630464.830 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-12-18T20:54:24.926Z,1513630464.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-12-18T20:54:24.926Z,1513630464.926 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-12-18T20:54:25.235Z,1513630465.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-12-18T20:54:25.237Z,1513630465.237 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-12-18T20:54:25.570Z,1513630465.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-12-18T20:54:25.571Z,1513630465.571 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-12-18T20:54:25.844Z,1513630465.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-12-18T20:54:25.845Z,1513630465.845 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-12-18T20:54:26.306Z,1513630466.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-12-18T20:54:26.307Z,1513630466.307 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-12-18T20:54:26.486Z,1513630466.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-12-18T20:54:26.487Z,1513630466.487 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-12-18T20:54:26.582Z,1513630466.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-12-18T20:54:26.584Z,1513630466.584 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-12-18T20:54:27.013Z,1513630467.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-12-18T20:54:27.014Z,1513630467.014 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-12-18T20:54:27.117Z,1513630467.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-12-18T20:54:27.119Z,1513630467.119 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2017-12-18T20:54:27.121Z,1513630467.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2017-12-18T20:54:27.359Z,1513630467.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-12-18T20:54:27.359Z,1513630467.359 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2017-12-18T20:54:30.903Z,1513630470.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2017-12-18T20:54:30.991Z,1513630470.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2017-12-18T20:54:31.089Z,1513630471.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2017-12-18T20:54:31.174Z,1513630471.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2017-12-18T20:54:31.293Z,1513630471.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2017-12-18T20:54:31.435Z,1513630471.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2017-12-18T20:54:31.527Z,1513630471.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2017-12-18T20:54:31.617Z,1513630471.617 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2017-12-18T20:54:31.699Z,1513630471.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2017-12-18T20:54:31.863Z,1513630471.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2017-12-18T20:54:31.939Z,1513630471.939 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-12-18T20:54:31.944Z,1513630471.944 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-12-18T20:54:32.094Z,1513630472.094 [SBIT](DEBUG): Construct Startup Built In Test. 2017-12-18T20:54:32.122Z,1513630472.122 [SBIT] Loaded 2017-12-18T20:54:32.122Z,1513630472.122 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-12-18T20:54:32.123Z,1513630472.123 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-12-18T20:54:32.150Z,1513630472.150 [IBIT] Loaded 2017-12-18T20:54:32.150Z,1513630472.150 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-12-18T20:54:32.153Z,1513630472.153 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-12-18T20:54:32.300Z,1513630472.300 [CBIT] Loaded 2017-12-18T20:54:32.300Z,1513630472.300 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-12-18T20:54:32.301Z,1513630472.301 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-12-18T20:54:32.301Z,1513630472.301 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-12-18T20:54:32.388Z,1513630472.388 [VerticalControl](DEBUG): Construct VerticalControl. 2017-12-18T20:54:32.481Z,1513630472.481 [VerticalControl] Loaded 2017-12-18T20:54:32.482Z,1513630472.482 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-12-18T20:54:32.482Z,1513630472.482 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-12-18T20:54:32.539Z,1513630472.539 [HorizontalControl] Loaded 2017-12-18T20:54:32.539Z,1513630472.539 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-12-18T20:54:32.540Z,1513630472.540 [SpeedControl](DEBUG): Construct SpeedControl. 2017-12-18T20:54:32.545Z,1513630472.545 [SpeedControl] Loaded 2017-12-18T20:54:32.546Z,1513630472.546 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-12-18T20:54:32.546Z,1513630472.546 [LoopControl](DEBUG): Construct LoopControl. 2017-12-18T20:54:32.547Z,1513630472.547 [LoopControl] Loaded 2017-12-18T20:54:32.547Z,1513630472.547 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-12-18T20:54:32.547Z,1513630472.547 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-12-18T20:54:32.548Z,1513630472.548 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-12-18T20:54:32.711Z,1513630472.711 [DepthRateCalculator] Loaded 2017-12-18T20:54:32.712Z,1513630472.712 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-12-18T20:54:32.722Z,1513630472.722 [PitchRateCalculator] Loaded 2017-12-18T20:54:32.722Z,1513630472.722 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-12-18T20:54:32.737Z,1513630472.737 [SpeedCalculator] Loaded 2017-12-18T20:54:32.737Z,1513630472.737 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-12-18T20:54:32.758Z,1513630472.758 [TempGradientCalculator] Loaded 2017-12-18T20:54:32.758Z,1513630472.758 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-12-18T20:54:32.772Z,1513630472.772 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-12-18T20:54:32.773Z,1513630472.773 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-12-18T20:54:32.777Z,1513630472.777 [YawRateCalculator] Loaded 2017-12-18T20:54:32.778Z,1513630472.778 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-12-18T20:54:32.778Z,1513630472.778 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-12-18T20:54:32.779Z,1513630472.779 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-12-18T20:54:34.351Z,1513630474.351 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-12-18T20:54:34.351Z,1513630474.351 [StratificationFrontDetector](DEBUG): (re)initializing 2017-12-18T20:54:34.351Z,1513630474.351 [StratificationFrontDetector] Loaded 2017-12-18T20:54:34.351Z,1513630474.351 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-12-18T20:54:34.352Z,1513630474.352 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-12-18T20:54:34.352Z,1513630474.352 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-12-18T20:54:34.625Z,1513630474.625 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-12-18T20:54:34.626Z,1513630474.626 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-12-18T20:54:34.732Z,1513630474.732 [DeadReckonUsingSpeedCalculator] Loaded 2017-12-18T20:54:34.733Z,1513630474.733 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-12-18T20:54:34.784Z,1513630474.784 [DeadReckonWithRespectToSeafloor] Loaded 2017-12-18T20:54:34.784Z,1513630474.784 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-12-18T20:54:34.800Z,1513630474.800 [NavChart] Loaded 2017-12-18T20:54:34.800Z,1513630474.800 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-12-18T20:54:34.804Z,1513630474.804 [UniversalFixResidualReporter] Loaded 2017-12-18T20:54:34.804Z,1513630474.804 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-12-18T20:54:34.805Z,1513630474.805 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-12-18T20:54:34.805Z,1513630474.805 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-12-18T20:54:34.830Z,1513630474.830 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-12-18T20:54:34.830Z,1513630474.830 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-12-18T20:54:35.027Z,1513630475.027 [Aanderaa_O2] Loaded 2017-12-18T20:54:35.027Z,1513630475.027 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-12-18T20:54:35.039Z,1513630475.039 [CANONSampler] Loaded 2017-12-18T20:54:35.039Z,1513630475.039 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2017-12-18T20:54:35.113Z,1513630475.113 [CTD_NeilBrown] Loaded 2017-12-18T20:54:35.114Z,1513630475.114 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-12-18T20:54:35.115Z,1513630475.115 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406D14E0 2017-12-18T20:54:35.115Z,1513630475.115 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 886 2017-12-18T20:54:35.147Z,1513630475.147 [CTD_Seabird] Loaded 2017-12-18T20:54:35.147Z,1513630475.147 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-12-18T20:54:35.148Z,1513630475.148 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407014E0 2017-12-18T20:54:35.148Z,1513630475.148 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 887 2017-12-18T20:54:35.162Z,1513630475.162 [PAR_Licor] Loaded 2017-12-18T20:54:35.162Z,1513630475.162 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-12-18T20:54:35.209Z,1513630475.209 [WetLabsBB2FL] Loaded 2017-12-18T20:54:35.210Z,1513630475.210 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-12-18T20:54:35.211Z,1513630475.211 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407314E0 2017-12-18T20:54:35.211Z,1513630475.211 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 888 2017-12-18T20:54:35.252Z,1513630475.252 [WetLabsSeaOWL_UV_A] Loaded 2017-12-18T20:54:35.252Z,1513630475.252 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2017-12-18T20:54:35.253Z,1513630475.253 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407614E0 2017-12-18T20:54:35.254Z,1513630475.254 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 889 2017-12-18T20:54:35.254Z,1513630475.254 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-12-18T20:54:35.255Z,1513630475.255 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-12-18T20:54:35.781Z,1513630475.781 [DataOverHttps] Loaded 2017-12-18T20:54:35.781Z,1513630475.781 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-12-18T20:54:35.794Z,1513630475.794 [Depth_Keller] Loaded 2017-12-18T20:54:35.794Z,1513630475.794 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-12-18T20:54:35.799Z,1513630475.799 [DropWeight] Loaded 2017-12-18T20:54:35.799Z,1513630475.799 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-12-18T20:54:35.885Z,1513630475.885 [NAL9602] Loaded 2017-12-18T20:54:35.886Z,1513630475.886 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-12-18T20:54:35.891Z,1513630475.891 [Onboard] Loaded 2017-12-18T20:54:35.891Z,1513630475.891 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-12-18T20:54:35.897Z,1513630475.897 [Radio_Surface] Loaded 2017-12-18T20:54:35.898Z,1513630475.898 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-12-18T20:54:35.899Z,1513630475.899 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0 2017-12-18T20:54:35.899Z,1513630475.899 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 890 2017-12-18T20:54:36.049Z,1513630476.049 [PNI_TCM] Loaded 2017-12-18T20:54:36.050Z,1513630476.050 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-12-18T20:54:36.108Z,1513630476.108 [Rowe_600LCM] Loaded 2017-12-18T20:54:36.109Z,1513630476.109 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-12-18T20:54:36.110Z,1513630476.110 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409D54E0 2017-12-18T20:54:36.110Z,1513630476.110 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 891 2017-12-18T20:54:37.847Z,1513630477.847 [BPC1] Loaded 2017-12-18T20:54:37.848Z,1513630477.848 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-12-18T20:54:37.848Z,1513630477.848 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-12-18T20:54:37.849Z,1513630477.849 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-12-18T20:54:37.965Z,1513630477.965 [BuoyancyServo] Loaded 2017-12-18T20:54:37.965Z,1513630477.965 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-12-18T20:54:37.976Z,1513630477.976 [ElevatorServo] Loaded 2017-12-18T20:54:37.976Z,1513630477.976 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-12-18T20:54:37.987Z,1513630477.987 [MassServo] Loaded 2017-12-18T20:54:37.987Z,1513630477.987 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-12-18T20:54:37.998Z,1513630477.998 [RudderServo] Loaded 2017-12-18T20:54:37.998Z,1513630477.998 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-12-18T20:54:38.008Z,1513630478.008 [ThrusterServo] Loaded 2017-12-18T20:54:38.009Z,1513630478.009 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-12-18T20:54:38.009Z,1513630478.009 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-12-18T20:54:38.010Z,1513630478.010 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-12-18T20:54:38.134Z,1513630478.134 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-12-18T20:54:38.135Z,1513630478.135 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-12-18T20:54:38.172Z,1513630478.172 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-12-18T20:54:38.175Z,1513630478.175 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-12-18T20:54:38.176Z,1513630478.176 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-12-18T20:54:38.183Z,1513630478.183 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-12-18T20:54:38.184Z,1513630478.184 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0 2017-12-18T20:54:38.184Z,1513630478.184 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 892 2017-12-18T20:54:38.189Z,1513630478.189 [Supervisor](INFO): Main Thread ID is 797 2017-12-18T20:54:38.189Z,1513630478.189 [Supervisor](DEBUG): Running supervisor. 2017-12-18T20:54:38.190Z,1513630478.190 [CommandLine ThreadHandler](INFO): Handler Thread ID is 893 2017-12-18T20:54:38.192Z,1513630478.192 [controlThread ThreadHandler](INFO): Handler Thread ID is 894 2017-12-18T20:54:38.193Z,1513630478.193 [controlThread](DEBUG): Initializing ControlThread 2017-12-18T20:54:38.194Z,1513630478.194 [SBIT](INFO): Initialize SBIT Component. 2017-12-18T20:54:38.194Z,1513630478.194 [SBIT](IMPORTANT): git: 2017-12-12 2017-12-18T20:54:38.194Z,1513630478.194 [SBIT](INFO): git hash: 18638d31f033e3fa0cb90b26256f5481d37f00cc 2017-12-18T20:54:38.195Z,1513630478.195 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2017-12-18T20:54:38.196Z,1513630478.196 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017 2017-12-18T20:54:38.197Z,1513630478.197 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2017-12-18T20:54:38.198Z,1513630478.198 [IBIT](INFO): Initialize IBIT Component. 2017-12-18T20:54:38.198Z,1513630478.198 [CBIT](DEBUG): Initialize CBIT Component. 2017-12-18T20:54:38.199Z,1513630478.199 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-12-18T20:54:38.199Z,1513630478.199 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-12-18T20:54:38.200Z,1513630478.200 [logger ThreadHandler](INFO): Handler Thread ID is 895 2017-12-18T20:54:38.225Z,1513630478.225 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 896 2017-12-18T20:54:38.226Z,1513630478.226 [CTD_NeilBrown](INFO): Powering down 2017-12-18T20:54:38.254Z,1513630478.254 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 897 2017-12-18T20:54:38.254Z,1513630478.254 [CTD_Seabird](INFO): Initializing 2017-12-18T20:54:38.290Z,1513630478.290 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-12-18T20:54:38.292Z,1513630478.292 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-12-18T20:54:38.293Z,1513630478.293 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-12-18T20:54:38.294Z,1513630478.294 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-12-18T20:54:38.294Z,1513630478.294 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-12-18T20:54:38.295Z,1513630478.295 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-12-18T20:54:38.295Z,1513630478.295 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-12-18T20:54:38.295Z,1513630478.295 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-12-18T20:54:38.296Z,1513630478.296 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-12-18T20:54:38.297Z,1513630478.297 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-12-18T20:54:38.297Z,1513630478.297 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-18T20:54:38.298Z,1513630478.298 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-18T20:54:38.299Z,1513630478.299 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-18T20:54:38.299Z,1513630478.299 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-12-18T20:54:38.300Z,1513630478.300 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-12-18T20:54:38.300Z,1513630478.300 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-12-18T20:54:38.300Z,1513630478.300 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-12-18T20:54:38.301Z,1513630478.301 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-12-18T20:54:38.315Z,1513630478.315 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 899 2017-12-18T20:54:38.316Z,1513630478.316 [WetLabsBB2FL](INFO): Powering down 2017-12-18T20:54:38.345Z,1513630478.345 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-12-18T20:54:38.357Z,1513630478.357 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 900 2017-12-18T20:54:38.377Z,1513630478.377 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 901 2017-12-18T20:54:38.383Z,1513630478.383 [Radio_Surface](INFO): Powering up 2017-12-18T20:54:38.387Z,1513630478.387 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-18T20:54:38.405Z,1513630478.405 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 902 2017-12-18T20:54:38.406Z,1513630478.406 [Rowe_600LCM](INFO): Initializing 2017-12-18T20:54:38.407Z,1513630478.407 [Rowe_600LCM](INFO): Checking LCM 2017-12-18T20:54:38.553Z,1513630478.553 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 903 2017-12-18T20:54:38.555Z,1513630478.555 [MissionManager](DEBUG): 2017-12-18T20:54:38.556Z,1513630478.556 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-12-18T20:54:38.568Z,1513630478.568 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-12-18T20:54:38.570Z,1513630478.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-12-18T20:54:38.570Z,1513630478.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-12-18T20:54:38.570Z,1513630478.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-12-18T20:54:38.570Z,1513630478.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-12-18T20:54:38.571Z,1513630478.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-12-18T20:54:38.571Z,1513630478.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-12-18T20:54:38.571Z,1513630478.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-12-18T20:54:38.571Z,1513630478.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-12-18T20:54:38.571Z,1513630478.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-12-18T20:54:38.571Z,1513630478.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-12-18T20:54:38.572Z,1513630478.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-12-18T20:54:38.572Z,1513630478.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-12-18T20:54:38.572Z,1513630478.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-12-18T20:54:38.572Z,1513630478.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-12-18T20:54:38.572Z,1513630478.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-12-18T20:54:38.646Z,1513630478.646 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-12-18T20:54:38.647Z,1513630478.647 [CTD_Seabird](INFO): Powering down 2017-12-18T20:54:38.779Z,1513630478.779 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-12-18T20:54:38.780Z,1513630478.780 [Default:A.Wait](DEBUG): Construct Wait. 2017-12-18T20:54:38.782Z,1513630478.782 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-18T20:54:38.816Z,1513630478.816 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-12-18T20:54:38.842Z,1513630478.842 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-12-18T20:54:38.873Z,1513630478.873 [Default:E.Execute](DEBUG): Construct Execute. 2017-12-18T20:54:38.878Z,1513630478.878 [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-12-18T20:54:38.917Z,1513630478.917 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-12-18T20:54:38.956Z,1513630478.956 [Rowe_600LCM](INFO): LCM OK 2017-12-18T20:54:38.956Z,1513630478.956 [Rowe_600LCM](INFO): Powering up 2017-12-18T20:54:38.965Z,1513630478.965 [CANONSampler](INFO): Powering down 2017-12-18T20:54:39.091Z,1513630479.091 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-12-18T20:54:39.114Z,1513630479.114 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2017-12-18T20:54:39.353Z,1513630479.353 [DepthRateCalculator](ERROR): Depth measurement is not active 2017-12-18T20:54:39.454Z,1513630479.454 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:39.581Z,1513630479.581 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:39.713Z,1513630479.713 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-12-18T20:54:39.722Z,1513630479.722 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-12-18T20:54:39.728Z,1513630479.728 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-12-18T20:54:39.745Z,1513630479.745 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-12-18T20:54:39.751Z,1513630479.751 [MassServo](DEBUG): Initializing EZServoServo. 2017-12-18T20:54:39.757Z,1513630479.757 [MassServo](DEBUG): Initializing MassServo. 2017-12-18T20:54:39.774Z,1513630479.774 [RudderServo](DEBUG): Initializing EZServoServo. 2017-12-18T20:54:39.781Z,1513630479.781 [RudderServo](DEBUG): Initializing RudderServo. 2017-12-18T20:54:39.795Z,1513630479.795 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-12-18T20:54:39.801Z,1513630479.801 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-12-18T20:54:39.846Z,1513630479.846 [CommandLine](FAULT): Scheduling is paused 2017-12-18T20:54:39.846Z,1513630479.846 [CBIT](INFO): Critical error at 20171218T205438 2017-12-18T20:54:39.846Z,1513630479.846 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-12-18T20:54:40.217Z,1513630480.217 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:40.218Z,1513630480.218 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:40.561Z,1513630480.561 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-12-18T20:54:40.561Z,1513630480.561 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-12-18T20:54:40.561Z,1513630480.561 [BuoyancyServo] Communications Fault, FailCount= 1 2017-12-18T20:54:40.561Z,1513630480.561 [BuoyancyServo](ERROR): Communications Fault 2017-12-18T20:54:40.825Z,1513630480.825 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-12-18T20:54:40.910Z,1513630480.910 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:40.910Z,1513630480.910 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:40.922Z,1513630480.922 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-12-18T20:54:40.923Z,1513630480.923 [BuoyancyServo](INFO): Powering down 2017-12-18T20:54:41.264Z,1513630481.264 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:41.265Z,1513630481.265 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:41.688Z,1513630481.688 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:41.689Z,1513630481.689 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:42.153Z,1513630482.153 [Aanderaa_O2](INFO): Powering down 2017-12-18T20:54:42.245Z,1513630482.245 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:42.261Z,1513630482.261 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:42.518Z,1513630482.518 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:42.518Z,1513630482.518 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-18T20:54:42.792Z,1513630482.792 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-18T20:54:42.920Z,1513630482.920 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-18T20:54:43.099Z,1513630483.099 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-18T20:54:43.100Z,1513630483.100 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-18T20:54:43.100Z,1513630483.100 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-18T20:54:43.174Z,1513630483.174 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-12-18T20:54:43.174Z,1513630483.174 [BuoyancyServo] No Fault, FailCount= 1 2017-12-18T20:54:43.347Z,1513630483.347 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-12-18T20:54:43.347Z,1513630483.347 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-12-18T20:54:49.233Z,1513630489.233 [CANONSampler](FAULT): LCB fault: Software Overcurrent. 2017-12-18T20:54:49.233Z,1513630489.233 [CANONSampler] Hardware Fault, FailCount= 1 2017-12-18T20:54:49.233Z,1513630489.233 [CANONSampler](ERROR): Hardware Fault 2017-12-18T20:54:49.316Z,1513630489.316 [CBIT](ERROR): Hardware Fault in component: CANONSampler 2017-12-18T20:54:49.816Z,1513630489.816 [CBIT](INFO): Clearing failed state for component CANONSampler 2017-12-18T20:54:49.816Z,1513630489.816 [CANONSampler] No Fault, FailCount= 1 2017-12-18T20:54:50.034Z,1513630490.034 [CANONSampler](INFO): Powering up 2017-12-18T20:54:51.232Z,1513630491.232 [CANONSampler](INFO): Powering down 2017-12-18T20:55:01.714Z,1513630501.714 [SBIT](IMPORTANT): Beginning Startup BIT 2017-12-18T20:55:01.718Z,1513630501.718 [CBIT](IMPORTANT): Beginning ground fault scan 2017-12-18T20:55:06.163Z,1513630506.163 [NAL9602](INFO): Powering up NAL9602 2017-12-18T20:55:13.072Z,1513630513.072 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.003650 CHAN A1 (24V): 0.126680 CHAN A2 (12V): -0.001661 CHAN A3 (5V): -0.001292 CHAN B0 (3.3V): 0.000207 CHAN B1 (3.15aV): 0.000336 CHAN B2 (3.15bV): -0.000573 CHAN B3 (GND): 0.000126 OPEN: -0.000474 Full Scale Calc: 4.765 mA, -1.589 mA 2017-12-18T20:55:16.983Z,1513630516.983 [NAL9602](INFO): NAL9602 initialized 2017-12-18T20:55:50.956Z,1513630550.956 [NAL9602](INFO): SBD MO Status=2, MOMSN=3820, MT Status=2, MTMSN=0 2017-12-18T20:55:50.956Z,1513630550.956 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2017-12-18T20:55:55.804Z,1513630555.804 [SBIT](IMPORTANT): SBIT PASSED 2017-12-18T20:55:56.196Z,1513630556.196 [MissionManager](IMPORTANT): Started mission Startup 2017-12-18T20:55:56.197Z,1513630556.197 [Startup] Running Loop=1 2017-12-18T20:55:56.197Z,1513630556.197 [Startup](DEBUG): Aggregate::initialize Startup 2017-12-18T20:55:56.197Z,1513630556.197 [Startup:A.GoToSurface] Running Loop=1 2017-12-18T20:55:56.197Z,1513630556.197 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-12-18T20:55:56.198Z,1513630556.198 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-12-18T20:55:56.198Z,1513630556.198 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-12-18T20:55:56.198Z,1513630556.198 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-12-18T20:55:56.199Z,1513630556.199 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-12-18T20:55:56.199Z,1513630556.199 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-12-18T20:55:56.204Z,1513630556.204 [Startup:StartupSatComms] Running Loop=1 2017-12-18T20:55:56.205Z,1513630556.205 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2017-12-18T20:55:56.205Z,1513630556.205 [Startup:StartupSatComms:A] Running Loop=1 2017-12-18T20:55:56.596Z,1513630556.596 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2017-12-18T20:56:25.788Z,1513630585.788 [BPC1](ERROR): Failed to receive battery data 2017-12-18T20:56:25.788Z,1513630585.788 [BPC1] Communications Fault, FailCount= 1 2017-12-18T20:56:25.788Z,1513630585.788 [BPC1](ERROR): Communications Fault 2017-12-18T20:56:25.836Z,1513630585.836 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-18T20:56:27.026Z,1513630587.026 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T20:56:27.026Z,1513630587.026 [BPC1] No Fault, FailCount= 1 2017-12-18T20:56:39.341Z,1513630599.341 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-18T20:56:39.341Z,1513630599.341 [Rowe_600LCM] Communications Fault, FailCount= 1 2017-12-18T20:56:39.341Z,1513630599.341 [Rowe_600LCM](ERROR): Communications Fault 2017-12-18T20:56:39.431Z,1513630599.431 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-18T20:56:39.745Z,1513630599.745 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-18T20:56:40.014Z,1513630600.014 [Rowe_600LCM](INFO): Powering down 2017-12-18T20:56:41.430Z,1513630601.430 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-18T20:56:41.430Z,1513630601.430 [Rowe_600LCM] No Fault, FailCount= 1 2017-12-18T20:56:41.629Z,1513630601.629 [Rowe_600LCM](INFO): Initializing 2017-12-18T20:56:41.629Z,1513630601.629 [Rowe_600LCM](INFO): Checking LCM 2017-12-18T20:56:41.629Z,1513630601.629 [Rowe_600LCM](INFO): LCM OK 2017-12-18T20:56:41.630Z,1513630601.630 [Rowe_600LCM](INFO): Powering up 2017-12-18T20:56:45.838Z,1513630605.838 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-18T20:56:45.940Z,1513630605.940 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-18T20:56:45.941Z,1513630605.941 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-18T20:56:45.942Z,1513630605.942 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-18T20:56:45.942Z,1513630605.942 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-18T20:56:56.597Z,1513630616.597 [Startup:StartupSatComms:A](INFO): Timed out from 2017-12-18T20:55:56.2Z 2017-12-18T20:56:56.597Z,1513630616.597 [Startup:StartupSatComms:A] Stopped 2017-12-18T20:56:56.597Z,1513630616.597 [Startup:StartupSatComms:B] Running Loop=1 2017-12-18T20:56:56.948Z,1513630616.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=3820, MT Status=2, MTMSN=0 2017-12-18T20:56:56.948Z,1513630616.948 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2017-12-18T20:56:57.009Z,1513630617.009 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2017-12-18T20:57:04.806Z,1513630624.806 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003745 2017-12-18T20:57:09.109Z,1513630629.109 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171218T191827/Courier0040.lzma 2017-12-18T20:57:09.864Z,1513630629.864 [DataOverHttps](INFO): Moved sent file to Logs/20171218T191827/Courier0040.lzma.bak 2017-12-18T20:57:09.865Z,1513630629.865 [DataOverHttps](INFO): SBD MOMSN=5451980 2017-12-18T20:57:13.928Z,1513630633.928 [NAL9602](INFO): SBD MO Status=2, MOMSN=3820, MT Status=2, MTMSN=0 2017-12-18T20:57:13.928Z,1513630633.928 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2017-12-18T20:57:19.110Z,1513630639.110 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20171218T205423/Courier0000.lzma 2017-12-18T20:57:19.871Z,1513630639.871 [DataOverHttps](INFO): Moved sent file to Logs/20171218T205423/Courier0000.lzma.bak 2017-12-18T20:57:19.871Z,1513630639.871 [DataOverHttps](INFO): SBD MOMSN=5451982 2017-12-18T20:57:25.839Z,1513630645.839 [BPC1](ERROR): Failed to receive battery data 2017-12-18T20:57:25.839Z,1513630645.839 [BPC1] Communications Fault, FailCount= 1 2017-12-18T20:57:25.839Z,1513630645.839 [BPC1](ERROR): Communications Fault 2017-12-18T20:57:25.880Z,1513630645.880 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-18T20:57:29.968Z,1513630649.968 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T20:57:29.968Z,1513630649.968 [BPC1] No Fault, FailCount= 1 2017-12-18T20:57:30.044Z,1513630650.044 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171218T191827/Express0041.lzma 2017-12-18T20:57:30.792Z,1513630650.792 [DataOverHttps](INFO): Moved sent file to Logs/20171218T191827/Express0041.lzma.bak 2017-12-18T20:57:30.793Z,1513630650.793 [DataOverHttps](INFO): SBD MOMSN=5451986 2017-12-18T20:57:40.142Z,1513630660.142 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-18T20:57:40.142Z,1513630660.142 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2017-12-18T20:57:40.142Z,1513630660.142 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-18T20:57:40.144Z,1513630660.144 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-18T20:57:40.144Z,1513630660.144 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2017-12-18T20:57:40.144Z,1513630660.144 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2017-12-18T20:57:40.202Z,1513630660.202 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-18T20:57:40.202Z,1513630660.202 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2017-12-18T20:57:40.227Z,1513630660.227 [DataOverHttps](INFO): Sending 757 bytes from file Logs/20171218T205423/Express0001.lzma 2017-12-18T20:57:40.341Z,1513630660.341 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-18T20:57:40.341Z,1513630660.341 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2017-12-18T20:57:40.342Z,1513630660.342 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2017-12-18T20:57:40.342Z,1513630660.342 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2017-12-18T20:57:40.662Z,1513630660.662 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-18T20:57:40.663Z,1513630660.663 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-18T20:57:40.663Z,1513630660.663 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-18T20:57:40.663Z,1513630660.663 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-12-18T20:57:40.664Z,1513630660.664 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-12-18T20:57:40.664Z,1513630660.664 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-12-18T20:57:41.015Z,1513630661.015 [DataOverHttps](INFO): Moved sent file to Logs/20171218T205423/Express0001.lzma.bak 2017-12-18T20:57:41.015Z,1513630661.015 [DataOverHttps](INFO): SBD MOMSN=5451988 2017-12-18T20:57:41.784Z,1513630661.784 [Startup:StartupSatComms:B] Stopped 2017-12-18T20:57:41.784Z,1513630661.784 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2017-12-18T20:57:41.784Z,1513630661.784 [Startup:StartupSatComms] Stopped 2017-12-18T20:57:41.785Z,1513630661.785 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2017-12-18T20:57:41.786Z,1513630661.786 [Startup](INFO): Completed Startup 2017-12-18T20:57:41.786Z,1513630661.786 [MissionManager](INFO): Startup is completed. 2017-12-18T20:57:41.786Z,1513630661.786 [MissionManager](INFO): Uninitializing Mission Startup 2017-12-18T20:57:41.786Z,1513630661.786 [Startup] Stopped 2017-12-18T20:57:41.786Z,1513630661.786 [Startup](DEBUG): Aggregate::uninitialize Startup 2017-12-18T20:57:41.786Z,1513630661.786 [Startup:A.GoToSurface] Stopped 2017-12-18T20:57:41.786Z,1513630661.786 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-12-18T20:57:41.957Z,1513630661.957 [MissionManager](IMPORTANT): Started mission Default 2017-12-18T20:57:41.957Z,1513630661.957 [Default] Running Loop=1 2017-12-18T20:57:41.957Z,1513630661.957 [Default](DEBUG): Aggregate::initialize Default 2017-12-18T20:57:41.957Z,1513630661.957 [Default:B.GoToSurface] Running Loop=1 2017-12-18T20:57:41.958Z,1513630661.958 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-12-18T20:57:41.958Z,1513630661.958 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-12-18T20:57:41.958Z,1513630661.958 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-12-18T20:57:41.958Z,1513630661.958 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-12-18T20:57:41.959Z,1513630661.959 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-12-18T20:57:41.959Z,1513630661.959 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-12-18T20:57:41.959Z,1513630661.959 [Default:A.Wait] Running Loop=1 2017-12-18T20:57:41.959Z,1513630661.959 [Default:A.Wait](DEBUG): Initialize Wait Component. 2017-12-18T20:57:55.332Z,1513630675.332 [Default:A.Wait](INFO): Done Waiting. 2017-12-18T20:57:55.333Z,1513630675.333 [Default:A.Wait] Stopped 2017-12-18T20:57:55.333Z,1513630675.333 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2017-12-18T20:57:55.726Z,1513630675.726 [Default:CheckIn] Running Loop=1 2017-12-18T20:57:55.726Z,1513630675.726 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-12-18T20:57:55.726Z,1513630675.726 [Default:CheckIn:Read_GPS] Running Loop=1 2017-12-18T20:57:56.095Z,1513630676.095 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2017-12-18T20:58:14.480Z,1513630694.480 [NAL9602](INFO): SBD MO Status=2, MOMSN=3820, MT Status=2, MTMSN=0 2017-12-18T20:58:14.480Z,1513630694.480 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2017-12-18T20:58:25.302Z,1513630705.302 [BPC1](ERROR): Failed to receive battery data 2017-12-18T20:58:25.302Z,1513630705.302 [BPC1] Communications Fault, FailCount= 1 2017-12-18T20:58:25.302Z,1513630705.302 [BPC1](ERROR): Communications Fault 2017-12-18T20:58:25.358Z,1513630705.358 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-18T20:58:26.531Z,1513630706.531 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T20:58:26.531Z,1513630706.531 [BPC1] No Fault, FailCount= 1 2017-12-18T20:58:39.306Z,1513630719.306 [PNI_TCM](ERROR): CRC does not match. Expected:0x51646 got:0x6460 2017-12-18T20:59:06.316Z,1513630746.316 [NAL9602](INFO): SBD MO Status=2, MOMSN=3820, MT Status=2, MTMSN=0 2017-12-18T20:59:06.316Z,1513630746.316 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2017-12-18T20:59:19.982Z,1513630759.982 [BPC1](ERROR): Failed to receive battery data 2017-12-18T20:59:19.982Z,1513630759.982 [BPC1] Communications Fault, FailCount= 1 2017-12-18T20:59:19.982Z,1513630759.982 [BPC1](ERROR): Communications Fault 2017-12-18T20:59:20.001Z,1513630760.001 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-18T20:59:21.168Z,1513630761.168 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T20:59:21.168Z,1513630761.168 [BPC1] No Fault, FailCount= 1 2017-12-18T20:59:51.312Z,1513630791.312 [NAL9602](INFO): SBD MO Status=0, MOMSN=3820, MT Status=0, MTMSN=0 2017-12-18T20:59:51.312Z,1513630791.312 [NAL9602](INFO): No messages in MT queue 2017-12-18T20:59:52.504Z,1513630792.504 [NAL9602](INFO): GPS fix at 20171218T205951: (36.802711, -121.787544) 2017-12-18T20:59:52.580Z,1513630792.580 [Default:CheckIn:Read_GPS] Stopped 2017-12-18T20:59:52.580Z,1513630792.580 [Default:CheckIn:Read_Iridium] Running Loop=1 2017-12-18T20:59:52.969Z,1513630792.969 [DeadReckonWithRespectToSeafloor](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-18T20:59:52.969Z,1513630792.969 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2017-12-18T20:59:52.970Z,1513630792.970 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2017-12-18T20:59:52.972Z,1513630792.972 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2017-12-18T20:59:53.010Z,1513630793.010 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2017-12-18T20:59:53.537Z,1513630793.537 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2017-12-18T20:59:53.537Z,1513630793.537 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2017-12-18T20:59:53.783Z,1513630793.783 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-12-18T20:59:53.783Z,1513630793.783 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-12-18T20:59:53.784Z,1513630793.784 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-12-18T20:59:59.338Z,1513630799.338 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20171218T205423/Courier0004.lzma 2017-12-18T21:00:00.119Z,1513630800.119 [DataOverHttps](INFO): Moved sent file to Logs/20171218T205423/Courier0004.lzma.bak 2017-12-18T21:00:00.119Z,1513630800.119 [DataOverHttps](INFO): SBD MOMSN=5452022 2017-12-18T21:00:06.441Z,1513630806.441 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-18T21:00:06.441Z,1513630806.441 [Rowe_600LCM] Communications Fault, FailCount= 2 2017-12-18T21:00:06.441Z,1513630806.441 [Rowe_600LCM](ERROR): Communications Fault 2017-12-18T21:00:06.856Z,1513630806.856 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-18T21:00:07.250Z,1513630807.250 [Rowe_600LCM](INFO): Powering down 2017-12-18T21:00:14.455Z,1513630814.455 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-18T21:00:14.455Z,1513630814.455 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-18T21:00:14.455Z,1513630814.455 [Rowe_600LCM] No Fault, FailCount= 2 2017-12-18T21:00:14.499Z,1513630814.499 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20171218T205423/Express0005.lzma 2017-12-18T21:00:14.521Z,1513630814.521 [Rowe_600LCM](INFO): Initializing 2017-12-18T21:00:14.522Z,1513630814.522 [Rowe_600LCM](INFO): Checking LCM 2017-12-18T21:00:14.522Z,1513630814.522 [Rowe_600LCM](INFO): LCM OK 2017-12-18T21:00:14.522Z,1513630814.522 [Rowe_600LCM](INFO): Powering up 2017-12-18T21:00:15.271Z,1513630815.271 [DataOverHttps](INFO): Moved sent file to Logs/20171218T205423/Express0005.lzma.bak 2017-12-18T21:00:15.271Z,1513630815.271 [DataOverHttps](INFO): SBD MOMSN=5452025 2017-12-18T21:00:16.559Z,1513630816.559 [Default:CheckIn:Read_Iridium] Stopped 2017-12-18T21:00:16.559Z,1513630816.559 [Default:CheckIn:C.Wait] Running Loop=1 2017-12-18T21:00:16.559Z,1513630816.559 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2017-12-18T21:00:18.802Z,1513630818.802 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-18T21:00:18.948Z,1513630818.948 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-18T21:00:18.949Z,1513630818.949 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-18T21:00:18.950Z,1513630818.950 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-18T21:00:18.950Z,1513630818.950 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-18T21:00:22.659Z,1513630822.659 [BPC1](ERROR): Failed to receive battery data 2017-12-18T21:00:22.659Z,1513630822.659 [BPC1] Communications Fault, FailCount= 1 2017-12-18T21:00:22.659Z,1513630822.659 [BPC1](ERROR): Communications Fault 2017-12-18T21:00:22.722Z,1513630822.722 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-18T21:00:23.635Z,1513630823.635 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T21:00:23.635Z,1513630823.635 [BPC1] No Fault, FailCount= 1 2017-12-18T21:00:29.546Z,1513630829.546 [NAL9602](INFO): Powering down 2017-12-18T21:00:53.166Z,1513630853.166 [BPC1](ERROR): Failed to parse bank B battery data 2017-12-18T21:00:53.166Z,1513630853.166 [BPC1] Data Fault, FailCount= 1 2017-12-18T21:00:53.167Z,1513630853.167 [BPC1](ERROR): Data Fault 2017-12-18T21:00:53.225Z,1513630853.225 [CBIT](ERROR): Data Fault in component: BPC1 2017-12-18T21:00:54.423Z,1513630854.423 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T21:00:54.423Z,1513630854.423 [BPC1] No Fault, FailCount= 1 2017-12-18T21:01:15.180Z,1513630875.180 [BPC1](ERROR): Failed to receive battery data 2017-12-18T21:01:15.180Z,1513630875.180 [BPC1] Communications Fault, FailCount= 2 2017-12-18T21:01:15.180Z,1513630875.180 [BPC1](ERROR): Communications Fault 2017-12-18T21:01:15.218Z,1513630875.218 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-18T21:01:16.410Z,1513630876.410 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T21:01:16.410Z,1513630876.410 [BPC1] No Fault, FailCount= 2 2017-12-18T21:01:26.352Z,1513630886.352 [PNI_TCM](ERROR): CRC does not match. Expected:0x25467 got:0x33317 2017-12-18T21:01:32.752Z,1513630892.752 [PNI_TCM](ERROR): CRC does not match. Expected:0x48771 got:0x1287 2017-12-18T21:01:37.147Z,1513630897.147 [BPC1](ERROR): Failed to receive battery data 2017-12-18T21:01:37.147Z,1513630897.147 [BPC1] Communications Fault, FailCount= 3 2017-12-18T21:01:37.148Z,1513630897.148 [BPC1](ERROR): Communications Fault 2017-12-18T21:01:37.190Z,1513630897.190 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-18T21:01:38.380Z,1513630898.380 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T21:01:38.380Z,1513630898.380 [BPC1] No Fault, FailCount= 3 2017-12-18T21:02:14.861Z,1513630934.861 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-18T21:02:14.861Z,1513630934.861 [Rowe_600LCM] Communications Fault, FailCount= 3 2017-12-18T21:02:14.861Z,1513630934.861 [Rowe_600LCM](ERROR): Communications Fault 2017-12-18T21:02:15.176Z,1513630935.176 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-18T21:02:15.265Z,1513630935.265 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-18T21:02:15.359Z,1513630935.359 [Rowe_600LCM](INFO): Powering down 2017-12-18T21:02:16.775Z,1513630936.775 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-18T21:02:16.775Z,1513630936.775 [Rowe_600LCM] No Fault, FailCount= 3 2017-12-18T21:02:16.973Z,1513630936.973 [Rowe_600LCM](INFO): Initializing 2017-12-18T21:02:16.973Z,1513630936.973 [Rowe_600LCM](INFO): Checking LCM 2017-12-18T21:02:16.974Z,1513630936.974 [Rowe_600LCM](INFO): LCM OK 2017-12-18T21:02:16.974Z,1513630936.974 [Rowe_600LCM](INFO): Powering up 2017-12-18T21:02:20.929Z,1513630940.929 [BPC1](ERROR): Failed to receive battery data 2017-12-18T21:02:20.929Z,1513630940.929 [BPC1] Communications Fault, FailCount= 1 2017-12-18T21:02:20.929Z,1513630940.929 [BPC1](ERROR): Communications Fault 2017-12-18T21:02:20.986Z,1513630940.986 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-18T21:02:21.220Z,1513630941.220 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-18T21:02:21.312Z,1513630941.312 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-18T21:02:21.313Z,1513630941.313 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-18T21:02:21.313Z,1513630941.313 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-18T21:02:21.314Z,1513630941.314 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-18T21:02:21.912Z,1513630941.912 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-18T21:02:21.912Z,1513630941.912 [BPC1] No Fault, FailCount= 1 2017-12-18T21:02:53.900Z,1513630973.900 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estima