2018-02-01T17:41:04.708Z,1517506864.708 [Supervisor](DEBUG): Initializing supervisor.
2018-02-01T17:41:04.711Z,1517506864.711 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-02-01T17:41:04.711Z,1517506864.711 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-02-01T17:41:04.711Z,1517506864.711 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-02-01T17:41:04.712Z,1517506864.712 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-02-01T17:41:04.713Z,1517506864.713 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-02-01T17:41:04.715Z,1517506864.715 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-02-01T17:41:04.726Z,1517506864.726 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-02-01T17:41:04.727Z,1517506864.727 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-02-01T17:41:04.727Z,1517506864.727 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-02-01T17:41:04.728Z,1517506864.728 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-02-01T17:41:04.729Z,1517506864.729 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-02-01T17:41:04.729Z,1517506864.729 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-02-01T17:41:04.731Z,1517506864.731 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-02-01T17:41:04.732Z,1517506864.732 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-02-01T17:41:04.736Z,1517506864.736 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-02-01T17:41:05.034Z,1517506865.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-02-01T17:41:05.036Z,1517506865.036 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-02-01T17:41:05.221Z,1517506865.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-02-01T17:41:05.223Z,1517506865.223 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-02-01T17:41:05.483Z,1517506865.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-02-01T17:41:05.483Z,1517506865.483 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-02-01T17:41:05.618Z,1517506865.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-02-01T17:41:05.619Z,1517506865.619 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-02-01T17:41:05.714Z,1517506865.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-02-01T17:41:05.716Z,1517506865.716 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-02-01T17:41:06.139Z,1517506866.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-02-01T17:41:06.139Z,1517506866.139 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-02-01T17:41:06.459Z,1517506866.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-02-01T17:41:06.460Z,1517506866.460 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-02-01T17:41:06.865Z,1517506866.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-02-01T17:41:06.867Z,1517506866.867 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-02-01T17:41:06.967Z,1517506866.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-02-01T17:41:06.969Z,1517506866.969 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-02-01T17:41:07.064Z,1517506867.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-02-01T17:41:07.066Z,1517506867.066 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-02-01T17:41:07.258Z,1517506867.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-02-01T17:41:07.259Z,1517506867.259 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-02-01T17:41:07.449Z,1517506867.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-02-01T17:41:07.449Z,1517506867.449 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-02-01T17:41:07.762Z,1517506867.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-02-01T17:41:07.763Z,1517506867.763 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-02-01T17:41:07.897Z,1517506867.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-02-01T17:41:07.898Z,1517506867.898 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-02-01T17:41:07.979Z,1517506867.979 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-02-01T17:41:07.983Z,1517506867.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-02-01T17:41:08.087Z,1517506868.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-02-01T17:41:08.403Z,1517506868.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-02-01T17:41:08.822Z,1517506868.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-02-01T17:41:09.059Z,1517506869.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-02-01T17:41:09.200Z,1517506869.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-02-01T17:41:09.364Z,1517506869.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-02-01T17:41:09.439Z,1517506869.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-02-01T17:41:09.658Z,1517506869.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-02-01T17:41:09.659Z,1517506869.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-02-01T17:41:09.741Z,1517506869.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-02-01T17:41:09.830Z,1517506869.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-02-01T17:41:09.928Z,1517506869.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-02-01T17:41:10.049Z,1517506870.049 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-02-01T17:41:10.055Z,1517506870.055 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-02-01T17:41:10.330Z,1517506870.330 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-02-01T17:41:10.332Z,1517506870.332 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-02-01T17:41:10.386Z,1517506870.386 [DepthRateCalculator] Loaded
2018-02-01T17:41:10.387Z,1517506870.387 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-02-01T17:41:10.392Z,1517506870.392 [PitchRateCalculator] Loaded
2018-02-01T17:41:10.393Z,1517506870.393 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-02-01T17:41:10.408Z,1517506870.408 [SpeedCalculator] Loaded
2018-02-01T17:41:10.408Z,1517506870.408 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-02-01T17:41:10.428Z,1517506870.428 [TempGradientCalculator] Loaded
2018-02-01T17:41:10.429Z,1517506870.429 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-02-01T17:41:10.443Z,1517506870.443 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-02-01T17:41:10.444Z,1517506870.444 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-02-01T17:41:10.449Z,1517506870.449 [YawRateCalculator] Loaded
2018-02-01T17:41:10.449Z,1517506870.449 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-02-01T17:41:10.450Z,1517506870.450 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-02-01T17:41:10.451Z,1517506870.451 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-02-01T17:41:10.570Z,1517506870.570 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-02-01T17:41:10.570Z,1517506870.570 [StratificationFrontDetector](DEBUG): (re)initializing
2018-02-01T17:41:10.570Z,1517506870.570 [StratificationFrontDetector] Loaded
2018-02-01T17:41:10.571Z,1517506870.571 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-02-01T17:41:10.571Z,1517506870.571 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-02-01T17:41:10.572Z,1517506870.572 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-02-01T17:41:10.703Z,1517506870.703 [BuoyancyServo] Loaded
2018-02-01T17:41:10.703Z,1517506870.703 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-02-01T17:41:10.717Z,1517506870.717 [ElevatorServo] Loaded
2018-02-01T17:41:10.717Z,1517506870.717 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-02-01T17:41:10.731Z,1517506870.731 [MassServo] Loaded
2018-02-01T17:41:10.732Z,1517506870.732 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-02-01T17:41:10.746Z,1517506870.746 [RudderServo] Loaded
2018-02-01T17:41:10.746Z,1517506870.746 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-02-01T17:41:10.760Z,1517506870.760 [ThrusterServo] Loaded
2018-02-01T17:41:10.760Z,1517506870.760 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-02-01T17:41:10.760Z,1517506870.760 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-02-01T17:41:10.761Z,1517506870.761 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-02-01T17:41:10.784Z,1517506870.784 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-02-01T17:41:10.785Z,1517506870.785 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-02-01T17:41:10.904Z,1517506870.904 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-02-01T17:41:10.906Z,1517506870.906 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-02-01T17:41:11.445Z,1517506871.445 [DataOverHttps] Loaded
2018-02-01T17:41:11.445Z,1517506871.445 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-02-01T17:41:11.458Z,1517506871.458 [Depth_Keller] Loaded
2018-02-01T17:41:11.458Z,1517506871.458 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-02-01T17:41:11.463Z,1517506871.463 [DropWeight] Loaded
2018-02-01T17:41:11.463Z,1517506871.463 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-02-01T17:41:11.556Z,1517506871.556 [NAL9602] Loaded
2018-02-01T17:41:11.557Z,1517506871.557 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-02-01T17:41:11.572Z,1517506871.572 [Onboard] Loaded
2018-02-01T17:41:11.572Z,1517506871.572 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-02-01T17:41:11.581Z,1517506871.581 [Radio_Surface] Loaded
2018-02-01T17:41:11.582Z,1517506871.582 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-02-01T17:41:11.583Z,1517506871.583 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0
2018-02-01T17:41:11.583Z,1517506871.583 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884
2018-02-01T17:41:11.734Z,1517506871.734 [PNI_TCM] Loaded
2018-02-01T17:41:11.734Z,1517506871.734 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-02-01T17:41:11.792Z,1517506871.792 [Rowe_600LCM] Loaded
2018-02-01T17:41:11.792Z,1517506871.792 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-02-01T17:41:11.793Z,1517506871.793 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 4089F4E0
2018-02-01T17:41:11.794Z,1517506871.794 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 885
2018-02-01T17:41:13.471Z,1517506873.471 [BPC1] Loaded
2018-02-01T17:41:13.472Z,1517506873.472 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-02-01T17:41:13.472Z,1517506873.472 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-02-01T17:41:13.474Z,1517506873.474 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-02-01T17:41:13.835Z,1517506873.835 [SBIT](DEBUG): Construct Startup Built In Test.
2018-02-01T17:41:13.860Z,1517506873.860 [SBIT] Loaded
2018-02-01T17:41:13.860Z,1517506873.860 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-02-01T17:41:13.861Z,1517506873.861 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-02-01T17:41:13.872Z,1517506873.872 [IBIT] Loaded
2018-02-01T17:41:13.872Z,1517506873.872 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-02-01T17:41:13.876Z,1517506873.876 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-02-01T17:41:14.022Z,1517506874.022 [CBIT] Loaded
2018-02-01T17:41:14.022Z,1517506874.022 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-02-01T17:41:14.022Z,1517506874.022 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-02-01T17:41:14.023Z,1517506874.023 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-02-01T17:41:14.230Z,1517506874.230 [Aanderaa_O2] Loaded
2018-02-01T17:41:14.230Z,1517506874.230 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-02-01T17:41:14.242Z,1517506874.242 [CANONSampler] Loaded
2018-02-01T17:41:14.242Z,1517506874.242 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-02-01T17:41:14.317Z,1517506874.317 [CTD_NeilBrown] Loaded
2018-02-01T17:41:14.317Z,1517506874.317 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-02-01T17:41:14.318Z,1517506874.318 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409664E0
2018-02-01T17:41:14.319Z,1517506874.319 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 887
2018-02-01T17:41:14.363Z,1517506874.363 [CTD_Seabird] Loaded
2018-02-01T17:41:14.363Z,1517506874.363 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-02-01T17:41:14.364Z,1517506874.364 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409964E0
2018-02-01T17:41:14.365Z,1517506874.365 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 888
2018-02-01T17:41:14.379Z,1517506874.379 [PAR_Licor] Loaded
2018-02-01T17:41:14.379Z,1517506874.379 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-02-01T17:41:14.426Z,1517506874.426 [WetLabsBB2FL] Loaded
2018-02-01T17:41:14.426Z,1517506874.426 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-02-01T17:41:14.427Z,1517506874.427 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409C64E0
2018-02-01T17:41:14.428Z,1517506874.428 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 889
2018-02-01T17:41:14.428Z,1517506874.428 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-02-01T17:41:14.429Z,1517506874.429 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-02-01T17:41:14.514Z,1517506874.514 [VerticalControl](DEBUG): Construct VerticalControl.
2018-02-01T17:41:14.598Z,1517506874.598 [VerticalControl] Loaded
2018-02-01T17:41:14.598Z,1517506874.598 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-02-01T17:41:14.599Z,1517506874.599 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-02-01T17:41:14.653Z,1517506874.653 [HorizontalControl] Loaded
2018-02-01T17:41:14.654Z,1517506874.654 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-02-01T17:41:14.654Z,1517506874.654 [SpeedControl](DEBUG): Construct SpeedControl.
2018-02-01T17:41:14.656Z,1517506874.656 [SpeedControl] Loaded
2018-02-01T17:41:14.656Z,1517506874.656 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-02-01T17:41:14.657Z,1517506874.657 [LoopControl](DEBUG): Construct LoopControl.
2018-02-01T17:41:14.658Z,1517506874.658 [LoopControl] Loaded
2018-02-01T17:41:14.658Z,1517506874.658 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-02-01T17:41:14.658Z,1517506874.658 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-02-01T17:41:14.659Z,1517506874.659 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-02-01T17:41:14.695Z,1517506874.695 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-02-01T17:41:14.696Z,1517506874.696 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-02-01T17:41:14.800Z,1517506874.800 [DeadReckonUsingSpeedCalculator] Loaded
2018-02-01T17:41:14.800Z,1517506874.800 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-02-01T17:41:14.853Z,1517506874.853 [DeadReckonWithRespectToSeafloor] Loaded
2018-02-01T17:41:14.854Z,1517506874.854 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-02-01T17:41:14.869Z,1517506874.869 [NavChart] Loaded
2018-02-01T17:41:14.869Z,1517506874.869 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-02-01T17:41:14.873Z,1517506874.873 [UniversalFixResidualReporter] Loaded
2018-02-01T17:41:14.874Z,1517506874.874 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-02-01T17:41:14.874Z,1517506874.874 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-02-01T17:41:14.878Z,1517506874.878 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-02-01T17:41:14.878Z,1517506874.878 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-02-01T17:41:14.885Z,1517506874.885 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-02-01T17:41:14.886Z,1517506874.886 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A514E0
2018-02-01T17:41:14.886Z,1517506874.886 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 890
2018-02-01T17:41:14.890Z,1517506874.890 [Supervisor](INFO): Main Thread ID is 795
2018-02-01T17:41:14.891Z,1517506874.891 [Supervisor](DEBUG): Running supervisor.
2018-02-01T17:41:14.891Z,1517506874.891 [CommandLine ThreadHandler](INFO): Handler Thread ID is 891
2018-02-01T17:41:14.894Z,1517506874.894 [controlThread ThreadHandler](INFO): Handler Thread ID is 892
2018-02-01T17:41:14.894Z,1517506874.894 [controlThread](DEBUG): Initializing ControlThread
2018-02-01T17:41:14.895Z,1517506874.895 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-01T17:41:14.895Z,1517506874.895 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-02-01T17:41:14.896Z,1517506874.896 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-02-01T17:41:14.896Z,1517506874.896 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-02-01T17:41:14.897Z,1517506874.897 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-02-01T17:41:14.897Z,1517506874.897 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-02-01T17:41:14.905Z,1517506874.905 [SBIT](INFO): Initialize SBIT Component.
2018-02-01T17:41:14.905Z,1517506874.905 [SBIT](IMPORTANT): git: 2018-01-24-12-g35243d8
2018-02-01T17:41:14.905Z,1517506874.905 [SBIT](INFO): git hash: 35243d8075f2fa9bd3aa575030d1586c25df9f26
2018-02-01T17:41:14.906Z,1517506874.906 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-02-01T17:41:14.907Z,1517506874.907 [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-02-01T17:41:14.908Z,1517506874.908 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-02-01T17:41:14.908Z,1517506874.908 [IBIT](INFO): Initialize IBIT Component.
2018-02-01T17:41:14.909Z,1517506874.909 [CBIT](DEBUG): Initialize CBIT Component.
2018-02-01T17:41:14.909Z,1517506874.909 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-02-01T17:41:14.909Z,1517506874.909 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-02-01T17:41:14.910Z,1517506874.910 [logger ThreadHandler](INFO): Handler Thread ID is 893
2018-02-01T17:41:14.927Z,1517506874.927 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 894
2018-02-01T17:41:14.932Z,1517506874.932 [Radio_Surface](INFO): Powering up
2018-02-01T17:41:14.939Z,1517506874.939 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 895
2018-02-01T17:41:14.939Z,1517506874.939 [Rowe_600LCM](INFO): Initializing
2018-02-01T17:41:14.940Z,1517506874.940 [Rowe_600LCM](INFO): Checking LCM
2018-02-01T17:41:15.027Z,1517506875.027 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 896
2018-02-01T17:41:15.028Z,1517506875.028 [CTD_NeilBrown](INFO): Powering down
2018-02-01T17:41:15.055Z,1517506875.055 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-02-01T17:41:15.056Z,1517506875.056 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-02-01T17:41:15.057Z,1517506875.057 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-02-01T17:41:15.057Z,1517506875.057 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-02-01T17:41:15.058Z,1517506875.058 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-01T17:41:15.059Z,1517506875.059 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 897
2018-02-01T17:41:15.059Z,1517506875.059 [CTD_Seabird](INFO): Initializing
2018-02-01T17:41:15.060Z,1517506875.060 [CTD_Seabird](INFO): Checking LCM
2018-02-01T17:41:15.063Z,1517506875.063 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:41:15.063Z,1517506875.063 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:41:15.066Z,1517506875.066 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T17:41:15.072Z,1517506875.072 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:41:15.072Z,1517506875.072 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:41:15.075Z,1517506875.075 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-02-01T17:41:15.077Z,1517506875.077 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-02-01T17:41:15.093Z,1517506875.093 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-02-01T17:41:15.165Z,1517506875.165 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 898
2018-02-01T17:41:15.166Z,1517506875.166 [WetLabsBB2FL](INFO): Powering down
2018-02-01T17:41:15.184Z,1517506875.184 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 899
2018-02-01T17:41:15.271Z,1517506875.271 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-01T17:41:15.281Z,1517506875.281 [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-02-01T17:41:15.282Z,1517506875.282 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-02-01T17:41:15.284Z,1517506875.284 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-02-01T17:41:15.285Z,1517506875.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-02-01T17:41:15.285Z,1517506875.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-02-01T17:41:15.285Z,1517506875.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-02-01T17:41:15.285Z,1517506875.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-02-01T17:41:15.285Z,1517506875.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-02-01T17:41:15.286Z,1517506875.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-02-01T17:41:15.286Z,1517506875.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-02-01T17:41:15.286Z,1517506875.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-02-01T17:41:15.286Z,1517506875.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-02-01T17:41:15.287Z,1517506875.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-02-01T17:41:15.287Z,1517506875.287 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-02-01T17:41:15.287Z,1517506875.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-02-01T17:41:15.287Z,1517506875.287 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-02-01T17:41:15.287Z,1517506875.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-02-01T17:41:15.287Z,1517506875.287 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-02-01T17:41:15.368Z,1517506875.368 [CTD_Seabird](INFO): LCM OK
2018-02-01T17:41:15.369Z,1517506875.369 [CTD_Seabird](INFO): Powering up
2018-02-01T17:41:15.369Z,1517506875.369 [Rowe_600LCM](INFO): LCM OK
2018-02-01T17:41:15.369Z,1517506875.369 [Rowe_600LCM](INFO): Powering up
2018-02-01T17:41:15.422Z,1517506875.422 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-02-01T17:41:15.423Z,1517506875.423 [Default:A.Wait](DEBUG): Construct Wait.
2018-02-01T17:41:15.425Z,1517506875.425 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-01T17:41:15.450Z,1517506875.450 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-02-01T17:41:15.472Z,1517506875.472 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-02-01T17:41:15.490Z,1517506875.490 [Default:E.Execute](DEBUG): Construct Execute.
2018-02-01T17:41:15.494Z,1517506875.494 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <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-02-01T17:41:15.499Z,1517506875.499 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-02-01T17:41:15.523Z,1517506875.523 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-02-01T17:41:15.533Z,1517506875.533 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-02-01T17:41:15.759Z,1517506875.759 [CANONSampler](INFO): Powering down
2018-02-01T17:41:15.851Z,1517506875.851 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-02-01T17:41:15.951Z,1517506875.951 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:16.082Z,1517506876.082 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:16.179Z,1517506876.179 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-01T17:41:16.187Z,1517506876.187 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-01T17:41:16.209Z,1517506876.209 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-02-01T17:41:16.215Z,1517506876.215 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-02-01T17:41:16.228Z,1517506876.228 [MassServo](DEBUG): Initializing EZServoServo.
2018-02-01T17:41:16.235Z,1517506876.235 [MassServo](DEBUG): Initializing MassServo.
2018-02-01T17:41:16.248Z,1517506876.248 [RudderServo](DEBUG): Initializing EZServoServo.
2018-02-01T17:41:16.255Z,1517506876.255 [RudderServo](DEBUG): Initializing RudderServo.
2018-02-01T17:41:16.272Z,1517506876.272 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-02-01T17:41:16.279Z,1517506876.279 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-02-01T17:41:16.508Z,1517506876.508 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-02-01T17:41:16.508Z,1517506876.508 [DropWeight] Hardware Fault, FailCount= 1
2018-02-01T17:41:16.508Z,1517506876.508 [DropWeight](ERROR): Hardware Fault
2018-02-01T17:41:16.575Z,1517506876.575 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:16.576Z,1517506876.576 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:16.603Z,1517506876.603 [CommandLine](FAULT): Scheduling is paused
2018-02-01T17:41:16.604Z,1517506876.604 [CBIT](INFO): Critical error at 20180201T174116
2018-02-01T17:41:16.604Z,1517506876.604 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-02-01T17:41:16.607Z,1517506876.607 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-02-01T17:41:16.607Z,1517506876.607 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-02-01T17:41:16.807Z,1517506876.807 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:16.808Z,1517506876.808 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:17.154Z,1517506877.154 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-02-01T17:41:17.154Z,1517506877.154 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-02-01T17:41:17.155Z,1517506877.155 [BuoyancyServo] Communications Fault, FailCount= 1
2018-02-01T17:41:17.155Z,1517506877.155 [BuoyancyServo](ERROR): Communications Fault
2018-02-01T17:41:17.360Z,1517506877.360 [CBIT](INFO): Critical error at 20180201T174116
2018-02-01T17:41:17.362Z,1517506877.362 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-02-01T17:41:17.465Z,1517506877.465 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:17.475Z,1517506877.475 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:17.488Z,1517506877.488 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-01T17:41:17.488Z,1517506877.488 [BuoyancyServo](INFO): Powering down
2018-02-01T17:41:17.853Z,1517506877.853 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:17.862Z,1517506877.862 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:18.210Z,1517506878.210 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:18.222Z,1517506878.222 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:18.626Z,1517506878.626 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:18.627Z,1517506878.627 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:19.127Z,1517506879.127 [Aanderaa_O2](INFO): Powering down
2018-02-01T17:41:19.231Z,1517506879.231 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:19.232Z,1517506879.232 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-01T17:41:19.477Z,1517506879.477 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-02-01T17:41:19.580Z,1517506879.580 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-02-01T17:41:19.580Z,1517506879.580 [BuoyancyServo] No Fault, FailCount= 1
2018-02-01T17:41:19.617Z,1517506879.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 38400 >& /dev/null &
2018-02-01T17:41:19.737Z,1517506879.737 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-02-01T17:41:19.738Z,1517506879.738 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-02-01T17:41:19.739Z,1517506879.739 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-02-01T17:41:19.868Z,1517506879.868 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-01T17:41:19.868Z,1517506879.868 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-01T17:41:20.727Z,1517506880.727 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-02-01T17:41:20.727Z,1517506880.727 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-02-01T17:41:20.728Z,1517506880.728 [BuoyancyServo] Communications Fault, FailCount= 2
2018-02-01T17:41:20.728Z,1517506880.728 [BuoyancyServo](ERROR): Communications Fault
2018-02-01T17:41:20.976Z,1517506880.976 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-01T17:41:21.011Z,1517506881.011 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-02-01T17:41:21.166Z,1517506881.166 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-01T17:41:21.167Z,1517506881.167 [BuoyancyServo](INFO): Powering down
2018-02-01T17:41:21.174Z,1517506881.174 [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-02-01T17:41:21.176Z,1517506881.176 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-02-01T17:41:21.724Z,1517506881.724 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-01T17:41:21.724Z,1517506881.724 [CTD_Seabird](INFO): Powering down
2018-02-01T17:41:23.587Z,1517506883.587 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-02-01T17:41:23.587Z,1517506883.587 [BuoyancyServo] No Fault, FailCount= 2
2018-02-01T17:41:23.924Z,1517506883.924 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-01T17:41:23.924Z,1517506883.924 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-01T17:41:24.774Z,1517506884.774 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-02-01T17:41:24.774Z,1517506884.774 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-02-01T17:41:24.774Z,1517506884.774 [BuoyancyServo] Communications Fault, FailCount= 3
2018-02-01T17:41:24.774Z,1517506884.774 [BuoyancyServo](ERROR): Communications Fault
2018-02-01T17:41:24.790Z,1517506884.790 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-02-01T17:41:24.896Z,1517506884.896 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-01T17:41:24.897Z,1517506884.897 [BuoyancyServo](INFO): Powering down
2018-02-01T17:41:26.928Z,1517506886.928 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-02-01T17:41:26.928Z,1517506886.928 [BuoyancyServo] No Fault, FailCount= 3
2018-02-01T17:41:27.271Z,1517506887.271 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-01T17:41:27.272Z,1517506887.272 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-01T17:41:38.510Z,1517506898.510 [SBIT](IMPORTANT): Beginning Startup BIT
2018-02-01T17:41:38.522Z,1517506898.522 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-01T17:41:41.736Z,1517506901.736 [NAL9602](INFO): Powering up NAL9602
2018-02-01T17:41:49.467Z,1517506909.467 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.005484
CHAN A1 (24V): -0.008090
CHAN A2 (12V): -0.002120
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001676
CHAN B1 (3.15aV): -0.000137
CHAN B2 (3.15bV): -0.000160
CHAN B3 (GND): 0.000079
OPEN: -0.000476
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-01T17:41:52.944Z,1517506912.944 [NAL9602](INFO): NAL9602 initialized
2018-02-01T17:42:32.612Z,1517506952.612 [SBIT](IMPORTANT): SBIT PASSED
2018-02-01T17:42:33.003Z,1517506953.003 [MissionManager](IMPORTANT): Started mission Startup
2018-02-01T17:42:33.003Z,1517506953.003 [Startup] Running Loop=1
2018-02-01T17:42:33.003Z,1517506953.003 [Startup](DEBUG): Aggregate::initialize Startup
2018-02-01T17:42:33.003Z,1517506953.003 [Startup:A.GoToSurface] Running Loop=1
2018-02-01T17:42:33.003Z,1517506953.003 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-01T17:42:33.004Z,1517506953.004 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-01T17:42:33.005Z,1517506953.005 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-01T17:42:33.005Z,1517506953.005 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-01T17:42:33.005Z,1517506953.005 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-01T17:42:33.006Z,1517506953.006 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-01T17:42:33.011Z,1517506953.011 [Startup:StartupSatComms] Running Loop=1
2018-02-01T17:42:33.011Z,1517506953.011 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-02-01T17:42:33.011Z,1517506953.011 [Startup:StartupSatComms:A] Running Loop=1
2018-02-01T17:42:33.405Z,1517506953.405 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-02-01T17:43:15.630Z,1517506995.630 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-02-01T17:43:15.630Z,1517506995.630 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-02-01T17:43:15.630Z,1517506995.630 [Rowe_600LCM](ERROR): Communications Fault
2018-02-01T17:43:15.817Z,1517506995.817 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-02-01T17:43:16.034Z,1517506996.034 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-02-01T17:43:16.126Z,1517506996.126 [Rowe_600LCM](INFO): Powering down
2018-02-01T17:43:17.390Z,1517506997.390 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-02-01T17:43:17.390Z,1517506997.390 [Rowe_600LCM] No Fault, FailCount= 1
2018-02-01T17:43:17.742Z,1517506997.742 [Rowe_600LCM](INFO): Initializing
2018-02-01T17:43:17.743Z,1517506997.743 [Rowe_600LCM](INFO): Checking LCM
2018-02-01T17:43:17.743Z,1517506997.743 [Rowe_600LCM](INFO): LCM OK
2018-02-01T17:43:17.743Z,1517506997.743 [Rowe_600LCM](INFO): Powering up
2018-02-01T17:43:21.933Z,1517507001.933 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-02-01T17:43:21.997Z,1517507001.997 [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 38400 >& /dev/null &
2018-02-01T17:43:21.999Z,1517507001.999 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-02-01T17:43:21.000Z,1517507002.000 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-02-01T17:43:22.000Z,1517507002.000 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-02-01T17:43:33.405Z,1517507013.405 [Startup:StartupSatComms:A](INFO): Timed out from 2018-02-01T17:42:33.0Z
2018-02-01T17:43:33.405Z,1517507013.405 [Startup:StartupSatComms:A] Stopped
2018-02-01T17:43:33.405Z,1517507013.405 [Startup:StartupSatComms:B] Running Loop=1
2018-02-01T17:43:33.807Z,1517507013.807 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-02-01T17:43:38.782Z,1517507018.782 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003309
2018-02-01T17:43:42.929Z,1517507022.929 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180201T172201/Courier0008.lzma
2018-02-01T17:43:43.719Z,1517507023.719 [DataOverHttps](INFO): Moved sent file to Logs/20180201T172201/Courier0008.lzma.bak
2018-02-01T17:43:43.719Z,1517507023.719 [DataOverHttps](INFO): SBD MOMSN=7781479
2018-02-01T17:43:52.841Z,1517507032.841 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20180201T174104/Courier0000.lzma
2018-02-01T17:43:53.607Z,1517507033.607 [DataOverHttps](INFO): Moved sent file to Logs/20180201T174104/Courier0000.lzma.bak
2018-02-01T17:43:53.607Z,1517507033.607 [DataOverHttps](INFO): SBD MOMSN=7781481
2018-02-01T17:44:02.678Z,1517507042.678 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180201T172201/Express0009.lzma
2018-02-01T17:44:03.493Z,1517507043.493 [DataOverHttps](INFO): Moved sent file to Logs/20180201T172201/Express0009.lzma.bak
2018-02-01T17:44:03.494Z,1517507043.494 [DataOverHttps](INFO): SBD MOMSN=7781486
2018-02-01T17:44:12.459Z,1517507052.459 [DataOverHttps](INFO): Sending 736 bytes from file Logs/20180201T174104/Express0001.lzma
2018-02-01T17:44:13.264Z,1517507053.264 [DataOverHttps](INFO): Moved sent file to Logs/20180201T174104/Express0001.lzma.bak
2018-02-01T17:44:13.264Z,1517507053.264 [DataOverHttps](INFO): SBD MOMSN=7781488
2018-02-01T17:44:13.815Z,1517507053.815 [Startup:StartupSatComms:B] Stopped
2018-02-01T17:44:13.815Z,1517507053.815 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-02-01T17:44:13.815Z,1517507053.815 [Startup:StartupSatComms] Stopped
2018-02-01T17:44:13.815Z,1517507053.815 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-02-01T17:44:13.816Z,1517507053.816 [Startup](INFO): Completed Startup
2018-02-01T17:44:13.816Z,1517507053.816 [MissionManager](INFO): Startup is completed.
2018-02-01T17:44:13.816Z,1517507053.816 [MissionManager](INFO): Uninitializing Mission Startup
2018-02-01T17:44:13.816Z,1517507053.816 [Startup] Stopped
2018-02-01T17:44:13.816Z,1517507053.816 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-02-01T17:44:13.816Z,1517507053.816 [Startup:A.GoToSurface] Stopped
2018-02-01T17:44:13.816Z,1517507053.816 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-01T17:44:14.109Z,1517507054.109 [MissionManager](IMPORTANT): Started mission Default
2018-02-01T17:44:14.109Z,1517507054.109 [Default] Running Loop=1
2018-02-01T17:44:14.109Z,1517507054.109 [Default](DEBUG): Aggregate::initialize Default
2018-02-01T17:44:14.109Z,1517507054.109 [Default:B.GoToSurface] Running Loop=1
2018-02-01T17:44:14.109Z,1517507054.109 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-01T17:44:14.109Z,1517507054.109 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-01T17:44:14.110Z,1517507054.110 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-01T17:44:14.110Z,1517507054.110 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-01T17:44:14.111Z,1517507054.111 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-01T17:44:14.111Z,1517507054.111 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-01T17:44:14.111Z,1517507054.111 [Default:A.Wait] Running Loop=1
2018-02-01T17:44:14.111Z,1517507054.111 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-02-01T17:44:17.329Z,1517507057.329 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:44:17.329Z,1517507057.329 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-02-01T17:44:17.329Z,1517507057.329 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-01T17:44:17.356Z,1517507057.356 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:44:17.356Z,1517507057.356 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-02-01T17:44:17.356Z,1517507057.356 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-01T17:44:17.372Z,1517507057.372 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-01T17:44:17.372Z,1517507057.372 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-01T17:44:17.580Z,1517507057.580 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-01T17:44:17.580Z,1517507057.580 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-02-01T17:44:17.580Z,1517507057.580 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-01T17:44:17.580Z,1517507057.580 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-02-01T17:44:17.852Z,1517507057.852 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-01T17:44:17.852Z,1517507057.852 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:44:17.853Z,1517507057.853 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:44:17.853Z,1517507057.853 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T17:44:17.854Z,1517507057.854 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:44:17.854Z,1517507057.854 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:44:27.440Z,1517507067.440 [Default:A.Wait](INFO): Done Waiting.
2018-02-01T17:44:27.440Z,1517507067.440 [Default:A.Wait] Stopped
2018-02-01T17:44:27.440Z,1517507067.440 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-02-01T17:44:27.840Z,1517507067.840 [Default:CheckIn] Running Loop=1
2018-02-01T17:44:27.840Z,1517507067.840 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-01T17:44:27.840Z,1517507067.840 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-01T17:44:28.234Z,1517507068.234 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-02-01T17:45:17.840Z,1517507117.840 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-02-01T17:45:17.840Z,1517507117.840 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-02-01T17:45:17.840Z,1517507117.840 [Rowe_600LCM](ERROR): Communications Fault
2018-02-01T17:45:17.979Z,1517507117.979 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-02-01T17:45:18.242Z,1517507118.242 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-02-01T17:45:18.446Z,1517507118.446 [Rowe_600LCM](INFO): Powering down
2018-02-01T17:45:19.957Z,1517507119.957 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-02-01T17:45:19.957Z,1517507119.957 [Rowe_600LCM] No Fault, FailCount= 2
2018-02-01T17:45:20.062Z,1517507120.062 [Rowe_600LCM](INFO): Initializing
2018-02-01T17:45:20.063Z,1517507120.063 [Rowe_600LCM](INFO): Checking LCM
2018-02-01T17:45:20.063Z,1517507120.063 [Rowe_600LCM](INFO): LCM OK
2018-02-01T17:45:20.063Z,1517507120.063 [Rowe_600LCM](INFO): Powering up
2018-02-01T17:45:24.183Z,1517507124.183 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-02-01T17:45:24.253Z,1517507124.253 [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 38400 >& /dev/null &
2018-02-01T17:45:24.255Z,1517507124.255 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-02-01T17:45:24.256Z,1517507124.256 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-02-01T17:45:24.257Z,1517507124.257 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-02-01T17:45:41.243Z,1517507141.243 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T17:45:42.039Z,1517507142.039 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T17:46:55.972Z,1517507215.972 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-02-01T17:47:18.085Z,1517507238.085 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:47:18.085Z,1517507238.085 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-02-01T17:47:18.085Z,1517507238.085 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-01T17:47:18.087Z,1517507238.087 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:47:18.087Z,1517507238.087 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-02-01T17:47:18.087Z,1517507238.087 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-01T17:47:18.099Z,1517507238.099 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-01T17:47:18.099Z,1517507238.099 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-01T17:47:18.500Z,1517507238.500 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-01T17:47:18.500Z,1517507238.500 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-02-01T17:47:18.501Z,1517507238.501 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-01T17:47:18.501Z,1517507238.501 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-02-01T17:47:18.898Z,1517507238.898 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-01T17:47:18.899Z,1517507238.899 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:47:18.899Z,1517507238.899 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:47:18.899Z,1517507238.899 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T17:47:18.900Z,1517507238.900 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:47:18.900Z,1517507238.900 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:47:20.430Z,1517507240.430 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-02-01T17:47:20.430Z,1517507240.430 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-02-01T17:47:20.430Z,1517507240.430 [Rowe_600LCM](ERROR): Communications Fault
2018-02-01T17:47:20.525Z,1517507240.525 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-02-01T17:47:20.834Z,1517507240.834 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-02-01T17:47:21.041Z,1517507241.041 [Rowe_600LCM](INFO): Powering down
2018-02-01T17:47:22.510Z,1517507242.510 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-02-01T17:47:22.510Z,1517507242.510 [Rowe_600LCM] No Fault, FailCount= 3
2018-02-01T17:47:22.655Z,1517507242.655 [Rowe_600LCM](INFO): Initializing
2018-02-01T17:47:22.655Z,1517507242.655 [Rowe_600LCM](INFO): Checking LCM
2018-02-01T17:47:22.655Z,1517507242.655 [Rowe_600LCM](INFO): LCM OK
2018-02-01T17:47:22.656Z,1517507242.656 [Rowe_600LCM](INFO): Powering up
2018-02-01T17:47:26.775Z,1517507246.775 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-02-01T17:47:26.870Z,1517507246.870 [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 38400 >& /dev/null &
2018-02-01T17:47:26.871Z,1517507246.871 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-02-01T17:47:26.872Z,1517507246.872 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-02-01T17:47:26.873Z,1517507246.873 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-02-01T17:48:59.788Z,1517507339.788 [BPC1](ERROR): Failed to receive battery data
2018-02-01T17:48:59.788Z,1517507339.788 [BPC1] Communications Fault, FailCount= 1
2018-02-01T17:48:59.788Z,1517507339.788 [BPC1](ERROR): Communications Fault
2018-02-01T17:48:59.817Z,1517507339.817 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-01T17:49:00.988Z,1517507340.988 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-01T17:49:00.988Z,1517507340.988 [BPC1] No Fault, FailCount= 1
2018-02-01T17:49:22.821Z,1517507362.821 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-02-01T17:49:22.821Z,1517507362.821 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-02-01T17:49:22.821Z,1517507362.821 [Rowe_600LCM](ERROR): Communications Fault
2018-02-01T17:49:23.089Z,1517507363.089 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-02-01T17:49:23.226Z,1517507363.226 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-02-01T17:49:23.316Z,1517507363.316 [Rowe_600LCM](INFO): Powering down
2018-02-01T17:49:24.733Z,1517507364.733 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-02-01T17:49:24.733Z,1517507364.733 [Rowe_600LCM] No Fault, FailCount= 4
2018-02-01T17:49:24.930Z,1517507364.930 [Rowe_600LCM](INFO): Initializing
2018-02-01T17:49:24.931Z,1517507364.931 [Rowe_600LCM](INFO): Checking LCM
2018-02-01T17:49:24.931Z,1517507364.931 [Rowe_600LCM](INFO): LCM OK
2018-02-01T17:49:24.931Z,1517507364.931 [Rowe_600LCM](INFO): Powering up
2018-02-01T17:49:27.889Z,1517507367.889 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-02-01T17:44:27.8Z
2018-02-01T17:49:27.889Z,1517507367.889 [Default:CheckIn:Read_GPS] Stopped
2018-02-01T17:49:27.889Z,1517507367.889 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-01T17:49:28.292Z,1517507368.292 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-02-01T17:49:29.150Z,1517507369.150 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-02-01T17:49:29.221Z,1517507369.221 [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 38400 >& /dev/null &
2018-02-01T17:49:29.223Z,1517507369.223 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-02-01T17:49:29.224Z,1517507369.224 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-02-01T17:49:29.224Z,1517507369.224 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-02-01T17:49:32.474Z,1517507372.474 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180201T174104/Courier0004.lzma
2018-02-01T17:49:33.266Z,1517507373.266 [DataOverHttps](INFO): Moved sent file to Logs/20180201T174104/Courier0004.lzma.bak
2018-02-01T17:49:33.266Z,1517507373.266 [DataOverHttps](INFO): SBD MOMSN=7781546
2018-02-01T17:49:42.296Z,1517507382.296 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20180201T174104/Express0005.lzma
2018-02-01T17:49:43.095Z,1517507383.095 [DataOverHttps](INFO): Moved sent file to Logs/20180201T174104/Express0005.lzma.bak
2018-02-01T17:49:43.095Z,1517507383.095 [DataOverHttps](INFO): SBD MOMSN=7781548
2018-02-01T17:49:43.685Z,1517507383.685 [Default:CheckIn:Read_Iridium] Stopped
2018-02-01T17:49:43.685Z,1517507383.685 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-01T17:49:43.685Z,1517507383.685 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-01T17:50:19.294Z,1517507419.294 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:50:19.294Z,1517507419.294 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-02-01T17:50:19.294Z,1517507419.294 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-01T17:50:19.319Z,1517507419.319 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:50:19.319Z,1517507419.319 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-02-01T17:50:19.319Z,1517507419.319 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-01T17:50:19.347Z,1517507419.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-01T17:50:19.347Z,1517507419.347 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-01T17:50:19.642Z,1517507419.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-01T17:50:19.642Z,1517507419.642 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-02-01T17:50:19.643Z,1517507419.643 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-01T17:50:19.643Z,1517507419.643 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-02-01T17:50:20.040Z,1517507420.040 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-01T17:50:20.040Z,1517507420.040 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:50:20.041Z,1517507420.041 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:50:20.041Z,1517507420.041 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T17:50:20.041Z,1517507420.042 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:50:20.042Z,1517507420.042 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:51:15.037Z,1517507475.037 [CBIT](DEBUG): EFC running - data check-sum false
2018-02-01T17:51:25.450Z,1517507485.450 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-02-01T17:51:25.450Z,1517507485.450 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-02-01T17:51:25.450Z,1517507485.450 [Rowe_600LCM](ERROR): Communications Fault
2018-02-01T17:51:25.483Z,1517507485.483 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-02-01T17:51:25.484Z,1517507485.484 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-02-01T17:51:25.855Z,1517507485.855 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-02-01T17:51:26.034Z,1517507486.034 [Rowe_600LCM](INFO): Powering down
2018-02-01T17:51:56.520Z,1517507516.520 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-02-01T17:51:56.520Z,1517507516.520 [NAL9602] Data Fault, FailCount= 1
2018-02-01T17:51:56.520Z,1517507516.520 [NAL9602](ERROR): Data Fault
2018-02-01T17:51:56.598Z,1517507516.598 [CBIT](ERROR): Data Fault in component: NAL9602
2018-02-01T17:51:56.915Z,1517507516.915 [NAL9602](INFO): Powering down
2018-02-01T17:51:57.767Z,1517507517.767 [CBIT](INFO): Clearing failed state for component NAL9602
2018-02-01T17:51:57.767Z,1517507517.767 [NAL9602] No Fault, FailCount= 1
2018-02-01T17:52:27.315Z,1517507547.315 [NAL9602](INFO): Powering up NAL9602
2018-02-01T17:52:38.520Z,1517507558.520 [NAL9602](INFO): NAL9602 initialized
2018-02-01T17:53:20.213Z,1517507600.213 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:53:20.213Z,1517507600.213 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-02-01T17:53:20.213Z,1517507600.213 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-01T17:53:20.215Z,1517507600.215 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:53:20.215Z,1517507600.215 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-02-01T17:53:20.215Z,1517507600.215 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-01T17:53:20.253Z,1517507600.253 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-01T17:53:20.253Z,1517507600.253 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-01T17:53:20.616Z,1517507600.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-01T17:53:20.617Z,1517507600.617 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-02-01T17:53:20.617Z,1517507600.617 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-01T17:53:20.617Z,1517507600.617 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-02-01T17:53:21.001Z,1517507601.001 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-01T17:53:21.002Z,1517507601.002 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:53:21.003Z,1517507601.003 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:53:21.003Z,1517507601.003 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T17:53:21.003Z,1517507601.003 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:53:21.004Z,1517507601.004 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:54:44.237Z,1517507684.237 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-02-01T17:54:44.237Z,1517507684.237 [Default:CheckIn:C.Wait] Stopped
2018-02-01T17:54:44.237Z,1517507684.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-01T17:54:44.237Z,1517507684.237 [Default:CheckIn:D] Running Loop=1
2018-02-01T17:54:44.653Z,1517507684.653 [Default:CheckIn:D] Stopped
2018-02-01T17:54:44.653Z,1517507684.653 [Default:CheckIn:E] Running Loop=1
2018-02-01T17:54:45.037Z,1517507685.037 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.509033 min 
2018-02-01T17:54:45.041Z,1517507685.041 [Default:CheckIn:E] Stopped
2018-02-01T17:54:45.041Z,1517507685.041 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-02-01T17:54:45.041Z,1517507685.041 [Default:CheckIn] Stopped
2018-02-01T17:54:45.041Z,1517507685.041 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-01T17:54:45.041Z,1517507685.041 [Default:CheckIn](INFO): Running loop #2
2018-02-01T17:54:45.041Z,1517507685.041 [Default:CheckIn] Running Loop=2
2018-02-01T17:54:45.041Z,1517507685.041 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-01T17:54:45.041Z,1517507685.041 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-01T17:56:16.658Z,1517507776.658 [CBIT](INFO): Clearing failed state for component DropWeight
2018-02-01T17:56:16.658Z,1517507776.658 [DropWeight] No Fault, FailCount= 1
2018-02-01T17:56:21.027Z,1517507781.027 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:56:21.027Z,1517507781.027 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-02-01T17:56:21.027Z,1517507781.027 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-01T17:56:21.029Z,1517507781.029 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:56:21.029Z,1517507781.029 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-02-01T17:56:21.029Z,1517507781.029 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-01T17:56:21.045Z,1517507781.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-01T17:56:21.045Z,1517507781.045 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-01T17:56:21.448Z,1517507781.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-01T17:56:21.448Z,1517507781.448 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-02-01T17:56:21.448Z,1517507781.448 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-01T17:56:21.448Z,1517507781.448 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-02-01T17:56:21.833Z,1517507781.833 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-01T17:56:21.833Z,1517507781.833 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:56:21.834Z,1517507781.834 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:56:21.834Z,1517507781.834 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T17:56:21.835Z,1517507781.835 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:56:21.835Z,1517507781.835 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:56:57.380Z,1517507817.380 [NAL9602](INFO): GPS fix at 20180201T175531: (36.802763, -121.788068)
2018-02-01T17:56:57.452Z,1517507817.452 [Default:CheckIn:Read_GPS] Stopped
2018-02-01T17:56:57.452Z,1517507817.452 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-01T17:57:03.775Z,1517507823.775 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20180201T174104/Courier0008.lzma
2018-02-01T17:57:04.559Z,1517507824.559 [DataOverHttps](INFO): Moved sent file to Logs/20180201T174104/Courier0008.lzma.bak
2018-02-01T17:57:04.559Z,1517507824.559 [DataOverHttps](INFO): SBD MOMSN=7781605
2018-02-01T17:57:14.396Z,1517507834.396 [DataOverHttps](INFO): Sending 402 bytes from file Logs/20180201T174104/Express0009.lzma
2018-02-01T17:57:15.195Z,1517507835.195 [DataOverHttps](INFO): Moved sent file to Logs/20180201T174104/Express0009.lzma.bak
2018-02-01T17:57:15.195Z,1517507835.195 [DataOverHttps](INFO): SBD MOMSN=7781608
2018-02-01T17:57:15.887Z,1517507835.887 [Default:CheckIn:Read_Iridium] Stopped
2018-02-01T17:57:15.887Z,1517507835.887 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-01T17:57:15.887Z,1517507835.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-01T17:57:33.919Z,1517507853.919 [NAL9602](INFO): Powering down
2018-02-01T17:59:21.988Z,1517507961.988 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T17:59:21.988Z,1517507961.988 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-02-01T17:59:21.988Z,1517507961.988 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-01T17:59:22.001Z,1517507962.001 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-01T17:59:22.413Z,1517507962.413 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-01T17:59:22.413Z,1517507962.413 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-02-01T17:59:22.787Z,1517507962.787 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T17:59:22.788Z,1517507962.788 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T17:59:22.788Z,1517507962.788 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T17:59:31.999Z,1517507971.999 [BPC1](ERROR): Failed to receive battery data
2018-02-01T17:59:31.999Z,1517507971.999 [BPC1] Communications Fault, FailCount= 1
2018-02-01T17:59:31.999Z,1517507971.999 [BPC1](ERROR): Communications Fault
2018-02-01T17:59:32.066Z,1517507972.066 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-01T17:59:33.239Z,1517507973.239 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-01T17:59:33.239Z,1517507973.239 [BPC1] No Fault, FailCount= 1
2018-02-01T18:01:15.303Z,1517508075.303 [CBIT](DEBUG): EFC running - data check-sum false
2018-02-01T18:01:25.751Z,1517508085.751 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-02-01T18:01:25.751Z,1517508085.751 [Rowe_600LCM] No Fault, FailCount= 5
2018-02-01T18:01:25.882Z,1517508085.882 [Rowe_600LCM](INFO): Initializing
2018-02-01T18:01:25.883Z,1517508085.883 [Rowe_600LCM](INFO): Checking LCM
2018-02-01T18:01:25.883Z,1517508085.883 [Rowe_600LCM](INFO): LCM OK
2018-02-01T18:01:25.883Z,1517508085.883 [Rowe_600LCM](INFO): Powering up
2018-02-01T18:01:30.006Z,1517508090.006 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-02-01T18:01:30.073Z,1517508090.073 [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 38400 >& /dev/null &
2018-02-01T18:01:30.076Z,1517508090.076 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-02-01T18:01:30.077Z,1517508090.077 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-02-01T18:01:30.077Z,1517508090.077 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-02-01T18:02:12.959Z,1517508132.959 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:13.755Z,1517508133.755 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:14.551Z,1517508134.551 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:15.259Z,1517508135.259 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:16.055Z,1517508136.055 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:16.149Z,1517508136.149 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-02-01T18:02:16.149Z,1517508136.149 [Default:CheckIn:C.Wait] Stopped
2018-02-01T18:02:16.149Z,1517508136.149 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-01T18:02:16.150Z,1517508136.150 [Default:CheckIn:D] Running Loop=1
2018-02-01T18:02:16.563Z,1517508136.563 [Default:CheckIn:D] Stopped
2018-02-01T18:02:16.563Z,1517508136.563 [Default:CheckIn:E] Running Loop=1
2018-02-01T18:02:16.851Z,1517508136.851 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:17.255Z,1517508137.255 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:18.051Z,1517508138.051 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:18.675Z,1517508138.675 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:19.471Z,1517508139.471 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:19.761Z,1517508139.761 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.040898 min 
2018-02-01T18:02:19.762Z,1517508139.762 [Default:CheckIn:E] Stopped
2018-02-01T18:02:19.762Z,1517508139.762 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-02-01T18:02:19.762Z,1517508139.762 [Default:CheckIn] Stopped
2018-02-01T18:02:19.762Z,1517508139.762 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-01T18:02:19.762Z,1517508139.762 [Default:CheckIn](INFO): Running loop #3
2018-02-01T18:02:19.762Z,1517508139.762 [Default:CheckIn] Running Loop=3
2018-02-01T18:02:19.762Z,1517508139.762 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-01T18:02:19.762Z,1517508139.762 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-01T18:02:20.183Z,1517508140.183 [NAL9602](INFO): Powering up
2018-02-01T18:02:20.268Z,1517508140.268 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:20.679Z,1517508140.679 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:21.475Z,1517508141.475 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:22.271Z,1517508142.271 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:22.719Z,1517508142.719 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:23.045Z,1517508143.045 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T18:02:23.045Z,1517508143.045 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-02-01T18:02:23.045Z,1517508143.045 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-01T18:02:23.075Z,1517508143.075 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-01T18:02:23.431Z,1517508143.431 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-01T18:02:23.431Z,1517508143.431 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-02-01T18:02:23.515Z,1517508143.515 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:23.818Z,1517508143.818 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T18:02:23.819Z,1517508143.819 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T18:02:23.819Z,1517508143.819 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T18:02:24.335Z,1517508144.335 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:24.771Z,1517508144.771 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:25.567Z,1517508145.567 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:26.363Z,1517508146.363 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:26.923Z,1517508146.923 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:27.719Z,1517508147.719 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:28.515Z,1517508148.515 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:29.215Z,1517508149.215 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:30.011Z,1517508150.011 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:30.808Z,1517508150.808 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:31.215Z,1517508151.215 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:31.380Z,1517508151.380 [NAL9602](INFO): NAL9602 initialized
2018-02-01T18:02:32.011Z,1517508152.011 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:32.683Z,1517508152.683 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:33.479Z,1517508153.479 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:34.275Z,1517508154.275 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:34.679Z,1517508154.679 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:35.475Z,1517508155.475 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:36.272Z,1517508156.272 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:36.711Z,1517508156.711 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:37.507Z,1517508157.507 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:38.304Z,1517508158.304 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:38.739Z,1517508158.739 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:40.923Z,1517508160.923 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:41.719Z,1517508161.719 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:42.515Z,1517508162.515 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:43.211Z,1517508163.211 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:44.007Z,1517508164.007 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:44.804Z,1517508164.804 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:45.215Z,1517508165.215 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:46.011Z,1517508166.011 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:46.680Z,1517508166.680 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:47.475Z,1517508167.475 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:48.272Z,1517508168.272 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:48.687Z,1517508168.687 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:49.483Z,1517508169.483 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:50.280Z,1517508170.280 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:50.695Z,1517508170.695 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:51.491Z,1517508171.491 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:52.291Z,1517508172.291 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:52.743Z,1517508172.743 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:53.539Z,1517508173.539 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:54.335Z,1517508174.335 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:54.923Z,1517508174.923 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:55.719Z,1517508175.719 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:56.515Z,1517508176.515 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:57.215Z,1517508177.215 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:58.011Z,1517508178.011 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:58.808Z,1517508178.808 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:02:59.215Z,1517508179.215 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:00.011Z,1517508180.011 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:00.683Z,1517508180.683 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:01.479Z,1517508181.479 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:02.275Z,1517508182.275 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:02.691Z,1517508182.691 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:03.487Z,1517508183.487 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:04.283Z,1517508184.283 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:04.715Z,1517508184.715 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:05.514Z,1517508185.514 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:06.314Z,1517508186.314 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:06.743Z,1517508186.743 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:07.539Z,1517508187.539 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:08.335Z,1517508188.335 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:08.919Z,1517508188.919 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:09.715Z,1517508189.715 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:10.511Z,1517508190.511 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:11.211Z,1517508191.211 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:12.007Z,1517508192.007 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:12.804Z,1517508192.804 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:13.215Z,1517508193.215 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:14.011Z,1517508194.011 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:14.687Z,1517508194.687 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:15.483Z,1517508195.483 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:16.279Z,1517508196.279 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:16.707Z,1517508196.707 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:17.503Z,1517508197.503 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:18.299Z,1517508198.299 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:18.703Z,1517508198.703 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:19.499Z,1517508199.499 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:20.296Z,1517508200.296 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:20.779Z,1517508200.779 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:21.575Z,1517508201.575 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:22.371Z,1517508202.371 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:22.939Z,1517508202.939 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:23.735Z,1517508203.735 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:24.531Z,1517508204.531 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:25.243Z,1517508205.243 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:26.039Z,1517508206.039 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:26.835Z,1517508206.835 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:27.239Z,1517508207.239 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:28.035Z,1517508208.035 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:28.671Z,1517508208.671 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:29.467Z,1517508209.467 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:30.270Z,1517508210.270 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:30.679Z,1517508210.679 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:31.475Z,1517508211.475 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:32.275Z,1517508212.275 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:32.683Z,1517508212.683 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:33.479Z,1517508213.479 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:34.276Z,1517508214.276 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:34.804Z,1517508214.804 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:35.599Z,1517508215.599 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:36.395Z,1517508216.395 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:36.927Z,1517508216.927 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:37.723Z,1517508217.723 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:38.519Z,1517508218.519 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:39.243Z,1517508219.243 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:40.039Z,1517508220.039 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:40.835Z,1517508220.835 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:41.255Z,1517508221.255 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:42.051Z,1517508222.051 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:42.667Z,1517508222.667 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:43.463Z,1517508223.463 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:44.260Z,1517508224.260 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:44.675Z,1517508224.675 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:45.471Z,1517508225.471 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:46.268Z,1517508226.268 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:46.683Z,1517508226.683 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:47.479Z,1517508227.479 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:48.276Z,1517508228.276 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:48.779Z,1517508228.779 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:49.575Z,1517508229.575 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:50.371Z,1517508230.371 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:50.939Z,1517508230.939 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:51.737Z,1517508231.737 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:52.531Z,1517508232.531 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:53.219Z,1517508233.219 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:54.015Z,1517508234.015 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:54.812Z,1517508234.812 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:55.227Z,1517508235.227 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:56.023Z,1517508236.023 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:56.671Z,1517508236.671 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:57.467Z,1517508237.467 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:58.264Z,1517508238.264 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:58.675Z,1517508238.675 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:03:59.471Z,1517508239.471 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:00.268Z,1517508240.268 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:00.683Z,1517508240.683 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:01.479Z,1517508241.479 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:02.276Z,1517508242.276 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:02.779Z,1517508242.779 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:03.575Z,1517508243.575 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:04.371Z,1517508244.371 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:04.923Z,1517508244.923 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:05.719Z,1517508245.719 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:06.515Z,1517508246.515 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:07.247Z,1517508247.247 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:08.043Z,1517508248.043 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:08.851Z,1517508248.851 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:09.255Z,1517508249.255 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:10.051Z,1517508250.051 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:10.671Z,1517508250.671 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:11.467Z,1517508251.467 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:12.264Z,1517508252.264 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:12.675Z,1517508252.675 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:13.471Z,1517508253.471 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:14.268Z,1517508254.268 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:14.687Z,1517508254.687 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:15.483Z,1517508255.483 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:16.280Z,1517508256.280 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:16.775Z,1517508256.775 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:17.571Z,1517508257.571 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:18.367Z,1517508258.367 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:18.951Z,1517508258.951 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:19.747Z,1517508259.747 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:20.543Z,1517508260.543 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:21.223Z,1517508261.223 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:22.019Z,1517508262.019 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:22.816Z,1517508262.816 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:23.223Z,1517508263.223 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:24.019Z,1517508264.019 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:04:24.719Z,1517508264.719 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-02-01T18:05:24.084Z,1517508324.084 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-01T18:05:24.084Z,1517508324.084 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-02-01T18:05:24.084Z,1517508324.084 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-01T18:05:24.133Z,1517508324.133 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-01T18:05:24.506Z,1517508324.506 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-01T18:05:24.506Z,1517508324.506 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-02-01T18:05:24.872Z,1517508324.872 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-01T18:05:24.873Z,1517508324.873 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-01T18:05:24.873Z,1517508324.873 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-01T18:05:53.078Z,1517508353.078 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-02-01T18:05:53.078Z,1517508353.078 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-02-01T18:05:53.078Z,1517508353.078 [Rowe_600LCM](ERROR): Communications Fault
2018-02-01T18:05:53.285Z,1517508353.285 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-02-01T18:05:53.482Z,1517508353.482 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-02-01T18:05:53.576Z,1517508353.576 [Rowe_600LCM](INFO): Powering down
2018-02-01T18:05:54.904Z,1517508354.904 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-02-01T18:05:54.904Z,1517508354.904 [Rowe_600LCM] No Fault, FailCount= 1
2018-02-01T18:05:55.190Z,1517508355.190 [Rowe_600LCM](INFO): Initializing
2018-02-01T18:05:55.191Z,1517508355.191 [Rowe_600LCM](INFO): Checking LCM
2018-02-01T18:05:55.191Z,1517508355.191 [Rowe_600LCM](INFO): LCM OK
2018-02-01T18:05:55.191Z,1517508355.191 [Rowe_600LCM](INFO): Powering up
2018-02-01T18:05:59.463Z,1517508359.463 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-02-01T18:05:59.561Z,1517508359.561 [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 38400 >& /dev/null &
2018-02-01T18:05:59.569Z,1517508359.569 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-02-01T18:05:59.570Z,1517508359.570 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-02-01T18:05:59.572Z,1517508359.572 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-02-01T18:06:21.407Z,1517508381.407 [DataOverHttps](IMPORTANT): SBD MTMSN=20180201T180456
2018-02-01T18:06:25.602Z,1517508385.602 [DataOverHttps](INFO): Received command:restart app
2018-02-01T18:06:25.679Z,1517508385.679 [CommandLine](IMPORTANT): got command restart application
2018-02-01T18:06:26.686Z,1517508386.686 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:26.686Z,1517508386.686 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:26.850Z,1517508386.850 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-02-01T18:06:26.850Z,1517508386.850 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:26.851Z,1517508386.851 [CommandLine](INFO): Join timeout helper Thread ID is 965
2018-02-01T18:06:26.871Z,1517508386.871 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-02-01T18:06:26.871Z,1517508386.871 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:26.872Z,1517508386.872 [NavChartDb](INFO): Join timeout helper Thread ID is 966
2018-02-01T18:06:27.112Z,1517508387.112 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:27.112Z,1517508387.112 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:27.118Z,1517508387.118 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-02-01T18:06:27.118Z,1517508387.118 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:27.119Z,1517508387.119 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 967
2018-02-01T18:06:27.370Z,1517508387.371 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:27.371Z,1517508387.371 [WetLabsBB2FL](INFO): Powering down
2018-02-01T18:06:27.371Z,1517508387.371 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:27.378Z,1517508387.378 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-02-01T18:06:27.378Z,1517508387.378 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:27.379Z,1517508387.379 [CTD_Seabird](INFO): Join timeout helper Thread ID is 968
2018-02-01T18:06:27.746Z,1517508387.746 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:27.836Z,1517508387.836 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-01T18:06:27.836Z,1517508387.836 [CTD_Seabird](INFO): Powering down
2018-02-01T18:06:27.837Z,1517508387.837 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:27.846Z,1517508387.846 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-02-01T18:06:27.846Z,1517508387.846 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:27.847Z,1517508387.847 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 970
2018-02-01T18:06:27.891Z,1517508387.891 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:27.891Z,1517508387.891 [CTD_NeilBrown](INFO): Powering down
2018-02-01T18:06:27.892Z,1517508387.892 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:27.895Z,1517508387.895 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-02-01T18:06:27.895Z,1517508387.895 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:27.896Z,1517508387.896 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 971
2018-02-01T18:06:27.927Z,1517508387.927 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:27.927Z,1517508387.927 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-02-01T18:06:28.115Z,1517508388.115 [Rowe_600LCM](INFO): Powering down
2018-02-01T18:06:28.116Z,1517508388.116 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.123Z,1517508388.123 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-02-01T18:06:28.123Z,1517508388.123 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.124Z,1517508388.124 [Radio_Surface](INFO): Join timeout helper Thread ID is 973
2018-02-01T18:06:28.255Z,1517508388.255 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:28.255Z,1517508388.255 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.275Z,1517508388.275 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-02-01T18:06:28.275Z,1517508388.275 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.276Z,1517508388.276 [logger](INFO): Join timeout helper Thread ID is 974
2018-02-01T18:06:28.276Z,1517508388.276 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:28.277Z,1517508388.277 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.295Z,1517508388.295 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-02-01T18:06:28.295Z,1517508388.295 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.295Z,1517508388.295 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-02-01T18:06:28.295Z,1517508388.295 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.296Z,1517508388.296 [controlThread](INFO): Join timeout helper Thread ID is 975
2018-02-01T18:06:28.390Z,1517508388.390 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-01T18:06:28.391Z,1517508388.391 [controlThread](DEBUG): Uninitializing ControlThread
2018-02-01T18:06:28.392Z,1517508388.392 [NAL9602](INFO): Powering down
2018-02-01T18:06:28.393Z,1517508388.393 [PNI_TCM](INFO): Powering down
2018-02-01T18:06:28.479Z,1517508388.479 [Aanderaa_O2](INFO): Powering down
2018-02-01T18:06:28.481Z,1517508388.481 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-01T18:06:28.483Z,1517508388.483 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-02-01T18:06:28.483Z,1517508388.483 [MissionManager](INFO): Uninitializing Mission Default
2018-02-01T18:06:28.483Z,1517508388.483 [Default] Stopped
2018-02-01T18:06:28.484Z,1517508388.484 [Default](DEBUG): Aggregate::uninitialize Default
2018-02-01T18:06:28.484Z,1517508388.484 [Default:B.GoToSurface] Stopped
2018-02-01T18:06:28.484Z,1517508388.484 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-01T18:06:28.484Z,1517508388.484 [Default:CheckIn] Stopped
2018-02-01T18:06:28.484Z,1517508388.484 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-01T18:06:28.484Z,1517508388.484 [Default:CheckIn:Read_GPS] Stopped
2018-02-01T18:06:28.487Z,1517508388.487 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-02-01T18:06:28.488Z,1517508388.488 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-02-01T18:06:28.488Z,1517508388.488 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-02-01T18:06:28.488Z,1517508388.488 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-02-01T18:06:28.488Z,1517508388.488 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-01T18:06:28.489Z,1517508388.489 [BuoyancyServo](INFO): Powering down
2018-02-01T18:06:28.502Z,1517508388.502 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-02-01T18:06:28.502Z,1517508388.502 [ElevatorServo](INFO): Powering down
2018-02-01T18:06:28.503Z,1517508388.503 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-02-01T18:06:28.503Z,1517508388.503 [MassServo](INFO): Powering down
2018-02-01T18:06:28.504Z,1517508388.504 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-02-01T18:06:28.504Z,1517508388.504 [RudderServo](INFO): Powering down
2018-02-01T18:06:28.505Z,1517508388.505 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-02-01T18:06:28.505Z,1517508388.505 [ThrusterServo](INFO): Powering down
2018-02-01T18:06:28.506Z,1517508388.506 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-02-01T18:06:28.506Z,1517508388.506 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-02-01T18:06:28.507Z,1517508388.507 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-02-01T18:06:28.507Z,1517508388.507 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.614Z,1517508388.614 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.620Z,1517508388.620 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.655Z,1517508388.655 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.658Z,1517508388.658 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.660Z,1517508388.660 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.688Z,1517508388.688 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-01T18:06:28.752Z,1517508388.752 [logger ThreadHandler](INFO): Thread cancelled.