2018-08-20T16:57:49.843Z,1534784269.843 [Supervisor](DEBUG): Initializing supervisor.
2018-08-20T16:57:49.845Z,1534784269.845 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-08-20T16:57:49.846Z,1534784269.846 [SyncHandler](INFO): Protected caller Thread ID is 798
2018-08-20T16:57:49.846Z,1534784269.846 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-08-20T16:57:49.847Z,1534784269.847 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-08-20T16:57:49.848Z,1534784269.848 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 799
2018-08-20T16:57:49.851Z,1534784269.851 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-08-20T16:57:49.863Z,1534784269.863 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-08-20T16:57:49.864Z,1534784269.864 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-08-20T16:57:49.864Z,1534784269.864 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 800
2018-08-20T16:57:49.865Z,1534784269.865 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-08-20T16:57:49.866Z,1534784269.866 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-08-20T16:57:49.866Z,1534784269.866 [logger ThreadHandler](INFO): Protected caller Thread ID is 801
2018-08-20T16:57:49.868Z,1534784269.868 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-08-20T16:57:49.869Z,1534784269.869 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-08-20T16:57:49.873Z,1534784269.873 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-08-20T16:57:50.255Z,1534784270.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-08-20T16:57:50.256Z,1534784270.256 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-08-20T16:57:50.489Z,1534784270.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-08-20T16:57:50.490Z,1534784270.490 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-08-20T16:57:50.810Z,1534784270.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-08-20T16:57:50.812Z,1534784270.812 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-08-20T16:57:50.963Z,1534784270.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-08-20T16:57:50.965Z,1534784270.965 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-08-20T16:57:51.068Z,1534784271.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-08-20T16:57:51.070Z,1534784271.070 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-08-20T16:57:51.593Z,1534784271.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-08-20T16:57:51.595Z,1534784271.595 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-08-20T16:57:52.066Z,1534784272.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-08-20T16:57:52.068Z,1534784272.068 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-08-20T16:57:52.581Z,1534784272.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-08-20T16:57:52.581Z,1534784272.581 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-08-20T16:57:52.692Z,1534784272.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-08-20T16:57:52.693Z,1534784272.693 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-08-20T16:57:52.795Z,1534784272.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-08-20T16:57:52.796Z,1534784272.796 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-08-20T16:57:53.022Z,1534784273.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-08-20T16:57:53.024Z,1534784273.024 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-08-20T16:57:53.256Z,1534784273.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-08-20T16:57:53.257Z,1534784273.257 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-08-20T16:57:53.647Z,1534784273.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-08-20T16:57:53.648Z,1534784273.648 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-08-20T16:57:54.534Z,1534784274.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-08-20T16:57:54.535Z,1534784274.535 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-08-20T16:57:54.620Z,1534784274.620 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-08-20T16:57:54.622Z,1534784274.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-08-20T16:57:54.735Z,1534784274.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-08-20T16:57:54.864Z,1534784274.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-08-20T16:57:54.964Z,1534784274.964 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-08-20T16:57:55.054Z,1534784275.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-08-20T16:57:55.228Z,1534784275.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-08-20T16:57:55.426Z,1534784275.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-08-20T16:57:55.503Z,1534784275.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-08-20T16:57:55.749Z,1534784275.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-08-20T16:57:55.749Z,1534784275.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-08-20T16:57:55.836Z,1534784275.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-08-20T16:57:55.932Z,1534784275.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-08-20T16:57:56.041Z,1534784276.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-08-20T16:57:56.180Z,1534784276.180 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-08-20T16:57:56.185Z,1534784276.185 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-08-20T16:57:56.481Z,1534784276.481 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-08-20T16:57:56.482Z,1534784276.482 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-08-20T16:57:56.540Z,1534784276.540 [DepthRateCalculator] Loaded
2018-08-20T16:57:56.540Z,1534784276.540 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-08-20T16:57:56.546Z,1534784276.546 [PitchRateCalculator] Loaded
2018-08-20T16:57:56.547Z,1534784276.547 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-08-20T16:57:56.564Z,1534784276.564 [SpeedCalculator] Loaded
2018-08-20T16:57:56.564Z,1534784276.564 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-08-20T16:57:56.587Z,1534784276.587 [TempGradientCalculator] Loaded
2018-08-20T16:57:56.587Z,1534784276.587 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-08-20T16:57:56.604Z,1534784276.604 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-08-20T16:57:56.604Z,1534784276.604 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-08-20T16:57:56.610Z,1534784276.610 [YawRateCalculator] Loaded
2018-08-20T16:57:56.610Z,1534784276.610 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-08-20T16:57:56.652Z,1534784276.652 [ElevatorOffsetCalculator] Loaded
2018-08-20T16:57:56.652Z,1534784276.652 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-08-20T16:57:56.653Z,1534784276.653 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-08-20T16:57:56.655Z,1534784276.655 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-08-20T16:57:56.780Z,1534784276.780 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-08-20T16:57:56.780Z,1534784276.780 [StratificationFrontDetector](DEBUG): (re)initializing
2018-08-20T16:57:56.780Z,1534784276.780 [StratificationFrontDetector] Loaded
2018-08-20T16:57:56.780Z,1534784276.780 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-08-20T16:57:56.781Z,1534784276.781 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-08-20T16:57:56.783Z,1534784276.783 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-08-20T16:57:56.921Z,1534784276.921 [BuoyancyServo] Loaded
2018-08-20T16:57:56.921Z,1534784276.921 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-08-20T16:57:56.937Z,1534784276.937 [ElevatorServo] Loaded
2018-08-20T16:57:56.937Z,1534784276.937 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-08-20T16:57:56.953Z,1534784276.953 [MassServo] Loaded
2018-08-20T16:57:56.953Z,1534784276.953 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-08-20T16:57:56.969Z,1534784276.969 [RudderServo] Loaded
2018-08-20T16:57:56.970Z,1534784276.970 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-08-20T16:57:56.985Z,1534784276.985 [ThrusterServo] Loaded
2018-08-20T16:57:56.985Z,1534784276.985 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-08-20T16:57:56.985Z,1534784276.985 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-08-20T16:57:56.987Z,1534784276.987 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-08-20T16:57:57.011Z,1534784277.011 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-08-20T16:57:57.012Z,1534784277.012 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-08-20T16:57:57.144Z,1534784277.144 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-08-20T16:57:57.146Z,1534784277.146 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-08-20T16:57:57.721Z,1534784277.721 [DataOverHttps] Loaded
2018-08-20T16:57:57.722Z,1534784277.722 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-08-20T16:57:57.736Z,1534784277.736 [Depth_Keller] Loaded
2018-08-20T16:57:57.736Z,1534784277.736 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-08-20T16:57:57.742Z,1534784277.742 [DropWeight] Loaded
2018-08-20T16:57:57.742Z,1534784277.742 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-08-20T16:57:57.847Z,1534784277.847 [NAL9602] Loaded
2018-08-20T16:57:57.848Z,1534784277.848 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-08-20T16:57:57.865Z,1534784277.865 [Onboard] Loaded
2018-08-20T16:57:57.865Z,1534784277.865 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-08-20T16:57:57.876Z,1534784277.876 [Radio_Surface] Loaded
2018-08-20T16:57:57.877Z,1534784277.877 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-08-20T16:57:57.878Z,1534784277.878 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408854E0
2018-08-20T16:57:57.878Z,1534784277.878 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883
2018-08-20T16:57:58.033Z,1534784278.033 [PNI_TCM] Loaded
2018-08-20T16:57:58.034Z,1534784278.034 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-08-20T16:57:58.098Z,1534784278.098 [Rowe_600LCM] Loaded
2018-08-20T16:57:58.098Z,1534784278.098 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-08-20T16:57:58.099Z,1534784278.099 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0
2018-08-20T16:57:58.100Z,1534784278.100 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 884
2018-08-20T16:58:00.220Z,1534784280.220 [BPC1] Loaded
2018-08-20T16:58:00.221Z,1534784280.221 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-08-20T16:58:00.221Z,1534784280.221 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-08-20T16:58:00.222Z,1534784280.222 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-08-20T16:58:00.386Z,1534784280.386 [SBIT](DEBUG): Construct Startup Built In Test.
2018-08-20T16:58:00.410Z,1534784280.410 [SBIT] Loaded
2018-08-20T16:58:00.410Z,1534784280.410 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-08-20T16:58:00.411Z,1534784280.411 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-08-20T16:58:00.423Z,1534784280.423 [IBIT] Loaded
2018-08-20T16:58:00.424Z,1534784280.424 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-08-20T16:58:00.427Z,1534784280.427 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-08-20T16:58:00.585Z,1534784280.585 [CBIT] Loaded
2018-08-20T16:58:00.586Z,1534784280.586 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-08-20T16:58:00.586Z,1534784280.586 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-08-20T16:58:00.588Z,1534784280.588 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-08-20T16:58:00.786Z,1534784280.786 [Aanderaa_O2] Loaded
2018-08-20T16:58:00.786Z,1534784280.786 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-08-20T16:58:00.867Z,1534784280.867 [CTD_NeilBrown] Loaded
2018-08-20T16:58:00.868Z,1534784280.868 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-08-20T16:58:00.869Z,1534784280.869 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409794E0
2018-08-20T16:58:00.869Z,1534784280.869 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 885
2018-08-20T16:58:00.923Z,1534784280.923 [CTD_Seabird] Loaded
2018-08-20T16:58:00.924Z,1534784280.924 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-08-20T16:58:00.925Z,1534784280.925 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409A94E0
2018-08-20T16:58:00.925Z,1534784280.925 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 886
2018-08-20T16:58:00.940Z,1534784280.940 [PAR_Licor] Loaded
2018-08-20T16:58:00.940Z,1534784280.940 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-08-20T16:58:00.993Z,1534784280.993 [WetLabsBB2FL] Loaded
2018-08-20T16:58:00.993Z,1534784280.993 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-08-20T16:58:00.994Z,1534784280.994 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409D94E0
2018-08-20T16:58:00.995Z,1534784280.995 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 887
2018-08-20T16:58:00.995Z,1534784280.995 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-08-20T16:58:00.996Z,1534784280.996 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-08-20T16:58:01.089Z,1534784281.089 [VerticalControl](DEBUG): Construct VerticalControl.
2018-08-20T16:58:01.177Z,1534784281.177 [VerticalControl] Loaded
2018-08-20T16:58:01.177Z,1534784281.177 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-08-20T16:58:01.178Z,1534784281.178 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-08-20T16:58:01.239Z,1534784281.239 [HorizontalControl] Loaded
2018-08-20T16:58:01.239Z,1534784281.239 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-08-20T16:58:01.240Z,1534784281.240 [SpeedControl](DEBUG): Construct SpeedControl.
2018-08-20T16:58:01.242Z,1534784281.242 [SpeedControl] Loaded
2018-08-20T16:58:01.242Z,1534784281.242 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-08-20T16:58:01.243Z,1534784281.243 [LoopControl](DEBUG): Construct LoopControl.
2018-08-20T16:58:01.243Z,1534784281.243 [LoopControl] Loaded
2018-08-20T16:58:01.244Z,1534784281.244 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-08-20T16:58:01.244Z,1534784281.244 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-08-20T16:58:01.246Z,1534784281.246 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-08-20T16:58:01.285Z,1534784281.285 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-08-20T16:58:01.286Z,1534784281.286 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-08-20T16:58:01.397Z,1534784281.397 [DeadReckonUsingSpeedCalculator] Loaded
2018-08-20T16:58:01.398Z,1534784281.398 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-08-20T16:58:01.415Z,1534784281.415 [NavChart] Loaded
2018-08-20T16:58:01.415Z,1534784281.415 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-08-20T16:58:01.419Z,1534784281.419 [UniversalFixResidualReporter] Loaded
2018-08-20T16:58:01.420Z,1534784281.420 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-08-20T16:58:01.420Z,1534784281.420 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-08-20T16:58:01.424Z,1534784281.424 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-08-20T16:58:01.425Z,1534784281.425 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-08-20T16:58:01.432Z,1534784281.432 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-08-20T16:58:01.433Z,1534784281.433 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A654E0
2018-08-20T16:58:01.433Z,1534784281.433 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 888
2018-08-20T16:58:01.438Z,1534784281.438 [Supervisor](INFO): Main Thread ID is 797
2018-08-20T16:58:01.438Z,1534784281.438 [Supervisor](DEBUG): Running supervisor.
2018-08-20T16:58:01.439Z,1534784281.439 [CommandLine ThreadHandler](INFO): Handler Thread ID is 889
2018-08-20T16:58:01.441Z,1534784281.441 [controlThread ThreadHandler](INFO): Handler Thread ID is 890
2018-08-20T16:58:01.441Z,1534784281.441 [controlThread](DEBUG): Initializing ControlThread
2018-08-20T16:58:01.442Z,1534784281.442 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-20T16:58:01.443Z,1534784281.443 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-08-20T16:58:01.443Z,1534784281.443 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-08-20T16:58:01.443Z,1534784281.443 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-08-20T16:58:01.444Z,1534784281.444 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-08-20T16:58:01.445Z,1534784281.445 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-08-20T16:58:01.445Z,1534784281.445 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-08-20T16:58:01.453Z,1534784281.453 [SBIT](INFO): Initialize SBIT Component.
2018-08-20T16:58:01.454Z,1534784281.454 [SBIT](IMPORTANT): git: 2018-08-20
2018-08-20T16:58:01.454Z,1534784281.454 [SBIT](INFO): git hash: 695b514009632b0c9b638e65628c1e5f155de51b
2018-08-20T16:58:01.455Z,1534784281.455 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-08-20T16:58:01.456Z,1534784281.456 [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-20T16:58:01.457Z,1534784281.457 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-08-20T16:58:01.457Z,1534784281.457 [IBIT](INFO): Initialize IBIT Component.
2018-08-20T16:58:01.458Z,1534784281.458 [CBIT](DEBUG): Initialize CBIT Component.
2018-08-20T16:58:01.459Z,1534784281.459 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-08-20T16:58:01.459Z,1534784281.459 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-08-20T16:58:01.460Z,1534784281.460 [logger ThreadHandler](INFO): Handler Thread ID is 891
2018-08-20T16:58:01.479Z,1534784281.479 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 892
2018-08-20T16:58:01.486Z,1534784281.486 [Radio_Surface](INFO): Powering up
2018-08-20T16:58:01.491Z,1534784281.491 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 893
2018-08-20T16:58:01.492Z,1534784281.492 [Rowe_600LCM](INFO): Initializing
2018-08-20T16:58:01.493Z,1534784281.493 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T16:58:01.573Z,1534784281.573 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-08-20T16:58:01.579Z,1534784281.579 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-08-20T16:58:01.582Z,1534784281.582 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-08-20T16:58:01.583Z,1534784281.583 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-08-20T16:58:01.584Z,1534784281.584 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T16:58:01.584Z,1534784281.584 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T16:58:01.585Z,1534784281.585 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T16:58:01.585Z,1534784281.585 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-08-20T16:58:01.586Z,1534784281.586 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-08-20T16:58:01.599Z,1534784281.599 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-08-20T16:58:01.611Z,1534784281.611 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 894
2018-08-20T16:58:01.612Z,1534784281.612 [CTD_NeilBrown](INFO): Powering down
2018-08-20T16:58:01.755Z,1534784281.755 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 895
2018-08-20T16:58:01.756Z,1534784281.756 [CTD_Seabird](INFO): Initializing
2018-08-20T16:58:01.756Z,1534784281.756 [CTD_Seabird](INFO): Checking LCM
2018-08-20T16:58:01.758Z,1534784281.758 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 896
2018-08-20T16:58:01.760Z,1534784281.760 [WetLabsBB2FL](INFO): Powering down
2018-08-20T16:58:01.859Z,1534784281.859 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 897
2018-08-20T16:58:01.862Z,1534784281.862 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-20T16:58:01.871Z,1534784281.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-08-20T16:58:01.871Z,1534784281.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-08-20T16:58:01.871Z,1534784281.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-08-20T16:58:01.872Z,1534784281.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-08-20T16:58:01.872Z,1534784281.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-08-20T16:58:01.872Z,1534784281.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-08-20T16:58:01.872Z,1534784281.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-08-20T16:58:01.872Z,1534784281.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-08-20T16:58:01.873Z,1534784281.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-08-20T16:58:01.873Z,1534784281.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-08-20T16:58:01.873Z,1534784281.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-08-20T16:58:01.873Z,1534784281.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-08-20T16:58:01.873Z,1534784281.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-08-20T16:58:01.874Z,1534784281.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-08-20T16:58:01.874Z,1534784281.874 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-08-20T16:58:01.874Z,1534784281.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-08-20T16:58:01.944Z,1534784281.944 [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-20T16:58:01.944Z,1534784281.944 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-08-20T16:58:01.971Z,1534784281.971 [CTD_Seabird](INFO): LCM OK
2018-08-20T16:58:01.971Z,1534784281.971 [CTD_Seabird](INFO): Powering up
2018-08-20T16:58:01.972Z,1534784281.972 [Rowe_600LCM](INFO): LCM OK
2018-08-20T16:58:01.972Z,1534784281.972 [Rowe_600LCM](INFO): Powering up
2018-08-20T16:58:02.029Z,1534784282.029 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-08-20T16:58:02.030Z,1534784282.030 [Default:A.Wait](DEBUG): Construct Wait.
2018-08-20T16:58:02.052Z,1534784282.052 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-20T16:58:02.087Z,1534784282.087 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-08-20T16:58:02.090Z,1534784282.090 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-08-20T16:58:02.116Z,1534784282.116 [Default:E.Execute](DEBUG): Construct Execute.
2018-08-20T16:58:02.144Z,1534784282.144 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-08-20T16:58:02.149Z,1534784282.149 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-08-20T16:58:02.183Z,1534784282.183 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-08-20T16:58:02.193Z,1534784282.193 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-08-20T16:58:02.453Z,1534784282.453 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-08-20T16:58:02.563Z,1534784282.563 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T16:58:02.743Z,1534784282.743 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-20T16:58:02.751Z,1534784282.751 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-20T16:58:02.762Z,1534784282.762 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-08-20T16:58:02.767Z,1534784282.767 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-08-20T16:58:02.774Z,1534784282.774 [MassServo](DEBUG): Initializing EZServoServo.
2018-08-20T16:58:02.779Z,1534784282.779 [MassServo](DEBUG): Initializing MassServo.
2018-08-20T16:58:02.786Z,1534784282.786 [RudderServo](DEBUG): Initializing EZServoServo.
2018-08-20T16:58:02.791Z,1534784282.791 [RudderServo](DEBUG): Initializing RudderServo.
2018-08-20T16:58:02.798Z,1534784282.798 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-08-20T16:58:02.803Z,1534784282.803 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-08-20T16:58:02.820Z,1534784282.820 [CommandLine](FAULT): Scheduling is paused
2018-08-20T16:58:02.821Z,1534784282.821 [CBIT](INFO): Critical error at 20180820T165801
2018-08-20T16:58:02.821Z,1534784282.821 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-08-20T16:58:03.081Z,1534784283.081 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T16:58:03.364Z,1534784283.364 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T16:58:03.687Z,1534784283.687 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-08-20T16:58:03.687Z,1534784283.687 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-08-20T16:58:03.687Z,1534784283.687 [BuoyancyServo] Communications Fault, FailCount= 1
2018-08-20T16:58:03.687Z,1534784283.687 [BuoyancyServo](ERROR): Communications Fault
2018-08-20T16:58:03.903Z,1534784283.903 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-08-20T16:58:03.965Z,1534784283.965 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T16:58:03.971Z,1534784283.971 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-20T16:58:03.972Z,1534784283.972 [BuoyancyServo](INFO): Powering down
2018-08-20T16:58:04.411Z,1534784284.411 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T16:58:04.804Z,1534784284.804 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T16:58:05.164Z,1534784285.164 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T16:58:05.667Z,1534784285.667 [Aanderaa_O2](INFO): Powering down
2018-08-20T16:58:05.752Z,1534784285.752 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T16:58:05.897Z,1534784285.897 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T16:58:06.026Z,1534784286.026 [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-20T16:58:06.166Z,1534784286.166 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T16:58:06.179Z,1534784286.179 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T16:58:06.180Z,1534784286.180 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T16:58:06.213Z,1534784286.213 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-08-20T16:58:06.213Z,1534784286.213 [BuoyancyServo] No Fault, FailCount= 1
2018-08-20T16:58:06.425Z,1534784286.425 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-20T16:58:06.425Z,1534784286.425 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-20T16:58:07.251Z,1534784287.251 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-08-20T16:58:07.252Z,1534784287.252 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-08-20T16:58:07.252Z,1534784287.252 [BuoyancyServo] Communications Fault, FailCount= 2
2018-08-20T16:58:07.252Z,1534784287.252 [BuoyancyServo](ERROR): Communications Fault
2018-08-20T16:58:07.348Z,1534784287.348 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-08-20T16:58:07.404Z,1534784287.404 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-20T16:58:07.632Z,1534784287.632 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-20T16:58:07.633Z,1534784287.633 [BuoyancyServo](INFO): Powering down
2018-08-20T16:58:07.682Z,1534784287.682 [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-20T16:58:07.684Z,1534784287.684 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-08-20T16:58:08.540Z,1534784288.540 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-20T16:58:08.540Z,1534784288.540 [CTD_Seabird](INFO): Powering down
2018-08-20T16:58:09.660Z,1534784289.660 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-08-20T16:58:09.660Z,1534784289.660 [BuoyancyServo] No Fault, FailCount= 2
2018-08-20T16:58:09.962Z,1534784289.962 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-20T16:58:09.962Z,1534784289.962 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-20T16:58:25.206Z,1534784305.206 [SBIT](IMPORTANT): Beginning Startup BIT
2018-08-20T16:58:25.219Z,1534784305.219 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-20T16:58:28.078Z,1534784308.078 [NAL9602](INFO): Powering up NAL9602
2018-08-20T16:58:36.188Z,1534784316.188 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006155
CHAN A1 (24V): -0.009042
CHAN A2 (12V): 4.697929
CHAN A3 (5V): -0.000600
CHAN B0 (3.3V): 0.000204
CHAN B1 (3.15aV): -0.000496
CHAN B2 (3.15bV): -0.000043
CHAN B3 (GND): 0.000488
OPEN: -0.000342
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-20T16:58:39.266Z,1534784319.266 [NAL9602](INFO): NAL9602 initialized
2018-08-20T16:59:19.495Z,1534784359.495 [SBIT](IMPORTANT): SBIT PASSED
2018-08-20T16:59:19.572Z,1534784359.572 [CommandLine](IMPORTANT): got command configSet list
2018-08-20T16:59:19.572Z,1534784359.572 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-08-20T16:59:19.577Z,1534784359.577 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2018-08-20T16:59:19.577Z,1534784359.577 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-08-20T16:59:19.856Z,1534784359.856 [MissionManager](IMPORTANT): Started mission Startup
2018-08-20T16:59:19.857Z,1534784359.857 [Startup] Running Loop=1
2018-08-20T16:59:19.857Z,1534784359.857 [Startup](DEBUG): Aggregate::initialize Startup
2018-08-20T16:59:19.857Z,1534784359.857 [Startup:A.GoToSurface] Running Loop=1
2018-08-20T16:59:19.857Z,1534784359.857 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-20T16:59:19.858Z,1534784359.858 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-20T16:59:19.883Z,1534784359.883 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-20T16:59:19.884Z,1534784359.884 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-20T16:59:19.884Z,1534784359.884 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-20T16:59:19.885Z,1534784359.885 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-20T16:59:19.910Z,1534784359.910 [Startup:StartupSatComms] Running Loop=1
2018-08-20T16:59:19.910Z,1534784359.910 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-08-20T16:59:19.910Z,1534784359.910 [Startup:StartupSatComms:A] Running Loop=1
2018-08-20T16:59:20.242Z,1534784360.242 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-08-20T16:59:35.005Z,1534784375.005 [BPC1](ERROR): Failed to parse Bank B battery data
2018-08-20T17:00:02.523Z,1534784402.523 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:00:02.523Z,1534784402.523 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T17:00:02.523Z,1534784402.523 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:00:02.661Z,1534784402.661 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:00:02.927Z,1534784402.927 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:00:03.244Z,1534784403.244 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:00:04.615Z,1534784404.615 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:00:04.615Z,1534784404.615 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T17:00:04.859Z,1534784404.859 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:00:04.860Z,1534784404.860 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:00:04.860Z,1534784404.860 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:00:04.860Z,1534784404.860 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:00:09.088Z,1534784409.088 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:00:09.169Z,1534784409.169 [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-20T17:00:09.170Z,1534784409.170 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:00:09.171Z,1534784409.171 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:00:09.172Z,1534784409.172 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:00:20.184Z,1534784420.184 [Startup:StartupSatComms:A](INFO): Timed out from 2018-08-20T16:59:19.9Z
2018-08-20T17:00:20.184Z,1534784420.184 [Startup:StartupSatComms:A] Stopped
2018-08-20T17:00:20.184Z,1534784420.184 [Startup:StartupSatComms:B] Running Loop=1
2018-08-20T17:00:20.594Z,1534784420.594 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-20T17:00:37.154Z,1534784437.155 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004609
2018-08-20T17:00:43.335Z,1534784443.335 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2018-08-20T17:00:43.335Z,1534784443.335 [PNI_TCM] Data Fault, FailCount= 1
2018-08-20T17:00:43.335Z,1534784443.335 [PNI_TCM](ERROR): Data Fault
2018-08-20T17:00:43.354Z,1534784443.354 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.951102 seconds old.
2018-08-20T17:00:43.369Z,1534784443.369 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-08-20T17:00:43.381Z,1534784443.381 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180820T165543/Courier0004.lzma
2018-08-20T17:00:43.466Z,1534784443.466 [PNI_TCM](INFO): Powering down
2018-08-20T17:00:43.499Z,1534784443.499 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.096578 seconds old.
2018-08-20T17:00:43.838Z,1534784443.838 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.434984 seconds old.
2018-08-20T17:00:44.182Z,1534784444.182 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165543/Courier0004.lzma.bak
2018-08-20T17:00:44.183Z,1534784444.183 [DataOverHttps](INFO): SBD MOMSN=8430128
2018-08-20T17:00:44.258Z,1534784444.258 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.855524 seconds old.
2018-08-20T17:00:44.823Z,1534784444.823 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.404215 seconds old.
2018-08-20T17:00:44.857Z,1534784444.857 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-08-20T17:00:44.857Z,1534784444.857 [PNI_TCM] No Fault, FailCount= 1
2018-08-20T17:00:45.032Z,1534784445.032 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.629270 seconds old.
2018-08-20T17:00:48.850Z,1534784448.850 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.447785 seconds old.
2018-08-20T17:00:48.970Z,1534784448.970 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.567248 seconds old.
2018-08-20T17:00:49.353Z,1534784449.353 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.950156 seconds old.
2018-08-20T17:00:49.807Z,1534784449.807 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.400244 seconds old.
2018-08-20T17:00:50.153Z,1534784450.153 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.749943 seconds old.
2018-08-20T17:00:50.558Z,1534784450.558 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.154803 seconds old.
2018-08-20T17:00:54.673Z,1534784454.673 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20180820T165749/Courier0000.lzma
2018-08-20T17:00:55.470Z,1534784455.470 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Courier0000.lzma.bak
2018-08-20T17:00:55.470Z,1534784455.470 [DataOverHttps](INFO): SBD MOMSN=8430130
2018-08-20T17:01:01.865Z,1534784461.865 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:01:01.865Z,1534784461.865 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-08-20T17:01:01.865Z,1534784461.865 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:01:01.901Z,1534784461.901 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:01:05.316Z,1534784465.316 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:01:05.316Z,1534784465.316 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-08-20T17:01:05.337Z,1534784465.337 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180820T165543/Express0005.lzma
2018-08-20T17:01:05.420Z,1534784465.420 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:01:05.420Z,1534784465.420 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:01:05.421Z,1534784465.421 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:01:06.126Z,1534784466.126 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165543/Express0005.lzma.bak
2018-08-20T17:01:06.126Z,1534784466.126 [DataOverHttps](INFO): SBD MOMSN=8430134
2018-08-20T17:01:15.777Z,1534784475.777 [DataOverHttps](INFO): Sending 800 bytes from file Logs/20180820T165749/Express0001.lzma
2018-08-20T17:01:16.566Z,1534784476.566 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Express0001.lzma.bak
2018-08-20T17:01:16.566Z,1534784476.566 [DataOverHttps](INFO): SBD MOMSN=8430136
2018-08-20T17:01:17.408Z,1534784477.408 [Startup:StartupSatComms:B] Stopped
2018-08-20T17:01:17.408Z,1534784477.408 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-08-20T17:01:17.408Z,1534784477.408 [Startup:StartupSatComms] Stopped
2018-08-20T17:01:17.408Z,1534784477.408 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-08-20T17:01:17.410Z,1534784477.410 [Startup](INFO): Completed Startup
2018-08-20T17:01:17.410Z,1534784477.410 [MissionManager](INFO): Startup is completed.
2018-08-20T17:01:17.410Z,1534784477.410 [MissionManager](INFO): Uninitializing Mission Startup
2018-08-20T17:01:17.410Z,1534784477.410 [Startup] Stopped
2018-08-20T17:01:17.410Z,1534784477.410 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-08-20T17:01:17.410Z,1534784477.410 [Startup:A.GoToSurface] Stopped
2018-08-20T17:01:17.410Z,1534784477.410 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-20T17:01:17.542Z,1534784477.542 [MissionManager](IMPORTANT): Started mission Default
2018-08-20T17:01:17.542Z,1534784477.542 [Default] Running Loop=1
2018-08-20T17:01:17.543Z,1534784477.543 [Default](DEBUG): Aggregate::initialize Default
2018-08-20T17:01:17.547Z,1534784477.547 [Default:B.GoToSurface] Running Loop=1
2018-08-20T17:01:17.547Z,1534784477.547 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-20T17:01:17.547Z,1534784477.547 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-20T17:01:17.548Z,1534784477.548 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-20T17:01:17.548Z,1534784477.548 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-20T17:01:17.549Z,1534784477.549 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-20T17:01:17.549Z,1534784477.549 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-20T17:01:17.549Z,1534784477.549 [Default:A.Wait] Running Loop=1
2018-08-20T17:01:17.549Z,1534784477.549 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-08-20T17:01:30.693Z,1534784490.693 [Default:A.Wait](INFO): Done Waiting.
2018-08-20T17:01:30.693Z,1534784490.693 [Default:A.Wait] Stopped
2018-08-20T17:01:30.693Z,1534784490.693 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T17:01:31.123Z,1534784491.123 [Default:CheckIn] Running Loop=1
2018-08-20T17:01:31.123Z,1534784491.123 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T17:01:31.123Z,1534784491.123 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T17:01:31.500Z,1534784491.500 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-08-20T17:02:05.411Z,1534784525.411 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:02:05.411Z,1534784525.411 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-20T17:02:05.411Z,1534784525.411 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:02:05.511Z,1534784525.511 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:02:05.815Z,1534784525.815 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:02:06.046Z,1534784526.046 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:02:07.511Z,1534784527.511 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:02:07.511Z,1534784527.511 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-20T17:02:07.663Z,1534784527.663 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:02:07.663Z,1534784527.663 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:02:07.664Z,1534784527.664 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:02:07.664Z,1534784527.664 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:02:11.791Z,1534784531.791 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:02:11.959Z,1534784531.959 [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-20T17:02:11.960Z,1534784531.960 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:02:11.961Z,1534784531.961 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:02:11.962Z,1534784531.962 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:03:43.245Z,1534784623.245 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-08-20T17:04:05.699Z,1534784645.699 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:04:05.699Z,1534784645.699 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-08-20T17:04:05.699Z,1534784645.699 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:04:05.715Z,1534784645.715 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:04:06.126Z,1534784646.126 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:04:06.126Z,1534784646.126 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-08-20T17:04:06.497Z,1534784646.497 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:04:06.497Z,1534784646.497 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:04:06.497Z,1534784646.497 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:04:08.187Z,1534784648.187 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:04:08.187Z,1534784648.187 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-20T17:04:08.187Z,1534784648.187 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:04:08.535Z,1534784648.535 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:04:08.591Z,1534784648.591 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:04:08.698Z,1534784648.698 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:04:10.110Z,1534784650.110 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:04:10.110Z,1534784650.110 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-20T17:04:10.315Z,1534784650.315 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:04:10.316Z,1534784650.316 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:04:10.316Z,1534784650.316 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:04:10.316Z,1534784650.316 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:04:14.505Z,1534784654.505 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:04:14.618Z,1534784654.618 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-20T17:04:14.619Z,1534784654.619 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:04:14.620Z,1534784654.620 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:04:14.620Z,1534784654.620 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:06:11.035Z,1534784771.035 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:06:11.035Z,1534784771.035 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-20T17:06:11.035Z,1534784771.035 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:06:11.379Z,1534784771.379 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:06:11.439Z,1534784771.439 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:06:11.547Z,1534784771.547 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:06:12.954Z,1534784772.954 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:06:12.954Z,1534784772.954 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-20T17:06:13.163Z,1534784773.163 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:06:13.164Z,1534784773.164 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:06:13.164Z,1534784773.164 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:06:13.164Z,1534784773.164 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:06:17.362Z,1534784777.362 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:06:17.475Z,1534784777.475 [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-20T17:06:17.476Z,1534784777.476 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:06:17.477Z,1534784777.477 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:06:17.478Z,1534784777.478 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:06:31.294Z,1534784791.294 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T17:01:31.1Z
2018-08-20T17:06:31.294Z,1534784791.294 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T17:06:31.294Z,1534784791.294 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T17:06:31.687Z,1534784791.687 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-20T17:06:36.094Z,1534784796.094 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T165749/Courier0004.lzma
2018-08-20T17:06:36.894Z,1534784796.894 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Courier0004.lzma.bak
2018-08-20T17:06:36.895Z,1534784796.895 [DataOverHttps](INFO): SBD MOMSN=8430155
2018-08-20T17:06:46.504Z,1534784806.504 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20180820T165749/Express0005.lzma
2018-08-20T17:06:47.302Z,1534784807.302 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Express0005.lzma.bak
2018-08-20T17:06:47.302Z,1534784807.302 [DataOverHttps](INFO): SBD MOMSN=8430157
2018-08-20T17:06:48.132Z,1534784808.132 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T17:06:48.133Z,1534784808.133 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T17:06:48.133Z,1534784808.133 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T17:07:06.599Z,1534784826.599 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:07:06.599Z,1534784826.599 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-08-20T17:07:06.600Z,1534784826.600 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:07:06.655Z,1534784826.655 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:07:07.038Z,1534784827.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:07:07.038Z,1534784827.038 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-08-20T17:07:07.431Z,1534784827.431 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:07:07.431Z,1534784827.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:07:07.432Z,1534784827.432 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:08:13.711Z,1534784893.711 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:08:13.711Z,1534784893.711 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-20T17:08:13.711Z,1534784893.711 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:08:13.820Z,1534784893.820 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:08:13.820Z,1534784893.820 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-20T17:08:14.115Z,1534784894.115 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:08:14.360Z,1534784894.360 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:08:42.529Z,1534784922.529 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T17:08:42.529Z,1534784922.529 [NAL9602] Data Fault, FailCount= 1
2018-08-20T17:08:42.529Z,1534784922.529 [NAL9602](ERROR): Data Fault
2018-08-20T17:08:42.606Z,1534784922.606 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T17:08:42.921Z,1534784922.921 [NAL9602](INFO): Powering down
2018-08-20T17:08:43.795Z,1534784923.795 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T17:08:43.795Z,1534784923.795 [NAL9602] No Fault, FailCount= 1
2018-08-20T17:09:12.929Z,1534784952.929 [NAL9602](INFO): Powering up NAL9602
2018-08-20T17:09:24.132Z,1534784964.132 [NAL9602](INFO): NAL9602 initialized
2018-08-20T17:10:07.777Z,1534785007.777 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:10:07.777Z,1534785007.777 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-08-20T17:10:07.777Z,1534785007.777 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:10:07.807Z,1534785007.807 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:10:08.203Z,1534785008.203 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:10:08.203Z,1534785008.203 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-08-20T17:10:08.590Z,1534785008.590 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:10:08.591Z,1534785008.591 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:10:08.591Z,1534785008.591 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:10:18.524Z,1534785018.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:11:48.616Z,1534785108.616 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T17:11:48.616Z,1534785108.616 [Default:CheckIn:C.Wait] Stopped
2018-08-20T17:11:48.616Z,1534785108.616 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T17:11:48.616Z,1534785108.616 [Default:CheckIn:D] Running Loop=1
2018-08-20T17:11:49.014Z,1534785109.014 [Default:CheckIn:D] Stopped
2018-08-20T17:11:49.014Z,1534785109.014 [Default:CheckIn:E] Running Loop=1
2018-08-20T17:11:49.413Z,1534785109.413 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.524495 min 
2018-08-20T17:11:49.415Z,1534785109.415 [Default:CheckIn:E] Stopped
2018-08-20T17:11:49.416Z,1534785109.416 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T17:11:49.416Z,1534785109.416 [Default:CheckIn] Stopped
2018-08-20T17:11:49.416Z,1534785109.416 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T17:11:49.416Z,1534785109.416 [Default:CheckIn](INFO): Running loop #2
2018-08-20T17:11:49.416Z,1534785109.416 [Default:CheckIn] Running Loop=2
2018-08-20T17:11:49.416Z,1534785109.416 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T17:11:49.416Z,1534785109.416 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T17:13:08.600Z,1534785188.600 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:13:08.600Z,1534785188.600 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-08-20T17:13:08.600Z,1534785188.600 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:13:08.614Z,1534785188.614 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:13:09.020Z,1534785189.020 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:13:09.020Z,1534785189.020 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-08-20T17:13:09.401Z,1534785189.401 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:13:09.401Z,1534785189.401 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:13:09.402Z,1534785189.402 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:14:09.345Z,1534785249.345 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.008087
2018-08-20T17:14:52.866Z,1534785292.866 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-08-20T17:14:54.140Z,1534785294.140 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002943
2018-08-20T17:15:24.247Z,1534785324.247 [NAL9602](ERROR): getFix uart error: serial timeout
2018-08-20T17:15:54.765Z,1534785354.765 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-08-20T17:16:09.604Z,1534785369.604 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:16:09.604Z,1534785369.604 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-08-20T17:16:09.604Z,1534785369.604 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:16:09.650Z,1534785369.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:16:10.044Z,1534785370.044 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:16:10.044Z,1534785370.044 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-08-20T17:16:10.407Z,1534785370.407 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:16:10.407Z,1534785370.407 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:16:10.408Z,1534785370.408 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:16:19.956Z,1534785379.956 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:16:26.471Z,1534785386.471 [NAL9602](ERROR): getFix uart error: serial timeout
2018-08-20T17:16:49.832Z,1534785409.832 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T17:11:49.4Z
2018-08-20T17:16:49.833Z,1534785409.833 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T17:16:49.833Z,1534785409.833 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T17:16:56.741Z,1534785416.741 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-08-20T17:17:28.075Z,1534785448.075 [NAL9602](ERROR): getFix uart error: serial timeout
2018-08-20T17:17:54.177Z,1534785474.177 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.039856
2018-08-20T17:17:58.581Z,1534785478.581 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-08-20T17:18:13.880Z,1534785493.880 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:18:13.881Z,1534785493.881 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-20T17:18:14.091Z,1534785494.091 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:18:14.091Z,1534785494.091 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:18:14.092Z,1534785494.092 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:18:14.092Z,1534785494.092 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:18:16.592Z,1534785496.592 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002916
2018-08-20T17:18:18.287Z,1534785498.287 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:18:18.413Z,1534785498.413 [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-20T17:18:18.414Z,1534785498.414 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:18:18.415Z,1534785498.415 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:18:18.416Z,1534785498.416 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:18:29.632Z,1534785509.632 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2018-08-20T17:18:30.151Z,1534785510.151 [NAL9602](ERROR): received: 
2018-08-20T17:18:30.151Z,1534785510.151 [NAL9602] Data Fault, FailCount= 2
2018-08-20T17:18:30.151Z,1534785510.151 [NAL9602](ERROR): Data Fault
2018-08-20T17:18:30.251Z,1534785510.251 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T17:18:30.284Z,1534785510.284 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T165749/Courier0007.lzma
2018-08-20T17:18:30.290Z,1534785510.290 [NAL9602](INFO): Powering down
2018-08-20T17:18:31.062Z,1534785511.062 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Courier0007.lzma.bak
2018-08-20T17:18:31.062Z,1534785511.062 [DataOverHttps](INFO): SBD MOMSN=8430165
2018-08-20T17:18:31.160Z,1534785511.160 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T17:18:31.160Z,1534785511.160 [NAL9602] No Fault, FailCount= 2
2018-08-20T17:19:00.385Z,1534785540.385 [NAL9602](INFO): Powering up NAL9602
2018-08-20T17:19:01.977Z,1534785541.977 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-08-20T17:19:02.824Z,1534785542.824 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 14 sticks.
2018-08-20T17:19:02.824Z,1534785542.824 [BPC1] Data Fault, FailCount= 1
2018-08-20T17:19:02.824Z,1534785542.824 [BPC1](ERROR): Data Fault
2018-08-20T17:19:02.851Z,1534785542.851 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-20T17:19:07.103Z,1534785547.103 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-20T17:19:07.104Z,1534785547.104 [BPC1] No Fault, FailCount= 1
2018-08-20T17:19:10.785Z,1534785550.785 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:19:10.786Z,1534785550.786 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-08-20T17:19:10.786Z,1534785550.786 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:19:10.818Z,1534785550.818 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:19:11.225Z,1534785551.225 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:19:11.225Z,1534785551.225 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-08-20T17:19:11.521Z,1534785551.521 [NAL9602](INFO): NAL9602 initialized
2018-08-20T17:19:11.576Z,1534785551.576 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:19:11.576Z,1534785551.576 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:19:11.577Z,1534785551.577 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:19:20.790Z,1534785560.790 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20180820T165749/Express0008.lzma
2018-08-20T17:19:25.561Z,1534785565.561 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T17:19:25.561Z,1534785565.561 [NAL9602] Data Fault, FailCount= 3
2018-08-20T17:19:25.561Z,1534785565.561 [NAL9602](ERROR): Data Fault
2018-08-20T17:19:25.664Z,1534785565.664 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T17:19:25.969Z,1534785565.969 [NAL9602](INFO): Powering down
2018-08-20T17:19:26.838Z,1534785566.838 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T17:19:26.838Z,1534785566.838 [NAL9602] No Fault, FailCount= 3
2018-08-20T17:19:41.957Z,1534785581.957 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-08-20T17:19:56.361Z,1534785596.361 [NAL9602](INFO): Powering up NAL9602
2018-08-20T17:20:07.565Z,1534785607.565 [NAL9602](INFO): NAL9602 initialized
2018-08-20T17:20:12.357Z,1534785612.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:20:14.747Z,1534785614.747 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:20:14.747Z,1534785614.747 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T17:20:14.747Z,1534785614.747 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:20:14.846Z,1534785614.846 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:20:15.151Z,1534785615.151 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:20:15.414Z,1534785615.414 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:20:16.852Z,1534785616.852 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:20:16.852Z,1534785616.852 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T17:20:17.031Z,1534785617.031 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:20:17.032Z,1534785617.032 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:20:17.032Z,1534785617.032 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:20:17.032Z,1534785617.032 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:20:21.195Z,1534785621.195 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:20:21.338Z,1534785621.338 [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-20T17:20:21.339Z,1534785621.339 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:20:21.340Z,1534785621.340 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:20:21.341Z,1534785621.341 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:20:40.022Z,1534785640.022 [BPC1](ERROR): Failed to parse Bank B battery data
2018-08-20T17:21:04.322Z,1534785664.322 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20180820T165749/Express0008.lzma
2018-08-20T17:21:12.314Z,1534785672.314 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Express0008.lzma.bak
2018-08-20T17:21:12.314Z,1534785672.314 [DataOverHttps](INFO): SBD MOMSN=8430167
2018-08-20T17:21:13.155Z,1534785673.155 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T17:21:13.156Z,1534785673.156 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T17:21:13.156Z,1534785673.156 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T17:22:11.687Z,1534785731.687 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:22:11.707Z,1534785731.707 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-08-20T17:22:11.707Z,1534785731.707 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:22:11.727Z,1534785731.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:22:12.115Z,1534785732.115 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:22:12.115Z,1534785732.115 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-08-20T17:22:12.500Z,1534785732.500 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:22:12.500Z,1534785732.500 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:22:12.501Z,1534785732.501 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:22:17.631Z,1534785737.631 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:22:17.631Z,1534785737.631 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-20T17:22:17.631Z,1534785737.631 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:22:17.745Z,1534785737.745 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:22:18.035Z,1534785738.035 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:22:18.283Z,1534785738.283 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:22:19.716Z,1534785739.716 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:22:19.716Z,1534785739.716 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-20T17:22:19.899Z,1534785739.899 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:22:19.900Z,1534785739.900 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:22:19.900Z,1534785739.900 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:22:19.900Z,1534785739.900 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:22:24.021Z,1534785744.021 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:22:24.146Z,1534785744.146 [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-20T17:22:24.147Z,1534785744.147 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:22:24.148Z,1534785744.148 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:22:24.148Z,1534785744.148 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:23:43.633Z,1534785823.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:24:17.977Z,1534785857.977 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.343918
2018-08-20T17:24:20.003Z,1534785860.003 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:24:20.003Z,1534785860.003 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-20T17:24:20.003Z,1534785860.003 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:24:20.044Z,1534785860.044 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:24:20.407Z,1534785860.407 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:24:20.683Z,1534785860.683 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:24:22.034Z,1534785862.034 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:24:22.034Z,1534785862.034 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-20T17:24:22.299Z,1534785862.299 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:24:22.300Z,1534785862.300 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:24:22.300Z,1534785862.300 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:24:22.300Z,1534785862.300 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:24:26.545Z,1534785866.545 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:24:26.617Z,1534785866.617 [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-20T17:24:26.618Z,1534785866.618 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:24:26.619Z,1534785866.619 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:24:26.620Z,1534785866.620 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:25:12.853Z,1534785912.853 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:25:12.854Z,1534785912.854 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-08-20T17:25:12.854Z,1534785912.854 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:25:12.890Z,1534785912.890 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:25:13.263Z,1534785913.263 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:25:13.263Z,1534785913.263 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-08-20T17:25:13.643Z,1534785913.643 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:25:13.644Z,1534785913.644 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:25:13.644Z,1534785913.644 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:26:13.641Z,1534785973.641 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T17:26:13.641Z,1534785973.641 [Default:CheckIn:C.Wait] Stopped
2018-08-20T17:26:13.642Z,1534785973.642 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T17:26:13.642Z,1534785973.642 [Default:CheckIn:D] Running Loop=1
2018-08-20T17:26:14.053Z,1534785974.053 [Default:CheckIn:D] Stopped
2018-08-20T17:26:14.053Z,1534785974.053 [Default:CheckIn:E] Running Loop=1
2018-08-20T17:26:14.438Z,1534785974.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.941844 min 
2018-08-20T17:26:14.438Z,1534785974.438 [Default:CheckIn:E] Stopped
2018-08-20T17:26:14.438Z,1534785974.438 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T17:26:14.438Z,1534785974.438 [Default:CheckIn] Stopped
2018-08-20T17:26:14.438Z,1534785974.438 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T17:26:14.439Z,1534785974.439 [Default:CheckIn](INFO): Running loop #3
2018-08-20T17:26:14.439Z,1534785974.439 [Default:CheckIn] Running Loop=3
2018-08-20T17:26:14.439Z,1534785974.439 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T17:26:14.439Z,1534785974.439 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T17:26:22.363Z,1534785982.363 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:26:22.363Z,1534785982.363 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-20T17:26:22.363Z,1534785982.363 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:26:22.480Z,1534785982.480 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:26:22.783Z,1534785982.783 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:26:23.019Z,1534785983.019 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:26:24.467Z,1534785984.467 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:26:24.467Z,1534785984.467 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-20T17:26:24.635Z,1534785984.635 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:26:24.636Z,1534785984.636 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:26:24.636Z,1534785984.636 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:26:24.636Z,1534785984.636 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:26:28.761Z,1534785988.761 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:26:28.910Z,1534785988.910 [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-20T17:26:28.911Z,1534785988.911 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:26:28.912Z,1534785988.912 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:26:28.912Z,1534785988.912 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:28:14.013Z,1534786094.013 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:28:14.013Z,1534786094.013 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-08-20T17:28:14.013Z,1534786094.013 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:28:14.049Z,1534786094.049 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:28:14.426Z,1534786094.426 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:28:14.426Z,1534786094.426 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-08-20T17:28:14.809Z,1534786094.809 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:28:14.810Z,1534786094.810 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:28:14.810Z,1534786094.810 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:28:24.747Z,1534786104.747 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:28:24.747Z,1534786104.747 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-20T17:28:24.747Z,1534786104.747 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:28:24.859Z,1534786104.859 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:28:24.859Z,1534786104.859 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-20T17:28:25.151Z,1534786105.151 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:28:25.389Z,1534786105.389 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:28:45.140Z,1534786125.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:30:09.545Z,1534786209.545 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T17:30:09.545Z,1534786209.545 [NAL9602] Data Fault, FailCount= 4
2018-08-20T17:30:09.545Z,1534786209.545 [NAL9602](ERROR): Data Fault
2018-08-20T17:30:09.628Z,1534786209.628 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T17:30:09.945Z,1534786209.945 [NAL9602](INFO): Powering down
2018-08-20T17:30:10.823Z,1534786210.823 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T17:30:10.823Z,1534786210.823 [NAL9602] No Fault, FailCount= 4
2018-08-20T17:30:37.532Z,1534786237.532 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003075
2018-08-20T17:30:40.333Z,1534786240.333 [NAL9602](INFO): Powering up NAL9602
2018-08-20T17:30:51.137Z,1534786251.137 [NAL9602](INFO): NAL9602 initialized
2018-08-20T17:31:14.784Z,1534786274.784 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T17:26:14.4Z
2018-08-20T17:31:14.784Z,1534786274.784 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T17:31:14.784Z,1534786274.784 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T17:31:15.194Z,1534786275.194 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:31:15.194Z,1534786275.194 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-08-20T17:31:15.194Z,1534786275.194 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:31:15.222Z,1534786275.222 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:31:15.758Z,1534786275.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:31:15.758Z,1534786275.758 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-08-20T17:31:16.010Z,1534786276.010 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:31:16.015Z,1534786276.015 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:31:16.015Z,1534786276.015 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:32:16.328Z,1534786336.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:32:41.152Z,1534786361.152 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T165749/Courier0010.lzma
2018-08-20T17:32:41.933Z,1534786361.933 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Courier0010.lzma.bak
2018-08-20T17:32:41.934Z,1534786361.934 [DataOverHttps](INFO): SBD MOMSN=8430174
2018-08-20T17:32:50.797Z,1534786370.797 [DataOverHttps](INFO): Sending 351 bytes from file Logs/20180820T165749/Express0011.lzma
2018-08-20T17:32:51.587Z,1534786371.588 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Express0011.lzma.bak
2018-08-20T17:32:51.588Z,1534786371.588 [DataOverHttps](INFO): SBD MOMSN=8430176
2018-08-20T17:32:52.372Z,1534786372.372 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T17:32:52.372Z,1534786372.372 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T17:32:52.372Z,1534786372.372 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T17:34:16.093Z,1534786456.093 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:34:16.093Z,1534786456.093 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-08-20T17:34:16.093Z,1534786456.093 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:34:16.107Z,1534786456.107 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:34:16.505Z,1534786456.505 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:34:16.505Z,1534786456.505 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-08-20T17:34:16.907Z,1534786456.907 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:34:16.907Z,1534786456.907 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:34:16.908Z,1534786456.908 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:36:22.840Z,1534786582.840 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:37:17.311Z,1534786637.311 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:37:17.311Z,1534786637.311 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-08-20T17:37:17.311Z,1534786637.311 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:37:17.326Z,1534786637.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:37:17.732Z,1534786637.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:37:17.733Z,1534786637.733 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-08-20T17:37:18.095Z,1534786638.095 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:37:18.096Z,1534786638.096 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:37:18.096Z,1534786638.096 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:37:23.240Z,1534786643.240 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:37:52.506Z,1534786672.506 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T17:37:52.506Z,1534786672.506 [Default:CheckIn:C.Wait] Stopped
2018-08-20T17:37:52.506Z,1534786672.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T17:37:52.506Z,1534786672.506 [Default:CheckIn:D] Running Loop=1
2018-08-20T17:37:52.906Z,1534786672.906 [Default:CheckIn:D] Stopped
2018-08-20T17:37:52.906Z,1534786672.906 [Default:CheckIn:E] Running Loop=1
2018-08-20T17:37:53.338Z,1534786673.338 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.589400 min 
2018-08-20T17:37:53.343Z,1534786673.343 [Default:CheckIn:E] Stopped
2018-08-20T17:37:53.343Z,1534786673.343 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T17:37:53.343Z,1534786673.343 [Default:CheckIn] Stopped
2018-08-20T17:37:53.343Z,1534786673.343 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T17:37:53.344Z,1534786673.344 [Default:CheckIn](INFO): Running loop #4
2018-08-20T17:37:53.344Z,1534786673.344 [Default:CheckIn] Running Loop=4
2018-08-20T17:37:53.344Z,1534786673.344 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T17:37:53.344Z,1534786673.344 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T17:37:57.641Z,1534786677.641 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.400436
2018-08-20T17:38:24.932Z,1534786704.932 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:38:24.933Z,1534786704.933 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-20T17:38:25.164Z,1534786705.164 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:38:25.165Z,1534786705.165 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:38:25.165Z,1534786705.165 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:38:25.165Z,1534786705.165 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:38:29.386Z,1534786709.386 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:38:29.474Z,1534786709.474 [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-20T17:38:29.475Z,1534786709.475 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:38:29.476Z,1534786709.476 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:38:29.476Z,1534786709.476 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:39:23.241Z,1534786763.241 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:40:07.701Z,1534786807.701 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 14 sticks.
2018-08-20T17:40:07.702Z,1534786807.702 [BPC1] Data Fault, FailCount= 2
2018-08-20T17:40:07.702Z,1534786807.702 [BPC1](ERROR): Data Fault
2018-08-20T17:40:07.748Z,1534786807.748 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-20T17:40:08.944Z,1534786808.944 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-20T17:40:08.944Z,1534786808.944 [BPC1] No Fault, FailCount= 2
2018-08-20T17:40:18.099Z,1534786818.099 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:40:18.100Z,1534786818.100 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-08-20T17:40:18.100Z,1534786818.100 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:40:18.127Z,1534786818.127 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:40:18.550Z,1534786818.550 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:40:18.550Z,1534786818.550 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-08-20T17:40:18.993Z,1534786818.993 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:40:18.994Z,1534786818.994 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:40:18.994Z,1534786818.994 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:40:23.242Z,1534786823.242 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:40:25.235Z,1534786825.235 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:40:25.235Z,1534786825.235 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T17:40:25.235Z,1534786825.235 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:40:25.371Z,1534786825.371 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:40:25.642Z,1534786825.642 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:40:25.889Z,1534786825.889 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:40:27.305Z,1534786827.305 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:40:27.305Z,1534786827.305 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T17:40:27.503Z,1534786827.503 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:40:27.504Z,1534786827.504 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:40:27.504Z,1534786827.504 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:40:27.504Z,1534786827.504 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:40:31.622Z,1534786831.622 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:40:31.756Z,1534786831.756 [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-20T17:40:31.757Z,1534786831.757 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:40:31.758Z,1534786831.758 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:40:31.758Z,1534786831.758 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:40:52.045Z,1534786852.045 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T17:40:52.045Z,1534786852.045 [NAL9602] Data Fault, FailCount= 5
2018-08-20T17:40:52.045Z,1534786852.045 [NAL9602](ERROR): Data Fault
2018-08-20T17:40:52.140Z,1534786852.140 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T17:40:52.141Z,1534786852.141 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-08-20T17:40:52.445Z,1534786852.445 [NAL9602](INFO): Powering down
2018-08-20T17:40:52.534Z,1534786852.534 [CBIT](INFO): Critical error at 20180820T174052
2018-08-20T17:41:23.641Z,1534786883.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T17:41:58.397Z,1534786918.397 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003269
2018-08-20T17:42:27.591Z,1534786947.591 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:42:27.591Z,1534786947.591 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-20T17:42:27.591Z,1534786947.591 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:42:27.693Z,1534786947.693 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:42:27.995Z,1534786947.995 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:42:28.243Z,1534786948.243 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:42:29.673Z,1534786949.673 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:42:29.673Z,1534786949.673 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-20T17:42:29.859Z,1534786949.859 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:42:29.860Z,1534786949.860 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:42:29.860Z,1534786949.860 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:42:29.860Z,1534786949.860 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:42:33.981Z,1534786953.981 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:42:34.146Z,1534786954.146 [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-20T17:42:34.146Z,1534786954.146 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:42:34.147Z,1534786954.147 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:42:34.148Z,1534786954.148 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:42:52.069Z,1534786972.069 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T17:42:52.069Z,1534786972.069 [NAL9602] No Fault, FailCount= 5
2018-08-20T17:42:52.397Z,1534786972.397 [NAL9602](INFO): Powering up NAL9602
2018-08-20T17:42:53.667Z,1534786973.667 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T17:37:53.3Z
2018-08-20T17:42:53.667Z,1534786973.667 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T17:42:53.667Z,1534786973.667 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T17:42:58.041Z,1534786978.041 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20180820T165749/Courier0013.lzma
2018-08-20T17:42:58.830Z,1534786978.830 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Courier0013.lzma.bak
2018-08-20T17:42:58.830Z,1534786978.830 [DataOverHttps](INFO): SBD MOMSN=8430188
2018-08-20T17:43:03.645Z,1534786983.645 [NAL9602](INFO): NAL9602 initialized
2018-08-20T17:43:08.387Z,1534786988.387 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20180820T165749/Express0014.lzma
2018-08-20T17:43:09.174Z,1534786989.174 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Express0014.lzma.bak
2018-08-20T17:43:09.175Z,1534786989.175 [DataOverHttps](INFO): SBD MOMSN=8430191
2018-08-20T17:43:10.002Z,1534786990.002 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T17:43:10.003Z,1534786990.003 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T17:43:10.023Z,1534786990.023 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T17:43:19.294Z,1534786999.294 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:43:19.294Z,1534786999.294 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-08-20T17:43:19.294Z,1534786999.294 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:43:19.309Z,1534786999.309 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:43:19.706Z,1534786999.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:43:19.706Z,1534786999.706 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-08-20T17:43:20.096Z,1534787000.096 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:43:20.097Z,1534787000.097 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:43:20.097Z,1534787000.097 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:44:30.423Z,1534787070.423 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:44:30.423Z,1534787070.423 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-20T17:44:30.423Z,1534787070.423 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:44:30.464Z,1534787070.464 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:44:30.827Z,1534787070.827 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:44:31.007Z,1534787071.007 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:44:32.463Z,1534787072.463 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:44:32.463Z,1534787072.463 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-20T17:44:32.623Z,1534787072.623 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:44:32.624Z,1534787072.624 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:44:32.624Z,1534787072.624 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:44:32.624Z,1534787072.624 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:44:36.748Z,1534787076.748 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:44:36.897Z,1534787076.897 [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-20T17:44:36.898Z,1534787076.898 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:44:36.899Z,1534787076.899 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:44:36.900Z,1534787076.900 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:46:20.335Z,1534787180.335 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:46:20.336Z,1534787180.336 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-08-20T17:46:20.336Z,1534787180.336 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:46:20.368Z,1534787180.368 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:46:20.777Z,1534787180.777 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:46:20.777Z,1534787180.777 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-08-20T17:46:21.149Z,1534787181.149 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:46:21.150Z,1534787181.150 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:46:21.150Z,1534787181.150 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:46:32.671Z,1534787192.671 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:46:32.671Z,1534787192.671 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-20T17:46:32.671Z,1534787192.671 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:46:32.775Z,1534787192.775 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:46:33.075Z,1534787193.075 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:46:33.299Z,1534787193.299 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:46:34.776Z,1534787194.776 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:46:34.776Z,1534787194.776 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-20T17:46:34.915Z,1534787194.915 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:46:34.916Z,1534787194.916 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:46:34.916Z,1534787194.916 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:46:34.916Z,1534787194.916 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:46:39.043Z,1534787199.043 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:46:39.186Z,1534787199.186 [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-20T17:46:39.187Z,1534787199.187 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:46:39.187Z,1534787199.187 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:46:39.188Z,1534787199.188 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T17:48:10.506Z,1534787290.506 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T17:48:10.506Z,1534787290.506 [Default:CheckIn:C.Wait] Stopped
2018-08-20T17:48:10.506Z,1534787290.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T17:48:10.506Z,1534787290.506 [Default:CheckIn:D] Running Loop=1
2018-08-20T17:48:10.886Z,1534787290.886 [Default:CheckIn:D] Stopped
2018-08-20T17:48:10.886Z,1534787290.886 [Default:CheckIn:E] Running Loop=1
2018-08-20T17:48:11.306Z,1534787291.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.889054 min 
2018-08-20T17:48:11.307Z,1534787291.307 [Default:CheckIn:E] Stopped
2018-08-20T17:48:11.307Z,1534787291.307 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T17:48:11.307Z,1534787291.307 [Default:CheckIn] Stopped
2018-08-20T17:48:11.307Z,1534787291.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T17:48:11.308Z,1534787291.308 [Default:CheckIn](INFO): Running loop #5
2018-08-20T17:48:11.308Z,1534787291.308 [Default:CheckIn] Running Loop=5
2018-08-20T17:48:11.308Z,1534787291.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T17:48:11.308Z,1534787291.308 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T17:48:35.595Z,1534787315.595 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T17:48:35.595Z,1534787315.595 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-20T17:48:35.595Z,1534787315.595 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T17:48:35.717Z,1534787315.717 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T17:48:35.718Z,1534787315.718 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-20T17:48:36.011Z,1534787316.011 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T17:48:36.256Z,1534787316.256 [Rowe_600LCM](INFO): Powering down
2018-08-20T17:48:41.604Z,1534787321.604 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-08-20T17:49:21.276Z,1534787361.276 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:49:21.276Z,1534787361.276 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-08-20T17:49:21.276Z,1534787361.276 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:49:21.302Z,1534787361.302 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:49:21.690Z,1534787361.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:49:21.691Z,1534787361.691 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-08-20T17:49:22.089Z,1534787362.089 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:49:22.090Z,1534787362.090 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:49:22.090Z,1534787362.090 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:50:16.404Z,1534787416.404 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.374878
2018-08-20T17:51:16.388Z,1534787476.388 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002903
2018-08-20T17:52:22.478Z,1534787542.478 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:52:22.478Z,1534787542.478 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-08-20T17:52:22.478Z,1534787542.478 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:52:22.492Z,1534787542.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:52:22.884Z,1534787542.884 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:52:22.884Z,1534787542.884 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-08-20T17:52:23.246Z,1534787543.246 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:52:23.247Z,1534787543.247 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:52:23.247Z,1534787543.247 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:53:06.420Z,1534787586.420 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T17:53:06.421Z,1534787586.421 [NAL9602] Data Fault, FailCount= 1
2018-08-20T17:53:06.421Z,1534787586.421 [NAL9602](ERROR): Data Fault
2018-08-20T17:53:06.489Z,1534787586.489 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T17:53:06.813Z,1534787586.813 [NAL9602](INFO): Powering down
2018-08-20T17:53:07.692Z,1534787587.692 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T17:53:07.692Z,1534787587.692 [NAL9602] No Fault, FailCount= 1
2018-08-20T17:53:11.669Z,1534787591.669 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T17:48:11.3Z
2018-08-20T17:53:11.669Z,1534787591.669 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T17:53:11.669Z,1534787591.669 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T17:53:16.462Z,1534787596.462 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T165749/Courier0016.lzma
2018-08-20T17:53:17.249Z,1534787597.249 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Courier0016.lzma.bak
2018-08-20T17:53:17.250Z,1534787597.250 [DataOverHttps](INFO): SBD MOMSN=8430198
2018-08-20T17:53:26.307Z,1534787606.307 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20180820T165749/Express0017.lzma
2018-08-20T17:53:27.089Z,1534787607.089 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Express0017.lzma.bak
2018-08-20T17:53:27.090Z,1534787607.090 [DataOverHttps](INFO): SBD MOMSN=8430200
2018-08-20T17:53:27.841Z,1534787607.841 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T17:53:27.841Z,1534787607.841 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T17:53:27.841Z,1534787607.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T17:53:36.925Z,1534787616.925 [NAL9602](INFO): Powering up NAL9602
2018-08-20T17:53:47.725Z,1534787627.725 [NAL9602](INFO): NAL9602 initialized
2018-08-20T17:55:23.420Z,1534787723.420 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:55:23.420Z,1534787723.420 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-08-20T17:55:23.420Z,1534787723.420 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:55:23.452Z,1534787723.452 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:55:23.822Z,1534787723.822 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T17:55:23.822Z,1534787723.822 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-08-20T17:55:24.212Z,1534787724.212 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T17:55:24.212Z,1534787724.212 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T17:55:24.213Z,1534787724.213 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T17:58:24.579Z,1534787904.579 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T17:58:24.579Z,1534787904.579 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-08-20T17:58:24.579Z,1534787904.579 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T17:58:24.593Z,1534787904.593 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:58:24.594Z,1534787904.594 [CBIT](FAULT): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T17:58:28.168Z,1534787908.168 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T17:58:28.168Z,1534787908.168 [Default:CheckIn:C.Wait] Stopped
2018-08-20T17:58:28.168Z,1534787908.168 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T17:58:28.168Z,1534787908.168 [Default:CheckIn:D] Running Loop=1
2018-08-20T17:58:28.580Z,1534787908.580 [Default:CheckIn:D] Stopped
2018-08-20T17:58:28.580Z,1534787908.580 [Default:CheckIn:E] Running Loop=1
2018-08-20T17:58:28.973Z,1534787908.973 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.183952 min 
2018-08-20T17:58:28.973Z,1534787908.973 [Default:CheckIn:E] Stopped
2018-08-20T17:58:28.974Z,1534787908.974 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T17:58:28.974Z,1534787908.974 [Default:CheckIn] Stopped
2018-08-20T17:58:28.974Z,1534787908.974 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T17:58:28.974Z,1534787908.974 [Default:CheckIn](INFO): Running loop #6
2018-08-20T17:58:28.974Z,1534787908.974 [Default:CheckIn] Running Loop=6
2018-08-20T17:58:28.974Z,1534787908.974 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T17:58:28.974Z,1534787908.974 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T17:58:35.799Z,1534787915.799 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T17:58:35.799Z,1534787915.799 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-20T17:58:36.056Z,1534787916.056 [Rowe_600LCM](INFO): Initializing
2018-08-20T17:58:36.056Z,1534787916.056 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T17:58:36.056Z,1534787916.056 [Rowe_600LCM](INFO): LCM OK
2018-08-20T17:58:36.056Z,1534787916.056 [Rowe_600LCM](INFO): Powering up
2018-08-20T17:58:40.304Z,1534787920.304 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T17:58:40.366Z,1534787920.366 [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-20T17:58:40.367Z,1534787920.367 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T17:58:40.368Z,1534787920.368 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T17:58:40.368Z,1534787920.368 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T18:00:36.095Z,1534788036.095 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T18:00:36.095Z,1534788036.095 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T18:00:36.095Z,1534788036.095 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T18:00:36.499Z,1534788036.499 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T18:00:36.747Z,1534788036.747 [Rowe_600LCM](INFO): Powering down
2018-08-20T18:00:37.446Z,1534788037.446 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T18:00:38.051Z,1534788038.051 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T18:00:38.052Z,1534788038.052 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T18:00:38.363Z,1534788038.363 [Rowe_600LCM](INFO): Initializing
2018-08-20T18:00:38.364Z,1534788038.364 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T18:00:38.364Z,1534788038.364 [Rowe_600LCM](INFO): LCM OK
2018-08-20T18:00:38.364Z,1534788038.364 [Rowe_600LCM](INFO): Powering up
2018-08-20T18:00:42.490Z,1534788042.490 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T18:00:42.553Z,1534788042.553 [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-20T18:00:42.554Z,1534788042.554 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T18:00:42.555Z,1534788042.555 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T18:00:42.556Z,1534788042.556 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T18:01:09.905Z,1534788069.905 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 9 sticks.
2018-08-20T18:01:09.906Z,1534788069.906 [BPC1] Data Fault, FailCount= 3
2018-08-20T18:01:09.906Z,1534788069.906 [BPC1](ERROR): Data Fault
2018-08-20T18:01:09.952Z,1534788069.952 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-20T18:01:11.169Z,1534788071.169 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-20T18:01:11.170Z,1534788071.170 [BPC1] No Fault, FailCount= 3
2018-08-20T18:02:38.971Z,1534788158.971 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T18:02:38.971Z,1534788158.971 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-20T18:02:38.971Z,1534788158.971 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T18:02:39.091Z,1534788159.091 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T18:02:39.375Z,1534788159.375 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T18:02:39.612Z,1534788159.612 [Rowe_600LCM](INFO): Powering down
2018-08-20T18:02:41.057Z,1534788161.057 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T18:02:41.057Z,1534788161.057 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-20T18:02:41.227Z,1534788161.227 [Rowe_600LCM](INFO): Initializing
2018-08-20T18:02:41.228Z,1534788161.228 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T18:02:41.228Z,1534788161.228 [Rowe_600LCM](INFO): LCM OK
2018-08-20T18:02:41.228Z,1534788161.228 [Rowe_600LCM](INFO): Powering up
2018-08-20T18:02:45.350Z,1534788165.350 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T18:02:45.489Z,1534788165.489 [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-20T18:02:45.490Z,1534788165.490 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T18:02:45.491Z,1534788165.491 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T18:02:45.492Z,1534788165.492 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T18:03:29.058Z,1534788209.058 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T17:58:28.0Z
2018-08-20T18:03:29.058Z,1534788209.058 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T18:03:29.058Z,1534788209.058 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T18:03:38.574Z,1534788218.574 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.309143
2018-08-20T18:03:48.577Z,1534788228.577 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T18:03:48.577Z,1534788228.577 [NAL9602] Data Fault, FailCount= 2
2018-08-20T18:03:48.577Z,1534788228.577 [NAL9602](ERROR): Data Fault
2018-08-20T18:03:48.682Z,1534788228.682 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T18:03:48.981Z,1534788228.981 [NAL9602](INFO): Powering down
2018-08-20T18:03:49.858Z,1534788229.858 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T18:03:49.859Z,1534788229.859 [NAL9602] No Fault, FailCount= 2
2018-08-20T18:04:08.976Z,1534788248.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T18:04:19.377Z,1534788259.377 [NAL9602](INFO): Powering up NAL9602
2018-08-20T18:04:30.185Z,1534788270.185 [NAL9602](INFO): NAL9602 initialized
2018-08-20T18:04:41.355Z,1534788281.355 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T18:04:41.355Z,1534788281.355 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-20T18:04:41.355Z,1534788281.355 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T18:04:41.759Z,1534788281.759 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T18:04:41.947Z,1534788281.947 [Rowe_600LCM](INFO): Powering down
2018-08-20T18:04:42.946Z,1534788282.946 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T18:04:43.421Z,1534788283.421 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T18:04:43.422Z,1534788283.422 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-20T18:04:43.563Z,1534788283.563 [Rowe_600LCM](INFO): Initializing
2018-08-20T18:04:43.564Z,1534788283.564 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T18:04:43.564Z,1534788283.564 [Rowe_600LCM](INFO): LCM OK
2018-08-20T18:04:43.564Z,1534788283.564 [Rowe_600LCM](INFO): Powering up
2018-08-20T18:04:43.764Z,1534788283.764 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003045
2018-08-20T18:04:47.739Z,1534788287.739 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T18:04:47.852Z,1534788287.852 [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-20T18:04:47.853Z,1534788287.853 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T18:04:47.854Z,1534788287.854 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T18:04:47.855Z,1534788287.855 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T18:04:48.057Z,1534788288.057 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T165749/Courier0019.lzma
2018-08-20T18:04:48.829Z,1534788288.829 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Courier0019.lzma.bak
2018-08-20T18:04:48.830Z,1534788288.830 [DataOverHttps](INFO): SBD MOMSN=8430208
2018-08-20T18:04:59.369Z,1534788299.369 [DataOverHttps](INFO): Sending 289 bytes from file Logs/20180820T165749/Express0020.lzma
2018-08-20T18:05:00.158Z,1534788300.158 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Express0020.lzma.bak
2018-08-20T18:05:00.158Z,1534788300.158 [DataOverHttps](INFO): SBD MOMSN=8430210
2018-08-20T18:05:00.983Z,1534788300.983 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T18:05:00.983Z,1534788300.983 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T18:05:00.983Z,1534788300.983 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T18:06:44.131Z,1534788404.131 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T18:06:44.131Z,1534788404.131 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-20T18:06:44.131Z,1534788404.131 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T18:06:44.243Z,1534788404.243 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T18:06:44.535Z,1534788404.535 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T18:06:44.775Z,1534788404.775 [Rowe_600LCM](INFO): Powering down
2018-08-20T18:06:46.237Z,1534788406.237 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T18:06:46.237Z,1534788406.237 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-20T18:06:46.391Z,1534788406.391 [Rowe_600LCM](INFO): Initializing
2018-08-20T18:06:46.392Z,1534788406.392 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T18:06:46.392Z,1534788406.392 [Rowe_600LCM](INFO): LCM OK
2018-08-20T18:06:46.392Z,1534788406.392 [Rowe_600LCM](INFO): Powering up
2018-08-20T18:06:50.512Z,1534788410.512 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T18:06:50.674Z,1534788410.674 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-20T18:06:50.675Z,1534788410.675 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T18:06:50.676Z,1534788410.676 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T18:06:50.676Z,1534788410.676 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T18:07:24.922Z,1534788444.922 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-20T18:07:24.922Z,1534788444.922 [BPC1] Communications Fault, FailCount= 4
2018-08-20T18:07:24.922Z,1534788444.922 [BPC1](ERROR): Communications Fault
2018-08-20T18:07:24.980Z,1534788444.980 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-20T18:07:26.174Z,1534788446.174 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-20T18:07:26.175Z,1534788446.175 [BPC1] No Fault, FailCount= 4
2018-08-20T18:08:46.407Z,1534788526.407 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T18:08:46.407Z,1534788526.407 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-20T18:08:46.407Z,1534788526.407 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T18:08:46.519Z,1534788526.519 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T18:08:46.520Z,1534788526.520 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-20T18:08:46.811Z,1534788526.811 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T18:08:47.032Z,1534788527.032 [Rowe_600LCM](INFO): Powering down
2018-08-20T18:10:01.372Z,1534788601.372 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T18:10:01.372Z,1534788601.372 [Default:CheckIn:C.Wait] Stopped
2018-08-20T18:10:01.372Z,1534788601.372 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T18:10:01.372Z,1534788601.372 [Default:CheckIn:D] Running Loop=1
2018-08-20T18:10:01.786Z,1534788601.786 [Default:CheckIn:D] Stopped
2018-08-20T18:10:01.786Z,1534788601.786 [Default:CheckIn:E] Running Loop=1
2018-08-20T18:10:02.178Z,1534788602.178 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.737394 min 
2018-08-20T18:10:02.178Z,1534788602.178 [Default:CheckIn:E] Stopped
2018-08-20T18:10:02.178Z,1534788602.178 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T18:10:02.178Z,1534788602.178 [Default:CheckIn] Stopped
2018-08-20T18:10:02.179Z,1534788602.179 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T18:10:02.179Z,1534788602.179 [Default:CheckIn](INFO): Running loop #7
2018-08-20T18:10:02.179Z,1534788602.179 [Default:CheckIn] Running Loop=7
2018-08-20T18:10:02.179Z,1534788602.179 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T18:10:02.179Z,1534788602.179 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T18:13:24.599Z,1534788804.599 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T18:13:24.599Z,1534788804.599 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2018-08-20T18:13:25.002Z,1534788805.002 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T18:13:25.002Z,1534788805.002 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T18:13:25.002Z,1534788805.002 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T18:14:32.561Z,1534788872.561 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T18:14:32.561Z,1534788872.561 [NAL9602] Data Fault, FailCount= 3
2018-08-20T18:14:32.561Z,1534788872.561 [NAL9602](ERROR): Data Fault
2018-08-20T18:14:32.653Z,1534788872.653 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T18:14:32.961Z,1534788872.961 [NAL9602](INFO): Powering down
2018-08-20T18:14:33.831Z,1534788873.831 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T18:14:33.831Z,1534788873.831 [NAL9602] No Fault, FailCount= 3
2018-08-20T18:15:02.217Z,1534788902.217 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T18:10:02.2Z
2018-08-20T18:15:02.217Z,1534788902.217 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T18:15:02.217Z,1534788902.217 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T18:15:03.122Z,1534788903.122 [NAL9602](INFO): Powering up NAL9602
2018-08-20T18:15:14.161Z,1534788914.161 [NAL9602](INFO): NAL9602 initialized
2018-08-20T18:16:24.976Z,1534788984.976 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T165749/Courier0022.lzma
2018-08-20T18:16:25.045Z,1534788985.045 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T18:16:25.045Z,1534788985.045 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-08-20T18:16:25.046Z,1534788985.046 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T18:16:25.068Z,1534788985.068 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T18:16:25.465Z,1534788985.465 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T18:16:25.466Z,1534788985.466 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-08-20T18:16:25.828Z,1534788985.828 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T18:16:25.828Z,1534788985.828 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T18:16:25.829Z,1534788985.829 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T18:16:26.565Z,1534788986.565 [DataOverHttps](INFO): Moved sent file to Logs/20180820T165749/Courier0022.lzma.bak
2018-08-20T18:16:26.566Z,1534788986.566 [DataOverHttps](INFO): SBD MOMSN=8430217
2018-08-20T18:16:57.364Z,1534789017.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T18:17:58.164Z,1534789078.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T18:18:28.564Z,1534789108.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T18:18:46.668Z,1534789126.668 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T18:18:46.669Z,1534789126.669 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-20T18:18:46.755Z,1534789126.755 [Rowe_600LCM](INFO): Initializing
2018-08-20T18:18:46.756Z,1534789126.756 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T18:18:46.756Z,1534789126.756 [Rowe_600LCM](INFO): LCM OK
2018-08-20T18:18:46.756Z,1534789126.756 [Rowe_600LCM](INFO): Powering up
2018-08-20T18:18:50.885Z,1534789130.885 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T18:18:50.945Z,1534789130.945 [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-20T18:18:50.947Z,1534789130.947 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T18:18:50.947Z,1534789130.947 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T18:18:50.948Z,1534789130.948 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T18:19:25.831Z,1534789165.831 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T18:19:25.831Z,1534789165.831 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-08-20T18:19:25.831Z,1534789165.831 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T18:19:25.873Z,1534789165.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T18:19:26.283Z,1534789166.283 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T18:19:26.284Z,1534789166.284 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-08-20T18:19:26.629Z,1534789166.629 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T18:19:26.630Z,1534789166.630 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T18:19:26.630Z,1534789166.630 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T18:19:26.965Z,1534789166.965 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.400269
2018-08-20T18:19:57.637Z,1534789197.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T18:20:47.195Z,1534789247.195 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T18:20:47.195Z,1534789247.195 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T18:20:47.195Z,1534789247.195 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T18:20:47.560Z,1534789247.560 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T18:20:47.599Z,1534789247.599 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T18:20:47.718Z,1534789247.718 [Rowe_600LCM](INFO): Powering down
2018-08-20T18:20:48.653Z,1534789248.653 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004193
2018-08-20T18:20:49.764Z,1534789249.764 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T18:20:49.764Z,1534789249.764 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T18:20:50.143Z,1534789250.143 [Rowe_600LCM](INFO): Initializing
2018-08-20T18:20:50.144Z,1534789250.144 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T18:20:50.144Z,1534789250.144 [Rowe_600LCM](INFO): LCM OK
2018-08-20T18:20:50.144Z,1534789250.144 [Rowe_600LCM](INFO): Powering up
2018-08-20T18:20:54.378Z,1534789254.378 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T18:20:54.441Z,1534789254.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-20T18:20:54.442Z,1534789254.442 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T18:20:54.444Z,1534789254.444 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T18:20:54.445Z,1534789254.445 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T18:21:23.980Z,1534789283.980 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20180820T165749/Express0023.lzma
2018-08-20T18:21:50.365Z,1534789310.365 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-08-20T18:22:20.765Z,1534789340.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-08-20T18:22:26.834Z,1534789346.834 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T18:22:26.834Z,1534789346.834 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-08-20T18:22:26.834Z,1534789346.834 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T18:22:26.867Z,1534789346.867 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T18:22:27.264Z,1534789347.264 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T18:22:27.265Z,1534789347.265 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-08-20T18:22:27.656Z,1534789347.656 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T18:22:27.657Z,1534789347.657 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T18:22