2019-01-17T21:32:34.984Z,1547760754.984 [Supervisor](DEBUG): Initializing supervisor.
2019-01-17T21:32:34.987Z,1547760754.987 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-01-17T21:32:34.987Z,1547760754.987 [SyncHandler](INFO): Protected caller Thread ID is 801
2019-01-17T21:32:34.988Z,1547760754.988 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-01-17T21:32:34.989Z,1547760754.989 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-01-17T21:32:34.989Z,1547760754.989 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 802
2019-01-17T21:32:34.992Z,1547760754.992 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-01-17T21:32:35.003Z,1547760755.003 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-01-17T21:32:35.004Z,1547760755.004 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-01-17T21:32:35.004Z,1547760755.004 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 803
2019-01-17T21:32:35.005Z,1547760755.005 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-01-17T21:32:35.006Z,1547760755.006 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-01-17T21:32:35.006Z,1547760755.006 [logger ThreadHandler](INFO): Protected caller Thread ID is 804
2019-01-17T21:32:35.008Z,1547760755.008 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-01-17T21:32:35.008Z,1547760755.008 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-01-17T21:32:35.012Z,1547760755.012 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-01-17T21:32:35.109Z,1547760755.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-01-17T21:32:35.111Z,1547760755.111 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-01-17T21:32:35.209Z,1547760755.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-01-17T21:32:35.211Z,1547760755.211 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-01-17T21:32:35.389Z,1547760755.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-01-17T21:32:35.389Z,1547760755.389 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-01-17T21:32:35.716Z,1547760755.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-01-17T21:32:35.718Z,1547760755.718 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-01-17T21:32:36.016Z,1547760756.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-01-17T21:32:36.018Z,1547760756.018 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-01-17T21:32:36.509Z,1547760756.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-01-17T21:32:36.510Z,1547760756.510 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-01-17T21:32:36.935Z,1547760756.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-01-17T21:32:36.937Z,1547760756.937 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-01-17T21:32:37.368Z,1547760757.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-01-17T21:32:37.369Z,1547760757.369 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-01-17T21:32:37.450Z,1547760757.450 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-01-17T21:32:37.738Z,1547760757.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-01-17T21:32:37.740Z,1547760757.740 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-01-17T21:32:37.883Z,1547760757.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-01-17T21:32:37.883Z,1547760757.883 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-01-17T21:32:38.084Z,1547760758.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-01-17T21:32:38.084Z,1547760758.084 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-01-17T21:32:38.278Z,1547760758.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-01-17T21:32:38.280Z,1547760758.280 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-01-17T21:32:38.378Z,1547760758.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-01-17T21:32:38.379Z,1547760758.379 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-01-17T21:32:39.114Z,1547760759.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-01-17T21:32:39.116Z,1547760759.116 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-01-17T21:32:39.118Z,1547760759.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-01-17T21:32:39.342Z,1547760759.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-01-17T21:32:39.342Z,1547760759.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-01-17T21:32:39.443Z,1547760759.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-01-17T21:32:39.587Z,1547760759.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-01-17T21:32:39.678Z,1547760759.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-01-17T21:32:39.761Z,1547760759.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-01-17T21:32:39.910Z,1547760759.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-01-17T21:32:40.097Z,1547760760.097 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-01-17T21:32:40.193Z,1547760760.193 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-01-17T21:32:40.285Z,1547760760.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-01-17T21:32:40.381Z,1547760760.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-01-17T21:32:40.463Z,1547760760.463 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-01-17T21:32:40.467Z,1547760760.467 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-01-17T21:32:40.593Z,1547760760.593 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-01-17T21:32:40.595Z,1547760760.595 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-01-17T21:32:40.618Z,1547760760.618 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-01-17T21:32:40.620Z,1547760760.620 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-01-17T21:32:40.676Z,1547760760.676 [DepthRateCalculator] Loaded
2019-01-17T21:32:40.676Z,1547760760.676 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-01-17T21:32:40.682Z,1547760760.682 [PitchRateCalculator] Loaded
2019-01-17T21:32:40.682Z,1547760760.682 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-01-17T21:32:40.698Z,1547760760.698 [SpeedCalculator] Loaded
2019-01-17T21:32:40.698Z,1547760760.698 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-01-17T21:32:40.718Z,1547760760.718 [TempGradientCalculator] Loaded
2019-01-17T21:32:40.719Z,1547760760.719 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-01-17T21:32:40.724Z,1547760760.724 [YawRateCalculator] Loaded
2019-01-17T21:32:40.724Z,1547760760.724 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-01-17T21:32:40.763Z,1547760760.763 [ElevatorOffsetCalculator] Loaded
2019-01-17T21:32:40.763Z,1547760760.763 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-01-17T21:32:40.764Z,1547760760.764 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-01-17T21:32:40.764Z,1547760760.764 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-01-17T21:32:40.805Z,1547760760.805 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-01-17T21:32:40.807Z,1547760760.807 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-01-17T21:32:41.318Z,1547760761.318 [AcousticModem_Benthos_ATM900] Loaded
2019-01-17T21:32:41.319Z,1547760761.319 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-01-17T21:32:41.457Z,1547760761.457 [DataOverHttps] Loaded
2019-01-17T21:32:41.457Z,1547760761.457 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-01-17T21:32:41.470Z,1547760761.470 [Depth_Keller] Loaded
2019-01-17T21:32:41.470Z,1547760761.470 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-01-17T21:32:41.475Z,1547760761.475 [DropWeight] Loaded
2019-01-17T21:32:41.475Z,1547760761.475 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-01-17T21:32:41.571Z,1547760761.571 [NAL9602] Loaded
2019-01-17T21:32:41.571Z,1547760761.571 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-01-17T21:32:41.586Z,1547760761.586 [Onboard] Loaded
2019-01-17T21:32:41.587Z,1547760761.587 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-01-17T21:32:41.596Z,1547760761.596 [Radio_Surface] Loaded
2019-01-17T21:32:41.597Z,1547760761.597 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-01-17T21:32:41.598Z,1547760761.598 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407D54E0
2019-01-17T21:32:41.598Z,1547760761.598 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883
2019-01-17T21:32:41.750Z,1547760761.750 [PNI_TCM] Loaded
2019-01-17T21:32:41.751Z,1547760761.751 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-01-17T21:32:41.809Z,1547760761.809 [Rowe_600LCM] Loaded
2019-01-17T21:32:41.809Z,1547760761.809 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-01-17T21:32:41.810Z,1547760761.810 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408054E0
2019-01-17T21:32:41.811Z,1547760761.811 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 884
2019-01-17T21:32:43.556Z,1547760763.556 [BPC1] Loaded
2019-01-17T21:32:43.556Z,1547760763.556 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-01-17T21:32:43.556Z,1547760763.556 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-01-17T21:32:43.557Z,1547760763.557 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-01-17T21:32:43.715Z,1547760763.715 [SBIT](DEBUG): Construct Startup Built In Test.
2019-01-17T21:32:43.737Z,1547760763.737 [SBIT] Loaded
2019-01-17T21:32:43.738Z,1547760763.738 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-01-17T21:32:43.738Z,1547760763.738 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-01-17T21:32:43.750Z,1547760763.750 [IBIT] Loaded
2019-01-17T21:32:43.750Z,1547760763.750 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-01-17T21:32:43.753Z,1547760763.753 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-01-17T21:32:43.887Z,1547760763.887 [CBIT] Loaded
2019-01-17T21:32:43.887Z,1547760763.887 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-01-17T21:32:43.887Z,1547760763.887 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-01-17T21:32:43.888Z,1547760763.888 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-01-17T21:32:44.196Z,1547760764.196 [Aanderaa_O2] Loaded
2019-01-17T21:32:44.197Z,1547760764.197 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-01-17T21:32:44.206Z,1547760764.206 [CTD_Seabird](DEBUG): add sea_water_density
2019-01-17T21:32:44.206Z,1547760764.206 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-01-17T21:32:44.206Z,1547760764.206 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_density
2019-01-17T21:32:44.211Z,1547760764.211 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-01-17T21:32:44.214Z,1547760764.214 [CTD_Seabird](DEBUG): add depth
2019-01-17T21:32:44.214Z,1547760764.214 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-01-17T21:32:44.214Z,1547760764.214 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter depth
2019-01-17T21:32:44.219Z,1547760764.219 [CTD_Seabird](INFO): created writer for : depth
2019-01-17T21:32:44.220Z,1547760764.220 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-01-17T21:32:44.220Z,1547760764.220 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_pressure
2019-01-17T21:32:44.224Z,1547760764.224 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-01-17T21:32:44.225Z,1547760764.225 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-01-17T21:32:44.225Z,1547760764.225 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_salinity
2019-01-17T21:32:44.230Z,1547760764.230 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-01-17T21:32:44.231Z,1547760764.231 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-01-17T21:32:44.231Z,1547760764.231 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_temperature
2019-01-17T21:32:44.236Z,1547760764.236 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-01-17T21:32:44.236Z,1547760764.236 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-01-17T21:32:44.236Z,1547760764.236 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_electrical_conductivity
2019-01-17T21:32:44.242Z,1547760764.242 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-01-17T21:32:44.242Z,1547760764.242 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-01-17T21:32:44.242Z,1547760764.242 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter speed_of_sound_in_sea_water
2019-01-17T21:32:44.247Z,1547760764.247 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-01-17T21:32:44.274Z,1547760764.274 [CTD_Seabird] Loaded
2019-01-17T21:32:44.274Z,1547760764.274 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-01-17T21:32:44.275Z,1547760764.275 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2019-01-17T21:32:44.275Z,1547760764.275 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 885
2019-01-17T21:32:44.292Z,1547760764.292 [ESPComponent] Loaded
2019-01-17T21:32:44.293Z,1547760764.293 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-01-17T21:32:44.306Z,1547760764.306 [PAR_Licor] Loaded
2019-01-17T21:32:44.306Z,1547760764.306 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-01-17T21:32:44.353Z,1547760764.353 [WetLabsBB2FL] Loaded
2019-01-17T21:32:44.353Z,1547760764.353 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-01-17T21:32:44.354Z,1547760764.354 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2019-01-17T21:32:44.355Z,1547760764.355 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 886
2019-01-17T21:32:44.355Z,1547760764.355 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-01-17T21:32:44.356Z,1547760764.356 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-01-17T21:32:44.630Z,1547760764.630 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-01-17T21:32:44.631Z,1547760764.631 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-01-17T21:32:44.716Z,1547760764.716 [VerticalControl](DEBUG): Construct VerticalControl.
2019-01-17T21:32:44.807Z,1547760764.807 [VerticalControl] Loaded
2019-01-17T21:32:44.807Z,1547760764.807 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-01-17T21:32:44.808Z,1547760764.808 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-01-17T21:32:44.867Z,1547760764.867 [HorizontalControl] Loaded
2019-01-17T21:32:44.868Z,1547760764.868 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-01-17T21:32:44.868Z,1547760764.868 [SpeedControl](DEBUG): Construct SpeedControl.
2019-01-17T21:32:44.873Z,1547760764.873 [SpeedControl] Loaded
2019-01-17T21:32:44.874Z,1547760764.874 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-01-17T21:32:44.874Z,1547760764.874 [LoopControl](DEBUG): Construct LoopControl.
2019-01-17T21:32:44.875Z,1547760764.875 [LoopControl] Loaded
2019-01-17T21:32:44.875Z,1547760764.875 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-01-17T21:32:44.876Z,1547760764.876 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-01-17T21:32:44.876Z,1547760764.876 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-01-17T21:32:44.977Z,1547760764.977 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-01-17T21:32:44.978Z,1547760764.978 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-01-17T21:32:45.094Z,1547760765.094 [BuoyancyServo] Loaded
2019-01-17T21:32:45.094Z,1547760765.094 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-01-17T21:32:45.105Z,1547760765.105 [ElevatorServo] Loaded
2019-01-17T21:32:45.105Z,1547760765.105 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-01-17T21:32:45.116Z,1547760765.116 [MassServo] Loaded
2019-01-17T21:32:45.116Z,1547760765.116 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-01-17T21:32:45.127Z,1547760765.127 [RudderServo] Loaded
2019-01-17T21:32:45.127Z,1547760765.127 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-01-17T21:32:45.138Z,1547760765.138 [ThrusterServo] Loaded
2019-01-17T21:32:45.138Z,1547760765.138 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-01-17T21:32:45.138Z,1547760765.138 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-01-17T21:32:45.139Z,1547760765.139 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-01-17T21:32:45.251Z,1547760765.251 [DeadReckonUsingSpeedCalculator] Loaded
2019-01-17T21:32:45.252Z,1547760765.252 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-01-17T21:32:45.267Z,1547760765.267 [NavChart] Loaded
2019-01-17T21:32:45.267Z,1547760765.267 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-01-17T21:32:45.271Z,1547760765.271 [UniversalFixResidualReporter] Loaded
2019-01-17T21:32:45.272Z,1547760765.272 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-01-17T21:32:45.272Z,1547760765.272 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-01-17T21:32:45.275Z,1547760765.275 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-01-17T21:32:45.276Z,1547760765.276 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-01-17T21:32:45.283Z,1547760765.283 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-01-17T21:32:45.284Z,1547760765.284 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A8F4E0
2019-01-17T21:32:45.284Z,1547760765.284 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 887
2019-01-17T21:32:45.289Z,1547760765.289 [Supervisor](INFO): Main Thread ID is 797
2019-01-17T21:32:45.289Z,1547760765.289 [Supervisor](DEBUG): Running supervisor.
2019-01-17T21:32:45.290Z,1547760765.290 [CommandLine ThreadHandler](INFO): Handler Thread ID is 888
2019-01-17T21:32:45.292Z,1547760765.292 [controlThread ThreadHandler](INFO): Handler Thread ID is 889
2019-01-17T21:32:45.292Z,1547760765.292 [controlThread](DEBUG): Initializing ControlThread
2019-01-17T21:32:45.293Z,1547760765.293 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-01-17T21:32:45.294Z,1547760765.294 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-01-17T21:32:45.294Z,1547760765.294 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-01-17T21:32:45.294Z,1547760765.294 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-01-17T21:32:45.295Z,1547760765.295 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-01-17T21:32:45.295Z,1547760765.295 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-01-17T21:32:45.302Z,1547760765.302 [SBIT](INFO): Initialize SBIT Component.
2019-01-17T21:32:45.303Z,1547760765.303 [SBIT](IMPORTANT): git: 2018-12-19-99-gd8c8f00
2019-01-17T21:32:45.303Z,1547760765.303 [SBIT](INFO): git hash: d8c8f0070e18b5d1f7bebf78b7e036244bae3f86
2019-01-17T21:32:45.303Z,1547760765.303 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-01-17T21:32:45.305Z,1547760765.305 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-01-17T21:32:45.306Z,1547760765.306 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-01-17T21:32:45.306Z,1547760765.306 [IBIT](INFO): Initialize IBIT Component.
2019-01-17T21:32:45.307Z,1547760765.307 [CBIT](DEBUG): Initialize CBIT Component.
2019-01-17T21:32:45.308Z,1547760765.308 [logger ThreadHandler](INFO): Handler Thread ID is 890
2019-01-17T21:32:45.320Z,1547760765.320 [CBIT](DEBUG): Initialized mux pins.
2019-01-17T21:32:45.320Z,1547760765.320 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-01-17T21:32:45.320Z,1547760765.320 [CBIT](DEBUG): Initializing the watchdog timer.
2019-01-17T21:32:45.328Z,1547760765.328 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 891
2019-01-17T21:32:45.334Z,1547760765.334 [Radio_Surface](INFO): Powering up
2019-01-17T21:32:45.340Z,1547760765.340 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 892
2019-01-17T21:32:45.341Z,1547760765.341 [Rowe_600LCM](INFO): Initializing
2019-01-17T21:32:45.348Z,1547760765.348 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-01-17T21:32:45.348Z,1547760765.348 [CBIT](DEBUG): Initializing heartbeat.
2019-01-17T21:32:45.420Z,1547760765.420 [CBIT](DEBUG): Deactivating GF circuits.
2019-01-17T21:32:45.420Z,1547760765.420 [CBIT](DEBUG): Deactivating emergency mode.
2019-01-17T21:32:45.444Z,1547760765.444 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 893
2019-01-17T21:32:45.452Z,1547760765.452 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-01-17T21:32:45.454Z,1547760765.454 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 895
2019-01-17T21:32:45.455Z,1547760765.455 [WetLabsBB2FL](INFO): Powering down
2019-01-17T21:32:45.476Z,1547760765.476 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-01-17T21:32:45.478Z,1547760765.478 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-01-17T21:32:45.479Z,1547760765.479 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-01-17T21:32:45.479Z,1547760765.479 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-01-17T21:32:45.481Z,1547760765.481 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:32:45.482Z,1547760765.482 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:32:45.482Z,1547760765.482 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:32:45.483Z,1547760765.483 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-01-17T21:32:45.483Z,1547760765.483 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-01-17T21:32:45.485Z,1547760765.485 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-01-17T21:32:45.569Z,1547760765.569 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-01-17T21:32:45.585Z,1547760765.585 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896
2019-01-17T21:32:45.594Z,1547760765.594 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-01-17T21:32:45.594Z,1547760765.594 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-01-17T21:32:45.594Z,1547760765.594 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-01-17T21:32:45.595Z,1547760765.595 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-01-17T21:32:45.595Z,1547760765.595 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-01-17T21:32:45.595Z,1547760765.595 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-01-17T21:32:45.595Z,1547760765.595 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-01-17T21:32:45.595Z,1547760765.595 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-01-17T21:32:45.596Z,1547760765.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-01-17T21:32:45.596Z,1547760765.596 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-01-17T21:32:45.596Z,1547760765.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-01-17T21:32:45.596Z,1547760765.596 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-01-17T21:32:45.597Z,1547760765.597 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-01-17T21:32:45.597Z,1547760765.597 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-01-17T21:32:45.597Z,1547760765.597 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-01-17T21:32:45.597Z,1547760765.597 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-01-17T21:32:45.630Z,1547760765.630 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-17T21:32:45.765Z,1547760765.765 [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>


2019-01-17T21:32:45.773Z,1547760765.773 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-01-17T21:32:45.925Z,1547760765.925 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-01-17T21:32:45.926Z,1547760765.926 [Default:A.Wait](DEBUG): Construct Wait.
2019-01-17T21:32:45.928Z,1547760765.928 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-17T21:32:45.940Z,1547760765.940 [Rowe_600LCM](INFO): Powering up
2019-01-17T21:32:45.963Z,1547760765.963 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-01-17T21:32:45.978Z,1547760765.978 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-01-17T21:32:46.024Z,1547760766.024 [Default:E.Execute](DEBUG): Construct Execute.
2019-01-17T21:32:46.028Z,1547760766.028 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-01-17T21:32:46.041Z,1547760766.041 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-01-17T21:32:46.085Z,1547760766.085 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-01-17T21:32:46.085Z,1547760766.085 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-01-17T21:32:46.118Z,1547760766.118 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-01-17T21:32:46.450Z,1547760766.450 [ESPComponent](INFO): powering down ESP
2019-01-17T21:32:47.252Z,1547760767.252 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-17T21:32:47.264Z,1547760767.264 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-17T21:32:47.282Z,1547760767.282 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-01-17T21:32:47.292Z,1547760767.292 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-01-17T21:32:47.298Z,1547760767.298 [MassServo](DEBUG): Initializing EZServoServo.
2019-01-17T21:32:47.324Z,1547760767.324 [MassServo](DEBUG): Initializing MassServo.
2019-01-17T21:32:47.346Z,1547760767.346 [RudderServo](DEBUG): Initializing EZServoServo.
2019-01-17T21:32:47.356Z,1547760767.356 [RudderServo](DEBUG): Initializing RudderServo.
2019-01-17T21:32:47.378Z,1547760767.378 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-01-17T21:32:47.392Z,1547760767.392 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-01-17T21:32:47.441Z,1547760767.441 [CommandLine](FAULT): Scheduling is paused
2019-01-17T21:32:47.442Z,1547760767.442 [CBIT](INFO): Critical error at 20190117T213245
2019-01-17T21:32:47.442Z,1547760767.442 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-01-17T21:32:47.617Z,1547760767.617 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-01-17T21:32:47.617Z,1547760767.617 [DropWeight] Hardware Fault, FailCount= 1
2019-01-17T21:32:47.617Z,1547760767.617 [DropWeight](ERROR): Hardware Fault
2019-01-17T21:32:47.733Z,1547760767.733 [CBIT](INFO): Critical error at 20190117T213247
2019-01-17T21:32:47.736Z,1547760767.736 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-01-17T21:32:47.737Z,1547760767.737 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-01-17T21:32:48.401Z,1547760768.401 [CBIT](INFO): Critical error at 20190117T213247
2019-01-17T21:32:49.990Z,1547760769.990 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-01-17T21:32:50.171Z,1547760770.171 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-01-17T21:32:53.696Z,1547760773.696 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-01-17T21:32:54.901Z,1547760774.901 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2019-01-17T21:32:54.902Z,1547760774.902 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2019-01-17T21:32:54.902Z,1547760774.902 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2019-01-17T21:32:54.903Z,1547760774.903 [AcousticModem_Benthos_ATM900](DEBUG): Jan 11 2019 22:40:56 
2019-01-17T21:32:55.310Z,1547760775.310 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2019-01-17T21:32:55.459Z,1547760775.459 [CBIT](CRITICAL): Environmental Failure. Press:14.766769 PSI. Humidity:43%. Temp:24 C. ABORTING MISSION
2019-01-17T21:32:55.815Z,1547760775.815 [CBIT](INFO): Critical error at 20190117T213255
2019-01-17T21:32:56.096Z,1547760776.096 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2019-01-17T21:32:56.502Z,1547760776.502 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2019-01-17T21:32:56.502Z,1547760776.502 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2019-01-17T21:32:56.502Z,1547760776.502 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2019-01-17T21:32:56.529Z,1547760776.529 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:32:56.529Z,1547760776.529 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:32:56.529Z,1547760776.529 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:32:56.607Z,1547760776.607 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:32:56.896Z,1547760776.896 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2019-01-17T21:32:56.938Z,1547760776.938 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:32:57.296Z,1547760777.296 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2019-01-17T21:32:57.297Z,1547760777.297 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2019-01-17T21:32:57.701Z,1547760777.701 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2019-01-17T21:32:57.855Z,1547760777.855 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:32:57.855Z,1547760777.855 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:33:08.137Z,1547760788.137 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:33:08.137Z,1547760788.137 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:33:08.137Z,1547760788.137 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:33:08.196Z,1547760788.196 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:33:08.524Z,1547760788.524 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:33:09.387Z,1547760789.387 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:33:09.387Z,1547760789.387 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:33:09.855Z,1547760789.855 [SBIT](IMPORTANT): Beginning Startup BIT
2019-01-17T21:33:09.881Z,1547760789.881 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-17T21:33:11.744Z,1547760791.744 [NAL9602](INFO): Powering up NAL9602
2019-01-17T21:33:20.024Z,1547760800.024 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:33:20.024Z,1547760800.024 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:33:20.024Z,1547760800.024 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:33:20.151Z,1547760800.151 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:33:20.151Z,1547760800.151 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:33:20.430Z,1547760800.430 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:33:20.979Z,1547760800.979 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009111
CHAN A1 (24V): -0.013028
CHAN A2 (12V): -0.005135
CHAN A3 (5V): -0.002686
CHAN B0 (3.3V): -0.001017
CHAN B1 (3.15aV): -0.001464
CHAN B2 (3.15bV): -0.001147
CHAN B3 (GND): 0.000068
OPEN: 0.004425
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-17T21:33:22.806Z,1547760802.806 [NAL9602](INFO): NAL9602 initialized
2019-01-17T21:33:58.869Z,1547760838.869 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-01-17T21:33:58.869Z,1547760838.869 [BPC1] Data Fault, FailCount= 1
2019-01-17T21:33:58.869Z,1547760838.869 [BPC1](ERROR): Data Fault
2019-01-17T21:33:58.935Z,1547760838.935 [CBIT](ERROR): Data Fault in component: BPC1
2019-01-17T21:34:03.236Z,1547760843.236 [SBIT](IMPORTANT): SBIT PASSED
2019-01-17T21:34:03.257Z,1547760843.257 [CommandLine](IMPORTANT): got command configSet list
2019-01-17T21:34:03.257Z,1547760843.257 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-01-17T21:34:03.264Z,1547760843.264 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-01-17T21:34:03.264Z,1547760843.264 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-01-17T21:34:03.264Z,1547760843.264 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-01-17T21:34:03.265Z,1547760843.265 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2019-01-17T21:34:03.638Z,1547760843.638 [MissionManager](IMPORTANT): Started mission Startup
2019-01-17T21:34:03.638Z,1547760843.638 [Startup] Running Loop=1
2019-01-17T21:34:03.638Z,1547760843.638 [Startup](DEBUG): Aggregate::initialize Startup
2019-01-17T21:34:03.638Z,1547760843.638 [Startup:A.GoToSurface] Running Loop=1
2019-01-17T21:34:03.638Z,1547760843.638 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-17T21:34:03.639Z,1547760843.639 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-17T21:34:03.639Z,1547760843.639 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-17T21:34:03.640Z,1547760843.640 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-17T21:34:03.640Z,1547760843.640 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-17T21:34:03.641Z,1547760843.641 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-17T21:34:03.647Z,1547760843.647 [Startup:StartupSatComms] Running Loop=1
2019-01-17T21:34:03.647Z,1547760843.647 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-01-17T21:34:03.647Z,1547760843.647 [Startup:StartupSatComms:A] Running Loop=1
2019-01-17T21:34:04.038Z,1547760844.038 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-01-17T21:35:04.032Z,1547760904.032 [Startup:StartupSatComms:A](INFO): Timed out from 2019-01-17T21:34:03.6Z
2019-01-17T21:35:04.032Z,1547760904.032 [Startup:StartupSatComms:A] Stopped
2019-01-17T21:35:04.032Z,1547760904.032 [Startup:StartupSatComms:B] Running Loop=1
2019-01-17T21:35:04.419Z,1547760904.419 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-01-17T21:35:45.619Z,1547760945.619 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000173.779845
2019-01-17T21:35:45.619Z,1547760945.619 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-01-17T21:35:45.619Z,1547760945.619 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:35:45.633Z,1547760945.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:35:46.099Z,1547760946.099 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:35:46.099Z,1547760946.099 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-01-17T21:35:46.431Z,1547760946.431 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:35:46.431Z,1547760946.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:35:46.431Z,1547760946.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:35:50.110Z,1547760950.110 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:35:50.110Z,1547760950.110 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T21:36:00.414Z,1547760960.414 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:36:00.414Z,1547760960.414 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:36:00.414Z,1547760960.414 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:36:00.454Z,1547760960.454 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:36:00.813Z,1547760960.813 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:36:01.648Z,1547760961.648 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:36:01.648Z,1547760961.648 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:36:04.076Z,1547760964.076 [Startup:StartupSatComms:B](INFO): Timed out from 2019-01-17T21:35:04.0Z
2019-01-17T21:36:04.080Z,1547760964.080 [Startup:StartupSatComms:B] Stopped
2019-01-17T21:36:04.080Z,1547760964.080 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-01-17T21:36:04.080Z,1547760964.080 [Startup:StartupSatComms] Stopped
2019-01-17T21:36:04.081Z,1547760964.081 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-01-17T21:36:04.082Z,1547760964.082 [Startup](INFO): Completed Startup
2019-01-17T21:36:04.082Z,1547760964.082 [MissionManager](INFO): Startup is completed.
2019-01-17T21:36:04.082Z,1547760964.082 [MissionManager](INFO): Uninitializing Mission Startup
2019-01-17T21:36:04.082Z,1547760964.082 [Startup] Stopped
2019-01-17T21:36:04.083Z,1547760964.083 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-01-17T21:36:04.083Z,1547760964.083 [Startup:A.GoToSurface] Stopped
2019-01-17T21:36:04.083Z,1547760964.083 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-17T21:36:04.435Z,1547760964.435 [MissionManager](IMPORTANT): Started mission Default
2019-01-17T21:36:04.435Z,1547760964.435 [Default] Running Loop=1
2019-01-17T21:36:04.435Z,1547760964.435 [Default](DEBUG): Aggregate::initialize Default
2019-01-17T21:36:04.435Z,1547760964.435 [Default:B.GoToSurface] Running Loop=1
2019-01-17T21:36:04.436Z,1547760964.436 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-17T21:36:04.436Z,1547760964.436 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-17T21:36:04.436Z,1547760964.436 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-17T21:36:04.437Z,1547760964.437 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-17T21:36:04.437Z,1547760964.437 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-17T21:36:04.437Z,1547760964.437 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-17T21:36:04.438Z,1547760964.438 [Default:A.Wait] Running Loop=1
2019-01-17T21:36:04.438Z,1547760964.438 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-01-17T21:36:11.045Z,1547760971.045 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006795
2019-01-17T21:36:12.261Z,1547760972.261 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:36:12.261Z,1547760972.261 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:36:12.261Z,1547760972.261 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:36:12.314Z,1547760972.314 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:36:12.661Z,1547760972.661 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:36:13.509Z,1547760973.509 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:36:13.509Z,1547760973.509 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:36:17.476Z,1547760977.476 [Default:A.Wait](INFO): Done Waiting.
2019-01-17T21:36:17.477Z,1547760977.477 [Default:A.Wait] Stopped
2019-01-17T21:36:17.477Z,1547760977.477 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-01-17T21:36:17.879Z,1547760977.879 [Default:CheckIn] Running Loop=1
2019-01-17T21:36:17.879Z,1547760977.879 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-17T21:36:17.879Z,1547760977.879 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-17T21:36:18.287Z,1547760978.287 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-01-17T21:36:23.871Z,1547760983.871 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:36:23.871Z,1547760983.871 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:36:23.871Z,1547760983.871 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:36:23.891Z,1547760983.891 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:36:23.891Z,1547760983.891 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:36:24.273Z,1547760984.273 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:38:26.290Z,1547761106.290 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-01-17T21:38:46.722Z,1547761126.722 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000354.882486
2019-01-17T21:38:46.722Z,1547761126.722 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-01-17T21:38:46.722Z,1547761126.722 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:38:46.735Z,1547761126.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:38:47.138Z,1547761127.138 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:38:47.138Z,1547761127.138 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-01-17T21:38:47.542Z,1547761127.542 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:38:47.542Z,1547761127.542 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:38:47.542Z,1547761127.542 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:38:53.993Z,1547761133.993 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:38:53.994Z,1547761133.994 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T21:39:04.359Z,1547761144.359 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:39:04.359Z,1547761144.359 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:39:04.359Z,1547761144.359 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:39:04.462Z,1547761144.462 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:39:04.727Z,1547761144.727 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:39:05.597Z,1547761145.597 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:39:05.597Z,1547761145.597 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:39:16.362Z,1547761156.362 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:39:16.362Z,1547761156.362 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:39:16.363Z,1547761156.363 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:39:16.447Z,1547761156.447 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:39:16.718Z,1547761156.718 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:39:17.532Z,1547761157.532 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:39:17.532Z,1547761157.532 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:39:27.909Z,1547761167.909 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:39:27.909Z,1547761167.909 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:39:27.909Z,1547761167.909 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:39:27.974Z,1547761167.974 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:39:27.975Z,1547761167.975 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:39:28.347Z,1547761168.347 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:41:18.114Z,1547761278.114 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-01-17T21:36:17.9Z
2019-01-17T21:41:18.114Z,1547761278.114 [Default:CheckIn:Read_GPS] Stopped
2019-01-17T21:41:18.114Z,1547761278.114 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-01-17T21:41:18.570Z,1547761278.570 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-01-17T21:41:25.837Z,1547761285.837 [DataOverHttps](INFO): Sending 2465 bytes from file Logs/20190117T201519/Courier0010.lzma
2019-01-17T21:41:26.609Z,1547761286.609 [DataOverHttps](INFO): Moved sent file to Logs/20190117T201519/Courier0010.lzma.bak
2019-01-17T21:41:26.609Z,1547761286.609 [DataOverHttps](INFO): SBD MOMSN=9524460
2019-01-17T21:41:40.230Z,1547761300.230 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20190117T213234/Courier0000.lzma
2019-01-17T21:41:41.022Z,1547761301.022 [DataOverHttps](INFO): Moved sent file to Logs/20190117T213234/Courier0000.lzma.bak
2019-01-17T21:41:41.022Z,1547761301.022 [DataOverHttps](INFO): SBD MOMSN=9525701
2019-01-17T21:41:47.879Z,1547761307.879 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000536.039502
2019-01-17T21:41:47.879Z,1547761307.879 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-01-17T21:41:47.879Z,1547761307.879 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:41:47.908Z,1547761307.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:41:48.285Z,1547761308.285 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:41:48.285Z,1547761308.285 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-01-17T21:41:48.679Z,1547761308.679 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:41:48.680Z,1547761308.680 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:41:48.680Z,1547761308.680 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:41:53.087Z,1547761313.087 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190117T213234/Courier0004.lzma
2019-01-17T21:41:53.886Z,1547761313.886 [DataOverHttps](INFO): Moved sent file to Logs/20190117T213234/Courier0004.lzma.bak
2019-01-17T21:41:53.886Z,1547761313.886 [DataOverHttps](INFO): SBD MOMSN=9525708
2019-01-17T21:41:58.020Z,1547761318.020 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:41:58.020Z,1547761318.020 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T21:42:09.359Z,1547761329.359 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190117T201519/Express0011.lzma
2019-01-17T21:42:10.150Z,1547761330.150 [DataOverHttps](INFO): Moved sent file to Logs/20190117T201519/Express0011.lzma.bak
2019-01-17T21:42:10.150Z,1547761330.150 [DataOverHttps](INFO): SBD MOMSN=9525710
2019-01-17T21:42:14.058Z,1547761334.058 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:42:14.058Z,1547761334.058 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:42:14.059Z,1547761334.059 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:42:14.078Z,1547761334.078 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:42:14.203Z,1547761334.203 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:42:18.117Z,1547761338.117 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:42:18.117Z,1547761338.117 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:42:24.087Z,1547761344.087 [DataOverHttps](INFO): Sending 833 bytes from file Logs/20190117T213234/Express0001.lzma
2019-01-17T21:42:24.882Z,1547761344.882 [DataOverHttps](INFO): Moved sent file to Logs/20190117T213234/Express0001.lzma.bak
2019-01-17T21:42:24.882Z,1547761344.882 [DataOverHttps](INFO): SBD MOMSN=9525712
2019-01-17T21:42:28.694Z,1547761348.694 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:42:28.694Z,1547761348.694 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:42:28.694Z,1547761348.694 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:42:28.779Z,1547761348.779 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:42:28.869Z,1547761348.869 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:42:32.714Z,1547761352.714 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:42:32.714Z,1547761352.714 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:42:37.876Z,1547761357.876 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20190117T213234/Express0005.lzma
2019-01-17T21:42:38.665Z,1547761358.665 [DataOverHttps](INFO): Moved sent file to Logs/20190117T213234/Express0005.lzma.bak
2019-01-17T21:42:38.665Z,1547761358.665 [DataOverHttps](INFO): SBD MOMSN=9525735
2019-01-17T21:42:42.409Z,1547761362.409 [Default:CheckIn:Read_Iridium] Stopped
2019-01-17T21:42:42.409Z,1547761362.409 [Default:CheckIn:C.Wait] Running Loop=1
2019-01-17T21:42:42.409Z,1547761362.409 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-01-17T21:42:46.142Z,1547761366.142 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:42:46.142Z,1547761366.142 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:42:46.143Z,1547761366.143 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:42:46.184Z,1547761366.184 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:42:46.184Z,1547761366.184 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:42:46.274Z,1547761366.274 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:43:23.797Z,1547761403.797 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-01-17T21:43:23.797Z,1547761403.797 [NAL9602] Data Fault, FailCount= 1
2019-01-17T21:43:23.797Z,1547761403.797 [NAL9602](ERROR): Data Fault
2019-01-17T21:43:23.904Z,1547761403.904 [CBIT](ERROR): Data Fault in component: NAL9602
2019-01-17T21:43:24.197Z,1547761404.197 [NAL9602](INFO): Powering down
2019-01-17T21:43:25.058Z,1547761405.058 [CBIT](INFO): Clearing failed state for component NAL9602
2019-01-17T21:43:25.058Z,1547761405.058 [NAL9602] No Fault, FailCount= 1
2019-01-17T21:43:54.249Z,1547761434.249 [NAL9602](INFO): Powering up NAL9602
2019-01-17T21:43:59.899Z,1547761439.899 [CBIT](INFO): Clearing failed state for component BPC1
2019-01-17T21:43:59.899Z,1547761439.899 [BPC1] No Fault, FailCount= 1
2019-01-17T21:44:05.449Z,1547761445.449 [NAL9602](INFO): NAL9602 initialized
2019-01-17T21:44:48.911Z,1547761488.911 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000717.070882
2019-01-17T21:44:48.911Z,1547761488.911 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-01-17T21:44:48.911Z,1547761488.911 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:44:48.960Z,1547761488.960 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:44:49.211Z,1547761489.211 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:44:49.211Z,1547761489.211 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-01-17T21:44:49.582Z,1547761489.582 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:44:49.582Z,1547761489.582 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:44:49.582Z,1547761489.582 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:45:05.000Z,1547761506.000 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-01-17T21:45:05.000Z,1547761506.000 [BPC1] Data Fault, FailCount= 2
2019-01-17T21:45:06.000Z,1547761506.000 [BPC1](ERROR): Data Fault
2019-01-17T21:45:06.042Z,1547761506.042 [CBIT](ERROR): Data Fault in component: BPC1
2019-01-17T21:45:16.411Z,1547761516.411 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:45:16.411Z,1547761516.411 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T21:45:26.763Z,1547761526.763 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:45:26.763Z,1547761526.763 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:45:26.763Z,1547761526.763 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:45:26.825Z,1547761526.825 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:45:27.170Z,1547761527.170 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:45:28.022Z,1547761528.022 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:45:28.022Z,1547761528.022 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:45:38.355Z,1547761538.355 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:45:38.355Z,1547761538.355 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:45:38.355Z,1547761538.355 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:45:38.375Z,1547761538.375 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:45:38.767Z,1547761538.767 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:45:39.585Z,1547761539.585 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:45:39.585Z,1547761539.585 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:45:50.037Z,1547761550.037 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:45:50.037Z,1547761550.037 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:45:50.037Z,1547761550.037 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:45:50.082Z,1547761550.082 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:45:50.082Z,1547761550.082 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:45:50.437Z,1547761550.437 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:47:42.967Z,1547761662.967 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-01-17T21:47:42.967Z,1547761662.967 [Default:CheckIn:C.Wait] Stopped
2019-01-17T21:47:42.967Z,1547761662.967 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-01-17T21:47:42.968Z,1547761662.968 [Default:CheckIn:D] Running Loop=1
2019-01-17T21:47:43.373Z,1547761663.373 [Default:CheckIn:D] Stopped
2019-01-17T21:47:43.373Z,1547761663.373 [Default:CheckIn:E] Running Loop=1
2019-01-17T21:47:46.957Z,1547761666.957 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.648933 min 
2019-01-17T21:47:46.959Z,1547761666.959 [Default:CheckIn:E] Stopped
2019-01-17T21:47:46.959Z,1547761666.959 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-01-17T21:47:46.959Z,1547761666.959 [Default:CheckIn] Stopped
2019-01-17T21:47:46.959Z,1547761666.959 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-17T21:47:46.959Z,1547761666.959 [Default:CheckIn](INFO): Running loop #2
2019-01-17T21:47:46.959Z,1547761666.959 [Default:CheckIn] Running Loop=2
2019-01-17T21:47:46.961Z,1547761666.961 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-17T21:47:46.961Z,1547761666.961 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-17T21:47:47.867Z,1547761667.867 [CBIT](INFO): Clearing failed state for component DropWeight
2019-01-17T21:47:47.867Z,1547761667.867 [DropWeight] No Fault, FailCount= 1
2019-01-17T21:47:49.835Z,1547761669.835 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000897.995561
2019-01-17T21:47:49.835Z,1547761669.835 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-01-17T21:47:49.835Z,1547761669.835 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:47:49.860Z,1547761669.860 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:47:50.280Z,1547761670.280 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:47:50.280Z,1547761670.280 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-01-17T21:47:50.628Z,1547761670.628 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:47:50.628Z,1547761670.628 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:47:50.629Z,1547761670.629 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:48:20.357Z,1547761700.357 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:48:20.357Z,1547761700.357 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T21:48:30.617Z,1547761710.617 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:48:30.617Z,1547761710.617 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:48:30.617Z,1547761710.617 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:48:30.661Z,1547761710.661 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:48:31.024Z,1547761711.024 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:48:31.856Z,1547761711.856 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:48:31.856Z,1547761711.856 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:48:42.609Z,1547761722.609 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:48:42.609Z,1547761722.609 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:48:42.609Z,1547761722.609 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:48:42.685Z,1547761722.685 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:48:43.049Z,1547761723.049 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:48:43.891Z,1547761723.891 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:48:43.891Z,1547761723.891 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:48:54.550Z,1547761734.550 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:48:54.550Z,1547761734.550 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:48:54.550Z,1547761734.550 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:48:54.598Z,1547761734.598 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:48:54.599Z,1547761734.599 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:48:54.954Z,1547761734.954 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:50:50.643Z,1547761850.643 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001078.803304
2019-01-17T21:50:50.643Z,1547761850.643 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-01-17T21:50:50.643Z,1547761850.643 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:50:50.676Z,1547761850.676 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:50:51.065Z,1547761851.065 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:50:51.065Z,1547761851.065 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-01-17T21:50:51.497Z,1547761851.497 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:50:51.498Z,1547761851.498 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:50:51.498Z,1547761851.498 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:51:24.663Z,1547761884.663 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:51:24.663Z,1547761884.663 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T21:51:35.043Z,1547761895.043 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:51:35.043Z,1547761895.043 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:51:35.043Z,1547761895.043 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:51:35.087Z,1547761895.087 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:51:35.502Z,1547761895.502 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:51:36.276Z,1547761896.276 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:51:36.276Z,1547761896.276 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:51:48.758Z,1547761908.758 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:51:48.759Z,1547761908.759 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:51:48.759Z,1547761908.759 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:51:48.778Z,1547761908.778 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:51:48.893Z,1547761908.893 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:51:49.682Z,1547761909.682 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:51:49.682Z,1547761909.682 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:52:00.510Z,1547761920.510 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:52:00.510Z,1547761920.510 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:52:00.510Z,1547761920.510 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:52:00.604Z,1547761920.604 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:52:00.604Z,1547761920.604 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:52:00.832Z,1547761920.832 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:52:48.038Z,1547761968.038 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-01-17T21:47:46.0Z
2019-01-17T21:52:48.038Z,1547761968.038 [Default:CheckIn:Read_GPS] Stopped
2019-01-17T21:52:48.039Z,1547761968.039 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-01-17T21:52:57.337Z,1547761977.337 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190117T213234/Courier0007.lzma
2019-01-17T21:52:58.118Z,1547761978.118 [DataOverHttps](INFO): Moved sent file to Logs/20190117T213234/Courier0007.lzma.bak
2019-01-17T21:52:58.118Z,1547761978.118 [DataOverHttps](INFO): SBD MOMSN=9525763
2019-01-17T21:53:14.125Z,1547761994.125 [DataOverHttps](INFO): Sending 345 bytes from file Logs/20190117T213234/Express0008.lzma
2019-01-17T21:53:14.906Z,1547761994.906 [DataOverHttps](INFO): Moved sent file to Logs/20190117T213234/Express0008.lzma.bak
2019-01-17T21:53:14.906Z,1547761994.906 [DataOverHttps](INFO): SBD MOMSN=9525765
2019-01-17T21:53:23.776Z,1547762003.776 [Default:CheckIn:Read_Iridium] Stopped
2019-01-17T21:53:23.776Z,1547762003.776 [Default:CheckIn:C.Wait] Running Loop=1
2019-01-17T21:53:23.776Z,1547762003.776 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-01-17T21:53:51.788Z,1547762031.788 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001259.948676
2019-01-17T21:53:51.788Z,1547762031.788 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-01-17T21:53:51.789Z,1547762031.789 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:53:51.801Z,1547762031.801 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:53:52.178Z,1547762032.178 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:53:52.178Z,1547762032.178 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-01-17T21:53:52.542Z,1547762032.542 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:53:52.543Z,1547762032.543 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:53:52.543Z,1547762032.543 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:54:06.501Z,1547762046.501 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-01-17T21:54:06.501Z,1547762046.501 [NAL9602] Data Fault, FailCount= 2
2019-01-17T21:54:06.501Z,1547762046.501 [NAL9602](ERROR): Data Fault
2019-01-17T21:54:06.564Z,1547762046.564 [CBIT](ERROR): Data Fault in component: NAL9602
2019-01-17T21:54:06.897Z,1547762046.897 [NAL9602](INFO): Powering down
2019-01-17T21:54:07.829Z,1547762047.829 [CBIT](INFO): Clearing failed state for component NAL9602
2019-01-17T21:54:07.829Z,1547762047.829 [NAL9602] No Fault, FailCount= 2
2019-01-17T21:54:30.851Z,1547762070.851 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:54:30.851Z,1547762070.851 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T21:54:37.142Z,1547762077.142 [NAL9602](INFO): Powering up NAL9602
2019-01-17T21:54:41.561Z,1547762081.561 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:54:41.561Z,1547762081.561 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:54:41.561Z,1547762081.561 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:54:41.617Z,1547762081.617 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:54:41.960Z,1547762081.960 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:54:42.871Z,1547762082.871 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:54:42.871Z,1547762082.871 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:54:48.338Z,1547762088.338 [NAL9602](INFO): NAL9602 initialized
2019-01-17T21:54:53.171Z,1547762093.171 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:54:53.171Z,1547762093.171 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:54:53.171Z,1547762093.171 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:54:53.191Z,1547762093.191 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:54:53.577Z,1547762093.577 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:54:54.395Z,1547762094.395 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:54:54.395Z,1547762094.395 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:55:04.815Z,1547762104.815 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:55:04.815Z,1547762104.815 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:55:04.815Z,1547762104.815 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:55:04.894Z,1547762104.894 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:55:04.894Z,1547762104.894 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:55:05.168Z,1547762105.168 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:55:06.899Z,1547762106.899 [CBIT](INFO): Clearing failed state for component BPC1
2019-01-17T21:55:06.899Z,1547762106.899 [BPC1] No Fault, FailCount= 2
2019-01-17T21:56:12.596Z,1547762172.596 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-01-17T21:56:12.596Z,1547762172.596 [BPC1] Data Fault, FailCount= 3
2019-01-17T21:56:12.596Z,1547762172.596 [BPC1](ERROR): Data Fault
2019-01-17T21:56:12.642Z,1547762172.642 [CBIT](ERROR): Data Fault in component: BPC1
2019-01-17T21:56:52.695Z,1547762212.695 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001440.855244
2019-01-17T21:56:52.695Z,1547762212.695 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-01-17T21:56:52.695Z,1547762212.695 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:56:52.733Z,1547762212.733 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:56:53.137Z,1547762213.137 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:56:53.138Z,1547762213.138 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-01-17T21:56:53.494Z,1547762213.494 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:56:53.494Z,1547762213.494 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:56:53.494Z,1547762213.494 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T21:57:35.075Z,1547762255.075 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:57:35.075Z,1547762255.075 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T21:57:45.333Z,1547762265.333 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:57:45.334Z,1547762265.334 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T21:57:45.334Z,1547762265.334 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:57:45.389Z,1547762265.389 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:57:45.735Z,1547762265.735 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:57:46.554Z,1547762266.554 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:57:46.554Z,1547762266.554 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T21:57:56.931Z,1547762276.931 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:57:56.931Z,1547762276.931 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-17T21:57:56.931Z,1547762276.931 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:57:57.025Z,1547762277.025 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:57:57.327Z,1547762277.327 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:57:58.180Z,1547762278.180 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T21:57:58.180Z,1547762278.180 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-17T21:58:08.935Z,1547762288.935 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T21:58:08.935Z,1547762288.935 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-17T21:58:08.935Z,1547762288.935 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T21:58:09.009Z,1547762289.009 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T21:58:09.009Z,1547762289.009 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-17T21:58:09.327Z,1547762289.327 [Aanderaa_O2](INFO): Powering down
2019-01-17T21:58:24.198Z,1547762304.198 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-01-17T21:58:24.199Z,1547762304.199 [Default:CheckIn:C.Wait] Stopped
2019-01-17T21:58:24.199Z,1547762304.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-01-17T21:58:24.199Z,1547762304.199 [Default:CheckIn:D] Running Loop=1
2019-01-17T21:58:24.568Z,1547762304.568 [Default:CheckIn:D] Stopped
2019-01-17T21:58:24.568Z,1547762304.568 [Default:CheckIn:E] Running Loop=1
2019-01-17T21:58:24.963Z,1547762304.963 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.335472 min 
2019-01-17T21:58:24.963Z,1547762304.963 [Default:CheckIn:E] Stopped
2019-01-17T21:58:24.963Z,1547762304.963 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-01-17T21:58:24.963Z,1547762304.963 [Default:CheckIn] Stopped
2019-01-17T21:58:24.963Z,1547762304.963 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-17T21:58:24.968Z,1547762304.968 [Default:CheckIn](INFO): Running loop #3
2019-01-17T21:58:24.968Z,1547762304.968 [Default:CheckIn] Running Loop=3
2019-01-17T21:58:24.968Z,1547762304.968 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-17T21:58:24.968Z,1547762304.968 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-17T21:59:53.553Z,1547762393.553 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001621.713673
2019-01-17T21:59:53.553Z,1547762393.553 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-01-17T21:59:53.553Z,1547762393.553 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-17T21:59:53.566Z,1547762393.566 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-17T21:59:53.973Z,1547762393.973 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-17T21:59:53.973Z,1547762393.973 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-01-17T21:59:54.370Z,1547762394.370 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-17T21:59:54.370Z,1547762394.370 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-17T21:59:54.370Z,1547762394.370 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-17T22:00:39.361Z,1547762439.361 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T22:00:39.361Z,1547762439.361 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-17T22:00:47.830Z,1547762447.830 [CommandLine](IMPORTANT): got command restart system
2019-01-17T22:00:49.690Z,1547762449.690 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-17T22:00:49.690Z,1547762449.690 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-17T22:00:49.691Z,1547762449.691 [Aanderaa_O2](ERROR): Communications Fault
2019-01-17T22:00:49.894Z,1547762449.894 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-17T22:00:50.078Z,1547762450.078 [Aanderaa_O2](INFO): Powering down
2019-01-17T22:00:50.884Z,1547762450.884 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-17T22:00:50.884Z,1547762450.884 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-17T22:00:51.144Z,1547762451.144 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-17T22:00:51.144Z,1547762451.144 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.240Z,1547762451.240 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-01-17T22:00:51.240Z,1547762451.240 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.241Z,1547762451.241 [CommandLine](INFO): Join timeout helper Thread ID is 960
2019-01-17T22:00:51.247Z,1547762451.247 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-01-17T22:00:51.261Z,1547762451.261 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.263Z,1547762451.263 [NavChartDb](INFO): Join timeout helper Thread ID is 961
2019-01-17T22:00:51.344Z,1547762451.344 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-17T22:00:51.345Z,1547762451.345 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.348Z,1547762451.348 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-01-17T22:00:51.348Z,1547762451.348 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.349Z,1547762451.349 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 962
2019-01-17T22:00:51.592Z,1547762451.592 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-17T22:00:51.592Z,1547762451.592 [WetLabsBB2FL](INFO): Powering down
2019-01-17T22:00:51.593Z,1547762451.593 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.615Z,1547762451.615 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-01-17T22:00:51.615Z,1547762451.615 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.615Z,1547762451.615 [CTD_Seabird](INFO): Join timeout helper Thread ID is 963
2019-01-17T22:00:51.912Z,1547762451.912 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-17T22:00:51.912Z,1547762451.912 [CTD_Seabird](INFO): Powering down
2019-01-17T22:00:51.924Z,1547762451.924 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.944Z,1547762451.944 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-01-17T22:00:51.944Z,1547762451.944 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:51.944Z,1547762451.944 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 964
2019-01-17T22:00:52.446Z,1547762452.446 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-17T22:00:52.447Z,1547762452.447 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-01-17T22:00:52.634Z,1547762452.634 [Rowe_600LCM](INFO): Powering down
2019-01-17T22:00:52.636Z,1547762452.636 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:52.645Z,1547762452.645 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-01-17T22:00:52.645Z,1547762452.645 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:52.645Z,1547762452.645 [Radio_Surface](INFO): Join timeout helper Thread ID is 966
2019-01-17T22:00:52.708Z,1547762452.708 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-17T22:00:52.709Z,1547762452.709 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:52.725Z,1547762452.725 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-01-17T22:00:52.725Z,1547762452.725 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:52.725Z,1547762452.725 [logger](INFO): Join timeout helper Thread ID is 967
2019-01-17T22:00:52.756Z,1547762452.756 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-17T22:00:52.756Z,1547762452.756 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:52.765Z,1547762452.765 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-01-17T22:00:52.765Z,1547762452.765 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:52.765Z,1547762452.765 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-01-17T22:00:52.765Z,1547762452.765 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:52.765Z,1547762452.765 [controlThread](INFO): Join timeout helper Thread ID is 968
2019-01-17T22:00:52.824Z,1547762452.824 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-17T22:00:52.824Z,1547762452.824 [controlThread](DEBUG): Uninitializing ControlThread
2019-01-17T22:00:52.825Z,1547762452.825 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-01-17T22:00:52.945Z,1547762452.945 [NAL9602](INFO): Powering down
2019-01-17T22:00:52.946Z,1547762452.946 [PNI_TCM](INFO): Powering down
2019-01-17T22:00:53.152Z,1547762453.152 [Aanderaa_O2](INFO): Powering down
2019-01-17T22:00:53.155Z,1547762453.155 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-01-17T22:00:53.156Z,1547762453.156 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-01-17T22:00:53.156Z,1547762453.156 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-01-17T22:00:53.157Z,1547762453.157 [MissionManager](INFO): Uninitializing Mission Default
2019-01-17T22:00:53.157Z,1547762453.157 [Default] Stopped
2019-01-17T22:00:53.157Z,1547762453.157 [Default](DEBUG): Aggregate::uninitialize Default
2019-01-17T22:00:53.157Z,1547762453.157 [Default:B.GoToSurface] Stopped
2019-01-17T22:00:53.157Z,1547762453.157 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-17T22:00:53.157Z,1547762453.157 [Default:CheckIn] Stopped
2019-01-17T22:00:53.158Z,1547762453.158 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-17T22:00:53.158Z,1547762453.158 [Default:CheckIn:Read_GPS] Stopped
2019-01-17T22:00:53.161Z,1547762453.161 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-01-17T22:00:53.162Z,1547762453.162 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-01-17T22:00:53.162Z,1547762453.162 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-01-17T22:00:53.162Z,1547762453.162 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-01-17T22:00:53.162Z,1547762453.162 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-01-17T22:00:53.163Z,1547762453.163 [BuoyancyServo](INFO): Powering down
2019-01-17T22:00:53.176Z,1547762453.176 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-01-17T22:00:53.176Z,1547762453.176 [ElevatorServo](INFO): Powering down
2019-01-17T22:00:53.177Z,1547762453.177 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-01-17T22:00:53.177Z,1547762453.177 [MassServo](INFO): Powering down
2019-01-17T22:00:53.178Z,1547762453.178 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-01-17T22:00:53.178Z,1547762453.178 [RudderServo](INFO): Powering down
2019-01-17T22:00:53.179Z,1547762453.179 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-01-17T22:00:53.179Z,1547762453.179 [ThrusterServo](INFO): Powering down
2019-01-17T22:00:53.180Z,1547762453.180 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-01-17T22:00:53.180Z,1547762453.180 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-01-17T22:00:53.181Z,1547762453.181 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-01-17T22:00:53.181Z,1547762453.181 [CBIT](DEBUG): Powering off loads.
2019-01-17T22:00:53.192Z,1547762453.192 [CBIT](DEBUG): Disabling WDT.
2019-01-17T22:00:53.204Z,1547762453.204 [CBIT](DEBUG): Opening all GF detection circuits.
2019-01-17T22:00:53.205Z,1547762453.205 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:53.315Z,1547762453.315 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:53.321Z,1547762453.321 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:53.369Z,1547762453.369 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:53.373Z,1547762453.373 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:53.431Z,1547762453.431 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-17T22:00:53.482Z,1547762453.482 [logger ThreadHandler](INFO): Thread cancelled.