2018-06-01T16:26:53.782Z,1527870413.782 [Supervisor](DEBUG): Initializing supervisor.
2018-06-01T16:26:53.785Z,1527870413.785 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-06-01T16:26:53.786Z,1527870413.786 [SyncHandler](INFO): Protected caller Thread ID is 1599
2018-06-01T16:26:53.786Z,1527870413.786 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-06-01T16:26:53.787Z,1527870413.787 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-06-01T16:26:53.788Z,1527870413.788 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1600
2018-06-01T16:26:53.792Z,1527870413.792 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-06-01T16:26:53.804Z,1527870413.804 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-06-01T16:26:53.805Z,1527870413.805 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-06-01T16:26:53.805Z,1527870413.805 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1601
2018-06-01T16:26:53.806Z,1527870413.806 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-06-01T16:26:53.807Z,1527870413.807 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-06-01T16:26:53.807Z,1527870413.807 [logger ThreadHandler](INFO): Protected caller Thread ID is 1602
2018-06-01T16:26:53.809Z,1527870413.809 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-06-01T16:26:53.810Z,1527870413.810 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-06-01T16:26:53.811Z,1527870413.811 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-06-01T16:26:53.976Z,1527870413.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-06-01T16:26:53.976Z,1527870413.976 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-06-01T16:26:54.081Z,1527870414.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-06-01T16:26:54.081Z,1527870414.081 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-06-01T16:26:54.290Z,1527870414.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-06-01T16:26:54.291Z,1527870414.291 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-06-01T16:26:54.619Z,1527870414.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-06-01T16:26:54.620Z,1527870414.620 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-06-01T16:26:54.970Z,1527870414.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-06-01T16:26:54.971Z,1527870414.971 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-06-01T16:26:55.448Z,1527870415.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-06-01T16:26:55.449Z,1527870415.449 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-06-01T16:26:55.850Z,1527870415.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-06-01T16:26:55.851Z,1527870415.851 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-06-01T16:26:56.325Z,1527870416.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-06-01T16:26:56.326Z,1527870416.326 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-06-01T16:26:56.410Z,1527870416.410 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-06-01T16:26:56.719Z,1527870416.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-06-01T16:26:56.720Z,1527870416.720 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-06-01T16:26:56.868Z,1527870416.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-06-01T16:26:56.869Z,1527870416.869 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-06-01T16:26:57.082Z,1527870417.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-06-01T16:26:57.083Z,1527870417.083 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-06-01T16:26:57.305Z,1527870417.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-06-01T16:26:57.306Z,1527870417.306 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-06-01T16:26:57.406Z,1527870417.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-06-01T16:26:57.407Z,1527870417.407 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-06-01T16:26:57.551Z,1527870417.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-06-01T16:26:57.553Z,1527870417.553 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-06-01T16:26:57.556Z,1527870417.556 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-06-01T16:26:57.873Z,1527870417.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-06-01T16:26:58.772Z,1527870418.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-06-01T16:26:58.987Z,1527870418.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-06-01T16:26:59.136Z,1527870419.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-06-01T16:26:59.232Z,1527870419.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-06-01T16:26:59.320Z,1527870419.320 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-06-01T16:26:59.460Z,1527870419.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-06-01T16:26:59.646Z,1527870419.646 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-06-01T16:26:59.747Z,1527870419.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-06-01T16:26:59.842Z,1527870419.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-06-01T16:26:59.942Z,1527870419.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-06-01T16:27:00.028Z,1527870420.028 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-06-01T16:27:00.029Z,1527870420.029 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-06-01T16:27:00.033Z,1527870420.033 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-06-01T16:27:00.162Z,1527870420.162 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-06-01T16:27:00.163Z,1527870420.163 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-06-01T16:27:00.185Z,1527870420.185 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-06-01T16:27:00.186Z,1527870420.186 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-06-01T16:27:00.241Z,1527870420.241 [DepthRateCalculator] Loaded
2018-06-01T16:27:00.241Z,1527870420.241 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-06-01T16:27:00.247Z,1527870420.247 [PitchRateCalculator] Loaded
2018-06-01T16:27:00.248Z,1527870420.248 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-06-01T16:27:00.264Z,1527870420.264 [SpeedCalculator] Loaded
2018-06-01T16:27:00.264Z,1527870420.264 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-06-01T16:27:00.285Z,1527870420.285 [TempGradientCalculator] Loaded
2018-06-01T16:27:00.285Z,1527870420.285 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-06-01T16:27:00.301Z,1527870420.301 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-06-01T16:27:00.301Z,1527870420.301 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-06-01T16:27:00.307Z,1527870420.307 [YawRateCalculator] Loaded
2018-06-01T16:27:00.307Z,1527870420.307 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-06-01T16:27:00.403Z,1527870420.403 [ElevatorOffsetCalculator] Loaded
2018-06-01T16:27:00.403Z,1527870420.403 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-06-01T16:27:00.404Z,1527870420.404 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-06-01T16:27:00.405Z,1527870420.405 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-06-01T16:27:00.527Z,1527870420.527 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-06-01T16:27:00.528Z,1527870420.528 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-06-01T16:27:00.867Z,1527870420.867 [AcousticModem_Benthos_ATM900] Loaded
2018-06-01T16:27:00.868Z,1527870420.868 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-06-01T16:27:00.949Z,1527870420.949 [DataOverHttps] Loaded
2018-06-01T16:27:00.949Z,1527870420.949 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-06-01T16:27:00.963Z,1527870420.963 [Depth_Keller] Loaded
2018-06-01T16:27:00.963Z,1527870420.963 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-06-01T16:27:01.062Z,1527870421.062 [NAL9602] Loaded
2018-06-01T16:27:01.063Z,1527870421.063 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-06-01T16:27:01.078Z,1527870421.078 [Onboard] Loaded
2018-06-01T16:27:01.079Z,1527870421.079 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-06-01T16:27:01.090Z,1527870421.090 [Radio_Surface] Loaded
2018-06-01T16:27:01.090Z,1527870421.090 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-06-01T16:27:01.091Z,1527870421.091 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B74E0
2018-06-01T16:27:01.091Z,1527870421.091 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1681
2018-06-01T16:27:01.138Z,1527870421.138 [PNI_TCM] Loaded
2018-06-01T16:27:01.139Z,1527870421.139 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-06-01T16:27:01.201Z,1527870421.201 [Rowe_600LCM] Loaded
2018-06-01T16:27:01.201Z,1527870421.201 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-06-01T16:27:01.202Z,1527870421.202 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407E74E0
2018-06-01T16:27:01.202Z,1527870421.202 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1682
2018-06-01T16:27:02.966Z,1527870422.966 [BPC1] Loaded
2018-06-01T16:27:02.966Z,1527870422.966 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-06-01T16:27:02.967Z,1527870422.967 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-06-01T16:27:02.967Z,1527870422.967 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-06-01T16:27:03.468Z,1527870423.468 [SBIT](DEBUG): Construct Startup Built In Test.
2018-06-01T16:27:03.491Z,1527870423.491 [SBIT] Loaded
2018-06-01T16:27:03.492Z,1527870423.492 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-06-01T16:27:03.492Z,1527870423.492 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-06-01T16:27:03.504Z,1527870423.504 [IBIT] Loaded
2018-06-01T16:27:03.505Z,1527870423.505 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-06-01T16:27:03.508Z,1527870423.508 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-06-01T16:27:03.662Z,1527870423.662 [CBIT] Loaded
2018-06-01T16:27:03.663Z,1527870423.663 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-06-01T16:27:03.663Z,1527870423.663 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-06-01T16:27:03.664Z,1527870423.664 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-06-01T16:27:03.849Z,1527870423.849 [Aanderaa_O2] Loaded
2018-06-01T16:27:03.849Z,1527870423.849 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-06-01T16:27:03.897Z,1527870423.897 [CTD_Seabird] Loaded
2018-06-01T16:27:03.897Z,1527870423.897 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-06-01T16:27:03.898Z,1527870423.898 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408A94E0
2018-06-01T16:27:03.899Z,1527870423.899 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1683
2018-06-01T16:27:03.916Z,1527870423.916 [ESPComponent] Loaded
2018-06-01T16:27:03.916Z,1527870423.916 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-06-01T16:27:03.930Z,1527870423.930 [PAR_Licor] Loaded
2018-06-01T16:27:03.931Z,1527870423.931 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-06-01T16:27:03.980Z,1527870423.980 [WetLabsBB2FL] Loaded
2018-06-01T16:27:03.980Z,1527870423.980 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-06-01T16:27:03.981Z,1527870423.981 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408D94E0
2018-06-01T16:27:03.982Z,1527870423.982 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1684
2018-06-01T16:27:03.982Z,1527870423.982 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-06-01T16:27:03.983Z,1527870423.983 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-06-01T16:27:04.264Z,1527870424.264 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-06-01T16:27:04.265Z,1527870424.265 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-06-01T16:27:04.351Z,1527870424.351 [VerticalControl](DEBUG): Construct VerticalControl.
2018-06-01T16:27:04.446Z,1527870424.446 [VerticalControl] Loaded
2018-06-01T16:27:04.446Z,1527870424.446 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-06-01T16:27:04.447Z,1527870424.447 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-06-01T16:27:04.509Z,1527870424.509 [HorizontalControl] Loaded
2018-06-01T16:27:04.509Z,1527870424.509 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-06-01T16:27:04.510Z,1527870424.510 [SpeedControl](DEBUG): Construct SpeedControl.
2018-06-01T16:27:04.515Z,1527870424.515 [SpeedControl] Loaded
2018-06-01T16:27:04.515Z,1527870424.515 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-06-01T16:27:04.516Z,1527870424.516 [LoopControl](DEBUG): Construct LoopControl.
2018-06-01T16:27:04.517Z,1527870424.517 [LoopControl] Loaded
2018-06-01T16:27:04.517Z,1527870424.517 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-06-01T16:27:04.518Z,1527870424.518 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-06-01T16:27:04.518Z,1527870424.518 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-06-01T16:27:04.637Z,1527870424.637 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-06-01T16:27:04.637Z,1527870424.637 [StratificationFrontDetector](DEBUG): (re)initializing
2018-06-01T16:27:04.638Z,1527870424.638 [StratificationFrontDetector] Loaded
2018-06-01T16:27:04.638Z,1527870424.638 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-06-01T16:27:04.639Z,1527870424.639 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-06-01T16:27:04.639Z,1527870424.639 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-06-01T16:27:04.757Z,1527870424.757 [BuoyancyServo] Loaded
2018-06-01T16:27:04.757Z,1527870424.757 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-06-01T16:27:04.769Z,1527870424.769 [ElevatorServo] Loaded
2018-06-01T16:27:04.769Z,1527870424.769 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-06-01T16:27:04.780Z,1527870424.780 [MassServo] Loaded
2018-06-01T16:27:04.780Z,1527870424.780 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-06-01T16:27:04.792Z,1527870424.792 [RudderServo] Loaded
2018-06-01T16:27:04.792Z,1527870424.792 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-06-01T16:27:04.803Z,1527870424.803 [ThrusterServo] Loaded
2018-06-01T16:27:04.803Z,1527870424.803 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-06-01T16:27:04.804Z,1527870424.804 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-06-01T16:27:04.804Z,1527870424.804 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-06-01T16:27:04.911Z,1527870424.911 [DeadReckonUsingSpeedCalculator] Loaded
2018-06-01T16:27:04.912Z,1527870424.912 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-06-01T16:27:04.928Z,1527870424.928 [NavChart] Loaded
2018-06-01T16:27:04.928Z,1527870424.928 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-06-01T16:27:04.932Z,1527870424.932 [UniversalFixResidualReporter] Loaded
2018-06-01T16:27:04.932Z,1527870424.932 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-06-01T16:27:04.933Z,1527870424.933 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-06-01T16:27:04.936Z,1527870424.936 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-06-01T16:27:04.937Z,1527870424.937 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-06-01T16:27:04.944Z,1527870424.944 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-06-01T16:27:04.945Z,1527870424.945 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A324E0
2018-06-01T16:27:04.946Z,1527870424.946 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1685
2018-06-01T16:27:04.951Z,1527870424.951 [Supervisor](INFO): Main Thread ID is 798
2018-06-01T16:27:04.951Z,1527870424.951 [Supervisor](DEBUG): Running supervisor.
2018-06-01T16:27:04.951Z,1527870424.951 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1686
2018-06-01T16:27:04.954Z,1527870424.954 [controlThread ThreadHandler](INFO): Handler Thread ID is 1687
2018-06-01T16:27:04.954Z,1527870424.954 [controlThread](DEBUG): Initializing ControlThread
2018-06-01T16:27:04.955Z,1527870424.955 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-06-01T16:27:04.956Z,1527870424.956 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-06-01T16:27:04.956Z,1527870424.956 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-06-01T16:27:04.956Z,1527870424.956 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-06-01T16:27:04.957Z,1527870424.957 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-06-01T16:27:04.958Z,1527870424.958 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-06-01T16:27:04.958Z,1527870424.958 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-06-01T16:27:04.962Z,1527870424.962 [SBIT](INFO): Initialize SBIT Component.
2018-06-01T16:27:04.962Z,1527870424.962 [SBIT](IMPORTANT): git: 2018-05-22-10-g39deb54
2018-06-01T16:27:04.963Z,1527870424.963 [SBIT](INFO): git hash: 39deb54c781a604be83388f58a28f6e6ea1f8d55
2018-06-01T16:27:04.963Z,1527870424.963 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-06-01T16:27:04.964Z,1527870424.964 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-06-01T16:27:04.965Z,1527870424.965 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-06-01T16:27:04.966Z,1527870424.966 [IBIT](INFO): Initialize IBIT Component.
2018-06-01T16:27:04.967Z,1527870424.967 [CBIT](DEBUG): Initialize CBIT Component.
2018-06-01T16:27:04.967Z,1527870424.967 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-06-01T16:27:04.968Z,1527870424.968 [logger ThreadHandler](INFO): Handler Thread ID is 1688
2018-06-01T16:27:04.985Z,1527870424.985 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1689
2018-06-01T16:27:04.991Z,1527870424.991 [Radio_Surface](INFO): Powering up
2018-06-01T16:27:04.997Z,1527870424.997 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1690
2018-06-01T16:27:04.998Z,1527870424.998 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:27:04.998Z,1527870424.998 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:27:05.007Z,1527870425.007 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:27:05.007Z,1527870425.007 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:27:05.013Z,1527870425.013 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1691
2018-06-01T16:27:05.014Z,1527870425.014 [CTD_Seabird](INFO): Initializing
2018-06-01T16:27:05.014Z,1527870425.014 [CTD_Seabird](INFO): Checking LCM
2018-06-01T16:27:05.015Z,1527870425.015 [CTD_Seabird](INFO): LCM OK
2018-06-01T16:27:05.015Z,1527870425.015 [CTD_Seabird](INFO): Powering up
2018-06-01T16:27:05.021Z,1527870425.021 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1692
2018-06-01T16:27:05.022Z,1527870425.022 [WetLabsBB2FL](INFO): Powering down
2018-06-01T16:27:05.049Z,1527870425.049 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1693
2018-06-01T16:27:05.055Z,1527870425.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-06-01T16:27:05.056Z,1527870425.056 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-06-01T16:27:05.056Z,1527870425.056 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-06-01T16:27:05.056Z,1527870425.056 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-06-01T16:27:05.057Z,1527870425.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-06-01T16:27:05.057Z,1527870425.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-06-01T16:27:05.057Z,1527870425.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-06-01T16:27:05.057Z,1527870425.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-06-01T16:27:05.058Z,1527870425.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-06-01T16:27:05.058Z,1527870425.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-06-01T16:27:05.058Z,1527870425.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-06-01T16:27:05.058Z,1527870425.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-06-01T16:27:05.058Z,1527870425.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-06-01T16:27:05.058Z,1527870425.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-06-01T16:27:05.059Z,1527870425.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-06-01T16:27:05.059Z,1527870425.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-06-01T16:27:05.077Z,1527870425.077 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-06-01T16:27:05.079Z,1527870425.079 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-06-01T16:27:05.080Z,1527870425.080 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-06-01T16:27:05.080Z,1527870425.080 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-06-01T16:27:05.082Z,1527870425.082 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:27:05.083Z,1527870425.083 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:27:05.084Z,1527870425.084 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:27:05.084Z,1527870425.084 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-06-01T16:27:05.085Z,1527870425.085 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-06-01T16:27:05.086Z,1527870425.086 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-06-01T16:27:05.109Z,1527870425.109 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-06-01T16:27:05.127Z,1527870425.127 [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-06-01T16:27:05.128Z,1527870425.128 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-06-01T16:27:05.229Z,1527870425.229 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-06-01T16:27:05.230Z,1527870425.230 [Default:A.Wait](DEBUG): Construct Wait.
2018-06-01T16:27:05.232Z,1527870425.232 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-06-01T16:27:05.267Z,1527870425.267 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-06-01T16:27:05.285Z,1527870425.285 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-06-01T16:27:05.291Z,1527870425.291 [Default:E.Execute](DEBUG): Construct Execute.
2018-06-01T16:27:05.311Z,1527870425.311 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-06-01T16:27:05.316Z,1527870425.316 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-06-01T16:27:05.369Z,1527870425.369 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-06-01T16:27:05.369Z,1527870425.369 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-06-01T16:27:05.416Z,1527870425.416 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-06-01T16:27:05.835Z,1527870425.835 [ESPComponent](INFO): powering down ESP
2018-06-01T16:27:06.803Z,1527870426.803 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-01T16:27:07.168Z,1527870427.168 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-06-01T16:27:07.206Z,1527870427.206 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-06-01T16:27:07.238Z,1527870427.238 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-06-01T16:27:07.241Z,1527870427.241 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-06-01T16:27:07.256Z,1527870427.256 [MassServo](DEBUG): Initializing EZServoServo.
2018-06-01T16:27:07.257Z,1527870427.257 [MassServo](DEBUG): Initializing MassServo.
2018-06-01T16:27:07.319Z,1527870427.319 [RudderServo](DEBUG): Initializing EZServoServo.
2018-06-01T16:27:07.346Z,1527870427.346 [RudderServo](DEBUG): Initializing RudderServo.
2018-06-01T16:27:07.355Z,1527870427.355 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-06-01T16:27:07.361Z,1527870427.361 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-06-01T16:27:07.665Z,1527870427.665 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-01T16:27:07.923Z,1527870427.923 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-01T16:27:08.384Z,1527870428.384 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-01T16:27:08.889Z,1527870428.889 [Aanderaa_O2](INFO): Powering down
2018-06-01T16:27:08.977Z,1527870428.977 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-01T16:27:09.175Z,1527870429.175 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-01T16:27:09.339Z,1527870429.339 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:27:09.408Z,1527870429.408 [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-06-01T16:27:09.458Z,1527870429.458 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:27:09.458Z,1527870429.458 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:27:09.459Z,1527870429.459 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:27:09.570Z,1527870429.570 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-01T16:27:10.019Z,1527870430.019 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-01T16:27:10.909Z,1527870430.909 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-06-01T16:27:11.342Z,1527870431.342 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-06-01T16:27:11.345Z,1527870431.345 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-06-01T16:27:11.935Z,1527870431.935 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-06-01T16:27:11.935Z,1527870431.935 [CTD_Seabird](INFO): Powering down
2018-06-01T16:27:12.962Z,1527870432.962 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-06-01T16:27:14.164Z,1527870434.164 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-06-01T16:27:14.164Z,1527870434.164 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-06-01T16:27:14.165Z,1527870434.165 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-06-01T16:27:14.166Z,1527870434.166 [AcousticModem_Benthos_ATM900](DEBUG): Apr  8 2000 16:22:25 
2018-06-01T16:27:14.565Z,1527870434.565 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-06-01T16:27:15.362Z,1527870435.362 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-06-01T16:27:15.763Z,1527870435.763 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-06-01T16:27:15.763Z,1527870435.763 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-06-01T16:27:15.763Z,1527870435.763 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-06-01T16:27:16.163Z,1527870436.163 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-06-01T16:27:16.567Z,1527870436.567 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-06-01T16:27:16.568Z,1527870436.568 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-06-01T16:27:16.964Z,1527870436.964 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-06-01T16:27:31.366Z,1527870451.366 [NAL9602](INFO): Powering up NAL9602
2018-06-01T16:27:42.166Z,1527870462.166 [NAL9602](INFO): NAL9602 initialized
2018-06-01T16:28:08.653Z,1527870488.653 [SBIT](IMPORTANT): Beginning Startup BIT
2018-06-01T16:28:08.662Z,1527870488.662 [CBIT](IMPORTANT): Beginning ground fault scan
2018-06-01T16:28:19.347Z,1527870499.347 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010412
CHAN A1 (24V): -0.011817
CHAN A2 (12V): -0.004146
CHAN A3 (5V): -0.001403
CHAN B0 (3.3V): -0.001287
CHAN B1 (3.15aV): -0.001294
CHAN B2 (3.15bV): -0.001247
CHAN B3 (GND): 0.000320
OPEN: 0.004741
Full Scale Calc: 4.765 mA, -1.589 mA
2018-06-01T16:29:02.413Z,1527870542.413 [SBIT](IMPORTANT): SBIT PASSED
2018-06-01T16:29:02.455Z,1527870542.455 [CommandLine](IMPORTANT): got command configSet list
2018-06-01T16:29:02.456Z,1527870542.456 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-06-01T16:29:02.457Z,1527870542.457 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2018-06-01T16:29:02.821Z,1527870542.821 [MissionManager](IMPORTANT): Started mission Startup
2018-06-01T16:29:02.821Z,1527870542.821 [Startup] Running Loop=1
2018-06-01T16:29:02.821Z,1527870542.821 [Startup](DEBUG): Aggregate::initialize Startup
2018-06-01T16:29:02.821Z,1527870542.821 [Startup:A.GoToSurface] Running Loop=1
2018-06-01T16:29:02.821Z,1527870542.821 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-06-01T16:29:02.822Z,1527870542.822 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-06-01T16:29:02.822Z,1527870542.822 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-06-01T16:29:02.823Z,1527870542.823 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-06-01T16:29:02.823Z,1527870542.823 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-06-01T16:29:02.824Z,1527870542.824 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-06-01T16:29:02.830Z,1527870542.830 [Startup:StartupSatComms] Running Loop=1
2018-06-01T16:29:02.830Z,1527870542.830 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-06-01T16:29:02.830Z,1527870542.830 [Startup:StartupSatComms:A] Running Loop=1
2018-06-01T16:29:03.264Z,1527870543.264 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-06-01T16:29:05.491Z,1527870545.491 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:29:05.491Z,1527870545.491 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-01T16:29:05.491Z,1527870545.491 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:29:05.630Z,1527870545.630 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:29:05.905Z,1527870545.905 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:29:06.124Z,1527870546.124 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:29:07.641Z,1527870547.641 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:29:07.641Z,1527870547.641 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-01T16:29:07.741Z,1527870547.741 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:29:07.741Z,1527870547.741 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:29:07.741Z,1527870547.741 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:29:07.742Z,1527870547.742 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:29:11.872Z,1527870551.872 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:29:11.932Z,1527870551.932 [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-06-01T16:29:11.934Z,1527870551.934 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:29:11.935Z,1527870551.935 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:29:11.936Z,1527870551.936 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:29:55.686Z,1527870595.686 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:29:56.481Z,1527870596.481 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:30:03.202Z,1527870603.202 [Startup:StartupSatComms:A](INFO): Timed out from 2018-06-01T16:29:02.8Z
2018-06-01T16:30:03.203Z,1527870603.203 [Startup:StartupSatComms:A] Stopped
2018-06-01T16:30:03.203Z,1527870603.203 [Startup:StartupSatComms:B] Running Loop=1
2018-06-01T16:30:03.611Z,1527870603.611 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-06-01T16:30:05.236Z,1527870605.236 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:30:05.236Z,1527870605.236 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-06-01T16:30:05.236Z,1527870605.236 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:30:05.269Z,1527870605.269 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:30:05.631Z,1527870605.631 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:30:05.631Z,1527870605.631 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-06-01T16:30:06.023Z,1527870606.023 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:30:06.023Z,1527870606.023 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:30:06.024Z,1527870606.024 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:30:11.008Z,1527870611.008 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004491
2018-06-01T16:30:15.613Z,1527870615.613 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T154359/Courier0015.lzma
2018-06-01T16:30:16.388Z,1527870616.388 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Courier0015.lzma.bak
2018-06-01T16:30:16.388Z,1527870616.388 [DataOverHttps](INFO): SBD MOMSN=8303099
2018-06-01T16:30:26.440Z,1527870626.440 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20180601T154359/Express0016.lzma
2018-06-01T16:30:27.240Z,1527870627.240 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Express0016.lzma.bak
2018-06-01T16:30:27.240Z,1527870627.240 [DataOverHttps](INFO): SBD MOMSN=8303101
2018-06-01T16:30:37.079Z,1527870637.079 [DataOverHttps](INFO): Sending 686 bytes from file Logs/20180601T162653/Express0001.lzma
2018-06-01T16:30:37.844Z,1527870637.844 [DataOverHttps](INFO): Moved sent file to Logs/20180601T162653/Express0001.lzma.bak
2018-06-01T16:30:37.845Z,1527870637.845 [DataOverHttps](INFO): SBD MOMSN=8303104
2018-06-01T16:30:39.100Z,1527870639.100 [Startup:StartupSatComms:B] Stopped
2018-06-01T16:30:39.101Z,1527870639.101 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-06-01T16:30:39.101Z,1527870639.101 [Startup:StartupSatComms] Stopped
2018-06-01T16:30:39.101Z,1527870639.101 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-06-01T16:30:39.102Z,1527870639.102 [Startup](INFO): Completed Startup
2018-06-01T16:30:39.102Z,1527870639.102 [MissionManager](INFO): Startup is completed.
2018-06-01T16:30:39.102Z,1527870639.102 [MissionManager](INFO): Uninitializing Mission Startup
2018-06-01T16:30:39.102Z,1527870639.102 [Startup] Stopped
2018-06-01T16:30:39.103Z,1527870639.103 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-06-01T16:30:39.103Z,1527870639.103 [Startup:A.GoToSurface] Stopped
2018-06-01T16:30:39.103Z,1527870639.103 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-06-01T16:30:39.248Z,1527870639.248 [MissionManager](IMPORTANT): Started mission Default
2018-06-01T16:30:39.248Z,1527870639.248 [Default] Running Loop=1
2018-06-01T16:30:39.248Z,1527870639.248 [Default](DEBUG): Aggregate::initialize Default
2018-06-01T16:30:39.248Z,1527870639.248 [Default:B.GoToSurface] Running Loop=1
2018-06-01T16:30:39.248Z,1527870639.248 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-06-01T16:30:39.253Z,1527870639.253 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-06-01T16:30:39.253Z,1527870639.253 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-06-01T16:30:39.253Z,1527870639.253 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-06-01T16:30:39.254Z,1527870639.254 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-06-01T16:30:39.254Z,1527870639.254 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-06-01T16:30:39.254Z,1527870639.254 [Default:A.Wait] Running Loop=1
2018-06-01T16:30:39.255Z,1527870639.255 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-06-01T16:30:52.317Z,1527870652.317 [Default:A.Wait](INFO): Done Waiting.
2018-06-01T16:30:52.317Z,1527870652.317 [Default:A.Wait] Stopped
2018-06-01T16:30:52.317Z,1527870652.317 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-06-01T16:30:52.726Z,1527870652.726 [Default:CheckIn] Running Loop=1
2018-06-01T16:30:52.727Z,1527870652.727 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-01T16:30:52.727Z,1527870652.727 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-01T16:30:53.128Z,1527870653.128 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-06-01T16:31:05.786Z,1527870665.786 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:31:06.581Z,1527870666.581 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:31:08.473Z,1527870668.473 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:31:08.473Z,1527870668.473 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-01T16:31:08.473Z,1527870668.473 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:31:08.754Z,1527870668.754 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:31:08.877Z,1527870668.877 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:31:08.973Z,1527870668.973 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:31:10.351Z,1527870670.351 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:31:10.351Z,1527870670.351 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-01T16:31:10.589Z,1527870670.589 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:31:10.589Z,1527870670.589 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:31:10.589Z,1527870670.589 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:31:10.590Z,1527870670.590 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:31:14.837Z,1527870674.837 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:31:14.911Z,1527870674.911 [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-06-01T16:31:14.914Z,1527870674.914 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:31:14.915Z,1527870674.915 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:31:14.916Z,1527870674.916 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:31:43.808Z,1527870703.808 [DataOverHttps](IMPORTANT): SBD MTMSN=20180601T163102
2018-06-01T16:31:44.854Z,1527870704.854 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:31:45.649Z,1527870705.649 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:31:48.958Z,1527870708.958 [DataOverHttps](INFO): Received command:load Maintenance/sample.xml;set sample.MissionTimeout 2 hour;set sample.Depth 2 meter;set sample.WaitBeforeSample 10 second;set sample:SampleAtDepth.TargetDepth 2 meter;set sample:SampleAtDepth.SettleTime 10 second;run 
2018-06-01T16:31:49.004Z,1527870709.004 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml
2018-06-01T16:31:49.004Z,1527870709.004 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
2018-06-01T16:31:49.053Z,1527870709.053 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
2018-06-01T16:31:49.055Z,1527870709.055 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
2018-06-01T16:31:49.058Z,1527870709.058 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count
2018-06-01T16:31:49.061Z,1527870709.061 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min
2018-06-01T16:31:49.063Z,1527870709.063 [sample:A.Pitch](DEBUG): Construct.
2018-06-01T16:31:49.117Z,1527870709.117 [sample:B.SetSpeed](DEBUG): Construct.
2018-06-01T16:31:49.120Z,1527870709.120 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
2018-06-01T16:31:49.233Z,1527870709.233 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
2018-06-01T16:31:49.236Z,1527870709.236 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 30.000000 s
2018-06-01T16:31:49.239Z,1527870709.239 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
2018-06-01T16:31:49.266Z,1527870709.266 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
2018-06-01T16:31:49.272Z,1527870709.272 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool
2018-06-01T16:31:49.275Z,1527870709.275 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool
2018-06-01T16:31:49.297Z,1527870709.297 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2018-06-01T16:31:49.300Z,1527870709.300 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2018-06-01T16:31:49.302Z,1527870709.302 [sample:SampleAtDepth:B.Pitch](DEBUG): Construct.
2018-06-01T16:31:49.335Z,1527870709.335 [sample:SampleAtDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-06-01T16:31:49.343Z,1527870709.343 [sample:SampleAtDepth:D.Wait](DEBUG): Construct Wait.
2018-06-01T16:31:49.359Z,1527870709.359 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-06-01T16:31:49.389Z,1527870709.389 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2018-06-01T16:31:49.389Z,1527870709.389 [MissionManager](ERROR): Slate does not contain celsius
2018-06-01T16:31:49.394Z,1527870709.394 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-06-01T16:31:49.413Z,1527870709.413 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2018-06-01T16:31:49.414Z,1527870709.414 [MissionManager](ERROR): Slate does not contain celsius
2018-06-01T16:31:49.440Z,1527870709.440 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="sample">

    <Description>
        This mission is designed to be run in the test tank only and samples
        whichever sampler is installed once the vehicle reaches the target depth.
    </Description>
    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth to sample at.
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="NumberOfSamples"><Description>
        Number of samples to take.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="WaitBeforeSample"><Description>
        How long to wait between samples. This starts *before* the first sample
        to exercise the SettleTime setting in the insert aggregate.
    </Description><Units:minute/><Value>3</Value></DefineArg>
    <Timeout Duration="MissionTimeout"/>
    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <Insert Filename="Insert/SampleAtDepth.xml"/>

    <Assign><Sequence/><Arg Name="SampleAtDepth:TargetDepth"/><Arg Name="Depth"/></Assign>

    <Assign><Sequence/><Arg Name="SampleAtDepth:SettleTime"/><Arg Name="WaitBeforeSample"/></Assign>

    <Aggregate Id="SampleRepeater">
        <Sequence Repeat="NumberOfSamples"/>

        <Call Id="Sample" RefId="SampleAtDepth"/>
    </Aggregate>
</Mission>

2018-06-01T16:31:49.440Z,1527870709.440 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml
2018-06-01T16:31:57.729Z,1527870717.729 [CommandLine](IMPORTANT): got command set sample.MissionTimeout 2.000000 hour
2018-06-01T16:31:57.730Z,1527870717.730 [CommandLine](IMPORTANT): got command set sample.Depth 2.000000 meter
2018-06-01T16:31:57.731Z,1527870717.731 [CommandLine](IMPORTANT): got command set sample.WaitBeforeSample 10.000000 second
2018-06-01T16:31:57.731Z,1527870717.731 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.TargetDepth 2.000000 meter
2018-06-01T16:31:57.732Z,1527870717.732 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.SettleTime 10.000000 second
2018-06-01T16:31:57.733Z,1527870717.733 [CommandLine](IMPORTANT): got command run 
2018-06-01T16:31:57.735Z,1527870717.735 [CommandLine](IMPORTANT): Running 
2018-06-01T16:31:57.827Z,1527870717.827 [Default] Stopped
2018-06-01T16:31:57.827Z,1527870717.827 [Default](DEBUG): Aggregate::uninitialize Default
2018-06-01T16:31:57.827Z,1527870717.827 [Default:B.GoToSurface] Stopped
2018-06-01T16:31:57.827Z,1527870717.827 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-06-01T16:31:57.827Z,1527870717.827 [Default:CheckIn] Stopped
2018-06-01T16:31:57.827Z,1527870717.827 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-01T16:31:57.827Z,1527870717.827 [Default:CheckIn:Read_GPS] Stopped
2018-06-01T16:31:57.828Z,1527870717.828 [MissionManager](IMPORTANT): Started mission sample
2018-06-01T16:31:57.828Z,1527870717.828 [sample] Running Loop=1
2018-06-01T16:31:57.828Z,1527870717.828 [sample](DEBUG): Aggregate::initialize sample
2018-06-01T16:31:57.828Z,1527870717.828 [sample:A.Pitch] Running Loop=1
2018-06-01T16:31:57.828Z,1527870717.828 [sample:A.Pitch](DEBUG): Initialize.
2018-06-01T16:31:57.829Z,1527870717.829 [sample:B.SetSpeed] Running Loop=1
2018-06-01T16:31:57.829Z,1527870717.829 [sample:B.SetSpeed](DEBUG): Initialize.
2018-06-01T16:31:57.829Z,1527870717.829 [sample:D] Running Loop=1
2018-06-01T16:31:57.831Z,1527870717.831 [sample:D] Stopped
2018-06-01T16:31:57.831Z,1527870717.831 [sample:E] Running Loop=1
2018-06-01T16:31:57.831Z,1527870717.831 [sample:B.SetSpeed] Running Loop=1
2018-06-01T16:31:57.836Z,1527870717.836 [sample:A.Pitch] Running Loop=1
2018-06-01T16:31:57.982Z,1527870717.982 [sample:E] Stopped
2018-06-01T16:31:57.982Z,1527870717.982 [sample:SampleRepeater] Running Loop=1
2018-06-01T16:31:57.982Z,1527870717.982 [sample:SampleRepeater](DEBUG): Aggregate::initialize sample:SampleRepeater
2018-06-01T16:31:57.983Z,1527870717.983 [sample:SampleRepeater:Sample] Running Loop=1
2018-06-01T16:31:57.983Z,1527870717.983 [sample:SampleRepeater:Sample](DEBUG): Aggregate::initialize sample:SampleRepeater:Sample
2018-06-01T16:31:58.428Z,1527870718.428 [sample:SampleAtDepth] Running Loop=1
2018-06-01T16:31:58.428Z,1527870718.428 [sample:SampleAtDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth
2018-06-01T16:31:58.437Z,1527870718.437 [sample:SampleAtDepth:B.Pitch] Running Loop=1
2018-06-01T16:31:58.437Z,1527870718.437 [sample:SampleAtDepth:B.Pitch](DEBUG): Initialize.
2018-06-01T16:31:58.438Z,1527870718.438 [sample:SampleAtDepth:A] Running Loop=1
2018-06-01T16:31:58.460Z,1527870718.460 [sample:SampleAtDepth:A](INFO): Moving to 2.000000 m 
2018-06-01T16:31:58.467Z,1527870718.467 [sample:SampleAtDepth:A] Stopped
2018-06-01T16:32:12.011Z,1527870732.011 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 2.000000 meter
2018-06-01T16:32:12.012Z,1527870732.012 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2018-06-01T16:32:12.045Z,1527870732.045 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-06-01T16:32:12.381Z,1527870732.381 [sample:SampleAtDepth:C] Running Loop=1
2018-06-01T16:32:12.630Z,1527870732.630 [Radio_Surface](INFO): Powering down
2018-06-01T16:32:12.754Z,1527870732.754 [sample:SampleAtDepth:C](INFO): At 2.000000 m , settling for 0.166667 min 
2018-06-01T16:32:12.755Z,1527870732.755 [sample:SampleAtDepth:C] Stopped
2018-06-01T16:32:12.755Z,1527870732.755 [sample:SampleAtDepth:D.Wait] Running Loop=1
2018-06-01T16:32:12.755Z,1527870732.755 [sample:SampleAtDepth:D.Wait](DEBUG): Initialize Wait Component.
2018-06-01T16:32:19.502Z,1527870739.502 [Radio_Surface](INFO): Powering up
2018-06-01T16:32:23.572Z,1527870743.572 [sample:SampleAtDepth:D.Wait](INFO): Done Waiting.
2018-06-01T16:32:23.572Z,1527870743.572 [sample:SampleAtDepth:D.Wait] Stopped
2018-06-01T16:32:23.572Z,1527870743.572 [sample:SampleAtDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2018-06-01T16:32:23.572Z,1527870743.572 [sample:SampleAtDepth:SampleWrapper] Running Loop=1
2018-06-01T16:32:23.572Z,1527870743.572 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper
2018-06-01T16:32:23.572Z,1527870743.572 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Running Loop=1
2018-06-01T16:32:23.573Z,1527870743.573 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler
2018-06-01T16:32:23.573Z,1527870743.573 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1
2018-06-01T16:32:23.573Z,1527870743.573 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Running Loop=1
2018-06-01T16:32:23.573Z,1527870743.573 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Running Loop=1
2018-06-01T16:32:23.943Z,1527870743.943 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Stopped
2018-06-01T16:32:23.943Z,1527870743.943 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler
2018-06-01T16:32:23.943Z,1527870743.943 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Stopped
2018-06-01T16:32:23.943Z,1527870743.943 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Stopped
2018-06-01T16:32:23.943Z,1527870743.943 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Stopped
2018-06-01T16:32:23.943Z,1527870743.943 [sample:SampleAtDepth:SampleWrapper:SampleESP] Running Loop=1
2018-06-01T16:32:23.944Z,1527870743.944 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleESP
2018-06-01T16:32:23.944Z,1527870743.944 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Running Loop=1
2018-06-01T16:32:24.378Z,1527870744.378 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sampling
2018-06-01T16:32:25.166Z,1527870745.166 [Radio_Surface](INFO): Powering down
2018-06-01T16:32:25.534Z,1527870745.534 [ESPComponent](INFO): powering up ESP
2018-06-01T16:32:25.535Z,1527870745.535 [ESPComponent](INFO): manually powering up ESP
2018-06-01T16:32:25.537Z,1527870745.537 [ESPComponent](IMPORTANT): Powered ESP with command: ! echo 1 > /dev/loadA6
2018-06-01T16:32:25.538Z,1527870745.538 [ESPComponent](IMPORTANT): Starting PPPD with command:/sbin/pppd xonxoff asyncmap A0000 /dev/ttyTX1 linkname esp noauth local lock 115200 134.89.95.2:134.89.95.12 persist maxfail 0 holdoff 10 lcp-echo-interval 60 lcp-echo-failure 3 proxyarp ktune deflate 12 ms-dns 134.89.10.32 ms-dns 134.89.10.10
2018-06-01T16:32:25.617Z,1527870745.617 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadA6
2018-06-01T16:32:25.922Z,1527870745.922 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=150.0000)
2018-06-01T16:32:34.862Z,1527870754.862 [Radio_Surface](INFO): Powering up
2018-06-01T16:32:40.922Z,1527870760.922 [Radio_Surface](INFO): Powering down
2018-06-01T16:32:45.662Z,1527870765.662 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-06-01T16:32:58.494Z,1527870778.494 [ESPComponent](IMPORTANT): ESP has connected as client
2018-06-01T16:32:59.306Z,1527870779.306 [ESPComponent](IMPORTANT): ESP sampling sequence starting (sample #1)  sampling state: S_WAITING_INITIAL_PROMPT
2018-06-01T16:32:59.306Z,1527870779.306 [ESPComponent](DEBUG): Writing samplingActive=1, sampleNumber=1
2018-06-01T16:32:59.318Z,1527870779.318 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Stopped
2018-06-01T16:32:59.318Z,1527870779.318 [sample:SampleAtDepth:SampleWrapper:SampleESP:B] Running Loop=1
2018-06-01T16:32:59.723Z,1527870779.723 [sample:SampleAtDepth:SampleWrapper:SampleESP:B](IMPORTANT): ESP sampling at 2.000000 m inwater with nan ug/l chlorophyll fluorescence.
2018-06-01T16:32:59.724Z,1527870779.724 [sample:SampleAtDepth:SampleWrapper:SampleESP:B] Stopped
2018-06-01T16:32:59.724Z,1527870779.724 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP] Running Loop=1
2018-06-01T16:33:00.156Z,1527870780.156 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sample_number
2018-06-01T16:33:00.488Z,1527870780.488 [ESPComponent](IMPORTANT): ESP sampling state: S_PREPARING_SHOW_LOG
2018-06-01T16:33:00.893Z,1527870780.893 [ESPComponent](IMPORTANT): ESP sampling state: S_PREPARING_SHOW_STATUS
2018-06-01T16:33:02.489Z,1527870782.489 [ESPComponent](IMPORTANT): ESP sampling state: S_LOADING_CARTRIDGE
2018-06-01T16:33:06.107Z,1527870786.107 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:33:06.107Z,1527870786.107 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-06-01T16:33:06.107Z,1527870786.107 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:33:06.205Z,1527870786.205 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:33:06.606Z,1527870786.606 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:33:06.606Z,1527870786.606 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-06-01T16:33:06.911Z,1527870786.911 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:33:06.912Z,1527870786.912 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:33:06.912Z,1527870786.912 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:33:10.869Z,1527870790.869 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:33:10.869Z,1527870790.869 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-01T16:33:10.869Z,1527870790.869 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:33:11.015Z,1527870791.015 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:33:11.273Z,1527870791.273 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:33:11.482Z,1527870791.482 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:33:12.289Z,1527870792.289 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-06-01T16:33:12.289Z,1527870792.289 [Rowe_600LCM] Hardware Fault, FailCount= 3
2018-06-01T16:33:12.289Z,1527870792.289 [Rowe_600LCM](ERROR): Hardware Fault
2018-06-01T16:33:12.955Z,1527870792.955 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:33:12.955Z,1527870792.955 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-01T16:33:13.097Z,1527870793.097 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:33:13.097Z,1527870793.097 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:33:13.098Z,1527870793.098 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:33:13.098Z,1527870793.098 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:33:13.264Z,1527870793.264 [Radio_Surface](INFO): Powering up
2018-06-01T16:33:14.451Z,1527870794.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:33:17.230Z,1527870797.230 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:33:17.356Z,1527870797.356 [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-06-01T16:33:17.357Z,1527870797.357 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:33:17.358Z,1527870797.358 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:33:17.359Z,1527870797.359 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:33:19.338Z,1527870799.338 [Radio_Surface](INFO): Powering down
2018-06-01T16:33:33.815Z,1527870813.815 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:33:34.610Z,1527870814.610 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:33:51.661Z,1527870831.661 [Radio_Surface](INFO): Powering up
2018-06-01T16:33:57.318Z,1527870837.318 [Radio_Surface](INFO): Powering down
2018-06-01T16:34:14.452Z,1527870854.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:34:32.065Z,1527870872.065 [Radio_Surface](INFO): Powering up
2018-06-01T16:34:37.787Z,1527870877.787 [Radio_Surface](INFO): Powering down
2018-06-01T16:34:58.451Z,1527870898.451 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.158279
2018-06-01T16:35:05.311Z,1527870905.311 [ESPComponent](INFO): Keyword match: Selecting. Found summary msg: Selecting Cartridge 10
2018-06-01T16:35:05.311Z,1527870905.311 [ESPComponent](INFO): Added summary msg# 1 to queue.
2018-06-01T16:35:05.665Z,1527870905.665 [Radio_Surface](INFO): Powering up
2018-06-01T16:35:08.532Z,1527870908.532 [ESPComponent](IMPORTANT): ESP sampling state: S_FILTERING
2018-06-01T16:35:11.330Z,1527870911.330 [Radio_Surface](INFO): Powering down
2018-06-01T16:35:11.694Z,1527870911.694 [ESPComponent](IMPORTANT): ESP sampling state: S_WAITING_FOR_PAUSED
2018-06-01T16:35:13.853Z,1527870913.853 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:35:13.853Z,1527870913.853 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-01T16:35:13.853Z,1527870913.853 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:35:14.149Z,1527870914.149 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:35:14.257Z,1527870914.257 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:35:14.350Z,1527870914.350 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:35:15.735Z,1527870915.735 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:35:15.736Z,1527870915.736 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-01T16:35:15.965Z,1527870915.965 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:35:15.966Z,1527870915.966 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:35:15.966Z,1527870915.966 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:35:15.966Z,1527870915.966 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:35:20.183Z,1527870920.183 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:35:20.289Z,1527870920.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-06-01T16:35:20.290Z,1527870920.290 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:35:20.291Z,1527870920.291 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:35:20.292Z,1527870920.292 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:35:44.872Z,1527870944.872 [Radio_Surface](INFO): Powering up
2018-06-01T16:35:50.534Z,1527870950.534 [Radio_Surface](INFO): Powering down
2018-06-01T16:36:06.924Z,1527870966.924 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:36:06.946Z,1527870966.946 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-06-01T16:36:06.946Z,1527870966.946 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:36:07.007Z,1527870967.007 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:36:07.334Z,1527870967.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:36:07.334Z,1527870967.334 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-06-01T16:36:07.727Z,1527870967.727 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:36:07.727Z,1527870967.727 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:36:07.728Z,1527870967.728 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:36:15.251Z,1527870975.251 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:36:22.859Z,1527870982.859 [Radio_Surface](INFO): Powering up
2018-06-01T16:36:28.104Z,1527870988.104 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-01T16:36:28.104Z,1527870988.104 [BPC1] Communications Fault, FailCount= 1
2018-06-01T16:36:28.104Z,1527870988.104 [BPC1](ERROR): Communications Fault
2018-06-01T16:36:28.158Z,1527870988.158 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-01T16:36:28.922Z,1527870988.922 [Radio_Surface](INFO): Powering down
2018-06-01T16:36:29.319Z,1527870989.319 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-01T16:36:29.319Z,1527870989.319 [BPC1] No Fault, FailCount= 1
2018-06-01T16:37:02.058Z,1527871022.058 [Radio_Surface](INFO): Powering up
2018-06-01T16:37:07.714Z,1527871027.714 [Radio_Surface](INFO): Powering down
2018-06-01T16:37:13.359Z,1527871033.359 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:37:14.153Z,1527871034.153 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:37:14.951Z,1527871034.951 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:37:15.257Z,1527871035.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:37:15.657Z,1527871035.657 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:37:16.049Z,1527871036.049 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:37:16.049Z,1527871036.049 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-01T16:37:16.049Z,1527871036.049 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:37:16.137Z,1527871036.137 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:37:16.138Z,1527871036.138 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-01T16:37:16.453Z,1527871036.453 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:37:16.665Z,1527871036.665 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:37:17.473Z,1527871037.473 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-06-01T16:37:17.473Z,1527871037.473 [Rowe_600LCM] Hardware Fault, FailCount= 5
2018-06-01T16:37:17.473Z,1527871037.473 [Rowe_600LCM](ERROR): Hardware Fault
2018-06-01T16:37:42.058Z,1527871062.058 [Radio_Surface](INFO): Powering up
2018-06-01T16:37:43.262Z,1527871063.262 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-06-01T16:37:43.262Z,1527871063.262 [NAL9602] Data Fault, FailCount= 1
2018-06-01T16:37:43.262Z,1527871063.262 [NAL9602](ERROR): Data Fault
2018-06-01T16:37:43.367Z,1527871063.367 [CBIT](ERROR): Data Fault in component: NAL9602
2018-06-01T16:37:43.654Z,1527871063.654 [NAL9602](INFO): Powering down
2018-06-01T16:37:44.512Z,1527871064.512 [CBIT](INFO): Clearing failed state for component NAL9602
2018-06-01T16:37:44.512Z,1527871064.512 [NAL9602] No Fault, FailCount= 1
2018-06-01T16:37:47.714Z,1527871067.714 [Radio_Surface](INFO): Powering down
2018-06-01T16:38:14.054Z,1527871094.054 [NAL9602](INFO): Powering up NAL9602
2018-06-01T16:38:15.651Z,1527871095.651 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:38:22.458Z,1527871102.458 [Radio_Surface](INFO): Powering up
2018-06-01T16:38:25.254Z,1527871105.254 [NAL9602](INFO): NAL9602 initialized
2018-06-01T16:38:28.114Z,1527871108.114 [Radio_Surface](INFO): Powering down
2018-06-01T16:39:02.060Z,1527871142.060 [Radio_Surface](INFO): Powering up
2018-06-01T16:39:07.714Z,1527871147.714 [Radio_Surface](INFO): Powering down
2018-06-01T16:39:08.100Z,1527871148.100 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:39:08.100Z,1527871148.100 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-06-01T16:39:08.100Z,1527871148.100 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:39:08.115Z,1527871148.115 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:39:08.514Z,1527871148.514 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:39:08.514Z,1527871148.514 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-06-01T16:39:08.901Z,1527871148.901 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:39:08.901Z,1527871148.901 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:39:08.901Z,1527871148.901 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:39:16.051Z,1527871156.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:39:42.461Z,1527871182.461 [Radio_Surface](INFO): Powering up
2018-06-01T16:39:48.118Z,1527871188.118 [Radio_Surface](INFO): Powering down
2018-06-01T16:40:16.051Z,1527871216.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:40:21.261Z,1527871221.261 [Radio_Surface](INFO): Powering up
2018-06-01T16:40:26.918Z,1527871226.918 [Radio_Surface](INFO): Powering down
2018-06-01T16:41:01.662Z,1527871261.662 [Radio_Surface](INFO): Powering up
2018-06-01T16:41:07.319Z,1527871267.319 [Radio_Surface](INFO): Powering down
2018-06-01T16:41:16.451Z,1527871276.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:41:38.461Z,1527871298.461 [Radio_Surface](INFO): Powering up
2018-06-01T16:41:44.118Z,1527871304.118 [Radio_Surface](INFO): Powering down
2018-06-01T16:41:55.603Z,1527871315.603 [CommandLine](IMPORTANT): got command show stack
2018-06-01T16:41:55.603Z,1527871315.603 [CommandLine](IMPORTANT): Behavior Stack:

2018-06-01T16:41:55.603Z,1527871315.603 [sample](IMPORTANT): Priority 0: sample:A.Pitch
2018-06-01T16:41:55.603Z,1527871315.603 [sample](IMPORTANT): Priority 1: sample:B.SetSpeed
2018-06-01T16:41:55.604Z,1527871315.604 [sample:SampleAtDepth](IMPORTANT): Priority 2: sample:SampleAtDepth:B.Pitch
2018-06-01T16:41:55.604Z,1527871315.604 [sample:SampleAtDepth:SampleWrapper:SampleESP](IMPORTANT): Priority 3: sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP
2018-06-01T16:41:58.389Z,1527871318.389 [CommandLine](IMPORTANT): got command maintain clear
2018-06-01T16:41:58.545Z,1527871318.545 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-06-01T16:41:59.074Z,1527871319.074 [Radio_Surface](INFO): Powering up
2018-06-01T16:42:08.912Z,1527871328.912 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:42:08.912Z,1527871328.912 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-06-01T16:42:08.912Z,1527871328.912 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:42:09.007Z,1527871329.007 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:42:09.388Z,1527871329.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:42:09.388Z,1527871329.388 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-06-01T16:42:09.711Z,1527871329.711 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:42:09.711Z,1527871329.711 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:42:09.712Z,1527871329.712 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:42:10.526Z,1527871330.526 [CommandLine](IMPORTANT): got command failComponent hardware ESPComponent
2018-06-01T16:42:10.526Z,1527871330.526 [ESPComponent] Hardware Fault, FailCount= 1
2018-06-01T16:42:10.526Z,1527871330.526 [ESPComponent](ERROR): Hardware Fault
2018-06-01T16:42:10.526Z,1527871330.526 [CommandLine](IMPORTANT): ESPComponent failureMode is Hardware Fault
2018-06-01T16:42:10.591Z,1527871330.591 [CBIT](ERROR): Hardware Fault in component: ESPComponent
2018-06-01T16:42:10.905Z,1527871330.905 [ESPComponent](IMPORTANT): Reporting ESP summary. 1 messages in queue.
2018-06-01T16:42:10.905Z,1527871330.905 [ESPComponent](IMPORTANT): Selecting Cartridge 10
2018-06-01T16:42:10.905Z,1527871330.905 [ESPComponent](IMPORTANT): ESP summary cleared. 0 messages in queue.
2018-06-01T16:42:10.906Z,1527871330.906 [ESPComponent](INFO): powering down ESP
2018-06-01T16:42:11.813Z,1527871331.813 [CBIT](INFO): Clearing failed state for component ESPComponent
2018-06-01T16:42:11.813Z,1527871331.813 [ESPComponent] No Fault, FailCount= 1
2018-06-01T16:42:12.488Z,1527871332.488 [ESPComponent](IMPORTANT): ESP summary empty. 0 messages in queue.
2018-06-01T16:42:12.489Z,1527871332.489 [ESPComponent](INFO): powering down ESP
2018-06-01T16:42:16.452Z,1527871336.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:42:26.043Z,1527871346.043 [CommandLine](IMPORTANT): got command show stack
2018-06-01T16:42:26.043Z,1527871346.043 [CommandLine](IMPORTANT): Behavior Stack:

2018-06-01T16:42:26.044Z,1527871346.044 [sample](IMPORTANT): Priority 0: sample:A.Pitch
2018-06-01T16:42:26.044Z,1527871346.044 [sample](IMPORTANT): Priority 1: sample:B.SetSpeed
2018-06-01T16:42:26.044Z,1527871346.044 [sample:SampleAtDepth](IMPORTANT): Priority 2: sample:SampleAtDepth:B.Pitch
2018-06-01T16:42:26.050Z,1527871346.050 [sample:SampleAtDepth:SampleWrapper:SampleESP](IMPORTANT): Priority 3: sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP
2018-06-01T16:42:27.973Z,1527871347.973 [CommandLine](IMPORTANT): got command get depth
2018-06-01T16:42:27.973Z,1527871347.973 [CommandLine](IMPORTANT): depth -0.039426 m
2018-06-01T16:43:16.851Z,1527871396.851 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-01T16:43:59.699Z,1527871439.699 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003111
2018-06-01T16:44:13.332Z,1527871453.332 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-01T16:44:13.333Z,1527871453.333 [BPC1] Communications Fault, FailCount= 2
2018-06-01T16:44:13.333Z,1527871453.333 [BPC1](ERROR): Communications Fault
2018-06-01T16:44:13.439Z,1527871453.439 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-01T16:44:14.599Z,1527871454.599 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-01T16:44:14.599Z,1527871454.599 [BPC1] No Fault, FailCount= 2
2018-06-01T16:45:09.756Z,1527871509.756 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:45:09.756Z,1527871509.756 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-06-01T16:45:09.756Z,1527871509.756 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:45:09.771Z,1527871509.771 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:45:10.213Z,1527871510.213 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:45:10.213Z,1527871510.213 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-06-01T16:45:10.572Z,1527871510.572 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:45:10.572Z,1527871510.572 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:45:10.573Z,1527871510.573 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:47:16.273Z,1527871636.273 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:47:16.273Z,1527871636.273 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-01T16:47:16.462Z,1527871636.462 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:47:16.463Z,1527871636.463 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:47:16.463Z,1527871636.463 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:47:16.463Z,1527871636.463 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:47:20.613Z,1527871640.613 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:47:20.766Z,1527871640.766 [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-06-01T16:47:20.767Z,1527871640.767 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:47:20.768Z,1527871640.768 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:47:20.770Z,1527871640.770 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:48:10.578Z,1527871690.578 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:48:10.578Z,1527871690.578 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-06-01T16:48:10.578Z,1527871690.578 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:48:10.613Z,1527871690.613 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:48:11.010Z,1527871691.010 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:48:11.010Z,1527871691.010 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-06-01T16:48:11.379Z,1527871691.379 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:48:11.379Z,1527871691.379 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:48:11.380Z,1527871691.380 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:48:26.538Z,1527871706.538 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-06-01T16:48:26.539Z,1527871706.539 [NAL9602] Data Fault, FailCount= 2
2018-06-01T16:48:26.539Z,1527871706.539 [NAL9602](ERROR): Data Fault
2018-06-01T16:48:26.622Z,1527871706.622 [CBIT](ERROR): Data Fault in component: NAL9602
2018-06-01T16:48:26.938Z,1527871706.938 [NAL9602](INFO): Powering down
2018-06-01T16:48:27.836Z,1527871707.836 [CBIT](INFO): Clearing failed state for component NAL9602
2018-06-01T16:48:27.836Z,1527871707.836 [NAL9602] No Fault, FailCount= 2
2018-06-01T16:48:57.302Z,1527871737.302 [NAL9602](INFO): Powering up NAL9602
2018-06-01T16:49:08.102Z,1527871748.102 [NAL9602](INFO): NAL9602 initialized
2018-06-01T16:49:17.153Z,1527871757.153 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:49:17.153Z,1527871757.153 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-01T16:49:17.153Z,1527871757.153 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:49:17.386Z,1527871757.386 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:49:17.557Z,1527871757.557 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:49:17.655Z,1527871757.655 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:49:18.960Z,1527871758.960 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:49:18.960Z,1527871758.960 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-01T16:49:19.269Z,1527871759.269 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:49:19.269Z,1527871759.269 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:49:19.269Z,1527871759.269 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:49:19.270Z,1527871759.270 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:49:23.513Z,1527871763.513 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:49:23.574Z,1527871763.574 [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-06-01T16:49:23.575Z,1527871763.575 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:49:23.576Z,1527871763.576 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:49:23.577Z,1527871763.577 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:50:54.327Z,1527871854.327 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-01T16:50:54.327Z,1527871854.327 [BPC1] Communications Fault, FailCount= 3
2018-06-01T16:50:54.327Z,1527871854.327 [BPC1](ERROR): Communications Fault
2018-06-01T16:50:54.384Z,1527871854.384 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-01T16:50:55.382Z,1527871855.382 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-01T16:50:55.382Z,1527871855.382 [BPC1] No Fault, FailCount= 3
2018-06-01T16:51:11.641Z,1527871871.641 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:51:11.641Z,1527871871.641 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-06-01T16:51:11.641Z,1527871871.641 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:51:11.676Z,1527871871.676 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:51:12.106Z,1527871872.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:51:12.106Z,1527871872.106 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-06-01T16:51:12.447Z,1527871872.447 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:51:12.447Z,1527871872.447 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:51:12.448Z,1527871872.448 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:51:17.662Z,1527871877.662 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:51:18.458Z,1527871878.458 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:52:59.289Z,1527871979.289 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:52:59.289Z,1527871979.289 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-01T16:52:59.289Z,1527871979.289 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:52:59.577Z,1527871979.577 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:52:59.693Z,1527871979.693 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:52:59.859Z,1527871979.859 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:53:01.081Z,1527871981.081 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:53:01.081Z,1527871981.081 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-01T16:53:01.473Z,1527871981.473 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:53:01.473Z,1527871981.473 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:53:01.473Z,1527871981.473 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:53:01.473Z,1527871981.473 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:53:05.630Z,1527871985.630 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:53:05.693Z,1527871985.693 [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-06-01T16:53:05.694Z,1527871985.694 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:53:05.695Z,1527871985.695 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:53:05.696Z,1527871985.696 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:54:12.679Z,1527872052.679 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:54:12.679Z,1527872052.679 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-06-01T16:54:12.679Z,1527872052.679 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:54:12.695Z,1527872052.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:54:13.092Z,1527872053.092 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:54:13.092Z,1527872053.092 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-06-01T16:54:13.487Z,1527872053.487 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:54:13.487Z,1527872053.487 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:54:13.488Z,1527872053.488 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:54:58.990Z,1527872098.990 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:54:59.787Z,1527872099.787 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:55:01.681Z,1527872101.681 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:55:01.681Z,1527872101.681 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-01T16:55:01.681Z,1527872101.681 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:55:01.946Z,1527872101.946 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:55:02.085Z,1527872102.085 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:55:02.178Z,1527872102.178 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:55:03.542Z,1527872103.542 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:55:03.542Z,1527872103.542 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-01T16:55:03.793Z,1527872103.793 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:55:03.793Z,1527872103.793 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:55:03.793Z,1527872103.793 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:55:03.794Z,1527872103.794 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:55:08.035Z,1527872108.035 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:55:08.101Z,1527872108.101 [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-06-01T16:55:08.102Z,1527872108.102 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:55:08.103Z,1527872108.103 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:55:08.103Z,1527872108.103 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:55:38.054Z,1527872138.054 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:55:38.849Z,1527872138.849 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:57:04.074Z,1527872224.074 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:57:04.074Z,1527872224.074 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-01T16:57:04.074Z,1527872224.074 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:57:04.307Z,1527872224.307 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:57:04.477Z,1527872224.477 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:57:04.567Z,1527872224.567 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:57:05.917Z,1527872225.917 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T16:57:05.917Z,1527872225.917 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-01T16:57:06.182Z,1527872226.182 [Rowe_600LCM](INFO): Initializing
2018-06-01T16:57:06.182Z,1527872226.182 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T16:57:06.183Z,1527872226.183 [Rowe_600LCM](INFO): LCM OK
2018-06-01T16:57:06.183Z,1527872226.183 [Rowe_600LCM](INFO): Powering up
2018-06-01T16:57:08.271Z,1527872228.271 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-01T16:57:08.271Z,1527872228.271 [BPC1] Communications Fault, FailCount= 4
2018-06-01T16:57:08.271Z,1527872228.271 [BPC1](ERROR): Communications Fault
2018-06-01T16:57:08.325Z,1527872228.325 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-01T16:57:09.490Z,1527872229.490 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-01T16:57:09.490Z,1527872229.490 [BPC1] No Fault, FailCount= 4
2018-06-01T16:57:10.478Z,1527872230.478 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T16:57:10.541Z,1527872230.541 [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-06-01T16:57:10.542Z,1527872230.542 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T16:57:10.543Z,1527872230.543 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T16:57:10.544Z,1527872230.544 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T16:57:13.876Z,1527872233.876 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T16:57:13.876Z,1527872233.876 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-06-01T16:57:13.876Z,1527872233.876 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T16:57:13.916Z,1527872233.916 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T16:57:14.336Z,1527872234.336 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T16:57:14.336Z,1527872234.336 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-06-01T16:57:14.681Z,1527872234.681 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T16:57:14.682Z,1527872234.682 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T16:57:14.682Z,1527872234.682 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T16:58:16.544Z,1527872296.544 [BPC1](ERROR): Failed to parse Bank A battery data
2018-06-01T16:58:28.959Z,1527872308.959 [BPC1](ERROR): Failed to parse Bank A battery data
2018-06-01T16:58:36.326Z,1527872316.326 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:37.122Z,1527872317.122 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:37.917Z,1527872317.917 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:38.617Z,1527872318.617 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:39.413Z,1527872319.413 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:40.210Z,1527872320.210 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:40.641Z,1527872320.641 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:41.439Z,1527872321.439 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:42.077Z,1527872322.077 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:42.873Z,1527872322.873 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:43.672Z,1527872323.672 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:44.093Z,1527872324.093 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:44.889Z,1527872324.889 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:45.688Z,1527872325.688 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:46.145Z,1527872326.145 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:46.943Z,1527872326.943 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:47.739Z,1527872327.739 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:48.181Z,1527872328.181 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:48.977Z,1527872328.977 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:49.774Z,1527872329.774 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:50.346Z,1527872330.346 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:51.141Z,1527872331.141 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:51.937Z,1527872331.937 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:52.642Z,1527872332.642 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:53.438Z,1527872333.438 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:54.234Z,1527872334.234 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:54.661Z,1527872334.661 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:55.457Z,1527872335.457 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:56.101Z,1527872336.101 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:56.897Z,1527872336.897 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:57.694Z,1527872337.694 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:58.121Z,1527872338.121 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:58.917Z,1527872338.917 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:58:59.722Z,1527872339.722 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:00.129Z,1527872340.129 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:00.925Z,1527872340.925 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:01.722Z,1527872341.722 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:02.177Z,1527872342.177 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:02.974Z,1527872342.974 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:03.772Z,1527872343.772 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:04.369Z,1527872344.369 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:05.165Z,1527872345.165 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:05.961Z,1527872345.961 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T16:59:06.266Z,1527872346.266 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T16:59:06.266Z,1527872346.266 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-01T16:59:06.266Z,1527872346.266 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T16:59:06.270Z,1527872346.270 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T16:59:06.270Z,1527872346.270 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-01T16:59:06.669Z,1527872346.669 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T16:59:06.766Z,1527872346.766 [Rowe_600LCM](INFO): Powering down
2018-06-01T16:59:07.573Z,1527872347.573 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-06-01T16:59:07.573Z,1527872347.573 [Rowe_600LCM] Hardware Fault, FailCount= 5
2018-06-01T16:59:07.573Z,1527872347.573 [Rowe_600LCM](ERROR): Hardware Fault
2018-06-01T16:59:10.811Z,1527872350.811 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-06-01T16:59:10.811Z,1527872350.811 [NAL9602] Data Fault, FailCount= 3
2018-06-01T16:59:10.811Z,1527872350.811 [NAL9602](ERROR): Data Fault
2018-06-01T16:59:10.888Z,1527872350.888 [CBIT](ERROR): Data Fault in component: NAL9602
2018-06-01T16:59:11.210Z,1527872351.210 [NAL9602](INFO): Powering down
2018-06-01T16:59:12.102Z,1527872352.102 [CBIT](INFO): Clearing failed state for component NAL9602
2018-06-01T16:59:12.102Z,1527872352.102 [NAL9602] No Fault, FailCount= 3
2018-06-01T16:59:41.214Z,1527872381.214 [NAL9602](INFO): Powering up NAL9602
2018-06-01T16:59:53.678Z,1527872393.678 [NAL9602](INFO): NAL9602 initialized
2018-06-01T17:00:14.919Z,1527872414.919 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:00:14.919Z,1527872414.919 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-06-01T17:00:14.919Z,1527872414.919 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:00:14.934Z,1527872414.934 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:00:15.334Z,1527872415.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:00:15.334Z,1527872415.334 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-06-01T17:00:15.721Z,1527872415.721 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:00:15.722Z,1527872415.722 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:00:15.722Z,1527872415.722 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:03:15.935Z,1527872595.935 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:03:15.935Z,1527872595.935 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-06-01T17:03:15.935Z,1527872595.935 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:03:15.950Z,1527872595.950 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:03:16.343Z,1527872596.343 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:03:16.343Z,1527872596.343 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-06-01T17:03:16.732Z,1527872596.732 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:03:16.733Z,1527872596.733 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:03:16.734Z,1527872596.734 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:04:46.008Z,1527872686.008 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-01T17:04:46.008Z,1527872686.008 [BPC1] Communications Fault, FailCount= 5
2018-06-01T17:04:46.008Z,1527872686.008 [BPC1](ERROR): Communications Fault
2018-06-01T17:04:46.038Z,1527872686.038 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-01T17:04:47.246Z,1527872687.246 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-01T17:04:47.246Z,1527872687.246 [BPC1] No Fault, FailCount= 5
2018-06-01T17:06:16.819Z,1527872776.819 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:06:16.819Z,1527872776.819 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-06-01T17:06:16.820Z,1527872776.820 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:06:16.863Z,1527872776.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:06:17.258Z,1527872777.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:06:17.258Z,1527872777.258 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-06-01T17:06:17.617Z,1527872777.617 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:06:17.618Z,1527872777.618 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:06:17.618Z,1527872777.618 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:09:06.604Z,1527872946.604 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T17:09:06.604Z,1527872946.604 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-01T17:09:06.665Z,1527872946.665 [Rowe_600LCM](INFO): Initializing
2018-06-01T17:09:06.665Z,1527872946.665 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T17:09:06.665Z,1527872946.665 [Rowe_600LCM](INFO): LCM OK
2018-06-01T17:09:06.666Z,1527872946.666 [Rowe_600LCM](INFO): Powering up
2018-06-01T17:09:10.802Z,1527872950.802 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T17:09:10.875Z,1527872950.875 [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-06-01T17:09:10.876Z,1527872950.876 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T17:09:10.878Z,1527872950.878 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T17:09:10.879Z,1527872950.879 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T17:09:17.801Z,1527872957.801 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:09:17.802Z,1527872957.802 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-06-01T17:09:17.802Z,1527872957.802 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:09:17.833Z,1527872957.833 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:09:18.216Z,1527872958.216 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:09:18.216Z,1527872958.216 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-06-01T17:09:18.584Z,1527872958.584 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:09:18.585Z,1527872958.585 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:09:18.585Z,1527872958.585 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:09:55.226Z,1527872995.226 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-06-01T17:09:55.227Z,1527872995.227 [NAL9602] Data Fault, FailCount= 4
2018-06-01T17:09:55.227Z,1527872995.227 [NAL9602](ERROR): Data Fault
2018-06-01T17:09:55.307Z,1527872995.307 [CBIT](ERROR): Data Fault in component: NAL9602
2018-06-01T17:09:55.614Z,1527872995.614 [NAL9602](INFO): Powering down
2018-06-01T17:09:56.598Z,1527872996.598 [CBIT](INFO): Clearing failed state for component NAL9602
2018-06-01T17:09:56.598Z,1527872996.598 [NAL9602] No Fault, FailCount= 4
2018-06-01T17:10:26.019Z,1527873026.019 [NAL9602](INFO): Powering up NAL9602
2018-06-01T17:10:36.434Z,1527873036.434 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:10:36.810Z,1527873036.810 [NAL9602](INFO): NAL9602 initialized
2018-06-01T17:10:37.231Z,1527873037.231 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:11:07.173Z,1527873067.173 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T17:11:07.173Z,1527873067.173 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-01T17:11:07.173Z,1527873067.173 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T17:11:07.199Z,1527873067.199 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T17:11:07.577Z,1527873067.577 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T17:11:07.750Z,1527873067.750 [Rowe_600LCM](INFO): Powering down
2018-06-01T17:11:08.557Z,1527873068.557 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-06-01T17:11:08.557Z,1527873068.557 [Rowe_600LCM] Hardware Fault, FailCount= 1
2018-06-01T17:11:08.557Z,1527873068.557 [Rowe_600LCM](ERROR): Hardware Fault
2018-06-01T17:11:09.183Z,1527873069.183 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T17:11:09.183Z,1527873069.183 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-01T17:11:09.365Z,1527873069.365 [Rowe_600LCM](INFO): Initializing
2018-06-01T17:11:09.365Z,1527873069.365 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T17:11:09.366Z,1527873069.366 [Rowe_600LCM](INFO): LCM OK
2018-06-01T17:11:09.366Z,1527873069.366 [Rowe_600LCM](INFO): Powering up
2018-06-01T17:11:13.489Z,1527873073.489 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T17:11:13.604Z,1527873073.604 [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-06-01T17:11:13.605Z,1527873073.605 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T17:11:13.605Z,1527873073.605 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T17:11:13.606Z,1527873073.606 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T17:12:01.995Z,1527873121.995 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:02.394Z,1527873122.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:02.798Z,1527873122.798 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:04.395Z,1527873124.395 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:04.796Z,1527873124.796 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:05.194Z,1527873125.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:05.594Z,1527873125.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:05.994Z,1527873125.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:06.394Z,1527873126.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:06.796Z,1527873126.796 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:07.195Z,1527873127.195 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:07.594Z,1527873127.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:07.994Z,1527873127.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:08.394Z,1527873128.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:08.795Z,1527873128.795 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:09.194Z,1527873129.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:09.594Z,1527873129.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:09.994Z,1527873129.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:10.394Z,1527873130.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:10.794Z,1527873130.794 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:11.196Z,1527873131.196 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:11.594Z,1527873131.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:11.994Z,1527873131.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:12.394Z,1527873132.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:12.794Z,1527873132.794 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:13.194Z,1527873133.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:13.594Z,1527873133.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:13.994Z,1527873133.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:14.394Z,1527873134.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:14.794Z,1527873134.794 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:15.194Z,1527873135.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:15.595Z,1527873135.595 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:15.994Z,1527873135.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:16.394Z,1527873136.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:16.796Z,1527873136.796 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:17.194Z,1527873137.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:17.594Z,1527873137.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:17.994Z,1527873137.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:18.395Z,1527873138.395 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:18.802Z,1527873138.802 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:18.921Z,1527873138.921 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:12:18.922Z,1527873138.922 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-06-01T17:12:18.924Z,1527873138.924 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:12:18.987Z,1527873138.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:12:19.195Z,1527873139.195 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:19.282Z,1527873139.282 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:12:19.282Z,1527873139.282 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-06-01T17:12:19.594Z,1527873139.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:19.652Z,1527873139.652 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:12:19.653Z,1527873139.653 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:12:19.653Z,1527873139.653 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:12:19.994Z,1527873139.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:20.394Z,1527873140.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:20.796Z,1527873140.796 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:21.194Z,1527873141.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:21.594Z,1527873141.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:21.994Z,1527873141.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:22.394Z,1527873142.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:22.798Z,1527873142.798 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:23.194Z,1527873143.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:23.594Z,1527873143.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:23.994Z,1527873143.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:24.394Z,1527873144.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:24.795Z,1527873144.795 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:25.196Z,1527873145.196 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:25.594Z,1527873145.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:25.994Z,1527873145.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:26.394Z,1527873146.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:26.794Z,1527873146.794 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:27.194Z,1527873147.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:27.594Z,1527873147.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:27.994Z,1527873147.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:28.394Z,1527873148.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:28.795Z,1527873148.795 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:28.835Z,1527873148.835 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-01T17:12:28.835Z,1527873148.835 [BPC1] Communications Fault, FailCount= 6
2018-06-01T17:12:28.835Z,1527873148.835 [BPC1](ERROR): Communications Fault
2018-06-01T17:12:28.860Z,1527873148.860 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-01T17:12:29.196Z,1527873149.196 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:29.595Z,1527873149.595 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:29.994Z,1527873149.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:30.073Z,1527873150.073 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-01T17:12:30.073Z,1527873150.073 [BPC1] No Fault, FailCount= 6
2018-06-01T17:12:30.394Z,1527873150.394 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:30.799Z,1527873150.799 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:31.194Z,1527873151.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:31.594Z,1527873151.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:31.994Z,1527873151.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:32.395Z,1527873152.395 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:32.796Z,1527873152.796 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:33.194Z,1527873153.194 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:33.594Z,1527873153.594 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:12:33.994Z,1527873153.994 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:13:09.555Z,1527873189.555 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T17:13:09.555Z,1527873189.555 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-01T17:13:09.555Z,1527873189.555 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T17:13:09.574Z,1527873189.574 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T17:13:09.957Z,1527873189.957 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T17:13:10.056Z,1527873190.056 [Rowe_600LCM](INFO): Powering down
2018-06-01T17:13:11.502Z,1527873191.502 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T17:13:11.502Z,1527873191.502 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-01T17:13:11.673Z,1527873191.673 [Rowe_600LCM](INFO): Initializing
2018-06-01T17:13:11.673Z,1527873191.673 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T17:13:11.673Z,1527873191.673 [Rowe_600LCM](INFO): LCM OK
2018-06-01T17:13:11.674Z,1527873191.674 [Rowe_600LCM](INFO): Powering up
2018-06-01T17:13:15.803Z,1527873195.803 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T17:13:15.909Z,1527873195.909 [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-06-01T17:13:15.910Z,1527873195.910 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T17:13:15.911Z,1527873195.911 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T17:13:15.911Z,1527873195.911 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T17:14:55.830Z,1527873295.830 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:14:56.628Z,1527873296.628 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:02.907Z,1527873302.907 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:03.307Z,1527873303.307 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:03.706Z,1527873303.706 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:04.106Z,1527873304.106 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:04.506Z,1527873304.506 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:04.906Z,1527873304.906 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:05.306Z,1527873305.306 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:05.706Z,1527873305.706 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:06.106Z,1527873306.106 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:06.506Z,1527873306.506 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:06.906Z,1527873306.906 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:07.307Z,1527873307.307 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:07.706Z,1527873307.706 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:08.106Z,1527873308.106 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:08.506Z,1527873308.506 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:08.906Z,1527873308.906 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:09.308Z,1527873309.308 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:09.706Z,1527873309.706 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:10.107Z,1527873310.107 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:10.506Z,1527873310.506 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:10.906Z,1527873310.906 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:13.708Z,1527873313.708 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:14.106Z,1527873314.106 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:14.506Z,1527873314.506 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:14.907Z,1527873314.907 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:15.307Z,1527873315.307 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:15.706Z,1527873315.706 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2018-06-01T17:15:19.753Z,1527873319.753 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:15:19.753Z,1527873319.753 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-06-01T17:15:19.753Z,1527873319.753 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:15:19.768Z,1527873319.768 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:15:20.181Z,1527873320.181 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:15:20.181Z,1527873320.181 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-06-01T17:15:20.564Z,1527873320.564 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:15:20.565Z,1527873320.565 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:15:20.565Z,1527873320.565 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:16:49.889Z,1527873409.889 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T17:16:49.889Z,1527873409.889 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-01T17:16:49.889Z,1527873409.889 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T17:16:49.915Z,1527873409.915 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T17:16:50.293Z,1527873410.293 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T17:16:50.388Z,1527873410.388 [Rowe_600LCM](INFO): Powering down
2018-06-01T17:16:51.821Z,1527873411.821 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T17:16:51.821Z,1527873411.821 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-01T17:16:52.005Z,1527873412.005 [Rowe_600LCM](INFO): Initializing
2018-06-01T17:16:52.005Z,1527873412.005 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T17:16:52.005Z,1527873412.005 [Rowe_600LCM](INFO): LCM OK
2018-06-01T17:16:52.006Z,1527873412.006 [Rowe_600LCM](INFO): Powering up
2018-06-01T17:16:56.153Z,1527873416.153 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T17:16:56.312Z,1527873416.312 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-06-01T17:16:56.314Z,1527873416.314 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T17:16:56.314Z,1527873416.314 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T17:16:56.315Z,1527873416.315 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T17:18:20.927Z,1527873500.927 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:18:20.928Z,1527873500.928 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-06-01T17:18:20.928Z,1527873500.928 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:18:20.963Z,1527873500.963 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:18:21.362Z,1527873501.362 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:18:21.362Z,1527873501.362 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-06-01T17:18:21.778Z,1527873501.778 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:18:21.781Z,1527873501.781 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:18:21.781Z,1527873501.781 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:18:52.091Z,1527873532.091 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T17:18:52.091Z,1527873532.091 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-01T17:18:52.092Z,1527873532.092 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T17:18:52.188Z,1527873532.188 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T17:18:52.497Z,1527873532.497 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T17:18:52.681Z,1527873532.681 [Rowe_600LCM](INFO): Powering down
2018-06-01T17:18:56.462Z,1527873536.462 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T17:18:56.462Z,1527873536.462 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-01T17:18:56.721Z,1527873536.721 [Rowe_600LCM](INFO): Initializing
2018-06-01T17:18:56.721Z,1527873536.721 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T17:18:56.721Z,1527873536.721 [Rowe_600LCM](INFO): LCM OK
2018-06-01T17:18:56.722Z,1527873536.722 [Rowe_600LCM](INFO): Powering up
2018-06-01T17:19:00.855Z,1527873540.855 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T17:19:00.976Z,1527873540.976 [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-06-01T17:19:00.977Z,1527873540.977 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T17:19:00.977Z,1527873540.977 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T17:19:00.978Z,1527873540.978 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T17:20:14.069Z,1527873614.069 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-01T17:20:14.070Z,1527873614.070 [BPC1] Communications Fault, FailCount= 7
2018-06-01T17:20:14.070Z,1527873614.070 [BPC1](ERROR): Communications Fault
2018-06-01T17:20:14.212Z,1527873614.212 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-01T17:20:15.282Z,1527873615.282 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-01T17:20:15.282Z,1527873615.282 [BPC1] No Fault, FailCount= 7
2018-06-01T17:20:39.627Z,1527873639.627 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-06-01T17:20:39.627Z,1527873639.627 [NAL9602] Data Fault, FailCount= 5
2018-06-01T17:20:39.627Z,1527873639.627 [NAL9602](ERROR): Data Fault
2018-06-01T17:20:39.751Z,1527873639.751 [CBIT](ERROR): Data Fault in component: NAL9602
2018-06-01T17:20:39.752Z,1527873639.752 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-06-01T17:20:40.022Z,1527873640.022 [NAL9602](INFO): Powering down
2018-06-01T17:20:40.099Z,1527873640.099 [CommandLine](FAULT): Scheduling is paused
2018-06-01T17:20:40.100Z,1527873640.100 [CBIT](INFO): Critical error at 20180601T172039
2018-06-01T17:20:40.100Z,1527873640.100 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-06-01T17:20:40.473Z,1527873640.473 [MissionManager](INFO): MissionManager is completed.
2018-06-01T17:20:40.473Z,1527873640.473 [MissionManager](INFO): Uninitializing Mission sample
2018-06-01T17:20:40.473Z,1527873640.473 [sample] Stopped
2018-06-01T17:20:40.473Z,1527873640.473 [sample](DEBUG): Aggregate::uninitialize sample
2018-06-01T17:20:40.473Z,1527873640.473 [sample:A.Pitch] Stopped
2018-06-01T17:20:40.473Z,1527873640.473 [sample:B.SetSpeed] Stopped
2018-06-01T17:20:40.473Z,1527873640.473 [sample:B.SetSpeed](DEBUG): Uninitialize.
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleAtDepth] Stopped
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleAtDepth](DEBUG): Aggregate::uninitialize sample:SampleAtDepth
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleAtDepth:B.Pitch] Stopped
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleAtDepth:SampleWrapper] Stopped
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleAtDepth:SampleWrapper:SampleESP] Stopped
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleESP
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP] Stopped
2018-06-01T17:20:40.474Z,1527873640.474 [sample:SampleRepeater] Stopped
2018-06-01T17:20:40.475Z,1527873640.475 [sample:SampleRepeater](DEBUG): Aggregate::uninitialize sample:SampleRepeater
2018-06-01T17:20:40.475Z,1527873640.475 [sample:SampleRepeater:Sample] Stopped
2018-06-01T17:20:40.475Z,1527873640.475 [sample:SampleRepeater:Sample](DEBUG): Aggregate::uninitialize sample:SampleRepeater:Sample
2018-06-01T17:20:40.872Z,1527873640.872 [MissionManager](IMPORTANT): Started mission Default
2018-06-01T17:20:40.873Z,1527873640.873 [Default] Running Loop=1
2018-06-01T17:20:40.873Z,1527873640.873 [Default](DEBUG): Aggregate::initialize Default
2018-06-01T17:20:40.873Z,1527873640.873 [Default:B.GoToSurface] Running Loop=1
2018-06-01T17:20:40.873Z,1527873640.873 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-06-01T17:20:40.873Z,1527873640.873 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-06-01T17:20:40.874Z,1527873640.874 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-06-01T17:20:40.874Z,1527873640.874 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-06-01T17:20:40.874Z,1527873640.874 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-06-01T17:20:40.875Z,1527873640.875 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-06-01T17:20:40.875Z,1527873640.875 [Default:A.Wait] Running Loop=1
2018-06-01T17:20:40.875Z,1527873640.875 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-06-01T17:20:54.103Z,1527873654.103 [Default:A.Wait](INFO): Done Waiting.
2018-06-01T17:20:54.103Z,1527873654.103 [Default:A.Wait] Stopped
2018-06-01T17:20:54.103Z,1527873654.103 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-06-01T17:20:54.499Z,1527873654.499 [Default:CheckIn] Running Loop=1
2018-06-01T17:20:54.499Z,1527873654.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-01T17:20:54.500Z,1527873654.500 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-01T17:20:57.061Z,1527873657.061 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T17:20:57.061Z,1527873657.061 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-01T17:20:57.061Z,1527873657.061 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T17:20:57.465Z,1527873657.465 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T17:20:57.654Z,1527873657.654 [Rowe_600LCM](INFO): Powering down
2018-06-01T17:20:59.052Z,1527873659.052 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T17:20:59.052Z,1527873659.052 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-01T17:21:21.911Z,1527873681.911 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:21:21.911Z,1527873681.911 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-06-01T17:21:21.912Z,1527873681.912 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:21:21.963Z,1527873681.963 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:21:22.435Z,1527873682.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:21:22.435Z,1527873682.435 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-06-01T17:21:22.719Z,1527873682.719 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:21:22.719Z,1527873682.719 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:21:22.720Z,1527873682.720 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:22:39.659Z,1527873759.659 [CBIT](INFO): Clearing failed state for component NAL9602
2018-06-01T17:22:39.659Z,1527873759.659 [NAL9602] No Fault, FailCount= 5
2018-06-01T17:22:40.006Z,1527873760.006 [NAL9602](INFO): Powering up NAL9602
2018-06-01T17:22:50.806Z,1527873770.806 [NAL9602](INFO): NAL9602 initialized
2018-06-01T17:24:22.894Z,1527873862.894 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:24:22.894Z,1527873862.894 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-06-01T17:24:22.894Z,1527873862.894 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:24:22.926Z,1527873862.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:24:23.290Z,1527873863.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:24:23.290Z,1527873863.290 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-06-01T17:24:23.686Z,1527873863.686 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:24:23.686Z,1527873863.686 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:24:23.687Z,1527873863.687 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:25:54.611Z,1527873954.611 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-01T17:20:54.5Z
2018-06-01T17:25:54.611Z,1527873954.611 [Default:CheckIn:Read_GPS] Stopped
2018-06-01T17:25:54.612Z,1527873954.612 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-01T17:25:59.982Z,1527873959.982 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-06-01T17:26:01.150Z,1527873961.150 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20180601T162653/Courier0004.lzma
2018-06-01T17:26:01.680Z,1527873961.680 [DataOverHttps](INFO): Moved sent file to Logs/20180601T162653/Courier0004.lzma.bak
2018-06-01T17:26:01.680Z,1527873961.680 [DataOverHttps](INFO): SBD MOMSN=8303163
2018-06-01T17:26:14.300Z,1527873974.300 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T162653/Courier0007.lzma
2018-06-01T17:26:15.068Z,1527873975.068 [DataOverHttps](INFO): Moved sent file to Logs/20180601T162653/Courier0007.lzma.bak
2018-06-01T17:26:15.068Z,1527873975.068 [DataOverHttps](INFO): SBD MOMSN=8303166
2018-06-01T17:26:25.182Z,1527873985.182 [DataOverHttps](INFO): Sending 1673 bytes from file Logs/20180601T162653/Express0005.lzma
2018-06-01T17:26:25.978Z,1527873985.978 [DataOverHttps](INFO): Moved sent file to Logs/20180601T162653/Express0005.lzma.bak
2018-06-01T17:26:25.978Z,1527873985.978 [DataOverHttps](INFO): SBD MOMSN=8303168
2018-06-01T17:26:35.295Z,1527873995.295 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T162653/Express0008.lzma
2018-06-01T17:26:36.096Z,1527873996.096 [DataOverHttps](INFO): Moved sent file to Logs/20180601T162653/Express0008.lzma.bak
2018-06-01T17:26:36.096Z,1527873996.096 [DataOverHttps](INFO): SBD MOMSN=8303245
2018-06-01T17:26:37.342Z,1527873997.342 [Default:CheckIn:Read_Iridium] Stopped
2018-06-01T17:26:37.342Z,1527873997.342 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-01T17:26:37.342Z,1527873997.342 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-01T17:27:23.829Z,1527874043.829 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T17:27:23.829Z,1527874043.829 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-06-01T17:27:23.829Z,1527874043.829 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T17:27:23.851Z,1527874043.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T17:27:24.235Z,1527874044.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T17:27:24.235Z,1527874044.235 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2018-06-01T17:27:24.623Z,1527874044.623 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T17:27:24.624Z,1527874044.624 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T17:27:24.624Z,1527874044.624 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T17:27:57.342Z,1527874077.342 [CommandLine](IMPORTANT): got command restart application
2018-06-01T17:27:57.600Z,1527874077.600 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-01T17:27:57.600Z,1527874077.600 [BPC1] Communications Fault, FailCount= 8
2018-06-01T17:27:57.600Z,1527874077.600 [BPC1](ERROR): Communications Fault
2018-06-01T17:27:57.648Z,1527874077.648 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-01T17:27:58.352Z,1527874078.352 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T17:27:58.352Z,1527874078.352 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:58.517Z,1527874078.517 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-06-01T17:27:58.517Z,1527874078.517 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:58.518Z,1527874078.518 [CommandLine](INFO): Join timeout helper Thread ID is 1938
2018-06-01T17:27:58.521Z,1527874078.521 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-06-01T17:27:58.521Z,1527874078.521 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:58.521Z,1527874078.521 [NavChartDb](INFO): Join timeout helper Thread ID is 1939
2018-06-01T17:27:58.769Z,1527874078.769 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T17:27:58.769Z,1527874078.769 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:58.786Z,1527874078.786 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-06-01T17:27:58.786Z,1527874078.786 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:58.787Z,1527874078.787 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1940
2018-06-01T17:27:58.849Z,1527874078.849 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T17:27:58.849Z,1527874078.849 [WetLabsBB2FL](INFO): Powering down
2018-06-01T17:27:58.850Z,1527874078.850 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:58.863Z,1527874078.863 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-01T17:27:58.863Z,1527874078.863 [BPC1] No Fault, FailCount= 8
2018-06-01T17:27:58.865Z,1527874078.865 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-06-01T17:27:58.865Z,1527874078.865 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:58.865Z,1527874078.865 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1941
2018-06-01T17:27:59.073Z,1527874079.073 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T17:27:59.159Z,1527874079.159 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-06-01T17:27:59.159Z,1527874079.159 [CTD_Seabird](INFO): Powering down
2018-06-01T17:27:59.160Z,1527874079.160 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.177Z,1527874079.177 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-06-01T17:27:59.177Z,1527874079.177 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.178Z,1527874079.178 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1943
2018-06-01T17:27:59.229Z,1527874079.229 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T17:27:59.229Z,1527874079.229 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T17:27:59.388Z,1527874079.388 [Rowe_600LCM](INFO): Powering down
2018-06-01T17:27:59.390Z,1527874079.390 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.398Z,1527874079.398 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-06-01T17:27:59.398Z,1527874079.398 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.399Z,1527874079.399 [Radio_Surface](INFO): Join timeout helper Thread ID is 1945
2018-06-01T17:27:59.457Z,1527874079.457 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T17:27:59.458Z,1527874079.458 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.478Z,1527874079.478 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-06-01T17:27:59.478Z,1527874079.478 [logger ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.478Z,1527874079.478 [logger](INFO): Join timeout helper Thread ID is 1946
2018-06-01T17:27:59.521Z,1527874079.521 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T17:27:59.521Z,1527874079.521 [logger ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.537Z,1527874079.537 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-06-01T17:27:59.538Z,1527874079.538 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.538Z,1527874079.538 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-06-01T17:27:59.538Z,1527874079.538 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.538Z,1527874079.538 [controlThread](INFO): Join timeout helper Thread ID is 1947
2018-06-01T17:27:59.565Z,1527874079.565 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T17:27:59.565Z,1527874079.565 [controlThread](DEBUG): Uninitializing ControlThread
2018-06-01T17:27:59.566Z,1527874079.566 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-06-01T17:27:59.686Z,1527874079.686 [NAL9602](INFO): Powering down
2018-06-01T17:27:59.687Z,1527874079.687 [PNI_TCM](INFO): Powering down
2018-06-01T17:27:59.773Z,1527874079.773 [Aanderaa_O2](INFO): Powering down
2018-06-01T17:27:59.777Z,1527874079.777 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-06-01T17:27:59.778Z,1527874079.778 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-06-01T17:27:59.779Z,1527874079.779 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-06-01T17:27:59.779Z,1527874079.779 [MissionManager](INFO): Uninitializing Mission Default
2018-06-01T17:27:59.779Z,1527874079.779 [Default] Stopped
2018-06-01T17:27:59.780Z,1527874079.780 [Default](DEBUG): Aggregate::uninitialize Default
2018-06-01T17:27:59.780Z,1527874079.780 [Default:B.GoToSurface] Stopped
2018-06-01T17:27:59.780Z,1527874079.780 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-06-01T17:27:59.780Z,1527874079.780 [Default:CheckIn] Stopped
2018-06-01T17:27:59.780Z,1527874079.780 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-01T17:27:59.780Z,1527874079.780 [Default:CheckIn:C.Wait] Stopped
2018-06-01T17:27:59.780Z,1527874079.780 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-01T17:27:59.784Z,1527874079.784 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-06-01T17:27:59.785Z,1527874079.785 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-06-01T17:27:59.785Z,1527874079.785 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-06-01T17:27:59.785Z,1527874079.785 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-06-01T17:27:59.786Z,1527874079.786 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-06-01T17:27:59.786Z,1527874079.786 [BuoyancyServo](INFO): Powering down
2018-06-01T17:27:59.801Z,1527874079.801 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-06-01T17:27:59.801Z,1527874079.801 [ElevatorServo](INFO): Powering down
2018-06-01T17:27:59.802Z,1527874079.802 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-06-01T17:27:59.802Z,1527874079.802 [MassServo](INFO): Powering down
2018-06-01T17:27:59.803Z,1527874079.803 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-06-01T17:27:59.803Z,1527874079.803 [RudderServo](INFO): Powering down
2018-06-01T17:27:59.804Z,1527874079.804 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-06-01T17:27:59.804Z,1527874079.804 [ThrusterServo](INFO): Powering down
2018-06-01T17:27:59.806Z,1527874079.806 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-06-01T17:27:59.806Z,1527874079.806 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-06-01T17:27:59.806Z,1527874079.806 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-06-01T17:27:59.807Z,1527874079.807 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.906Z,1527874079.906 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.912Z,1527874079.912 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.954Z,1527874079.954 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:27:59.961Z,1527874079.961 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:28:00.015Z,1527874080.015 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-06-01T17:28:00.075Z,1527874080.075 [logger ThreadHandler](INFO): Thread cancelled.