2018-05-08T20:25:00.934Z,1525811100.934 [Supervisor](DEBUG): Initializing supervisor.
2018-05-08T20:25:00.936Z,1525811100.936 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-08T20:25:00.937Z,1525811100.937 [SyncHandler](INFO): Protected caller Thread ID is 6831
2018-05-08T20:25:00.937Z,1525811100.937 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-08T20:25:00.938Z,1525811100.938 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-08T20:25:00.939Z,1525811100.939 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6832
2018-05-08T20:25:00.941Z,1525811100.941 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-08T20:25:00.952Z,1525811100.952 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-08T20:25:00.953Z,1525811100.953 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-08T20:25:00.953Z,1525811100.953 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6833
2018-05-08T20:25:00.954Z,1525811100.954 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-08T20:25:00.955Z,1525811100.955 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-08T20:25:00.955Z,1525811100.955 [logger ThreadHandler](INFO): Protected caller Thread ID is 6834
2018-05-08T20:25:00.957Z,1525811100.957 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-08T20:25:00.958Z,1525811100.958 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-08T20:25:00.959Z,1525811100.959 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-08T20:25:01.260Z,1525811101.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-08T20:25:01.261Z,1525811101.261 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-08T20:25:01.445Z,1525811101.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-08T20:25:01.446Z,1525811101.446 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-08T20:25:01.704Z,1525811101.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-08T20:25:01.705Z,1525811101.705 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-08T20:25:01.842Z,1525811101.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-08T20:25:01.842Z,1525811101.842 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-08T20:25:01.941Z,1525811101.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-08T20:25:01.942Z,1525811101.942 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-08T20:25:02.361Z,1525811102.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-08T20:25:02.362Z,1525811102.362 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-08T20:25:02.679Z,1525811102.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-08T20:25:02.679Z,1525811102.679 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-08T20:25:03.082Z,1525811103.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-08T20:25:03.083Z,1525811103.083 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-08T20:25:03.184Z,1525811103.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-08T20:25:03.185Z,1525811103.185 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-08T20:25:03.280Z,1525811103.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-08T20:25:03.281Z,1525811103.281 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-08T20:25:03.482Z,1525811103.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-08T20:25:03.483Z,1525811103.483 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-08T20:25:03.677Z,1525811103.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-08T20:25:03.678Z,1525811103.678 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-08T20:25:03.991Z,1525811103.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-08T20:25:03.991Z,1525811103.991 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-08T20:25:04.127Z,1525811104.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-08T20:25:04.128Z,1525811104.128 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-08T20:25:04.210Z,1525811104.210 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-05-08T20:25:04.211Z,1525811104.211 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-05-08T20:25:04.317Z,1525811104.317 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-05-08T20:25:04.408Z,1525811104.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-05-08T20:25:04.503Z,1525811104.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-05-08T20:25:04.590Z,1525811104.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-05-08T20:25:04.735Z,1525811104.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-05-08T20:25:04.937Z,1525811104.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-05-08T20:25:05.058Z,1525811105.058 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-05-08T20:25:05.277Z,1525811105.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-08T20:25:05.277Z,1525811105.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-05-08T20:25:05.361Z,1525811105.361 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-05-08T20:25:05.450Z,1525811105.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-05-08T20:25:05.549Z,1525811105.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-05-08T20:25:05.721Z,1525811105.721 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-08T20:25:05.724Z,1525811105.724 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-08T20:25:06.635Z,1525811106.635 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-08T20:25:06.635Z,1525811106.635 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-08T20:25:06.715Z,1525811106.715 [DepthRateCalculator] Loaded
2018-05-08T20:25:06.715Z,1525811106.715 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-08T20:25:06.754Z,1525811106.754 [PitchRateCalculator] Loaded
2018-05-08T20:25:06.754Z,1525811106.754 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-08T20:25:06.790Z,1525811106.790 [SpeedCalculator] Loaded
2018-05-08T20:25:06.790Z,1525811106.790 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-08T20:25:06.811Z,1525811106.811 [TempGradientCalculator] Loaded
2018-05-08T20:25:06.811Z,1525811106.811 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-08T20:25:06.825Z,1525811106.825 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-08T20:25:06.826Z,1525811106.826 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-08T20:25:06.831Z,1525811106.831 [YawRateCalculator] Loaded
2018-05-08T20:25:06.831Z,1525811106.831 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-08T20:25:06.869Z,1525811106.869 [ElevatorOffsetCalculator] Loaded
2018-05-08T20:25:06.869Z,1525811106.869 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-08T20:25:06.894Z,1525811106.894 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-08T20:25:06.895Z,1525811106.895 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-08T20:25:07.076Z,1525811107.076 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-08T20:25:07.076Z,1525811107.076 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-08T20:25:07.076Z,1525811107.076 [StratificationFrontDetector] Loaded
2018-05-08T20:25:07.077Z,1525811107.077 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-08T20:25:07.077Z,1525811107.077 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-08T20:25:07.078Z,1525811107.078 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-08T20:25:07.187Z,1525811107.187 [BuoyancyServo] Loaded
2018-05-08T20:25:07.187Z,1525811107.187 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-08T20:25:07.201Z,1525811107.201 [ElevatorServo] Loaded
2018-05-08T20:25:07.201Z,1525811107.201 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-08T20:25:07.215Z,1525811107.215 [MassServo] Loaded
2018-05-08T20:25:07.216Z,1525811107.216 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-08T20:25:07.230Z,1525811107.230 [RudderServo] Loaded
2018-05-08T20:25:07.230Z,1525811107.230 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-08T20:25:07.243Z,1525811107.243 [ThrusterServo] Loaded
2018-05-08T20:25:07.244Z,1525811107.244 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-08T20:25:07.244Z,1525811107.244 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-08T20:25:07.245Z,1525811107.245 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-08T20:25:07.257Z,1525811107.257 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-08T20:25:07.258Z,1525811107.258 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-08T20:25:07.351Z,1525811107.351 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-08T20:25:07.351Z,1525811107.351 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-08T20:25:07.602Z,1525811107.602 [DataOverHttps] Loaded
2018-05-08T20:25:07.603Z,1525811107.603 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-08T20:25:07.616Z,1525811107.616 [Depth_Keller] Loaded
2018-05-08T20:25:07.616Z,1525811107.616 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-08T20:25:07.621Z,1525811107.621 [DropWeight] Loaded
2018-05-08T20:25:07.621Z,1525811107.621 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-05-08T20:25:07.713Z,1525811107.713 [NAL9602] Loaded
2018-05-08T20:25:07.713Z,1525811107.713 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-08T20:25:07.729Z,1525811107.729 [Onboard] Loaded
2018-05-08T20:25:07.729Z,1525811107.729 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-08T20:25:07.739Z,1525811107.739 [Radio_Surface] Loaded
2018-05-08T20:25:07.739Z,1525811107.739 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-08T20:25:07.741Z,1525811107.741 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408724E0
2018-05-08T20:25:07.741Z,1525811107.741 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6917
2018-05-08T20:25:07.785Z,1525811107.785 [PNI_TCM] Loaded
2018-05-08T20:25:07.785Z,1525811107.785 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-05-08T20:25:09.439Z,1525811109.439 [BPC1] Loaded
2018-05-08T20:25:09.439Z,1525811109.439 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-08T20:25:09.439Z,1525811109.439 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-08T20:25:09.440Z,1525811109.440 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-08T20:25:09.561Z,1525811109.561 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-08T20:25:09.582Z,1525811109.582 [SBIT] Loaded
2018-05-08T20:25:09.582Z,1525811109.582 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-08T20:25:09.583Z,1525811109.583 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-08T20:25:09.594Z,1525811109.594 [IBIT] Loaded
2018-05-08T20:25:09.594Z,1525811109.594 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-08T20:25:09.597Z,1525811109.597 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-08T20:25:09.739Z,1525811109.739 [CBIT] Loaded
2018-05-08T20:25:09.739Z,1525811109.739 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-08T20:25:09.740Z,1525811109.740 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-08T20:25:09.740Z,1525811109.740 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-08T20:25:09.872Z,1525811109.872 [Aanderaa_O2] Loaded
2018-05-08T20:25:09.872Z,1525811109.872 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-05-08T20:25:09.884Z,1525811109.884 [CANONSampler] Loaded
2018-05-08T20:25:09.884Z,1525811109.884 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-05-08T20:25:09.957Z,1525811109.957 [CTD_NeilBrown] Loaded
2018-05-08T20:25:09.958Z,1525811109.958 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-05-08T20:25:09.959Z,1525811109.959 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409324E0
2018-05-08T20:25:09.959Z,1525811109.959 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6918
2018-05-08T20:25:10.003Z,1525811110.003 [CTD_Seabird] Loaded
2018-05-08T20:25:10.004Z,1525811110.004 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-08T20:25:10.005Z,1525811110.005 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409624E0
2018-05-08T20:25:10.005Z,1525811110.005 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6919
2018-05-08T20:25:10.018Z,1525811110.018 [PAR_Licor] Loaded
2018-05-08T20:25:10.019Z,1525811110.019 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-08T20:25:10.065Z,1525811110.065 [WetLabsBB2FL] Loaded
2018-05-08T20:25:10.066Z,1525811110.066 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-05-08T20:25:10.067Z,1525811110.067 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409924E0
2018-05-08T20:25:10.067Z,1525811110.067 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6920
2018-05-08T20:25:10.068Z,1525811110.068 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-08T20:25:10.068Z,1525811110.068 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-08T20:25:10.131Z,1525811110.131 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-08T20:25:10.211Z,1525811110.211 [VerticalControl] Loaded
2018-05-08T20:25:10.212Z,1525811110.212 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-08T20:25:10.212Z,1525811110.212 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-08T20:25:10.267Z,1525811110.267 [HorizontalControl] Loaded
2018-05-08T20:25:10.267Z,1525811110.267 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-08T20:25:10.268Z,1525811110.268 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-08T20:25:10.270Z,1525811110.270 [SpeedControl] Loaded
2018-05-08T20:25:10.270Z,1525811110.270 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-08T20:25:10.271Z,1525811110.271 [LoopControl](DEBUG): Construct LoopControl.
2018-05-08T20:25:10.271Z,1525811110.271 [LoopControl] Loaded
2018-05-08T20:25:10.271Z,1525811110.271 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-08T20:25:10.272Z,1525811110.272 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-08T20:25:10.272Z,1525811110.272 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-08T20:25:10.296Z,1525811110.296 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-08T20:25:10.296Z,1525811110.296 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-08T20:25:10.382Z,1525811110.382 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-08T20:25:10.382Z,1525811110.382 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-08T20:25:10.435Z,1525811110.435 [DeadReckonWithRespectToSeafloor] Loaded
2018-05-08T20:25:10.435Z,1525811110.435 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-05-08T20:25:10.451Z,1525811110.451 [NavChart] Loaded
2018-05-08T20:25:10.451Z,1525811110.451 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-08T20:25:10.455Z,1525811110.455 [UniversalFixResidualReporter] Loaded
2018-05-08T20:25:10.455Z,1525811110.455 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-08T20:25:10.456Z,1525811110.456 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-08T20:25:10.459Z,1525811110.459 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-08T20:25:10.460Z,1525811110.460 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-08T20:25:10.466Z,1525811110.466 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-08T20:25:10.467Z,1525811110.467 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0
2018-05-08T20:25:10.467Z,1525811110.467 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6921
2018-05-08T20:25:10.472Z,1525811110.472 [Supervisor](INFO): Main Thread ID is 6830
2018-05-08T20:25:10.472Z,1525811110.472 [Supervisor](DEBUG): Running supervisor.
2018-05-08T20:25:10.473Z,1525811110.473 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6922
2018-05-08T20:25:10.476Z,1525811110.476 [controlThread ThreadHandler](INFO): Handler Thread ID is 6923
2018-05-08T20:25:10.477Z,1525811110.477 [controlThread](DEBUG): Initializing ControlThread
2018-05-08T20:25:10.478Z,1525811110.478 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-08T20:25:10.478Z,1525811110.478 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-08T20:25:10.478Z,1525811110.478 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-08T20:25:10.479Z,1525811110.479 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-08T20:25:10.479Z,1525811110.479 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-08T20:25:10.480Z,1525811110.480 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-08T20:25:10.480Z,1525811110.480 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-08T20:25:10.489Z,1525811110.489 [SBIT](INFO): Initialize SBIT Component.
2018-05-08T20:25:10.489Z,1525811110.489 [SBIT](IMPORTANT): git: 2018-03-19
2018-05-08T20:25:10.489Z,1525811110.489 [SBIT](INFO): git hash: a0816c0235cbc02280eea7af955e3502bfefb717
2018-05-08T20:25:10.490Z,1525811110.490 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-08T20:25:10.491Z,1525811110.491 [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-05-08T20:25:10.492Z,1525811110.492 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-05-08T20:25:10.492Z,1525811110.492 [IBIT](INFO): Initialize IBIT Component.
2018-05-08T20:25:10.493Z,1525811110.493 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-08T20:25:10.493Z,1525811110.493 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-05-08T20:25:10.494Z,1525811110.494 [logger ThreadHandler](INFO): Handler Thread ID is 6924
2018-05-08T20:25:10.511Z,1525811110.511 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6925
2018-05-08T20:25:10.517Z,1525811110.517 [Radio_Surface](INFO): Powering up
2018-05-08T20:25:10.523Z,1525811110.523 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6926
2018-05-08T20:25:10.524Z,1525811110.524 [CTD_NeilBrown](INFO): Powering down
2018-05-08T20:25:10.551Z,1525811110.551 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6927
2018-05-08T20:25:10.552Z,1525811110.552 [CTD_Seabird](INFO): Initializing
2018-05-08T20:25:10.552Z,1525811110.552 [CTD_Seabird](INFO): Checking LCM
2018-05-08T20:25:10.561Z,1525811110.561 [CTD_Seabird](INFO): LCM OK
2018-05-08T20:25:10.561Z,1525811110.561 [CTD_Seabird](INFO): Powering up
2018-05-08T20:25:10.571Z,1525811110.571 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6928
2018-05-08T20:25:10.572Z,1525811110.572 [WetLabsBB2FL](INFO): Powering down
2018-05-08T20:25:10.603Z,1525811110.603 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-08T20:25:10.604Z,1525811110.604 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-08T20:25:10.605Z,1525811110.605 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-08T20:25:10.606Z,1525811110.606 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-08T20:25:10.606Z,1525811110.606 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:25:10.607Z,1525811110.607 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:25:10.607Z,1525811110.607 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:25:10.608Z,1525811110.608 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:25:10.608Z,1525811110.608 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:25:10.608Z,1525811110.608 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:25:10.609Z,1525811110.609 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-08T20:25:10.609Z,1525811110.609 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-08T20:25:10.610Z,1525811110.610 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-08T20:25:10.619Z,1525811110.619 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6929
2018-05-08T20:25:10.622Z,1525811110.622 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-08T20:25:10.622Z,1525811110.622 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-08T20:25:10.622Z,1525811110.622 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-08T20:25:10.623Z,1525811110.623 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-08T20:25:10.623Z,1525811110.623 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-08T20:25:10.623Z,1525811110.623 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-08T20:25:10.623Z,1525811110.623 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-08T20:25:10.623Z,1525811110.623 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-08T20:25:10.624Z,1525811110.624 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-08T20:25:10.624Z,1525811110.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-08T20:25:10.624Z,1525811110.624 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-08T20:25:10.624Z,1525811110.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-08T20:25:10.624Z,1525811110.624 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-08T20:25:10.624Z,1525811110.624 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-08T20:25:10.625Z,1525811110.625 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-08T20:25:10.625Z,1525811110.625 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-08T20:25:10.649Z,1525811110.649 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-08T20:25:10.692Z,1525811110.692 [MissionManager](DEBUG):
2018-05-08T20:25:10.693Z,1525811110.693 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-08T20:25:10.780Z,1525811110.780 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-08T20:25:10.781Z,1525811110.781 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-08T20:25:10.811Z,1525811110.811 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-08T20:25:10.839Z,1525811110.839 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-08T20:25:10.841Z,1525811110.841 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-08T20:25:10.875Z,1525811110.875 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-08T20:25:10.879Z,1525811110.879 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn 300
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2018-05-08T20:25:10.899Z,1525811110.899 [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,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-08T20:25:10.906Z,1525811110.906 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-08T20:25:11.244Z,1525811111.244 [CANONSampler](INFO): Powering down
2018-05-08T20:25:11.495Z,1525811111.495 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:11.507Z,1525811111.507 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:11.617Z,1525811111.617 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-08T20:25:11.623Z,1525811111.623 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-08T20:25:11.645Z,1525811111.645 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-08T20:25:11.651Z,1525811111.651 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-08T20:25:11.672Z,1525811111.672 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-08T20:25:11.679Z,1525811111.679 [MassServo](DEBUG): Initializing MassServo.
2018-05-08T20:25:11.684Z,1525811111.684 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-08T20:25:11.691Z,1525811111.691 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-08T20:25:11.711Z,1525811111.711 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-08T20:25:11.719Z,1525811111.719 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-08T20:25:11.883Z,1525811111.883 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-05-08T20:25:11.883Z,1525811111.883 [DropWeight] Hardware Fault, FailCount= 1
2018-05-08T20:25:11.883Z,1525811111.883 [DropWeight](ERROR): Hardware Fault
2018-05-08T20:25:11.945Z,1525811111.945 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:11.946Z,1525811111.946 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:11.963Z,1525811111.963 [CommandLine](FAULT): Scheduling is paused
2018-05-08T20:25:11.963Z,1525811111.963 [CBIT](INFO): Critical error at 20180508T202511
2018-05-08T20:25:11.963Z,1525811111.963 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-05-08T20:25:11.966Z,1525811111.966 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-05-08T20:25:11.978Z,1525811111.978 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-05-08T20:25:16.040Z,1525811116.040 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-08T20:25:16.158Z,1525811116.158 [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-05-08T20:25:16.248Z,1525811116.248 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-08T20:25:16.692Z,1525811116.692 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-08T20:25:16.692Z,1525811116.692 [CTD_Seabird](INFO): Powering down
2018-05-08T20:25:21.000Z,1525811121.000 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:21.001Z,1525811121.001 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:21.312Z,1525811121.312 [CBIT](INFO): Critical error at 20180508T202511
2018-05-08T20:25:21.449Z,1525811121.449 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:21.462Z,1525811121.462 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:21.875Z,1525811121.875 [Aanderaa_O2](INFO): Powering down
2018-05-08T20:25:21.991Z,1525811121.991 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:21.991Z,1525811121.991 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:22.127Z,1525811122.127 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004300
2018-05-08T20:25:22.182Z,1525811122.182 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:22.199Z,1525811122.199 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:22.574Z,1525811122.574 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:22.583Z,1525811122.583 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:22.990Z,1525811122.990 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:22.991Z,1525811122.991 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-08T20:25:33.936Z,1525811133.936 [SBIT](IMPORTANT): Beginning Startup BIT
2018-05-08T20:25:33.940Z,1525811133.940 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-08T20:25:37.996Z,1525811137.996 [NAL9602](INFO): Powering up NAL9602
2018-05-08T20:25:44.872Z,1525811144.872 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.013582
CHAN A1 (24V): -0.016977
CHAN A2 (12V): 4.697929
CHAN A3 (5V): -0.000445
CHAN B0 (3.3V): 0.000181
CHAN B1 (3.15aV): -0.000390
CHAN B2 (3.15bV): -0.000422
CHAN B3 (GND): -0.000068
OPEN: -0.000418
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-08T20:25:49.192Z,1525811149.192 [NAL9602](INFO): NAL9602 initialized
2018-05-08T20:26:29.605Z,1525811189.605 [SBIT](IMPORTANT): SBIT PASSED
2018-05-08T20:26:29.649Z,1525811189.649 [CommandLine](IMPORTANT): got command configSet list
2018-05-08T20:26:29.649Z,1525811189.649 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-05-08T20:26:29.650Z,1525811189.650 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;
2018-05-08T20:26:29.987Z,1525811189.987 [MissionManager](IMPORTANT): Started mission Startup
2018-05-08T20:26:29.987Z,1525811189.987 [Startup] Running Loop=1
2018-05-08T20:26:29.988Z,1525811189.988 [Startup](DEBUG): Aggregate::initialize Startup
2018-05-08T20:26:29.988Z,1525811189.988 [Startup:A.GoToSurface] Running Loop=1
2018-05-08T20:26:29.988Z,1525811189.988 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-08T20:26:29.988Z,1525811189.988 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-08T20:26:29.989Z,1525811189.989 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-08T20:26:29.989Z,1525811189.989 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-08T20:26:29.990Z,1525811189.990 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-08T20:26:29.990Z,1525811189.990 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-08T20:26:30.004Z,1525811190.004 [Startup:StartupSatComms] Running Loop=1
2018-05-08T20:26:30.004Z,1525811190.004 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-05-08T20:26:30.004Z,1525811190.004 [Startup:StartupSatComms:A] Running Loop=1
2018-05-08T20:26:30.404Z,1525811190.404 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-05-08T20:26:59.512Z,1525811219.512 [CommandLine](IMPORTANT): got command report mod platform_mass_position
2018-05-08T20:26:59.626Z,1525811219.626 [Reporter](INFO): platform_mass_position -0.000764 m
2018-05-08T20:27:30.017Z,1525811250.017 [Startup:StartupSatComms:A](INFO): Timed out from 2018-05-08T20:26:30.0Z
2018-05-08T20:27:30.017Z,1525811250.017 [Startup:StartupSatComms:A] Stopped
2018-05-08T20:27:30.018Z,1525811250.018 [Startup:StartupSatComms:B] Running Loop=1
2018-05-08T20:27:30.419Z,1525811250.419 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-08T20:27:35.807Z,1525811255.807 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180508T032310/Courier0008.lzma
2018-05-08T20:27:36.599Z,1525811256.599 [DataOverHttps](INFO): Moved sent file to Logs/20180508T032310/Courier0008.lzma.bak
2018-05-08T20:27:36.599Z,1525811256.599 [DataOverHttps](INFO): SBD MOMSN=8274688
2018-05-08T20:27:45.831Z,1525811265.831 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180508T202500/Courier0000.lzma
2018-05-08T20:27:46.619Z,1525811266.619 [DataOverHttps](INFO): Moved sent file to Logs/20180508T202500/Courier0000.lzma.bak
2018-05-08T20:27:46.619Z,1525811266.619 [DataOverHttps](INFO): SBD MOMSN=8274690
2018-05-08T20:27:55.858Z,1525811275.858 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20180508T032310/Express0009.lzma
2018-05-08T20:27:56.655Z,1525811276.655 [DataOverHttps](INFO): Moved sent file to Logs/20180508T032310/Express0009.lzma.bak
2018-05-08T20:27:56.655Z,1525811276.655 [DataOverHttps](INFO): SBD MOMSN=8274695
2018-05-08T20:28:05.879Z,1525811285.879 [DataOverHttps](INFO): Sending 651 bytes from file Logs/20180508T202500/Express0001.lzma
2018-05-08T20:28:06.675Z,1525811286.675 [DataOverHttps](INFO): Moved sent file to Logs/20180508T202500/Express0001.lzma.bak
2018-05-08T20:28:06.675Z,1525811286.675 [DataOverHttps](INFO): SBD MOMSN=8274702
2018-05-08T20:28:07.327Z,1525811287.327 [Startup:StartupSatComms:B] Stopped
2018-05-08T20:28:07.327Z,1525811287.327 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-05-08T20:28:07.327Z,1525811287.327 [Startup:StartupSatComms] Stopped
2018-05-08T20:28:07.327Z,1525811287.327 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-05-08T20:28:07.328Z,1525811287.328 [Startup](INFO): Completed Startup
2018-05-08T20:28:07.328Z,1525811287.328 [MissionManager](INFO): Startup is completed.
2018-05-08T20:28:07.328Z,1525811287.328 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-08T20:28:07.328Z,1525811287.328 [Startup] Stopped
2018-05-08T20:28:07.328Z,1525811287.328 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-05-08T20:28:07.328Z,1525811287.328 [Startup:A.GoToSurface] Stopped
2018-05-08T20:28:07.328Z,1525811287.328 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-08T20:28:07.521Z,1525811287.521 [MissionManager](IMPORTANT): Started mission Default
2018-05-08T20:28:07.522Z,1525811287.522 [Default] Running Loop=1
2018-05-08T20:28:07.522Z,1525811287.522 [Default](DEBUG): Aggregate::initialize Default
2018-05-08T20:28:07.522Z,1525811287.522 [Default:B.GoToSurface] Running Loop=1
2018-05-08T20:28:07.522Z,1525811287.522 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-08T20:28:07.522Z,1525811287.522 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-08T20:28:07.523Z,1525811287.523 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-08T20:28:07.523Z,1525811287.523 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-08T20:28:07.523Z,1525811287.523 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-08T20:28:07.524Z,1525811287.524 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-08T20:28:07.524Z,1525811287.524 [Default:A.Wait] Running Loop=1
2018-05-08T20:28:07.524Z,1525811287.524 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-05-08T20:28:10.636Z,1525811290.636 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:28:10.636Z,1525811290.636 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-05-08T20:28:10.636Z,1525811290.636 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-08T20:28:10.638Z,1525811290.638 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:28:10.638Z,1525811290.638 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-08T20:28:10.638Z,1525811290.638 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-08T20:28:10.649Z,1525811290.649 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-08T20:28:10.650Z,1525811290.650 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-08T20:28:10.824Z,1525811290.824 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-08T20:28:10.825Z,1525811290.825 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-05-08T20:28:10.825Z,1525811290.825 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-08T20:28:10.825Z,1525811290.825 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-08T20:28:11.120Z,1525811291.120 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:28:11.121Z,1525811291.121 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:28:11.121Z,1525811291.121 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:28:11.122Z,1525811291.122 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:28:11.122Z,1525811291.122 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:28:11.123Z,1525811291.123 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:28:20.714Z,1525811300.714 [Default:A.Wait](INFO): Done Waiting.
2018-05-08T20:28:20.714Z,1525811300.714 [Default:A.Wait] Stopped
2018-05-08T20:28:20.715Z,1525811300.715 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-05-08T20:28:21.115Z,1525811301.115 [Default:CheckIn] Running Loop=1
2018-05-08T20:28:21.115Z,1525811301.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-08T20:28:21.116Z,1525811301.116 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-08T20:28:21.610Z,1525811301.610 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-08T20:29:07.918Z,1525811347.918 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -3.000000 centimeter
2018-05-08T20:29:07.920Z,1525811347.920 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2018-05-08T20:29:07.955Z,1525811347.955 [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,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-08T20:29:12.815Z,1525811352.815 [Reporter](INFO): platform_mass_position -0.000846 m
2018-05-08T20:29:13.184Z,1525811353.184 [Reporter](INFO): platform_mass_position -0.001133 m
2018-05-08T20:29:13.597Z,1525811353.597 [Reporter](INFO): platform_mass_position -0.001456 m
2018-05-08T20:29:14.005Z,1525811354.005 [Reporter](INFO): platform_mass_position -0.001779 m
2018-05-08T20:29:14.401Z,1525811354.401 [Reporter](INFO): platform_mass_position -0.002089 m
2018-05-08T20:29:14.797Z,1525811354.797 [Reporter](INFO): platform_mass_position -0.002400 m
2018-05-08T20:29:15.202Z,1525811355.202 [Reporter](INFO): platform_mass_position -0.002716 m
2018-05-08T20:29:15.609Z,1525811355.609 [Reporter](INFO): platform_mass_position -0.003036 m
2018-05-08T20:29:16.013Z,1525811356.013 [Reporter](INFO): platform_mass_position -0.003346 m
2018-05-08T20:29:16.385Z,1525811356.385 [Reporter](INFO): platform_mass_position -0.003644 m
2018-05-08T20:29:16.821Z,1525811356.821 [Reporter](INFO): platform_mass_position -0.003986 m
2018-05-08T20:29:17.209Z,1525811357.209 [Reporter](INFO): platform_mass_position -0.004290 m
2018-05-08T20:29:17.605Z,1525811357.605 [Reporter](INFO): platform_mass_position -0.004600 m
2018-05-08T20:29:17.988Z,1525811357.988 [Reporter](INFO): platform_mass_position -0.004895 m
2018-05-08T20:29:18.400Z,1525811358.400 [Reporter](INFO): platform_mass_position -0.005218 m
2018-05-08T20:29:18.797Z,1525811358.797 [Reporter](INFO): platform_mass_position -0.005535 m
2018-05-08T20:29:19.221Z,1525811359.221 [Reporter](INFO): platform_mass_position -0.005868 m
2018-05-08T20:29:19.605Z,1525811359.605 [Reporter](INFO): platform_mass_position -0.006168 m
2018-05-08T20:29:20.006Z,1525811360.006 [Reporter](INFO): platform_mass_position -0.006478 m
2018-05-08T20:29:20.385Z,1525811360.385 [Reporter](INFO): platform_mass_position -0.006779 m
2018-05-08T20:29:20.785Z,1525811360.785 [Reporter](INFO): platform_mass_position -0.007093 m
2018-05-08T20:29:21.189Z,1525811361.189 [Reporter](INFO): platform_mass_position -0.007406 m
2018-05-08T20:29:21.597Z,1525811361.597 [Reporter](INFO): platform_mass_position -0.007729 m
2018-05-08T20:29:22.013Z,1525811362.013 [Reporter](INFO): platform_mass_position -0.008056 m
2018-05-08T20:29:22.400Z,1525811362.400 [Reporter](INFO): platform_mass_position -0.008358 m
2018-05-08T20:29:22.801Z,1525811362.801 [Reporter](INFO): platform_mass_position -0.008673 m
2018-05-08T20:29:23.181Z,1525811363.181 [Reporter](INFO): platform_mass_position -0.008971 m
2018-05-08T20:29:23.601Z,1525811363.601 [Reporter](INFO): platform_mass_position -0.009297 m
2018-05-08T20:29:24.017Z,1525811364.017 [Reporter](INFO): platform_mass_position -0.009626 m
2018-05-08T20:29:24.401Z,1525811364.401 [Reporter](INFO): platform_mass_position -0.009927 m
2018-05-08T20:29:24.801Z,1525811364.801 [Reporter](INFO): platform_mass_position -0.010241 m
2018-05-08T20:29:25.193Z,1525811365.193 [Reporter](INFO): platform_mass_position -0.010548 m
2018-05-08T20:29:25.585Z,1525811365.585 [Reporter](INFO): platform_mass_position -0.010855 m
2018-05-08T20:29:25.977Z,1525811365.977 [Reporter](INFO): platform_mass_position -0.011162 m
2018-05-08T20:29:26.420Z,1525811366.420 [Reporter](INFO): platform_mass_position -0.011507 m
2018-05-08T20:29:26.813Z,1525811366.813 [Reporter](INFO): platform_mass_position -0.011818 m
2018-05-08T20:29:27.209Z,1525811367.209 [Reporter](INFO): platform_mass_position -0.012128 m
2018-05-08T20:29:27.613Z,1525811367.613 [Reporter](INFO): platform_mass_position -0.012436 m
2018-05-08T20:29:27.982Z,1525811367.982 [Reporter](INFO): platform_mass_position -0.012733 m
2018-05-08T20:29:28.389Z,1525811368.389 [Reporter](INFO): platform_mass_position -0.013053 m
2018-05-08T20:29:28.793Z,1525811368.793 [Reporter](INFO): platform_mass_position -0.013370 m
2018-05-08T20:29:29.185Z,1525811369.185 [Reporter](INFO): platform_mass_position -0.013673 m
2018-05-08T20:29:29.601Z,1525811369.601 [Reporter](INFO): platform_mass_position -0.014000 m
2018-05-08T20:29:30.001Z,1525811370.001 [Reporter](INFO): platform_mass_position -0.014307 m
2018-05-08T20:29:30.406Z,1525811370.406 [Reporter](INFO): platform_mass_position -0.014633 m
2018-05-08T20:29:30.857Z,1525811370.857 [Reporter](INFO): platform_mass_position -0.014981 m
2018-05-08T20:29:31.205Z,1525811371.205 [Reporter](INFO): platform_mass_position -0.015257 m
2018-05-08T20:29:31.640Z,1525811371.640 [Reporter](INFO): platform_mass_position -0.015592 m
2018-05-08T20:29:32.112Z,1525811372.112 [Reporter](INFO): platform_mass_position -0.015962 m
2018-05-08T20:29:32.445Z,1525811372.445 [Reporter](INFO): platform_mass_position -0.016232 m
2018-05-08T20:29:32.825Z,1525811372.825 [Reporter](INFO): platform_mass_position -0.016528 m
2018-05-08T20:29:33.100Z,1525811373.100 [CommandLine](IMPORTANT): got command maintain clear
2018-05-08T20:29:33.227Z,1525811373.227 [Reporter](INFO): platform_mass_position -0.016831 m
2018-05-08T20:29:33.233Z,1525811373.233 [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,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-08T20:29:33.665Z,1525811373.665 [Reporter](INFO): platform_mass_position -0.017157 m
2018-05-08T20:29:34.024Z,1525811374.024 [Reporter](INFO): platform_mass_position -0.017210 m
2018-05-08T20:29:34.405Z,1525811374.405 [Reporter](INFO): platform_mass_position -0.016935 m
2018-05-08T20:29:34.785Z,1525811374.785 [Reporter](INFO): platform_mass_position -0.016637 m
2018-05-08T20:29:35.193Z,1525811375.193 [Reporter](INFO): platform_mass_position -0.016320 m
2018-05-08T20:29:35.601Z,1525811375.601 [Reporter](INFO): platform_mass_position -0.016000 m
2018-05-08T20:29:36.017Z,1525811376.017 [Reporter](INFO): platform_mass_position -0.015671 m
2018-05-08T20:29:36.389Z,1525811376.389 [Reporter](INFO): platform_mass_position -0.015380 m
2018-05-08T20:29:36.797Z,1525811376.797 [Reporter](INFO): platform_mass_position -0.015063 m
2018-05-08T20:29:37.208Z,1525811377.208 [Reporter](INFO): platform_mass_position -0.014740 m
2018-05-08T20:29:37.601Z,1525811377.601 [Reporter](INFO): platform_mass_position -0.014430 m
2018-05-08T20:29:38.005Z,1525811378.005 [Reporter](INFO): platform_mass_position -0.014116 m
2018-05-08T20:29:38.405Z,1525811378.405 [Reporter](INFO): platform_mass_position -0.013799 m
2018-05-08T20:29:38.797Z,1525811378.797 [Reporter](INFO): platform_mass_position -0.013492 m
2018-05-08T20:29:39.213Z,1525811379.213 [Reporter](INFO): platform_mass_position -0.013169 m
2018-05-08T20:29:39.597Z,1525811379.597 [Reporter](INFO): platform_mass_position -0.012865 m
2018-05-08T20:29:40.005Z,1525811380.005 [Reporter](INFO): platform_mass_position -0.012546 m
2018-05-08T20:29:40.413Z,1525811380.413 [Reporter](INFO): platform_mass_position -0.012229 m
2018-05-08T20:29:40.801Z,1525811380.801 [Reporter](INFO): platform_mass_position -0.011921 m
2018-05-08T20:29:41.201Z,1525811381.201 [Reporter](INFO): platform_mass_position -0.011608 m
2018-05-08T20:29:41.601Z,1525811381.601 [Reporter](INFO): platform_mass_position -0.011295 m
2018-05-08T20:29:42.013Z,1525811382.013 [Reporter](INFO): platform_mass_position -0.010975 m
2018-05-08T20:29:42.405Z,1525811382.405 [Reporter](INFO): platform_mass_position -0.010664 m
2018-05-08T20:29:42.809Z,1525811382.809 [Reporter](INFO): platform_mass_position -0.010348 m
2018-05-08T20:29:43.197Z,1525811383.197 [Reporter](INFO): platform_mass_position -0.010043 m
2018-05-08T20:29:43.589Z,1525811383.589 [Reporter](INFO): platform_mass_position -0.009736 m
2018-05-08T20:29:43.986Z,1525811383.986 [Reporter](INFO): platform_mass_position -0.009426 m
2018-05-08T20:29:44.405Z,1525811384.405 [Reporter](INFO): platform_mass_position -0.009100 m
2018-05-08T20:29:44.789Z,1525811384.789 [Reporter](INFO): platform_mass_position -0.008796 m
2018-05-08T20:29:45.193Z,1525811385.193 [Reporter](INFO): platform_mass_position -0.008479 m
2018-05-08T20:29:45.600Z,1525811385.600 [Reporter](INFO): platform_mass_position -0.008161 m
2018-05-08T20:29:45.985Z,1525811385.985 [Reporter](INFO): platform_mass_position -0.007862 m
2018-05-08T20:29:46.453Z,1525811386.453 [Reporter](INFO): platform_mass_position -0.007492 m
2018-05-08T20:29:46.859Z,1525811386.859 [Reporter](INFO): platform_mass_position -0.007181 m
2018-05-08T20:29:47.209Z,1525811387.209 [Reporter](INFO): platform_mass_position -0.006899 m
2018-05-08T20:29:47.593Z,1525811387.593 [Reporter](INFO): platform_mass_position -0.006598 m
2018-05-08T20:29:48.009Z,1525811388.009 [Reporter](INFO): platform_mass_position -0.006272 m
2018-05-08T20:29:48.401Z,1525811388.401 [Reporter](INFO): platform_mass_position -0.005968 m
2018-05-08T20:29:48.789Z,1525811388.789 [Reporter](INFO): platform_mass_position -0.005662 m
2018-05-08T20:29:49.189Z,1525811389.189 [Reporter](INFO): platform_mass_position -0.005347 m
2018-05-08T20:29:49.589Z,1525811389.589 [Reporter](INFO): platform_mass_position -0.005033 m
2018-05-08T20:29:50.037Z,1525811390.037 [Reporter](INFO): platform_mass_position -0.004682 m
2018-05-08T20:29:50.429Z,1525811390.429 [Reporter](INFO): platform_mass_position -0.004375 m
2018-05-08T20:29:50.821Z,1525811390.821 [Reporter](INFO): platform_mass_position -0.004068 m
2018-05-08T20:29:51.285Z,1525811391.285 [Reporter](INFO): platform_mass_position -0.003704 m
2018-05-08T20:29:51.649Z,1525811391.649 [Reporter](INFO): platform_mass_position -0.003419 m
2018-05-08T20:29:51.985Z,1525811391.985 [Reporter](INFO): platform_mass_position -0.003158 m
2018-05-08T20:29:52.497Z,1525811392.497 [Reporter](INFO): platform_mass_position -0.002754 m
2018-05-08T20:29:52.901Z,1525811392.901 [Reporter](INFO): platform_mass_position -0.002438 m
2018-05-08T20:29:53.229Z,1525811393.229 [Reporter](INFO): platform_mass_position -0.002180 m
2018-05-08T20:29:53.633Z,1525811393.633 [Reporter](INFO): platform_mass_position -0.001873 m
2018-05-08T20:29:54.029Z,1525811394.029 [Reporter](INFO): platform_mass_position -0.001553 m
2018-05-08T20:29:54.405Z,1525811394.405 [Reporter](INFO): platform_mass_position -0.001259 m
2018-05-08T20:29:54.797Z,1525811394.797 [Reporter](INFO): platform_mass_position -0.000958 m
2018-05-08T20:29:55.181Z,1525811395.181 [Reporter](INFO): platform_mass_position -0.000750 m
2018-05-08T20:30:12.620Z,1525811412.620 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2018-05-08T20:30:12.622Z,1525811412.622 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2018-05-08T20:30:12.937Z,1525811412.937 [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,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-08T20:30:43.461Z,1525811443.461 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 500.000000 revolution_per_minute
2018-05-08T20:30:52.881Z,1525811452.881 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-05-08T20:31:03.990Z,1525811463.990 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 600.000000 revolution_per_minute
2018-05-08T20:31:06.955Z,1525811466.955 [ThrusterServo](FAULT): Overload Error
2018-05-08T20:31:06.955Z,1525811466.955 [ThrusterServo] Hardware Fault, FailCount= 1
2018-05-08T20:31:06.955Z,1525811466.955 [ThrusterServo](ERROR): Hardware Fault
2018-05-08T20:31:06.966Z,1525811466.966 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2018-05-08T20:31:07.340Z,1525811467.340 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-08T20:31:07.340Z,1525811467.340 [ThrusterServo](INFO): Powering down
2018-05-08T20:31:08.143Z,1525811468.143 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-08T20:31:08.259Z,1525811468.259 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-08T20:31:08.263Z,1525811468.263 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-05-08T20:31:08.263Z,1525811468.263 [ThrusterServo] No Fault, FailCount= 1
2018-05-08T20:31:11.243Z,1525811471.243 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:31:11.243Z,1525811471.243 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-05-08T20:31:11.243Z,1525811471.243 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-08T20:31:11.245Z,1525811471.245 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:31:11.245Z,1525811471.245 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-08T20:31:11.245Z,1525811471.245 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-08T20:31:11.378Z,1525811471.378 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-08T20:31:11.378Z,1525811471.378 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-08T20:31:11.516Z,1525811471.516 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-08T20:31:11.517Z,1525811471.517 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-05-08T20:31:11.517Z,1525811471.517 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-08T20:31:11.517Z,1525811471.517 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-08T20:31:11.850Z,1525811471.850 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:31:11.850Z,1525811471.850 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:31:11.851Z,1525811471.851 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:31:11.851Z,1525811471.851 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:31:11.852Z,1525811471.852 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:31:11.852Z,1525811471.852 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:31:17.068Z,1525811477.068 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 500.000000 revolution_per_minute
2018-05-08T20:31:23.516Z,1525811483.516 [CommandLine](IMPORTANT): got command failComponent
2018-05-08T20:31:23.516Z,1525811483.516 [CommandLine](IMPORTANT): Failed components:
2018-05-08T20:31:23.516Z,1525811483.516 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-05-08T20:31:36.921Z,1525811496.921 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2018-05-08T20:33:21.490Z,1525811601.490 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-08T20:28:21.1Z
2018-05-08T20:33:21.490Z,1525811601.490 [Default:CheckIn:Read_GPS] Stopped
2018-05-08T20:33:21.490Z,1525811601.490 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-08T20:33:21.890Z,1525811601.890 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-08T20:33:27.161Z,1525811607.161 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180508T202500/Courier0004.lzma
2018-05-08T20:33:27.955Z,1525811607.955 [DataOverHttps](INFO): Moved sent file to Logs/20180508T202500/Courier0004.lzma.bak
2018-05-08T20:33:27.955Z,1525811607.955 [DataOverHttps](INFO): SBD MOMSN=8274717
2018-05-08T20:33:37.820Z,1525811617.820 [DataOverHttps](INFO): Sending 417 bytes from file Logs/20180508T202500/Express0005.lzma
2018-05-08T20:33:38.609Z,1525811618.609 [DataOverHttps](INFO): Moved sent file to Logs/20180508T202500/Express0005.lzma.bak
2018-05-08T20:33:38.609Z,1525811618.609 [DataOverHttps](INFO): SBD MOMSN=8274719
2018-05-08T20:33:39.227Z,1525811619.227 [Default:CheckIn:Read_Iridium] Stopped
2018-05-08T20:33:39.227Z,1525811619.227 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-08T20:33:39.227Z,1525811619.227 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-08T20:33:42.239Z,1525811622.239 [CommandLine](IMPORTANT): got command maintain clear
2018-05-08T20:33:42.688Z,1525811622.688 [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,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-08T20:33:51.971Z,1525811631.971 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadC2
2018-05-08T20:34:11.933Z,1525811651.933 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:34:11.934Z,1525811651.934 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-05-08T20:34:11.934Z,1525811651.934 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-08T20:34:11.966Z,1525811651.966 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:34:11.967Z,1525811651.967 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-08T20:34:11.967Z,1525811651.967 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-08T20:34:12.002Z,1525811652.002 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-08T20:34:12.002Z,1525811652.002 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-08T20:34:12.363Z,1525811652.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-08T20:34:12.363Z,1525811652.363 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-05-08T20:34:12.363Z,1525811652.363 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-08T20:34:12.364Z,1525811652.364 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-08T20:34:12.743Z,1525811652.743 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:34:12.743Z,1525811652.743 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:34:12.743Z,1525811652.743 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:34:12.744Z,1525811652.744 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:34:12.744Z,1525811652.744 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:34:12.744Z,1525811652.744 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:34:29.776Z,1525811669.776 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadC6
2018-05-08T20:35:10.756Z,1525811710.756 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-08T20:35:23.071Z,1525811723.071 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadC1
2018-05-08T20:35:52.228Z,1525811752.228 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-08T20:35:52.228Z,1525811752.228 [NAL9602] Data Fault, FailCount= 1
2018-05-08T20:35:52.228Z,1525811752.228 [NAL9602](ERROR): Data Fault
2018-05-08T20:35:52.323Z,1525811752.323 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-08T20:35:52.624Z,1525811752.624 [NAL9602](INFO): Powering down
2018-05-08T20:35:53.487Z,1525811753.487 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-08T20:35:53.487Z,1525811753.487 [NAL9602] No Fault, FailCount= 1
2018-05-08T20:36:14.199Z,1525811774.199 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB4
2018-05-08T20:36:23.024Z,1525811783.024 [NAL9602](INFO): Powering up NAL9602
2018-05-08T20:36:34.224Z,1525811794.224 [NAL9602](INFO): NAL9602 initialized
2018-05-08T20:37:10.257Z,1525811830.257 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB3
2018-05-08T20:37:13.062Z,1525811833.062 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:37:13.062Z,1525811833.062 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-05-08T20:37:13.062Z,1525811833.062 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-08T20:37:13.064Z,1525811833.064 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:37:13.064Z,1525811833.064 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-05-08T20:37:13.064Z,1525811833.064 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-08T20:37:13.077Z,1525811833.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-08T20:37:13.077Z,1525811833.077 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-08T20:37:13.488Z,1525811833.488 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-08T20:37:13.488Z,1525811833.488 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-05-08T20:37:13.489Z,1525811833.489 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-08T20:37:13.489Z,1525811833.489 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-05-08T20:37:13.861Z,1525811833.861 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:37:13.861Z,1525811833.861 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:37:13.862Z,1525811833.862 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:37:13.862Z,1525811833.862 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:37:13.867Z,1525811833.867 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:37:13.867Z,1525811833.867 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:38:39.496Z,1525811919.496 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-08T20:38:39.496Z,1525811919.496 [Default:CheckIn:C.Wait] Stopped
2018-05-08T20:38:39.496Z,1525811919.496 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-08T20:38:39.496Z,1525811919.496 [Default:CheckIn:D] Running Loop=1
2018-05-08T20:38:39.904Z,1525811919.904 [Default:CheckIn:D] Stopped
2018-05-08T20:38:39.905Z,1525811919.905 [Default:CheckIn:E] Running Loop=1
2018-05-08T20:38:40.308Z,1525811920.308 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.539682 min
2018-05-08T20:38:40.310Z,1525811920.310 [Default:CheckIn:E] Stopped
2018-05-08T20:38:40.310Z,1525811920.310 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-08T20:38:40.310Z,1525811920.310 [Default:CheckIn] Stopped
2018-05-08T20:38:40.311Z,1525811920.311 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-08T20:38:40.311Z,1525811920.311 [Default:CheckIn](INFO): Running loop #2
2018-05-08T20:38:40.311Z,1525811920.311 [Default:CheckIn] Running Loop=2
2018-05-08T20:38:40.311Z,1525811920.311 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-08T20:38:40.311Z,1525811920.311 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-08T20:40:11.985Z,1525812011.985 [CBIT](INFO): Clearing failed state for component DropWeight
2018-05-08T20:40:11.985Z,1525812011.985 [DropWeight] No Fault, FailCount= 1
2018-05-08T20:40:13.953Z,1525812013.953 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:40:13.953Z,1525812013.953 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-05-08T20:40:13.953Z,1525812013.953 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-08T20:40:13.955Z,1525812013.955 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:40:13.955Z,1525812013.955 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-05-08T20:40:13.955Z,1525812013.955 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-08T20:40:14.008Z,1525812014.008 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-08T20:40:14.008Z,1525812014.008 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-08T20:40:14.387Z,1525812014.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-08T20:40:14.387Z,1525812014.387 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-05-08T20:40:14.388Z,1525812014.388 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-08T20:40:14.388Z,1525812014.388 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-05-08T20:40:14.770Z,1525812014.770 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:40:14.771Z,1525812014.771 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:40:14.771Z,1525812014.771 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:40:14.772Z,1525812014.772 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:40:14.772Z,1525812014.772 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:40:14.772Z,1525812014.772 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:43:15.154Z,1525812195.154 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:43:15.154Z,1525812195.154 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-05-08T20:43:15.154Z,1525812195.154 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-08T20:43:15.156Z,1525812195.156 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:43:15.156Z,1525812195.156 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2018-05-08T20:43:15.156Z,1525812195.156 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-08T20:43:15.168Z,1525812195.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-08T20:43:15.168Z,1525812195.168 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-08T20:43:15.551Z,1525812195.551 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-08T20:43:15.551Z,1525812195.551 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-05-08T20:43:15.551Z,1525812195.551 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-08T20:43:15.551Z,1525812195.551 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2018-05-08T20:43:15.948Z,1525812195.948 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:43:15.948Z,1525812195.948 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:43:15.949Z,1525812195.949 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:43:15.949Z,1525812195.949 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:43:15.949Z,1525812195.949 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:43:15.950Z,1525812195.950 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:43:40.355Z,1525812220.355 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-08T20:38:40.3Z
2018-05-08T20:43:40.355Z,1525812220.355 [Default:CheckIn:Read_GPS] Stopped
2018-05-08T20:43:40.355Z,1525812220.355 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-08T20:44:06.516Z,1525812246.516 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180508T202500/Courier0008.lzma
2018-05-08T20:44:07.299Z,1525812247.299 [DataOverHttps](INFO): Moved sent file to Logs/20180508T202500/Courier0008.lzma.bak
2018-05-08T20:44:07.299Z,1525812247.299 [DataOverHttps](INFO): SBD MOMSN=8274734
2018-05-08T20:44:18.270Z,1525812258.270 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20180508T202500/Express0009.lzma
2018-05-08T20:44:19.067Z,1525812259.067 [DataOverHttps](INFO): Moved sent file to Logs/20180508T202500/Express0009.lzma.bak
2018-05-08T20:44:19.067Z,1525812259.067 [DataOverHttps](INFO): SBD MOMSN=8274736
2018-05-08T20:44:19.707Z,1525812259.707 [Default:CheckIn:Read_Iridium] Stopped
2018-05-08T20:44:19.707Z,1525812259.707 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-08T20:44:19.707Z,1525812259.707 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-08T20:44:27.079Z,1525812267.079 [CommandLine](IMPORTANT): got command ibit
2018-05-08T20:44:27.153Z,1525812267.153 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-05-08T20:44:27.153Z,1525812267.153 [IBIT](IMPORTANT): Beginning control surface checks.
2018-05-08T20:44:27.160Z,1525812267.160 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-08T20:44:37.970Z,1525812277.970 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.014661
CHAN A1 (24V): 4.657929
CHAN A2 (12V): -0.002229
CHAN A3 (5V): -0.001869
CHAN B0 (3.3V): -0.000198
CHAN B1 (3.15aV): 0.000237
CHAN B2 (3.15bV): 0.000481
CHAN B3 (GND): 0.000273
OPEN: -0.000292
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-08T20:45:10.308Z,1525812310.308 [CommandLine](IMPORTANT): got command ! echo 0 > /dev/loadC6
2018-05-08T20:45:10.757Z,1525812310.757 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-08T20:45:46.281Z,1525812346.281 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-08T20:45:46.281Z,1525812346.281 [BPC1] Communications Fault, FailCount= 1
2018-05-08T20:45:46.281Z,1525812346.281 [BPC1](ERROR): Communications Fault
2018-05-08T20:45:46.327Z,1525812346.327 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-08T20:45:47.518Z,1525812347.518 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-08T20:45:47.518Z,1525812347.518 [BPC1] No Fault, FailCount= 1
2018-05-08T20:46:16.313Z,1525812376.313 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:46:16.313Z,1525812376.313 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-05-08T20:46:16.314Z,1525812376.314 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-08T20:46:16.315Z,1525812376.315 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:46:16.316Z,1525812376.316 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2018-05-08T20:46:16.316Z,1525812376.316 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-08T20:46:16.350Z,1525812376.350 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-08T20:46:16.351Z,1525812376.351 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-08T20:46:16.715Z,1525812376.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-08T20:46:16.715Z,1525812376.715 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-05-08T20:46:16.716Z,1525812376.716 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-08T20:46:16.716Z,1525812376.716 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2018-05-08T20:46:17.125Z,1525812377.125 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:46:17.125Z,1525812377.125 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:46:17.125Z,1525812377.125 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:46:17.126Z,1525812377.126 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:46:17.126Z,1525812377.126 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:46:17.126Z,1525812377.126 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:46:17.921Z,1525812377.921 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2018-05-08T20:46:18.365Z,1525812378.365 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2018-05-08T20:46:18.700Z,1525812378.700 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-05-08T20:46:18.701Z,1525812378.701 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-05-08T20:46:18.701Z,1525812378.701 [IBIT](IMPORTANT): Pressure:9.518899 PSI
2018-05-08T20:46:18.701Z,1525812378.701 [IBIT](IMPORTANT): Humidity:14.552557 %
2018-05-08T20:46:19.106Z,1525812379.106 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-05-08T20:46:19.107Z,1525812379.107 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2018-05-08T20:46:19.107Z,1525812379.107 [IBIT](IMPORTANT): massDefault: -0.075000 cm
2018-05-08T20:46:19.107Z,1525812379.107 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2018-05-08T20:46:19.108Z,1525812379.108 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-05-08T20:46:19.108Z,1525812379.108 [IBIT](IMPORTANT): IBIT FAILED
2018-05-08T20:46:37.028Z,1525812397.028 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-08T20:46:37.028Z,1525812397.028 [NAL9602] Data Fault, FailCount= 2
2018-05-08T20:46:37.028Z,1525812397.028 [NAL9602](ERROR): Data Fault
2018-05-08T20:46:37.122Z,1525812397.122 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-08T20:46:37.428Z,1525812397.428 [NAL9602](INFO): Powering down
2018-05-08T20:46:38.285Z,1525812398.285 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-08T20:46:38.285Z,1525812398.285 [NAL9602] No Fault, FailCount= 2
2018-05-08T20:46:57.053Z,1525812417.053 [BPC1](ERROR): Failed to parse Bank B battery data
2018-05-08T20:47:07.828Z,1525812427.828 [NAL9602](INFO): Powering up NAL9602
2018-05-08T20:47:19.028Z,1525812439.028 [NAL9602](INFO): NAL9602 initialized
2018-05-08T20:49:17.445Z,1525812557.445 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:49:17.445Z,1525812557.445 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-05-08T20:49:17.445Z,1525812557.445 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-08T20:49:17.448Z,1525812557.448 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-08T20:49:17.448Z,1525812557.448 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2018-05-08T20:49:17.448Z,1525812557.448 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-08T20:49:17.460Z,1525812557.460 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-08T20:49:17.460Z,1525812557.460 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-08T20:49:17.879Z,1525812557.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-08T20:49:17.880Z,1525812557.880 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-05-08T20:49:17.880Z,1525812557.880 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-08T20:49:17.880Z,1525812557.880 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2018-05-08T20:49:18.263Z,1525812558.263 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-08T20:49:18.263Z,1525812558.263 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:49:18.264Z,1525812558.264 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:49:18.264Z,1525812558.264 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-08T20:49:18.265Z,1525812558.265 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-08T20:49:18.265Z,1525812558.265 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-08T20:49:20.265Z,1525812560.265 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-08T20:49:20.266Z,1525812560.266 [Default:CheckIn:C.Wait] Stopped
2018-05-08T20:49:20.266Z,1525812560.266 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-08T20:49:20.266Z,1525812560.266 [Default:CheckIn:D] Running Loop=1
2018-05-08T20:49:20.676Z,1525812560.676 [Default:CheckIn:D] Stopped
2018-05-08T20:49:20.676Z,1525812560.676 [Default:CheckIn:E] Running Loop=1
2018-05-08T20:49:21.072Z,1525812561.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.219230 min
2018-05-08T20:49:21.072Z,1525812561.072 [Default:CheckIn:E] Stopped
2018-05-08T20:49:21.072Z,1525812561.072 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-08T20:49:21.072Z,1525812561.072 [Default:CheckIn] Stopped
2018-05-08T20:49:21.072Z,1525812561.072 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-08T20:49:21.072Z,1525812561.072 [Default:CheckIn](INFO): Running loop #3
2018-05-08T20:49:21.072Z,1525812561.072 [Default:CheckIn] Running Loop=3
2018-05-08T20:49:21.073Z,1525812561.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-08T20:49:21.073Z,1525812561.073 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-08T20:49:59.592Z,1525812599.592 [CommandLine](IMPORTANT): got command quit
2018-05-08T20:50:00.599Z,1525812600.599 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-08T20:50:00.599Z,1525812600.599 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:00.770Z,1525812600.770 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2018-05-08T20:50:00.771Z,1525812600.771 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:00.771Z,1525812600.771 [CommandLine](INFO): Join timeout helper Thread ID is 7068
2018-05-08T20:50:00.772Z,1525812600.772 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-05-08T20:50:00.772Z,1525812600.772 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:00.772Z,1525812600.772 [NavChartDb](INFO): Join timeout helper Thread ID is 7069
2018-05-08T20:50:00.811Z,1525812600.811 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-08T20:50:00.811Z,1525812600.811 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:00.830Z,1525812600.830 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-05-08T20:50:00.830Z,1525812600.830 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:00.831Z,1525812600.831 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7070
2018-05-08T20:50:00.855Z,1525812600.855 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-08T20:50:00.855Z,1525812600.855 [WetLabsBB2FL](INFO): Powering down
2018-05-08T20:50:00.856Z,1525812600.856 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:00.870Z,1525812600.870 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-05-08T20:50:00.870Z,1525812600.870 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:00.871Z,1525812600.871 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7071
2018-05-08T20:50:00.935Z,1525812600.935 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-08T20:50:01.058Z,1525812601.058 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-08T20:50:01.058Z,1525812601.058 [CTD_Seabird](INFO): Powering down
2018-05-08T20:50:01.061Z,1525812601.061 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.063Z,1525812601.063 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-05-08T20:50:01.063Z,1525812601.063 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.063Z,1525812601.063 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7073
2018-05-08T20:50:01.303Z,1525812601.303 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-08T20:50:01.303Z,1525812601.303 [CTD_NeilBrown](INFO): Powering down
2018-05-08T20:50:01.304Z,1525812601.304 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.319Z,1525812601.319 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-05-08T20:50:01.320Z,1525812601.320 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.320Z,1525812601.320 [Radio_Surface](INFO): Join timeout helper Thread ID is 7074
2018-05-08T20:50:01.323Z,1525812601.323 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-08T20:50:01.323Z,1525812601.323 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.339Z,1525812601.339 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-05-08T20:50:01.339Z,1525812601.339 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.340Z,1525812601.340 [logger](INFO): Join timeout helper Thread ID is 7075
2018-05-08T20:50:01.343Z,1525812601.343 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-08T20:50:01.343Z,1525812601.343 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.359Z,1525812601.359 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-05-08T20:50:01.359Z,1525812601.359 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.359Z,1525812601.359 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-05-08T20:50:01.360Z,1525812601.360 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.360Z,1525812601.360 [controlThread](INFO): Join timeout helper Thread ID is 7076
2018-05-08T20:50:01.415Z,1525812601.415 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-08T20:50:01.415Z,1525812601.415 [controlThread](DEBUG): Uninitializing ControlThread
2018-05-08T20:50:01.416Z,1525812601.416 [NAL9602](INFO): Powering down
2018-05-08T20:50:01.417Z,1525812601.417 [PNI_TCM](INFO): Powering down
2018-05-08T20:50:01.503Z,1525812601.503 [Aanderaa_O2](INFO): Powering down
2018-05-08T20:50:01.505Z,1525812601.505 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-08T20:50:01.507Z,1525812601.507 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-05-08T20:50:01.507Z,1525812601.507 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-05-08T20:50:01.508Z,1525812601.508 [MissionManager](INFO): Uninitializing Mission Default
2018-05-08T20:50:01.508Z,1525812601.508 [Default] Stopped
2018-05-08T20:50:01.508Z,1525812601.508 [Default](DEBUG): Aggregate::uninitialize Default
2018-05-08T20:50:01.508Z,1525812601.508 [Default:B.GoToSurface] Stopped
2018-05-08T20:50:01.508Z,1525812601.508 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-08T20:50:01.508Z,1525812601.508 [Default:CheckIn] Stopped
2018-05-08T20:50:01.509Z,1525812601.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-08T20:50:01.509Z,1525812601.509 [Default:CheckIn:Read_GPS] Stopped
2018-05-08T20:50:01.512Z,1525812601.512 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-05-08T20:50:01.512Z,1525812601.512 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-05-08T20:50:01.513Z,1525812601.513 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-05-08T20:50:01.513Z,1525812601.513 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-05-08T20:50:01.513Z,1525812601.513 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-08T20:50:01.513Z,1525812601.513 [BuoyancyServo](INFO): Powering down
2018-05-08T20:50:01.527Z,1525812601.527 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-05-08T20:50:01.527Z,1525812601.527 [ElevatorServo](INFO): Powering down
2018-05-08T20:50:01.528Z,1525812601.528 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-05-08T20:50:01.528Z,1525812601.528 [MassServo](INFO): Powering down
2018-05-08T20:50:01.528Z,1525812601.528 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-05-08T20:50:01.528Z,1525812601.528 [RudderServo](INFO): Powering down
2018-05-08T20:50:01.529Z,1525812601.529 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-08T20:50:01.529Z,1525812601.529 [ThrusterServo](INFO): Powering down
2018-05-08T20:50:01.530Z,1525812601.530 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-05-08T20:50:01.531Z,1525812601.531 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-05-08T20:50:01.531Z,1525812601.531 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-05-08T20:50:01.532Z,1525812601.532 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.642Z,1525812601.642 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.679Z,1525812601.679 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.682Z,1525812601.682 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.685Z,1525812601.685 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.712Z,1525812601.712 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-08T20:50:01.766Z,1525812601.766 [logger ThreadHandler](INFO): Thread cancelled.