2018-06-04T14:37:10.022Z,1528123030.022 [Supervisor](DEBUG): Initializing supervisor.
2018-06-04T14:37:10.024Z,1528123030.024 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-06-04T14:37:10.025Z,1528123030.025 [SyncHandler](INFO): Protected caller Thread ID is 3168
2018-06-04T14:37:10.025Z,1528123030.025 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-06-04T14:37:10.026Z,1528123030.026 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-06-04T14:37:10.027Z,1528123030.027 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3169
2018-06-04T14:37:10.029Z,1528123030.029 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-06-04T14:37:10.041Z,1528123030.041 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-06-04T14:37:10.042Z,1528123030.042 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-06-04T14:37:10.042Z,1528123030.042 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3170
2018-06-04T14:37:10.043Z,1528123030.043 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-06-04T14:37:10.044Z,1528123030.044 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-06-04T14:37:10.044Z,1528123030.044 [logger ThreadHandler](INFO): Protected caller Thread ID is 3171
2018-06-04T14:37:10.046Z,1528123030.046 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-06-04T14:37:10.047Z,1528123030.047 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-06-04T14:37:10.053Z,1528123030.053 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-06-04T14:37:10.209Z,1528123030.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-06-04T14:37:10.209Z,1528123030.209 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-06-04T14:37:10.313Z,1528123030.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-06-04T14:37:10.314Z,1528123030.314 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-06-04T14:37:10.524Z,1528123030.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-06-04T14:37:10.524Z,1528123030.524 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-06-04T14:37:10.853Z,1528123030.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-06-04T14:37:10.854Z,1528123030.854 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-06-04T14:37:11.205Z,1528123031.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-06-04T14:37:11.206Z,1528123031.206 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-06-04T14:37:11.681Z,1528123031.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-06-04T14:37:11.682Z,1528123031.682 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-06-04T14:37:12.083Z,1528123032.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-06-04T14:37:12.084Z,1528123032.084 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-06-04T14:37:12.562Z,1528123032.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-06-04T14:37:12.562Z,1528123032.562 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-06-04T14:37:12.645Z,1528123032.645 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-06-04T14:37:12.955Z,1528123032.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-06-04T14:37:12.955Z,1528123032.955 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-06-04T14:37:13.104Z,1528123033.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-06-04T14:37:13.105Z,1528123033.105 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-06-04T14:37:13.318Z,1528123033.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-06-04T14:37:13.319Z,1528123033.319 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-06-04T14:37:13.541Z,1528123033.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-06-04T14:37:13.541Z,1528123033.541 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-06-04T14:37:13.642Z,1528123033.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-06-04T14:37:13.642Z,1528123033.642 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-06-04T14:37:13.787Z,1528123033.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-06-04T14:37:13.789Z,1528123033.789 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-06-04T14:37:13.791Z,1528123033.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-06-04T14:37:14.027Z,1528123034.027 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-06-04T14:37:14.028Z,1528123034.028 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-06-04T14:37:14.137Z,1528123034.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-06-04T14:37:14.285Z,1528123034.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-06-04T14:37:14.381Z,1528123034.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-06-04T14:37:14.469Z,1528123034.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-06-04T14:37:14.609Z,1528123034.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-06-04T14:37:14.795Z,1528123034.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-06-04T14:37:14.920Z,1528123034.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-06-04T14:37:15.429Z,1528123035.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-06-04T14:37:15.701Z,1528123035.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-06-04T14:37:15.798Z,1528123035.798 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-06-04T14:37:15.799Z,1528123035.799 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-06-04T14:37:15.803Z,1528123035.803 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-06-04T14:37:15.903Z,1528123035.903 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-06-04T14:37:15.903Z,1528123035.903 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-06-04T14:37:15.921Z,1528123035.921 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-06-04T14:37:15.922Z,1528123035.922 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-06-04T14:37:15.968Z,1528123035.968 [DepthRateCalculator] Loaded
2018-06-04T14:37:15.968Z,1528123035.968 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-06-04T14:37:15.974Z,1528123035.974 [PitchRateCalculator] Loaded
2018-06-04T14:37:15.974Z,1528123035.974 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-06-04T14:37:15.990Z,1528123035.990 [SpeedCalculator] Loaded
2018-06-04T14:37:15.991Z,1528123035.991 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-06-04T14:37:16.012Z,1528123036.012 [TempGradientCalculator] Loaded
2018-06-04T14:37:16.012Z,1528123036.012 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-06-04T14:37:16.028Z,1528123036.028 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-06-04T14:37:16.028Z,1528123036.028 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-06-04T14:37:16.033Z,1528123036.033 [YawRateCalculator] Loaded
2018-06-04T14:37:16.034Z,1528123036.034 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-06-04T14:37:16.073Z,1528123036.073 [ElevatorOffsetCalculator] Loaded
2018-06-04T14:37:16.074Z,1528123036.074 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-06-04T14:37:16.074Z,1528123036.074 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-06-04T14:37:16.075Z,1528123036.075 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-06-04T14:37:16.099Z,1528123036.099 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-06-04T14:37:16.099Z,1528123036.099 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-06-04T14:37:16.367Z,1528123036.367 [AcousticModem_Benthos_ATM900] Loaded
2018-06-04T14:37:16.368Z,1528123036.368 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-06-04T14:37:16.443Z,1528123036.443 [DataOverHttps] Loaded
2018-06-04T14:37:16.443Z,1528123036.443 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-06-04T14:37:16.457Z,1528123036.457 [Depth_Keller] Loaded
2018-06-04T14:37:16.457Z,1528123036.457 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-06-04T14:37:16.462Z,1528123036.462 [DropWeight] Loaded
2018-06-04T14:37:16.462Z,1528123036.462 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-06-04T14:37:16.560Z,1528123036.560 [NAL9602] Loaded
2018-06-04T14:37:16.560Z,1528123036.560 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-06-04T14:37:16.576Z,1528123036.576 [Onboard] Loaded
2018-06-04T14:37:16.576Z,1528123036.576 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-06-04T14:37:16.586Z,1528123036.586 [Radio_Surface] Loaded
2018-06-04T14:37:16.587Z,1528123036.587 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-06-04T14:37:16.588Z,1528123036.588 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B74E0
2018-06-04T14:37:16.588Z,1528123036.588 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3250
2018-06-04T14:37:16.635Z,1528123036.635 [PNI_TCM] Loaded
2018-06-04T14:37:16.635Z,1528123036.635 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-06-04T14:37:16.696Z,1528123036.696 [Rowe_600LCM] Loaded
2018-06-04T14:37:16.697Z,1528123036.697 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-06-04T14:37:16.698Z,1528123036.698 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407E74E0
2018-06-04T14:37:16.698Z,1528123036.698 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 3251
2018-06-04T14:37:18.469Z,1528123038.469 [BPC1] Loaded
2018-06-04T14:37:18.469Z,1528123038.469 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-06-04T14:37:18.470Z,1528123038.470 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-06-04T14:37:18.470Z,1528123038.470 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-06-04T14:37:18.597Z,1528123038.597 [SBIT](DEBUG): Construct Startup Built In Test.
2018-06-04T14:37:18.621Z,1528123038.621 [SBIT] Loaded
2018-06-04T14:37:18.621Z,1528123038.621 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-06-04T14:37:18.622Z,1528123038.622 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-06-04T14:37:18.633Z,1528123038.633 [IBIT] Loaded
2018-06-04T14:37:18.634Z,1528123038.634 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-06-04T14:37:18.637Z,1528123038.637 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-06-04T14:37:18.786Z,1528123038.786 [CBIT] Loaded
2018-06-04T14:37:18.787Z,1528123038.787 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-06-04T14:37:18.787Z,1528123038.787 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-06-04T14:37:18.788Z,1528123038.788 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-06-04T14:37:18.924Z,1528123038.924 [Aanderaa_O2] Loaded
2018-06-04T14:37:18.925Z,1528123038.925 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-06-04T14:37:18.972Z,1528123038.972 [CTD_Seabird] Loaded
2018-06-04T14:37:18.973Z,1528123038.973 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-06-04T14:37:18.974Z,1528123038.974 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408A84E0
2018-06-04T14:37:18.974Z,1528123038.974 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3252
2018-06-04T14:37:18.991Z,1528123038.991 [ESPComponent] Loaded
2018-06-04T14:37:18.991Z,1528123038.991 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-06-04T14:37:19.005Z,1528123039.005 [PAR_Licor] Loaded
2018-06-04T14:37:19.006Z,1528123039.006 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-06-04T14:37:19.055Z,1528123039.055 [WetLabsBB2FL] Loaded
2018-06-04T14:37:19.055Z,1528123039.055 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-06-04T14:37:19.056Z,1528123039.056 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408D84E0
2018-06-04T14:37:19.056Z,1528123039.056 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3253
2018-06-04T14:37:19.057Z,1528123039.057 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-06-04T14:37:19.058Z,1528123039.058 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-06-04T14:37:19.472Z,1528123039.472 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-06-04T14:37:19.473Z,1528123039.473 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-06-04T14:37:19.538Z,1528123039.538 [VerticalControl](DEBUG): Construct VerticalControl.
2018-06-04T14:37:19.633Z,1528123039.633 [VerticalControl] Loaded
2018-06-04T14:37:19.633Z,1528123039.633 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-06-04T14:37:19.634Z,1528123039.634 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-06-04T14:37:19.696Z,1528123039.696 [HorizontalControl] Loaded
2018-06-04T14:37:19.696Z,1528123039.696 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-06-04T14:37:19.697Z,1528123039.697 [SpeedControl](DEBUG): Construct SpeedControl.
2018-06-04T14:37:19.702Z,1528123039.702 [SpeedControl] Loaded
2018-06-04T14:37:19.702Z,1528123039.702 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-06-04T14:37:19.703Z,1528123039.703 [LoopControl](DEBUG): Construct LoopControl.
2018-06-04T14:37:19.704Z,1528123039.704 [LoopControl] Loaded
2018-06-04T14:37:19.704Z,1528123039.704 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-06-04T14:37:19.704Z,1528123039.704 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-06-04T14:37:19.705Z,1528123039.705 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-06-04T14:37:19.785Z,1528123039.785 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-06-04T14:37:19.785Z,1528123039.785 [StratificationFrontDetector](DEBUG): (re)initializing
2018-06-04T14:37:19.785Z,1528123039.785 [StratificationFrontDetector] Loaded
2018-06-04T14:37:19.786Z,1528123039.786 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-06-04T14:37:19.786Z,1528123039.786 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-06-04T14:37:19.787Z,1528123039.787 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-06-04T14:37:19.884Z,1528123039.884 [BuoyancyServo] Loaded
2018-06-04T14:37:19.884Z,1528123039.884 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-06-04T14:37:19.896Z,1528123039.896 [ElevatorServo] Loaded
2018-06-04T14:37:19.896Z,1528123039.896 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-06-04T14:37:19.907Z,1528123039.907 [MassServo] Loaded
2018-06-04T14:37:19.907Z,1528123039.907 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-06-04T14:37:19.919Z,1528123039.919 [RudderServo] Loaded
2018-06-04T14:37:19.919Z,1528123039.919 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-06-04T14:37:19.930Z,1528123039.930 [ThrusterServo] Loaded
2018-06-04T14:37:19.930Z,1528123039.930 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-06-04T14:37:19.931Z,1528123039.931 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-06-04T14:37:19.931Z,1528123039.931 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-06-04T14:37:20.081Z,1528123040.081 [DeadReckonUsingSpeedCalculator] Loaded
2018-06-04T14:37:20.081Z,1528123040.081 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-06-04T14:37:20.097Z,1528123040.097 [NavChart] Loaded
2018-06-04T14:37:20.098Z,1528123040.098 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-06-04T14:37:20.104Z,1528123040.104 [UniversalFixResidualReporter] Loaded
2018-06-04T14:37:20.105Z,1528123040.105 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-06-04T14:37:20.105Z,1528123040.105 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-06-04T14:37:20.109Z,1528123040.109 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-06-04T14:37:20.110Z,1528123040.110 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-06-04T14:37:20.116Z,1528123040.116 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-06-04T14:37:20.117Z,1528123040.117 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0
2018-06-04T14:37:20.118Z,1528123040.118 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3254
2018-06-04T14:37:20.122Z,1528123040.122 [Supervisor](INFO): Main Thread ID is 799
2018-06-04T14:37:20.122Z,1528123040.122 [Supervisor](DEBUG): Running supervisor.
2018-06-04T14:37:20.123Z,1528123040.123 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3255
2018-06-04T14:37:20.126Z,1528123040.126 [controlThread ThreadHandler](INFO): Handler Thread ID is 3256
2018-06-04T14:37:20.127Z,1528123040.127 [controlThread](DEBUG): Initializing ControlThread
2018-06-04T14:37:20.128Z,1528123040.128 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-06-04T14:37:20.128Z,1528123040.128 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-06-04T14:37:20.128Z,1528123040.128 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-06-04T14:37:20.129Z,1528123040.129 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-06-04T14:37:20.129Z,1528123040.129 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-06-04T14:37:20.130Z,1528123040.130 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-06-04T14:37:20.131Z,1528123040.131 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-06-04T14:37:20.135Z,1528123040.135 [SBIT](INFO): Initialize SBIT Component.
2018-06-04T14:37:20.136Z,1528123040.136 [SBIT](IMPORTANT): git: 2018-06-03
2018-06-04T14:37:20.136Z,1528123040.136 [SBIT](INFO): git hash: e89e4219fcae2edd415aee450018ea5d8b44159b
2018-06-04T14:37:20.136Z,1528123040.136 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-06-04T14:37:20.138Z,1528123040.138 [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-04T14:37:20.139Z,1528123040.139 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-06-04T14:37:20.139Z,1528123040.139 [IBIT](INFO): Initialize IBIT Component.
2018-06-04T14:37:20.140Z,1528123040.140 [CBIT](DEBUG): Initialize CBIT Component.
2018-06-04T14:37:20.140Z,1528123040.140 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-06-04T14:37:20.141Z,1528123040.141 [logger ThreadHandler](INFO): Handler Thread ID is 3257
2018-06-04T14:37:20.161Z,1528123040.161 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3258
2018-06-04T14:37:20.167Z,1528123040.167 [Radio_Surface](INFO): Powering up
2018-06-04T14:37:20.173Z,1528123040.173 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 3259
2018-06-04T14:37:20.174Z,1528123040.174 [Rowe_600LCM](INFO): Initializing
2018-06-04T14:37:20.174Z,1528123040.174 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T14:37:20.183Z,1528123040.183 [Rowe_600LCM](INFO): LCM OK
2018-06-04T14:37:20.183Z,1528123040.183 [Rowe_600LCM](INFO): Powering up
2018-06-04T14:37:20.189Z,1528123040.189 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3260
2018-06-04T14:37:20.190Z,1528123040.190 [CTD_Seabird](INFO): Initializing
2018-06-04T14:37:20.191Z,1528123040.191 [CTD_Seabird](INFO): Checking LCM
2018-06-04T14:37:20.191Z,1528123040.191 [CTD_Seabird](INFO): LCM OK
2018-06-04T14:37:20.191Z,1528123040.191 [CTD_Seabird](INFO): Powering up
2018-06-04T14:37:20.201Z,1528123040.201 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3261
2018-06-04T14:37:20.202Z,1528123040.202 [WetLabsBB2FL](INFO): Powering down
2018-06-04T14:37:20.229Z,1528123040.229 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3262
2018-06-04T14:37:20.237Z,1528123040.237 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-06-04T14:37:20.237Z,1528123040.237 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-06-04T14:37:20.237Z,1528123040.237 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-06-04T14:37:20.237Z,1528123040.237 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-06-04T14:37:20.238Z,1528123040.238 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-06-04T14:37:20.238Z,1528123040.238 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-06-04T14:37:20.238Z,1528123040.238 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-06-04T14:37:20.238Z,1528123040.238 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-06-04T14:37:20.238Z,1528123040.238 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-06-04T14:37:20.239Z,1528123040.239 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-06-04T14:37:20.239Z,1528123040.239 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-06-04T14:37:20.239Z,1528123040.239 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-06-04T14:37:20.239Z,1528123040.239 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-06-04T14:37:20.239Z,1528123040.239 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-06-04T14:37:20.240Z,1528123040.240 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-06-04T14:37:20.240Z,1528123040.240 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-06-04T14:37:20.257Z,1528123040.257 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-06-04T14:37:20.259Z,1528123040.259 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-06-04T14:37:20.260Z,1528123040.260 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-06-04T14:37:20.260Z,1528123040.260 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-06-04T14:37:20.262Z,1528123040.262 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-04T14:37:20.263Z,1528123040.263 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-04T14:37:20.264Z,1528123040.264 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-04T14:37:20.264Z,1528123040.264 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-06-04T14:37:20.264Z,1528123040.264 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-06-04T14:37:20.266Z,1528123040.266 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-06-04T14:37:20.288Z,1528123040.288 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-06-04T14:37:20.315Z,1528123040.315 [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-04T14:37:20.316Z,1528123040.316 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-06-04T14:37:20.406Z,1528123040.406 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-06-04T14:37:20.407Z,1528123040.407 [Default:A.Wait](DEBUG): Construct Wait.
2018-06-04T14:37:20.437Z,1528123040.437 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-06-04T14:37:20.456Z,1528123040.456 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-06-04T14:37:20.458Z,1528123040.458 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-06-04T14:37:20.480Z,1528123040.480 [Default:E.Execute](DEBUG): Construct Execute.
2018-06-04T14:37:20.484Z,1528123040.484 [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-04T14:37:20.495Z,1528123040.495 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,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-04T14:37:20.520Z,1528123040.520 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-06-04T14:37:20.526Z,1528123040.526 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-06-04T14:37:20.551Z,1528123040.551 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-06-04T14:37:20.895Z,1528123040.895 [ESPComponent](INFO): powering down ESP
2018-06-04T14:37:21.505Z,1528123041.505 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-04T14:37:21.619Z,1528123041.619 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-06-04T14:37:21.625Z,1528123041.625 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-06-04T14:37:21.644Z,1528123041.644 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-06-04T14:37:21.653Z,1528123041.653 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-06-04T14:37:21.675Z,1528123041.675 [MassServo](DEBUG): Initializing EZServoServo.
2018-06-04T14:37:21.681Z,1528123041.681 [MassServo](DEBUG): Initializing MassServo.
2018-06-04T14:37:21.692Z,1528123041.692 [RudderServo](DEBUG): Initializing EZServoServo.
2018-06-04T14:37:21.697Z,1528123041.697 [RudderServo](DEBUG): Initializing RudderServo.
2018-06-04T14:37:21.719Z,1528123041.719 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-06-04T14:37:21.725Z,1528123041.725 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-06-04T14:37:21.971Z,1528123041.971 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-04T14:37:22.256Z,1528123042.256 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-04T14:37:22.593Z,1528123042.593 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-06-04T14:37:22.593Z,1528123042.593 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-06-04T14:37:22.593Z,1528123042.593 [BuoyancyServo] Communications Fault, FailCount= 1
2018-06-04T14:37:22.593Z,1528123042.593 [BuoyancyServo](ERROR): Communications Fault
2018-06-04T14:37:22.801Z,1528123042.801 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-06-04T14:37:22.867Z,1528123042.867 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-04T14:37:22.889Z,1528123042.889 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-06-04T14:37:22.889Z,1528123042.889 [BuoyancyServo](INFO): Powering down
2018-06-04T14:37:23.298Z,1528123043.298 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-04T14:37:23.662Z,1528123043.662 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-04T14:37:24.157Z,1528123044.157 [Aanderaa_O2](INFO): Powering down
2018-06-04T14:37:24.227Z,1528123044.227 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-04T14:37:24.509Z,1528123044.509 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-06-04T14:37:24.584Z,1528123044.584 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T14:37:24.781Z,1528123044.781 [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-04T14:37:24.800Z,1528123044.800 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T14:37:24.800Z,1528123044.800 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T14:37:24.801Z,1528123044.801 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T14:37:24.963Z,1528123044.963 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-06-04T14:37:24.963Z,1528123044.963 [BuoyancyServo] No Fault, FailCount= 1
2018-06-04T14:37:25.316Z,1528123045.316 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-06-04T14:37:25.316Z,1528123045.316 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-06-04T14:37:25.870Z,1528123045.870 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-06-04T14:37:26.255Z,1528123046.255 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-06-04T14:37:26.255Z,1528123046.255 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-06-04T14:37:26.255Z,1528123046.255 [BuoyancyServo] Communications Fault, FailCount= 2
2018-06-04T14:37:26.255Z,1528123046.255 [BuoyancyServo](ERROR): Communications Fault
2018-06-04T14:37:26.434Z,1528123046.434 [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-04T14:37:26.436Z,1528123046.436 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-06-04T14:37:26.591Z,1528123046.591 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-06-04T14:37:26.969Z,1528123046.969 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-06-04T14:37:26.971Z,1528123046.971 [BuoyancyServo](INFO): Powering down
2018-06-04T14:37:27.178Z,1528123047.178 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-06-04T14:37:27.178Z,1528123047.178 [CTD_Seabird](INFO): Powering down
2018-06-04T14:37:27.895Z,1528123047.895 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-06-04T14:37:29.097Z,1528123049.097 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-06-04T14:37:29.254Z,1528123049.254 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-06-04T14:37:29.255Z,1528123049.255 [BuoyancyServo] No Fault, FailCount= 2
2018-06-04T14:37:29.497Z,1528123049.497 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-06-04T14:37:29.498Z,1528123049.498 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-06-04T14:37:29.499Z,1528123049.499 [AcousticModem_Benthos_ATM900](DEBUG): Apr 11 2000 14:31:51 
2018-06-04T14:37:29.660Z,1528123049.660 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-06-04T14:37:29.660Z,1528123049.660 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-06-04T14:37:29.895Z,1528123049.895 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-06-04T14:37:30.694Z,1528123050.694 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-06-04T14:37:31.095Z,1528123051.095 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-06-04T14:37:31.096Z,1528123051.096 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-06-04T14:37:31.096Z,1528123051.096 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-06-04T14:37:31.496Z,1528123051.496 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-06-04T14:37:31.894Z,1528123051.894 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-06-04T14:37:31.895Z,1528123051.895 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-06-04T14:37:32.295Z,1528123052.295 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-06-04T14:37:46.699Z,1528123066.699 [NAL9602](INFO): Powering up NAL9602
2018-06-04T14:37:57.899Z,1528123077.899 [NAL9602](INFO): NAL9602 initialized
2018-06-04T14:38:16.301Z,1528123096.301 [NAL9602](INFO): SBD MO Status=0, MOMSN=21592, MT Status=0, MTMSN=0
2018-06-04T14:38:16.301Z,1528123096.301 [NAL9602](INFO): No messages in MT queue
2018-06-04T14:38:23.594Z,1528123103.594 [SBIT](IMPORTANT): Beginning Startup BIT
2018-06-04T14:38:23.622Z,1528123103.622 [CBIT](IMPORTANT): Beginning ground fault scan
2018-06-04T14:38:34.311Z,1528123114.311 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011172
CHAN A1 (24V): -0.014256
CHAN A2 (12V): -0.004443
CHAN A3 (5V): -0.002184
CHAN B0 (3.3V): -0.001246
CHAN B1 (3.15aV): -0.001038
CHAN B2 (3.15bV): -0.001262
CHAN B3 (GND): 0.000190
OPEN: 0.004799
Full Scale Calc: 4.765 mA, -1.589 mA
2018-06-04T14:38:38.706Z,1528123118.706 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:38:39.501Z,1528123119.501 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:38:50.434Z,1528123130.434 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:38:51.229Z,1528123131.229 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:39:17.395Z,1528123157.395 [SBIT](IMPORTANT): SBIT PASSED
2018-06-04T14:39:17.470Z,1528123157.470 [CommandLine](IMPORTANT): got command configSet list
2018-06-04T14:39:17.471Z,1528123157.471 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-06-04T14:39:17.471Z,1528123157.471 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=200 milliliter;

2018-06-04T14:39:17.801Z,1528123157.801 [MissionManager](IMPORTANT): Started mission Startup
2018-06-04T14:39:17.801Z,1528123157.801 [Startup] Running Loop=1
2018-06-04T14:39:17.801Z,1528123157.801 [Startup](DEBUG): Aggregate::initialize Startup
2018-06-04T14:39:17.802Z,1528123157.802 [Startup:A.GoToSurface] Running Loop=1
2018-06-04T14:39:17.802Z,1528123157.802 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-06-04T14:39:17.802Z,1528123157.802 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-06-04T14:39:17.803Z,1528123157.803 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-06-04T14:39:17.803Z,1528123157.803 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-06-04T14:39:17.804Z,1528123157.804 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-06-04T14:39:17.804Z,1528123157.804 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-06-04T14:39:17.811Z,1528123157.811 [Startup:StartupSatComms] Running Loop=1
2018-06-04T14:39:17.811Z,1528123157.811 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-06-04T14:39:17.811Z,1528123157.811 [Startup:StartupSatComms:A] Running Loop=1
2018-06-04T14:39:18.186Z,1528123158.186 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-06-04T14:39:20.370Z,1528123160.370 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T14:39:20.370Z,1528123160.370 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-04T14:39:20.370Z,1528123160.370 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T14:39:20.623Z,1528123160.623 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T14:39:20.773Z,1528123160.773 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T14:39:20.882Z,1528123160.882 [Rowe_600LCM](INFO): Powering down
2018-06-04T14:39:22.238Z,1528123162.238 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T14:39:22.238Z,1528123162.238 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-04T14:39:22.497Z,1528123162.497 [Rowe_600LCM](INFO): Initializing
2018-06-04T14:39:22.497Z,1528123162.497 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T14:39:22.498Z,1528123162.498 [Rowe_600LCM](INFO): LCM OK
2018-06-04T14:39:22.498Z,1528123162.498 [Rowe_600LCM](INFO): Powering up
2018-06-04T14:39:26.739Z,1528123166.739 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T14:39:26.800Z,1528123166.800 [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-04T14:39:26.801Z,1528123166.801 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T14:39:26.801Z,1528123166.801 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T14:39:26.802Z,1528123166.802 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T14:39:31.775Z,1528123171.775 [NAL9602](INFO): GPS fix at 20180604T143800: (36.802779, -121.786885)
2018-06-04T14:39:31.890Z,1528123171.890 [Startup:StartupSatComms:A] Stopped
2018-06-04T14:39:31.890Z,1528123171.890 [Startup:StartupSatComms:B] Running Loop=1
2018-06-04T14:39:32.201Z,1528123172.201 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-06-04T14:39:43.888Z,1528123183.888 [NAL9602](INFO): SBD MO Status=1, MOMSN=21593, MT Status=0, MTMSN=0
2018-06-04T14:39:43.949Z,1528123183.949 [NAL9602](INFO): Sent 25 bytes from file Logs/20180603T223119/Courier0289.lzma
2018-06-04T14:39:43.949Z,1528123183.949 [NAL9602](INFO): Packets left to send: 0
2018-06-04T14:39:43.951Z,1528123183.951 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180603T223119/Courier0289.lzma.parts/0000.sbd
2018-06-04T14:39:43.951Z,1528123183.951 [NAL9602](DEBUG): Completed sending Logs/20180603T223119/Courier0289.lzma
2018-06-04T14:40:06.800Z,1528123206.800 [NAL9602](INFO): SBD MO Status=1, MOMSN=21594, MT Status=0, MTMSN=0
2018-06-04T14:40:06.857Z,1528123206.857 [NAL9602](INFO): Sent 25 bytes from file Logs/20180603T223119/Courier0292.lzma
2018-06-04T14:40:06.857Z,1528123206.857 [NAL9602](INFO): Packets left to send: 0
2018-06-04T14:40:06.859Z,1528123206.859 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180603T223119/Courier0292.lzma.parts/0000.sbd
2018-06-04T14:40:06.859Z,1528123206.859 [NAL9602](DEBUG): Completed sending Logs/20180603T223119/Courier0292.lzma
2018-06-04T14:40:22.916Z,1528123222.916 [NAL9602](INFO): SBD MO Status=2, MOMSN=21595, MT Status=2, MTMSN=0
2018-06-04T14:40:22.916Z,1528123222.916 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T14:40:32.182Z,1528123232.182 [Startup:StartupSatComms:B](INFO): Timed out from 2018-06-04T14:39:31.9Z
2018-06-04T14:40:32.182Z,1528123232.182 [Startup:StartupSatComms:B] Stopped
2018-06-04T14:40:32.182Z,1528123232.182 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-06-04T14:40:32.182Z,1528123232.182 [Startup:StartupSatComms] Stopped
2018-06-04T14:40:32.182Z,1528123232.182 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-06-04T14:40:32.183Z,1528123232.183 [Startup](INFO): Completed Startup
2018-06-04T14:40:32.184Z,1528123232.184 [MissionManager](INFO): Startup is completed.
2018-06-04T14:40:32.184Z,1528123232.184 [MissionManager](INFO): Uninitializing Mission Startup
2018-06-04T14:40:32.184Z,1528123232.184 [Startup] Stopped
2018-06-04T14:40:32.184Z,1528123232.184 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-06-04T14:40:32.184Z,1528123232.184 [Startup:A.GoToSurface] Stopped
2018-06-04T14:40:32.184Z,1528123232.184 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-06-04T14:40:32.575Z,1528123232.575 [MissionManager](IMPORTANT): Started mission Default
2018-06-04T14:40:32.575Z,1528123232.575 [Default] Running Loop=1
2018-06-04T14:40:32.575Z,1528123232.575 [Default](DEBUG): Aggregate::initialize Default
2018-06-04T14:40:32.576Z,1528123232.576 [Default:B.GoToSurface] Running Loop=1
2018-06-04T14:40:32.576Z,1528123232.576 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-06-04T14:40:32.576Z,1528123232.576 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-06-04T14:40:32.576Z,1528123232.576 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-06-04T14:40:32.577Z,1528123232.577 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-06-04T14:40:32.577Z,1528123232.577 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-06-04T14:40:32.578Z,1528123232.578 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-06-04T14:40:32.578Z,1528123232.578 [Default:A.Wait] Running Loop=1
2018-06-04T14:40:32.578Z,1528123232.578 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-06-04T14:40:38.610Z,1528123238.610 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:40:39.405Z,1528123239.405 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:40:45.818Z,1528123245.818 [Default:A.Wait](INFO): Done Waiting.
2018-06-04T14:40:45.820Z,1528123245.820 [Default:A.Wait] Stopped
2018-06-04T14:40:45.820Z,1528123245.820 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T14:40:46.176Z,1528123246.176 [Default:CheckIn] Running Loop=1
2018-06-04T14:40:46.176Z,1528123246.176 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T14:40:46.176Z,1528123246.176 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T14:40:46.565Z,1528123246.565 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-06-04T14:40:48.118Z,1528123248.118 [NAL9602](INFO): GPS fix at 20180604T143916: (36.802762, -121.786914)
2018-06-04T14:40:48.188Z,1528123248.188 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T14:40:48.193Z,1528123248.193 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T14:40:48.581Z,1528123248.581 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-06-04T14:41:19.424Z,1528123279.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=21595, MT Status=2, MTMSN=0
2018-06-04T14:41:19.424Z,1528123279.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T14:41:22.555Z,1528123282.555 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T14:41:22.555Z,1528123282.555 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-04T14:41:22.555Z,1528123282.555 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T14:41:22.704Z,1528123282.704 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T14:41:22.957Z,1528123282.957 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T14:41:23.191Z,1528123283.191 [Rowe_600LCM](INFO): Powering down
2018-06-04T14:41:26.325Z,1528123286.325 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T14:41:26.325Z,1528123286.325 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-04T14:41:26.421Z,1528123286.421 [Rowe_600LCM](INFO): Initializing
2018-06-04T14:41:26.421Z,1528123286.421 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T14:41:26.422Z,1528123286.422 [Rowe_600LCM](INFO): LCM OK
2018-06-04T14:41:26.422Z,1528123286.422 [Rowe_600LCM](INFO): Powering up
2018-06-04T14:41:27.140Z,1528123287.140 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004339
2018-06-04T14:41:30.622Z,1528123290.622 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T14:41:30.723Z,1528123290.723 [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-04T14:41:30.724Z,1528123290.724 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T14:41:30.725Z,1528123290.725 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T14:41:30.726Z,1528123290.726 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T14:41:31.674Z,1528123291.674 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180604T143709/Courier0000.lzma
2018-06-04T14:41:32.460Z,1528123292.460 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0000.lzma.bak
2018-06-04T14:41:32.460Z,1528123292.460 [DataOverHttps](INFO): SBD MOMSN=8312357
2018-06-04T14:41:41.673Z,1528123301.673 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180604T143709/Courier0004.lzma
2018-06-04T14:41:42.452Z,1528123302.452 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0004.lzma.bak
2018-06-04T14:41:42.452Z,1528123302.452 [DataOverHttps](INFO): SBD MOMSN=8312361
2018-06-04T14:41:53.194Z,1528123313.194 [DataOverHttps](INFO): Sending 1253 bytes from file Logs/20180603T223119/Express0284.lzma
2018-06-04T14:41:53.951Z,1528123313.951 [DataOverHttps](INFO): Moved sent file to Logs/20180603T223119/Express0284.lzma.bak
2018-06-04T14:41:53.951Z,1528123313.951 [DataOverHttps](INFO): SBD MOMSN=8312366
2018-06-04T14:42:00.240Z,1528123320.240 [NAL9602](INFO): Powering down
2018-06-04T14:42:06.748Z,1528123326.748 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180603T223119/Express0287.lzma
2018-06-04T14:42:07.548Z,1528123327.548 [DataOverHttps](INFO): Moved sent file to Logs/20180603T223119/Express0287.lzma.bak
2018-06-04T14:42:07.549Z,1528123327.549 [DataOverHttps](INFO): SBD MOMSN=8312418
2018-06-04T14:42:18.210Z,1528123338.210 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20180603T223119/Express0290.lzma
2018-06-04T14:42:18.966Z,1528123338.966 [DataOverHttps](INFO): Moved sent file to Logs/20180603T223119/Express0290.lzma.bak
2018-06-04T14:42:18.966Z,1528123338.966 [DataOverHttps](INFO): SBD MOMSN=8312420
2018-06-04T14:42:28.496Z,1528123348.496 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180603T223119/Express0293.lzma
2018-06-04T14:42:29.315Z,1528123349.315 [DataOverHttps](INFO): Moved sent file to Logs/20180603T223119/Express0293.lzma.bak
2018-06-04T14:42:29.316Z,1528123349.316 [DataOverHttps](INFO): SBD MOMSN=8312424
2018-06-04T14:42:38.871Z,1528123358.871 [DataOverHttps](INFO): Sending 813 bytes from file Logs/20180604T143709/Express0001.lzma
2018-06-04T14:42:39.656Z,1528123359.656 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0001.lzma.bak
2018-06-04T14:42:39.656Z,1528123359.656 [DataOverHttps](INFO): SBD MOMSN=8312426
2018-06-04T14:42:49.140Z,1528123369.140 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20180604T143709/Express0005.lzma
2018-06-04T14:42:49.941Z,1528123369.941 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0005.lzma.bak
2018-06-04T14:42:49.942Z,1528123369.942 [DataOverHttps](INFO): SBD MOMSN=8312443
2018-06-04T14:42:50.430Z,1528123370.430 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T14:42:50.430Z,1528123370.430 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T14:42:50.430Z,1528123370.430 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T14:43:26.754Z,1528123406.754 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T14:43:26.755Z,1528123406.755 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-04T14:43:26.755Z,1528123406.755 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T14:43:26.803Z,1528123406.803 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T14:43:27.157Z,1528123407.157 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T14:43:27.280Z,1528123407.280 [Rowe_600LCM](INFO): Powering down
2018-06-04T14:43:28.736Z,1528123408.736 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T14:43:28.736Z,1528123408.736 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-04T14:43:28.897Z,1528123408.897 [Rowe_600LCM](INFO): Initializing
2018-06-04T14:43:28.897Z,1528123408.897 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T14:43:28.898Z,1528123408.898 [Rowe_600LCM](INFO): LCM OK
2018-06-04T14:43:28.898Z,1528123408.898 [Rowe_600LCM](INFO): Powering up
2018-06-04T14:43:33.027Z,1528123413.027 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T14:43:33.196Z,1528123413.196 [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-04T14:43:33.196Z,1528123413.196 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T14:43:33.197Z,1528123413.197 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T14:43:33.198Z,1528123413.198 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T14:45:27.054Z,1528123527.054 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:45:27.850Z,1528123527.850 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:45:28.939Z,1528123528.939 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T14:45:28.939Z,1528123528.939 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-04T14:45:28.939Z,1528123528.939 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T14:45:29.085Z,1528123529.085 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T14:45:29.345Z,1528123529.345 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T14:45:29.568Z,1528123529.568 [Rowe_600LCM](INFO): Powering down
2018-06-04T14:45:31.092Z,1528123531.092 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T14:45:31.093Z,1528123531.093 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-04T14:45:31.185Z,1528123531.185 [Rowe_600LCM](INFO): Initializing
2018-06-04T14:45:31.186Z,1528123531.186 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T14:45:31.186Z,1528123531.186 [Rowe_600LCM](INFO): LCM OK
2018-06-04T14:45:31.186Z,1528123531.186 [Rowe_600LCM](INFO): Powering up
2018-06-04T14:45:35.319Z,1528123535.319 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T14:45:35.385Z,1528123535.385 [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-04T14:45:35.385Z,1528123535.385 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T14:45:35.386Z,1528123535.386 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T14:45:35.387Z,1528123535.387 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T14:46:05.106Z,1528123565.106 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:46:05.901Z,1528123565.901 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:47:15.206Z,1528123635.206 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:47:16.001Z,1528123636.001 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:47:31.917Z,1528123651.917 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T14:47:31.917Z,1528123651.917 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-04T14:47:31.917Z,1528123651.917 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T14:47:32.135Z,1528123652.135 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T14:47:32.136Z,1528123652.136 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-04T14:47:32.321Z,1528123652.321 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T14:47:32.420Z,1528123652.420 [Rowe_600LCM](INFO): Powering down
2018-06-04T14:47:50.902Z,1528123670.902 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T14:47:50.903Z,1528123670.903 [Default:CheckIn:C.Wait] Stopped
2018-06-04T14:47:50.903Z,1528123670.903 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T14:47:50.903Z,1528123670.903 [Default:CheckIn:D] Running Loop=1
2018-06-04T14:47:51.314Z,1528123671.314 [Default:CheckIn:D] Stopped
2018-06-04T14:47:51.314Z,1528123671.314 [Default:CheckIn:E] Running Loop=1
2018-06-04T14:47:51.706Z,1528123671.706 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.312277 min 
2018-06-04T14:47:51.708Z,1528123671.708 [Default:CheckIn:E] Stopped
2018-06-04T14:47:51.708Z,1528123671.708 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T14:47:51.708Z,1528123671.708 [Default:CheckIn] Stopped
2018-06-04T14:47:51.708Z,1528123671.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T14:47:51.709Z,1528123671.709 [Default:CheckIn](INFO): Running loop #2
2018-06-04T14:47:51.709Z,1528123671.709 [Default:CheckIn] Running Loop=2
2018-06-04T14:47:51.709Z,1528123671.709 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T14:47:51.709Z,1528123671.709 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T14:47:52.462Z,1528123672.462 [NAL9602](INFO): Powering up
2018-06-04T14:48:03.290Z,1528123683.290 [NAL9602](INFO): NAL9602 initialized
2018-06-04T14:48:10.096Z,1528123690.096 [NAL9602](INFO): SBD MO Status=2, MOMSN=21595, MT Status=2, MTMSN=0
2018-06-04T14:48:10.096Z,1528123690.096 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T14:48:30.092Z,1528123710.092 [NAL9602](INFO): SBD MO Status=0, MOMSN=21595, MT Status=0, MTMSN=0
2018-06-04T14:48:30.092Z,1528123710.092 [NAL9602](INFO): No messages in MT queue
2018-06-04T14:49:02.523Z,1528123742.523 [NAL9602](INFO): GPS fix at 20180604T144730: (36.802752, -121.786859)
2018-06-04T14:49:02.593Z,1528123742.593 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T14:49:02.593Z,1528123742.593 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T14:49:08.194Z,1528123748.194 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180604T143709/Courier0007.lzma
2018-06-04T14:49:08.980Z,1528123748.980 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0007.lzma.bak
2018-06-04T14:49:08.981Z,1528123748.981 [DataOverHttps](INFO): SBD MOMSN=8312455
2018-06-04T14:49:17.794Z,1528123757.794 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20180604T143709/Express0008.lzma
2018-06-04T14:49:18.588Z,1528123758.588 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0008.lzma.bak
2018-06-04T14:49:18.589Z,1528123758.589 [DataOverHttps](INFO): SBD MOMSN=8312458
2018-06-04T14:49:19.085Z,1528123759.085 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T14:49:19.085Z,1528123759.085 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T14:49:19.085Z,1528123759.085 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T14:49:34.759Z,1528123774.759 [NAL9602](INFO): Powering down
2018-06-04T14:52:41.670Z,1528123961.670 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-04T14:52:41.670Z,1528123961.670 [BPC1] Communications Fault, FailCount= 1
2018-06-04T14:52:41.670Z,1528123961.670 [BPC1](ERROR): Communications Fault
2018-06-04T14:52:41.696Z,1528123961.696 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-04T14:52:42.921Z,1528123962.921 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T14:52:42.921Z,1528123962.921 [BPC1] No Fault, FailCount= 1
2018-06-04T14:54:19.708Z,1528124059.708 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T14:54:19.708Z,1528124059.708 [Default:CheckIn:C.Wait] Stopped
2018-06-04T14:54:19.708Z,1528124059.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T14:54:19.708Z,1528124059.708 [Default:CheckIn:D] Running Loop=1
2018-06-04T14:54:20.124Z,1528124060.124 [Default:CheckIn:D] Stopped
2018-06-04T14:54:20.124Z,1528124060.124 [Default:CheckIn:E] Running Loop=1
2018-06-04T14:54:23.217Z,1528124063.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.792481 min 
2018-06-04T14:54:23.217Z,1528124063.217 [Default:CheckIn:E] Stopped
2018-06-04T14:54:23.217Z,1528124063.217 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T14:54:23.217Z,1528124063.217 [Default:CheckIn] Stopped
2018-06-04T14:54:23.217Z,1528124063.217 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T14:54:23.217Z,1528124063.217 [Default:CheckIn](INFO): Running loop #3
2018-06-04T14:54:23.218Z,1528124063.218 [Default:CheckIn] Running Loop=3
2018-06-04T14:54:23.218Z,1528124063.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T14:54:23.218Z,1528124063.218 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T14:54:23.682Z,1528124063.682 [NAL9602](INFO): Powering up
2018-06-04T14:54:34.881Z,1528124074.881 [NAL9602](INFO): NAL9602 initialized
2018-06-04T14:54:59.680Z,1528124099.680 [NAL9602](INFO): SBD MO Status=0, MOMSN=21596, MT Status=0, MTMSN=0
2018-06-04T14:54:59.680Z,1528124099.680 [NAL9602](INFO): No messages in MT queue
2018-06-04T14:55:34.934Z,1528124134.934 [NAL9602](INFO): GPS fix at 20180604T145402: (36.802793, -121.786676)
2018-06-04T14:55:35.006Z,1528124135.006 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T14:55:35.006Z,1528124135.006 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T14:55:39.406Z,1528124139.406 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180604T143709/Courier0010.lzma
2018-06-04T14:55:40.164Z,1528124140.164 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0010.lzma.bak
2018-06-04T14:55:40.164Z,1528124140.164 [DataOverHttps](INFO): SBD MOMSN=8312481
2018-06-04T14:55:50.231Z,1528124150.231 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180604T143709/Express0011.lzma
2018-06-04T14:55:51.029Z,1528124151.029 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0011.lzma.bak
2018-06-04T14:55:51.029Z,1528124151.029 [DataOverHttps](INFO): SBD MOMSN=8312489
2018-06-04T14:55:51.523Z,1528124151.523 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T14:55:51.523Z,1528124151.523 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T14:55:51.523Z,1528124151.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T14:56:09.462Z,1528124169.462 [NAL9602](INFO): Powering down
2018-06-04T14:57:32.018Z,1528124252.018 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T14:57:32.018Z,1528124252.018 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-04T14:57:32.261Z,1528124252.261 [Rowe_600LCM](INFO): Initializing
2018-06-04T14:57:32.261Z,1528124252.261 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T14:57:32.261Z,1528124252.261 [Rowe_600LCM](INFO): LCM OK
2018-06-04T14:57:32.261Z,1528124252.261 [Rowe_600LCM](INFO): Powering up
2018-06-04T14:57:36.499Z,1528124256.499 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T14:57:36.564Z,1528124256.564 [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-04T14:57:36.565Z,1528124256.565 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T14:57:36.565Z,1528124256.565 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T14:57:36.566Z,1528124256.566 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T14:57:54.478Z,1528124274.478 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:57:55.273Z,1528124275.273 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:58:06.205Z,1528124286.205 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:58:07.001Z,1528124287.001 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:59:33.049Z,1528124373.049 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T14:59:33.049Z,1528124373.049 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-04T14:59:33.049Z,1528124373.049 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T14:59:33.123Z,1528124373.123 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T14:59:33.453Z,1528124373.453 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T14:59:33.676Z,1528124373.676 [Rowe_600LCM](INFO): Powering down
2018-06-04T14:59:35.063Z,1528124375.063 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T14:59:35.063Z,1528124375.063 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-04T14:59:35.289Z,1528124375.289 [Rowe_600LCM](INFO): Initializing
2018-06-04T14:59:35.289Z,1528124375.289 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T14:59:35.290Z,1528124375.290 [Rowe_600LCM](INFO): LCM OK
2018-06-04T14:59:35.290Z,1528124375.290 [Rowe_600LCM](INFO): Powering up
2018-06-04T14:59:39.505Z,1528124379.505 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T14:59:39.612Z,1528124379.612 [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-04T14:59:39.613Z,1528124379.613 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T14:59:39.613Z,1528124379.613 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T14:59:39.614Z,1528124379.614 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T14:59:55.379Z,1528124395.379 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T14:59:56.173Z,1528124396.173 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:00:52.074Z,1528124452.074 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:00:52.074Z,1528124452.074 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:00:52.074Z,1528124452.074 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:00:52.074Z,1528124452.074 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:00:52.478Z,1528124452.478 [Default:CheckIn:D] Stopped
2018-06-04T15:00:52.478Z,1528124452.478 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:00:52.872Z,1528124452.872 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.331714 min 
2018-06-04T15:00:52.872Z,1528124452.872 [Default:CheckIn:E] Stopped
2018-06-04T15:00:52.872Z,1528124452.872 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:00:52.872Z,1528124452.872 [Default:CheckIn] Stopped
2018-06-04T15:00:52.873Z,1528124452.873 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:00:52.874Z,1528124452.874 [Default:CheckIn](INFO): Running loop #4
2018-06-04T15:00:52.874Z,1528124452.874 [Default:CheckIn] Running Loop=4
2018-06-04T15:00:52.874Z,1528124452.874 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:00:52.874Z,1528124452.874 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:00:56.881Z,1528124456.881 [NAL9602](INFO): Powering up
2018-06-04T15:01:05.486Z,1528124465.486 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:06.289Z,1528124466.289 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:07.085Z,1528124467.085 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:07.773Z,1528124467.773 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:07.811Z,1528124467.811 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:01:08.569Z,1528124468.569 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:09.366Z,1528124469.366 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:09.777Z,1528124469.777 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:10.573Z,1528124470.573 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:11.233Z,1528124471.233 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:11.505Z,1528124471.505 [CommandLine](IMPORTANT): got command get platform_battery_charge
2018-06-04T15:01:11.505Z,1528124471.505 [CommandLine](FAULT): Element has no value
2018-06-04T15:01:12.029Z,1528124472.029 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:12.828Z,1528124472.828 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:13.241Z,1528124473.241 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:14.037Z,1528124474.037 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:14.834Z,1528124474.834 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:15.257Z,1528124475.257 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:16.053Z,1528124476.053 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:16.850Z,1528124476.850 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:17.309Z,1528124477.309 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:18.106Z,1528124478.106 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:18.903Z,1528124478.903 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:19.513Z,1528124479.513 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:20.310Z,1528124480.310 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:21.105Z,1528124481.105 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:21.817Z,1528124481.817 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:22.613Z,1528124482.613 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:23.410Z,1528124483.410 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:23.808Z,1528124483.808 [NAL9602](INFO): SBD MO Status=0, MOMSN=21597, MT Status=0, MTMSN=0
2018-06-04T15:01:23.808Z,1528124483.808 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:01:23.814Z,1528124483.814 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:24.609Z,1528124484.609 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:25.261Z,1528124485.261 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:26.057Z,1528124486.057 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:26.854Z,1528124486.854 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:27.265Z,1528124487.265 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:28.061Z,1528124488.061 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:28.858Z,1528124488.858 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:29.281Z,1528124489.281 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:30.077Z,1528124490.077 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:30.875Z,1528124490.875 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:31.333Z,1528124491.333 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:32.130Z,1528124492.130 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:32.926Z,1528124492.926 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:33.533Z,1528124493.533 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:33.677Z,1528124493.677 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_charge
2018-06-04T15:01:33.678Z,1528124493.678 [CommandLine](IMPORTANT): BPC1.platform_battery_charge nan Ah
2018-06-04T15:01:34.330Z,1528124494.330 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:35.126Z,1528124495.126 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:01:35.418Z,1528124495.418 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:01:35.418Z,1528124495.418 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-04T15:01:35.418Z,1528124495.418 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:01:35.551Z,1528124495.551 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:01:35.821Z,1528124495.821 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:01:36.024Z,1528124496.024 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:01:36.833Z,1528124496.833 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-06-04T15:01:36.833Z,1528124496.833 [Rowe_600LCM] Hardware Fault, FailCount= 2
2018-06-04T15:01:36.833Z,1528124496.833 [Rowe_600LCM](ERROR): Hardware Fault
2018-06-04T15:01:37.466Z,1528124497.466 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:01:37.466Z,1528124497.466 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-04T15:01:37.641Z,1528124497.641 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:01:37.641Z,1528124497.641 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:01:37.641Z,1528124497.641 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:01:37.641Z,1528124497.641 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:01:41.771Z,1528124501.771 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:01:41.940Z,1528124501.940 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-06-04T15:01:41.941Z,1528124501.941 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:01:41.941Z,1528124501.941 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:01:41.942Z,1528124501.942 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:02:06.205Z,1528124526.205 [CommandLine](IMPORTANT): got command failComponent hardware BPC1
2018-06-04T15:02:06.205Z,1528124526.205 [BPC1] Hardware Fault, FailCount= 2
2018-06-04T15:02:06.206Z,1528124526.206 [BPC1](ERROR): Hardware Fault
2018-06-04T15:02:06.206Z,1528124526.206 [CommandLine](IMPORTANT): BPC1 failureMode is Hardware Fault
2018-06-04T15:02:06.328Z,1528124526.328 [CBIT](ERROR): Hardware Fault in component: BPC1
2018-06-04T15:02:07.094Z,1528124527.094 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T15:02:07.094Z,1528124527.094 [BPC1] No Fault, FailCount= 2
2018-06-04T15:02:53.658Z,1528124573.658 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:02:54.453Z,1528124574.453 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:03:05.998Z,1528124585.998 [NAL9602](INFO): GPS fix at 20180604T150132: (36.807601, -121.788159)
2018-06-04T15:03:06.062Z,1528124586.062 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:03:06.062Z,1528124586.062 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:03:10.998Z,1528124590.998 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180604T143709/Courier0013.lzma
2018-06-04T15:03:11.792Z,1528124591.792 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0013.lzma.bak
2018-06-04T15:03:11.792Z,1528124591.792 [DataOverHttps](INFO): SBD MOMSN=8312504
2018-06-04T15:03:21.050Z,1528124601.050 [DataOverHttps](INFO): Sending 387 bytes from file Logs/20180604T143709/Express0014.lzma
2018-06-04T15:03:21.836Z,1528124601.836 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0014.lzma.bak
2018-06-04T15:03:21.837Z,1528124601.837 [DataOverHttps](INFO): SBD MOMSN=8312507
2018-06-04T15:03:22.335Z,1528124602.335 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:03:22.335Z,1528124602.335 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:03:22.335Z,1528124602.335 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T15:03:38.405Z,1528124618.405 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:03:38.405Z,1528124618.405 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-04T15:03:38.405Z,1528124618.405 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:03:38.427Z,1528124618.427 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:03:38.809Z,1528124618.809 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:03:38.970Z,1528124618.970 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:03:40.441Z,1528124620.441 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:03:40.441Z,1528124620.441 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-04T15:03:40.585Z,1528124620.585 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:03:40.585Z,1528124620.585 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:03:40.585Z,1528124620.585 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:03:40.586Z,1528124620.586 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:03:40.750Z,1528124620.750 [NAL9602](INFO): Powering down
2018-06-04T15:03:44.720Z,1528124624.720 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:03:44.848Z,1528124624.848 [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-04T15:03:44.849Z,1528124624.849 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:03:44.849Z,1528124624.849 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:03:44.850Z,1528124624.850 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:04:42.377Z,1528124682.377 [CommandLine](IMPORTANT): got command get platform_battery_charge
2018-06-04T15:04:42.378Z,1528124682.378 [CommandLine](FAULT): Element has no value
2018-06-04T15:05:14.787Z,1528124714.787 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_charge
2018-06-04T15:05:14.788Z,1528124714.788 [CommandLine](IMPORTANT): BPC1.platform_battery_charge nan Ah
2018-06-04T15:05:27.131Z,1528124727.131 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-06-04T15:05:27.132Z,1528124727.132 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage nan _
2018-06-04T15:05:40.797Z,1528124740.797 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:05:40.797Z,1528124740.797 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-04T15:05:40.797Z,1528124740.797 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:05:40.997Z,1528124740.997 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:05:41.201Z,1528124741.201 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:05:41.302Z,1528124741.302 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:05:42.601Z,1528124742.601 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:05:42.601Z,1528124742.601 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-04T15:05:42.917Z,1528124742.917 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:05:42.917Z,1528124742.917 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:05:42.918Z,1528124742.918 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:05:42.918Z,1528124742.918 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:05:47.128Z,1528124747.128 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:05:47.227Z,1528124747.227 [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-04T15:05:47.228Z,1528124747.228 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:05:47.229Z,1528124747.229 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:05:47.230Z,1528124747.230 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:06:10.406Z,1528124770.406 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-06-04T15:06:10.407Z,1528124770.407 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage nan _
2018-06-04T15:07:42.985Z,1528124862.985 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:07:42.985Z,1528124862.985 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-04T15:07:42.986Z,1528124862.986 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:07:43.030Z,1528124863.030 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:07:43.030Z,1528124863.030 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-04T15:07:43.389Z,1528124863.389 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:07:43.488Z,1528124863.488 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:07:58.768Z,1528124878.768 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-06-04T15:07:58.769Z,1528124878.769 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage nan _
2018-06-04T15:08:22.878Z,1528124902.878 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:08:22.878Z,1528124902.878 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:08:22.878Z,1528124902.878 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:08:22.878Z,1528124902.878 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:08:23.319Z,1528124903.319 [Default:CheckIn:D] Stopped
2018-06-04T15:08:23.319Z,1528124903.319 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:08:23.679Z,1528124903.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.845728 min 
2018-06-04T15:08:23.680Z,1528124903.680 [Default:CheckIn:E] Stopped
2018-06-04T15:08:23.680Z,1528124903.680 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:08:23.680Z,1528124903.680 [Default:CheckIn] Stopped
2018-06-04T15:08:23.680Z,1528124903.680 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:08:23.680Z,1528124903.680 [Default:CheckIn](INFO): Running loop #5
2018-06-04T15:08:23.681Z,1528124903.681 [Default:CheckIn] Running Loop=5
2018-06-04T15:08:23.681Z,1528124903.681 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:08:23.681Z,1528124903.681 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:08:26.867Z,1528124906.867 [NAL9602](INFO): Powering up
2018-06-04T15:08:29.286Z,1528124909.286 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-04T15:08:29.286Z,1528124909.286 [BPC1] Communications Fault, FailCount= 3
2018-06-04T15:08:29.286Z,1528124909.286 [BPC1](ERROR): Communications Fault
2018-06-04T15:08:29.341Z,1528124909.341 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-04T15:08:30.511Z,1528124910.511 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T15:08:30.511Z,1528124910.511 [BPC1] No Fault, FailCount= 3
2018-06-04T15:08:38.054Z,1528124918.054 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:09:06.056Z,1528124946.056 [NAL9602](INFO): SBD MO Status=2, MOMSN=21598, MT Status=2, MTMSN=0
2018-06-04T15:09:06.056Z,1528124946.056 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:09:32.076Z,1528124972.076 [NAL9602](INFO): SBD MO Status=2, MOMSN=21598, MT Status=2, MTMSN=0
2018-06-04T15:09:32.076Z,1528124972.076 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:09:43.671Z,1528124983.671 [NAL9602](INFO): SBD MO Status=0, MOMSN=21598, MT Status=0, MTMSN=0
2018-06-04T15:09:43.671Z,1528124983.671 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:10:05.274Z,1528125005.274 [NAL9602](INFO): GPS fix at 20180604T150830: (36.806262, -121.785315)
2018-06-04T15:10:05.357Z,1528125005.357 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:10:05.357Z,1528125005.357 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:10:09.765Z,1528125009.765 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180604T143709/Courier0016.lzma
2018-06-04T15:10:10.556Z,1528125010.556 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0016.lzma.bak
2018-06-04T15:10:10.556Z,1528125010.556 [DataOverHttps](INFO): SBD MOMSN=8312531
2018-06-04T15:10:19.414Z,1528125019.414 [DataOverHttps](INFO): Sending 401 bytes from file Logs/20180604T143709/Express0017.lzma
2018-06-04T15:10:20.180Z,1528125020.180 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0017.lzma.bak
2018-06-04T15:10:20.180Z,1528125020.180 [DataOverHttps](INFO): SBD MOMSN=8312534
2018-06-04T15:10:20.676Z,1528125020.676 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:10:20.676Z,1528125020.676 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:10:20.676Z,1528125020.676 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T15:10:39.994Z,1528125039.994 [NAL9602](INFO): Powering down
2018-06-04T15:12:15.740Z,1528125135.740 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-06-04T15:12:15.741Z,1528125135.741 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage nan _
2018-06-04T15:13:05.416Z,1528125185.416 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-06-04T15:13:05.417Z,1528125185.417 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage nan _
2018-06-04T15:15:21.188Z,1528125321.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:15:21.188Z,1528125321.188 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:15:21.188Z,1528125321.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:15:21.189Z,1528125321.189 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:15:21.591Z,1528125321.591 [Default:CheckIn:D] Stopped
2018-06-04T15:15:21.592Z,1528125321.592 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:15:24.720Z,1528125324.720 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.816931 min 
2018-06-04T15:15:24.721Z,1528125324.721 [Default:CheckIn:E] Stopped
2018-06-04T15:15:24.721Z,1528125324.721 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:15:24.721Z,1528125324.721 [Default:CheckIn] Stopped
2018-06-04T15:15:24.721Z,1528125324.721 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:15:24.721Z,1528125324.721 [Default:CheckIn](INFO): Running loop #6
2018-06-04T15:15:24.721Z,1528125324.721 [Default:CheckIn] Running Loop=6
2018-06-04T15:15:24.721Z,1528125324.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:15:24.722Z,1528125324.722 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:15:25.158Z,1528125325.158 [NAL9602](INFO): Powering up
2018-06-04T15:15:36.346Z,1528125336.346 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:15:49.548Z,1528125349.548 [NAL9602](INFO): SBD MO Status=0, MOMSN=21599, MT Status=0, MTMSN=0
2018-06-04T15:15:49.548Z,1528125349.548 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:16:39.979Z,1528125399.979 [NAL9602](INFO): GPS fix at 20180604T151505: (36.805011, -121.797443)
2018-06-04T15:16:40.034Z,1528125400.034 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:16:40.034Z,1528125400.034 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:16:45.672Z,1528125405.672 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180604T143709/Courier0019.lzma
2018-06-04T15:16:46.468Z,1528125406.468 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0019.lzma.bak
2018-06-04T15:16:46.469Z,1528125406.469 [DataOverHttps](INFO): SBD MOMSN=8312559
2018-06-04T15:16:56.488Z,1528125416.488 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20180604T143709/Express0020.lzma
2018-06-04T15:16:57.288Z,1528125417.288 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0020.lzma.bak
2018-06-04T15:16:57.288Z,1528125417.288 [DataOverHttps](INFO): SBD MOMSN=8312562
2018-06-04T15:16:57.787Z,1528125417.787 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:16:57.787Z,1528125417.787 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:16:57.787Z,1528125417.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T15:17:15.358Z,1528125435.358 [NAL9602](INFO): Powering down
2018-06-04T15:17:43.018Z,1528125463.018 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:17:43.018Z,1528125463.018 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-04T15:17:43.325Z,1528125463.325 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:17:43.325Z,1528125463.325 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:17:43.325Z,1528125463.325 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:17:43.325Z,1528125463.325 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:17:47.554Z,1528125467.554 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:17:47.618Z,1528125467.618 [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-04T15:17:47.619Z,1528125467.619 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:17:47.619Z,1528125467.619 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:17:47.620Z,1528125467.620 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:18:55.935Z,1528125535.935 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-04T15:18:55.936Z,1528125535.936 [BPC1] Communications Fault, FailCount= 4
2018-06-04T15:18:55.936Z,1528125535.936 [BPC1](ERROR): Communications Fault
2018-06-04T15:18:55.982Z,1528125535.982 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-04T15:18:57.193Z,1528125537.193 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T15:18:57.193Z,1528125537.193 [BPC1] No Fault, FailCount= 4
2018-06-04T15:19:43.917Z,1528125583.917 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:19:43.917Z,1528125583.917 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-04T15:19:43.917Z,1528125583.917 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:19:43.952Z,1528125583.952 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:19:44.321Z,1528125584.321 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:19:44.480Z,1528125584.480 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:19:45.949Z,1528125585.949 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:19:45.949Z,1528125585.949 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-04T15:19:46.093Z,1528125586.093 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:19:46.093Z,1528125586.093 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:19:46.093Z,1528125586.093 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:19:46.094Z,1528125586.094 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:19:50.214Z,1528125590.214 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:19:50.320Z,1528125590.320 [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-04T15:19:50.320Z,1528125590.320 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:19:50.321Z,1528125590.321 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:19:50.322Z,1528125590.322 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:20:48.314Z,1528125648.314 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:20:49.109Z,1528125649.109 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:00.922Z,1528125660.922 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:01.722Z,1528125661.722 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:02.317Z,1528125662.317 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:03.113Z,1528125663.113 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:03.909Z,1528125663.909 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:04.609Z,1528125664.609 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:05.405Z,1528125665.405 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:06.202Z,1528125666.202 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:06.617Z,1528125666.617 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:07.413Z,1528125667.413 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:08.069Z,1528125668.069 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:08.865Z,1528125668.865 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:09.662Z,1528125669.662 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:10.081Z,1528125670.081 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:10.877Z,1528125670.877 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:11.674Z,1528125671.674 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:12.093Z,1528125672.093 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:12.891Z,1528125672.891 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:13.687Z,1528125673.687 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:14.145Z,1528125674.145 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:14.942Z,1528125674.942 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:15.738Z,1528125675.738 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:16.385Z,1528125676.385 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:17.181Z,1528125677.181 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:17.977Z,1528125677.977 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:18.641Z,1528125678.641 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:19.437Z,1528125679.437 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:20.234Z,1528125680.234 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:20.641Z,1528125680.641 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:21.437Z,1528125681.437 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:22.093Z,1528125682.093 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:22.889Z,1528125682.889 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:23.686Z,1528125683.686 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:24.101Z,1528125684.101 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:24.897Z,1528125684.897 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:25.694Z,1528125685.694 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:26.113Z,1528125686.113 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:26.909Z,1528125686.909 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:27.706Z,1528125687.706 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:28.174Z,1528125688.174 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:28.970Z,1528125688.970 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:29.766Z,1528125689.766 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:30.381Z,1528125690.381 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:31.178Z,1528125691.178 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:31.973Z,1528125691.973 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:32.657Z,1528125692.657 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:33.453Z,1528125693.453 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:34.250Z,1528125694.250 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:34.661Z,1528125694.661 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:35.459Z,1528125695.459 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:36.117Z,1528125696.117 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:36.913Z,1528125696.913 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:37.710Z,1528125697.710 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:38.129Z,1528125698.129 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:38.925Z,1528125698.925 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:39.722Z,1528125699.722 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:40.141Z,1528125700.141 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:40.937Z,1528125700.937 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:41.734Z,1528125701.734 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:42.197Z,1528125702.197 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:42.994Z,1528125702.994 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:43.790Z,1528125703.790 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:44.393Z,1528125704.393 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:45.189Z,1528125705.189 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:45.985Z,1528125705.985 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:21:46.277Z,1528125706.277 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:21:46.277Z,1528125706.277 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-04T15:21:46.277Z,1528125706.277 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:21:46.384Z,1528125706.384 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:21:46.681Z,1528125706.681 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:21:46.915Z,1528125706.915 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:21:47.721Z,1528125707.721 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-06-04T15:21:47.721Z,1528125707.721 [Rowe_600LCM] Hardware Fault, FailCount= 2
2018-06-04T15:21:47.721Z,1528125707.721 [Rowe_600LCM](ERROR): Hardware Fault
2018-06-04T15:21:48.392Z,1528125708.392 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:21:48.392Z,1528125708.392 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-04T15:21:48.529Z,1528125708.529 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:21:48.529Z,1528125708.529 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:21:48.530Z,1528125708.530 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:21:48.530Z,1528125708.530 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:21:52.657Z,1528125712.657 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:21:52.714Z,1528125712.714 [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-04T15:21:52.714Z,1528125712.714 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:21:52.715Z,1528125712.715 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:21:52.716Z,1528125712.716 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:21:58.388Z,1528125718.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:21:58.393Z,1528125718.393 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:21:58.393Z,1528125718.393 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:21:58.393Z,1528125718.393 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:21:58.776Z,1528125718.776 [Default:CheckIn:D] Stopped
2018-06-04T15:21:58.776Z,1528125718.776 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:22:03.478Z,1528125723.478 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.436674 min 
2018-06-04T15:22:03.478Z,1528125723.478 [Default:CheckIn:E] Stopped
2018-06-04T15:22:03.479Z,1528125723.479 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:22:03.479Z,1528125723.479 [Default:CheckIn] Stopped
2018-06-04T15:22:03.479Z,1528125723.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:22:03.479Z,1528125723.479 [Default:CheckIn](INFO): Running loop #7
2018-06-04T15:22:03.479Z,1528125723.479 [Default:CheckIn] Running Loop=7
2018-06-04T15:22:03.479Z,1528125723.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:22:03.479Z,1528125723.479 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:22:03.955Z,1528125723.955 [NAL9602](INFO): Powering up
2018-06-04T15:22:15.146Z,1528125735.146 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:22:46.352Z,1528125766.352 [NAL9602](INFO): SBD MO Status=2, MOMSN=21600, MT Status=2, MTMSN=0
2018-06-04T15:22:46.352Z,1528125766.352 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:23:02.225Z,1528125782.225 [NAL9602](INFO): SBD MO Status=2, MOMSN=21600, MT Status=2, MTMSN=0
2018-06-04T15:23:02.225Z,1528125782.225 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:23:18.308Z,1528125798.308 [NAL9602](INFO): SBD MO Status=2, MOMSN=21600, MT Status=2, MTMSN=0
2018-06-04T15:23:18.308Z,1528125798.308 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:23:45.915Z,1528125825.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=21600, MT Status=2, MTMSN=0
2018-06-04T15:23:45.915Z,1528125825.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:23:49.261Z,1528125829.261 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:23:49.261Z,1528125829.261 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-04T15:23:49.261Z,1528125829.261 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:23:49.582Z,1528125829.582 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:23:49.665Z,1528125829.665 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:23:49.760Z,1528125829.760 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:23:51.174Z,1528125831.174 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:23:51.174Z,1528125831.174 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-04T15:23:51.379Z,1528125831.379 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:23:51.380Z,1528125831.380 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:23:51.380Z,1528125831.380 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:23:51.380Z,1528125831.380 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:23:55.557Z,1528125835.557 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:23:55.708Z,1528125835.708 [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-04T15:23:55.708Z,1528125835.708 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:23:55.709Z,1528125835.709 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:23:55.710Z,1528125835.710 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:23:57.908Z,1528125837.908 [NAL9602](INFO): SBD MO Status=0, MOMSN=21600, MT Status=0, MTMSN=0
2018-06-04T15:23:57.908Z,1528125837.908 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:25:04.806Z,1528125904.806 [NAL9602](INFO): GPS fix at 20180604T152329: (36.832588, -121.821449)
2018-06-04T15:25:04.891Z,1528125904.891 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:25:04.892Z,1528125904.892 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:25:09.979Z,1528125909.979 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180604T143709/Courier0022.lzma
2018-06-04T15:25:10.764Z,1528125910.764 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0022.lzma.bak
2018-06-04T15:25:10.765Z,1528125910.765 [DataOverHttps](INFO): SBD MOMSN=8312578
2018-06-04T15:25:20.470Z,1528125920.470 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20180604T143709/Express0023.lzma
2018-06-04T15:25:21.265Z,1528125921.265 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0023.lzma.bak
2018-06-04T15:25:21.266Z,1528125921.266 [DataOverHttps](INFO): SBD MOMSN=8312581
2018-06-04T15:25:21.779Z,1528125921.779 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:25:21.780Z,1528125921.780 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:25:21.780Z,1528125921.780 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T15:25:37.247Z,1528125937.247 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-04T15:25:37.247Z,1528125937.247 [BPC1] Communications Fault, FailCount= 5
2018-06-04T15:25:37.247Z,1528125937.247 [BPC1](ERROR): Communications Fault
2018-06-04T15:25:37.302Z,1528125937.302 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-04T15:25:38.491Z,1528125938.491 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T15:25:38.491Z,1528125938.491 [BPC1] No Fault, FailCount= 5
2018-06-04T15:25:40.422Z,1528125940.422 [NAL9602](INFO): Powering down
2018-06-04T15:25:51.668Z,1528125951.668 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:25:51.668Z,1528125951.668 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-04T15:25:51.668Z,1528125951.668 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:25:51.745Z,1528125951.745 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:25:52.077Z,1528125952.077 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:25:52.229Z,1528125952.229 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:25:53.680Z,1528125953.680 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:25:53.680Z,1528125953.680 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-04T15:25:53.845Z,1528125953.845 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:25:53.845Z,1528125953.845 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:25:53.845Z,1528125953.845 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:25:53.846Z,1528125953.846 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:25:57.968Z,1528125957.968 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:25:58.115Z,1528125958.115 [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-04T15:25:58.116Z,1528125958.116 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:25:58.117Z,1528125958.117 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:25:58.118Z,1528125958.118 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:27:53.847Z,1528126073.847 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:27:53.847Z,1528126073.847 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-04T15:27:53.847Z,1528126073.847 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:27:54.077Z,1528126074.077 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:27:54.078Z,1528126074.078 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-04T15:27:54.249Z,1528126074.249 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:27:54.340Z,1528126074.340 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:30:22.468Z,1528126222.468 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:30:22.468Z,1528126222.468 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:30:22.468Z,1528126222.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:30:22.468Z,1528126222.468 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:30:22.868Z,1528126222.868 [Default:CheckIn:D] Stopped
2018-06-04T15:30:22.868Z,1528126222.868 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:30:26.169Z,1528126226.169 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.838212 min 
2018-06-04T15:30:26.169Z,1528126226.169 [Default:CheckIn:E] Stopped
2018-06-04T15:30:26.170Z,1528126226.170 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:30:26.170Z,1528126226.170 [Default:CheckIn] Stopped
2018-06-04T15:30:26.170Z,1528126226.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:30:26.170Z,1528126226.170 [Default:CheckIn](INFO): Running loop #8
2018-06-04T15:30:26.170Z,1528126226.170 [Default:CheckIn] Running Loop=8
2018-06-04T15:30:26.170Z,1528126226.170 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:30:26.170Z,1528126226.170 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:30:26.597Z,1528126226.597 [NAL9602](INFO): Powering up
2018-06-04T15:30:37.798Z,1528126237.798 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:31:07.797Z,1528126267.797 [NAL9602](INFO): SBD MO Status=0, MOMSN=21601, MT Status=0, MTMSN=0
2018-06-04T15:31:07.797Z,1528126267.797 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:32:00.623Z,1528126320.623 [NAL9602](INFO): GPS fix at 20180604T153024: (36.851095, -121.842310)
2018-06-04T15:32:00.686Z,1528126320.686 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:32:00.686Z,1528126320.686 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:32:06.747Z,1528126326.747 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180604T143709/Courier0025.lzma
2018-06-04T15:32:07.544Z,1528126327.544 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0025.lzma.bak
2018-06-04T15:32:07.545Z,1528126327.545 [DataOverHttps](INFO): SBD MOMSN=8312597
2018-06-04T15:32:16.463Z,1528126336.463 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20180604T143709/Express0026.lzma
2018-06-04T15:32:17.248Z,1528126337.248 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0026.lzma.bak
2018-06-04T15:32:17.249Z,1528126337.249 [DataOverHttps](INFO): SBD MOMSN=8312600
2018-06-04T15:32:17.748Z,1528126337.748 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:32:17.749Z,1528126337.749 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:32:17.749Z,1528126337.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T15:32:36.650Z,1528126356.650 [NAL9602](INFO): Powering down
2018-06-04T15:37:18.404Z,1528126638.404 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:37:18.405Z,1528126638.405 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:37:18.405Z,1528126638.405 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:37:18.405Z,1528126638.405 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:37:21.556Z,1528126641.556 [Default:CheckIn:D] Stopped
2018-06-04T15:37:21.557Z,1528126641.557 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:37:21.693Z,1528126641.693 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.816349 min 
2018-06-04T15:37:21.693Z,1528126641.693 [Default:CheckIn:E] Stopped
2018-06-04T15:37:21.693Z,1528126641.693 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:37:21.693Z,1528126641.693 [Default:CheckIn] Stopped
2018-06-04T15:37:21.694Z,1528126641.694 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:37:21.694Z,1528126641.694 [Default:CheckIn](INFO): Running loop #9
2018-06-04T15:37:21.694Z,1528126641.694 [Default:CheckIn] Running Loop=9
2018-06-04T15:37:21.694Z,1528126641.694 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:37:21.694Z,1528126641.694 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:37:22.391Z,1528126642.391 [NAL9602](INFO): Powering up
2018-06-04T15:37:33.582Z,1528126653.582 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:37:54.065Z,1528126674.065 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:37:54.065Z,1528126674.065 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-04T15:37:54.194Z,1528126674.194 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:37:54.194Z,1528126674.194 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:37:54.194Z,1528126674.194 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:37:54.194Z,1528126674.194 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:37:55.985Z,1528126675.985 [NAL9602](INFO): SBD MO Status=2, MOMSN=21602, MT Status=2, MTMSN=0
2018-06-04T15:37:55.985Z,1528126675.985 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:37:58.321Z,1528126678.321 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:37:58.435Z,1528126678.435 [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-04T15:37:58.436Z,1528126678.436 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:37:58.437Z,1528126678.437 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:37:58.437Z,1528126678.437 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:38:08.792Z,1528126688.792 [NAL9602](INFO): SBD MO Status=0, MOMSN=21602, MT Status=0, MTMSN=0
2018-06-04T15:38:08.792Z,1528126688.792 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:38:37.246Z,1528126717.246 [NAL9602](INFO): GPS fix at 20180604T153700: (36.826738, -121.812741)
2018-06-04T15:38:37.324Z,1528126717.324 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:38:37.329Z,1528126717.329 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:38:42.039Z,1528126722.039 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180604T143709/Courier0028.lzma
2018-06-04T15:38:42.817Z,1528126722.817 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0028.lzma.bak
2018-06-04T15:38:42.817Z,1528126722.817 [DataOverHttps](INFO): SBD MOMSN=8312614
2018-06-04T15:38:54.331Z,1528126734.331 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20180604T143709/Express0029.lzma
2018-06-04T15:38:55.124Z,1528126735.124 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0029.lzma.bak
2018-06-04T15:38:55.125Z,1528126735.125 [DataOverHttps](INFO): SBD MOMSN=8312617
2018-06-04T15:38:55.710Z,1528126735.710 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:38:55.710Z,1528126735.710 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:38:55.710Z,1528126735.710 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T15:39:12.242Z,1528126752.242 [NAL9602](INFO): Powering down
2018-06-04T15:39:54.613Z,1528126794.613 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:39:54.613Z,1528126794.613 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-04T15:39:54.613Z,1528126794.613 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:39:54.721Z,1528126794.721 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:39:55.017Z,1528126795.017 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:39:55.266Z,1528126795.266 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:39:59.431Z,1528126799.431 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:39:59.431Z,1528126799.431 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-04T15:39:59.709Z,1528126799.709 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:39:59.709Z,1528126799.709 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:39:59.710Z,1528126799.710 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:39:59.710Z,1528126799.710 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:40:04.025Z,1528126804.025 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:40:04.093Z,1528126804.093 [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-04T15:40:04.094Z,1528126804.094 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:40:04.095Z,1528126804.095 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:40:04.095Z,1528126804.095 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:41:59.937Z,1528126919.937 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:41:59.937Z,1528126919.937 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-04T15:41:59.937Z,1528126919.937 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:41:59.966Z,1528126919.966 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:42:00.341Z,1528126920.341 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:42:00.573Z,1528126920.573 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:42:02.058Z,1528126922.058 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:42:02.058Z,1528126922.058 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-04T15:42:02.189Z,1528126922.189 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:42:02.189Z,1528126922.189 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:42:02.189Z,1528126922.189 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:42:02.190Z,1528126922.190 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:42:06.310Z,1528126926.310 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:42:06.423Z,1528126926.423 [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-04T15:42:06.424Z,1528126926.424 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:42:06.425Z,1528126926.425 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:42:06.425Z,1528126926.425 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:43:56.004Z,1528127036.004 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:43:56.009Z,1528127036.009 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:43:56.009Z,1528127036.009 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:43:56.009Z,1528127036.009 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:43:56.402Z,1528127036.402 [Default:CheckIn:D] Stopped
2018-06-04T15:43:56.402Z,1528127036.402 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:43:56.796Z,1528127036.796 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.397107 min 
2018-06-04T15:43:56.796Z,1528127036.796 [Default:CheckIn:E] Stopped
2018-06-04T15:43:56.796Z,1528127036.796 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:43:56.801Z,1528127036.801 [Default:CheckIn] Stopped
2018-06-04T15:43:56.801Z,1528127036.801 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:43:56.801Z,1528127036.801 [Default:CheckIn](INFO): Running loop #10
2018-06-04T15:43:56.801Z,1528127036.801 [Default:CheckIn] Running Loop=10
2018-06-04T15:43:56.801Z,1528127036.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:43:56.801Z,1528127036.801 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:44:00.663Z,1528127040.663 [NAL9602](INFO): Powering up
2018-06-04T15:44:02.333Z,1528127042.333 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:44:02.333Z,1528127042.333 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-04T15:44:02.333Z,1528127042.333 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:44:02.466Z,1528127042.466 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:44:02.737Z,1528127042.737 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:44:03.043Z,1528127043.043 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:44:04.491Z,1528127044.491 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:44:04.491Z,1528127044.491 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-04T15:44:04.657Z,1528127044.657 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:44:04.658Z,1528127044.658 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:44:04.659Z,1528127044.659 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:44:04.659Z,1528127044.659 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:44:08.833Z,1528127048.833 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:44:08.960Z,1528127048.960 [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-04T15:44:08.960Z,1528127048.960 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:44:08.961Z,1528127048.961 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:44:08.962Z,1528127048.962 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:44:11.578Z,1528127051.578 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:44:27.180Z,1528127067.180 [NAL9602](INFO): SBD MO Status=0, MOMSN=21603, MT Status=0, MTMSN=0
2018-06-04T15:44:27.180Z,1528127067.180 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:45:27.597Z,1528127127.597 [NAL9602](INFO): GPS fix at 20180604T154351: (36.807044, -121.788501)
2018-06-04T15:45:27.668Z,1528127127.668 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:45:27.668Z,1528127127.668 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:45:33.486Z,1528127133.486 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180604T143709/Courier0031.lzma
2018-06-04T15:45:34.252Z,1528127134.252 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0031.lzma.bak
2018-06-04T15:45:34.252Z,1528127134.252 [DataOverHttps](INFO): SBD MOMSN=8312630
2018-06-04T15:45:43.615Z,1528127143.615 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20180604T143709/Express0032.lzma
2018-06-04T15:45:44.380Z,1528127144.380 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0032.lzma.bak
2018-06-04T15:45:44.380Z,1528127144.380 [DataOverHttps](INFO): SBD MOMSN=8312633
2018-06-04T15:45:44.968Z,1528127144.968 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:45:44.968Z,1528127144.968 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:45:44.968Z,1528127144.968 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T15:46:00.342Z,1528127160.342 [NAL9602](INFO): Powering down
2018-06-04T15:46:05.317Z,1528127165.317 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:46:05.317Z,1528127165.317 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-04T15:46:05.317Z,1528127165.317 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:46:05.653Z,1528127165.653 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:46:05.721Z,1528127165.721 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:46:05.817Z,1528127165.817 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:46:07.202Z,1528127167.202 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:46:07.202Z,1528127167.202 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-04T15:46:07.433Z,1528127167.433 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:46:07.433Z,1528127167.433 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:46:07.433Z,1528127167.433 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:46:07.434Z,1528127167.434 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:46:11.660Z,1528127171.660 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:46:11.752Z,1528127171.752 [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-04T15:46:11.753Z,1528127171.753 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:46:11.753Z,1528127171.753 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:46:11.754Z,1528127171.754 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:46:39.582Z,1528127199.582 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 3 sticks.
2018-06-04T15:46:39.582Z,1528127199.582 [BPC1] Data Fault, FailCount= 6
2018-06-04T15:46:39.582Z,1528127199.582 [BPC1](ERROR): Data Fault
2018-06-04T15:46:39.633Z,1528127199.633 [CBIT](ERROR): Data Fault in component: BPC1
2018-06-04T15:46:40.812Z,1528127200.812 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T15:46:40.812Z,1528127200.812 [BPC1] No Fault, FailCount= 6
2018-06-04T15:46:41.682Z,1528127201.682 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:46:42.477Z,1528127202.477 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:48:07.707Z,1528127287.707 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T15:48:07.707Z,1528127287.707 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-04T15:48:07.708Z,1528127287.708 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T15:48:07.869Z,1528127287.869 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T15:48:07.869Z,1528127287.869 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-04T15:48:08.113Z,1528127288.113 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T15:48:08.318Z,1528127288.318 [Rowe_600LCM](INFO): Powering down
2018-06-04T15:50:45.327Z,1528127445.327 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:50:45.327Z,1528127445.327 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:50:45.327Z,1528127445.327 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:50:45.327Z,1528127445.327 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:50:45.731Z,1528127445.731 [Default:CheckIn:D] Stopped
2018-06-04T15:50:45.731Z,1528127445.731 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:50:46.127Z,1528127446.127 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.219255 min 
2018-06-04T15:50:46.128Z,1528127446.128 [Default:CheckIn:E] Stopped
2018-06-04T15:50:46.128Z,1528127446.128 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:50:46.128Z,1528127446.128 [Default:CheckIn] Stopped
2018-06-04T15:50:46.128Z,1528127446.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:50:46.128Z,1528127446.128 [Default:CheckIn](INFO): Running loop #11
2018-06-04T15:50:46.128Z,1528127446.128 [Default:CheckIn] Running Loop=11
2018-06-04T15:50:46.128Z,1528127446.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:50:46.129Z,1528127446.129 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:50:49.603Z,1528127449.603 [NAL9602](INFO): Powering up
2018-06-04T15:51:00.502Z,1528127460.502 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:51:26.108Z,1528127486.108 [NAL9602](INFO): SBD MO Status=2, MOMSN=21604, MT Status=2, MTMSN=0
2018-06-04T15:51:26.108Z,1528127486.108 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:51:44.904Z,1528127504.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=21604, MT Status=2, MTMSN=0
2018-06-04T15:51:44.904Z,1528127504.904 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:52:00.940Z,1528127520.940 [NAL9602](INFO): SBD MO Status=2, MOMSN=21604, MT Status=2, MTMSN=0
2018-06-04T15:52:00.940Z,1528127520.940 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:52:49.737Z,1528127569.737 [NAL9602](INFO): SBD MO Status=2, MOMSN=21604, MT Status=2, MTMSN=0
2018-06-04T15:52:49.737Z,1528127569.737 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T15:52:55.820Z,1528127575.820 [NAL9602](INFO): SBD MO Status=0, MOMSN=21604, MT Status=0, MTMSN=0
2018-06-04T15:52:55.820Z,1528127575.820 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:52:57.023Z,1528127577.023 [NAL9602](INFO): GPS fix at 20180604T155118: (36.805958, -121.785623)
2018-06-04T15:52:57.079Z,1528127577.079 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:52:57.079Z,1528127577.079 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:53:01.847Z,1528127581.847 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180604T143709/Courier0034.lzma
2018-06-04T15:53:02.644Z,1528127582.644 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0034.lzma.bak
2018-06-04T15:53:02.644Z,1528127582.644 [DataOverHttps](INFO): SBD MOMSN=8312649
2018-06-04T15:53:11.977Z,1528127591.977 [DataOverHttps](INFO): Sending 338 bytes from file Logs/20180604T143709/Express0035.lzma
2018-06-04T15:53:12.732Z,1528127592.732 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0035.lzma.bak
2018-06-04T15:53:12.732Z,1528127592.732 [DataOverHttps](INFO): SBD MOMSN=8312652
2018-06-04T15:53:13.234Z,1528127593.234 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:53:13.234Z,1528127593.234 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:53:13.234Z,1528127593.234 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T15:53:31.702Z,1528127611.702 [NAL9602](INFO): Powering down
2018-06-04T15:56:05.048Z,1528127765.048 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.417351 seconds old.
2018-06-04T15:56:06.616Z,1528127766.616 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.391835 seconds old.
2018-06-04T15:56:07.016Z,1528127767.016 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.791619 seconds old.
2018-06-04T15:56:07.418Z,1528127767.418 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.194351 seconds old.
2018-06-04T15:56:07.820Z,1528127767.820 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.595768 seconds old.
2018-06-04T15:58:07.896Z,1528127887.896 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T15:58:07.896Z,1528127887.896 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-04T15:58:08.119Z,1528127888.119 [Rowe_600LCM](INFO): Initializing
2018-06-04T15:58:08.120Z,1528127888.120 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T15:58:08.120Z,1528127888.120 [Rowe_600LCM](INFO): LCM OK
2018-06-04T15:58:08.120Z,1528127888.120 [Rowe_600LCM](INFO): Powering up
2018-06-04T15:58:12.369Z,1528127892.369 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T15:58:12.431Z,1528127892.431 [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-04T15:58:12.432Z,1528127892.432 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T15:58:12.433Z,1528127892.433 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T15:58:12.434Z,1528127892.434 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T15:58:13.904Z,1528127893.904 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T15:58:13.910Z,1528127893.910 [Default:CheckIn:C.Wait] Stopped
2018-06-04T15:58:13.910Z,1528127893.910 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T15:58:13.910Z,1528127893.910 [Default:CheckIn:D] Running Loop=1
2018-06-04T15:58:17.198Z,1528127897.198 [Default:CheckIn:D] Stopped
2018-06-04T15:58:17.198Z,1528127897.198 [Default:CheckIn:E] Running Loop=1
2018-06-04T15:58:17.332Z,1528127897.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.743709 min 
2018-06-04T15:58:17.333Z,1528127897.333 [Default:CheckIn:E] Stopped
2018-06-04T15:58:17.333Z,1528127897.333 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T15:58:17.333Z,1528127897.333 [Default:CheckIn] Stopped
2018-06-04T15:58:17.333Z,1528127897.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T15:58:17.334Z,1528127897.334 [Default:CheckIn](INFO): Running loop #12
2018-06-04T15:58:17.334Z,1528127897.334 [Default:CheckIn] Running Loop=12
2018-06-04T15:58:17.334Z,1528127897.334 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T15:58:17.334Z,1528127897.334 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T15:58:18.059Z,1528127898.059 [NAL9602](INFO): Powering up
2018-06-04T15:58:29.258Z,1528127909.258 [NAL9602](INFO): NAL9602 initialized
2018-06-04T15:58:41.794Z,1528127921.794 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:58:42.589Z,1528127922.589 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T15:58:47.252Z,1528127927.252 [NAL9602](INFO): SBD MO Status=0, MOMSN=21605, MT Status=0, MTMSN=0
2018-06-04T15:58:47.252Z,1528127927.252 [NAL9602](INFO): No messages in MT queue
2018-06-04T15:59:28.913Z,1528127968.913 [NAL9602](INFO): GPS fix at 20180604T155750: (36.807363, -121.785347)
2018-06-04T15:59:28.967Z,1528127968.967 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T15:59:28.967Z,1528127968.967 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T15:59:34.822Z,1528127974.822 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180604T143709/Courier0037.lzma
2018-06-04T15:59:35.617Z,1528127975.617 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0037.lzma.bak
2018-06-04T15:59:35.617Z,1528127975.617 [DataOverHttps](INFO): SBD MOMSN=8312669
2018-06-04T15:59:46.174Z,1528127986.174 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180604T143709/Express0038.lzma
2018-06-04T15:59:46.936Z,1528127986.936 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0038.lzma.bak
2018-06-04T15:59:46.937Z,1528127986.937 [DataOverHttps](INFO): SBD MOMSN=8312672
2018-06-04T15:59:47.440Z,1528127987.440 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T15:59:47.440Z,1528127987.440 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T15:59:47.441Z,1528127987.441 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T16:00:01.606Z,1528128001.606 [NAL9602](INFO): Powering down
2018-06-04T16:00:08.605Z,1528128008.605 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:00:08.605Z,1528128008.605 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-04T16:00:08.605Z,1528128008.605 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:00:08.882Z,1528128008.882 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:00:09.010Z,1528128009.010 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:00:09.099Z,1528128009.099 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:00:10.477Z,1528128010.477 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:00:10.477Z,1528128010.477 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-04T16:00:10.713Z,1528128010.713 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:00:10.713Z,1528128010.713 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:00:10.713Z,1528128010.713 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:00:10.714Z,1528128010.714 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:00:14.927Z,1528128014.927 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:00:15.039Z,1528128015.039 [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-04T16:00:15.040Z,1528128015.040 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:00:15.041Z,1528128015.041 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:00:15.042Z,1528128015.042 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:01:55.094Z,1528128115.094 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:01:55.889Z,1528128115.889 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:02:11.020Z,1528128131.020 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:02:11.020Z,1528128131.020 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-04T16:02:11.020Z,1528128131.020 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:02:11.115Z,1528128131.115 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:02:11.429Z,1528128131.429 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:02:11.616Z,1528128131.616 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:02:13.062Z,1528128133.062 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:02:13.062Z,1528128133.062 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-04T16:02:13.233Z,1528128133.233 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:02:13.233Z,1528128133.233 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:02:13.234Z,1528128133.234 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:02:13.234Z,1528128133.234 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:02:17.363Z,1528128137.363 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:02:17.504Z,1528128137.504 [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-04T16:02:17.505Z,1528128137.505 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:02:17.505Z,1528128137.505 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:02:17.506Z,1528128137.506 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:02:44.732Z,1528128164.732 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-06-04T16:02:44.733Z,1528128164.733 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage nan _
2018-06-04T16:03:01.194Z,1528128181.194 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:03:01.990Z,1528128181.990 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:04:13.981Z,1528128253.981 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:04:13.981Z,1528128253.981 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-04T16:04:13.981Z,1528128253.981 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:04:14.353Z,1528128254.353 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:04:14.385Z,1528128254.385 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:04:14.513Z,1528128254.513 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:04:16.074Z,1528128256.074 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:04:16.074Z,1528128256.074 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-04T16:04:16.129Z,1528128256.129 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:04:16.130Z,1528128256.130 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:04:16.130Z,1528128256.130 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:04:16.131Z,1528128256.131 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:04:20.255Z,1528128260.255 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:04:20.388Z,1528128260.388 [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-04T16:04:20.393Z,1528128260.393 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:04:20.394Z,1528128260.394 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:04:20.394Z,1528128260.394 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:04:36.338Z,1528128276.338 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:04:37.134Z,1528128277.134 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:04:47.948Z,1528128287.948 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T16:04:47.949Z,1528128287.949 [Default:CheckIn:C.Wait] Stopped
2018-06-04T16:04:47.949Z,1528128287.949 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T16:04:47.949Z,1528128287.949 [Default:CheckIn:D] Running Loop=1
2018-06-04T16:04:48.335Z,1528128288.335 [Default:CheckIn:D] Stopped
2018-06-04T16:04:48.335Z,1528128288.335 [Default:CheckIn:E] Running Loop=1
2018-06-04T16:04:48.741Z,1528128288.741 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.262663 min 
2018-06-04T16:04:48.741Z,1528128288.741 [Default:CheckIn:E] Stopped
2018-06-04T16:04:48.741Z,1528128288.741 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T16:04:48.741Z,1528128288.741 [Default:CheckIn] Stopped
2018-06-04T16:04:48.741Z,1528128288.741 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T16:04:48.742Z,1528128288.742 [Default:CheckIn](INFO): Running loop #13
2018-06-04T16:04:48.742Z,1528128288.742 [Default:CheckIn] Running Loop=13
2018-06-04T16:04:48.742Z,1528128288.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T16:04:48.742Z,1528128288.742 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T16:04:49.491Z,1528128289.491 [NAL9602](INFO): Powering up
2018-06-04T16:05:00.578Z,1528128300.578 [NAL9602](INFO): NAL9602 initialized
2018-06-04T16:05:18.188Z,1528128318.188 [NAL9602](INFO): SBD MO Status=0, MOMSN=21606, MT Status=0, MTMSN=0
2018-06-04T16:05:18.188Z,1528128318.188 [NAL9602](INFO): No messages in MT queue
2018-06-04T16:06:14.494Z,1528128374.494 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:06:15.289Z,1528128375.289 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:06:16.386Z,1528128376.386 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:06:16.386Z,1528128376.386 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-04T16:06:16.386Z,1528128376.386 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:06:16.620Z,1528128376.620 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:06:16.789Z,1528128376.789 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:06:16.883Z,1528128376.883 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:06:18.218Z,1528128378.218 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:06:18.218Z,1528128378.218 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-04T16:06:18.497Z,1528128378.497 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:06:18.497Z,1528128378.497 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:06:18.498Z,1528128378.498 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:06:18.498Z,1528128378.498 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:06:22.756Z,1528128382.756 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:06:22.819Z,1528128382.819 [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-04T16:06:22.820Z,1528128382.820 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:06:22.821Z,1528128382.821 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:06:22.822Z,1528128382.822 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:07:34.863Z,1528128454.863 [NAL9602](INFO): GPS fix at 20180604T160555: (36.822069, -121.813846)
2018-06-04T16:07:34.927Z,1528128454.927 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T16:07:34.927Z,1528128454.927 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T16:07:39.596Z,1528128459.596 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180604T143709/Courier0040.lzma
2018-06-04T16:07:40.389Z,1528128460.389 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0040.lzma.bak
2018-06-04T16:07:40.389Z,1528128460.389 [DataOverHttps](INFO): SBD MOMSN=8312691
2018-06-04T16:07:44.699Z,1528128464.699 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 3 sticks.
2018-06-04T16:07:44.699Z,1528128464.699 [BPC1] Data Fault, FailCount= 7
2018-06-04T16:07:44.699Z,1528128464.699 [BPC1](ERROR): Data Fault
2018-06-04T16:07:44.726Z,1528128464.726 [CBIT](ERROR): Data Fault in component: BPC1
2018-06-04T16:07:45.619Z,1528128465.619 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T16:07:45.619Z,1528128465.619 [BPC1] No Fault, FailCount= 7
2018-06-04T16:07:48.630Z,1528128468.630 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:07:49.425Z,1528128469.425 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:07:49.758Z,1528128469.758 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20180604T143709/Express0041.lzma
2018-06-04T16:07:50.546Z,1528128470.546 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0041.lzma.bak
2018-06-04T16:07:50.546Z,1528128470.546 [DataOverHttps](INFO): SBD MOMSN=8312694
2018-06-04T16:07:51.049Z,1528128471.049 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T16:07:51.049Z,1528128471.049 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T16:07:51.049Z,1528128471.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T16:08:09.863Z,1528128489.863 [NAL9602](INFO): Powering down
2018-06-04T16:08:18.564Z,1528128498.564 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:08:18.565Z,1528128498.565 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-04T16:08:18.565Z,1528128498.565 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:08:18.738Z,1528128498.738 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:08:18.739Z,1528128498.739 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-04T16:08:18.969Z,1528128498.969 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:08:19.119Z,1528128499.119 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:12:51.531Z,1528128771.531 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T16:12:51.531Z,1528128771.531 [Default:CheckIn:C.Wait] Stopped
2018-06-04T16:12:51.531Z,1528128771.531 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T16:12:51.531Z,1528128771.531 [Default:CheckIn:D] Running Loop=1
2018-06-04T16:12:51.939Z,1528128771.939 [Default:CheckIn:D] Stopped
2018-06-04T16:12:51.939Z,1528128771.939 [Default:CheckIn:E] Running Loop=1
2018-06-04T16:12:55.125Z,1528128775.125 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.322721 min 
2018-06-04T16:12:55.126Z,1528128775.126 [Default:CheckIn:E] Stopped
2018-06-04T16:12:55.126Z,1528128775.126 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T16:12:55.126Z,1528128775.126 [Default:CheckIn] Stopped
2018-06-04T16:12:55.126Z,1528128775.126 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T16:12:55.126Z,1528128775.126 [Default:CheckIn](INFO): Running loop #14
2018-06-04T16:12:55.126Z,1528128775.126 [Default:CheckIn] Running Loop=14
2018-06-04T16:12:55.126Z,1528128775.126 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T16:12:55.127Z,1528128775.127 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T16:12:55.557Z,1528128775.557 [NAL9602](INFO): Powering up
2018-06-04T16:13:06.750Z,1528128786.750 [NAL9602](INFO): NAL9602 initialized
2018-06-04T16:13:21.552Z,1528128801.552 [NAL9602](INFO): SBD MO Status=0, MOMSN=21607, MT Status=0, MTMSN=0
2018-06-04T16:13:21.552Z,1528128801.552 [NAL9602](INFO): No messages in MT queue
2018-06-04T16:14:58.439Z,1528128898.439 [NAL9602](INFO): GPS fix at 20180604T161318: (36.841712, -121.846255)
2018-06-04T16:14:58.499Z,1528128898.499 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T16:14:58.499Z,1528128898.499 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T16:15:03.262Z,1528128903.262 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180604T143709/Courier0043.lzma
2018-06-04T16:15:04.060Z,1528128904.060 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0043.lzma.bak
2018-06-04T16:15:04.062Z,1528128904.062 [DataOverHttps](INFO): SBD MOMSN=8312717
2018-06-04T16:15:12.975Z,1528128912.975 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20180604T143709/Express0044.lzma
2018-06-04T16:15:13.765Z,1528128913.765 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0044.lzma.bak
2018-06-04T16:15:13.765Z,1528128913.765 [DataOverHttps](INFO): SBD MOMSN=8312720
2018-06-04T16:15:14.326Z,1528128914.326 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T16:15:14.326Z,1528128914.326 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T16:15:14.326Z,1528128914.326 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T16:15:33.118Z,1528128933.118 [NAL9602](INFO): Powering down
2018-06-04T16:18:18.813Z,1528129098.813 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:18:18.813Z,1528129098.813 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-04T16:18:19.029Z,1528129099.029 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:18:19.030Z,1528129099.030 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:18:19.030Z,1528129099.030 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:18:19.030Z,1528129099.030 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:18:23.248Z,1528129103.248 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:18:23.342Z,1528129103.342 [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-04T16:18:23.343Z,1528129103.343 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:18:23.343Z,1528129103.343 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:18:23.344Z,1528129103.344 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:18:31.573Z,1528129111.573 [BPC1](ERROR): Failed to receive data from both battery packs
2018-06-04T16:18:31.573Z,1528129111.573 [BPC1] Communications Fault, FailCount= 8
2018-06-04T16:18:31.573Z,1528129111.573 [BPC1](ERROR): Communications Fault
2018-06-04T16:18:31.631Z,1528129111.631 [CBIT](ERROR): Communications Fault in component: BPC1
2018-06-04T16:18:32.812Z,1528129112.812 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T16:18:32.812Z,1528129112.812 [BPC1] No Fault, FailCount= 8
2018-06-04T16:20:14.844Z,1528129214.844 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T16:20:14.844Z,1528129214.844 [Default:CheckIn:C.Wait] Stopped
2018-06-04T16:20:14.845Z,1528129214.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T16:20:14.845Z,1528129214.845 [Default:CheckIn:D] Running Loop=1
2018-06-04T16:20:18.198Z,1528129218.198 [Default:CheckIn:D] Stopped
2018-06-04T16:20:18.198Z,1528129218.198 [Default:CheckIn:E] Running Loop=1
2018-06-04T16:20:18.341Z,1528129218.341 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.760384 min 
2018-06-04T16:20:18.341Z,1528129218.341 [Default:CheckIn:E] Stopped
2018-06-04T16:20:18.341Z,1528129218.341 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T16:20:18.341Z,1528129218.341 [Default:CheckIn] Stopped
2018-06-04T16:20:18.342Z,1528129218.342 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T16:20:18.342Z,1528129218.342 [Default:CheckIn](INFO): Running loop #15
2018-06-04T16:20:18.342Z,1528129218.342 [Default:CheckIn] Running Loop=15
2018-06-04T16:20:18.342Z,1528129218.342 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T16:20:18.342Z,1528129218.342 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T16:20:19.042Z,1528129219.042 [NAL9602](INFO): Powering up
2018-06-04T16:20:19.505Z,1528129219.505 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:20:19.505Z,1528129219.505 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-04T16:20:19.505Z,1528129219.505 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:20:19.507Z,1528129219.507 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:20:19.909Z,1528129219.909 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:20:20.039Z,1528129220.039 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:20:21.500Z,1528129221.500 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:20:21.500Z,1528129221.500 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-04T16:20:21.654Z,1528129221.654 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:20:21.655Z,1528129221.655 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:20:21.655Z,1528129221.655 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:20:21.655Z,1528129221.655 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:20:25.776Z,1528129225.776 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:20:25.892Z,1528129225.892 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-06-04T16:20:25.893Z,1528129225.893 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:20:25.893Z,1528129225.894 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:20:25.894Z,1528129225.894 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:20:30.234Z,1528129230.234 [NAL9602](INFO): NAL9602 initialized
2018-06-04T16:20:52.636Z,1528129252.636 [NAL9602](INFO): SBD MO Status=2, MOMSN=21608, MT Status=2, MTMSN=0
2018-06-04T16:20:52.636Z,1528129252.636 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:21:23.792Z,1528129283.792 [NAL9602](INFO): SBD MO Status=2, MOMSN=21608, MT Status=2, MTMSN=0
2018-06-04T16:21:23.792Z,1528129283.792 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:21:43.782Z,1528129303.782 [NAL9602](INFO): SBD MO Status=0, MOMSN=21608, MT Status=0, MTMSN=0
2018-06-04T16:21:43.782Z,1528129303.782 [NAL9602](INFO): No messages in MT queue
2018-06-04T16:22:05.395Z,1528129325.395 [NAL9602](INFO): GPS fix at 20180604T162024: (36.860382, -121.878777)
2018-06-04T16:22:05.535Z,1528129325.535 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T16:22:05.536Z,1528129325.536 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T16:22:05.954Z,1528129325.954 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:22:06.750Z,1528129326.750 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:22:11.632Z,1528129331.632 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180604T143709/Courier0046.lzma
2018-06-04T16:22:12.424Z,1528129332.424 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0046.lzma.bak
2018-06-04T16:22:12.424Z,1528129332.424 [DataOverHttps](INFO): SBD MOMSN=8312735
2018-06-04T16:22:21.869Z,1528129341.869 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:22:21.869Z,1528129341.869 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-04T16:22:21.869Z,1528129341.869 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:22:21.950Z,1528129341.950 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:22:22.227Z,1528129342.227 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20180604T143709/Express0047.lzma
2018-06-04T16:22:22.273Z,1528129342.273 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:22:22.447Z,1528129342.447 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:22:23.021Z,1528129343.021 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0047.lzma.bak
2018-06-04T16:22:23.021Z,1528129343.021 [DataOverHttps](INFO): SBD MOMSN=8312738
2018-06-04T16:22:23.553Z,1528129343.553 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T16:22:23.553Z,1528129343.553 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T16:22:23.553Z,1528129343.553 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T16:22:23.902Z,1528129343.902 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:22:23.902Z,1528129343.902 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-04T16:22:24.061Z,1528129344.061 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:22:24.061Z,1528129344.061 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:22:24.061Z,1528129344.061 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:22:24.062Z,1528129344.062 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:22:28.267Z,1528129348.267 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:22:28.440Z,1528129348.440 [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-04T16:22:28.441Z,1528129348.441 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:22:28.441Z,1528129348.441 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:22:28.442Z,1528129348.442 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:22:42.030Z,1528129362.030 [NAL9602](INFO): Powering down
2018-06-04T16:23:40.090Z,1528129420.090 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:23:40.886Z,1528129420.886 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:24:24.845Z,1528129464.845 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:24:24.845Z,1528129464.845 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-04T16:24:24.845Z,1528129464.845 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:24:25.249Z,1528129465.249 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:24:25.406Z,1528129465.406 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:24:27.561Z,1528129467.561 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:24:27.561Z,1528129467.561 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:24:27.561Z,1528129467.561 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-04T16:24:27.829Z,1528129467.829 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:24:27.829Z,1528129467.829 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:24:27.830Z,1528129467.830 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:24:27.830Z,1528129467.830 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:24:32.124Z,1528129472.124 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:24:32.219Z,1528129472.219 [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-04T16:24:32.220Z,1528129472.220 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:24:32.223Z,1528129472.223 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:24:32.223Z,1528129472.223 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:25:15.238Z,1528129515.238 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:25:16.033Z,1528129516.033 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:26:28.037Z,1528129588.037 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:26:28.037Z,1528129588.037 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-04T16:26:28.037Z,1528129588.037 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:26:28.138Z,1528129588.138 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:26:28.451Z,1528129588.451 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:26:28.710Z,1528129588.710 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:26:30.136Z,1528129590.136 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:26:30.145Z,1528129590.145 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-04T16:26:30.325Z,1528129590.325 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:26:30.325Z,1528129590.325 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:26:30.325Z,1528129590.325 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:26:30.326Z,1528129590.326 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:26:34.497Z,1528129594.497 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:26:34.634Z,1528129594.634 [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-04T16:26:34.635Z,1528129594.635 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:26:34.635Z,1528129594.635 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:26:34.636Z,1528129594.636 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:27:24.108Z,1528129644.108 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T16:27:24.108Z,1528129644.108 [Default:CheckIn:C.Wait] Stopped
2018-06-04T16:27:24.108Z,1528129644.108 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T16:27:24.108Z,1528129644.108 [Default:CheckIn:D] Running Loop=1
2018-06-04T16:27:24.496Z,1528129644.496 [Default:CheckIn:D] Stopped
2018-06-04T16:27:24.496Z,1528129644.496 [Default:CheckIn:E] Running Loop=1
2018-06-04T16:27:24.900Z,1528129644.900 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.865340 min 
2018-06-04T16:27:24.900Z,1528129644.900 [Default:CheckIn:E] Stopped
2018-06-04T16:27:24.900Z,1528129644.900 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T16:27:24.900Z,1528129644.900 [Default:CheckIn] Stopped
2018-06-04T16:27:24.901Z,1528129644.901 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T16:27:24.901Z,1528129644.901 [Default:CheckIn](INFO): Running loop #16
2018-06-04T16:27:24.901Z,1528129644.901 [Default:CheckIn] Running Loop=16
2018-06-04T16:27:24.901Z,1528129644.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T16:27:24.901Z,1528129644.901 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T16:27:28.614Z,1528129648.614 [NAL9602](INFO): Powering up
2018-06-04T16:27:39.763Z,1528129659.763 [NAL9602](INFO): NAL9602 initialized
2018-06-04T16:28:00.964Z,1528129680.964 [NAL9602](INFO): SBD MO Status=2, MOMSN=21609, MT Status=2, MTMSN=0
2018-06-04T16:28:00.964Z,1528129680.964 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:28:30.430Z,1528129710.430 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:28:30.430Z,1528129710.430 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-04T16:28:30.430Z,1528129710.430 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:28:30.609Z,1528129710.609 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:28:30.610Z,1528129710.610 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-04T16:28:30.833Z,1528129710.833 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:28:30.970Z,1528129710.970 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:28:46.128Z,1528129726.128 [NAL9602](INFO): SBD MO Status=2, MOMSN=21609, MT Status=2, MTMSN=0
2018-06-04T16:28:46.128Z,1528129726.128 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:29:56.652Z,1528129796.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=21609, MT Status=2, MTMSN=0
2018-06-04T16:29:56.652Z,1528129796.652 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:30:07.448Z,1528129807.448 [NAL9602](INFO): SBD MO Status=2, MOMSN=21609, MT Status=2, MTMSN=0
2018-06-04T16:30:07.448Z,1528129807.448 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:31:01.116Z,1528129861.116 [NAL9602](INFO): SBD MO Status=0, MOMSN=21609, MT Status=0, MTMSN=0
2018-06-04T16:31:01.116Z,1528129861.116 [NAL9602](INFO): No messages in MT queue
2018-06-04T16:31:02.318Z,1528129862.318 [NAL9602](INFO): GPS fix at 20180604T162920: (36.883423, -121.920843)
2018-06-04T16:31:02.369Z,1528129862.369 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T16:31:02.369Z,1528129862.369 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T16:31:07.190Z,1528129867.190 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180604T143709/Courier0049.lzma
2018-06-04T16:31:07.988Z,1528129867.988 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0049.lzma.bak
2018-06-04T16:31:07.989Z,1528129867.989 [DataOverHttps](INFO): SBD MOMSN=8312752
2018-06-04T16:31:18.979Z,1528129878.979 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20180604T143709/Express0050.lzma
2018-06-04T16:31:19.756Z,1528129879.756 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0050.lzma.bak
2018-06-04T16:31:19.756Z,1528129879.756 [DataOverHttps](INFO): SBD MOMSN=8312755
2018-06-04T16:31:20.256Z,1528129880.256 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T16:31:20.257Z,1528129880.257 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T16:31:20.257Z,1528129880.257 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T16:31:36.970Z,1528129896.970 [NAL9602](INFO): Powering down
2018-06-04T16:36:20.824Z,1528130180.824 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T16:36:20.825Z,1528130180.825 [Default:CheckIn:C.Wait] Stopped
2018-06-04T16:36:20.825Z,1528130180.825 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T16:36:20.825Z,1528130180.825 [Default:CheckIn:D] Running Loop=1
2018-06-04T16:36:21.223Z,1528130181.223 [Default:CheckIn:D] Stopped
2018-06-04T16:36:21.223Z,1528130181.223 [Default:CheckIn:E] Running Loop=1
2018-06-04T16:36:25.603Z,1528130185.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.810783 min 
2018-06-04T16:36:25.603Z,1528130185.603 [Default:CheckIn:E] Stopped
2018-06-04T16:36:25.604Z,1528130185.604 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T16:36:25.604Z,1528130185.604 [Default:CheckIn] Stopped
2018-06-04T16:36:25.604Z,1528130185.604 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T16:36:25.604Z,1528130185.604 [Default:CheckIn](INFO): Running loop #17
2018-06-04T16:36:25.604Z,1528130185.604 [Default:CheckIn] Running Loop=17
2018-06-04T16:36:25.604Z,1528130185.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T16:36:25.604Z,1528130185.604 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T16:36:26.038Z,1528130186.038 [NAL9602](INFO): Powering up
2018-06-04T16:36:37.238Z,1528130197.238 [NAL9602](INFO): NAL9602 initialized
2018-06-04T16:37:08.040Z,1528130228.040 [NAL9602](INFO): SBD MO Status=2, MOMSN=21610, MT Status=2, MTMSN=0
2018-06-04T16:37:08.040Z,1528130228.040 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:37:32.156Z,1528130252.156 [NAL9602](INFO): SBD MO Status=2, MOMSN=21610, MT Status=2, MTMSN=0
2018-06-04T16:37:32.156Z,1528130252.156 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:37:46.948Z,1528130266.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=21610, MT Status=2, MTMSN=0
2018-06-04T16:37:46.948Z,1528130266.948 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:38:14.948Z,1528130294.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=21610, MT Status=2, MTMSN=0
2018-06-04T16:38:14.948Z,1528130294.948 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T16:38:30.453Z,1528130310.453 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:38:30.453Z,1528130310.453 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-04T16:38:30.497Z,1528130310.497 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:38:30.498Z,1528130310.498 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:38:30.498Z,1528130310.498 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:38:30.498Z,1528130310.498 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:38:34.626Z,1528130314.626 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:38:34.691Z,1528130314.691 [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-04T16:38:34.692Z,1528130314.692 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:38:34.693Z,1528130314.693 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:38:34.694Z,1528130314.694 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:38:50.392Z,1528130330.392 [NAL9602](INFO): SBD MO Status=0, MOMSN=21610, MT Status=0, MTMSN=0
2018-06-04T16:38:50.392Z,1528130330.392 [NAL9602](INFO): No messages in MT queue
2018-06-04T16:39:09.595Z,1528130349.595 [NAL9602](INFO): GPS fix at 20180604T163727: (36.905233, -121.964231)
2018-06-04T16:39:09.717Z,1528130349.717 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T16:39:09.717Z,1528130349.717 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T16:39:18.438Z,1528130358.438 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180604T143709/Courier0052.lzma
2018-06-04T16:39:19.224Z,1528130359.224 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0052.lzma.bak
2018-06-04T16:39:19.225Z,1528130359.225 [DataOverHttps](INFO): SBD MOMSN=8312776
2018-06-04T16:39:28.801Z,1528130368.801 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20180604T143709/Express0053.lzma
2018-06-04T16:39:29.600Z,1528130369.600 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0053.lzma.bak
2018-06-04T16:39:29.601Z,1528130369.601 [DataOverHttps](INFO): SBD MOMSN=8312779
2018-06-04T16:39:30.107Z,1528130370.107 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T16:39:30.107Z,1528130370.107 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T16:39:30.107Z,1528130370.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T16:39:34.012Z,1528130374.012 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 3 sticks.
2018-06-04T16:39:34.012Z,1528130374.012 [BPC1] Data Fault, FailCount= 9
2018-06-04T16:39:34.012Z,1528130374.012 [BPC1](ERROR): Data Fault
2018-06-04T16:39:34.096Z,1528130374.096 [CBIT](ERROR): Data Fault in component: BPC1
2018-06-04T16:39:34.991Z,1528130374.991 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T16:39:34.991Z,1528130374.991 [BPC1] No Fault, FailCount= 9
2018-06-04T16:39:42.118Z,1528130382.118 [NAL9602](INFO): Powering down
2018-06-04T16:40:28.642Z,1528130428.642 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:40:29.437Z,1528130429.437 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:40:30.532Z,1528130430.532 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:40:30.532Z,1528130430.532 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-04T16:40:30.532Z,1528130430.532 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:40:30.651Z,1528130430.651 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:40:30.937Z,1528130430.937 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:40:31.096Z,1528130431.096 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:40:33.836Z,1528130433.836 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:40:33.836Z,1528130433.836 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-04T16:40:33.925Z,1528130433.925 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:40:33.926Z,1528130433.926 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:40:33.926Z,1528130433.926 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:40:33.926Z,1528130433.926 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:40:38.064Z,1528130438.064 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:40:38.122Z,1528130438.122 [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-04T16:40:38.123Z,1528130438.123 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:40:38.124Z,1528130438.124 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:40:38.124Z,1528130438.124 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:42:31.838Z,1528130551.838 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:42:32.633Z,1528130552.633 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:42:34.521Z,1528130554.521 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:42:34.521Z,1528130554.521 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-04T16:42:34.521Z,1528130554.521 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:42:34.657Z,1528130554.657 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:42:34.925Z,1528130554.925 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:42:35.019Z,1528130555.019 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:42:36.364Z,1528130556.364 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:42:36.364Z,1528130556.364 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-04T16:42:36.633Z,1528130556.633 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:42:36.633Z,1528130556.633 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:42:36.634Z,1528130556.634 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:42:36.634Z,1528130556.634 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:42:40.881Z,1528130560.881 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:42:40.955Z,1528130560.955 [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-04T16:42:40.956Z,1528130560.956 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:42:40.957Z,1528130560.957 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:42:40.958Z,1528130560.958 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:44:06.981Z,1528130646.981 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:07.777Z,1528130647.777 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:08.574Z,1528130648.574 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:09.273Z,1528130649.273 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:10.069Z,1528130650.069 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:10.866Z,1528130650.866 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:11.277Z,1528130651.277 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:12.073Z,1528130652.073 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:12.737Z,1528130652.737 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:13.534Z,1528130653.534 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:14.330Z,1528130654.330 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:14.741Z,1528130654.741 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:15.537Z,1528130655.537 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:16.334Z,1528130656.334 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:16.757Z,1528130656.757 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:17.553Z,1528130657.553 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:18.350Z,1528130658.350 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:18.813Z,1528130658.813 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:19.609Z,1528130659.609 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:20.406Z,1528130660.406 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:21.005Z,1528130661.005 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:21.801Z,1528130661.801 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:22.597Z,1528130662.597 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:23.297Z,1528130663.297 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:24.093Z,1528130664.093 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:24.890Z,1528130664.890 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:25.301Z,1528130665.301 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:26.097Z,1528130666.097 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:26.763Z,1528130666.763 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:27.557Z,1528130667.557 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:28.354Z,1528130668.354 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:28.769Z,1528130668.769 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:29.565Z,1528130669.565 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:30.362Z,1528130670.362 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:30.694Z,1528130670.694 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T16:44:30.694Z,1528130670.694 [Default:CheckIn:C.Wait] Stopped
2018-06-04T16:44:30.694Z,1528130670.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T16:44:30.695Z,1528130670.695 [Default:CheckIn:D] Running Loop=1
2018-06-04T16:44:30.781Z,1528130670.781 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:31.108Z,1528130671.108 [Default:CheckIn:D] Stopped
2018-06-04T16:44:31.108Z,1528130671.108 [Default:CheckIn:E] Running Loop=1
2018-06-04T16:44:31.506Z,1528130671.506 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.975537 min 
2018-06-04T16:44:31.506Z,1528130671.506 [Default:CheckIn:E] Stopped
2018-06-04T16:44:31.506Z,1528130671.506 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T16:44:31.506Z,1528130671.506 [Default:CheckIn] Stopped
2018-06-04T16:44:31.507Z,1528130671.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T16:44:31.507Z,1528130671.507 [Default:CheckIn](INFO): Running loop #18
2018-06-04T16:44:31.507Z,1528130671.507 [Default:CheckIn] Running Loop=18
2018-06-04T16:44:31.507Z,1528130671.507 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T16:44:31.507Z,1528130671.507 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T16:44:31.577Z,1528130671.577 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:32.374Z,1528130672.374 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:32.833Z,1528130672.833 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:33.632Z,1528130673.632 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:34.426Z,1528130674.426 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:35.029Z,1528130675.029 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:35.102Z,1528130675.102 [NAL9602](INFO): Powering up
2018-06-04T16:44:35.826Z,1528130675.826 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:36.622Z,1528130676.622 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:44:36.917Z,1528130676.917 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:44:36.917Z,1528130676.917 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-06-04T16:44:36.917Z,1528130676.917 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:44:37.163Z,1528130677.163 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:44:37.321Z,1528130677.321 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:44:37.413Z,1528130677.413 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:44:38.221Z,1528130678.221 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-06-04T16:44:38.221Z,1528130678.221 [Rowe_600LCM] Hardware Fault, FailCount= 3
2018-06-04T16:44:38.221Z,1528130678.221 [Rowe_600LCM](ERROR): Hardware Fault
2018-06-04T16:44:38.759Z,1528130678.759 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:44:38.759Z,1528130678.759 [Rowe_600LCM] No Fault, FailCount= 3
2018-06-04T16:44:39.029Z,1528130679.029 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:44:39.029Z,1528130679.029 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:44:39.030Z,1528130679.030 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:44:39.030Z,1528130679.030 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:44:43.282Z,1528130683.282 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:44:43.351Z,1528130683.351 [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-04T16:44:43.352Z,1528130683.352 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:44:43.357Z,1528130683.357 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:44:43.357Z,1528130683.357 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:44:46.286Z,1528130686.286 [NAL9602](INFO): NAL9602 initialized
2018-06-04T16:45:14.280Z,1528130714.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=21611, MT Status=0, MTMSN=0
2018-06-04T16:45:14.280Z,1528130714.280 [NAL9602](INFO): No messages in MT queue
2018-06-04T16:45:41.142Z,1528130741.142 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:45:41.938Z,1528130741.938 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:46:39.106Z,1528130799.106 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:46:39.106Z,1528130799.106 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-04T16:46:39.106Z,1528130799.106 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:46:39.165Z,1528130799.165 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:46:39.509Z,1528130799.509 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:46:39.606Z,1528130799.606 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:46:41.070Z,1528130801.070 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:46:41.070Z,1528130801.070 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-04T16:46:41.221Z,1528130801.221 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:46:41.221Z,1528130801.221 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:46:41.222Z,1528130801.222 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:46:41.222Z,1528130801.222 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:46:45.352Z,1528130805.352 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:46:45.529Z,1528130805.529 [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-04T16:46:45.530Z,1528130805.530 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:46:45.531Z,1528130805.531 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:46:45.532Z,1528130805.532 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T16:47:15.270Z,1528130835.270 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:47:16.065Z,1528130836.065 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T16:47:41.016Z,1528130861.016 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.388785 seconds old.
2018-06-04T16:47:41.401Z,1528130861.401 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.774003 seconds old.
2018-06-04T16:47:41.817Z,1528130861.817 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.190318 seconds old.
2018-06-04T16:47:42.211Z,1528130862.211 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.583904 seconds old.
2018-06-04T16:48:41.285Z,1528130921.285 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T16:48:41.285Z,1528130921.285 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-06-04T16:48:41.285Z,1528130921.285 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T16:48:41.418Z,1528130921.418 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T16:48:41.418Z,1528130921.418 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-06-04T16:48:41.689Z,1528130921.689 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T16:48:41.918Z,1528130921.918 [Rowe_600LCM](INFO): Powering down
2018-06-04T16:49:31.828Z,1528130971.828 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-04T16:44:31.5Z
2018-06-04T16:49:31.828Z,1528130971.828 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T16:49:31.829Z,1528130971.829 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T16:49:36.654Z,1528130976.654 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180604T143709/Courier0055.lzma
2018-06-04T16:49:37.453Z,1528130977.453 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0055.lzma.bak
2018-06-04T16:49:37.453Z,1528130977.453 [DataOverHttps](INFO): SBD MOMSN=8312790
2018-06-04T16:49:59.655Z,1528130999.655 [DataOverHttps](INFO): Sending 351 bytes from file Logs/20180604T143709/Express0056.lzma
2018-06-04T16:50:02.453Z,1528131002.453 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0056.lzma.bak
2018-06-04T16:50:02.453Z,1528131002.453 [DataOverHttps](INFO): SBD MOMSN=8312792
2018-06-04T16:50:02.964Z,1528131002.964 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T16:50:02.965Z,1528131002.965 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T16:50:02.965Z,1528131002.965 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T16:50:03.254Z,1528131003.254 [NAL9602](INFO): Powering down
2018-06-04T16:51:10.559Z,1528131070.559 [BPC1](ERROR): Bank A: No match for stick 5 serial number:1717 in the onboard configuration file.
2018-06-04T16:51:10.559Z,1528131070.559 [BPC1](ERROR): Failed to parse Bank A battery data
2018-06-04T16:55:03.731Z,1528131303.731 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T16:55:03.731Z,1528131303.731 [Default:CheckIn:C.Wait] Stopped
2018-06-04T16:55:03.731Z,1528131303.731 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T16:55:03.732Z,1528131303.732 [Default:CheckIn:D] Running Loop=1
2018-06-04T16:55:04.144Z,1528131304.144 [Default:CheckIn:D] Stopped
2018-06-04T16:55:04.144Z,1528131304.144 [Default:CheckIn:E] Running Loop=1
2018-06-04T16:55:04.534Z,1528131304.534 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.526147 min 
2018-06-04T16:55:04.534Z,1528131304.534 [Default:CheckIn:E] Stopped
2018-06-04T16:55:04.534Z,1528131304.534 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T16:55:04.535Z,1528131304.535 [Default:CheckIn] Stopped
2018-06-04T16:55:04.535Z,1528131304.535 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T16:55:04.535Z,1528131304.535 [Default:CheckIn](INFO): Running loop #19
2018-06-04T16:55:04.535Z,1528131304.535 [Default:CheckIn] Running Loop=19
2018-06-04T16:55:04.535Z,1528131304.535 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T16:55:04.535Z,1528131304.535 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T16:55:08.075Z,1528131308.075 [NAL9602](INFO): Powering up
2018-06-04T16:55:18.986Z,1528131318.986 [NAL9602](INFO): NAL9602 initialized
2018-06-04T16:55:19.782Z,1528131319.782 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-06-04T16:55:19.783Z,1528131319.783 [NAL9602] Data Fault, FailCount= 1
2018-06-04T16:55:19.783Z,1528131319.783 [NAL9602](ERROR): Data Fault
2018-06-04T16:55:19.885Z,1528131319.885 [CBIT](ERROR): Data Fault in component: NAL9602
2018-06-04T16:55:20.181Z,1528131320.181 [NAL9602](INFO): Powering down
2018-06-04T16:55:21.046Z,1528131321.046 [CBIT](INFO): Clearing failed state for component NAL9602
2018-06-04T16:55:21.046Z,1528131321.046 [NAL9602] No Fault, FailCount= 1
2018-06-04T16:55:50.582Z,1528131350.582 [NAL9602](INFO): Powering up NAL9602
2018-06-04T16:56:01.383Z,1528131361.383 [NAL9602](INFO): NAL9602 initialized
2018-06-04T16:56:20.696Z,1528131380.696 [NAL9602](INFO): SBD MO Status=0, MOMSN=21612, MT Status=0, MTMSN=0
2018-06-04T16:56:20.696Z,1528131380.696 [NAL9602](INFO): No messages in MT queue
2018-06-04T16:56:57.099Z,1528131417.099 [NAL9602](INFO): GPS fix at 20180604T165512: (36.923212, -122.041936)
2018-06-04T16:56:57.156Z,1528131417.156 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T16:56:57.156Z,1528131417.156 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T16:57:02.263Z,1528131422.263 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180604T143709/Courier0058.lzma
2018-06-04T16:57:03.065Z,1528131423.065 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Courier0058.lzma.bak
2018-06-04T16:57:03.065Z,1528131423.065 [DataOverHttps](INFO): SBD MOMSN=8312811
2018-06-04T16:57:12.837Z,1528131432.837 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20180604T143709/Express0059.lzma
2018-06-04T16:57:13.632Z,1528131433.632 [DataOverHttps](INFO): Moved sent file to Logs/20180604T143709/Express0059.lzma.bak
2018-06-04T16:57:13.633Z,1528131433.633 [DataOverHttps](INFO): SBD MOMSN=8312814
2018-06-04T16:57:14.131Z,1528131434.131 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T16:57:14.131Z,1528131434.131 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T16:57:14.132Z,1528131434.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T16:57:32.450Z,1528131452.450 [NAL9602](INFO): Powering down
2018-06-04T16:58:41.320Z,1528131521.320 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T16:58:41.321Z,1528131521.321 [Rowe_600LCM] No Fault, FailCount= 5
2018-06-04T16:58:41.705Z,1528131521.705 [Rowe_600LCM](INFO): Initializing
2018-06-04T16:58:41.705Z,1528131521.705 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T16:58:41.705Z,1528131521.705 [Rowe_600LCM](INFO): LCM OK
2018-06-04T16:58:41.705Z,1528131521.705 [Rowe_600LCM](INFO): Powering up
2018-06-04T16:58:45.861Z,1528131525.861 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T16:58:45.920Z,1528131525.920 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-06-04T16:58:45.921Z,1528131525.921 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T16:58:45.922Z,1528131525.922 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T16:58:45.923Z,1528131525.923 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T17:00:11.478Z,1528131611.478 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T17:00:12.276Z,1528131612.276 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-04T17:00:35.652Z,1528131635.652 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 3 sticks.
2018-06-04T17:00:35.652Z,1528131635.652 [BPC1] Data Fault, FailCount= 10
2018-06-04T17:00:35.652Z,1528131635.652 [BPC1](ERROR): Data Fault
2018-06-04T17:00:35.678Z,1528131635.678 [CBIT](ERROR): Data Fault in component: BPC1
2018-06-04T17:00:35.678Z,1528131635.678 [CBIT](CRITICAL): Data Fault in component: BPC1
2018-06-04T17:00:36.074Z,1528131636.074 [CommandLine](FAULT): Scheduling is paused
2018-06-04T17:00:36.074Z,1528131636.074 [CBIT](INFO): Critical error at 20180604T170035
2018-06-04T17:00:36.075Z,1528131636.075 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-06-04T17:00:42.189Z,1528131642.189 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T17:00:42.189Z,1528131642.189 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-06-04T17:00:42.189Z,1528131642.189 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T17:00:42.482Z,1528131642.482 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T17:00:42.593Z,1528131642.593 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T17:00:42.701Z,1528131642.701 [Rowe_600LCM](INFO): Powering down
2018-06-04T17:00:44.077Z,1528131644.077 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T17:00:44.077Z,1528131644.077 [Rowe_600LCM] No Fault, FailCount= 1
2018-06-04T17:00:44.317Z,1528131644.317 [Rowe_600LCM](INFO): Initializing
2018-06-04T17:00:44.317Z,1528131644.317 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T17:00:44.318Z,1528131644.318 [Rowe_600LCM](INFO): LCM OK
2018-06-04T17:00:44.318Z,1528131644.318 [Rowe_600LCM](INFO): Powering up
2018-06-04T17:00:48.516Z,1528131648.516 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T17:00:48.631Z,1528131648.631 [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-04T17:00:48.632Z,1528131648.632 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T17:00:48.633Z,1528131648.633 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T17:00:48.634Z,1528131648.634 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T17:02:14.758Z,1528131734.758 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-06-04T17:02:14.758Z,1528131734.758 [Default:CheckIn:C.Wait] Stopped
2018-06-04T17:02:14.758Z,1528131734.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-06-04T17:02:14.758Z,1528131734.758 [Default:CheckIn:D] Running Loop=1
2018-06-04T17:02:15.157Z,1528131735.157 [Default:CheckIn:D] Stopped
2018-06-04T17:02:15.157Z,1528131735.157 [Default:CheckIn:E] Running Loop=1
2018-06-04T17:02:15.558Z,1528131735.558 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.709701 min 
2018-06-04T17:02:15.558Z,1528131735.558 [Default:CheckIn:E] Stopped
2018-06-04T17:02:15.558Z,1528131735.558 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-06-04T17:02:15.558Z,1528131735.558 [Default:CheckIn] Stopped
2018-06-04T17:02:15.559Z,1528131735.559 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-04T17:02:15.559Z,1528131735.559 [Default:CheckIn](INFO): Running loop #20
2018-06-04T17:02:15.559Z,1528131735.559 [Default:CheckIn] Running Loop=20
2018-06-04T17:02:15.559Z,1528131735.559 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-06-04T17:02:15.559Z,1528131735.559 [Default:CheckIn:Read_GPS] Running Loop=1
2018-06-04T17:02:16.282Z,1528131736.282 [NAL9602](INFO): Powering up
2018-06-04T17:02:27.122Z,1528131747.122 [NAL9602](INFO): NAL9602 initialized
2018-06-04T17:02:44.591Z,1528131764.591 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-04T17:02:44.591Z,1528131764.591 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-06-04T17:02:44.591Z,1528131764.591 [Rowe_600LCM](ERROR): Communications Fault
2018-06-04T17:02:44.796Z,1528131764.796 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-04T17:02:44.993Z,1528131764.993 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-04T17:02:45.086Z,1528131765.086 [Rowe_600LCM](INFO): Powering down
2018-06-04T17:02:46.402Z,1528131766.402 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-04T17:02:46.402Z,1528131766.402 [Rowe_600LCM] No Fault, FailCount= 2
2018-06-04T17:02:46.703Z,1528131766.703 [Rowe_600LCM](INFO): Initializing
2018-06-04T17:02:46.703Z,1528131766.703 [Rowe_600LCM](INFO): Checking LCM
2018-06-04T17:02:46.703Z,1528131766.703 [Rowe_600LCM](INFO): LCM OK
2018-06-04T17:02:46.703Z,1528131766.703 [Rowe_600LCM](INFO): Powering up
2018-06-04T17:02:49.120Z,1528131769.120 [NAL9602](INFO): SBD MO Status=0, MOMSN=21613, MT Status=0, MTMSN=0
2018-06-04T17:02:49.120Z,1528131769.120 [NAL9602](INFO): No messages in MT queue
2018-06-04T17:02:50.939Z,1528131770.939 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-04T17:02:51.007Z,1528131771.007 [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-04T17:02:51.008Z,1528131771.008 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-04T17:02:51.009Z,1528131771.009 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-04T17:02:51.010Z,1528131771.010 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-04T17:03:36.066Z,1528131816.066 [Radio_Surface](INFO): Powering down
2018-06-04T17:03:36.387Z,1528131816.387 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (1 active estimators).
2018-06-04T17:03:48.606Z,1528131828.606 [Radio_Surface](INFO): Powering up
2018-06-04T17:04:24.074Z,1528131864.074 [NAL9602](INFO): GPS fix at 20180604T170239: (36.923909, -122.041133)
2018-06-04T17:04:24.134Z,1528131864.134 [Default:CheckIn:Read_GPS] Stopped
2018-06-04T17:04:24.134Z,1528131864.134 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-06-04T17:04:45.699Z,1528131885.699 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-04T17:04:56.502Z,1528131896.502 [NAL9602](INFO): Powering down
2018-06-04T17:05:16.499Z,1528131916.499 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-04T17:05:35.766Z,1528131935.766 [CBIT](INFO): Clearing failed state for component BPC1
2018-06-04T17:05:35.766Z,1528131935.766 [BPC1] No Fault, FailCount= 10
2018-06-04T17:05:47.299Z,1528131947.299 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-04T17:06:17.699Z,1528131977.699 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-04T17:06:22.099Z,1528131982.099 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.167313
2018-06-04T17:06:22.102Z,1528131982.102 [NAL9602](INFO): Powering up
2018-06-04T17:06:33.302Z,1528131993.302 [NAL9602](INFO): NAL9602 initialized
2018-06-04T17:06:52.595Z,1528132012.595 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-06-04T17:06:53.404Z,1528132013.404 [NAL9602](INFO): SBD MO Status=1, MOMSN=21614, MT Status=0, MTMSN=0
2018-06-04T17:06:53.453Z,1528132013.453 [NAL9602](INFO): Sent 126 bytes from file Logs/20180604T143709/Courier0061.lzma
2018-06-04T17:06:53.453Z,1528132013.453 [NAL9602](INFO): Packets left to send: 0
2018-06-04T17:06:53.455Z,1528132013.455 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180604T143709/Courier0061.lzma.parts/0000.sbd
2018-06-04T17:06:53.455Z,1528132013.455 [NAL9602](DEBUG): Completed sending Logs/20180604T143709/Courier0061.lzma
2018-06-04T17:07:06.380Z,1528132026.380 [NAL9602](INFO): SBD MO Status=1, MOMSN=21615, MT Status=0, MTMSN=0
2018-06-04T17:07:06.429Z,1528132026.429 [NAL9602](INFO): Sent 315 bytes from file Logs/20180604T143709/Express0062.lzma
2018-06-04T17:07:06.429Z,1528132026.429 [NAL9602](INFO): Packets left to send: 0
2018-06-04T17:07:06.431Z,1528132026.431 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180604T143709/Express0062.lzma.parts/0000.sbd
2018-06-04T17:07:06.431Z,1528132026.431 [NAL9602](DEBUG): Completed sending Logs/20180604T143709/Express0062.lzma
2018-06-04T17:07:27.472Z,1528132047.472 [DataOverHttps](IMPORTANT): SBD MTMSN=20180604T170543
2018-06-04T17:07:27.475Z,1528132047.475 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003136
2018-06-04T17:07:28.031Z,1528132048.031 [Default:CheckIn:Read_Iridium] Stopped
2018-06-04T17:07:28.031Z,1528132048.031 [Default:CheckIn:C.Wait] Running Loop=1
2018-06-04T17:07:28.031Z,1528132048.031 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-06-04T17:07:31.728Z,1528132051.728 [NAL9602](INFO): SBD MO Status=2, MOMSN=21616, MT Status=2, MTMSN=0
2018-06-04T17:07:31.729Z,1528132051.729 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-06-04T17:07:31.844Z,1528132051.844 [DataOverHttps](INFO): Received command:restart logs