2018-08-27T19:16:18.801Z,1535397378.801 [Supervisor](DEBUG): Initializing supervisor.
2018-08-27T19:16:18.804Z,1535397378.804 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-08-27T19:16:18.804Z,1535397378.804 [SyncHandler](INFO): Protected caller Thread ID is 1821
2018-08-27T19:16:18.805Z,1535397378.805 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-08-27T19:16:18.805Z,1535397378.805 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-08-27T19:16:18.806Z,1535397378.806 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1822
2018-08-27T19:16:18.809Z,1535397378.809 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-08-27T19:16:18.820Z,1535397378.820 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-08-27T19:16:18.821Z,1535397378.821 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-08-27T19:16:18.821Z,1535397378.821 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1823
2018-08-27T19:16:18.822Z,1535397378.822 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-08-27T19:16:18.823Z,1535397378.823 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-08-27T19:16:18.824Z,1535397378.824 [logger ThreadHandler](INFO): Protected caller Thread ID is 1824
2018-08-27T19:16:18.826Z,1535397378.826 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-08-27T19:16:18.826Z,1535397378.826 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-08-27T19:16:18.828Z,1535397378.828 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-08-27T19:16:18.945Z,1535397378.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-08-27T19:16:18.946Z,1535397378.946 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-08-27T19:16:19.049Z,1535397379.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-08-27T19:16:19.049Z,1535397379.049 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-08-27T19:16:19.262Z,1535397379.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-08-27T19:16:19.263Z,1535397379.263 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-08-27T19:16:19.600Z,1535397379.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-08-27T19:16:19.601Z,1535397379.601 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-08-27T19:16:19.952Z,1535397379.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-08-27T19:16:19.953Z,1535397379.953 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-08-27T19:16:20.427Z,1535397380.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-08-27T19:16:20.428Z,1535397380.428 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-08-27T19:16:20.863Z,1535397380.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-08-27T19:16:20.863Z,1535397380.863 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-08-27T19:16:21.638Z,1535397381.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-08-27T19:16:21.639Z,1535397381.639 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-08-27T19:16:21.721Z,1535397381.721 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-08-27T19:16:22.033Z,1535397382.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-08-27T19:16:22.034Z,1535397382.034 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-08-27T19:16:22.183Z,1535397382.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-08-27T19:16:22.183Z,1535397382.183 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-08-27T19:16:22.398Z,1535397382.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-08-27T19:16:22.398Z,1535397382.398 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-08-27T19:16:22.617Z,1535397382.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-08-27T19:16:22.617Z,1535397382.617 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-08-27T19:16:22.718Z,1535397382.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-08-27T19:16:22.718Z,1535397382.718 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-08-27T19:16:22.864Z,1535397382.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-08-27T19:16:22.866Z,1535397382.866 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-08-27T19:16:22.867Z,1535397382.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-08-27T19:16:23.104Z,1535397383.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-08-27T19:16:23.105Z,1535397383.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-08-27T19:16:23.211Z,1535397383.211 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-08-27T19:16:23.358Z,1535397383.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-08-27T19:16:23.454Z,1535397383.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-08-27T19:16:23.540Z,1535397383.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-08-27T19:16:23.686Z,1535397383.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-08-27T19:16:23.872Z,1535397383.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-08-27T19:16:23.971Z,1535397383.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-08-27T19:16:24.068Z,1535397384.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-08-27T19:16:24.168Z,1535397384.168 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-08-27T19:16:24.252Z,1535397384.252 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-08-27T19:16:24.265Z,1535397384.265 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-08-27T19:16:24.364Z,1535397384.364 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-08-27T19:16:24.365Z,1535397384.365 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-08-27T19:16:24.378Z,1535397384.378 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-08-27T19:16:24.378Z,1535397384.378 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-08-27T19:16:24.419Z,1535397384.419 [DepthRateCalculator] Loaded
2018-08-27T19:16:24.419Z,1535397384.419 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-08-27T19:16:24.425Z,1535397384.425 [PitchRateCalculator] Loaded
2018-08-27T19:16:24.426Z,1535397384.426 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-08-27T19:16:24.442Z,1535397384.442 [SpeedCalculator] Loaded
2018-08-27T19:16:24.442Z,1535397384.442 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-08-27T19:16:24.463Z,1535397384.463 [TempGradientCalculator] Loaded
2018-08-27T19:16:24.464Z,1535397384.464 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-08-27T19:16:24.479Z,1535397384.479 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-08-27T19:16:24.479Z,1535397384.479 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-08-27T19:16:24.485Z,1535397384.485 [YawRateCalculator] Loaded
2018-08-27T19:16:24.485Z,1535397384.485 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-08-27T19:16:24.525Z,1535397384.525 [ElevatorOffsetCalculator] Loaded
2018-08-27T19:16:24.526Z,1535397384.526 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-08-27T19:16:24.526Z,1535397384.526 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-08-27T19:16:24.527Z,1535397384.527 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-08-27T19:16:24.551Z,1535397384.551 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-08-27T19:16:24.552Z,1535397384.552 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-08-27T19:16:24.830Z,1535397384.830 [DataOverHttps] Loaded
2018-08-27T19:16:24.830Z,1535397384.830 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-08-27T19:16:24.856Z,1535397384.856 [Depth_Keller] Loaded
2018-08-27T19:16:24.856Z,1535397384.856 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-08-27T19:16:24.955Z,1535397384.955 [NAL9602] Loaded
2018-08-27T19:16:24.955Z,1535397384.955 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-08-27T19:16:24.971Z,1535397384.971 [Onboard] Loaded
2018-08-27T19:16:24.971Z,1535397384.971 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-08-27T19:16:24.981Z,1535397384.981 [Radio_Surface] Loaded
2018-08-27T19:16:24.981Z,1535397384.981 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-08-27T19:16:24.982Z,1535397384.982 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B84E0
2018-08-27T19:16:24.983Z,1535397384.983 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1903
2018-08-27T19:16:25.031Z,1535397385.031 [PNI_TCM] Loaded
2018-08-27T19:16:25.031Z,1535397385.031 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-08-27T19:16:25.092Z,1535397385.092 [Rowe_600LCM] Loaded
2018-08-27T19:16:25.092Z,1535397385.092 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-08-27T19:16:25.093Z,1535397385.093 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407E84E0
2018-08-27T19:16:25.094Z,1535397385.094 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1904
2018-08-27T19:16:26.960Z,1535397386.960 [BPC1] Loaded
2018-08-27T19:16:26.960Z,1535397386.960 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-08-27T19:16:26.964Z,1535397386.964 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-08-27T19:16:26.965Z,1535397386.965 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-08-27T19:16:27.309Z,1535397387.309 [SBIT](DEBUG): Construct Startup Built In Test.
2018-08-27T19:16:27.333Z,1535397387.333 [SBIT] Loaded
2018-08-27T19:16:27.333Z,1535397387.333 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-08-27T19:16:27.334Z,1535397387.334 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-08-27T19:16:27.346Z,1535397387.346 [IBIT] Loaded
2018-08-27T19:16:27.346Z,1535397387.346 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-08-27T19:16:27.349Z,1535397387.349 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-08-27T19:16:27.499Z,1535397387.499 [CBIT] Loaded
2018-08-27T19:16:27.499Z,1535397387.499 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-08-27T19:16:27.499Z,1535397387.499 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-08-27T19:16:27.500Z,1535397387.500 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-08-27T19:16:27.640Z,1535397387.640 [Aanderaa_O2] Loaded
2018-08-27T19:16:27.641Z,1535397387.641 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-08-27T19:16:27.694Z,1535397387.694 [CTD_Seabird] Loaded
2018-08-27T19:16:27.694Z,1535397387.694 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-08-27T19:16:27.695Z,1535397387.695 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408AC4E0
2018-08-27T19:16:27.695Z,1535397387.695 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1905
2018-08-27T19:16:27.713Z,1535397387.713 [ESPComponent] Loaded
2018-08-27T19:16:27.713Z,1535397387.713 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-08-27T19:16:27.727Z,1535397387.727 [PAR_Licor] Loaded
2018-08-27T19:16:27.727Z,1535397387.727 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-08-27T19:16:27.777Z,1535397387.777 [WetLabsBB2FL] Loaded
2018-08-27T19:16:27.777Z,1535397387.777 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-08-27T19:16:27.778Z,1535397387.778 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408DC4E0
2018-08-27T19:16:27.778Z,1535397387.778 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1906
2018-08-27T19:16:27.779Z,1535397387.779 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-08-27T19:16:27.780Z,1535397387.780 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-08-27T19:16:28.018Z,1535397388.018 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-08-27T19:16:28.019Z,1535397388.019 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-08-27T19:16:28.084Z,1535397388.084 [VerticalControl](DEBUG): Construct VerticalControl.
2018-08-27T19:16:28.178Z,1535397388.178 [VerticalControl] Loaded
2018-08-27T19:16:28.178Z,1535397388.178 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-08-27T19:16:28.179Z,1535397388.179 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-08-27T19:16:28.240Z,1535397388.240 [HorizontalControl] Loaded
2018-08-27T19:16:28.241Z,1535397388.241 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-08-27T19:16:28.241Z,1535397388.241 [SpeedControl](DEBUG): Construct SpeedControl.
2018-08-27T19:16:28.246Z,1535397388.246 [SpeedControl] Loaded
2018-08-27T19:16:28.247Z,1535397388.247 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-08-27T19:16:28.247Z,1535397388.247 [LoopControl](DEBUG): Construct LoopControl.
2018-08-27T19:16:28.248Z,1535397388.248 [LoopControl] Loaded
2018-08-27T19:16:28.248Z,1535397388.248 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-08-27T19:16:28.249Z,1535397388.249 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-08-27T19:16:28.249Z,1535397388.249 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-08-27T19:16:28.330Z,1535397388.330 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-08-27T19:16:28.330Z,1535397388.330 [StratificationFrontDetector](DEBUG): (re)initializing
2018-08-27T19:16:28.330Z,1535397388.330 [StratificationFrontDetector] Loaded
2018-08-27T19:16:28.331Z,1535397388.331 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-08-27T19:16:28.331Z,1535397388.331 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-08-27T19:16:28.332Z,1535397388.332 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-08-27T19:16:28.428Z,1535397388.428 [BuoyancyServo] Loaded
2018-08-27T19:16:28.428Z,1535397388.428 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-08-27T19:16:28.440Z,1535397388.440 [ElevatorServo] Loaded
2018-08-27T19:16:28.440Z,1535397388.440 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-08-27T19:16:28.451Z,1535397388.451 [MassServo] Loaded
2018-08-27T19:16:28.451Z,1535397388.451 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-08-27T19:16:28.463Z,1535397388.463 [RudderServo] Loaded
2018-08-27T19:16:28.463Z,1535397388.463 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-08-27T19:16:28.474Z,1535397388.474 [ThrusterServo] Loaded
2018-08-27T19:16:28.474Z,1535397388.474 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-08-27T19:16:28.474Z,1535397388.474 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-08-27T19:16:28.475Z,1535397388.475 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-08-27T19:16:28.566Z,1535397388.566 [DeadReckonUsingSpeedCalculator] Loaded
2018-08-27T19:16:28.566Z,1535397388.566 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-08-27T19:16:28.570Z,1535397388.570 [UniversalFixResidualReporter] Loaded
2018-08-27T19:16:28.571Z,1535397388.571 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-08-27T19:16:28.571Z,1535397388.571 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-08-27T19:16:28.575Z,1535397388.575 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-08-27T19:16:28.576Z,1535397388.576 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-08-27T19:16:28.582Z,1535397388.582 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-08-27T19:16:28.583Z,1535397388.583 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A354E0
2018-08-27T19:16:28.584Z,1535397388.584 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1907
2018-08-27T19:16:28.588Z,1535397388.588 [Supervisor](INFO): Main Thread ID is 796
2018-08-27T19:16:28.588Z,1535397388.588 [Supervisor](DEBUG): Running supervisor.
2018-08-27T19:16:28.589Z,1535397388.589 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1908
2018-08-27T19:16:28.591Z,1535397388.591 [controlThread ThreadHandler](INFO): Handler Thread ID is 1909
2018-08-27T19:16:28.592Z,1535397388.592 [controlThread](DEBUG): Initializing ControlThread
2018-08-27T19:16:28.593Z,1535397388.593 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-27T19:16:28.593Z,1535397388.593 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-08-27T19:16:28.593Z,1535397388.593 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-08-27T19:16:28.594Z,1535397388.594 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-08-27T19:16:28.594Z,1535397388.594 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-08-27T19:16:28.595Z,1535397388.595 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-08-27T19:16:28.595Z,1535397388.595 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-08-27T19:16:28.598Z,1535397388.598 [SBIT](INFO): Initialize SBIT Component.
2018-08-27T19:16:28.598Z,1535397388.598 [SBIT](IMPORTANT): git: 2018-08-23a
2018-08-27T19:16:28.599Z,1535397388.599 [SBIT](INFO): git hash: f7a0edcfaf5a05cdb3ccc2a17aa2780e938d0460
2018-08-27T19:16:28.599Z,1535397388.599 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-08-27T19:16:28.600Z,1535397388.600 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-08-27T19:16:28.601Z,1535397388.601 [SBIT](INFO): Beginning SBIT in 68.000000 seconds.
2018-08-27T19:16:28.602Z,1535397388.602 [IBIT](INFO): Initialize IBIT Component.
2018-08-27T19:16:28.603Z,1535397388.603 [CBIT](DEBUG): Initialize CBIT Component.
2018-08-27T19:16:28.603Z,1535397388.603 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-08-27T19:16:28.604Z,1535397388.604 [logger ThreadHandler](INFO): Handler Thread ID is 1910
2018-08-27T19:16:28.621Z,1535397388.621 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1911
2018-08-27T19:16:28.627Z,1535397388.627 [Radio_Surface](INFO): Powering up
2018-08-27T19:16:28.633Z,1535397388.633 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1912
2018-08-27T19:16:28.634Z,1535397388.634 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:16:28.634Z,1535397388.634 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:16:28.643Z,1535397388.643 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:16:28.643Z,1535397388.643 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:16:28.649Z,1535397388.649 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1913
2018-08-27T19:16:28.650Z,1535397388.650 [CTD_Seabird](INFO): Initializing
2018-08-27T19:16:28.651Z,1535397388.651 [CTD_Seabird](INFO): Checking LCM
2018-08-27T19:16:28.651Z,1535397388.651 [CTD_Seabird](INFO): LCM OK
2018-08-27T19:16:28.651Z,1535397388.651 [CTD_Seabird](INFO): Powering up
2018-08-27T19:16:28.657Z,1535397388.657 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1914
2018-08-27T19:16:28.658Z,1535397388.658 [WetLabsBB2FL](INFO): Powering down
2018-08-27T19:16:28.679Z,1535397388.679 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1915
2018-08-27T19:16:28.682Z,1535397388.682 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000
2018-08-27T19:16:28.683Z,1535397388.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000
2018-08-27T19:16:28.683Z,1535397388.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000
2018-08-27T19:16:28.683Z,1535397388.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000
2018-08-27T19:16:28.683Z,1535397388.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000
2018-08-27T19:16:28.683Z,1535397388.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000
2018-08-27T19:16:28.684Z,1535397388.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000
2018-08-27T19:16:28.684Z,1535397388.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000
2018-08-27T19:16:28.713Z,1535397388.713 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-08-27T19:16:28.715Z,1535397388.715 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-08-27T19:16:28.716Z,1535397388.716 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-08-27T19:16:28.716Z,1535397388.716 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-08-27T19:16:28.718Z,1535397388.718 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:16:28.719Z,1535397388.719 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:16:28.719Z,1535397388.719 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:16:28.720Z,1535397388.720 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-08-27T19:16:28.721Z,1535397388.721 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-08-27T19:16:28.732Z,1535397388.732 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-27T19:16:28.772Z,1535397388.772 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-08-27T19:16:28.773Z,1535397388.773 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-08-27T19:16:28.857Z,1535397388.857 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-08-27T19:16:28.858Z,1535397388.858 [Default:A.Wait](DEBUG): Construct Wait.
2018-08-27T19:16:28.860Z,1535397388.860 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-27T19:16:28.899Z,1535397388.899 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-08-27T19:16:28.902Z,1535397388.902 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-08-27T19:16:28.922Z,1535397388.922 [Default:E.Execute](DEBUG): Construct Execute.
2018-08-27T19:16:28.954Z,1535397388.954 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-08-27T19:16:28.965Z,1535397388.965 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-08-27T19:16:28.983Z,1535397388.983 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-08-27T19:16:29.007Z,1535397389.007 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2018-08-27T19:16:29.245Z,1535397389.245 [ESPComponent](INFO): powering down ESP
2018-08-27T19:16:29.663Z,1535397389.663 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-08-27T19:16:29.784Z,1535397389.784 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-27T19:16:29.897Z,1535397389.897 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-27T19:16:29.905Z,1535397389.905 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-27T19:16:29.912Z,1535397389.912 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-08-27T19:16:29.917Z,1535397389.917 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-08-27T19:16:29.939Z,1535397389.939 [MassServo](DEBUG): Initializing EZServoServo.
2018-08-27T19:16:29.945Z,1535397389.945 [MassServo](DEBUG): Initializing MassServo.
2018-08-27T19:16:29.963Z,1535397389.963 [RudderServo](DEBUG): Initializing EZServoServo.
2018-08-27T19:16:29.969Z,1535397389.969 [RudderServo](DEBUG): Initializing RudderServo.
2018-08-27T19:16:29.983Z,1535397389.983 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-08-27T19:16:29.989Z,1535397389.989 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-08-27T19:16:30.310Z,1535397390.310 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-27T19:16:30.561Z,1535397390.561 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-27T19:16:31.012Z,1535397391.012 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-27T19:16:31.409Z,1535397391.409 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-27T19:16:31.833Z,1535397391.833 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-27T19:16:32.197Z,1535397392.197 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-27T19:16:32.718Z,1535397392.718 [Aanderaa_O2](INFO): Powering down
2018-08-27T19:16:32.836Z,1535397392.836 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-27T19:16:32.955Z,1535397392.955 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:16:33.116Z,1535397393.116 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:16:33.170Z,1535397393.170 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:16:33.171Z,1535397393.171 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:16:33.171Z,1535397393.171 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:16:34.405Z,1535397394.405 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-27T19:16:34.700Z,1535397394.700 [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-08-27T19:16:34.702Z,1535397394.702 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-08-27T19:16:35.130Z,1535397395.130 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-27T19:16:35.130Z,1535397395.130 [CTD_Seabird](INFO): Powering down
2018-08-27T19:16:55.044Z,1535397415.044 [NAL9602](INFO): Powering up NAL9602
2018-08-27T19:17:06.222Z,1535397426.222 [NAL9602](INFO): NAL9602 initialized
2018-08-27T19:17:33.032Z,1535397453.032 [NAL9602](INFO): SBD MO Status=2, MOMSN=24176, MT Status=2, MTMSN=0
2018-08-27T19:17:33.033Z,1535397453.033 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-08-27T19:17:37.183Z,1535397457.183 [SBIT](IMPORTANT): Beginning Startup BIT
2018-08-27T19:17:37.191Z,1535397457.191 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-27T19:17:48.284Z,1535397468.284 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003147
CHAN A1 (24V): -0.007961
CHAN A2 (12V): -0.001797
CHAN A3 (5V): -0.001697
CHAN B0 (3.3V): 0.000088
CHAN B1 (3.15aV): -0.000278
CHAN B2 (3.15bV): -0.000139
CHAN B3 (GND): -0.000212
OPEN: 0.005180
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-27T19:18:28.662Z,1535397508.662 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:18:28.662Z,1535397508.662 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-27T19:18:28.662Z,1535397508.662 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:18:28.929Z,1535397508.929 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:18:29.065Z,1535397509.065 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:18:29.364Z,1535397509.364 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:18:30.918Z,1535397510.918 [SBIT](IMPORTANT): SBIT PASSED
2018-08-27T19:18:30.961Z,1535397510.961 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:18:30.961Z,1535397510.961 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-27T19:18:30.981Z,1535397510.981 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:18:30.982Z,1535397510.982 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:18:30.982Z,1535397510.982 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:18:30.982Z,1535397510.982 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:18:31.017Z,1535397511.017 [CommandLine](IMPORTANT): got command configSet list
2018-08-27T19:18:31.018Z,1535397511.018 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-08-27T19:18:31.019Z,1535397511.019 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.loadAtStartup=0 bool;

2018-08-27T19:18:31.019Z,1535397511.019 [CommandLine](IMPORTANT): CTD_Seabird.minSalinityBound=0.01 practical_salinity_unit;

2018-08-27T19:18:31.019Z,1535397511.019 [CommandLine](IMPORTANT): Depth_Keller.offset=-0.56 decibar;

2018-08-27T19:18:31.019Z,1535397511.019 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2018-08-27T19:18:31.019Z,1535397511.019 [CommandLine](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.000500 practical_salinity_unit;

2018-08-27T19:18:31.019Z,1535397511.019 [CommandLine](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.005000 kelvin;

2018-08-27T19:18:31.020Z,1535397511.020 [CommandLine](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter;

2018-08-27T19:18:31.020Z,1535397511.020 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 2.000000 meter;

2018-08-27T19:18:31.020Z,1535397511.020 [CommandLine](IMPORTANT): Express linearApproximation mass_concentration_of_chlorophyll_in_sea_water 1.000000 microgram_per_liter;

2018-08-27T19:18:31.020Z,1535397511.020 [CommandLine](IMPORTANT): IBIT.batteryHibernationTimeout=1 minute;

2018-08-27T19:18:31.020Z,1535397511.020 [CommandLine](IMPORTANT): IBIT.batteryMissingStickThreshold=18 count;

2018-08-27T19:18:31.021Z,1535397511.021 [CommandLine](IMPORTANT): NavChart.loadAtStartup=0 bool;

2018-08-27T19:18:31.021Z,1535397511.021 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=539 cubic_centimeter;

2018-08-27T19:18:31.021Z,1535397511.021 [CommandLine](IMPORTANT): VerticalControl.massDefault=-12 millimeter;

2018-08-27T19:18:31.310Z,1535397511.310 [MissionManager](IMPORTANT): Started mission Startup
2018-08-27T19:18:31.310Z,1535397511.310 [Startup] Running Loop=1
2018-08-27T19:18:31.310Z,1535397511.310 [Startup](DEBUG): Aggregate::initialize Startup
2018-08-27T19:18:31.310Z,1535397511.310 [Startup:A.GoToSurface] Running Loop=1
2018-08-27T19:18:31.310Z,1535397511.310 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-27T19:18:31.311Z,1535397511.311 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-27T19:18:31.312Z,1535397511.312 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-27T19:18:31.312Z,1535397511.312 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-27T19:18:31.312Z,1535397511.312 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-27T19:18:31.321Z,1535397511.321 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-27T19:18:31.333Z,1535397511.333 [Startup:StartupSatComms] Running Loop=1
2018-08-27T19:18:31.334Z,1535397511.334 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-08-27T19:18:31.334Z,1535397511.334 [Startup:StartupSatComms:A] Running Loop=1
2018-08-27T19:18:31.711Z,1535397511.711 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-08-27T19:18:35.101Z,1535397515.101 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:18:35.163Z,1535397515.163 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:18:35.165Z,1535397515.165 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:18:35.166Z,1535397515.166 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:18:35.167Z,1535397515.167 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:19:28.924Z,1535397568.924 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:19:28.924Z,1535397568.924 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-08-27T19:19:28.924Z,1535397568.924 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:19:28.959Z,1535397568.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:19:29.382Z,1535397569.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:19:29.382Z,1535397569.382 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-08-27T19:19:29.735Z,1535397569.735 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:19:29.735Z,1535397569.735 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:19:29.736Z,1535397569.736 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:19:31.347Z,1535397571.347 [Startup:StartupSatComms:A](INFO): Timed out from 2018-08-27T19:18:31.3Z
2018-08-27T19:19:31.347Z,1535397571.347 [Startup:StartupSatComms:A] Stopped
2018-08-27T19:19:31.348Z,1535397571.348 [Startup:StartupSatComms:B] Running Loop=1
2018-08-27T19:19:31.716Z,1535397571.716 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-27T19:20:31.297Z,1535397631.297 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:20:31.297Z,1535397631.297 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-27T19:20:31.297Z,1535397631.297 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:20:31.701Z,1535397631.701 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:20:31.943Z,1535397631.943 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:20:35.108Z,1535397635.108 [Startup:StartupSatComms:B](INFO): Timed out from 2018-08-27T19:19:31.3Z
2018-08-27T19:20:35.108Z,1535397635.108 [Startup:StartupSatComms:B] Stopped
2018-08-27T19:20:35.108Z,1535397635.108 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-08-27T19:20:35.108Z,1535397635.108 [Startup:StartupSatComms] Stopped
2018-08-27T19:20:35.109Z,1535397635.109 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-08-27T19:20:35.110Z,1535397635.110 [Startup](INFO): Completed Startup
2018-08-27T19:20:35.110Z,1535397635.110 [MissionManager](INFO): Startup is completed.
2018-08-27T19:20:35.110Z,1535397635.110 [MissionManager](INFO): Uninitializing Mission Startup
2018-08-27T19:20:35.111Z,1535397635.111 [Startup] Stopped
2018-08-27T19:20:35.111Z,1535397635.111 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-08-27T19:20:35.111Z,1535397635.111 [Startup:A.GoToSurface] Stopped
2018-08-27T19:20:35.111Z,1535397635.111 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-27T19:20:35.182Z,1535397635.182 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:20:35.182Z,1535397635.182 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:20:35.182Z,1535397635.182 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-27T19:20:35.205Z,1535397635.205 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:20:35.206Z,1535397635.206 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:20:35.206Z,1535397635.206 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:20:35.206Z,1535397635.206 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:20:35.370Z,1535397635.370 [MissionManager](IMPORTANT): Started mission Default
2018-08-27T19:20:35.371Z,1535397635.371 [Default] Running Loop=1
2018-08-27T19:20:35.371Z,1535397635.371 [Default](DEBUG): Aggregate::initialize Default
2018-08-27T19:20:35.371Z,1535397635.371 [Default:B.GoToSurface] Running Loop=1
2018-08-27T19:20:35.371Z,1535397635.371 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-27T19:20:35.371Z,1535397635.371 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-27T19:20:35.372Z,1535397635.372 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-27T19:20:35.372Z,1535397635.372 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-27T19:20:35.372Z,1535397635.372 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-27T19:20:35.377Z,1535397635.377 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-27T19:20:35.377Z,1535397635.377 [Default:A.Wait] Running Loop=1
2018-08-27T19:20:35.378Z,1535397635.378 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-08-27T19:20:35.997Z,1535397635.997 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005521
2018-08-27T19:20:39.383Z,1535397639.383 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:20:39.441Z,1535397639.441 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:20:39.442Z,1535397639.442 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:20:39.443Z,1535397639.443 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:20:39.445Z,1535397639.445 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:20:48.486Z,1535397648.486 [Default:A.Wait](INFO): Done Waiting.
2018-08-27T19:20:48.486Z,1535397648.486 [Default:A.Wait] Stopped
2018-08-27T19:20:48.486Z,1535397648.486 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-08-27T19:20:48.838Z,1535397648.838 [Default:CheckIn] Running Loop=1
2018-08-27T19:20:48.839Z,1535397648.839 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-27T19:20:48.839Z,1535397648.839 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-27T19:20:49.285Z,1535397649.285 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-08-27T19:22:13.904Z,1535397733.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=24176, MT Status=2, MTMSN=0
2018-08-27T19:22:13.904Z,1535397733.904 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-08-27T19:22:14.302Z,1535397734.302 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-08-27T19:22:29.958Z,1535397749.958 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:22:29.958Z,1535397749.958 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-08-27T19:22:29.958Z,1535397749.958 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:22:29.987Z,1535397749.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:22:30.366Z,1535397750.366 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:22:30.366Z,1535397750.366 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-08-27T19:22:30.751Z,1535397750.751 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:22:30.752Z,1535397750.752 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:22:30.752Z,1535397750.752 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:22:35.637Z,1535397755.637 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:22:35.637Z,1535397755.637 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-27T19:22:35.637Z,1535397755.637 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:22:35.898Z,1535397755.898 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:22:36.041Z,1535397756.041 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:22:36.133Z,1535397756.133 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:22:37.515Z,1535397757.515 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:22:37.515Z,1535397757.515 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-27T19:22:37.749Z,1535397757.749 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:22:37.749Z,1535397757.749 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:22:37.750Z,1535397757.750 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:22:37.750Z,1535397757.750 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:22:41.989Z,1535397761.989 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:22:42.056Z,1535397762.056 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:22:42.059Z,1535397762.059 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:22:42.060Z,1535397762.060 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:22:42.061Z,1535397762.061 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:24:38.042Z,1535397878.042 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:24:38.042Z,1535397878.042 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-27T19:24:38.042Z,1535397878.042 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:24:38.140Z,1535397878.140 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:24:38.445Z,1535397878.445 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:24:38.669Z,1535397878.669 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:24:40.146Z,1535397880.146 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:24:40.146Z,1535397880.146 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-27T19:24:40.285Z,1535397880.285 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:24:40.285Z,1535397880.285 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:24:40.285Z,1535397880.285 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:24:40.286Z,1535397880.286 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:24:41.944Z,1535397881.944 [CommandLine](IMPORTANT): got command failComponent
2018-08-27T19:24:41.944Z,1535397881.944 [CommandLine](IMPORTANT): Failed components:
2018-08-27T19:24:41.944Z,1535397881.944 [CommandLine](IMPORTANT): No failed Components.
2018-08-27T19:24:44.412Z,1535397884.412 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:24:44.524Z,1535397884.524 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:24:44.525Z,1535397884.525 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:24:44.526Z,1535397884.526 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:24:44.527Z,1535397884.527 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:24:48.917Z,1535397888.917 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T19:24:48.917Z,1535397888.917 [BPC1] Communications Fault, FailCount= 1
2018-08-27T19:24:48.917Z,1535397888.917 [BPC1](ERROR): Communications Fault
2018-08-27T19:24:48.972Z,1535397888.972 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T19:24:50.130Z,1535397890.130 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T19:24:50.130Z,1535397890.130 [BPC1] No Fault, FailCount= 1
2018-08-27T19:25:30.941Z,1535397930.941 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:25:30.941Z,1535397930.941 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-08-27T19:25:30.941Z,1535397930.941 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:25:30.990Z,1535397930.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:25:31.345Z,1535397931.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:25:31.345Z,1535397931.345 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-08-27T19:25:31.741Z,1535397931.741 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:25:31.742Z,1535397931.742 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:25:31.743Z,1535397931.743 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:25:48.950Z,1535397948.950 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-27T19:20:48.8Z
2018-08-27T19:25:48.950Z,1535397948.950 [Default:CheckIn:Read_GPS] Stopped
2018-08-27T19:25:48.950Z,1535397948.950 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-27T19:25:49.283Z,1535397949.283 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-27T19:25:56.430Z,1535397956.430 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20180827T181904/Courier0012.lzma
2018-08-27T19:25:57.227Z,1535397957.227 [DataOverHttps](INFO): Moved sent file to Logs/20180827T181904/Courier0012.lzma.bak
2018-08-27T19:25:57.227Z,1535397957.227 [DataOverHttps](INFO): SBD MOMSN=8448325
2018-08-27T19:26:08.417Z,1535397968.417 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180827T191618/Courier0004.lzma
2018-08-27T19:26:09.215Z,1535397969.215 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Courier0004.lzma.bak
2018-08-27T19:26:09.215Z,1535397969.215 [DataOverHttps](INFO): SBD MOMSN=8448329
2018-08-27T19:26:22.969Z,1535397982.969 [DataOverHttps](INFO): Sending 716 bytes from file Logs/20180827T181904/Express0013.lzma
2018-08-27T19:26:23.759Z,1535397983.759 [DataOverHttps](INFO): Moved sent file to Logs/20180827T181904/Express0013.lzma.bak
2018-08-27T19:26:23.759Z,1535397983.759 [DataOverHttps](INFO): SBD MOMSN=8448332
2018-08-27T19:26:35.539Z,1535397995.539 [DataOverHttps](INFO): Sending 1065 bytes from file Logs/20180827T191618/Express0001.lzma
2018-08-27T19:26:36.331Z,1535397996.331 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Express0001.lzma.bak
2018-08-27T19:26:36.331Z,1535397996.331 [DataOverHttps](INFO): SBD MOMSN=8448354
2018-08-27T19:26:41.016Z,1535398001.016 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:26:41.017Z,1535398001.017 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-27T19:26:41.017Z,1535398001.017 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:26:41.421Z,1535398001.421 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:26:41.579Z,1535398001.579 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:26:43.867Z,1535398003.867 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:26:43.868Z,1535398003.868 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-27T19:26:51.851Z,1535398011.851 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20180827T191618/Express0005.lzma
2018-08-27T19:26:52.803Z,1535398012.803 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Express0005.lzma.bak
2018-08-27T19:26:52.804Z,1535398012.804 [DataOverHttps](INFO): SBD MOMSN=8448381
2018-08-27T19:26:54.686Z,1535398014.686 [Default:CheckIn:Read_Iridium] Stopped
2018-08-27T19:26:54.686Z,1535398014.686 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-27T19:26:54.686Z,1535398014.686 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-27T19:27:07.911Z,1535398027.911 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-27T19:27:07.911Z,1535398027.911 [NAL9602] Data Fault, FailCount= 1
2018-08-27T19:27:07.911Z,1535398027.911 [NAL9602](ERROR): Data Fault
2018-08-27T19:27:07.986Z,1535398027.986 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-27T19:27:08.310Z,1535398028.310 [NAL9602](INFO): Powering down
2018-08-27T19:27:09.163Z,1535398029.163 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-27T19:27:09.164Z,1535398029.164 [NAL9602] No Fault, FailCount= 1
2018-08-27T19:27:38.710Z,1535398058.710 [NAL9602](INFO): Powering up NAL9602
2018-08-27T19:27:49.914Z,1535398069.914 [NAL9602](INFO): NAL9602 initialized
2018-08-27T19:28:31.928Z,1535398111.928 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:28:31.928Z,1535398111.928 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-08-27T19:28:31.928Z,1535398111.928 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:28:31.961Z,1535398111.961 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:28:32.357Z,1535398112.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:28:32.357Z,1535398112.357 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-08-27T19:28:32.720Z,1535398112.720 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:28:32.720Z,1535398112.720 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:28:32.721Z,1535398112.721 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:30:58.984Z,1535398258.984 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T19:30:58.984Z,1535398258.984 [BPC1] Communications Fault, FailCount= 2
2018-08-27T19:30:58.984Z,1535398258.984 [BPC1](ERROR): Communications Fault
2018-08-27T19:30:59.033Z,1535398259.033 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T19:30:59.901Z,1535398259.901 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T19:30:59.902Z,1535398259.902 [BPC1] No Fault, FailCount= 2
2018-08-27T19:31:33.109Z,1535398293.109 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:31:33.109Z,1535398293.109 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-08-27T19:31:33.109Z,1535398293.109 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:31:33.122Z,1535398293.122 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:31:33.512Z,1535398293.512 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:31:33.512Z,1535398293.512 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-08-27T19:31:33.893Z,1535398293.893 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:31:33.894Z,1535398293.894 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:31:33.894Z,1535398293.894 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:31:55.102Z,1535398315.102 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-27T19:31:55.102Z,1535398315.102 [Default:CheckIn:C.Wait] Stopped
2018-08-27T19:31:55.102Z,1535398315.102 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-27T19:31:55.103Z,1535398315.103 [Default:CheckIn:D] Running Loop=1
2018-08-27T19:31:55.490Z,1535398315.490 [Default:CheckIn:D] Stopped
2018-08-27T19:31:55.490Z,1535398315.490 [Default:CheckIn:E] Running Loop=1
2018-08-27T19:31:55.894Z,1535398315.894 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.335287 min 
2018-08-27T19:31:55.896Z,1535398315.896 [Default:CheckIn:E] Stopped
2018-08-27T19:31:55.896Z,1535398315.896 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-27T19:31:55.896Z,1535398315.896 [Default:CheckIn] Stopped
2018-08-27T19:31:55.896Z,1535398315.896 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-27T19:31:55.896Z,1535398315.896 [Default:CheckIn](INFO): Running loop #2
2018-08-27T19:31:55.896Z,1535398315.896 [Default:CheckIn] Running Loop=2
2018-08-27T19:31:55.896Z,1535398315.896 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-27T19:31:55.897Z,1535398315.897 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-27T19:34:34.266Z,1535398474.266 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:34:34.266Z,1535398474.266 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-08-27T19:34:34.266Z,1535398474.266 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:34:34.280Z,1535398474.280 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:34:34.684Z,1535398474.684 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:34:34.684Z,1535398474.684 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-08-27T19:34:35.065Z,1535398475.065 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:34:35.066Z,1535398475.066 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:34:35.066Z,1535398475.066 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:36:41.033Z,1535398601.033 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:36:41.034Z,1535398601.034 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-27T19:36:41.381Z,1535398601.381 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:36:41.381Z,1535398601.381 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:36:41.381Z,1535398601.381 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:36:41.382Z,1535398601.382 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:36:45.537Z,1535398605.537 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:36:45.600Z,1535398605.600 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:36:45.602Z,1535398605.602 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:36:45.603Z,1535398605.603 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:36:45.604Z,1535398605.604 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:36:56.199Z,1535398616.199 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-27T19:31:55.9Z
2018-08-27T19:36:56.199Z,1535398616.199 [Default:CheckIn:Read_GPS] Stopped
2018-08-27T19:36:56.199Z,1535398616.199 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-27T19:37:01.251Z,1535398621.251 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T19:37:01.251Z,1535398621.251 [BPC1] Communications Fault, FailCount= 3
2018-08-27T19:37:01.252Z,1535398621.252 [BPC1](ERROR): Communications Fault
2018-08-27T19:37:01.310Z,1535398621.310 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T19:37:01.721Z,1535398621.721 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180827T191618/Courier0007.lzma
2018-08-27T19:37:02.178Z,1535398622.178 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T19:37:02.178Z,1535398622.178 [BPC1] No Fault, FailCount= 3
2018-08-27T19:37:02.519Z,1535398622.519 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Courier0007.lzma.bak
2018-08-27T19:37:02.519Z,1535398622.519 [DataOverHttps](INFO): SBD MOMSN=8448391
2018-08-27T19:37:15.940Z,1535398635.940 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20180827T191618/Express0008.lzma
2018-08-27T19:37:16.735Z,1535398636.735 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Express0008.lzma.bak
2018-08-27T19:37:16.735Z,1535398636.735 [DataOverHttps](INFO): SBD MOMSN=8448393
2018-08-27T19:37:18.639Z,1535398638.639 [Default:CheckIn:Read_Iridium] Stopped
2018-08-27T19:37:18.639Z,1535398638.639 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-27T19:37:18.639Z,1535398638.639 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-27T19:37:35.103Z,1535398655.103 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:37:35.103Z,1535398655.103 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-08-27T19:37:35.103Z,1535398655.103 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:37:35.180Z,1535398655.180 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:37:35.581Z,1535398655.581 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:37:35.581Z,1535398655.581 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-08-27T19:37:36.055Z,1535398656.055 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:37:36.056Z,1535398656.056 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:37:36.057Z,1535398656.057 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:37:51.850Z,1535398671.850 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-27T19:37:51.850Z,1535398671.850 [NAL9602] Data Fault, FailCount= 2
2018-08-27T19:37:51.850Z,1535398671.850 [NAL9602](ERROR): Data Fault
2018-08-27T19:37:52.028Z,1535398672.028 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-27T19:37:52.250Z,1535398672.250 [NAL9602](INFO): Powering down
2018-08-27T19:37:53.129Z,1535398673.129 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-27T19:37:53.129Z,1535398673.129 [NAL9602] No Fault, FailCount= 2
2018-08-27T19:38:22.464Z,1535398702.464 [NAL9602](INFO): Powering up NAL9602
2018-08-27T19:38:33.357Z,1535398713.357 [NAL9602](INFO): NAL9602 initialized
2018-08-27T19:38:41.929Z,1535398721.929 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:38:41.929Z,1535398721.929 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-27T19:38:41.929Z,1535398721.929 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:38:42.209Z,1535398722.209 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:38:42.333Z,1535398722.333 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:38:42.425Z,1535398722.425 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:38:43.848Z,1535398723.848 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:38:43.848Z,1535398723.848 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-27T19:38:44.041Z,1535398724.041 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:38:44.041Z,1535398724.041 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:38:44.041Z,1535398724.041 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:38:44.041Z,1535398724.041 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:38:48.272Z,1535398728.272 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:38:48.344Z,1535398728.344 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:38:48.350Z,1535398728.350 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:38:48.351Z,1535398728.351 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:38:48.351Z,1535398728.351 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:40:36.199Z,1535398836.199 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:40:36.199Z,1535398836.199 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-08-27T19:40:36.199Z,1535398836.199 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:40:36.255Z,1535398836.255 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:40:36.579Z,1535398836.579 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:40:36.580Z,1535398836.580 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-08-27T19:40:36.960Z,1535398836.960 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:40:36.960Z,1535398836.960 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:40:36.961Z,1535398836.961 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:40:44.326Z,1535398844.326 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:40:44.326Z,1535398844.326 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-27T19:40:44.327Z,1535398844.327 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:40:44.585Z,1535398844.585 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:40:44.729Z,1535398844.729 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:40:44.823Z,1535398844.823 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:40:46.185Z,1535398846.185 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:40:46.185Z,1535398846.185 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-27T19:40:46.437Z,1535398846.437 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:40:46.437Z,1535398846.437 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:40:46.438Z,1535398846.438 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:40:46.438Z,1535398846.438 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:40:50.683Z,1535398850.683 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:40:50.744Z,1535398850.744 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:40:50.746Z,1535398850.746 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:40:50.747Z,1535398850.747 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:40:50.748Z,1535398850.748 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:42:18.901Z,1535398938.901 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-27T19:42:18.901Z,1535398938.901 [Default:CheckIn:C.Wait] Stopped
2018-08-27T19:42:18.901Z,1535398938.901 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-27T19:42:18.901Z,1535398938.901 [Default:CheckIn:D] Running Loop=1
2018-08-27T19:42:19.316Z,1535398939.316 [Default:CheckIn:D] Stopped
2018-08-27T19:42:19.321Z,1535398939.321 [Default:CheckIn:E] Running Loop=1
2018-08-27T19:42:19.700Z,1535398939.700 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.732426 min 
2018-08-27T19:42:19.705Z,1535398939.705 [Default:CheckIn:E] Stopped
2018-08-27T19:42:19.705Z,1535398939.705 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-27T19:42:19.705Z,1535398939.705 [Default:CheckIn] Stopped
2018-08-27T19:42:19.705Z,1535398939.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-27T19:42:19.705Z,1535398939.705 [Default:CheckIn](INFO): Running loop #3
2018-08-27T19:42:19.705Z,1535398939.705 [Default:CheckIn] Running Loop=3
2018-08-27T19:42:19.706Z,1535398939.706 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-27T19:42:19.706Z,1535398939.706 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-27T19:42:46.515Z,1535398966.515 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:42:46.515Z,1535398966.515 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-27T19:42:46.515Z,1535398966.515 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:42:46.818Z,1535398966.818 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:42:46.917Z,1535398966.917 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:42:47.010Z,1535398967.010 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:42:48.430Z,1535398968.430 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:42:48.430Z,1535398968.430 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-27T19:42:48.625Z,1535398968.625 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:42:48.625Z,1535398968.625 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:42:48.625Z,1535398968.625 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:42:48.626Z,1535398968.626 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:42:52.791Z,1535398972.791 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:42:52.924Z,1535398972.924 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:42:52.925Z,1535398972.925 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:42:52.926Z,1535398972.926 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:42:52.927Z,1535398972.927 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:43:28.745Z,1535399008.745 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T19:43:28.745Z,1535399008.745 [BPC1] Communications Fault, FailCount= 4
2018-08-27T19:43:28.745Z,1535399008.745 [BPC1](ERROR): Communications Fault
2018-08-27T19:43:28.839Z,1535399008.839 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T19:43:29.817Z,1535399009.817 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T19:43:29.818Z,1535399009.818 [BPC1] No Fault, FailCount= 4
2018-08-27T19:43:37.340Z,1535399017.340 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:43:37.340Z,1535399017.340 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-08-27T19:43:37.340Z,1535399017.340 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:43:37.404Z,1535399017.404 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:43:37.790Z,1535399017.790 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:43:37.790Z,1535399017.790 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-08-27T19:43:38.150Z,1535399018.150 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:43:38.151Z,1535399018.151 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:43:38.151Z,1535399018.151 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:44:48.902Z,1535399088.902 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:44:48.903Z,1535399088.903 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-27T19:44:48.903Z,1535399088.903 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:44:49.277Z,1535399089.277 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:44:49.305Z,1535399089.305 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:44:49.475Z,1535399089.475 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:44:50.857Z,1535399090.857 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:44:50.857Z,1535399090.857 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-27T19:44:51.089Z,1535399091.089 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:44:51.089Z,1535399091.089 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:44:51.090Z,1535399091.090 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:44:51.090Z,1535399091.090 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:44:55.429Z,1535399095.429 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:44:55.603Z,1535399095.603 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:44:55.606Z,1535399095.606 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:44:55.606Z,1535399095.606 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:44:55.607Z,1535399095.607 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:46:38.455Z,1535399198.455 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:46:38.455Z,1535399198.455 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-08-27T19:46:38.455Z,1535399198.455 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:46:38.480Z,1535399198.480 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:46:38.882Z,1535399198.882 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:46:38.882Z,1535399198.882 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-08-27T19:46:39.259Z,1535399199.259 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:46:39.260Z,1535399199.260 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:46:39.260Z,1535399199.260 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:46:51.685Z,1535399211.685 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:46:51.685Z,1535399211.685 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-27T19:46:51.685Z,1535399211.685 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:46:52.089Z,1535399212.089 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:46:52.185Z,1535399212.185 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:46:52.186Z,1535399212.186 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-27T19:46:52.292Z,1535399212.292 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:47:20.060Z,1535399240.060 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-27T19:42:19.7Z
2018-08-27T19:47:20.060Z,1535399240.060 [Default:CheckIn:Read_GPS] Stopped
2018-08-27T19:47:20.060Z,1535399240.060 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-27T19:47:26.818Z,1535399246.818 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180827T191618/Courier0010.lzma
2018-08-27T19:47:27.615Z,1535399247.615 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Courier0010.lzma.bak
2018-08-27T19:47:27.615Z,1535399247.615 [DataOverHttps](INFO): SBD MOMSN=8448399
2018-08-27T19:47:41.942Z,1535399261.943 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20180827T191618/Express0011.lzma
2018-08-27T19:47:42.743Z,1535399262.743 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Express0011.lzma.bak
2018-08-27T19:47:42.743Z,1535399262.743 [DataOverHttps](INFO): SBD MOMSN=8448401
2018-08-27T19:47:45.146Z,1535399265.146 [Default:CheckIn:Read_Iridium] Stopped
2018-08-27T19:47:45.146Z,1535399265.146 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-27T19:47:45.146Z,1535399265.146 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-27T19:48:36.511Z,1535399316.511 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-27T19:48:36.511Z,1535399316.511 [NAL9602] Data Fault, FailCount= 3
2018-08-27T19:48:36.511Z,1535399316.511 [NAL9602](ERROR): Data Fault
2018-08-27T19:48:36.600Z,1535399316.600 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-27T19:48:36.910Z,1535399316.910 [NAL9602](INFO): Powering down
2018-08-27T19:48:37.824Z,1535399317.824 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-27T19:48:37.824Z,1535399317.824 [NAL9602] No Fault, FailCount= 3
2018-08-27T19:49:07.078Z,1535399347.078 [NAL9602](INFO): Powering up NAL9602
2018-08-27T19:49:18.279Z,1535399358.279 [NAL9602](INFO): NAL9602 initialized
2018-08-27T19:49:39.551Z,1535399379.551 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:49:39.551Z,1535399379.551 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-08-27T19:49:39.551Z,1535399379.551 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:49:39.614Z,1535399379.614 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:49:40.008Z,1535399380.008 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:49:40.008Z,1535399380.008 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-08-27T19:49:40.349Z,1535399380.349 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:49:40.349Z,1535399380.349 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:49:40.350Z,1535399380.350 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:50:27.463Z,1535399427.463 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T19:50:27.463Z,1535399427.463 [BPC1] Communications Fault, FailCount= 5
2018-08-27T19:50:27.463Z,1535399427.463 [BPC1](ERROR): Communications Fault
2018-08-27T19:50:27.507Z,1535399427.507 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T19:50:28.704Z,1535399428.704 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T19:50:28.704Z,1535399428.704 [BPC1] No Fault, FailCount= 5
2018-08-27T19:52:40.549Z,1535399560.549 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:52:40.550Z,1535399560.550 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-08-27T19:52:40.550Z,1535399560.550 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:52:40.592Z,1535399560.592 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:52:40.936Z,1535399560.936 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:52:40.936Z,1535399560.936 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-08-27T19:52:41.327Z,1535399561.327 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:52:41.328Z,1535399561.328 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:52:41.328Z,1535399561.328 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:52:45.322Z,1535399565.322 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-27T19:52:45.322Z,1535399565.322 [Default:CheckIn:C.Wait] Stopped
2018-08-27T19:52:45.323Z,1535399565.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-27T19:52:45.323Z,1535399565.323 [Default:CheckIn:D] Running Loop=1
2018-08-27T19:52:45.771Z,1535399565.771 [Default:CheckIn:D] Stopped
2018-08-27T19:52:45.771Z,1535399565.771 [Default:CheckIn:E] Running Loop=1
2018-08-27T19:52:46.118Z,1535399566.118 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.173336 min 
2018-08-27T19:52:46.118Z,1535399566.118 [Default:CheckIn:E] Stopped
2018-08-27T19:52:46.118Z,1535399566.118 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-27T19:52:46.118Z,1535399566.118 [Default:CheckIn] Stopped
2018-08-27T19:52:46.119Z,1535399566.119 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-27T19:52:46.119Z,1535399566.119 [Default:CheckIn](INFO): Running loop #4
2018-08-27T19:52:46.119Z,1535399566.119 [Default:CheckIn] Running Loop=4
2018-08-27T19:52:46.119Z,1535399566.119 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-27T19:52:46.119Z,1535399566.119 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-27T19:55:41.562Z,1535399741.562 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:55:41.562Z,1535399741.562 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-08-27T19:55:41.563Z,1535399741.563 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:55:41.588Z,1535399741.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:55:41.987Z,1535399741.987 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:55:41.988Z,1535399741.988 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-08-27T19:55:42.348Z,1535399742.348 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:55:42.348Z,1535399742.348 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:55:42.349Z,1535399742.349 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:56:29.501Z,1535399789.501 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T19:56:29.502Z,1535399789.502 [BPC1] Communications Fault, FailCount= 6
2018-08-27T19:56:29.502Z,1535399789.502 [BPC1](ERROR): Communications Fault
2018-08-27T19:56:29.529Z,1535399789.529 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T19:56:30.783Z,1535399790.783 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T19:56:30.783Z,1535399790.783 [BPC1] No Fault, FailCount= 6
2018-08-27T19:56:51.959Z,1535399811.959 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:56:51.960Z,1535399811.960 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-27T19:56:52.137Z,1535399812.137 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:56:52.137Z,1535399812.137 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:56:52.137Z,1535399812.137 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:56:52.137Z,1535399812.137 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:56:56.403Z,1535399816.403 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:56:56.556Z,1535399816.556 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:56:56.557Z,1535399816.557 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:56:56.558Z,1535399816.558 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:56:56.558Z,1535399816.558 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:57:46.352Z,1535399866.352 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-27T19:52:46.1Z
2018-08-27T19:57:46.352Z,1535399866.352 [Default:CheckIn:Read_GPS] Stopped
2018-08-27T19:57:46.361Z,1535399866.361 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-27T19:58:01.475Z,1535399881.475 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180827T191618/Courier0013.lzma
2018-08-27T19:58:02.217Z,1535399882.217 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Courier0013.lzma.bak
2018-08-27T19:58:02.217Z,1535399882.217 [DataOverHttps](INFO): SBD MOMSN=8448411
2018-08-27T19:58:19.354Z,1535399899.354 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20180827T191618/Express0014.lzma
2018-08-27T19:58:20.152Z,1535399900.152 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Express0014.lzma.bak
2018-08-27T19:58:20.152Z,1535399900.152 [DataOverHttps](INFO): SBD MOMSN=8448413
2018-08-27T19:58:22.373Z,1535399902.373 [Default:CheckIn:Read_Iridium] Stopped
2018-08-27T19:58:22.374Z,1535399902.374 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-27T19:58:22.374Z,1535399902.374 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-27T19:58:42.644Z,1535399922.644 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T19:58:42.644Z,1535399922.644 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-08-27T19:58:42.644Z,1535399922.644 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T19:58:42.689Z,1535399922.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T19:58:43.072Z,1535399923.072 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T19:58:43.072Z,1535399923.072 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-08-27T19:58:43.412Z,1535399923.412 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T19:58:43.413Z,1535399923.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T19:58:43.413Z,1535399923.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T19:58:52.179Z,1535399932.179 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T19:58:52.179Z,1535399932.179 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-27T19:58:52.179Z,1535399932.179 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T19:58:52.255Z,1535399932.255 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T19:58:52.585Z,1535399932.585 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T19:58:52.778Z,1535399932.778 [Rowe_600LCM](INFO): Powering down
2018-08-27T19:58:54.274Z,1535399934.274 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T19:58:54.274Z,1535399934.274 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-27T19:58:54.393Z,1535399934.393 [Rowe_600LCM](INFO): Initializing
2018-08-27T19:58:54.393Z,1535399934.393 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T19:58:54.393Z,1535399934.393 [Rowe_600LCM](INFO): LCM OK
2018-08-27T19:58:54.394Z,1535399934.394 [Rowe_600LCM](INFO): Powering up
2018-08-27T19:58:58.532Z,1535399938.532 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T19:58:58.668Z,1535399938.668 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T19:58:58.671Z,1535399938.671 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T19:58:58.672Z,1535399938.672 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T19:58:58.677Z,1535399938.677 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T19:59:20.570Z,1535399960.570 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-27T19:59:20.571Z,1535399960.571 [NAL9602] Data Fault, FailCount= 4
2018-08-27T19:59:20.571Z,1535399960.571 [NAL9602](ERROR): Data Fault
2018-08-27T19:59:20.645Z,1535399960.645 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-27T19:59:20.974Z,1535399960.974 [NAL9602](INFO): Powering down
2018-08-27T19:59:21.998Z,1535399961.998 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-27T19:59:21.998Z,1535399961.998 [NAL9602] No Fault, FailCount= 4
2018-08-27T19:59:51.262Z,1535399991.262 [NAL9602](INFO): Powering up NAL9602
2018-08-27T20:00:02.466Z,1535400002.466 [NAL9602](INFO): NAL9602 initialized
2018-08-27T20:00:55.157Z,1535400055.157 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:00:55.157Z,1535400055.157 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-27T20:00:55.157Z,1535400055.157 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:00:55.197Z,1535400055.197 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:00:55.561Z,1535400055.561 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:00:55.761Z,1535400055.761 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:00:57.131Z,1535400057.131 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T20:00:57.131Z,1535400057.131 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-27T20:00:57.377Z,1535400057.377 [Rowe_600LCM](INFO): Initializing
2018-08-27T20:00:57.377Z,1535400057.377 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T20:00:57.378Z,1535400057.378 [Rowe_600LCM](INFO): LCM OK
2018-08-27T20:00:57.378Z,1535400057.378 [Rowe_600LCM](INFO): Powering up
2018-08-27T20:01:01.662Z,1535400061.662 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T20:01:01.740Z,1535400061.740 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T20:01:01.742Z,1535400061.742 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T20:01:01.743Z,1535400061.743 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T20:01:01.744Z,1535400061.744 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T20:01:43.456Z,1535400103.456 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:01:43.457Z,1535400103.457 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-08-27T20:01:43.457Z,1535400103.457 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:01:43.504Z,1535400103.504 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:01:43.915Z,1535400103.915 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:01:43.915Z,1535400103.915 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-08-27T20:01:44.265Z,1535400104.265 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:01:44.266Z,1535400104.266 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:01:44.266Z,1535400104.266 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:02:57.758Z,1535400177.758 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:02:57.758Z,1535400177.758 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-27T20:02:57.758Z,1535400177.758 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:02:58.125Z,1535400178.125 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:02:58.161Z,1535400178.161 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:02:58.572Z,1535400178.572 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:02:59.803Z,1535400179.803 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T20:02:59.804Z,1535400179.804 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-27T20:03:00.197Z,1535400180.197 [Rowe_600LCM](INFO): Initializing
2018-08-27T20:03:00.198Z,1535400180.198 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T20:03:00.198Z,1535400180.198 [Rowe_600LCM](INFO): LCM OK
2018-08-27T20:03:00.198Z,1535400180.198 [Rowe_600LCM](INFO): Powering up
2018-08-27T20:03:04.344Z,1535400184.344 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T20:03:04.408Z,1535400184.408 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T20:03:04.423Z,1535400184.423 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T20:03:04.424Z,1535400184.424 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T20:03:04.425Z,1535400184.425 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T20:03:22.866Z,1535400202.866 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-27T20:03:22.866Z,1535400202.866 [Default:CheckIn:C.Wait] Stopped
2018-08-27T20:03:22.866Z,1535400202.866 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-27T20:03:22.866Z,1535400202.866 [Default:CheckIn:D] Running Loop=1
2018-08-27T20:03:23.296Z,1535400203.296 [Default:CheckIn:D] Stopped
2018-08-27T20:03:23.296Z,1535400203.296 [Default:CheckIn:E] Running Loop=1
2018-08-27T20:03:23.699Z,1535400203.699 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.798747 min 
2018-08-27T20:03:23.699Z,1535400203.699 [Default:CheckIn:E] Stopped
2018-08-27T20:03:23.700Z,1535400203.700 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-27T20:03:23.700Z,1535400203.700 [Default:CheckIn] Stopped
2018-08-27T20:03:23.700Z,1535400203.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-27T20:03:23.700Z,1535400203.700 [Default:CheckIn](INFO): Running loop #5
2018-08-27T20:03:23.700Z,1535400203.700 [Default:CheckIn] Running Loop=5
2018-08-27T20:03:23.700Z,1535400203.700 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-27T20:03:23.700Z,1535400203.700 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-27T20:04:05.964Z,1535400245.964 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T20:04:05.964Z,1535400245.964 [BPC1] Communications Fault, FailCount= 7
2018-08-27T20:04:05.965Z,1535400245.965 [BPC1](ERROR): Communications Fault
2018-08-27T20:04:06.021Z,1535400246.021 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T20:04:07.188Z,1535400247.188 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T20:04:07.188Z,1535400247.188 [BPC1] No Fault, FailCount= 7
2018-08-27T20:04:44.541Z,1535400284.541 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:04:44.541Z,1535400284.541 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-08-27T20:04:44.541Z,1535400284.541 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:04:44.579Z,1535400284.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:04:44.890Z,1535400284.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:04:44.891Z,1535400284.891 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-08-27T20:04:45.248Z,1535400285.248 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:04:45.249Z,1535400285.249 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:04:45.249Z,1535400285.249 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:05:00.345Z,1535400300.345 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:05:00.345Z,1535400300.345 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-27T20:05:00.345Z,1535400300.345 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:05:00.568Z,1535400300.568 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:05:00.750Z,1535400300.750 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:05:01.007Z,1535400301.007 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:05:02.484Z,1535400302.484 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T20:05:02.484Z,1535400302.484 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-27T20:05:02.621Z,1535400302.621 [Rowe_600LCM](INFO): Initializing
2018-08-27T20:05:02.622Z,1535400302.622 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T20:05:02.622Z,1535400302.622 [Rowe_600LCM](INFO): LCM OK
2018-08-27T20:05:02.622Z,1535400302.622 [Rowe_600LCM](INFO): Powering up
2018-08-27T20:05:06.812Z,1535400306.812 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T20:05:07.039Z,1535400307.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-08-27T20:05:07.041Z,1535400307.041 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T20:05:07.042Z,1535400307.042 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T20:05:07.043Z,1535400307.043 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T20:07:02.757Z,1535400422.757 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:07:02.757Z,1535400422.757 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-27T20:07:02.757Z,1535400422.757 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:07:02.818Z,1535400422.818 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:07:02.818Z,1535400422.818 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-27T20:07:03.161Z,1535400423.161 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:07:03.409Z,1535400423.409 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:07:45.625Z,1535400465.625 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:07:45.625Z,1535400465.625 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-08-27T20:07:45.625Z,1535400465.625 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:07:45.638Z,1535400465.638 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:07:46.083Z,1535400466.083 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:07:46.083Z,1535400466.083 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-08-27T20:07:46.468Z,1535400466.468 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:07:46.468Z,1535400466.468 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:07:46.472Z,1535400466.472 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:08:24.055Z,1535400504.055 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-27T20:03:23.7Z
2018-08-27T20:08:24.055Z,1535400504.055 [Default:CheckIn:Read_GPS] Stopped
2018-08-27T20:08:24.055Z,1535400504.055 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-27T20:08:29.990Z,1535400509.990 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180827T191618/Courier0016.lzma
2018-08-27T20:08:30.788Z,1535400510.788 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Courier0016.lzma.bak
2018-08-27T20:08:30.788Z,1535400510.788 [DataOverHttps](INFO): SBD MOMSN=8448417
2018-08-27T20:08:47.642Z,1535400527.642 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20180827T191618/Express0017.lzma
2018-08-27T20:08:48.440Z,1535400528.440 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Express0017.lzma.bak
2018-08-27T20:08:48.440Z,1535400528.440 [DataOverHttps](INFO): SBD MOMSN=8448419
2018-08-27T20:08:50.631Z,1535400530.631 [Default:CheckIn:Read_Iridium] Stopped
2018-08-27T20:08:50.631Z,1535400530.631 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-27T20:08:50.631Z,1535400530.631 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-27T20:10:03.603Z,1535400603.603 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-27T20:10:03.603Z,1535400603.603 [NAL9602] Data Fault, FailCount= 5
2018-08-27T20:10:03.603Z,1535400603.603 [NAL9602](ERROR): Data Fault
2018-08-27T20:10:03.698Z,1535400603.698 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-27T20:10:03.698Z,1535400603.698 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-08-27T20:10:04.002Z,1535400604.002 [NAL9602](INFO): Powering down
2018-08-27T20:10:04.092Z,1535400604.092 [CommandLine](FAULT): Scheduling is paused
2018-08-27T20:10:04.098Z,1535400604.098 [CBIT](INFO): Critical error at 20180827T201003
2018-08-27T20:10:04.098Z,1535400604.098 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-08-27T20:10:46.850Z,1535400646.850 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:10:46.850Z,1535400646.850 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-08-27T20:10:46.850Z,1535400646.850 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:10:46.863Z,1535400646.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:10:47.266Z,1535400647.266 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:10:47.266Z,1535400647.266 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-08-27T20:10:47.652Z,1535400647.652 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:10:47.653Z,1535400647.653 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:10:47.653Z,1535400647.653 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:11:30.098Z,1535400690.098 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T20:11:30.098Z,1535400690.098 [BPC1] Communications Fault, FailCount= 8
2018-08-27T20:11:30.099Z,1535400690.099 [BPC1](ERROR): Communications Fault
2018-08-27T20:11:30.123Z,1535400690.123 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T20:11:31.320Z,1535400691.320 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T20:11:31.321Z,1535400691.321 [BPC1] No Fault, FailCount= 8
2018-08-27T20:12:04.004Z,1535400724.004 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-27T20:12:04.004Z,1535400724.004 [NAL9602] No Fault, FailCount= 5
2018-08-27T20:12:04.350Z,1535400724.350 [NAL9602](INFO): Powering up NAL9602
2018-08-27T20:12:15.157Z,1535400735.157 [NAL9602](INFO): NAL9602 initialized
2018-08-27T20:13:47.768Z,1535400827.768 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:13:47.768Z,1535400827.768 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-08-27T20:13:47.768Z,1535400827.768 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:13:47.782Z,1535400827.782 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:13:48.187Z,1535400828.187 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:13:48.187Z,1535400828.187 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-08-27T20:13:48.579Z,1535400828.579 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:13:48.580Z,1535400828.580 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:13:48.580Z,1535400828.580 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:13:51.030Z,1535400831.030 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-27T20:13:51.031Z,1535400831.031 [Default:CheckIn:C.Wait] Stopped
2018-08-27T20:13:51.031Z,1535400831.031 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-27T20:13:51.031Z,1535400831.031 [Default:CheckIn:D] Running Loop=1
2018-08-27T20:13:51.383Z,1535400831.383 [Default:CheckIn:D] Stopped
2018-08-27T20:13:51.383Z,1535400831.383 [Default:CheckIn:E] Running Loop=1
2018-08-27T20:13:51.782Z,1535400831.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.266866 min 
2018-08-27T20:13:51.782Z,1535400831.782 [Default:CheckIn:E] Stopped
2018-08-27T20:13:51.782Z,1535400831.782 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-27T20:13:51.782Z,1535400831.782 [Default:CheckIn] Stopped
2018-08-27T20:13:51.783Z,1535400831.783 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-27T20:13:51.783Z,1535400831.783 [Default:CheckIn](INFO): Running loop #6
2018-08-27T20:13:51.783Z,1535400831.783 [Default:CheckIn] Running Loop=6
2018-08-27T20:13:51.783Z,1535400831.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-27T20:13:51.783Z,1535400831.783 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-27T20:16:48.735Z,1535401008.735 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:16:48.735Z,1535401008.735 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-08-27T20:16:48.735Z,1535401008.735 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:16:48.749Z,1535401008.749 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:16:49.160Z,1535401009.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:16:49.160Z,1535401009.160 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2018-08-27T20:16:49.579Z,1535401009.579 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:16:49.580Z,1535401009.580 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:16:49.580Z,1535401009.580 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:17:02.974Z,1535401022.974 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T20:17:02.974Z,1535401022.974 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-27T20:17:03.181Z,1535401023.181 [Rowe_600LCM](INFO): Initializing
2018-08-27T20:17:03.181Z,1535401023.181 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T20:17:03.181Z,1535401023.181 [Rowe_600LCM](INFO): LCM OK
2018-08-27T20:17:03.181Z,1535401023.181 [Rowe_600LCM](INFO): Powering up
2018-08-27T20:17:07.512Z,1535401027.512 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T20:17:07.612Z,1535401027.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-08-27T20:17:07.614Z,1535401027.614 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T20:17:07.615Z,1535401027.615 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T20:17:07.616Z,1535401027.616 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T20:17:32.109Z,1535401052.109 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T20:17:32.109Z,1535401052.109 [BPC1] Communications Fault, FailCount= 9
2018-08-27T20:17:32.109Z,1535401052.109 [BPC1](ERROR): Communications Fault
2018-08-27T20:17:32.169Z,1535401052.169 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T20:17:33.360Z,1535401053.360 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T20:17:33.360Z,1535401053.360 [BPC1] No Fault, FailCount= 9
2018-08-27T20:18:51.873Z,1535401131.873 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-27T20:13:51.8Z
2018-08-27T20:18:51.873Z,1535401131.873 [Default:CheckIn:Read_GPS] Stopped
2018-08-27T20:18:51.873Z,1535401131.873 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-27T20:18:57.898Z,1535401137.898 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20180827T191618/Courier0019.lzma
2018-08-27T20:18:59.091Z,1535401139.091 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Courier0019.lzma.bak
2018-08-27T20:18:59.091Z,1535401139.091 [DataOverHttps](INFO): SBD MOMSN=8448429
2018-08-27T20:19:03.224Z,1535401143.224 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:19:03.224Z,1535401143.224 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-27T20:19:03.224Z,1535401143.224 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:19:03.629Z,1535401143.629 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:19:03.934Z,1535401143.934 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:19:05.639Z,1535401145.639 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:19:05.639Z,1535401145.639 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T20:19:05.640Z,1535401145.640 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-27T20:19:05.953Z,1535401145.953 [Rowe_600LCM](INFO): Initializing
2018-08-27T20:19:05.954Z,1535401145.954 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T20:19:05.954Z,1535401145.954 [Rowe_600LCM](INFO): LCM OK
2018-08-27T20:19:05.954Z,1535401145.954 [Rowe_600LCM](INFO): Powering up
2018-08-27T20:19:10.147Z,1535401150.147 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T20:19:10.250Z,1535401150.250 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T20:19:10.252Z,1535401150.252 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T20:19:10.252Z,1535401150.252 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T20:19:10.253Z,1535401150.253 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T20:19:12.938Z,1535401152.938 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20180827T191618/Express0020.lzma
2018-08-27T20:19:14.136Z,1535401154.136 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Express0020.lzma.bak
2018-08-27T20:19:14.136Z,1535401154.136 [DataOverHttps](INFO): SBD MOMSN=8448432
2018-08-27T20:19:16.019Z,1535401156.019 [Default:CheckIn:Read_Iridium] Stopped
2018-08-27T20:19:16.019Z,1535401156.019 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-27T20:19:16.020Z,1535401156.020 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-27T20:19:49.906Z,1535401189.906 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:19:49.907Z,1535401189.907 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 21
2018-08-27T20:19:49.907Z,1535401189.907 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:19:49.925Z,1535401189.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:19:50.327Z,1535401190.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:19:50.328Z,1535401190.328 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 21
2018-08-27T20:19:50.665Z,1535401190.665 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:19:50.666Z,1535401190.666 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:19:50.666Z,1535401190.666 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:21:06.240Z,1535401266.240 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:21:06.240Z,1535401266.240 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-27T20:21:06.240Z,1535401266.240 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:21:06.432Z,1535401266.432 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:21:06.645Z,1535401266.645 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:21:06.748Z,1535401266.748 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:21:08.028Z,1535401268.028 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T20:21:08.028Z,1535401268.028 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-27T20:21:08.375Z,1535401268.375 [Rowe_600LCM](INFO): Initializing
2018-08-27T20:21:08.377Z,1535401268.377 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T20:21:08.377Z,1535401268.377 [Rowe_600LCM](INFO): LCM OK
2018-08-27T20:21:08.377Z,1535401268.377 [Rowe_600LCM](INFO): Powering up
2018-08-27T20:21:12.561Z,1535401272.561 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T20:21:12.659Z,1535401272.659 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T20:21:12.661Z,1535401272.661 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T20:21:12.662Z,1535401272.662 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T20:21:12.663Z,1535401272.663 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T20:22:16.019Z,1535401336.019 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-27T20:22:16.019Z,1535401336.019 [NAL9602] Data Fault, FailCount= 1
2018-08-27T20:22:16.019Z,1535401336.019 [NAL9602](ERROR): Data Fault
2018-08-27T20:22:16.158Z,1535401336.158 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-27T20:22:16.414Z,1535401336.414 [NAL9602](INFO): Powering down
2018-08-27T20:22:17.305Z,1535401337.305 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-27T20:22:17.305Z,1535401337.305 [NAL9602] No Fault, FailCount= 1
2018-08-27T20:22:46.666Z,1535401366.666 [NAL9602](INFO): Powering up NAL9602
2018-08-27T20:22:50.722Z,1535401370.722 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:22:50.722Z,1535401370.722 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 22
2018-08-27T20:22:50.722Z,1535401370.722 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:22:50.736Z,1535401370.736 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:22:51.221Z,1535401371.221 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:22:51.221Z,1535401371.221 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 22
2018-08-27T20:22:51.555Z,1535401371.555 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:22:51.555Z,1535401371.555 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:22:51.556Z,1535401371.556 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:22:57.866Z,1535401377.866 [NAL9602](INFO): NAL9602 initialized
2018-08-27T20:23:08.423Z,1535401388.423 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:23:08.423Z,1535401388.423 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-27T20:23:08.423Z,1535401388.423 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:23:08.741Z,1535401388.741 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:23:08.825Z,1535401388.825 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:23:08.955Z,1535401388.955 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:23:10.361Z,1535401390.361 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T20:23:10.361Z,1535401390.361 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-27T20:23:10.569Z,1535401390.569 [Rowe_600LCM](INFO): Initializing
2018-08-27T20:23:10.569Z,1535401390.569 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T20:23:10.570Z,1535401390.570 [Rowe_600LCM](INFO): LCM OK
2018-08-27T20:23:10.570Z,1535401390.570 [Rowe_600LCM](INFO): Powering up
2018-08-27T20:23:14.812Z,1535401394.812 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T20:23:14.921Z,1535401394.921 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T20:23:14.922Z,1535401394.922 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T20:23:14.923Z,1535401394.923 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T20:23:14.924Z,1535401394.924 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T20:23:33.929Z,1535401413.929 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-27T20:23:33.929Z,1535401413.929 [BPC1] Communications Fault, FailCount= 10
2018-08-27T20:23:33.929Z,1535401413.929 [BPC1](ERROR): Communications Fault
2018-08-27T20:23:34.001Z,1535401414.001 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-27T20:23:34.013Z,1535401414.013 [CBIT](CRITICAL): Communications Fault in component: BPC1
2018-08-27T20:23:34.356Z,1535401414.356 [CBIT](INFO): Critical error at 20180827T202334
2018-08-27T20:24:16.346Z,1535401456.346 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-27T20:24:16.346Z,1535401456.346 [Default:CheckIn:C.Wait] Stopped
2018-08-27T20:24:16.346Z,1535401456.346 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-27T20:24:16.346Z,1535401456.346 [Default:CheckIn:D] Running Loop=1
2018-08-27T20:24:16.750Z,1535401456.750 [Default:CheckIn:D] Stopped
2018-08-27T20:24:16.750Z,1535401456.750 [Default:CheckIn:E] Running Loop=1
2018-08-27T20:24:17.142Z,1535401457.142 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.689657 min 
2018-08-27T20:24:17.143Z,1535401457.143 [Default:CheckIn:E] Stopped
2018-08-27T20:24:17.143Z,1535401457.143 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-27T20:24:17.144Z,1535401457.144 [Default:CheckIn] Stopped
2018-08-27T20:24:17.144Z,1535401457.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-27T20:24:17.144Z,1535401457.144 [Default:CheckIn](INFO): Running loop #7
2018-08-27T20:24:17.145Z,1535401457.145 [Default:CheckIn] Running Loop=7
2018-08-27T20:24:17.145Z,1535401457.145 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-27T20:24:17.146Z,1535401457.146 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-27T20:25:10.611Z,1535401510.611 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:25:10.611Z,1535401510.611 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-27T20:25:10.611Z,1535401510.611 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:25:10.933Z,1535401510.933 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:25:11.013Z,1535401511.013 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:25:11.112Z,1535401511.112 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:25:12.542Z,1535401512.542 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-27T20:25:12.543Z,1535401512.543 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-27T20:25:12.729Z,1535401512.729 [Rowe_600LCM](INFO): Initializing
2018-08-27T20:25:12.729Z,1535401512.729 [Rowe_600LCM](INFO): Checking LCM
2018-08-27T20:25:12.730Z,1535401512.730 [Rowe_600LCM](INFO): LCM OK
2018-08-27T20:25:12.730Z,1535401512.730 [Rowe_600LCM](INFO): Powering up
2018-08-27T20:25:16.857Z,1535401516.857 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-27T20:25:16.984Z,1535401516.984 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-27T20:25:16.985Z,1535401516.985 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-27T20:25:16.986Z,1535401516.986 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-27T20:25:16.987Z,1535401516.987 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-27T20:25:51.620Z,1535401551.620 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:25:51.620Z,1535401551.620 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 23
2018-08-27T20:25:51.620Z,1535401551.620 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:25:51.671Z,1535401551.671 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:25:52.054Z,1535401552.054 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:25:52.054Z,1535401552.054 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 23
2018-08-27T20:25:52.435Z,1535401552.435 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:25:52.435Z,1535401552.435 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:25:52.436Z,1535401552.436 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:27:12.800Z,1535401632.800 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-27T20:27:12.801Z,1535401632.801 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-27T20:27:12.801Z,1535401632.801 [Rowe_600LCM](ERROR): Communications Fault
2018-08-27T20:27:12.964Z,1535401632.964 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-27T20:27:12.965Z,1535401632.965 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-27T20:27:13.205Z,1535401633.205 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:27:13.465Z,1535401633.465 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:28:34.045Z,1535401714.045 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-27T20:28:34.046Z,1535401714.046 [BPC1] No Fault, FailCount= 10
2018-08-27T20:28:52.774Z,1535401732.774 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:28:52.774Z,1535401732.774 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 24
2018-08-27T20:28:52.774Z,1535401732.774 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:28:52.808Z,1535401732.808 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:28:53.219Z,1535401733.219 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:28:53.219Z,1535401733.219 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 24
2018-08-27T20:28:53.576Z,1535401733.576 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:28:53.576Z,1535401733.576 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:28:53.577Z,1535401733.577 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:29:17.190Z,1535401757.190 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-27T20:24:17.1Z
2018-08-27T20:29:17.190Z,1535401757.190 [Default:CheckIn:Read_GPS] Stopped
2018-08-27T20:29:17.191Z,1535401757.191 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-27T20:29:24.586Z,1535401764.586 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20180827T191618/Courier0022.lzma
2018-08-27T20:29:25.783Z,1535401765.783 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Courier0022.lzma.bak
2018-08-27T20:29:25.783Z,1535401765.783 [DataOverHttps](INFO): SBD MOMSN=8448437
2018-08-27T20:29:43.555Z,1535401783.555 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20180827T191618/Express0023.lzma
2018-08-27T20:29:44.752Z,1535401784.752 [DataOverHttps](INFO): Moved sent file to Logs/20180827T191618/Express0023.lzma.bak
2018-08-27T20:29:44.752Z,1535401784.752 [DataOverHttps](INFO): SBD MOMSN=8448440
2018-08-27T20:29:46.679Z,1535401786.679 [Default:CheckIn:Read_Iridium] Stopped
2018-08-27T20:29:46.679Z,1535401786.679 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-27T20:29:46.679Z,1535401786.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-27T20:31:13.936Z,1535401873.936 [CommandLine](IMPORTANT): got command configSet BPC1.loadAtStartup 0.000000 bool persist
2018-08-27T20:31:13.938Z,1535401873.938 [CommandLine](IMPORTANT): configSet BPC1.loadAtStartup requires a restart to take effect.
2018-08-27T20:31:53.833Z,1535401913.833 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-27T20:31:53.833Z,1535401913.833 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 25
2018-08-27T20:31:53.833Z,1535401913.833 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-27T20:31:53.933Z,1535401913.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-27T20:31:54.274Z,1535401914.274 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-27T20:31:54.274Z,1535401914.274 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 25
2018-08-27T20:31:54.573Z,1535401914.573 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-27T20:31:54.574Z,1535401914.574 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-27T20:31:54.574Z,1535401914.574 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-27T20:32:59.582Z,1535401979.582 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-27T20:32:59.583Z,1535401979.583 [NAL9602] Data Fault, FailCount= 2
2018-08-27T20:32:59.583Z,1535401979.583 [NAL9602](ERROR): Data Fault
2018-08-27T20:32:59.688Z,1535401979.688 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-27T20:32:59.986Z,1535401979.986 [NAL9602](INFO): Powering down
2018-08-27T20:33:00.889Z,1535401980.889 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-27T20:33:00.890Z,1535401980.890 [NAL9602] No Fault, FailCount= 2
2018-08-27T20:33:30.385Z,1535402010.385 [NAL9602](INFO): Powering up NAL9602
2018-08-27T20:33:34.068Z,1535402014.068 [CommandLine](IMPORTANT): got command restart application
2018-08-27T20:33:35.080Z,1535402015.080 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-27T20:33:35.081Z,1535402015.081 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.213Z,1535402015.213 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-08-27T20:33:35.213Z,1535402015.213 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.214Z,1535402015.214 [CommandLine](INFO): Join timeout helper Thread ID is 2290
2018-08-27T20:33:35.221Z,1535402015.221 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-08-27T20:33:35.221Z,1535402015.221 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.221Z,1535402015.221 [NavChartDb](INFO): Join timeout helper Thread ID is 2291
2018-08-27T20:33:35.297Z,1535402015.297 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-27T20:33:35.297Z,1535402015.297 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.305Z,1535402015.305 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-08-27T20:33:35.305Z,1535402015.305 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.305Z,1535402015.305 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2292
2018-08-27T20:33:35.649Z,1535402015.649 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-27T20:33:35.649Z,1535402015.649 [WetLabsBB2FL](INFO): Powering down
2018-08-27T20:33:35.650Z,1535402015.650 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.651Z,1535402015.651 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-08-27T20:33:35.651Z,1535402015.651 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.651Z,1535402015.651 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2293
2018-08-27T20:33:35.697Z,1535402015.697 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-27T20:33:35.783Z,1535402015.783 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-27T20:33:35.783Z,1535402015.783 [CTD_Seabird](INFO): Powering down
2018-08-27T20:33:35.784Z,1535402015.784 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.797Z,1535402015.797 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-08-27T20:33:35.797Z,1535402015.797 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:35.798Z,1535402015.798 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 2295
2018-08-27T20:33:35.921Z,1535402015.921 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-27T20:33:35.921Z,1535402015.921 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-27T20:33:36.110Z,1535402016.110 [Rowe_600LCM](INFO): Powering down
2018-08-27T20:33:36.111Z,1535402016.111 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.114Z,1535402016.114 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-08-27T20:33:36.114Z,1535402016.114 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.115Z,1535402016.115 [Radio_Surface](INFO): Join timeout helper Thread ID is 2297
2018-08-27T20:33:36.217Z,1535402016.217 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-27T20:33:36.217Z,1535402016.217 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.234Z,1535402016.234 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-08-27T20:33:36.234Z,1535402016.234 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.234Z,1535402016.234 [logger](INFO): Join timeout helper Thread ID is 2298
2018-08-27T20:33:36.241Z,1535402016.241 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-27T20:33:36.241Z,1535402016.241 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.253Z,1535402016.253 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-08-27T20:33:36.254Z,1535402016.254 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.254Z,1535402016.254 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-08-27T20:33:36.254Z,1535402016.254 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.254Z,1535402016.254 [controlThread](INFO): Join timeout helper Thread ID is 2299
2018-08-27T20:33:36.373Z,1535402016.373 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-27T20:33:36.373Z,1535402016.373 [controlThread](DEBUG): Uninitializing ControlThread
2018-08-27T20:33:36.374Z,1535402016.374 [NAL9602](INFO): Powering down
2018-08-27T20:33:36.375Z,1535402016.375 [PNI_TCM](INFO): Powering down
2018-08-27T20:33:36.462Z,1535402016.462 [Aanderaa_O2](INFO): Powering down
2018-08-27T20:33:36.465Z,1535402016.465 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-27T20:33:36.466Z,1535402016.466 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-08-27T20:33:36.467Z,1535402016.467 [MissionManager](INFO): Uninitializing Mission Default
2018-08-27T20:33:36.467Z,1535402016.467 [Default] Stopped
2018-08-27T20:33:36.467Z,1535402016.467 [Default](DEBUG): Aggregate::uninitialize Default
2018-08-27T20:33:36.467Z,1535402016.467 [Default:B.GoToSurface] Stopped
2018-08-27T20:33:36.467Z,1535402016.467 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-27T20:33:36.468Z,1535402016.468 [Default:CheckIn] Stopped
2018-08-27T20:33:36.468Z,1535402016.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-27T20:33:36.468Z,1535402016.468 [Default:CheckIn:C.Wait] Stopped
2018-08-27T20:33:36.468Z,1535402016.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-27T20:33:36.472Z,1535402016.472 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-08-27T20:33:36.472Z,1535402016.472 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-08-27T20:33:36.473Z,1535402016.473 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-08-27T20:33:36.473Z,1535402016.473 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-08-27T20:33:36.474Z,1535402016.474 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-27T20:33:36.474Z,1535402016.474 [BuoyancyServo](INFO): Powering down
2018-08-27T20:33:36.497Z,1535402016.497 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-08-27T20:33:36.497Z,1535402016.497 [ElevatorServo](INFO): Powering down
2018-08-27T20:33:36.498Z,1535402016.498 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-08-27T20:33:36.498Z,1535402016.498 [MassServo](INFO): Powering down
2018-08-27T20:33:36.499Z,1535402016.499 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-08-27T20:33:36.499Z,1535402016.499 [RudderServo](INFO): Powering down
2018-08-27T20:33:36.500Z,1535402016.500 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-08-27T20:33:36.500Z,1535402016.500 [ThrusterServo](INFO): Powering down
2018-08-27T20:33:36.537Z,1535402016.537 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-08-27T20:33:36.538Z,1535402016.538 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-08-27T20:33:36.539Z,1535402016.539 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-08-27T20:33:36.540Z,1535402016.540 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.642Z,1535402016.642 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.648Z,1535402016.648 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.685Z,1535402016.685 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.689Z,1535402016.689 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.734Z,1535402016.734 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-27T20:33:36.795Z,1535402016.795 [logger ThreadHandler](INFO): Thread cancelled.