2018-12-10T09:07:38.721Z,1544432858.721 [Supervisor](DEBUG): Initializing supervisor.
2018-12-10T09:07:38.724Z,1544432858.724 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-12-10T09:07:38.724Z,1544432858.724 [SyncHandler](INFO): Protected caller Thread ID is 798
2018-12-10T09:07:38.725Z,1544432858.725 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-12-10T09:07:38.726Z,1544432858.726 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-12-10T09:07:38.726Z,1544432858.726 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 799
2018-12-10T09:07:38.729Z,1544432858.729 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-12-10T09:07:38.740Z,1544432858.740 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-12-10T09:07:38.741Z,1544432858.741 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-12-10T09:07:38.741Z,1544432858.741 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 800
2018-12-10T09:07:38.742Z,1544432858.742 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-12-10T09:07:38.743Z,1544432858.743 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-12-10T09:07:38.744Z,1544432858.744 [logger ThreadHandler](INFO): Protected caller Thread ID is 801
2018-12-10T09:07:38.745Z,1544432858.745 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-12-10T09:07:38.746Z,1544432858.746 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-12-10T09:07:38.750Z,1544432858.750 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-12-10T09:07:39.071Z,1544432859.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-12-10T09:07:39.071Z,1544432859.071 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-12-10T09:07:39.259Z,1544432859.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-12-10T09:07:39.261Z,1544432859.261 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-12-10T09:07:39.544Z,1544432859.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-12-10T09:07:39.546Z,1544432859.546 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-12-10T09:07:39.688Z,1544432859.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-12-10T09:07:39.690Z,1544432859.690 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-12-10T09:07:39.788Z,1544432859.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-12-10T09:07:39.788Z,1544432859.788 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-12-10T09:07:40.242Z,1544432860.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-12-10T09:07:40.244Z,1544432860.244 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-12-10T09:07:40.698Z,1544432860.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-12-10T09:07:40.700Z,1544432860.700 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-12-10T09:07:41.151Z,1544432861.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-12-10T09:07:41.151Z,1544432861.151 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-12-10T09:07:41.254Z,1544432861.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-12-10T09:07:41.255Z,1544432861.255 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-12-10T09:07:41.350Z,1544432861.350 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-12-10T09:07:41.351Z,1544432861.351 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-12-10T09:07:41.564Z,1544432861.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-12-10T09:07:41.565Z,1544432861.565 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-12-10T09:07:41.763Z,1544432861.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-12-10T09:07:41.765Z,1544432861.765 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-12-10T09:07:42.124Z,1544432862.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-12-10T09:07:42.125Z,1544432862.125 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-12-10T09:07:42.268Z,1544432862.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-12-10T09:07:42.269Z,1544432862.269 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-12-10T09:07:42.352Z,1544432862.352 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-12-10T09:07:42.356Z,1544432862.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-12-10T09:07:42.504Z,1544432862.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-12-10T09:07:43.205Z,1544432863.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-12-10T09:07:43.386Z,1544432863.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-12-10T09:07:43.471Z,1544432863.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-12-10T09:07:43.632Z,1544432863.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-12-10T09:07:43.812Z,1544432863.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-12-10T09:07:43.888Z,1544432863.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-12-10T09:07:44.114Z,1544432864.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-12-10T09:07:44.114Z,1544432864.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-12-10T09:07:44.198Z,1544432864.198 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-12-10T09:07:44.287Z,1544432864.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-12-10T09:07:44.385Z,1544432864.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-12-10T09:07:44.507Z,1544432864.507 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-12-10T09:07:44.510Z,1544432864.510 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-12-10T09:07:44.787Z,1544432864.787 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-12-10T09:07:44.789Z,1544432864.789 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-12-10T09:07:44.845Z,1544432864.845 [DepthRateCalculator] Loaded
2018-12-10T09:07:44.845Z,1544432864.845 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-12-10T09:07:44.851Z,1544432864.851 [PitchRateCalculator] Loaded
2018-12-10T09:07:44.851Z,1544432864.851 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-12-10T09:07:44.866Z,1544432864.866 [SpeedCalculator] Loaded
2018-12-10T09:07:44.867Z,1544432864.867 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-12-10T09:07:44.887Z,1544432864.887 [TempGradientCalculator] Loaded
2018-12-10T09:07:44.888Z,1544432864.888 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-12-10T09:07:44.893Z,1544432864.893 [YawRateCalculator] Loaded
2018-12-10T09:07:44.894Z,1544432864.894 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-12-10T09:07:44.932Z,1544432864.932 [ElevatorOffsetCalculator] Loaded
2018-12-10T09:07:44.932Z,1544432864.932 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-12-10T09:07:44.933Z,1544432864.933 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-12-10T09:07:44.933Z,1544432864.933 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-12-10T09:07:45.035Z,1544432865.035 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-12-10T09:07:45.037Z,1544432865.037 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-12-10T09:07:45.179Z,1544432865.179 [BuoyancyServo] Loaded
2018-12-10T09:07:45.179Z,1544432865.179 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-12-10T09:07:45.194Z,1544432865.194 [ElevatorServo] Loaded
2018-12-10T09:07:45.194Z,1544432865.194 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-12-10T09:07:45.208Z,1544432865.208 [MassServo] Loaded
2018-12-10T09:07:45.208Z,1544432865.208 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-12-10T09:07:45.223Z,1544432865.223 [RudderServo] Loaded
2018-12-10T09:07:45.223Z,1544432865.223 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-12-10T09:07:45.237Z,1544432865.237 [ThrusterServo] Loaded
2018-12-10T09:07:45.237Z,1544432865.237 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-12-10T09:07:45.238Z,1544432865.238 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-12-10T09:07:45.238Z,1544432865.238 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-12-10T09:07:45.262Z,1544432865.262 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-12-10T09:07:45.264Z,1544432865.264 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-12-10T09:07:45.388Z,1544432865.388 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-12-10T09:07:45.388Z,1544432865.388 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-12-10T09:07:45.953Z,1544432865.953 [DataOverHttps] Loaded
2018-12-10T09:07:45.953Z,1544432865.953 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-12-10T09:07:45.967Z,1544432865.967 [Depth_Keller] Loaded
2018-12-10T09:07:45.967Z,1544432865.967 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-12-10T09:07:45.972Z,1544432865.972 [DropWeight] Loaded
2018-12-10T09:07:45.972Z,1544432865.972 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-12-10T09:07:46.069Z,1544432866.069 [NAL9602] Loaded
2018-12-10T09:07:46.069Z,1544432866.069 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-12-10T09:07:46.085Z,1544432866.085 [Onboard] Loaded
2018-12-10T09:07:46.085Z,1544432866.085 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-12-10T09:07:46.095Z,1544432866.095 [Radio_Surface] Loaded
2018-12-10T09:07:46.095Z,1544432866.095 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-12-10T09:07:46.096Z,1544432866.096 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0
2018-12-10T09:07:46.096Z,1544432866.096 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883
2018-12-10T09:07:46.248Z,1544432866.248 [PNI_TCM] Loaded
2018-12-10T09:07:46.248Z,1544432866.248 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-12-10T09:07:46.307Z,1544432866.307 [Rowe_600LCM] Loaded
2018-12-10T09:07:46.307Z,1544432866.307 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-12-10T09:07:46.308Z,1544432866.308 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0
2018-12-10T09:07:46.308Z,1544432866.308 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 884
2018-12-10T09:07:46.309Z,1544432866.309 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-12-10T09:07:46.310Z,1544432866.310 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-12-10T09:07:46.467Z,1544432866.467 [SBIT](DEBUG): Construct Startup Built In Test.
2018-12-10T09:07:46.488Z,1544432866.488 [SBIT] Loaded
2018-12-10T09:07:46.489Z,1544432866.489 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-12-10T09:07:46.490Z,1544432866.490 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-12-10T09:07:46.501Z,1544432866.501 [IBIT] Loaded
2018-12-10T09:07:46.502Z,1544432866.502 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-12-10T09:07:46.505Z,1544432866.505 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-12-10T09:07:46.885Z,1544432866.885 [CBIT] Loaded
2018-12-10T09:07:46.885Z,1544432866.885 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-12-10T09:07:46.885Z,1544432866.885 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-12-10T09:07:46.886Z,1544432866.886 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-12-10T09:07:47.111Z,1544432867.111 [Aanderaa_O2] Loaded
2018-12-10T09:07:47.111Z,1544432867.111 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-12-10T09:07:47.193Z,1544432867.193 [CTD_NeilBrown] Loaded
2018-12-10T09:07:47.194Z,1544432867.194 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-12-10T09:07:47.195Z,1544432867.195 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4099D4E0
2018-12-10T09:07:47.195Z,1544432867.195 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 885
2018-12-10T09:07:47.267Z,1544432867.267 [CTD_Seabird] Loaded
2018-12-10T09:07:47.268Z,1544432867.268 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-12-10T09:07:47.269Z,1544432867.269 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409CD4E0
2018-12-10T09:07:47.269Z,1544432867.269 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 886
2018-12-10T09:07:47.283Z,1544432867.283 [PAR_Licor] Loaded
2018-12-10T09:07:47.283Z,1544432867.283 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-12-10T09:07:47.331Z,1544432867.331 [WetLabsBB2FL] Loaded
2018-12-10T09:07:47.331Z,1544432867.331 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-12-10T09:07:47.332Z,1544432867.332 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409FD4E0
2018-12-10T09:07:47.333Z,1544432867.333 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 887
2018-12-10T09:07:47.333Z,1544432867.333 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-12-10T09:07:47.334Z,1544432867.334 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-12-10T09:07:47.422Z,1544432867.422 [VerticalControl](DEBUG): Construct VerticalControl.
2018-12-10T09:07:47.503Z,1544432867.503 [VerticalControl] Loaded
2018-12-10T09:07:47.504Z,1544432867.504 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-12-10T09:07:47.504Z,1544432867.504 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-12-10T09:07:47.560Z,1544432867.560 [HorizontalControl] Loaded
2018-12-10T09:07:47.561Z,1544432867.561 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-12-10T09:07:47.561Z,1544432867.561 [SpeedControl](DEBUG): Construct SpeedControl.
2018-12-10T09:07:47.563Z,1544432867.563 [SpeedControl] Loaded
2018-12-10T09:07:47.563Z,1544432867.563 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-12-10T09:07:47.564Z,1544432867.564 [LoopControl](DEBUG): Construct LoopControl.
2018-12-10T09:07:47.564Z,1544432867.564 [LoopControl] Loaded
2018-12-10T09:07:47.564Z,1544432867.564 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-12-10T09:07:47.565Z,1544432867.565 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-12-10T09:07:47.566Z,1544432867.566 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-12-10T09:07:47.625Z,1544432867.625 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-12-10T09:07:47.626Z,1544432867.626 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-12-10T09:07:47.952Z,1544432867.952 [DeadReckonUsingSpeedCalculator] Loaded
2018-12-10T09:07:47.952Z,1544432867.952 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-12-10T09:07:47.968Z,1544432867.968 [NavChart] Loaded
2018-12-10T09:07:47.968Z,1544432867.968 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-12-10T09:07:47.972Z,1544432867.972 [UniversalFixResidualReporter] Loaded
2018-12-10T09:07:47.973Z,1544432867.973 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-12-10T09:07:47.973Z,1544432867.973 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-12-10T09:07:47.977Z,1544432867.977 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-12-10T09:07:47.978Z,1544432867.978 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-12-10T09:07:47.984Z,1544432867.984 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-12-10T09:07:47.985Z,1544432867.985 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0
2018-12-10T09:07:47.986Z,1544432867.986 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 888
2018-12-10T09:07:47.990Z,1544432867.990 [Supervisor](INFO): Main Thread ID is 797
2018-12-10T09:07:47.990Z,1544432867.990 [Supervisor](DEBUG): Running supervisor.
2018-12-10T09:07:47.991Z,1544432867.991 [CommandLine ThreadHandler](INFO): Handler Thread ID is 889
2018-12-10T09:07:47.993Z,1544432867.993 [controlThread ThreadHandler](INFO): Handler Thread ID is 890
2018-12-10T09:07:47.994Z,1544432867.994 [controlThread](DEBUG): Initializing ControlThread
2018-12-10T09:07:47.994Z,1544432867.994 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-10T09:07:47.995Z,1544432867.995 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-12-10T09:07:47.995Z,1544432867.995 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-12-10T09:07:47.995Z,1544432867.995 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-12-10T09:07:47.996Z,1544432867.996 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-12-10T09:07:47.996Z,1544432867.996 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-12-10T09:07:48.004Z,1544432868.004 [SBIT](INFO): Initialize SBIT Component.
2018-12-10T09:07:48.004Z,1544432868.004 [SBIT](IMPORTANT): git: 2018-11-27-1-gaeee697
2018-12-10T09:07:48.004Z,1544432868.004 [SBIT](INFO): git hash: aeee69771537a23ad86f82a0fb9d5f86eb8b2445
2018-12-10T09:07:48.005Z,1544432868.005 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-12-10T09:07:48.005Z,1544432868.005 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-12-10T09:07:48.006Z,1544432868.006 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-12-10T09:07:48.006Z,1544432868.006 [IBIT](INFO): Initialize IBIT Component.
2018-12-10T09:07:48.007Z,1544432868.007 [CBIT](DEBUG): Initialize CBIT Component.
2018-12-10T09:07:48.008Z,1544432868.008 [logger ThreadHandler](INFO): Handler Thread ID is 891
2018-12-10T09:07:48.022Z,1544432868.022 [CBIT](DEBUG): Initialized mux pins.
2018-12-10T09:07:48.022Z,1544432868.022 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-12-10T09:07:48.022Z,1544432868.022 [CBIT](DEBUG): Initializing the watchdog timer.
2018-12-10T09:07:48.026Z,1544432868.026 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 892
2018-12-10T09:07:48.032Z,1544432868.032 [Radio_Surface](INFO): Powering up
2018-12-10T09:07:48.042Z,1544432868.042 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 893
2018-12-10T09:07:48.042Z,1544432868.042 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:07:48.043Z,1544432868.043 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:07:48.046Z,1544432868.046 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-12-10T09:07:48.046Z,1544432868.046 [CBIT](DEBUG): Initializing heartbeat.
2018-12-10T09:07:48.117Z,1544432868.117 [CBIT](DEBUG): Deactivating GF circuits.
2018-12-10T09:07:48.118Z,1544432868.118 [CBIT](DEBUG): Deactivating emergency mode.
2018-12-10T09:07:48.158Z,1544432868.158 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-12-10T09:07:48.160Z,1544432868.160 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-12-10T09:07:48.160Z,1544432868.160 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-12-10T09:07:48.161Z,1544432868.161 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-12-10T09:07:48.161Z,1544432868.161 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:07:48.162Z,1544432868.162 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:07:48.162Z,1544432868.162 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:07:48.163Z,1544432868.163 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-12-10T09:07:48.163Z,1544432868.163 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-12-10T09:07:48.169Z,1544432868.169 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-12-10T09:07:48.180Z,1544432868.180 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 894
2018-12-10T09:07:48.182Z,1544432868.182 [CTD_NeilBrown](INFO): Powering down
2018-12-10T09:07:48.281Z,1544432868.281 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 895
2018-12-10T09:07:48.290Z,1544432868.290 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-10T09:07:48.293Z,1544432868.293 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-10T09:07:48.300Z,1544432868.300 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-10T09:07:48.366Z,1544432868.366 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 897
2018-12-10T09:07:48.367Z,1544432868.367 [WetLabsBB2FL](INFO): Powering down
2018-12-10T09:07:48.385Z,1544432868.385 [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-12-10T09:07:48.386Z,1544432868.386 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-12-10T09:07:48.466Z,1544432868.466 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 898
2018-12-10T09:07:48.475Z,1544432868.475 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-12-10T09:07:48.475Z,1544432868.475 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-12-10T09:07:48.475Z,1544432868.475 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-12-10T09:07:48.475Z,1544432868.475 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-12-10T09:07:48.476Z,1544432868.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-12-10T09:07:48.476Z,1544432868.476 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-12-10T09:07:48.476Z,1544432868.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-12-10T09:07:48.476Z,1544432868.476 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-12-10T09:07:48.476Z,1544432868.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-12-10T09:07:48.477Z,1544432868.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-12-10T09:07:48.477Z,1544432868.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-12-10T09:07:48.477Z,1544432868.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-12-10T09:07:48.477Z,1544432868.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-12-10T09:07:48.477Z,1544432868.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-12-10T09:07:48.478Z,1544432868.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-12-10T09:07:48.478Z,1544432868.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-12-10T09:07:48.507Z,1544432868.507 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:07:48.507Z,1544432868.507 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:07:48.522Z,1544432868.522 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-12-10T09:07:48.523Z,1544432868.523 [Default:A.Wait](DEBUG): Construct Wait.
2018-12-10T09:07:48.525Z,1544432868.525 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-10T09:07:48.547Z,1544432868.547 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-12-10T09:07:48.566Z,1544432868.566 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-12-10T09:07:48.582Z,1544432868.582 [Default:E.Execute](DEBUG): Construct Execute.
2018-12-10T09:07:48.609Z,1544432868.609 [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 on</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-12-10T09:07:48.621Z,1544432868.621 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-12-10T09:07:48.639Z,1544432868.639 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-12-10T09:07:48.651Z,1544432868.651 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-12-10T09:07:48.880Z,1544432868.880 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-10T09:07:49.178Z,1544432869.178 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-10T09:07:49.186Z,1544432869.186 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-10T09:07:49.200Z,1544432869.200 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-12-10T09:07:49.206Z,1544432869.206 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-12-10T09:07:49.219Z,1544432869.219 [MassServo](DEBUG): Initializing EZServoServo.
2018-12-10T09:07:49.226Z,1544432869.226 [MassServo](DEBUG): Initializing MassServo.
2018-12-10T09:07:49.231Z,1544432869.231 [RudderServo](DEBUG): Initializing EZServoServo.
2018-12-10T09:07:49.238Z,1544432869.238 [RudderServo](DEBUG): Initializing RudderServo.
2018-12-10T09:07:49.252Z,1544432869.252 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-12-10T09:07:49.258Z,1544432869.258 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-12-10T09:07:49.295Z,1544432869.295 [CommandLine](FAULT): Scheduling is paused
2018-12-10T09:07:49.296Z,1544432869.296 [CBIT](INFO): Critical error at 20181210T090748
2018-12-10T09:07:49.296Z,1544432869.296 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-12-10T09:07:50.130Z,1544432870.130 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-12-10T09:07:50.130Z,1544432870.130 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-12-10T09:07:50.130Z,1544432870.130 [BuoyancyServo] Communications Fault, FailCount= 1
2018-12-10T09:07:50.130Z,1544432870.130 [BuoyancyServo](ERROR): Communications Fault
2018-12-10T09:07:50.341Z,1544432870.341 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-12-10T09:07:51.443Z,1544432871.443 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2018-12-10T09:07:52.110Z,1544432872.110 [Aanderaa_O2](INFO): Powering down
2018-12-10T09:07:52.432Z,1544432872.432 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:07:52.595Z,1544432872.595 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-12-10T09:07:52.595Z,1544432872.595 [BuoyancyServo] No Fault, FailCount= 1
2018-12-10T09:07:52.633Z,1544432872.633 [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-12-10T09:07:52.835Z,1544432872.835 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:07:52.836Z,1544432872.836 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:07:52.837Z,1544432872.837 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:07:52.898Z,1544432872.898 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-10T09:07:52.898Z,1544432872.898 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-10T09:08:11.676Z,1544432891.676 [SBIT](IMPORTANT): Beginning Startup BIT
2018-12-10T09:08:11.692Z,1544432891.692 [CBIT](IMPORTANT): Beginning ground fault scan
2018-12-10T09:08:16.131Z,1544432896.131 [NAL9602](INFO): Powering up NAL9602
2018-12-10T09:08:22.602Z,1544432902.602 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011923
CHAN A1 (24V): -0.015557
CHAN A2 (12V): -0.002060
CHAN A3 (5V): -0.001615
CHAN B0 (3.3V): -0.000031
CHAN B1 (3.15aV): -0.000257
CHAN B2 (3.15bV): 0.000130
CHAN B3 (GND): -0.000298
OPEN: -0.000370
Full Scale Calc: 4.765 mA, -1.589 mA
2018-12-10T09:08:27.584Z,1544432907.584 [NAL9602](INFO): NAL9602 initialized
2018-12-10T09:09:05.812Z,1544432945.812 [SBIT](IMPORTANT): SBIT PASSED
2018-12-10T09:09:05.885Z,1544432945.885 [CommandLine](IMPORTANT): got command configSet list
2018-12-10T09:09:05.885Z,1544432945.885 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-12-10T09:09:05.889Z,1544432945.889 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-12-10T09:09:05.890Z,1544432945.890 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=28 count;

2018-12-10T09:09:05.890Z,1544432945.890 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-12-10T09:09:05.890Z,1544432945.890 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2018-12-10T09:09:05.890Z,1544432945.890 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;

2018-12-10T09:09:05.890Z,1544432945.890 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_salinity;

2018-12-10T09:09:05.890Z,1544432945.890 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_temperature 0.150000 kelvin;

2018-12-10T09:09:05.890Z,1544432945.890 [CommandLine](IMPORTANT): Express linearApproximation CTD_NeilBrown.bin_median_sea_water_salinity 0.020000 practical_salinity_unit;

2018-12-10T09:09:05.891Z,1544432945.891 [CommandLine](IMPORTANT): Express linearApproximation CTD_NeilBrown.bin_median_sea_water_temperature 0.150000 kelvin;

2018-12-10T09:09:05.891Z,1544432945.891 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-12-10T09:09:05.891Z,1544432945.891 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-12-10T09:09:05.891Z,1544432945.891 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-12-10T09:09:05.891Z,1544432945.891 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-12-10T09:09:05.891Z,1544432945.891 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-12-10T09:09:06.157Z,1544432946.157 [MissionManager](IMPORTANT): Started mission Startup
2018-12-10T09:09:06.170Z,1544432946.170 [Startup] Running Loop=1
2018-12-10T09:09:06.170Z,1544432946.170 [Startup](DEBUG): Aggregate::initialize Startup
2018-12-10T09:09:06.170Z,1544432946.170 [Startup:A.GoToSurface] Running Loop=1
2018-12-10T09:09:06.170Z,1544432946.170 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-12-10T09:09:06.171Z,1544432946.171 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-12-10T09:09:06.171Z,1544432946.171 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-12-10T09:09:06.171Z,1544432946.171 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-12-10T09:09:06.172Z,1544432946.172 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-12-10T09:09:06.172Z,1544432946.172 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-12-10T09:09:06.177Z,1544432946.177 [Startup:StartupSatComms] Running Loop=1
2018-12-10T09:09:06.178Z,1544432946.178 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-12-10T09:09:06.178Z,1544432946.178 [Startup:StartupSatComms:A] Running Loop=1
2018-12-10T09:09:06.598Z,1544432946.598 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-12-10T09:10:06.577Z,1544433006.577 [Startup:StartupSatComms:A](INFO): Timed out from 2018-12-10T09:09:06.2Z
2018-12-10T09:10:06.577Z,1544433006.577 [Startup:StartupSatComms:A] Stopped
2018-12-10T09:10:06.577Z,1544433006.577 [Startup:StartupSatComms:B] Running Loop=1
2018-12-10T09:10:06.980Z,1544433006.980 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-12-10T09:10:19.499Z,1544433019.499 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005873
2018-12-10T09:10:24.308Z,1544433024.308 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181209T131228/Courier0349.lzma
2018-12-10T09:10:25.100Z,1544433025.100 [DataOverHttps](INFO): Moved sent file to Logs/20181209T131228/Courier0349.lzma.bak
2018-12-10T09:10:25.101Z,1544433025.101 [DataOverHttps](INFO): SBD MOMSN=8986009
2018-12-10T09:10:35.768Z,1544433035.768 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20181210T090738/Courier0000.lzma
2018-12-10T09:10:36.568Z,1544433036.568 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0000.lzma.bak
2018-12-10T09:10:36.568Z,1544433036.568 [DataOverHttps](INFO): SBD MOMSN=8986011
2018-12-10T09:10:53.723Z,1544433053.723 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:10:53.724Z,1544433053.724 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-12-10T09:10:53.724Z,1544433053.724 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:10:53.763Z,1544433053.763 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:10:53.775Z,1544433053.775 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181209T131228/Express0350.lzma
2018-12-10T09:10:53.879Z,1544433053.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:10:53.879Z,1544433053.879 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-12-10T09:10:54.231Z,1544433054.231 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:10:54.231Z,1544433054.231 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:10:54.232Z,1544433054.232 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:10:54.572Z,1544433054.572 [DataOverHttps](INFO): Moved sent file to Logs/20181209T131228/Express0350.lzma.bak
2018-12-10T09:10:54.572Z,1544433054.572 [DataOverHttps](INFO): SBD MOMSN=8986015
2018-12-10T09:11:04.676Z,1544433064.676 [DataOverHttps](INFO): Sending 1068 bytes from file Logs/20181210T090738/Express0001.lzma
2018-12-10T09:11:05.474Z,1544433065.474 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0001.lzma.bak
2018-12-10T09:11:05.474Z,1544433065.474 [DataOverHttps](INFO): SBD MOMSN=8986017
2018-12-10T09:11:06.181Z,1544433066.181 [Startup:StartupSatComms:B] Stopped
2018-12-10T09:11:06.182Z,1544433066.181 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-12-10T09:11:06.182Z,1544433066.182 [Startup:StartupSatComms] Stopped
2018-12-10T09:11:06.182Z,1544433066.182 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-12-10T09:11:06.182Z,1544433066.182 [Startup](INFO): Completed Startup
2018-12-10T09:11:06.183Z,1544433066.183 [MissionManager](INFO): Startup is completed.
2018-12-10T09:11:06.183Z,1544433066.183 [MissionManager](INFO): Uninitializing Mission Startup
2018-12-10T09:11:06.183Z,1544433066.183 [Startup] Stopped
2018-12-10T09:11:06.183Z,1544433066.183 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-12-10T09:11:06.183Z,1544433066.183 [Startup:A.GoToSurface] Stopped
2018-12-10T09:11:06.183Z,1544433066.183 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-12-10T09:11:06.333Z,1544433066.333 [MissionManager](IMPORTANT): Started mission Default
2018-12-10T09:11:06.333Z,1544433066.333 [Default] Running Loop=1
2018-12-10T09:11:06.334Z,1544433066.334 [Default](DEBUG): Aggregate::initialize Default
2018-12-10T09:11:06.334Z,1544433066.334 [Default:B.GoToSurface] Running Loop=1
2018-12-10T09:11:06.334Z,1544433066.334 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-12-10T09:11:06.334Z,1544433066.334 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-12-10T09:11:06.334Z,1544433066.334 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-12-10T09:11:06.335Z,1544433066.335 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-12-10T09:11:06.335Z,1544433066.335 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-12-10T09:11:06.335Z,1544433066.335 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-12-10T09:11:06.335Z,1544433066.335 [Default:A.Wait] Running Loop=1
2018-12-10T09:11:06.335Z,1544433066.335 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-12-10T09:11:19.537Z,1544433079.537 [Default:A.Wait](INFO): Done Waiting.
2018-12-10T09:11:19.538Z,1544433079.538 [Default:A.Wait] Stopped
2018-12-10T09:11:19.538Z,1544433079.538 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T09:11:19.923Z,1544433079.923 [Default:CheckIn] Running Loop=1
2018-12-10T09:11:19.923Z,1544433079.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T09:11:19.923Z,1544433079.923 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T09:11:20.341Z,1544433080.341 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-12-10T09:11:23.742Z,1544433083.742 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:11:23.742Z,1544433083.742 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-10T09:11:23.742Z,1544433083.742 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:11:23.945Z,1544433083.945 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:11:24.145Z,1544433084.145 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:11:24.240Z,1544433084.240 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:11:25.560Z,1544433085.560 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:11:25.560Z,1544433085.560 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-10T09:11:25.854Z,1544433085.854 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:11:25.854Z,1544433085.854 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:11:25.854Z,1544433085.854 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:11:25.854Z,1544433085.854 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:11:30.074Z,1544433090.074 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:11:30.136Z,1544433090.136 [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-12-10T09:11:30.137Z,1544433090.137 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:11:30.140Z,1544433090.140 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:11:30.141Z,1544433090.141 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:13:30.411Z,1544433210.411 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-12-10T09:13:54.448Z,1544433234.448 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:13:54.448Z,1544433234.448 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-12-10T09:13:54.448Z,1544433234.448 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:13:54.463Z,1544433234.463 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:13:54.923Z,1544433234.923 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:13:54.923Z,1544433234.923 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-12-10T09:13:55.270Z,1544433235.270 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:13:55.270Z,1544433235.270 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:13:55.271Z,1544433235.271 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:15:18.850Z,1544433318.850 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:15:18.850Z,1544433318.850 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-12-10T09:15:18.850Z,1544433318.850 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:15:19.114Z,1544433319.114 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:15:19.254Z,1544433319.254 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:15:19.342Z,1544433319.342 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:15:20.706Z,1544433320.706 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:15:20.707Z,1544433320.707 [Rowe_600LCM] No Fault, FailCount= 2
2018-12-10T09:15:20.958Z,1544433320.958 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:15:20.958Z,1544433320.958 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:15:20.958Z,1544433320.958 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:15:20.958Z,1544433320.958 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:15:25.199Z,1544433325.199 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:15:25.264Z,1544433325.264 [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-12-10T09:15:25.267Z,1544433325.267 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:15:25.267Z,1544433325.267 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:15:25.268Z,1544433325.268 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:16:20.337Z,1544433380.337 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T09:11:19.9Z
2018-12-10T09:16:20.337Z,1544433380.337 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T09:16:20.342Z,1544433380.342 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T09:16:20.741Z,1544433380.741 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-12-10T09:16:25.608Z,1544433385.608 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0004.lzma
2018-12-10T09:16:26.392Z,1544433386.392 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0004.lzma.bak
2018-12-10T09:16:26.392Z,1544433386.392 [DataOverHttps](INFO): SBD MOMSN=8986047
2018-12-10T09:16:36.442Z,1544433396.442 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20181210T090738/Express0005.lzma
2018-12-10T09:16:37.212Z,1544433397.212 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0005.lzma.bak
2018-12-10T09:16:37.213Z,1544433397.213 [DataOverHttps](INFO): SBD MOMSN=8986049
2018-12-10T09:16:37.945Z,1544433397.945 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T09:16:37.945Z,1544433397.945 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T09:16:37.945Z,1544433397.945 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T09:16:55.424Z,1544433415.424 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:16:55.424Z,1544433415.424 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-12-10T09:16:55.424Z,1544433415.424 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:16:55.436Z,1544433415.436 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:16:55.830Z,1544433415.830 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:16:55.831Z,1544433415.831 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-12-10T09:16:56.219Z,1544433416.219 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:16:56.220Z,1544433416.220 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:16:56.220Z,1544433416.220 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:18:29.567Z,1544433509.567 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T09:18:29.567Z,1544433509.567 [NAL9602] Data Fault, FailCount= 1
2018-12-10T09:18:29.567Z,1544433509.567 [NAL9602](ERROR): Data Fault
2018-12-10T09:18:29.608Z,1544433509.608 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T09:18:29.959Z,1544433509.959 [NAL9602](INFO): Powering down
2018-12-10T09:18:30.885Z,1544433510.885 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T09:18:30.885Z,1544433510.885 [NAL9602] No Fault, FailCount= 1
2018-12-10T09:18:48.753Z,1544433528.753 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:18:48.754Z,1544433528.754 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-12-10T09:18:48.754Z,1544433528.754 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:18:48.765Z,1544433528.765 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:18:49.158Z,1544433529.158 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:18:49.279Z,1544433529.279 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:18:50.769Z,1544433530.769 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:18:50.770Z,1544433530.770 [Rowe_600LCM] No Fault, FailCount= 3
2018-12-10T09:18:50.893Z,1544433530.893 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:18:50.894Z,1544433530.894 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:18:50.894Z,1544433530.894 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:18:50.894Z,1544433530.894 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:18:55.018Z,1544433535.018 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:18:55.081Z,1544433535.081 [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-12-10T09:18:55.083Z,1544433535.083 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:18:55.084Z,1544433535.084 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:18:55.085Z,1544433535.085 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:19:00.299Z,1544433540.299 [NAL9602](INFO): Powering up NAL9602
2018-12-10T09:19:11.499Z,1544433551.499 [NAL9602](INFO): NAL9602 initialized
2018-12-10T09:19:56.537Z,1544433596.537 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:19:56.537Z,1544433596.537 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-12-10T09:19:56.537Z,1544433596.537 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:19:56.550Z,1544433596.550 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:19:56.953Z,1544433596.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:19:56.953Z,1544433596.953 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-12-10T09:19:57.339Z,1544433597.339 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:19:57.340Z,1544433597.340 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:19:57.340Z,1544433597.340 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:20:51.313Z,1544433651.313 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:20:51.314Z,1544433651.314 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-12-10T09:20:51.314Z,1544433651.314 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:20:51.711Z,1544433651.711 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:20:51.718Z,1544433651.718 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:20:51.893Z,1544433651.893 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:20:53.291Z,1544433653.291 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:20:53.291Z,1544433653.291 [Rowe_600LCM] No Fault, FailCount= 4
2018-12-10T09:20:53.510Z,1544433653.510 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:20:53.510Z,1544433653.510 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:20:53.510Z,1544433653.510 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:20:53.510Z,1544433653.510 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:20:57.723Z,1544433657.723 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:20:57.812Z,1544433657.812 [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-12-10T09:20:57.815Z,1544433657.815 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:20:57.816Z,1544433657.816 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:20:57.816Z,1544433657.816 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:21:38.461Z,1544433698.461 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T09:21:38.461Z,1544433698.461 [Default:CheckIn:C.Wait] Stopped
2018-12-10T09:21:38.461Z,1544433698.461 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T09:21:38.461Z,1544433698.461 [Default:CheckIn:D] Running Loop=1
2018-12-10T09:21:38.861Z,1544433698.861 [Default:CheckIn:D] Stopped
2018-12-10T09:21:38.861Z,1544433698.861 [Default:CheckIn:E] Running Loop=1
2018-12-10T09:21:39.267Z,1544433699.267 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.542098 min 
2018-12-10T09:21:39.269Z,1544433699.269 [Default:CheckIn:E] Stopped
2018-12-10T09:21:39.269Z,1544433699.269 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T09:21:39.269Z,1544433699.269 [Default:CheckIn] Stopped
2018-12-10T09:21:39.269Z,1544433699.269 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T09:21:39.270Z,1544433699.270 [Default:CheckIn](INFO): Running loop #2
2018-12-10T09:21:39.270Z,1544433699.270 [Default:CheckIn] Running Loop=2
2018-12-10T09:21:39.270Z,1544433699.270 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T09:21:39.270Z,1544433699.270 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T09:22:57.595Z,1544433777.595 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:22:57.595Z,1544433777.595 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-12-10T09:22:57.595Z,1544433777.595 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:22:57.631Z,1544433777.631 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:22:58.095Z,1544433778.095 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:22:58.095Z,1544433778.095 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-12-10T09:22:58.434Z,1544433778.434 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:22:58.434Z,1544433778.434 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:22:58.435Z,1544433778.435 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:25:58.867Z,1544433958.867 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:25:58.867Z,1544433958.867 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-12-10T09:25:58.867Z,1544433958.867 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:25:58.901Z,1544433958.901 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:25:59.233Z,1544433959.233 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:25:59.233Z,1544433959.233 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-12-10T09:25:59.633Z,1544433959.633 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:25:59.634Z,1544433959.634 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:25:59.634Z,1544433959.634 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:26:39.626Z,1544433999.626 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T09:21:39.3Z
2018-12-10T09:26:39.626Z,1544433999.626 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T09:26:39.626Z,1544433999.626 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T09:26:44.003Z,1544434004.003 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0007.lzma
2018-12-10T09:26:44.776Z,1544434004.776 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0007.lzma.bak
2018-12-10T09:26:44.776Z,1544434004.776 [DataOverHttps](INFO): SBD MOMSN=8986074
2018-12-10T09:26:52.409Z,1544434012.409 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:26:52.410Z,1544434012.410 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-12-10T09:26:52.410Z,1544434012.410 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:26:52.814Z,1544434012.814 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:26:53.014Z,1544434013.014 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:26:54.825Z,1544434014.825 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:26:54.825Z,1544434014.825 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-12-10T09:26:54.868Z,1544434014.868 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20181210T090738/Express0008.lzma
2018-12-10T09:26:55.637Z,1544434015.637 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0008.lzma.bak
2018-12-10T09:26:55.637Z,1544434015.637 [DataOverHttps](INFO): SBD MOMSN=8986076
2018-12-10T09:26:56.342Z,1544434016.342 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T09:26:56.342Z,1544434016.342 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T09:26:56.342Z,1544434016.342 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T09:29:00.482Z,1544434140.482 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:29:00.482Z,1544434140.482 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-12-10T09:29:00.482Z,1544434140.482 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:29:00.494Z,1544434140.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:29:00.635Z,1544434140.635 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:29:00.635Z,1544434140.635 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-12-10T09:29:00.951Z,1544434140.951 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:29:00.952Z,1544434140.952 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:29:00.952Z,1544434140.952 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:29:12.915Z,1544434152.915 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T09:29:12.915Z,1544434152.915 [NAL9602] Data Fault, FailCount= 2
2018-12-10T09:29:12.916Z,1544434152.916 [NAL9602](ERROR): Data Fault
2018-12-10T09:29:12.973Z,1544434152.973 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T09:29:13.315Z,1544434153.315 [NAL9602](INFO): Powering down
2018-12-10T09:29:14.182Z,1544434154.182 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T09:29:14.182Z,1544434154.182 [NAL9602] No Fault, FailCount= 2
2018-12-10T09:29:43.711Z,1544434183.711 [NAL9602](INFO): Powering up NAL9602
2018-12-10T09:29:54.907Z,1544434194.907 [NAL9602](INFO): NAL9602 initialized
2018-12-10T09:31:56.589Z,1544434316.589 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T09:31:56.589Z,1544434316.589 [Default:CheckIn:C.Wait] Stopped
2018-12-10T09:31:56.589Z,1544434316.589 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T09:31:56.589Z,1544434316.589 [Default:CheckIn:D] Running Loop=1
2018-12-10T09:31:56.995Z,1544434316.995 [Default:CheckIn:D] Stopped
2018-12-10T09:31:56.995Z,1544434316.995 [Default:CheckIn:E] Running Loop=1
2018-12-10T09:31:57.371Z,1544434317.371 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.844366 min 
2018-12-10T09:31:57.371Z,1544434317.371 [Default:CheckIn:E] Stopped
2018-12-10T09:31:57.371Z,1544434317.371 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T09:31:57.371Z,1544434317.371 [Default:CheckIn] Stopped
2018-12-10T09:31:57.371Z,1544434317.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T09:31:57.371Z,1544434317.371 [Default:CheckIn](INFO): Running loop #3
2018-12-10T09:31:57.372Z,1544434317.372 [Default:CheckIn] Running Loop=3
2018-12-10T09:31:57.372Z,1544434317.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T09:31:57.372Z,1544434317.372 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T09:32:01.012Z,1544434321.012 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:32:01.013Z,1544434321.013 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-12-10T09:32:01.013Z,1544434321.013 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:32:01.041Z,1544434321.041 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:32:01.402Z,1544434321.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:32:01.402Z,1544434321.402 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-12-10T09:32:01.758Z,1544434321.758 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:32:01.758Z,1544434321.758 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:32:01.759Z,1544434321.759 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:35:01.776Z,1544434501.776 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:35:01.776Z,1544434501.776 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-12-10T09:35:01.776Z,1544434501.776 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:35:01.809Z,1544434501.809 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:35:02.158Z,1544434502.158 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:35:02.158Z,1544434502.158 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-12-10T09:35:02.519Z,1544434502.519 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:35:02.519Z,1544434502.519 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:35:02.519Z,1544434502.519 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:36:52.555Z,1544434612.555 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:36:52.555Z,1544434612.555 [Rowe_600LCM] No Fault, FailCount= 5
2018-12-10T09:36:52.835Z,1544434612.835 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:36:52.836Z,1544434612.836 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:36:52.836Z,1544434612.836 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:36:52.836Z,1544434612.836 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:36:57.067Z,1544434617.067 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:36:57.132Z,1544434617.132 [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-12-10T09:36:57.135Z,1544434617.135 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:36:57.136Z,1544434617.136 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:36:57.136Z,1544434617.136 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:36:57.724Z,1544434617.724 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T09:31:57.4Z
2018-12-10T09:36:57.724Z,1544434617.724 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T09:36:57.724Z,1544434617.724 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T09:37:03.613Z,1544434623.613 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0010.lzma
2018-12-10T09:37:04.407Z,1544434624.407 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0010.lzma.bak
2018-12-10T09:37:04.408Z,1544434624.408 [DataOverHttps](INFO): SBD MOMSN=8986099
2018-12-10T09:37:14.771Z,1544434634.771 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20181210T090738/Express0011.lzma
2018-12-10T09:37:15.550Z,1544434635.550 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0011.lzma.bak
2018-12-10T09:37:15.550Z,1544434635.550 [DataOverHttps](INFO): SBD MOMSN=8986101
2018-12-10T09:37:16.289Z,1544434636.289 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T09:37:16.289Z,1544434636.289 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T09:37:16.289Z,1544434636.289 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T09:38:02.822Z,1544434682.822 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:38:02.822Z,1544434682.822 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-12-10T09:38:02.822Z,1544434682.822 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:38:02.892Z,1544434682.892 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:38:03.258Z,1544434683.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:38:03.259Z,1544434683.259 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-12-10T09:38:03.579Z,1544434683.579 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:38:03.579Z,1544434683.579 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:38:03.579Z,1544434683.579 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:39:37.581Z,1544434777.581 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:39:37.582Z,1544434777.582 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-10T09:39:37.582Z,1544434777.582 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:39:37.983Z,1544434777.983 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:39:37.986Z,1544434777.986 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:39:38.103Z,1544434778.103 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:39:39.591Z,1544434779.591 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:39:39.591Z,1544434779.591 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-10T09:39:39.718Z,1544434779.718 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:39:39.718Z,1544434779.718 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:39:39.718Z,1544434779.718 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:39:39.718Z,1544434779.718 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:39:43.843Z,1544434783.843 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:39:43.925Z,1544434783.925 [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-12-10T09:39:43.927Z,1544434783.927 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:39:43.927Z,1544434783.927 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:39:43.928Z,1544434783.928 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:39:58.307Z,1544434798.307 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T09:39:58.307Z,1544434798.307 [NAL9602] Data Fault, FailCount= 3
2018-12-10T09:39:58.307Z,1544434798.307 [NAL9602](ERROR): Data Fault
2018-12-10T09:39:58.412Z,1544434798.412 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T09:39:58.707Z,1544434798.707 [NAL9602](INFO): Powering down
2018-12-10T09:39:59.572Z,1544434799.572 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T09:39:59.572Z,1544434799.572 [NAL9602] No Fault, FailCount= 3
2018-12-10T09:40:29.071Z,1544434829.071 [NAL9602](INFO): Powering up NAL9602
2018-12-10T09:40:40.283Z,1544434840.283 [NAL9602](INFO): NAL9602 initialized
2018-12-10T09:41:03.939Z,1544434863.939 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:41:03.939Z,1544434863.939 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-12-10T09:41:03.939Z,1544434863.939 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:41:03.964Z,1544434863.964 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:41:04.369Z,1544434864.369 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:41:04.370Z,1544434864.370 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-12-10T09:41:04.723Z,1544434864.723 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:41:04.723Z,1544434864.723 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:41:04.723Z,1544434864.723 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:42:16.526Z,1544434936.526 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T09:42:16.527Z,1544434936.527 [Default:CheckIn:C.Wait] Stopped
2018-12-10T09:42:16.527Z,1544434936.527 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T09:42:16.527Z,1544434936.527 [Default:CheckIn:D] Running Loop=1
2018-12-10T09:42:16.939Z,1544434936.939 [Default:CheckIn:D] Stopped
2018-12-10T09:42:16.939Z,1544434936.939 [Default:CheckIn:E] Running Loop=1
2018-12-10T09:42:17.335Z,1544434937.335 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.176754 min 
2018-12-10T09:42:17.335Z,1544434937.335 [Default:CheckIn:E] Stopped
2018-12-10T09:42:17.335Z,1544434937.335 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T09:42:17.335Z,1544434937.335 [Default:CheckIn] Stopped
2018-12-10T09:42:17.335Z,1544434937.335 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T09:42:17.335Z,1544434937.335 [Default:CheckIn](INFO): Running loop #4
2018-12-10T09:42:17.335Z,1544434937.335 [Default:CheckIn] Running Loop=4
2018-12-10T09:42:17.335Z,1544434937.335 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T09:42:17.336Z,1544434937.336 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T09:44:04.866Z,1544435044.866 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:44:04.866Z,1544435044.866 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-12-10T09:44:04.866Z,1544435044.866 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:44:04.886Z,1544435044.886 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:44:05.303Z,1544435045.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:44:05.303Z,1544435045.303 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-12-10T09:44:05.655Z,1544435045.655 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:44:05.655Z,1544435045.655 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:44:05.655Z,1544435045.655 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:47:05.897Z,1544435225.897 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:47:05.897Z,1544435225.897 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-12-10T09:47:05.897Z,1544435225.897 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:47:05.942Z,1544435225.942 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:47:06.304Z,1544435226.304 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:47:06.304Z,1544435226.304 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-12-10T09:47:06.683Z,1544435226.683 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:47:06.683Z,1544435226.683 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:47:06.684Z,1544435226.684 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:47:17.486Z,1544435237.486 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T09:42:17.3Z
2018-12-10T09:47:17.486Z,1544435237.486 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T09:47:17.486Z,1544435237.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T09:47:21.784Z,1544435241.784 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0013.lzma
2018-12-10T09:47:22.575Z,1544435242.575 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0013.lzma.bak
2018-12-10T09:47:22.576Z,1544435242.576 [DataOverHttps](INFO): SBD MOMSN=8986125
2018-12-10T09:47:32.567Z,1544435252.567 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20181210T090738/Express0014.lzma
2018-12-10T09:47:33.336Z,1544435253.336 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0014.lzma.bak
2018-12-10T09:47:33.336Z,1544435253.336 [DataOverHttps](INFO): SBD MOMSN=8986127
2018-12-10T09:47:34.260Z,1544435254.260 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T09:47:34.261Z,1544435254.261 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T09:47:34.261Z,1544435254.261 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T09:47:36.977Z,1544435256.977 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:47:36.977Z,1544435256.977 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-12-10T09:47:36.978Z,1544435256.978 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:47:37.381Z,1544435257.381 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:47:37.527Z,1544435257.527 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:47:37.949Z,1544435257.949 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:47:38.852Z,1544435258.852 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:47:38.852Z,1544435258.852 [Rowe_600LCM] No Fault, FailCount= 2
2018-12-10T09:47:39.142Z,1544435259.142 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:47:39.142Z,1544435259.142 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:47:39.142Z,1544435259.142 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:47:39.142Z,1544435259.142 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:47:43.349Z,1544435263.349 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:47:43.410Z,1544435263.410 [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-12-10T09:47:43.411Z,1544435263.411 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:47:43.412Z,1544435263.412 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:47:43.413Z,1544435263.413 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:50:06.779Z,1544435406.779 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:50:06.779Z,1544435406.779 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-12-10T09:50:06.779Z,1544435406.779 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:50:06.842Z,1544435406.842 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:50:07.164Z,1544435407.164 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:50:07.164Z,1544435407.164 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-12-10T09:50:07.553Z,1544435407.553 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:50:07.554Z,1544435407.554 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:50:07.554Z,1544435407.554 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:50:41.539Z,1544435441.539 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T09:50:41.539Z,1544435441.539 [NAL9602] Data Fault, FailCount= 4
2018-12-10T09:50:41.539Z,1544435441.539 [NAL9602](ERROR): Data Fault
2018-12-10T09:50:41.642Z,1544435441.642 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T09:50:41.931Z,1544435441.931 [NAL9602](INFO): Powering down
2018-12-10T09:50:42.808Z,1544435442.808 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T09:50:42.808Z,1544435442.808 [NAL9602] No Fault, FailCount= 4
2018-12-10T09:51:12.335Z,1544435472.335 [NAL9602](INFO): Powering up NAL9602
2018-12-10T09:51:23.539Z,1544435483.539 [NAL9602](INFO): NAL9602 initialized
2018-12-10T09:52:34.590Z,1544435554.590 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T09:52:34.591Z,1544435554.591 [Default:CheckIn:C.Wait] Stopped
2018-12-10T09:52:34.591Z,1544435554.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T09:52:34.591Z,1544435554.591 [Default:CheckIn:D] Running Loop=1
2018-12-10T09:52:35.012Z,1544435555.012 [Default:CheckIn:D] Stopped
2018-12-10T09:52:35.012Z,1544435555.012 [Default:CheckIn:E] Running Loop=1
2018-12-10T09:52:35.424Z,1544435555.424 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.477979 min 
2018-12-10T09:52:35.424Z,1544435555.424 [Default:CheckIn:E] Stopped
2018-12-10T09:52:35.424Z,1544435555.424 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T09:52:35.424Z,1544435555.424 [Default:CheckIn] Stopped
2018-12-10T09:52:35.425Z,1544435555.425 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T09:52:35.425Z,1544435555.425 [Default:CheckIn](INFO): Running loop #5
2018-12-10T09:52:35.425Z,1544435555.425 [Default:CheckIn] Running Loop=5
2018-12-10T09:52:35.425Z,1544435555.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T09:52:35.425Z,1544435555.425 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T09:53:07.780Z,1544435587.780 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:53:07.780Z,1544435587.780 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-12-10T09:53:07.780Z,1544435587.780 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:53:07.792Z,1544435587.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:53:08.243Z,1544435588.243 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:53:08.243Z,1544435588.243 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-12-10T09:53:08.635Z,1544435588.635 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:53:08.636Z,1544435588.636 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:53:08.636Z,1544435588.636 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:53:54.961Z,1544435634.961 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:53:54.961Z,1544435634.961 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-12-10T09:53:54.961Z,1544435634.961 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:53:55.323Z,1544435635.323 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:53:55.366Z,1544435635.366 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:53:55.458Z,1544435635.458 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:53:56.914Z,1544435636.914 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:53:56.914Z,1544435636.914 [Rowe_600LCM] No Fault, FailCount= 3
2018-12-10T09:53:57.073Z,1544435637.073 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:53:57.074Z,1544435637.074 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:53:57.074Z,1544435637.074 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:53:57.074Z,1544435637.074 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:54:01.196Z,1544435641.196 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:54:01.346Z,1544435641.346 [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-12-10T09:54:01.347Z,1544435641.347 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:54:01.348Z,1544435641.348 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:54:01.348Z,1544435641.348 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:55:57.109Z,1544435757.109 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:55:57.110Z,1544435757.110 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-12-10T09:55:57.110Z,1544435757.110 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:55:57.207Z,1544435757.207 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:55:57.513Z,1544435757.513 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:55:57.717Z,1544435757.717 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:55:59.202Z,1544435759.202 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T09:55:59.202Z,1544435759.202 [Rowe_600LCM] No Fault, FailCount= 4
2018-12-10T09:55:59.334Z,1544435759.334 [Rowe_600LCM](INFO): Initializing
2018-12-10T09:55:59.334Z,1544435759.334 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T09:55:59.334Z,1544435759.334 [Rowe_600LCM](INFO): LCM OK
2018-12-10T09:55:59.334Z,1544435759.334 [Rowe_600LCM](INFO): Powering up
2018-12-10T09:56:03.454Z,1544435763.454 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T09:56:03.569Z,1544435763.569 [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-12-10T09:56:03.570Z,1544435763.570 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T09:56:03.571Z,1544435763.571 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T09:56:03.571Z,1544435763.571 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T09:56:08.789Z,1544435768.789 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:56:08.789Z,1544435768.789 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-12-10T09:56:08.789Z,1544435768.789 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:56:08.819Z,1544435768.819 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:56:09.183Z,1544435769.183 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:56:09.183Z,1544435769.183 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-12-10T09:56:09.572Z,1544435769.572 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:56:09.572Z,1544435769.572 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:56:09.573Z,1544435769.573 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T09:57:35.459Z,1544435855.459 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T09:52:35.4Z
2018-12-10T09:57:35.459Z,1544435855.459 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T09:57:35.460Z,1544435855.460 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T09:57:40.619Z,1544435860.619 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0016.lzma
2018-12-10T09:57:41.411Z,1544435861.411 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0016.lzma.bak
2018-12-10T09:57:41.412Z,1544435861.412 [DataOverHttps](INFO): SBD MOMSN=8986150
2018-12-10T09:57:51.309Z,1544435871.309 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20181210T090738/Express0017.lzma
2018-12-10T09:57:52.108Z,1544435872.108 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0017.lzma.bak
2018-12-10T09:57:52.108Z,1544435872.108 [DataOverHttps](INFO): SBD MOMSN=8986152
2018-12-10T09:57:52.858Z,1544435872.858 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T09:57:52.858Z,1544435872.858 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T09:57:52.858Z,1544435872.858 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T09:58:21.229Z,1544435901.229 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T09:58:21.229Z,1544435901.229 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-12-10T09:58:21.229Z,1544435901.229 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T09:58:21.316Z,1544435901.316 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T09:58:21.317Z,1544435901.317 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-12-10T09:58:21.644Z,1544435901.644 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T09:58:21.849Z,1544435901.849 [Rowe_600LCM](INFO): Powering down
2018-12-10T09:59:09.672Z,1544435949.672 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T09:59:09.672Z,1544435949.672 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-12-10T09:59:09.672Z,1544435949.672 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T09:59:09.684Z,1544435949.684 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T09:59:10.084Z,1544435950.084 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T09:59:10.084Z,1544435950.084 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-12-10T09:59:10.457Z,1544435950.457 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T09:59:10.457Z,1544435950.457 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T09:59:10.462Z,1544435950.462 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:01:24.403Z,1544436084.403 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T10:01:24.403Z,1544436084.403 [NAL9602] Data Fault, FailCount= 5
2018-12-10T10:01:24.403Z,1544436084.403 [NAL9602](ERROR): Data Fault
2018-12-10T10:01:24.487Z,1544436084.487 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T10:01:24.487Z,1544436084.487 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-12-10T10:01:24.804Z,1544436084.804 [NAL9602](INFO): Powering down
2018-12-10T10:01:24.873Z,1544436084.873 [CBIT](INFO): Critical error at 20181210T100124
2018-12-10T10:02:10.810Z,1544436130.810 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:02:10.810Z,1544436130.810 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-12-10T10:02:10.810Z,1544436130.810 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:02:10.852Z,1544436130.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:02:11.219Z,1544436131.219 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:02:11.219Z,1544436131.219 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-12-10T10:02:11.605Z,1544436131.605 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:02:11.605Z,1544436131.605 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:02:11.606Z,1544436131.606 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:02:53.207Z,1544436173.207 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T10:02:53.207Z,1544436173.207 [Default:CheckIn:C.Wait] Stopped
2018-12-10T10:02:53.207Z,1544436173.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T10:02:53.207Z,1544436173.207 [Default:CheckIn:D] Running Loop=1
2018-12-10T10:02:53.641Z,1544436173.641 [Default:CheckIn:D] Stopped
2018-12-10T10:02:53.641Z,1544436173.641 [Default:CheckIn:E] Running Loop=1
2018-12-10T10:02:54.004Z,1544436174.004 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.788460 min 
2018-12-10T10:02:54.004Z,1544436174.004 [Default:CheckIn:E] Stopped
2018-12-10T10:02:54.005Z,1544436174.005 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T10:02:54.005Z,1544436174.005 [Default:CheckIn] Stopped
2018-12-10T10:02:54.005Z,1544436174.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T10:02:54.005Z,1544436174.005 [Default:CheckIn](INFO): Running loop #6
2018-12-10T10:02:54.005Z,1544436174.005 [Default:CheckIn] Running Loop=6
2018-12-10T10:02:54.006Z,1544436174.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T10:02:54.006Z,1544436174.006 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T10:03:24.782Z,1544436204.782 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T10:03:24.782Z,1544436204.782 [NAL9602] No Fault, FailCount= 5
2018-12-10T10:03:25.135Z,1544436205.135 [NAL9602](INFO): Powering up NAL9602
2018-12-10T10:03:36.335Z,1544436216.335 [NAL9602](INFO): NAL9602 initialized
2018-12-10T10:05:11.982Z,1544436311.982 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:05:11.982Z,1544436311.982 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-12-10T10:05:11.982Z,1544436311.982 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:05:11.995Z,1544436311.995 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:05:12.389Z,1544436312.389 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:05:12.389Z,1544436312.389 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-12-10T10:05:12.772Z,1544436312.772 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:05:12.773Z,1544436312.773 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:05:12.773Z,1544436312.773 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:07:54.366Z,1544436474.366 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T10:02:54.0Z
2018-12-10T10:07:54.366Z,1544436474.366 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T10:07:54.366Z,1544436474.366 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T10:07:58.337Z,1544436478.337 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181210T090738/Courier0019.lzma
2018-12-10T10:07:59.136Z,1544436479.136 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0019.lzma.bak
2018-12-10T10:07:59.136Z,1544436479.136 [DataOverHttps](INFO): SBD MOMSN=8986179
2018-12-10T10:08:07.970Z,1544436487.970 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20181210T090738/Express0020.lzma
2018-12-10T10:08:08.728Z,1544436488.728 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0020.lzma.bak
2018-12-10T10:08:08.728Z,1544436488.728 [DataOverHttps](INFO): SBD MOMSN=8986182
2018-12-10T10:08:09.426Z,1544436489.426 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T10:08:09.426Z,1544436489.426 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T10:08:09.426Z,1544436489.426 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T10:08:12.821Z,1544436492.821 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:08:12.821Z,1544436492.821 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-12-10T10:08:12.821Z,1544436492.821 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:08:12.834Z,1544436492.834 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:08:12.834Z,1544436492.834 [CBIT](FAULT): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:08:21.625Z,1544436501.625 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:08:21.625Z,1544436501.625 [Rowe_600LCM] No Fault, FailCount= 5
2018-12-10T10:08:21.637Z,1544436501.637 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:08:21.638Z,1544436501.638 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:08:21.638Z,1544436501.638 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:08:21.638Z,1544436501.638 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:08:25.764Z,1544436505.764 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:08:25.822Z,1544436505.822 [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-12-10T10:08:25.823Z,1544436505.823 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:08:25.824Z,1544436505.824 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:08:25.825Z,1544436505.825 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:10:22.074Z,1544436622.074 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:10:22.074Z,1544436622.074 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-10T10:10:22.074Z,1544436622.074 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:10:22.148Z,1544436622.148 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:10:22.478Z,1544436622.478 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:10:22.737Z,1544436622.737 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:10:24.155Z,1544436624.155 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:10:24.156Z,1544436624.156 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-10T10:10:24.354Z,1544436624.354 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:10:24.354Z,1544436624.354 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:10:24.354Z,1544436624.354 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:10:24.354Z,1544436624.354 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:10:28.550Z,1544436628.550 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:10:28.674Z,1544436628.674 [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-12-10T10:10:28.675Z,1544436628.675 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:10:28.676Z,1544436628.676 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:10:28.677Z,1544436628.677 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:13:09.740Z,1544436789.740 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T10:13:09.740Z,1544436789.740 [Default:CheckIn:C.Wait] Stopped
2018-12-10T10:13:09.740Z,1544436789.740 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T10:13:09.740Z,1544436789.740 [Default:CheckIn:D] Running Loop=1
2018-12-10T10:13:10.136Z,1544436790.136 [Default:CheckIn:D] Stopped
2018-12-10T10:13:10.136Z,1544436790.136 [Default:CheckIn:E] Running Loop=1
2018-12-10T10:13:10.542Z,1544436790.542 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.063375 min 
2018-12-10T10:13:10.543Z,1544436790.543 [Default:CheckIn:E] Stopped
2018-12-10T10:13:10.543Z,1544436790.543 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T10:13:10.543Z,1544436790.543 [Default:CheckIn] Stopped
2018-12-10T10:13:10.543Z,1544436790.543 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T10:13:10.543Z,1544436790.543 [Default:CheckIn](INFO): Running loop #7
2018-12-10T10:13:10.543Z,1544436790.543 [Default:CheckIn] Running Loop=7
2018-12-10T10:13:10.543Z,1544436790.543 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T10:13:10.543Z,1544436790.543 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T10:13:37.656Z,1544436817.656 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T10:13:37.656Z,1544436817.656 [NAL9602] Data Fault, FailCount= 1
2018-12-10T10:13:37.656Z,1544436817.656 [NAL9602](ERROR): Data Fault
2018-12-10T10:13:37.735Z,1544436817.735 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T10:13:38.055Z,1544436818.055 [NAL9602](INFO): Powering down
2018-12-10T10:13:38.970Z,1544436818.970 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T10:13:38.970Z,1544436818.970 [NAL9602] No Fault, FailCount= 1
2018-12-10T10:14:08.453Z,1544436848.453 [NAL9602](INFO): Powering up NAL9602
2018-12-10T10:14:19.292Z,1544436859.292 [NAL9602](INFO): NAL9602 initialized
2018-12-10T10:16:32.113Z,1544436992.113 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:16:32.113Z,1544436992.113 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-12-10T10:16:32.114Z,1544436992.114 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:16:32.215Z,1544436992.215 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:16:32.517Z,1544436992.517 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:16:32.700Z,1544436992.700 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:16:34.185Z,1544436994.185 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:16:34.185Z,1544436994.185 [Rowe_600LCM] No Fault, FailCount= 2
2018-12-10T10:16:34.318Z,1544436994.318 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:16:34.318Z,1544436994.318 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:16:34.318Z,1544436994.318 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:16:34.318Z,1544436994.318 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:16:38.443Z,1544436998.443 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:16:38.510Z,1544436998.510 [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-12-10T10:16:38.511Z,1544436998.511 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:16:38.512Z,1544436998.512 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:16:38.512Z,1544436998.512 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:18:10.720Z,1544437090.720 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T10:13:10.5Z
2018-12-10T10:18:10.720Z,1544437090.720 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T10:18:10.720Z,1544437090.720 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T10:18:15.133Z,1544437095.133 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0022.lzma
2018-12-10T10:18:15.924Z,1544437095.924 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0022.lzma.bak
2018-12-10T10:18:15.924Z,1544437095.924 [DataOverHttps](INFO): SBD MOMSN=8986205
2018-12-10T10:18:25.722Z,1544437105.722 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20181210T090738/Express0023.lzma
2018-12-10T10:18:26.524Z,1544437106.524 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0023.lzma.bak
2018-12-10T10:18:26.524Z,1544437106.524 [DataOverHttps](INFO): SBD MOMSN=8986207
2018-12-10T10:18:27.449Z,1544437107.449 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T10:18:27.449Z,1544437107.449 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T10:18:27.449Z,1544437107.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T10:20:47.885Z,1544437247.885 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:20:47.885Z,1544437247.885 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-12-10T10:20:47.886Z,1544437247.886 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:20:47.972Z,1544437247.972 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:20:48.290Z,1544437248.290 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:20:48.459Z,1544437248.459 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:20:49.975Z,1544437249.975 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:20:49.976Z,1544437249.976 [Rowe_600LCM] No Fault, FailCount= 3
2018-12-10T10:20:50.074Z,1544437250.074 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:20:50.074Z,1544437250.074 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:20:50.074Z,1544437250.074 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:20:50.074Z,1544437250.074 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:20:54.195Z,1544437254.195 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:20:54.267Z,1544437254.267 [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-12-10T10:20:54.268Z,1544437254.268 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:20:54.269Z,1544437254.269 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:20:54.270Z,1544437254.270 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:23:13.112Z,1544437393.112 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:23:13.113Z,1544437393.113 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2018-12-10T10:23:13.473Z,1544437393.473 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:23:13.473Z,1544437393.473 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:23:13.474Z,1544437393.474 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:23:27.883Z,1544437407.883 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T10:23:27.883Z,1544437407.883 [Default:CheckIn:C.Wait] Stopped
2018-12-10T10:23:27.883Z,1544437407.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T10:23:27.884Z,1544437407.884 [Default:CheckIn:D] Running Loop=1
2018-12-10T10:23:28.281Z,1544437408.281 [Default:CheckIn:D] Stopped
2018-12-10T10:23:28.281Z,1544437408.281 [Default:CheckIn:E] Running Loop=1
2018-12-10T10:23:28.684Z,1544437408.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.365788 min 
2018-12-10T10:23:28.685Z,1544437408.685 [Default:CheckIn:E] Stopped
2018-12-10T10:23:28.685Z,1544437408.685 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T10:23:28.685Z,1544437408.685 [Default:CheckIn] Stopped
2018-12-10T10:23:28.685Z,1544437408.685 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T10:23:28.685Z,1544437408.685 [Default:CheckIn](INFO): Running loop #8
2018-12-10T10:23:28.685Z,1544437408.685 [Default:CheckIn] Running Loop=8
2018-12-10T10:23:28.685Z,1544437408.685 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T10:23:28.686Z,1544437408.686 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T10:24:20.327Z,1544437460.327 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T10:24:20.327Z,1544437460.327 [NAL9602] Data Fault, FailCount= 2
2018-12-10T10:24:20.327Z,1544437460.327 [NAL9602](ERROR): Data Fault
2018-12-10T10:24:20.368Z,1544437460.368 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T10:24:20.719Z,1544437460.719 [NAL9602](INFO): Powering down
2018-12-10T10:24:21.584Z,1544437461.584 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T10:24:21.584Z,1544437461.584 [NAL9602] No Fault, FailCount= 2
2018-12-10T10:24:50.876Z,1544437490.876 [NAL9602](INFO): Powering up NAL9602
2018-12-10T10:25:02.071Z,1544437502.071 [NAL9602](INFO): NAL9602 initialized
2018-12-10T10:25:55.953Z,1544437555.953 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:25:55.953Z,1544437555.953 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-12-10T10:25:55.954Z,1544437555.954 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:25:56.287Z,1544437556.287 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:25:56.358Z,1544437556.358 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:25:56.453Z,1544437556.453 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:25:57.881Z,1544437557.881 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:25:57.881Z,1544437557.881 [Rowe_600LCM] No Fault, FailCount= 4
2018-12-10T10:25:58.069Z,1544437558.069 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:25:58.070Z,1544437558.070 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:25:58.070Z,1544437558.070 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:25:58.070Z,1544437558.070 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:26:02.192Z,1544437562.192 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:26:02.341Z,1544437562.341 [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-12-10T10:26:02.343Z,1544437562.343 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:26:02.343Z,1544437562.343 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:26:02.344Z,1544437562.344 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:26:13.860Z,1544437573.860 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:26:13.860Z,1544437573.860 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-12-10T10:26:13.860Z,1544437573.860 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:26:13.872Z,1544437573.872 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:26:14.287Z,1544437574.287 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:26:14.287Z,1544437574.287 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-12-10T10:26:14.673Z,1544437574.673 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:26:14.674Z,1544437574.674 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:26:14.674Z,1544437574.674 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:27:58.661Z,1544437678.661 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:27:58.661Z,1544437678.661 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-12-10T10:27:58.661Z,1544437678.661 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:27:58.747Z,1544437678.747 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:27:58.747Z,1544437678.747 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-12-10T10:27:59.078Z,1544437679.078 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:27:59.278Z,1544437679.278 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:28:28.729Z,1544437708.729 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T10:23:28.7Z
2018-12-10T10:28:28.733Z,1544437708.733 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T10:28:28.734Z,1544437708.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T10:28:33.063Z,1544437713.063 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0025.lzma
2018-12-10T10:28:33.864Z,1544437713.864 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0025.lzma.bak
2018-12-10T10:28:33.864Z,1544437713.864 [DataOverHttps](INFO): SBD MOMSN=8986231
2018-12-10T10:28:42.675Z,1544437722.675 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20181210T090738/Express0026.lzma
2018-12-10T10:28:43.436Z,1544437723.436 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0026.lzma.bak
2018-12-10T10:28:43.436Z,1544437723.436 [DataOverHttps](INFO): SBD MOMSN=8986233
2018-12-10T10:28:44.157Z,1544437724.157 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T10:28:44.158Z,1544437724.158 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T10:28:44.158Z,1544437724.158 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T10:29:14.695Z,1544437754.695 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:29:14.695Z,1544437754.695 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-12-10T10:29:14.695Z,1544437754.695 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:29:14.719Z,1544437754.719 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:29:15.104Z,1544437755.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:29:15.105Z,1544437755.105 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-12-10T10:29:15.480Z,1544437755.480 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:29:15.480Z,1544437755.480 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:29:15.481Z,1544437755.481 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:32:15.727Z,1544437935.727 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:32:15.727Z,1544437935.727 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-12-10T10:32:15.727Z,1544437935.727 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:32:15.740Z,1544437935.740 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:32:16.135Z,1544437936.135 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:32:16.135Z,1544437936.135 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-12-10T10:32:16.528Z,1544437936.528 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:32:16.529Z,1544437936.529 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:32:16.529Z,1544437936.529 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:33:44.511Z,1544438024.511 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T10:33:44.511Z,1544438024.511 [Default:CheckIn:C.Wait] Stopped
2018-12-10T10:33:44.511Z,1544438024.511 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T10:33:44.511Z,1544438024.511 [Default:CheckIn:D] Running Loop=1
2018-12-10T10:33:44.906Z,1544438024.906 [Default:CheckIn:D] Stopped
2018-12-10T10:33:44.906Z,1544438024.906 [Default:CheckIn:E] Running Loop=1
2018-12-10T10:33:45.316Z,1544438025.316 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.642879 min 
2018-12-10T10:33:45.316Z,1544438025.316 [Default:CheckIn:E] Stopped
2018-12-10T10:33:45.316Z,1544438025.316 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T10:33:45.317Z,1544438025.317 [Default:CheckIn] Stopped
2018-12-10T10:33:45.317Z,1544438025.317 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T10:33:45.317Z,1544438025.317 [Default:CheckIn](INFO): Running loop #9
2018-12-10T10:33:45.317Z,1544438025.317 [Default:CheckIn] Running Loop=9
2018-12-10T10:33:45.317Z,1544438025.317 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T10:33:45.317Z,1544438025.317 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T10:35:03.255Z,1544438103.255 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T10:35:03.255Z,1544438103.255 [NAL9602] Data Fault, FailCount= 3
2018-12-10T10:35:03.256Z,1544438103.256 [NAL9602](ERROR): Data Fault
2018-12-10T10:35:03.328Z,1544438103.328 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T10:35:03.655Z,1544438103.655 [NAL9602](INFO): Powering down
2018-12-10T10:35:04.519Z,1544438104.519 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T10:35:04.519Z,1544438104.519 [NAL9602] No Fault, FailCount= 3
2018-12-10T10:35:16.896Z,1544438116.896 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:35:16.896Z,1544438116.896 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-12-10T10:35:16.896Z,1544438116.896 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:35:16.909Z,1544438116.909 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:35:17.307Z,1544438117.307 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:35:17.307Z,1544438117.307 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-12-10T10:35:17.690Z,1544438117.690 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:35:17.690Z,1544438117.690 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:35:17.691Z,1544438117.691 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:35:33.663Z,1544438133.663 [NAL9602](INFO): Powering up NAL9602
2018-12-10T10:35:44.855Z,1544438144.855 [NAL9602](INFO): NAL9602 initialized
2018-12-10T10:37:59.036Z,1544438279.036 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:37:59.036Z,1544438279.036 [Rowe_600LCM] No Fault, FailCount= 5
2018-12-10T10:37:59.102Z,1544438279.102 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:37:59.102Z,1544438279.102 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:37:59.102Z,1544438279.102 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:37:59.102Z,1544438279.102 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:38:03.225Z,1544438283.225 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:38:03.289Z,1544438283.289 [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-12-10T10:38:03.291Z,1544438283.291 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:38:03.291Z,1544438283.291 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:38:03.292Z,1544438283.292 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:38:17.802Z,1544438297.802 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:38:17.802Z,1544438297.802 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-12-10T10:38:17.802Z,1544438297.802 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:38:17.843Z,1544438297.843 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:38:18.290Z,1544438298.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:38:18.291Z,1544438298.291 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-12-10T10:38:18.621Z,1544438298.621 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:38:18.622Z,1544438298.622 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:38:18.622Z,1544438298.622 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:38:45.430Z,1544438325.430 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T10:33:45.3Z
2018-12-10T10:38:45.430Z,1544438325.430 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T10:38:45.430Z,1544438325.430 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T10:38:50.130Z,1544438330.130 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0028.lzma
2018-12-10T10:38:50.904Z,1544438330.904 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0028.lzma.bak
2018-12-10T10:38:50.905Z,1544438330.905 [DataOverHttps](INFO): SBD MOMSN=8986257
2018-12-10T10:39:00.897Z,1544438340.897 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20181210T090738/Express0029.lzma
2018-12-10T10:39:01.660Z,1544438341.660 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0029.lzma.bak
2018-12-10T10:39:01.660Z,1544438341.660 [DataOverHttps](INFO): SBD MOMSN=8986259
2018-12-10T10:39:02.647Z,1544438342.647 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T10:39:02.647Z,1544438342.647 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T10:39:02.647Z,1544438342.647 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T10:41:18.892Z,1544438478.892 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:41:18.892Z,1544438478.892 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-12-10T10:41:18.892Z,1544438478.892 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:41:18.916Z,1544438478.916 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:41:19.383Z,1544438479.383 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:41:19.383Z,1544438479.383 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-12-10T10:41:19.684Z,1544438479.684 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:41:19.684Z,1544438479.684 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:41:19.684Z,1544438479.684 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:41:38.429Z,1544438498.429 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:41:38.430Z,1544438498.430 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-10T10:41:38.430Z,1544438498.430 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:41:38.540Z,1544438498.540 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:41:38.834Z,1544438498.834 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:41:39.032Z,1544438499.032 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:41:40.494Z,1544438500.494 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:41:40.495Z,1544438500.495 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-10T10:41:40.646Z,1544438500.646 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:41:40.646Z,1544438500.646 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:41:40.646Z,1544438500.646 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:41:40.646Z,1544438500.646 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:41:44.771Z,1544438504.771 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:41:44.857Z,1544438504.857 [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-12-10T10:41:44.858Z,1544438504.858 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:41:44.858Z,1544438504.858 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:41:44.859Z,1544438504.859 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:44:02.493Z,1544438642.493 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:44:02.493Z,1544438642.493 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-12-10T10:44:02.494Z,1544438642.494 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:44:02.568Z,1544438642.568 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:44:02.898Z,1544438642.898 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:44:02.988Z,1544438642.988 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T10:44:02.988Z,1544438642.988 [Default:CheckIn:C.Wait] Stopped
2018-12-10T10:44:02.988Z,1544438642.988 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T10:44:02.988Z,1544438642.988 [Default:CheckIn:D] Running Loop=1
2018-12-10T10:44:03.170Z,1544438643.170 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:44:03.325Z,1544438643.325 [Default:CheckIn:D] Stopped
2018-12-10T10:44:03.325Z,1544438643.325 [Default:CheckIn:E] Running Loop=1
2018-12-10T10:44:03.724Z,1544438643.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.949854 min 
2018-12-10T10:44:03.724Z,1544438643.724 [Default:CheckIn:E] Stopped
2018-12-10T10:44:03.724Z,1544438643.724 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T10:44:03.724Z,1544438643.724 [Default:CheckIn] Stopped
2018-12-10T10:44:03.724Z,1544438643.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T10:44:03.725Z,1544438643.725 [Default:CheckIn](INFO): Running loop #10
2018-12-10T10:44:03.725Z,1544438643.725 [Default:CheckIn] Running Loop=10
2018-12-10T10:44:03.725Z,1544438643.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T10:44:03.725Z,1544438643.725 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T10:44:07.235Z,1544438647.235 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:44:07.235Z,1544438647.235 [Rowe_600LCM] No Fault, FailCount= 2
2018-12-10T10:44:07.614Z,1544438647.614 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:44:07.614Z,1544438647.614 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:44:07.614Z,1544438647.614 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:44:07.614Z,1544438647.614 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:44:11.827Z,1544438651.827 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:44:11.893Z,1544438651.893 [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-12-10T10:44:11.894Z,1544438651.894 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:44:11.895Z,1544438651.895 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:44:11.895Z,1544438651.895 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:44:19.701Z,1544438659.701 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:44:19.701Z,1544438659.701 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-12-10T10:44:19.701Z,1544438659.701 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:44:19.742Z,1544438659.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:44:20.183Z,1544438660.183 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:44:20.183Z,1544438660.183 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-12-10T10:44:20.492Z,1544438660.492 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:44:20.492Z,1544438660.492 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:44:20.493Z,1544438660.493 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:45:47.339Z,1544438747.339 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T10:45:47.339Z,1544438747.339 [NAL9602] Data Fault, FailCount= 4
2018-12-10T10:45:47.339Z,1544438747.339 [NAL9602](ERROR): Data Fault
2018-12-10T10:45:47.380Z,1544438747.380 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T10:45:47.743Z,1544438747.743 [NAL9602](INFO): Powering down
2018-12-10T10:45:48.579Z,1544438748.579 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T10:45:48.579Z,1544438748.579 [NAL9602] No Fault, FailCount= 4
2018-12-10T10:46:08.197Z,1544438768.197 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:46:08.197Z,1544438768.197 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-12-10T10:46:08.198Z,1544438768.198 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:46:08.350Z,1544438768.350 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:46:08.612Z,1544438768.612 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:46:08.901Z,1544438768.901 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:46:10.272Z,1544438770.272 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:46:10.272Z,1544438770.272 [Rowe_600LCM] No Fault, FailCount= 3
2018-12-10T10:46:10.517Z,1544438770.517 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:46:10.518Z,1544438770.518 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:46:10.518Z,1544438770.518 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:46:10.518Z,1544438770.518 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:46:14.757Z,1544438774.757 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:46:14.825Z,1544438774.825 [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-12-10T10:46:14.827Z,1544438774.827 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:46:14.828Z,1544438774.828 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:46:14.828Z,1544438774.828 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:46:17.811Z,1544438777.811 [NAL9602](INFO): Powering up NAL9602
2018-12-10T10:46:29.011Z,1544438789.011 [NAL9602](INFO): NAL9602 initialized
2018-12-10T10:47:20.721Z,1544438840.721 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:47:20.721Z,1544438840.721 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-12-10T10:47:20.721Z,1544438840.721 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:47:20.770Z,1544438840.770 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:47:21.188Z,1544438841.188 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:47:21.188Z,1544438841.188 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-12-10T10:47:21.500Z,1544438841.500 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:47:21.501Z,1544438841.501 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:47:21.501Z,1544438841.501 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:48:11.101Z,1544438891.101 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:48:11.101Z,1544438891.101 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-12-10T10:48:11.102Z,1544438891.102 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:48:11.322Z,1544438891.322 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:48:11.506Z,1544438891.506 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:48:11.767Z,1544438891.767 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:48:13.276Z,1544438893.276 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T10:48:13.276Z,1544438893.276 [Rowe_600LCM] No Fault, FailCount= 4
2018-12-10T10:48:13.382Z,1544438893.382 [Rowe_600LCM](INFO): Initializing
2018-12-10T10:48:13.382Z,1544438893.382 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T10:48:13.382Z,1544438893.382 [Rowe_600LCM](INFO): LCM OK
2018-12-10T10:48:13.382Z,1544438893.382 [Rowe_600LCM](INFO): Powering up
2018-12-10T10:48:17.502Z,1544438897.502 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T10:48:17.569Z,1544438897.569 [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-12-10T10:48:17.571Z,1544438897.571 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T10:48:17.572Z,1544438897.572 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T10:48:17.574Z,1544438897.574 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T10:49:04.065Z,1544438944.065 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T10:44:03.7Z
2018-12-10T10:49:04.065Z,1544438944.065 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T10:49:04.065Z,1544438944.065 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T10:49:08.535Z,1544438948.535 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0031.lzma
2018-12-10T10:49:09.336Z,1544438949.336 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0031.lzma.bak
2018-12-10T10:49:09.336Z,1544438949.336 [DataOverHttps](INFO): SBD MOMSN=8986280
2018-12-10T10:49:19.534Z,1544438959.534 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20181210T090738/Express0032.lzma
2018-12-10T10:49:20.332Z,1544438960.332 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0032.lzma.bak
2018-12-10T10:49:20.333Z,1544438960.333 [DataOverHttps](INFO): SBD MOMSN=8986282
2018-12-10T10:49:21.066Z,1544438961.066 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T10:49:21.066Z,1544438961.066 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T10:49:21.066Z,1544438961.066 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T10:50:13.793Z,1544439013.793 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T10:50:13.793Z,1544439013.793 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-12-10T10:50:13.794Z,1544439013.794 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T10:50:13.811Z,1544439013.811 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T10:50:13.812Z,1544439013.812 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-12-10T10:50:14.198Z,1544439014.198 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T10:50:14.393Z,1544439014.393 [Rowe_600LCM](INFO): Powering down
2018-12-10T10:50:21.785Z,1544439021.785 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:50:21.785Z,1544439021.785 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-12-10T10:50:21.785Z,1544439021.785 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:50:21.797Z,1544439021.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:50:24.944Z,1544439024.944 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:50:24.944Z,1544439024.944 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-12-10T10:50:25.076Z,1544439025.076 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:50:25.077Z,1544439025.077 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:50:25.077Z,1544439025.077 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:53:25.664Z,1544439205.664 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:53:25.664Z,1544439205.664 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-12-10T10:53:25.664Z,1544439205.664 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:53:25.677Z,1544439205.677 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:53:25.815Z,1544439205.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:53:25.815Z,1544439205.815 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-12-10T10:53:26.157Z,1544439206.157 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:53:26.157Z,1544439206.157 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:53:26.157Z,1544439206.157 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:54:21.362Z,1544439261.362 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T10:54:21.362Z,1544439261.362 [Default:CheckIn:C.Wait] Stopped
2018-12-10T10:54:21.362Z,1544439261.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T10:54:21.362Z,1544439261.362 [Default:CheckIn:D] Running Loop=1
2018-12-10T10:54:21.765Z,1544439261.765 [Default:CheckIn:D] Stopped
2018-12-10T10:54:21.765Z,1544439261.765 [Default:CheckIn:E] Running Loop=1
2018-12-10T10:54:22.167Z,1544439262.167 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.257194 min 
2018-12-10T10:54:22.167Z,1544439262.167 [Default:CheckIn:E] Stopped
2018-12-10T10:54:22.167Z,1544439262.167 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T10:54:22.167Z,1544439262.167 [Default:CheckIn] Stopped
2018-12-10T10:54:22.168Z,1544439262.168 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T10:54:22.168Z,1544439262.168 [Default:CheckIn](INFO): Running loop #11
2018-12-10T10:54:22.168Z,1544439262.168 [Default:CheckIn] Running Loop=11
2018-12-10T10:54:22.168Z,1544439262.168 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T10:54:22.168Z,1544439262.168 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T10:56:26.425Z,1544439386.425 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:56:26.425Z,1544439386.425 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-12-10T10:56:26.425Z,1544439386.425 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:56:26.466Z,1544439386.466 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:56:26.569Z,1544439386.569 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:56:26.569Z,1544439386.569 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-12-10T10:56:26.927Z,1544439386.927 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:56:26.927Z,1544439386.927 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:56:26.927Z,1544439386.927 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:56:32.479Z,1544439392.479 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T10:56:32.479Z,1544439392.479 [NAL9602] Data Fault, FailCount= 5
2018-12-10T10:56:32.479Z,1544439392.479 [NAL9602](ERROR): Data Fault
2018-12-10T10:56:32.574Z,1544439392.574 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T10:56:32.574Z,1544439392.574 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-12-10T10:56:32.879Z,1544439392.879 [NAL9602](INFO): Powering down
2018-12-10T10:56:32.958Z,1544439392.958 [CBIT](INFO): Critical error at 20181210T105632
2018-12-10T10:58:32.497Z,1544439512.497 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T10:58:32.497Z,1544439512.497 [NAL9602] No Fault, FailCount= 5
2018-12-10T10:58:32.848Z,1544439512.848 [NAL9602](INFO): Powering up NAL9602
2018-12-10T10:58:43.651Z,1544439523.651 [NAL9602](INFO): NAL9602 initialized
2018-12-10T10:59:22.486Z,1544439562.486 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T10:54:22.2Z
2018-12-10T10:59:22.487Z,1544439562.487 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T10:59:22.487Z,1544439562.487 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T10:59:26.849Z,1544439566.849 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181210T090738/Courier0034.lzma
2018-12-10T10:59:27.296Z,1544439567.296 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T10:59:27.296Z,1544439567.296 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-12-10T10:59:27.296Z,1544439567.296 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T10:59:27.308Z,1544439567.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T10:59:27.648Z,1544439567.648 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0034.lzma.bak
2018-12-10T10:59:27.649Z,1544439567.649 [DataOverHttps](INFO): SBD MOMSN=8986315
2018-12-10T10:59:27.726Z,1544439567.726 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T10:59:27.726Z,1544439567.726 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-12-10T10:59:28.347Z,1544439568.347 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T10:59:28.347Z,1544439568.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T10:59:28.348Z,1544439568.348 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T10:59:36.432Z,1544439576.432 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20181210T090738/Express0035.lzma
2018-12-10T10:59:37.232Z,1544439577.232 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0035.lzma.bak
2018-12-10T10:59:37.232Z,1544439577.232 [DataOverHttps](INFO): SBD MOMSN=8986318
2018-12-10T10:59:37.932Z,1544439577.932 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T10:59:37.932Z,1544439577.932 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T10:59:37.932Z,1544439577.932 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T11:00:14.116Z,1544439614.116 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T11:00:14.116Z,1544439614.116 [Rowe_600LCM] No Fault, FailCount= 5
2018-12-10T11:00:14.301Z,1544439614.301 [Rowe_600LCM](INFO): Initializing
2018-12-10T11:00:14.302Z,1544439614.302 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T11:00:14.302Z,1544439614.302 [Rowe_600LCM](INFO): LCM OK
2018-12-10T11:00:14.302Z,1544439614.302 [Rowe_600LCM](INFO): Powering up
2018-12-10T11:00:18.425Z,1544439618.425 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T11:00:18.588Z,1544439618.588 [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-12-10T11:00:18.590Z,1544439618.590 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T11:00:18.591Z,1544439618.591 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T11:00:18.591Z,1544439618.591 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T11:02:28.746Z,1544439748.746 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T11:02:28.746Z,1544439748.746 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-12-10T11:02:28.746Z,1544439748.746 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T11:02:28.758Z,1544439748.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:02:29.144Z,1544439749.144 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T11:02:29.144Z,1544439749.144 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-12-10T11:02:29.551Z,1544439749.551 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T11:02:29.552Z,1544439749.552 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T11:02:29.552Z,1544439749.552 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T11:04:38.159Z,1544439878.159 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T11:04:38.160Z,1544439878.160 [Default:CheckIn:C.Wait] Stopped
2018-12-10T11:04:38.160Z,1544439878.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T11:04:38.160Z,1544439878.160 [Default:CheckIn:D] Running Loop=1
2018-12-10T11:04:38.549Z,1544439878.549 [Default:CheckIn:D] Stopped
2018-12-10T11:04:38.549Z,1544439878.549 [Default:CheckIn:E] Running Loop=1
2018-12-10T11:04:38.962Z,1544439878.962 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.536922 min 
2018-12-10T11:04:38.962Z,1544439878.962 [Default:CheckIn:E] Stopped
2018-12-10T11:04:38.962Z,1544439878.962 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T11:04:38.962Z,1544439878.962 [Default:CheckIn] Stopped
2018-12-10T11:04:38.962Z,1544439878.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T11:04:38.963Z,1544439878.963 [Default:CheckIn](INFO): Running loop #12
2018-12-10T11:04:38.963Z,1544439878.963 [Default:CheckIn] Running Loop=12
2018-12-10T11:04:38.963Z,1544439878.963 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T11:04:38.963Z,1544439878.963 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T11:05:29.757Z,1544439929.757 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T11:05:29.757Z,1544439929.757 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-12-10T11:05:29.757Z,1544439929.757 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T11:05:29.792Z,1544439929.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:05:30.170Z,1544439930.170 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T11:05:30.171Z,1544439930.171 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-12-10T11:05:30.535Z,1544439930.535 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T11:05:30.535Z,1544439930.535 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T11:05:30.535Z,1544439930.535 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T11:05:50.197Z,1544439950.197 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T11:05:50.197Z,1544439950.197 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-10T11:05:50.198Z,1544439950.198 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T11:05:50.484Z,1544439950.484 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T11:05:50.603Z,1544439950.603 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T11:05:50.691Z,1544439950.691 [Rowe_600LCM](INFO): Powering down
2018-12-10T11:05:52.100Z,1544439952.100 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T11:05:52.100Z,1544439952.100 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-10T11:05:52.306Z,1544439952.306 [Rowe_600LCM](INFO): Initializing
2018-12-10T11:05:52.306Z,1544439952.306 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T11:05:52.306Z,1544439952.306 [Rowe_600LCM](INFO): LCM OK
2018-12-10T11:05:52.306Z,1544439952.306 [Rowe_600LCM](INFO): Powering up
2018-12-10T11:05:56.499Z,1544439956.499 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T11:05:56.606Z,1544439956.606 [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-12-10T11:05:56.607Z,1544439956.607 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T11:05:56.608Z,1544439956.608 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T11:05:56.609Z,1544439956.609 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T11:08:30.776Z,1544440110.776 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T11:08:30.776Z,1544440110.776 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-12-10T11:08:30.776Z,1544440110.776 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T11:08:30.806Z,1544440110.806 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:08:31.243Z,1544440111.243 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T11:08:31.243Z,1544440111.243 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-12-10T11:08:31.584Z,1544440111.584 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T11:08:31.584Z,1544440111.584 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T11:08:31.585Z,1544440111.585 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T11:08:44.764Z,1544440124.764 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T11:08:44.765Z,1544440124.765 [NAL9602] Data Fault, FailCount= 1
2018-12-10T11:08:44.765Z,1544440124.765 [NAL9602](ERROR): Data Fault
2018-12-10T11:08:44.836Z,1544440124.836 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T11:08:45.159Z,1544440125.159 [NAL9602](INFO): Powering down
2018-12-10T11:08:46.023Z,1544440126.023 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T11:08:46.023Z,1544440126.023 [NAL9602] No Fault, FailCount= 1
2018-12-10T11:09:15.552Z,1544440155.552 [NAL9602](INFO): Powering up NAL9602
2018-12-10T11:09:19.725Z,1544440159.725 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T11:09:19.725Z,1544440159.725 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-12-10T11:09:19.725Z,1544440159.725 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T11:09:20.035Z,1544440160.035 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T11:09:20.130Z,1544440160.130 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T11:09:20.218Z,1544440160.218 [Rowe_600LCM](INFO): Powering down
2018-12-10T11:09:21.611Z,1544440161.611 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T11:09:21.612Z,1544440161.612 [Rowe_600LCM] No Fault, FailCount= 2
2018-12-10T11:09:21.834Z,1544440161.834 [Rowe_600LCM](INFO): Initializing
2018-12-10T11:09:21.834Z,1544440161.834 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T11:09:21.834Z,1544440161.834 [Rowe_600LCM](INFO): LCM OK
2018-12-10T11:09:21.834Z,1544440161.834 [Rowe_600LCM](INFO): Powering up
2018-12-10T11:09:25.995Z,1544440165.995 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T11:09:26.097Z,1544440166.097 [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-12-10T11:09:26.098Z,1544440166.098 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T11:09:26.099Z,1544440166.099 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T11:09:26.099Z,1544440166.099 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T11:09:26.352Z,1544440166.352 [NAL9602](INFO): NAL9602 initialized
2018-12-10T11:09:39.239Z,1544440179.239 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T11:04:38.0Z
2018-12-10T11:09:39.239Z,1544440179.239 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T11:09:39.239Z,1544440179.239 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T11:09:44.506Z,1544440184.506 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0037.lzma
2018-12-10T11:09:45.308Z,1544440185.308 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0037.lzma.bak
2018-12-10T11:09:45.308Z,1544440185.308 [DataOverHttps](INFO): SBD MOMSN=8986331
2018-12-10T11:09:54.891Z,1544440194.891 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20181210T090738/Express0038.lzma
2018-12-10T11:09:55.690Z,1544440195.690 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0038.lzma.bak
2018-12-10T11:09:55.690Z,1544440195.690 [DataOverHttps](INFO): SBD MOMSN=8986333
2018-12-10T11:09:56.407Z,1544440196.407 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T11:09:56.407Z,1544440196.407 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T11:09:56.407Z,1544440196.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T11:11:31.623Z,1544440291.623 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T11:11:31.623Z,1544440291.623 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-12-10T11:11:31.623Z,1544440291.623 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T11:11:31.635Z,1544440291.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:11:32.034Z,1544440292.034 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T11:11:32.035Z,1544440292.035 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-12-10T11:11:32.421Z,1544440292.421 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T11:11:32.422Z,1544440292.422 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T11:11:32.422Z,1544440292.422 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T11:12:49.881Z,1544440369.881 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T11:12:49.881Z,1544440369.881 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-12-10T11:12:49.881Z,1544440369.881 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T11:12:49.974Z,1544440369.974 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T11:12:50.294Z,1544440370.294 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T11:12:50.497Z,1544440370.497 [Rowe_600LCM](INFO): Powering down
2018-12-10T11:12:51.952Z,1544440371.952 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T11:12:51.952Z,1544440371.952 [Rowe_600LCM] No Fault, FailCount= 3
2018-12-10T11:12:52.113Z,1544440372.113 [Rowe_600LCM](INFO): Initializing
2018-12-10T11:12:52.114Z,1544440372.114 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T11:12:52.114Z,1544440372.114 [Rowe_600LCM](INFO): LCM OK
2018-12-10T11:12:52.114Z,1544440372.114 [Rowe_600LCM](INFO): Powering up
2018-12-10T11:12:56.237Z,1544440376.237 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T11:12:56.345Z,1544440376.345 [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-12-10T11:12:56.347Z,1544440376.347 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T11:12:56.348Z,1544440376.348 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T11:12:56.350Z,1544440376.350 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T11:14:32.514Z,1544440472.514 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T11:14:32.514Z,1544440472.514 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-12-10T11:14:32.514Z,1544440472.514 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T11:14:32.535Z,1544440472.535 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:14:32.963Z,1544440472.963 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T11:14:32.964Z,1544440472.964 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-12-10T11:14:33.346Z,1544440473.346 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T11:14:33.347Z,1544440473.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T11:14:33.348Z,1544440473.348 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T11:14:56.921Z,1544440496.921 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T11:14:56.926Z,1544440496.926 [Default:CheckIn:C.Wait] Stopped
2018-12-10T11:14:56.926Z,1544440496.926 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T11:14:56.926Z,1544440496.926 [Default:CheckIn:D] Running Loop=1
2018-12-10T11:14:57.335Z,1544440497.335 [Default:CheckIn:D] Stopped
2018-12-10T11:14:57.335Z,1544440497.335 [Default:CheckIn:E] Running Loop=1
2018-12-10T11:14:57.726Z,1544440497.726 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.850024 min 
2018-12-10T11:14:57.726Z,1544440497.726 [Default:CheckIn:E] Stopped
2018-12-10T11:14:57.727Z,1544440497.727 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T11:14:57.727Z,1544440497.727 [Default:CheckIn] Stopped
2018-12-10T11:14:57.727Z,1544440497.727 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T11:14:57.727Z,1544440497.727 [Default:CheckIn](INFO): Running loop #13
2018-12-10T11:14:57.727Z,1544440497.727 [Default:CheckIn] Running Loop=13
2018-12-10T11:14:57.727Z,1544440497.727 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T11:14:57.727Z,1544440497.727 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T11:17:29.533Z,1544440649.533 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T11:17:29.533Z,1544440649.533 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-12-10T11:17:29.534Z,1544440649.534 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T11:17:29.620Z,1544440649.620 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T11:17:29.938Z,1544440649.938 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T11:17:30.143Z,1544440650.143 [Rowe_600LCM](INFO): Powering down
2018-12-10T11:17:31.620Z,1544440651.620 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T11:17:31.620Z,1544440651.620 [Rowe_600LCM] No Fault, FailCount= 4
2018-12-10T11:17:31.758Z,1544440651.758 [Rowe_600LCM](INFO): Initializing
2018-12-10T11:17:31.758Z,1544440651.758 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T11:17:31.758Z,1544440651.758 [Rowe_600LCM](INFO): LCM OK
2018-12-10T11:17:31.758Z,1544440651.758 [Rowe_600LCM](INFO): Powering up
2018-12-10T11:17:33.589Z,1544440653.589 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T11:17:33.589Z,1544440653.589 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-12-10T11:17:33.589Z,1544440653.589 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T11:17:33.619Z,1544440653.619 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:17:34.029Z,1544440654.029 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T11:17:34.029Z,1544440654.029 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-12-10T11:17:34.395Z,1544440654.395 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T11:17:34.396Z,1544440654.396 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T11:17:34.396Z,1544440654.396 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T11:17:35.884Z,1544440655.884 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T11:17:35.985Z,1544440655.985 [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-12-10T11:17:35.986Z,1544440655.986 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T11:17:35.987Z,1544440655.987 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T11:17:35.988Z,1544440655.988 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T11:19:27.395Z,1544440767.395 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T11:19:27.395Z,1544440767.395 [NAL9602] Data Fault, FailCount= 2
2018-12-10T11:19:27.395Z,1544440767.395 [NAL9602](ERROR): Data Fault
2018-12-10T11:19:27.465Z,1544440767.465 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T11:19:27.791Z,1544440767.791 [NAL9602](INFO): Powering down
2018-12-10T11:19:28.684Z,1544440768.684 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T11:19:28.684Z,1544440768.684 [NAL9602] No Fault, FailCount= 2
2018-12-10T11:19:57.830Z,1544440797.830 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T11:14:57.7Z
2018-12-10T11:19:57.830Z,1544440797.830 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T11:19:57.830Z,1544440797.830 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T11:19:58.191Z,1544440798.191 [NAL9602](INFO): Powering up NAL9602
2018-12-10T11:20:02.777Z,1544440802.777 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0040.lzma
2018-12-10T11:20:03.568Z,1544440803.568 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0040.lzma.bak
2018-12-10T11:20:03.568Z,1544440803.568 [DataOverHttps](INFO): SBD MOMSN=8986352
2018-12-10T11:20:09.351Z,1544440809.351 [NAL9602](INFO): NAL9602 initialized
2018-12-10T11:20:13.634Z,1544440813.634 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20181210T090738/Express0041.lzma
2018-12-10T11:20:14.396Z,1544440814.396 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0041.lzma.bak
2018-12-10T11:20:14.396Z,1544440814.396 [DataOverHttps](INFO): SBD MOMSN=8986354
2018-12-10T11:20:15.123Z,1544440815.123 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T11:20:15.123Z,1544440815.123 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T11:20:15.123Z,1544440815.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T11:20:34.447Z,1544440834.447 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T11:20:34.447Z,1544440834.447 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-12-10T11:20:34.447Z,1544440834.447 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T11:20:34.459Z,1544440834.459 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:20:34.861Z,1544440834.861 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-10T11:20:34.861Z,1544440834.861 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-12-10T11:20:35.260Z,1544440835.260 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-10T11:20:35.260Z,1544440835.260 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-10T11:20:35.260Z,1544440835.260 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-10T11:20:50.798Z,1544440850.798 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-10T11:20:50.798Z,1544440850.798 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-12-10T11:20:50.798Z,1544440850.798 [Rowe_600LCM](ERROR): Communications Fault
2018-12-10T11:20:50.906Z,1544440850.906 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-10T11:20:50.906Z,1544440850.906 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-12-10T11:20:51.202Z,1544440851.202 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-10T11:20:51.395Z,1544440851.395 [Rowe_600LCM](INFO): Powering down
2018-12-10T11:23:35.586Z,1544441015.586 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-12-10T11:23:35.586Z,1544441015.586 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-12-10T11:23:35.587Z,1544441015.587 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-10T11:23:35.599Z,1544441015.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:23:35.599Z,1544441015.599 [CBIT](FAULT): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-10T11:25:15.575Z,1544441115.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T11:25:15.575Z,1544441115.575 [Default:CheckIn:C.Wait] Stopped
2018-12-10T11:25:15.575Z,1544441115.575 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T11:25:15.575Z,1544441115.575 [Default:CheckIn:D] Running Loop=1
2018-12-10T11:25:16.001Z,1544441116.001 [Default:CheckIn:D] Stopped
2018-12-10T11:25:16.001Z,1544441116.001 [Default:CheckIn:E] Running Loop=1
2018-12-10T11:25:16.402Z,1544441116.402 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.161125 min 
2018-12-10T11:25:16.402Z,1544441116.402 [Default:CheckIn:E] Stopped
2018-12-10T11:25:16.402Z,1544441116.402 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-12-10T11:25:16.403Z,1544441116.403 [Default:CheckIn] Stopped
2018-12-10T11:25:16.403Z,1544441116.403 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-12-10T11:25:16.403Z,1544441116.403 [Default:CheckIn](INFO): Running loop #14
2018-12-10T11:25:16.403Z,1544441116.403 [Default:CheckIn] Running Loop=14
2018-12-10T11:25:16.403Z,1544441116.403 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-12-10T11:25:16.403Z,1544441116.403 [Default:CheckIn:Read_GPS] Running Loop=1
2018-12-10T11:30:15.915Z,1544441415.915 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-12-10T11:30:15.915Z,1544441415.915 [NAL9602] Data Fault, FailCount= 3
2018-12-10T11:30:15.916Z,1544441415.916 [NAL9602](ERROR): Data Fault
2018-12-10T11:30:15.990Z,1544441415.990 [CBIT](ERROR): Data Fault in component: NAL9602
2018-12-10T11:30:16.315Z,1544441416.315 [NAL9602](INFO): Powering down
2018-12-10T11:30:16.748Z,1544441416.748 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-12-10T11:25:16.4Z
2018-12-10T11:30:16.748Z,1544441416.748 [Default:CheckIn:Read_GPS] Stopped
2018-12-10T11:30:16.748Z,1544441416.748 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-12-10T11:30:17.194Z,1544441417.194 [CBIT](INFO): Clearing failed state for component NAL9602
2018-12-10T11:30:17.194Z,1544441417.194 [NAL9602] No Fault, FailCount= 3
2018-12-10T11:30:21.164Z,1544441421.164 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181210T090738/Courier0043.lzma
2018-12-10T11:30:21.965Z,1544441421.965 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Courier0043.lzma.bak
2018-12-10T11:30:21.965Z,1544441421.965 [DataOverHttps](INFO): SBD MOMSN=8986378
2018-12-10T11:30:30.970Z,1544441430.970 [DataOverHttps](INFO): Sending 291 bytes from file Logs/20181210T090738/Express0044.lzma
2018-12-10T11:30:31.736Z,1544441431.736 [DataOverHttps](INFO): Moved sent file to Logs/20181210T090738/Express0044.lzma.bak
2018-12-10T11:30:31.736Z,1544441431.736 [DataOverHttps](INFO): SBD MOMSN=8986380
2018-12-10T11:30:32.452Z,1544441432.452 [Default:CheckIn:Read_Iridium] Stopped
2018-12-10T11:30:32.452Z,1544441432.452 [Default:CheckIn:C.Wait] Running Loop=1
2018-12-10T11:30:32.452Z,1544441432.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-12-10T11:30:46.441Z,1544441446.441 [NAL9602](INFO): Powering up NAL9602
2018-12-10T11:30:50.919Z,1544441450.919 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-10T11:30:50.919Z,1544441450.919 [Rowe_600LCM] No Fault, FailCount= 5
2018-12-10T11:30:51.249Z,1544441451.249 [Rowe_600LCM](INFO): Initializing
2018-12-10T11:30:51.251Z,1544441451.251 [Rowe_600LCM](INFO): Checking LCM
2018-12-10T11:30:51.251Z,1544441451.251 [Rowe_600LCM](INFO): LCM OK
2018-12-10T11:30:51.251Z,1544441451.251 [Rowe_600LCM](INFO): Powering up
2018-12-10T11:30:55.424Z,1544441455.424 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-10T11:30:55.488Z,1544441455.488 [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-12-10T11:30:55.490Z,1544441455.490 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-10T11:30:55.491Z,1544441455.491 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-10T11:30:55.492Z,1544441455.492 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-10T11:30:57.643Z,1544441457.643 [NAL9602](INFO): NAL9602 initialized
2018-12-10T11:35:32.836Z,1544441732.836 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-12-10T11:35:32.836Z,1544441732.836 [Default:CheckIn:C.Wait] Stopped
2018-12-10T11:35:32.836Z,1544441732.836 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-12-10T11:35:32.836Z,1544441732.836 [Default:CheckIn:D] Running Loop=1
2018-12-10T11:35:33.237Z,1544441733.237 [Default:CheckIn:D] Stopped
2018-12-10T11:35:33.237Z,1544441733.237 [Default:CheckIn:E] Running Loop=1
2018-12-10T11:35:33.639Z,1544441733.639 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14