2018-08-10T22:25:37.378Z,1533939937.378 [Supervisor](DEBUG): Initializing supervisor.
2018-08-10T22:25:37.393Z,1533939937.393 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-08-10T22:25:37.393Z,1533939937.393 [SyncHandler](INFO): Protected caller Thread ID is 798
2018-08-10T22:25:37.394Z,1533939937.394 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-08-10T22:25:37.413Z,1533939937.413 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-08-10T22:25:37.413Z,1533939937.413 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 799
2018-08-10T22:25:37.416Z,1533939937.416 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-08-10T22:25:37.441Z,1533939937.441 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-08-10T22:25:37.453Z,1533939937.453 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-08-10T22:25:37.453Z,1533939937.453 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 800
2018-08-10T22:25:37.454Z,1533939937.454 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-08-10T22:25:37.465Z,1533939937.465 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-08-10T22:25:37.465Z,1533939937.465 [logger ThreadHandler](INFO): Protected caller Thread ID is 801
2018-08-10T22:25:37.467Z,1533939937.467 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-08-10T22:25:37.468Z,1533939937.468 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-08-10T22:25:37.472Z,1533939937.472 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-08-10T22:25:38.228Z,1533939938.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-08-10T22:25:38.228Z,1533939938.228 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-08-10T22:25:38.643Z,1533939938.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-08-10T22:25:38.645Z,1533939938.645 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-08-10T22:25:39.245Z,1533939939.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-08-10T22:25:39.247Z,1533939939.247 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-08-10T22:25:39.526Z,1533939939.526 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-08-10T22:25:39.528Z,1533939939.528 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-08-10T22:25:39.712Z,1533939939.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-08-10T22:25:39.713Z,1533939939.713 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-08-10T22:25:40.679Z,1533939940.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-08-10T22:25:40.680Z,1533939940.680 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-08-10T22:25:41.273Z,1533939941.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-08-10T22:25:41.274Z,1533939941.274 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-08-10T22:25:41.764Z,1533939941.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-08-10T22:25:41.766Z,1533939941.766 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-08-10T22:25:41.875Z,1533939941.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-08-10T22:25:41.877Z,1533939941.877 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-08-10T22:25:41.977Z,1533939941.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-08-10T22:25:41.978Z,1533939941.978 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-08-10T22:25:42.197Z,1533939942.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-08-10T22:25:42.198Z,1533939942.198 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-08-10T22:25:42.421Z,1533939942.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-08-10T22:25:42.421Z,1533939942.421 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-08-10T22:25:42.795Z,1533939942.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-08-10T22:25:42.795Z,1533939942.795 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-08-10T22:25:42.944Z,1533939942.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-08-10T22:25:42.945Z,1533939942.945 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-08-10T22:25:43.029Z,1533939943.029 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-08-10T22:25:43.033Z,1533939943.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-08-10T22:25:43.141Z,1533939943.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-08-10T22:25:43.267Z,1533939943.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-08-10T22:25:43.365Z,1533939943.365 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-08-10T22:25:43.453Z,1533939943.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-08-10T22:25:43.620Z,1533939943.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-08-10T22:25:43.811Z,1533939943.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-08-10T22:25:43.888Z,1533939943.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-08-10T22:25:44.127Z,1533939944.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-08-10T22:25:44.128Z,1533939944.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-08-10T22:25:44.214Z,1533939944.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-08-10T22:25:44.307Z,1533939944.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-08-10T22:25:44.414Z,1533939944.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-08-10T22:25:44.549Z,1533939944.549 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-08-10T22:25:44.558Z,1533939944.558 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-08-10T22:25:44.839Z,1533939944.839 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-08-10T22:25:44.840Z,1533939944.840 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-08-10T22:25:44.897Z,1533939944.897 [DepthRateCalculator] Loaded
2018-08-10T22:25:44.897Z,1533939944.897 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-08-10T22:25:44.903Z,1533939944.903 [PitchRateCalculator] Loaded
2018-08-10T22:25:44.903Z,1533939944.903 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-08-10T22:25:44.920Z,1533939944.920 [SpeedCalculator] Loaded
2018-08-10T22:25:44.920Z,1533939944.920 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-08-10T22:25:44.943Z,1533939944.943 [TempGradientCalculator] Loaded
2018-08-10T22:25:44.943Z,1533939944.943 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-08-10T22:25:44.960Z,1533939944.960 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-08-10T22:25:44.960Z,1533939944.960 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-08-10T22:25:44.966Z,1533939944.966 [YawRateCalculator] Loaded
2018-08-10T22:25:44.966Z,1533939944.966 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-08-10T22:25:45.007Z,1533939945.007 [ElevatorOffsetCalculator] Loaded
2018-08-10T22:25:45.008Z,1533939945.008 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-08-10T22:25:45.008Z,1533939945.008 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-08-10T22:25:45.010Z,1533939945.010 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-08-10T22:25:45.133Z,1533939945.133 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-08-10T22:25:45.133Z,1533939945.133 [StratificationFrontDetector](DEBUG): (re)initializing
2018-08-10T22:25:45.133Z,1533939945.133 [StratificationFrontDetector] Loaded
2018-08-10T22:25:45.133Z,1533939945.133 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-08-10T22:25:45.134Z,1533939945.134 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-08-10T22:25:45.135Z,1533939945.135 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-08-10T22:25:45.271Z,1533939945.271 [BuoyancyServo] Loaded
2018-08-10T22:25:45.271Z,1533939945.271 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-08-10T22:25:45.287Z,1533939945.287 [ElevatorServo] Loaded
2018-08-10T22:25:45.287Z,1533939945.287 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-08-10T22:25:45.303Z,1533939945.303 [MassServo] Loaded
2018-08-10T22:25:45.303Z,1533939945.303 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-08-10T22:25:45.319Z,1533939945.319 [RudderServo] Loaded
2018-08-10T22:25:45.319Z,1533939945.319 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-08-10T22:25:45.334Z,1533939945.334 [ThrusterServo] Loaded
2018-08-10T22:25:45.335Z,1533939945.335 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-08-10T22:25:45.335Z,1533939945.335 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-08-10T22:25:45.337Z,1533939945.337 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-08-10T22:25:45.361Z,1533939945.361 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-08-10T22:25:45.362Z,1533939945.362 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-08-10T22:25:45.487Z,1533939945.487 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-08-10T22:25:45.488Z,1533939945.488 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-08-10T22:25:46.054Z,1533939946.054 [DataOverHttps] Loaded
2018-08-10T22:25:46.054Z,1533939946.054 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-08-10T22:25:46.068Z,1533939946.068 [Depth_Keller] Loaded
2018-08-10T22:25:46.068Z,1533939946.068 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-08-10T22:25:46.074Z,1533939946.074 [DropWeight] Loaded
2018-08-10T22:25:46.074Z,1533939946.074 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-08-10T22:25:46.176Z,1533939946.176 [NAL9602] Loaded
2018-08-10T22:25:46.177Z,1533939946.177 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-08-10T22:25:46.194Z,1533939946.194 [Onboard] Loaded
2018-08-10T22:25:46.194Z,1533939946.194 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-08-10T22:25:46.205Z,1533939946.205 [Radio_Surface] Loaded
2018-08-10T22:25:46.205Z,1533939946.205 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-08-10T22:25:46.206Z,1533939946.206 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408854E0
2018-08-10T22:25:46.207Z,1533939946.207 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883
2018-08-10T22:25:46.361Z,1533939946.361 [PNI_TCM] Loaded
2018-08-10T22:25:46.362Z,1533939946.362 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-08-10T22:25:46.425Z,1533939946.425 [Rowe_600LCM] Loaded
2018-08-10T22:25:46.425Z,1533939946.425 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-08-10T22:25:46.426Z,1533939946.426 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0
2018-08-10T22:25:46.426Z,1533939946.426 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 884
2018-08-10T22:25:48.226Z,1533939948.226 [BPC1] Loaded
2018-08-10T22:25:48.227Z,1533939948.227 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-08-10T22:25:48.227Z,1533939948.227 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-08-10T22:25:48.229Z,1533939948.229 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-08-10T22:25:48.781Z,1533939948.781 [SBIT](DEBUG): Construct Startup Built In Test.
2018-08-10T22:25:48.804Z,1533939948.804 [SBIT] Loaded
2018-08-10T22:25:48.805Z,1533939948.805 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-08-10T22:25:48.806Z,1533939948.806 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-08-10T22:25:48.818Z,1533939948.818 [IBIT] Loaded
2018-08-10T22:25:48.818Z,1533939948.818 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-08-10T22:25:48.821Z,1533939948.821 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-08-10T22:25:49.127Z,1533939949.127 [CBIT] Loaded
2018-08-10T22:25:49.127Z,1533939949.127 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-08-10T22:25:49.127Z,1533939949.127 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-08-10T22:25:49.357Z,1533939949.357 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-08-10T22:25:49.555Z,1533939949.555 [Aanderaa_O2] Loaded
2018-08-10T22:25:49.555Z,1533939949.555 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-08-10T22:25:49.571Z,1533939949.571 [PAR_Licor] Loaded
2018-08-10T22:25:49.571Z,1533939949.571 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-08-10T22:25:49.622Z,1533939949.622 [WetLabsBB2FL] Loaded
2018-08-10T22:25:49.623Z,1533939949.623 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-08-10T22:25:49.624Z,1533939949.624 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409784E0
2018-08-10T22:25:49.624Z,1533939949.624 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 885
2018-08-10T22:25:49.670Z,1533939949.670 [WetLabsSeaOWL_UV_A] Loaded
2018-08-10T22:25:49.670Z,1533939949.670 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-08-10T22:25:49.671Z,1533939949.671 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409A84E0
2018-08-10T22:25:49.671Z,1533939949.671 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 886
2018-08-10T22:25:49.672Z,1533939949.672 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-08-10T22:25:49.673Z,1533939949.673 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-08-10T22:25:49.762Z,1533939949.762 [VerticalControl](DEBUG): Construct VerticalControl.
2018-08-10T22:25:49.850Z,1533939949.850 [VerticalControl] Loaded
2018-08-10T22:25:49.851Z,1533939949.851 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-08-10T22:25:49.851Z,1533939949.851 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-08-10T22:25:49.912Z,1533939949.912 [HorizontalControl] Loaded
2018-08-10T22:25:49.912Z,1533939949.912 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-08-10T22:25:49.913Z,1533939949.913 [SpeedControl](DEBUG): Construct SpeedControl.
2018-08-10T22:25:49.915Z,1533939949.915 [SpeedControl] Loaded
2018-08-10T22:25:49.915Z,1533939949.915 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-08-10T22:25:49.916Z,1533939949.916 [LoopControl](DEBUG): Construct LoopControl.
2018-08-10T22:25:49.917Z,1533939949.917 [LoopControl] Loaded
2018-08-10T22:25:49.917Z,1533939949.917 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-08-10T22:25:49.918Z,1533939949.918 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-08-10T22:25:49.918Z,1533939949.918 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-08-10T22:25:49.956Z,1533939949.956 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-08-10T22:25:49.958Z,1533939949.958 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-08-10T22:25:50.070Z,1533939950.070 [DeadReckonUsingSpeedCalculator] Loaded
2018-08-10T22:25:50.071Z,1533939950.071 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-08-10T22:25:50.088Z,1533939950.088 [NavChart] Loaded
2018-08-10T22:25:50.088Z,1533939950.088 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-08-10T22:25:50.092Z,1533939950.092 [UniversalFixResidualReporter] Loaded
2018-08-10T22:25:50.093Z,1533939950.093 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-08-10T22:25:50.093Z,1533939950.093 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-08-10T22:25:50.097Z,1533939950.097 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-08-10T22:25:50.098Z,1533939950.098 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-08-10T22:25:50.105Z,1533939950.105 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-08-10T22:25:50.106Z,1533939950.106 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A344E0
2018-08-10T22:25:50.107Z,1533939950.107 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 887
2018-08-10T22:25:50.112Z,1533939950.112 [Supervisor](INFO): Main Thread ID is 797
2018-08-10T22:25:50.112Z,1533939950.112 [Supervisor](DEBUG): Running supervisor.
2018-08-10T22:25:50.112Z,1533939950.112 [CommandLine ThreadHandler](INFO): Handler Thread ID is 888
2018-08-10T22:25:50.115Z,1533939950.115 [controlThread ThreadHandler](INFO): Handler Thread ID is 889
2018-08-10T22:25:50.115Z,1533939950.115 [controlThread](DEBUG): Initializing ControlThread
2018-08-10T22:25:50.116Z,1533939950.116 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-10T22:25:50.116Z,1533939950.116 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-08-10T22:25:50.117Z,1533939950.117 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-08-10T22:25:50.117Z,1533939950.117 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-08-10T22:25:50.118Z,1533939950.118 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-08-10T22:25:50.119Z,1533939950.119 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-08-10T22:25:50.119Z,1533939950.119 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-08-10T22:25:50.127Z,1533939950.127 [SBIT](INFO): Initialize SBIT Component.
2018-08-10T22:25:50.128Z,1533939950.128 [SBIT](IMPORTANT): git: 2018-08-02-5-g06aa788
2018-08-10T22:25:50.128Z,1533939950.128 [SBIT](INFO): git hash: 06aa78889e7acde3510ba544c85e929ba2172e86
2018-08-10T22:25:50.128Z,1533939950.128 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-08-10T22:25:50.130Z,1533939950.130 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-08-10T22:25:50.131Z,1533939950.131 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-08-10T22:25:50.131Z,1533939950.131 [IBIT](INFO): Initialize IBIT Component.
2018-08-10T22:25:50.132Z,1533939950.132 [CBIT](DEBUG): Initialize CBIT Component.
2018-08-10T22:25:50.133Z,1533939950.133 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-08-10T22:25:50.133Z,1533939950.133 [logger ThreadHandler](INFO): Handler Thread ID is 890
2018-08-10T22:25:50.153Z,1533939950.153 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 891
2018-08-10T22:25:50.160Z,1533939950.160 [Radio_Surface](INFO): Powering up
2018-08-10T22:25:50.165Z,1533939950.165 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 892
2018-08-10T22:25:50.166Z,1533939950.166 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:25:50.166Z,1533939950.166 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:25:50.245Z,1533939950.245 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-08-10T22:25:50.247Z,1533939950.247 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-08-10T22:25:50.248Z,1533939950.248 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-08-10T22:25:50.248Z,1533939950.248 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-08-10T22:25:50.265Z,1533939950.265 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:25:50.265Z,1533939950.265 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:25:50.266Z,1533939950.266 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:25:50.266Z,1533939950.266 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-08-10T22:25:50.267Z,1533939950.267 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-08-10T22:25:50.268Z,1533939950.268 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-08-10T22:25:50.278Z,1533939950.278 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 893
2018-08-10T22:25:50.280Z,1533939950.280 [WetLabsBB2FL](INFO): Powering down
2018-08-10T22:25:50.406Z,1533939950.406 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 894
2018-08-10T22:25:50.409Z,1533939950.409 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 895
2018-08-10T22:25:50.420Z,1533939950.420 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-08-10T22:25:50.421Z,1533939950.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-08-10T22:25:50.421Z,1533939950.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-08-10T22:25:50.421Z,1533939950.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-08-10T22:25:50.421Z,1533939950.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-08-10T22:25:50.421Z,1533939950.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-08-10T22:25:50.422Z,1533939950.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-08-10T22:25:50.422Z,1533939950.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-08-10T22:25:50.422Z,1533939950.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-08-10T22:25:50.422Z,1533939950.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-08-10T22:25:50.422Z,1533939950.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-08-10T22:25:50.423Z,1533939950.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-08-10T22:25:50.423Z,1533939950.423 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-08-10T22:25:50.423Z,1533939950.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-08-10T22:25:50.423Z,1533939950.423 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-08-10T22:25:50.423Z,1533939950.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-08-10T22:25:50.541Z,1533939950.541 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-10T22:25:50.614Z,1533939950.614 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2018-08-10T22:25:50.615Z,1533939950.615 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-08-10T22:25:50.636Z,1533939950.636 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:25:50.636Z,1533939950.636 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:25:50.697Z,1533939950.697 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-08-10T22:25:50.699Z,1533939950.699 [Default:A.Wait](DEBUG): Construct Wait.
2018-08-10T22:25:50.717Z,1533939950.717 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-10T22:25:50.763Z,1533939950.763 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-08-10T22:25:50.766Z,1533939950.766 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-08-10T22:25:50.780Z,1533939950.780 [Default:E.Execute](DEBUG): Construct Execute.
2018-08-10T22:25:50.784Z,1533939950.784 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2018-08-10T22:25:50.797Z,1533939950.797 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-08-10T22:25:50.821Z,1533939950.821 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-08-10T22:25:50.834Z,1533939950.834 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-08-10T22:25:51.221Z,1533939951.221 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-08-10T22:25:51.333Z,1533939951.333 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-10T22:25:51.541Z,1533939951.541 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-10T22:25:51.549Z,1533939951.549 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-10T22:25:51.564Z,1533939951.564 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-08-10T22:25:51.569Z,1533939951.569 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-08-10T22:25:51.587Z,1533939951.587 [MassServo](DEBUG): Initializing EZServoServo.
2018-08-10T22:25:51.593Z,1533939951.593 [MassServo](DEBUG): Initializing MassServo.
2018-08-10T22:25:51.607Z,1533939951.607 [RudderServo](DEBUG): Initializing EZServoServo.
2018-08-10T22:25:51.613Z,1533939951.613 [RudderServo](DEBUG): Initializing RudderServo.
2018-08-10T22:25:51.635Z,1533939951.635 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-08-10T22:25:51.641Z,1533939951.641 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-08-10T22:25:51.939Z,1533939951.939 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-10T22:25:52.182Z,1533939952.182 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-10T22:25:52.529Z,1533939952.529 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-08-10T22:25:52.529Z,1533939952.529 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-08-10T22:25:52.529Z,1533939952.529 [BuoyancyServo] Communications Fault, FailCount= 1
2018-08-10T22:25:52.529Z,1533939952.529 [BuoyancyServo](ERROR): Communications Fault
2018-08-10T22:25:52.741Z,1533939952.741 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-08-10T22:25:52.799Z,1533939952.799 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-10T22:25:52.818Z,1533939952.818 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-10T22:25:52.819Z,1533939952.819 [BuoyancyServo](INFO): Powering down
2018-08-10T22:25:53.197Z,1533939953.197 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-10T22:25:53.611Z,1533939953.611 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-10T22:25:54.017Z,1533939954.017 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-10T22:25:54.391Z,1533939954.391 [Aanderaa_O2](INFO): Powering down
2018-08-10T22:25:54.562Z,1533939954.562 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-10T22:25:54.709Z,1533939954.709 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:25:54.833Z,1533939954.833 [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 9600 >& /dev/null &
2018-08-10T22:25:55.019Z,1533939955.019 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-08-10T22:25:55.019Z,1533939955.019 [BuoyancyServo] No Fault, FailCount= 1
2018-08-10T22:25:55.061Z,1533939955.061 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:25:55.062Z,1533939955.062 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:25:55.062Z,1533939955.062 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:25:55.275Z,1533939955.275 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-10T22:25:55.275Z,1533939955.275 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-10T22:26:13.663Z,1533939973.663 [SBIT](IMPORTANT): Beginning Startup BIT
2018-08-10T22:26:13.680Z,1533939973.680 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-10T22:26:16.540Z,1533939976.540 [NAL9602](INFO): Powering up NAL9602
2018-08-10T22:26:24.302Z,1533939984.302 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): -0.020086
CHAN A2 (12V): -0.002032
CHAN A3 (5V): -0.001135
CHAN B0 (3.3V): -0.000455
CHAN B1 (3.15aV): -0.000255
CHAN B2 (3.15bV): 4.767929
CHAN B3 (GND): 0.000171
OPEN: -0.000606
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-10T22:26:27.739Z,1533939987.739 [NAL9602](INFO): NAL9602 initialized
2018-08-10T22:27:07.955Z,1533940027.955 [SBIT](IMPORTANT): SBIT PASSED
2018-08-10T22:27:08.042Z,1533940028.042 [CommandLine](IMPORTANT): got command configSet list
2018-08-10T22:27:08.043Z,1533940028.043 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-08-10T22:27:08.044Z,1533940028.044 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2018-08-10T22:27:08.044Z,1533940028.044 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-08-10T22:27:08.294Z,1533940028.294 [MissionManager](IMPORTANT): Started mission Startup
2018-08-10T22:27:08.294Z,1533940028.294 [Startup] Running Loop=1
2018-08-10T22:27:08.295Z,1533940028.295 [Startup](DEBUG): Aggregate::initialize Startup
2018-08-10T22:27:08.295Z,1533940028.295 [Startup:A.GoToSurface] Running Loop=1
2018-08-10T22:27:08.295Z,1533940028.295 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-10T22:27:08.296Z,1533940028.296 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-10T22:27:08.297Z,1533940028.297 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-10T22:27:08.297Z,1533940028.297 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-10T22:27:08.298Z,1533940028.298 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-10T22:27:08.298Z,1533940028.298 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-10T22:27:08.319Z,1533940028.319 [Startup:StartupSatComms] Running Loop=1
2018-08-10T22:27:08.319Z,1533940028.319 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-08-10T22:27:08.320Z,1533940028.320 [Startup:StartupSatComms:A] Running Loop=1
2018-08-10T22:27:08.685Z,1533940028.685 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-08-10T22:27:25.016Z,1533940045.016 [BPC1](ERROR): Failed to parse Bank B battery data
2018-08-10T22:27:51.361Z,1533940071.361 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:27:51.361Z,1533940071.361 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-10T22:27:51.361Z,1533940071.361 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:27:51.483Z,1533940071.483 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:27:51.773Z,1533940071.773 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:27:52.077Z,1533940072.077 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:27:52.885Z,1533940072.885 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-08-10T22:27:52.885Z,1533940072.885 [Rowe_600LCM] Hardware Fault, FailCount= 1
2018-08-10T22:27:52.885Z,1533940072.885 [Rowe_600LCM](ERROR): Hardware Fault
2018-08-10T22:27:53.446Z,1533940073.446 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:27:53.446Z,1533940073.446 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-10T22:27:53.693Z,1533940073.693 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:27:53.694Z,1533940073.694 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:27:53.694Z,1533940073.694 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:27:53.694Z,1533940073.694 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:27:57.945Z,1533940077.945 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:27:58.012Z,1533940078.012 [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 9600 >& /dev/null &
2018-08-10T22:27:58.013Z,1533940078.013 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:27:58.014Z,1533940078.014 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:27:58.015Z,1533940078.015 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:28:08.635Z,1533940088.635 [Startup:StartupSatComms:A](INFO): Timed out from 2018-08-10T22:27:08.3Z
2018-08-10T22:28:08.635Z,1533940088.635 [Startup:StartupSatComms:A] Stopped
2018-08-10T22:28:08.635Z,1533940088.635 [Startup:StartupSatComms:B] Running Loop=1
2018-08-10T22:28:09.031Z,1533940089.031 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-10T22:28:19.060Z,1533940099.060 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008447
2018-08-10T22:28:23.590Z,1533940103.590 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180810T220448/Courier0004.lzma
2018-08-10T22:28:24.388Z,1533940104.388 [DataOverHttps](INFO): Moved sent file to Logs/20180810T220448/Courier0004.lzma.bak
2018-08-10T22:28:24.388Z,1533940104.388 [DataOverHttps](INFO): SBD MOMSN=8411996
2018-08-10T22:28:34.073Z,1533940114.073 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180810T220448/Courier0007.lzma
2018-08-10T22:28:34.864Z,1533940114.864 [DataOverHttps](INFO): Moved sent file to Logs/20180810T220448/Courier0007.lzma.bak
2018-08-10T22:28:34.864Z,1533940114.864 [DataOverHttps](INFO): SBD MOMSN=8411998
2018-08-10T22:28:44.612Z,1533940124.612 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20180810T220448/Express0005.lzma
2018-08-10T22:28:45.400Z,1533940125.400 [DataOverHttps](INFO): Moved sent file to Logs/20180810T220448/Express0005.lzma.bak
2018-08-10T22:28:45.400Z,1533940125.400 [DataOverHttps](INFO): SBD MOMSN=8412000
2018-08-10T22:28:50.329Z,1533940130.329 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:28:50.329Z,1533940130.329 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-08-10T22:28:50.329Z,1533940130.329 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:28:50.344Z,1533940130.344 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:28:50.772Z,1533940130.772 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:28:50.772Z,1533940130.772 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-08-10T22:28:51.140Z,1533940131.140 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:28:51.145Z,1533940131.145 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:28:51.145Z,1533940131.145 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:28:55.109Z,1533940135.109 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180810T220448/Express0008.lzma
2018-08-10T22:28:55.888Z,1533940135.888 [DataOverHttps](INFO): Moved sent file to Logs/20180810T220448/Express0008.lzma.bak
2018-08-10T22:28:55.888Z,1533940135.888 [DataOverHttps](INFO): SBD MOMSN=8412007
2018-08-10T22:29:06.234Z,1533940146.234 [DataOverHttps](INFO): Sending 770 bytes from file Logs/20180810T222537/Express0001.lzma
2018-08-10T22:29:07.032Z,1533940147.032 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Express0001.lzma.bak
2018-08-10T22:29:07.032Z,1533940147.032 [DataOverHttps](INFO): SBD MOMSN=8412009
2018-08-10T22:29:07.758Z,1533940147.758 [Startup:StartupSatComms:B] Stopped
2018-08-10T22:29:07.758Z,1533940147.758 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-08-10T22:29:07.758Z,1533940147.758 [Startup:StartupSatComms] Stopped
2018-08-10T22:29:07.758Z,1533940147.758 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-08-10T22:29:07.759Z,1533940147.759 [Startup](INFO): Completed Startup
2018-08-10T22:29:07.759Z,1533940147.759 [MissionManager](INFO): Startup is completed.
2018-08-10T22:29:07.760Z,1533940147.760 [MissionManager](INFO): Uninitializing Mission Startup
2018-08-10T22:29:07.760Z,1533940147.760 [Startup] Stopped
2018-08-10T22:29:07.760Z,1533940147.760 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-08-10T22:29:07.760Z,1533940147.760 [Startup:A.GoToSurface] Stopped
2018-08-10T22:29:07.760Z,1533940147.760 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-10T22:29:07.916Z,1533940147.916 [MissionManager](IMPORTANT): Started mission Default
2018-08-10T22:29:07.916Z,1533940147.916 [Default] Running Loop=1
2018-08-10T22:29:07.916Z,1533940147.916 [Default](DEBUG): Aggregate::initialize Default
2018-08-10T22:29:07.916Z,1533940147.916 [Default:B.GoToSurface] Running Loop=1
2018-08-10T22:29:07.916Z,1533940147.916 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-10T22:29:07.921Z,1533940147.921 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-10T22:29:07.921Z,1533940147.921 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-10T22:29:07.922Z,1533940147.922 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-10T22:29:07.922Z,1533940147.922 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-10T22:29:07.923Z,1533940147.923 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-10T22:29:07.923Z,1533940147.923 [Default:A.Wait] Running Loop=1
2018-08-10T22:29:07.923Z,1533940147.923 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-08-10T22:29:21.167Z,1533940161.167 [Default:A.Wait](INFO): Done Waiting.
2018-08-10T22:29:21.168Z,1533940161.168 [Default:A.Wait] Stopped
2018-08-10T22:29:21.168Z,1533940161.168 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-08-10T22:29:21.580Z,1533940161.580 [Default:CheckIn] Running Loop=1
2018-08-10T22:29:21.580Z,1533940161.580 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-10T22:29:21.585Z,1533940161.585 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-10T22:29:21.959Z,1533940161.959 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-08-10T22:29:54.293Z,1533940194.293 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:29:54.293Z,1533940194.293 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-10T22:29:54.293Z,1533940194.293 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:29:54.386Z,1533940194.386 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:29:54.697Z,1533940194.697 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:29:54.949Z,1533940194.949 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:29:56.368Z,1533940196.368 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:29:56.368Z,1533940196.368 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-10T22:29:56.565Z,1533940196.565 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:29:56.565Z,1533940196.565 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:29:56.566Z,1533940196.566 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:29:56.566Z,1533940196.566 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:30:00.687Z,1533940200.687 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:30:00.859Z,1533940200.859 [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 9600 >& /dev/null &
2018-08-10T22:30:00.860Z,1533940200.860 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:30:00.861Z,1533940200.861 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:30:00.862Z,1533940200.862 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:31:31.975Z,1533940291.975 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-08-10T22:31:51.250Z,1533940311.250 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:31:51.250Z,1533940311.250 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-08-10T22:31:51.250Z,1533940311.250 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:31:51.278Z,1533940311.278 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:31:51.659Z,1533940311.659 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:31:51.659Z,1533940311.659 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-08-10T22:31:52.049Z,1533940312.049 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:31:52.049Z,1533940312.049 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:31:52.050Z,1533940312.050 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:31:57.313Z,1533940317.313 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:31:57.313Z,1533940317.313 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-10T22:31:57.313Z,1533940317.313 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:31:57.686Z,1533940317.686 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:31:57.717Z,1533940317.717 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:31:57.847Z,1533940317.847 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:31:59.250Z,1533940319.250 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:31:59.251Z,1533940319.251 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-10T22:31:59.461Z,1533940319.461 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:31:59.461Z,1533940319.461 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:31:59.462Z,1533940319.462 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:31:59.462Z,1533940319.462 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:32:03.627Z,1533940323.627 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:32:03.780Z,1533940323.780 [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 9600 >& /dev/null &
2018-08-10T22:32:03.781Z,1533940323.781 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:32:03.782Z,1533940323.782 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:32:03.783Z,1533940323.783 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:34:00.257Z,1533940440.257 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:34:00.257Z,1533940440.257 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-10T22:34:00.257Z,1533940440.257 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:34:00.633Z,1533940440.633 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:34:00.661Z,1533940440.661 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:34:00.810Z,1533940440.810 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:34:02.246Z,1533940442.246 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:34:02.246Z,1533940442.246 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-10T22:34:02.426Z,1533940442.426 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:34:02.427Z,1533940442.427 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:34:02.427Z,1533940442.427 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:34:02.427Z,1533940442.427 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:34:06.616Z,1533940446.616 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:34:06.736Z,1533940446.736 [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 9600 >& /dev/null &
2018-08-10T22:34:06.737Z,1533940446.737 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:34:06.738Z,1533940446.738 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:34:06.738Z,1533940446.738 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:34:21.779Z,1533940461.779 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-10T22:29:21.6Z
2018-08-10T22:34:21.779Z,1533940461.779 [Default:CheckIn:Read_GPS] Stopped
2018-08-10T22:34:21.779Z,1533940461.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-10T22:34:22.177Z,1533940462.177 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-10T22:34:26.629Z,1533940466.629 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180810T222537/Courier0004.lzma
2018-08-10T22:34:27.424Z,1533940467.424 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Courier0004.lzma.bak
2018-08-10T22:34:27.424Z,1533940467.424 [DataOverHttps](INFO): SBD MOMSN=8412027
2018-08-10T22:34:37.053Z,1533940477.053 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20180810T222537/Express0005.lzma
2018-08-10T22:34:37.852Z,1533940477.852 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Express0005.lzma.bak
2018-08-10T22:34:37.852Z,1533940477.852 [DataOverHttps](INFO): SBD MOMSN=8412029
2018-08-10T22:34:38.202Z,1533940478.202 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:34:38.570Z,1533940478.570 [Default:CheckIn:Read_Iridium] Stopped
2018-08-10T22:34:38.570Z,1533940478.570 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-10T22:34:38.570Z,1533940478.570 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-10T22:34:38.998Z,1533940478.998 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:34:52.355Z,1533940492.355 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:34:52.355Z,1533940492.355 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-08-10T22:34:52.355Z,1533940492.355 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:34:52.384Z,1533940492.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:34:52.787Z,1533940492.787 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:34:52.787Z,1533940492.787 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-08-10T22:34:53.160Z,1533940493.160 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:34:53.165Z,1533940493.165 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:34:53.165Z,1533940493.165 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:36:02.997Z,1533940562.997 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:36:02.997Z,1533940562.997 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-10T22:36:02.997Z,1533940562.997 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:36:03.222Z,1533940563.222 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:36:03.223Z,1533940563.223 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-10T22:36:03.401Z,1533940563.401 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:36:03.530Z,1533940563.530 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:36:29.910Z,1533940589.910 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-10T22:36:29.911Z,1533940589.911 [NAL9602] Data Fault, FailCount= 1
2018-08-10T22:36:29.911Z,1533940589.911 [NAL9602](ERROR): Data Fault
2018-08-10T22:36:30.002Z,1533940590.002 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-10T22:36:30.315Z,1533940590.315 [NAL9602](INFO): Powering down
2018-08-10T22:36:31.175Z,1533940591.175 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-10T22:36:31.175Z,1533940591.175 [NAL9602] No Fault, FailCount= 1
2018-08-10T22:37:00.547Z,1533940620.547 [NAL9602](INFO): Powering up NAL9602
2018-08-10T22:37:11.743Z,1533940631.743 [NAL9602](INFO): NAL9602 initialized
2018-08-10T22:37:53.475Z,1533940673.475 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:37:53.475Z,1533940673.475 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-08-10T22:37:53.475Z,1533940673.475 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:37:53.505Z,1533940673.505 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:37:53.921Z,1533940673.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:37:53.921Z,1533940673.921 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-08-10T22:37:54.296Z,1533940674.296 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:37:54.296Z,1533940674.296 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:37:54.297Z,1533940674.297 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:39:38.788Z,1533940778.788 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-10T22:39:38.788Z,1533940778.788 [Default:CheckIn:C.Wait] Stopped
2018-08-10T22:39:38.788Z,1533940778.788 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-10T22:39:38.788Z,1533940778.788 [Default:CheckIn:D] Running Loop=1
2018-08-10T22:39:39.174Z,1533940779.174 [Default:CheckIn:D] Stopped
2018-08-10T22:39:39.174Z,1533940779.174 [Default:CheckIn:E] Running Loop=1
2018-08-10T22:39:39.572Z,1533940779.572 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.520875 min 
2018-08-10T22:39:39.574Z,1533940779.574 [Default:CheckIn:E] Stopped
2018-08-10T22:39:39.574Z,1533940779.574 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-10T22:39:39.574Z,1533940779.574 [Default:CheckIn] Stopped
2018-08-10T22:39:39.575Z,1533940779.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-10T22:39:39.575Z,1533940779.575 [Default:CheckIn](INFO): Running loop #2
2018-08-10T22:39:39.575Z,1533940779.575 [Default:CheckIn] Running Loop=2
2018-08-10T22:39:39.575Z,1533940779.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-10T22:39:39.575Z,1533940779.575 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-10T22:40:54.557Z,1533940854.557 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:40:54.557Z,1533940854.557 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-08-10T22:40:54.557Z,1533940854.557 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:40:54.584Z,1533940854.584 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:40:54.989Z,1533940854.989 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:40:54.989Z,1533940854.989 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-08-10T22:40:55.346Z,1533940855.346 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:40:55.347Z,1533940855.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:40:55.347Z,1533940855.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:43:55.577Z,1533941035.577 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:43:55.577Z,1533941035.577 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-08-10T22:43:55.577Z,1533941035.577 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:43:55.599Z,1533941035.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:43:55.994Z,1533941035.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:43:55.994Z,1533941035.994 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-08-10T22:43:56.358Z,1533941036.358 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:43:56.358Z,1533941036.358 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:43:56.359Z,1533941036.359 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:44:39.947Z,1533941079.947 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-10T22:39:39.6Z
2018-08-10T22:44:39.948Z,1533941079.948 [Default:CheckIn:Read_GPS] Stopped
2018-08-10T22:44:39.948Z,1533941079.948 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-10T22:44:43.492Z,1533941083.492 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180810T222537/Courier0007.lzma
2018-08-10T22:44:44.179Z,1533941084.179 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Courier0007.lzma.bak
2018-08-10T22:44:44.179Z,1533941084.179 [DataOverHttps](INFO): SBD MOMSN=8412045
2018-08-10T22:44:53.127Z,1533941093.127 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20180810T222537/Express0008.lzma
2018-08-10T22:44:53.918Z,1533941093.918 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Express0008.lzma.bak
2018-08-10T22:44:53.918Z,1533941093.918 [DataOverHttps](INFO): SBD MOMSN=8412047
2018-08-10T22:44:54.610Z,1533941094.610 [Default:CheckIn:Read_Iridium] Stopped
2018-08-10T22:44:54.610Z,1533941094.610 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-10T22:44:54.610Z,1533941094.610 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-10T22:46:03.322Z,1533941163.322 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:46:03.322Z,1533941163.322 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-10T22:46:03.697Z,1533941163.697 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:46:03.697Z,1533941163.697 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:46:03.698Z,1533941163.698 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:46:03.698Z,1533941163.698 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:46:07.838Z,1533941167.838 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:46:07.892Z,1533941167.892 [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 9600 >& /dev/null &
2018-08-10T22:46:07.893Z,1533941167.893 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:46:07.894Z,1533941167.894 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:46:07.895Z,1533941167.895 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:46:48.902Z,1533941208.902 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:49.699Z,1533941209.699 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:50.494Z,1533941210.494 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:51.290Z,1533941211.290 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:51.290Z,1533941211.290 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 15 sticks.
2018-08-10T22:46:51.290Z,1533941211.290 [BPC1] Data Fault, FailCount= 1
2018-08-10T22:46:51.290Z,1533941211.290 [BPC1](ERROR): Data Fault
2018-08-10T22:46:51.352Z,1533941211.352 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-10T22:46:52.086Z,1533941212.086 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:52.540Z,1533941212.540 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-10T22:46:52.540Z,1533941212.540 [BPC1] No Fault, FailCount= 1
2018-08-10T22:46:52.882Z,1533941212.882 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:53.678Z,1533941213.678 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:54.474Z,1533941214.474 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:55.270Z,1533941215.270 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:56.066Z,1533941216.066 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:56.862Z,1533941216.862 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:57.658Z,1533941217.658 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:58.457Z,1533941218.457 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:58.802Z,1533941218.802 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:46:58.803Z,1533941218.803 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-08-10T22:46:58.803Z,1533941218.803 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:46:58.857Z,1533941218.857 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:46:58.997Z,1533941218.997 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:46:58.997Z,1533941218.997 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-08-10T22:46:59.254Z,1533941219.254 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:46:59.343Z,1533941219.343 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:46:59.344Z,1533941219.344 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:46:59.344Z,1533941219.344 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:47:00.049Z,1533941220.049 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:00.846Z,1533941220.846 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:01.642Z,1533941221.642 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:02.438Z,1533941222.438 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:03.234Z,1533941223.234 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:04.030Z,1533941224.030 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:04.825Z,1533941224.825 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:05.622Z,1533941225.622 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:06.418Z,1533941226.418 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:07.217Z,1533941227.217 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:08.014Z,1533941228.014 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:08.814Z,1533941228.814 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:09.609Z,1533941229.609 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:10.406Z,1533941230.406 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:11.202Z,1533941231.202 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:11.998Z,1533941231.998 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:12.793Z,1533941232.793 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:13.590Z,1533941233.590 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:14.079Z,1533941234.079 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-10T22:47:14.079Z,1533941234.079 [NAL9602] Data Fault, FailCount= 2
2018-08-10T22:47:14.079Z,1533941234.079 [NAL9602](ERROR): Data Fault
2018-08-10T22:47:14.178Z,1533941234.178 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-10T22:47:14.385Z,1533941234.385 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:14.475Z,1533941234.475 [NAL9602](INFO): Powering down
2018-08-10T22:47:15.182Z,1533941235.182 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:15.373Z,1533941235.373 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-10T22:47:15.373Z,1533941235.373 [NAL9602] No Fault, FailCount= 2
2018-08-10T22:47:15.977Z,1533941235.977 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:16.775Z,1533941236.775 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:17.569Z,1533941237.569 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:18.367Z,1533941238.367 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:19.161Z,1533941239.161 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:19.961Z,1533941239.961 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:20.758Z,1533941240.758 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:21.554Z,1533941241.554 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:22.349Z,1533941242.349 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:23.146Z,1533941243.146 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:23.942Z,1533941243.942 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:24.738Z,1533941244.738 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:25.536Z,1533941245.536 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:26.330Z,1533941246.330 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:27.125Z,1533941247.125 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:27.922Z,1533941247.922 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:28.718Z,1533941248.718 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:29.514Z,1533941249.514 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:30.309Z,1533941250.309 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:31.107Z,1533941251.107 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:31.902Z,1533941251.902 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:32.698Z,1533941252.698 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:33.494Z,1533941253.494 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:34.290Z,1533941254.290 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:35.085Z,1533941255.085 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:35.882Z,1533941255.882 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:36.677Z,1533941256.677 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:37.474Z,1533941257.474 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:38.270Z,1533941258.270 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:39.066Z,1533941259.066 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:39.862Z,1533941259.862 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:40.658Z,1533941260.658 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:41.453Z,1533941261.453 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:42.250Z,1533941262.250 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:43.046Z,1533941263.046 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:43.842Z,1533941263.842 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:44.480Z,1533941264.480 [NAL9602](INFO): Powering up NAL9602
2018-08-10T22:47:44.638Z,1533941264.638 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:45.434Z,1533941265.434 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:46.230Z,1533941266.230 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:47.026Z,1533941267.026 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:47.822Z,1533941267.822 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:48.618Z,1533941268.618 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:49.414Z,1533941269.414 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:50.210Z,1533941270.210 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:51.005Z,1533941271.005 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:51.802Z,1533941271.802 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:52.597Z,1533941272.597 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:53.394Z,1533941273.394 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:54.190Z,1533941274.190 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:54.987Z,1533941274.987 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:55.279Z,1533941275.279 [NAL9602](INFO): NAL9602 initialized
2018-08-10T22:47:55.781Z,1533941275.781 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:56.578Z,1533941276.578 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:57.373Z,1533941277.373 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:58.170Z,1533941278.170 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:58.966Z,1533941278.966 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:47:59.762Z,1533941279.762 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:48:00.558Z,1533941280.558 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:48:01.354Z,1533941281.354 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:48:02.150Z,1533941282.150 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:48:02.946Z,1533941282.946 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:48:03.742Z,1533941283.742 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:48:04.133Z,1533941284.133 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:48:04.133Z,1533941284.133 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-10T22:48:04.133Z,1533941284.133 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:48:04.246Z,1533941284.246 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:48:04.537Z,1533941284.537 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:48:04.762Z,1533941284.762 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:48:05.569Z,1533941285.569 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-08-10T22:48:05.569Z,1533941285.569 [Rowe_600LCM] Hardware Fault, FailCount= 1
2018-08-10T22:48:05.569Z,1533941285.569 [Rowe_600LCM](ERROR): Hardware Fault
2018-08-10T22:48:06.223Z,1533941286.223 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:48:06.224Z,1533941286.224 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-10T22:48:06.377Z,1533941286.377 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:48:06.377Z,1533941286.377 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:48:06.378Z,1533941286.378 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:48:06.378Z,1533941286.378 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:48:10.499Z,1533941290.499 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:48:10.627Z,1533941290.627 [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 9600 >& /dev/null &
2018-08-10T22:48:10.628Z,1533941290.628 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:48:10.629Z,1533941290.629 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:48:10.630Z,1533941290.630 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:49:55.093Z,1533941395.093 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-10T22:49:55.093Z,1533941395.093 [Default:CheckIn:C.Wait] Stopped
2018-08-10T22:49:55.093Z,1533941395.093 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-10T22:49:55.093Z,1533941395.093 [Default:CheckIn:D] Running Loop=1
2018-08-10T22:49:55.475Z,1533941395.475 [Default:CheckIn:D] Stopped
2018-08-10T22:49:55.475Z,1533941395.475 [Default:CheckIn:E] Running Loop=1
2018-08-10T22:49:58.979Z,1533941398.979 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.792653 min 
2018-08-10T22:49:58.979Z,1533941398.979 [Default:CheckIn:E] Stopped
2018-08-10T22:49:58.979Z,1533941398.979 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-10T22:49:58.979Z,1533941398.979 [Default:CheckIn] Stopped
2018-08-10T22:49:58.980Z,1533941398.980 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-10T22:49:58.980Z,1533941398.980 [Default:CheckIn](INFO): Running loop #3
2018-08-10T22:49:58.980Z,1533941398.980 [Default:CheckIn] Running Loop=3
2018-08-10T22:49:58.980Z,1533941398.980 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-10T22:49:58.980Z,1533941398.980 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-10T22:49:59.501Z,1533941399.501 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:49:59.501Z,1533941399.501 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-08-10T22:49:59.501Z,1533941399.501 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:49:59.533Z,1533941399.533 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:49:59.939Z,1533941399.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:49:59.939Z,1533941399.939 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-08-10T22:50:00.296Z,1533941400.296 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:50:00.296Z,1533941400.296 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:50:00.301Z,1533941400.301 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:50:07.025Z,1533941407.025 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:50:07.025Z,1533941407.025 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-10T22:50:07.025Z,1533941407.025 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:50:07.153Z,1533941407.153 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:50:07.429Z,1533941407.429 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:50:07.680Z,1533941407.680 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:50:09.125Z,1533941409.125 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:50:09.125Z,1533941409.125 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-10T22:50:09.293Z,1533941409.293 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:50:09.293Z,1533941409.293 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:50:09.294Z,1533941409.294 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:50:09.294Z,1533941409.294 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:50:13.419Z,1533941413.419 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:50:13.568Z,1533941413.568 [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 9600 >& /dev/null &
2018-08-10T22:50:13.569Z,1533941413.569 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:50:13.569Z,1533941413.569 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:50:13.570Z,1533941413.570 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:52:09.873Z,1533941529.873 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:52:09.873Z,1533941529.873 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-10T22:52:09.873Z,1533941529.873 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:52:10.034Z,1533941530.034 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:52:10.277Z,1533941530.277 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:52:10.533Z,1533941530.533 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:52:12.026Z,1533941532.026 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:52:12.026Z,1533941532.026 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-10T22:52:12.149Z,1533941532.149 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:52:12.149Z,1533941532.149 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:52:12.149Z,1533941532.149 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:52:12.150Z,1533941532.150 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:52:16.274Z,1533941536.274 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:52:16.390Z,1533941536.390 [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 9600 >& /dev/null &
2018-08-10T22:52:16.391Z,1533941536.391 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:52:16.392Z,1533941536.392 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:52:16.393Z,1533941536.393 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:53:00.473Z,1533941580.473 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:53:00.473Z,1533941580.473 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-08-10T22:53:00.473Z,1533941580.473 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:53:00.489Z,1533941580.489 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:53:00.889Z,1533941580.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:53:00.889Z,1533941580.889 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-08-10T22:53:01.255Z,1533941581.255 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:53:01.255Z,1533941581.255 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:53:01.256Z,1533941581.256 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:53:18.258Z,1533941598.258 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:53:19.054Z,1533941599.054 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-08-10T22:54:12.797Z,1533941652.797 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:54:12.797Z,1533941652.797 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-10T22:54:12.797Z,1533941652.797 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:54:12.926Z,1533941652.926 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:54:13.201Z,1533941653.201 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:54:13.443Z,1533941653.443 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:54:14.884Z,1533941654.884 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T22:54:14.884Z,1533941654.884 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-10T22:54:15.057Z,1533941655.057 [Rowe_600LCM](INFO): Initializing
2018-08-10T22:54:15.057Z,1533941655.057 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T22:54:15.058Z,1533941655.058 [Rowe_600LCM](INFO): LCM OK
2018-08-10T22:54:15.058Z,1533941655.058 [Rowe_600LCM](INFO): Powering up
2018-08-10T22:54:19.176Z,1533941659.176 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T22:54:19.313Z,1533941659.313 [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 9600 >& /dev/null &
2018-08-10T22:54:19.314Z,1533941659.314 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T22:54:19.315Z,1533941659.315 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T22:54:19.315Z,1533941659.315 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T22:54:59.210Z,1533941699.210 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-10T22:49:58.0Z
2018-08-10T22:54:59.210Z,1533941699.210 [Default:CheckIn:Read_GPS] Stopped
2018-08-10T22:54:59.210Z,1533941699.210 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-10T22:55:04.580Z,1533941704.580 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180810T222537/Courier0010.lzma
2018-08-10T22:55:05.372Z,1533941705.372 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Courier0010.lzma.bak
2018-08-10T22:55:05.372Z,1533941705.372 [DataOverHttps](INFO): SBD MOMSN=8412055
2018-08-10T22:55:15.093Z,1533941715.093 [DataOverHttps](INFO): Sending 326 bytes from file Logs/20180810T222537/Express0011.lzma
2018-08-10T22:55:15.860Z,1533941715.860 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Express0011.lzma.bak
2018-08-10T22:55:15.861Z,1533941715.861 [DataOverHttps](INFO): SBD MOMSN=8412057
2018-08-10T22:55:16.573Z,1533941716.573 [Default:CheckIn:Read_Iridium] Stopped
2018-08-10T22:55:16.573Z,1533941716.573 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-10T22:55:16.573Z,1533941716.573 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-10T22:56:01.578Z,1533941761.578 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:56:01.578Z,1533941761.578 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-08-10T22:56:01.578Z,1533941761.578 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:56:01.605Z,1533941761.605 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:56:01.971Z,1533941761.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:56:01.972Z,1533941761.972 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-08-10T22:56:02.354Z,1533941762.354 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:56:02.355Z,1533941762.355 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:56:02.355Z,1533941762.355 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T22:56:15.613Z,1533941775.613 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T22:56:15.613Z,1533941775.613 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-10T22:56:15.613Z,1533941775.613 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T22:56:15.984Z,1533941775.984 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T22:56:15.984Z,1533941775.984 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-10T22:56:16.017Z,1533941776.017 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T22:56:16.150Z,1533941776.150 [Rowe_600LCM](INFO): Powering down
2018-08-10T22:57:59.196Z,1533941879.196 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-10T22:57:59.196Z,1533941879.196 [NAL9602] Data Fault, FailCount= 3
2018-08-10T22:57:59.196Z,1533941879.196 [NAL9602](ERROR): Data Fault
2018-08-10T22:57:59.282Z,1533941879.282 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-10T22:57:59.594Z,1533941879.594 [NAL9602](INFO): Powering down
2018-08-10T22:58:00.465Z,1533941880.465 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-10T22:58:00.465Z,1533941880.465 [NAL9602] No Fault, FailCount= 3
2018-08-10T22:58:29.659Z,1533941909.659 [NAL9602](INFO): Powering up NAL9602
2018-08-10T22:58:40.851Z,1533941920.851 [NAL9602](INFO): NAL9602 initialized
2018-08-10T22:59:02.517Z,1533941942.517 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T22:59:02.517Z,1533941942.517 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-08-10T22:59:02.518Z,1533941942.518 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T22:59:02.544Z,1533941942.544 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T22:59:02.941Z,1533941942.941 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T22:59:02.941Z,1533941942.941 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-08-10T22:59:03.309Z,1533941943.309 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T22:59:03.309Z,1533941943.309 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T22:59:03.310Z,1533941943.310 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T23:00:16.982Z,1533942016.982 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-10T23:00:16.982Z,1533942016.982 [Default:CheckIn:C.Wait] Stopped
2018-08-10T23:00:16.982Z,1533942016.982 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-10T23:00:16.982Z,1533942016.982 [Default:CheckIn:D] Running Loop=1
2018-08-10T23:00:17.356Z,1533942017.356 [Default:CheckIn:D] Stopped
2018-08-10T23:00:17.356Z,1533942017.356 [Default:CheckIn:E] Running Loop=1
2018-08-10T23:00:17.761Z,1533942017.761 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.157330 min 
2018-08-10T23:00:17.761Z,1533942017.761 [Default:CheckIn:E] Stopped
2018-08-10T23:00:17.761Z,1533942017.761 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-10T23:00:17.761Z,1533942017.761 [Default:CheckIn] Stopped
2018-08-10T23:00:17.761Z,1533942017.761 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-10T23:00:17.762Z,1533942017.762 [Default:CheckIn](INFO): Running loop #4
2018-08-10T23:00:17.762Z,1533942017.762 [Default:CheckIn] Running Loop=4
2018-08-10T23:00:17.762Z,1533942017.762 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-10T23:00:17.762Z,1533942017.762 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-10T23:02:03.474Z,1533942123.474 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T23:02:03.474Z,1533942123.474 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-08-10T23:02:03.474Z,1533942123.474 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T23:02:03.496Z,1533942123.496 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T23:02:03.893Z,1533942123.893 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T23:02:03.894Z,1533942123.894 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-08-10T23:02:04.269Z,1533942124.269 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T23:02:04.270Z,1533942124.270 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T23:02:04.270Z,1533942124.270 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T23:05:04.500Z,1533942304.500 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T23:05:04.500Z,1533942304.500 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-08-10T23:05:04.500Z,1533942304.500 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T23:05:04.514Z,1533942304.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T23:05:04.965Z,1533942304.965 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T23:05:04.966Z,1533942304.966 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-08-10T23:05:05.313Z,1533942305.313 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T23:05:05.314Z,1533942305.314 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T23:05:05.314Z,1533942305.314 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T23:05:18.108Z,1533942318.108 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-10T23:00:17.8Z
2018-08-10T23:05:18.108Z,1533942318.108 [Default:CheckIn:Read_GPS] Stopped
2018-08-10T23:05:18.108Z,1533942318.108 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-10T23:05:21.635Z,1533942321.635 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180810T222537/Courier0013.lzma
2018-08-10T23:05:22.315Z,1533942322.315 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Courier0013.lzma.bak
2018-08-10T23:05:22.315Z,1533942322.315 [DataOverHttps](INFO): SBD MOMSN=8412069
2018-08-10T23:05:31.501Z,1533942331.501 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20180810T222537/Express0014.lzma
2018-08-10T23:05:32.278Z,1533942332.278 [DataOverHttps](INFO): Moved sent file to Logs/20180810T222537/Express0014.lzma.bak
2018-08-10T23:05:32.278Z,1533942332.278 [DataOverHttps](INFO): SBD MOMSN=8412071
2018-08-10T23:05:32.955Z,1533942332.955 [Default:CheckIn:Read_Iridium] Stopped
2018-08-10T23:05:32.955Z,1533942332.955 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-10T23:05:32.956Z,1533942332.956 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-10T23:06:15.622Z,1533942375.622 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T23:06:15.622Z,1533942375.622 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-10T23:06:15.926Z,1533942375.926 [Rowe_600LCM](INFO): Initializing
2018-08-10T23:06:15.926Z,1533942375.926 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T23:06:15.926Z,1533942375.926 [Rowe_600LCM](INFO): LCM OK
2018-08-10T23:06:15.927Z,1533942375.927 [Rowe_600LCM](INFO): Powering up
2018-08-10T23:06:20.140Z,1533942380.140 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T23:06:20.209Z,1533942380.209 [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 9600 >& /dev/null &
2018-08-10T23:06:20.210Z,1533942380.210 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T23:06:20.211Z,1533942380.211 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T23:06:20.212Z,1533942380.212 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T23:07:53.353Z,1533942473.353 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 10 sticks.
2018-08-10T23:07:53.353Z,1533942473.353 [BPC1] Data Fault, FailCount= 2
2018-08-10T23:07:53.353Z,1533942473.353 [BPC1](ERROR): Data Fault
2018-08-10T23:07:53.410Z,1533942473.410 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-10T23:07:54.579Z,1533942474.579 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-10T23:07:54.579Z,1533942474.579 [BPC1] No Fault, FailCount= 2
2018-08-10T23:08:05.386Z,1533942485.386 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T23:08:05.386Z,1533942485.386 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-08-10T23:08:05.387Z,1533942485.387 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T23:08:05.418Z,1533942485.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T23:08:05.795Z,1533942485.795 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T23:08:05.796Z,1533942485.796 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-08-10T23:08:06.160Z,1533942486.160 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T23:08:06.166Z,1533942486.166 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T23:08:06.166Z,1533942486.166 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T23:08:16.585Z,1533942496.585 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T23:08:16.585Z,1533942496.585 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-10T23:08:16.585Z,1533942496.585 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T23:08:16.614Z,1533942496.614 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T23:08:16.989Z,1533942496.989 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T23:08:17.177Z,1533942497.177 [Rowe_600LCM](INFO): Powering down
2018-08-10T23:08:18.587Z,1533942498.587 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T23:08:18.587Z,1533942498.587 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-10T23:08:18.794Z,1533942498.794 [Rowe_600LCM](INFO): Initializing
2018-08-10T23:08:18.795Z,1533942498.795 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T23:08:18.795Z,1533942498.795 [Rowe_600LCM](INFO): LCM OK
2018-08-10T23:08:18.795Z,1533942498.795 [Rowe_600LCM](INFO): Powering up
2018-08-10T23:08:22.968Z,1533942502.968 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T23:08:23.111Z,1533942503.111 [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 9600 >& /dev/null &
2018-08-10T23:08:23.112Z,1533942503.112 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T23:08:23.113Z,1533942503.113 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T23:08:23.114Z,1533942503.114 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T23:08:43.098Z,1533942523.098 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-10T23:08:43.098Z,1533942523.098 [NAL9602] Data Fault, FailCount= 4
2018-08-10T23:08:43.098Z,1533942523.098 [NAL9602](ERROR): Data Fault
2018-08-10T23:08:43.236Z,1533942523.236 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-10T23:08:43.359Z,1533942523.359 [NAL9602](INFO): Powering down
2018-08-10T23:08:44.237Z,1533942524.237 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-10T23:08:44.237Z,1533942524.237 [NAL9602] No Fault, FailCount= 4
2018-08-10T23:09:13.764Z,1533942553.764 [NAL9602](INFO): Powering up NAL9602
2018-08-10T23:09:24.964Z,1533942564.964 [NAL9602](INFO): NAL9602 initialized
2018-08-10T23:10:19.377Z,1533942619.377 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-10T23:10:19.377Z,1533942619.377 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-10T23:10:19.377Z,1533942619.377 [Rowe_600LCM](ERROR): Communications Fault
2018-08-10T23:10:19.501Z,1533942619.501 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-10T23:10:19.781Z,1533942619.781 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T23:10:20.025Z,1533942620.025 [Rowe_600LCM](INFO): Powering down
2018-08-10T23:10:21.476Z,1533942621.476 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-10T23:10:21.477Z,1533942621.477 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-10T23:10:21.641Z,1533942621.641 [Rowe_600LCM](INFO): Initializing
2018-08-10T23:10:21.641Z,1533942621.641 [Rowe_600LCM](INFO): Checking LCM
2018-08-10T23:10:21.641Z,1533942621.641 [Rowe_600LCM](INFO): LCM OK
2018-08-10T23:10:21.642Z,1533942621.642 [Rowe_600LCM](INFO): Powering up
2018-08-10T23:10:25.767Z,1533942625.767 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-10T23:10:25.913Z,1533942625.913 [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 9600 >& /dev/null &
2018-08-10T23:10:25.914Z,1533942625.914 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-10T23:10:25.915Z,1533942625.915 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-10T23:10:25.915Z,1533942625.915 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-10T23:10:33.448Z,1533942633.448 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-10T23:10:33.448Z,1533942633.448 [Default:CheckIn:C.Wait] Stopped
2018-08-10T23:10:33.448Z,1533942633.448 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-10T23:10:33.448Z,1533942633.448 [Default:CheckIn:D] Running Loop=1
2018-08-10T23:10:33.862Z,1533942633.862 [Default:CheckIn:D] Stopped
2018-08-10T23:10:33.862Z,1533942633.862 [Default:CheckIn:E] Running Loop=1
2018-08-10T23:10:37.388Z,1533942637.388 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.432426 min 
2018-08-10T23:10:37.388Z,1533942637.388 [Default:CheckIn:E] Stopped
2018-08-10T23:10:37.390Z,1533942637.390 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-10T23:10:37.390Z,1533942637.390 [Default:CheckIn] Stopped
2018-08-10T23:10:37.390Z,1533942637.390 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-10T23:10:37.390Z,1533942637.390 [Default:CheckIn](INFO): Running loop #5
2018-08-10T23:10:37.390Z,1533942637.390 [Default:CheckIn] Running Loop=5
2018-08-10T23:10:37.391Z,1533942637.391 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-10T23:10:37.391Z,1533942637.391 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-10T23:11:06.318Z,1533942666.318 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-10T23:11:06.318Z,1533942666.318 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-08-10T23:11:06.319Z,1533942666.319 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-10T23:11:06.346Z,1533942666.346 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-10T23:11:06.721Z,1533942666.721 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-10T23:11:06.721Z,1533942666.721 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-08-10T23:11:07.104Z,1533942667.104 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-10T23:11:07.105Z,1533942667.105 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-10T23:11:07.105Z,1533942667.105 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-10T23:11:26.571Z,1533942686.571 [CommandLine](IMPORTANT): got command restart system
2018-08-10T23:11:29.117Z,1533942689.117 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-08-10T23:11:29.121Z,1533942689.121 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-10T23:11:29.122Z,1533942689.122 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.197Z,1533942689.197 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-08-10T23:11:29.197Z,1533942689.197 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.198Z,1533942689.198 [CommandLine](INFO): Join timeout helper Thread ID is 1005
2018-08-10T23:11:29.199Z,1533942689.199 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-08-10T23:11:29.199Z,1533942689.199 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.199Z,1533942689.199 [NavChartDb](INFO): Join timeout helper Thread ID is 1006
2018-08-10T23:11:29.365Z,1533942689.365 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-10T23:11:29.365Z,1533942689.365 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.381Z,1533942689.381 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-08-10T23:11:29.381Z,1533942689.381 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.382Z,1533942689.382 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1007
2018-08-10T23:11:29.473Z,1533942689.473 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-10T23:11:29.474Z,1533942689.474 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.477Z,1533942689.477 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-08-10T23:11:29.477Z,1533942689.477 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.478Z,1533942689.478 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1008
2018-08-10T23:11:29.781Z,1533942689.781 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-10T23:11:29.781Z,1533942689.781 [WetLabsBB2FL](INFO): Powering down
2018-08-10T23:11:29.782Z,1533942689.782 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.793Z,1533942689.793 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-08-10T23:11:29.793Z,1533942689.793 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:29.793Z,1533942689.793 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1009
2018-08-10T23:11:30.231Z,1533942690.231 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-10T23:11:30.232Z,1533942690.232 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-10T23:11:30.484Z,1533942690.484 [Rowe_600LCM](INFO): Powering down
2018-08-10T23:11:30.485Z,1533942690.485 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:30.494Z,1533942690.494 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-08-10T23:11:30.494Z,1533942690.494 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:30.495Z,1533942690.495 [Radio_Surface](INFO): Join timeout helper Thread ID is 1011
2018-08-10T23:11:30.701Z,1533942690.701 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-10T23:11:30.701Z,1533942690.701 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:30.718Z,1533942690.718 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-08-10T23:11:30.718Z,1533942690.718 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:30.718Z,1533942690.718 [logger](INFO): Join timeout helper Thread ID is 1012
2018-08-10T23:11:30.729Z,1533942690.729 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-10T23:11:30.730Z,1533942690.730 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:30.745Z,1533942690.745 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-08-10T23:11:30.746Z,1533942690.746 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:30.746Z,1533942690.746 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-08-10T23:11:30.746Z,1533942690.746 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:30.746Z,1533942690.746 [controlThread](INFO): Join timeout helper Thread ID is 1013
2018-08-10T23:11:31.017Z,1533942691.017 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-10T23:11:31.017Z,1533942691.017 [controlThread](DEBUG): Uninitializing ControlThread
2018-08-10T23:11:31.018Z,1533942691.018 [NAL9602](INFO): Powering down
2018-08-10T23:11:31.020Z,1533942691.020 [PNI_TCM](INFO): Powering down
2018-08-10T23:11:31.106Z,1533942691.106 [Aanderaa_O2](INFO): Powering down
2018-08-10T23:11:31.107Z,1533942691.107 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-10T23:11:31.108Z,1533942691.108 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-08-10T23:11:31.109Z,1533942691.109 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-08-10T23:11:31.110Z,1533942691.110 [MissionManager](INFO): Uninitializing Mission Default
2018-08-10T23:11:31.110Z,1533942691.110 [Default] Stopped
2018-08-10T23:11:31.110Z,1533942691.110 [Default](DEBUG): Aggregate::uninitialize Default
2018-08-10T23:11:31.111Z,1533942691.111 [Default:B.GoToSurface] Stopped
2018-08-10T23:11:31.111Z,1533942691.111 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-10T23:11:31.111Z,1533942691.111 [Default:CheckIn] Stopped
2018-08-10T23:11:31.111Z,1533942691.111 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-10T23:11:31.111Z,1533942691.111 [Default:CheckIn:Read_GPS] Stopped
2018-08-10T23:11:31.115Z,1533942691.115 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-08-10T23:11:31.115Z,1533942691.115 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-08-10T23:11:31.116Z,1533942691.116 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-08-10T23:11:31.116Z,1533942691.116 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-08-10T23:11:31.116Z,1533942691.116 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-10T23:11:31.117Z,1533942691.117 [BuoyancyServo](INFO): Powering down
2018-08-10T23:11:31.129Z,1533942691.129 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-08-10T23:11:31.129Z,1533942691.129 [ElevatorServo](INFO): Powering down
2018-08-10T23:11:31.130Z,1533942691.130 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-08-10T23:11:31.130Z,1533942691.130 [MassServo](INFO): Powering down
2018-08-10T23:11:31.131Z,1533942691.131 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-08-10T23:11:31.131Z,1533942691.131 [RudderServo](INFO): Powering down
2018-08-10T23:11:31.132Z,1533942691.132 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-08-10T23:11:31.132Z,1533942691.132 [ThrusterServo](INFO): Powering down
2018-08-10T23:11:31.133Z,1533942691.133 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-08-10T23:11:31.134Z,1533942691.134 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-08-10T23:11:31.134Z,1533942691.134 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-08-10T23:11:31.135Z,1533942691.135 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:31.260Z,1533942691.260 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:31.267Z,1533942691.267 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:31.309Z,1533942691.309 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:31.312Z,1533942691.312 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:31.341Z,1533942691.341 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-10T23:11:31.396Z,1533942691.396 [logger ThreadHandler](INFO): Thread cancelled.