2019-05-08T23:21:47.542Z,1557357707.542 [Supervisor](DEBUG): Initializing supervisor.
2019-05-08T23:21:47.545Z,1557357707.545 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-08T23:21:47.546Z,1557357707.546 [SyncHandler](INFO): Protected caller Thread ID is 806
2019-05-08T23:21:47.546Z,1557357707.546 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-08T23:21:47.547Z,1557357707.547 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-08T23:21:47.547Z,1557357707.547 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807
2019-05-08T23:21:47.550Z,1557357707.550 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-08T23:21:47.563Z,1557357707.563 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-08T23:21:47.564Z,1557357707.564 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-08T23:21:47.564Z,1557357707.564 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808
2019-05-08T23:21:47.565Z,1557357707.565 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-08T23:21:47.566Z,1557357707.566 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-08T23:21:47.566Z,1557357707.566 [logger ThreadHandler](INFO): Protected caller Thread ID is 809
2019-05-08T23:21:47.568Z,1557357707.568 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-08T23:21:47.569Z,1557357707.569 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-08T23:21:47.572Z,1557357707.572 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-08T23:21:47.671Z,1557357707.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-08T23:21:47.672Z,1557357707.672 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-08T23:21:47.892Z,1557357707.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-08T23:21:47.892Z,1557357707.892 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-08T23:21:48.038Z,1557357708.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-08T23:21:48.040Z,1557357708.040 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-08T23:21:48.647Z,1557357708.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-08T23:21:48.649Z,1557357708.649 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-08T23:21:49.149Z,1557357709.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-08T23:21:49.150Z,1557357709.150 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-08T23:21:49.657Z,1557357709.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-08T23:21:49.657Z,1557357709.657 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-08T23:21:49.994Z,1557357709.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-08T23:21:49.996Z,1557357709.996 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-08T23:21:50.370Z,1557357710.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-08T23:21:50.370Z,1557357710.370 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-08T23:21:50.806Z,1557357710.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-08T23:21:50.806Z,1557357710.806 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-08T23:21:50.978Z,1557357710.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-08T23:21:51.422Z,1557357711.422 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-08T23:21:51.865Z,1557357711.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-08T23:21:51.867Z,1557357711.867 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-08T23:21:51.953Z,1557357711.953 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-08T23:21:52.058Z,1557357712.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-08T23:21:52.058Z,1557357712.058 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-08T23:21:52.279Z,1557357712.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-08T23:21:52.280Z,1557357712.280 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-08T23:21:52.504Z,1557357712.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-08T23:21:52.506Z,1557357712.506 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-05-08T23:21:52.508Z,1557357712.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-05-08T23:21:52.595Z,1557357712.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-05-08T23:21:52.830Z,1557357712.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-08T23:21:52.831Z,1557357712.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-05-08T23:21:52.930Z,1557357712.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-05-08T23:21:53.105Z,1557357713.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-05-08T23:21:53.331Z,1557357713.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-05-08T23:21:53.419Z,1557357713.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-05-08T23:21:53.522Z,1557357713.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-05-08T23:21:53.619Z,1557357713.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-05-08T23:21:53.787Z,1557357713.787 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-05-08T23:21:53.898Z,1557357713.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-05-08T23:21:54.002Z,1557357714.002 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-05-08T23:21:54.002Z,1557357714.002 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-08T23:21:54.012Z,1557357714.012 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-08T23:21:54.140Z,1557357714.140 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-08T23:21:54.141Z,1557357714.141 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-08T23:21:54.300Z,1557357714.300 [BuoyancyServo] Loaded
2019-05-08T23:21:54.300Z,1557357714.300 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-08T23:21:54.315Z,1557357714.315 [ElevatorServo] Loaded
2019-05-08T23:21:54.316Z,1557357714.316 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-08T23:21:54.330Z,1557357714.330 [MassServo] Loaded
2019-05-08T23:21:54.331Z,1557357714.331 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-08T23:21:54.346Z,1557357714.346 [RudderServo] Loaded
2019-05-08T23:21:54.347Z,1557357714.347 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-08T23:21:54.361Z,1557357714.361 [ThrusterServo] Loaded
2019-05-08T23:21:54.362Z,1557357714.362 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-08T23:21:54.362Z,1557357714.362 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-08T23:21:54.364Z,1557357714.364 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-08T23:21:54.526Z,1557357714.526 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-08T23:21:54.557Z,1557357714.557 [SBIT] Loaded
2019-05-08T23:21:54.557Z,1557357714.557 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-08T23:21:54.558Z,1557357714.558 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-08T23:21:54.586Z,1557357714.586 [IBIT] Loaded
2019-05-08T23:21:54.586Z,1557357714.586 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-08T23:21:54.590Z,1557357714.590 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-08T23:21:54.986Z,1557357714.986 [CBIT] Loaded
2019-05-08T23:21:54.986Z,1557357714.986 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-08T23:21:54.987Z,1557357714.987 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-08T23:21:54.987Z,1557357714.987 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-08T23:21:55.246Z,1557357715.246 [Aanderaa_O2] Loaded
2019-05-08T23:21:55.246Z,1557357715.246 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-05-08T23:21:55.256Z,1557357715.256 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-08T23:21:55.261Z,1557357715.261 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-08T23:21:55.263Z,1557357715.263 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-08T23:21:55.267Z,1557357715.267 [CTD_Seabird](INFO): created writer for : depth
2019-05-08T23:21:55.268Z,1557357715.268 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-08T23:21:55.273Z,1557357715.273 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-08T23:21:55.274Z,1557357715.274 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-08T23:21:55.279Z,1557357715.279 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-08T23:21:55.279Z,1557357715.279 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-08T23:21:55.285Z,1557357715.285 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-08T23:21:55.286Z,1557357715.286 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-08T23:21:55.291Z,1557357715.291 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-08T23:21:55.292Z,1557357715.292 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-08T23:21:55.297Z,1557357715.297 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-08T23:21:55.324Z,1557357715.324 [CTD_Seabird] Loaded
2019-05-08T23:21:55.324Z,1557357715.324 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-08T23:21:55.325Z,1557357715.325 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406624E0
2019-05-08T23:21:55.325Z,1557357715.325 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 889
2019-05-08T23:21:55.352Z,1557357715.352 [ESPComponent] Loaded
2019-05-08T23:21:55.352Z,1557357715.352 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-05-08T23:21:55.366Z,1557357715.366 [PAR_Licor] Loaded
2019-05-08T23:21:55.367Z,1557357715.367 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-08T23:21:55.416Z,1557357715.416 [WetLabsBB2FL] Loaded
2019-05-08T23:21:55.416Z,1557357715.416 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-08T23:21:55.417Z,1557357715.417 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406924E0
2019-05-08T23:21:55.417Z,1557357715.417 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 890
2019-05-08T23:21:55.418Z,1557357715.418 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-08T23:21:55.420Z,1557357715.420 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-08T23:21:55.475Z,1557357715.475 [DepthRateCalculator] Loaded
2019-05-08T23:21:55.475Z,1557357715.475 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-08T23:21:55.481Z,1557357715.481 [PitchRateCalculator] Loaded
2019-05-08T23:21:55.482Z,1557357715.482 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-08T23:21:55.498Z,1557357715.498 [SpeedCalculator] Loaded
2019-05-08T23:21:55.498Z,1557357715.498 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-08T23:21:55.520Z,1557357715.520 [TempGradientCalculator] Loaded
2019-05-08T23:21:55.520Z,1557357715.520 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-08T23:21:55.526Z,1557357715.526 [YawRateCalculator] Loaded
2019-05-08T23:21:55.527Z,1557357715.527 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-08T23:21:55.560Z,1557357715.560 [ElevatorOffsetCalculator] Loaded
2019-05-08T23:21:55.560Z,1557357715.560 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-08T23:21:55.561Z,1557357715.561 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-08T23:21:55.562Z,1557357715.562 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-08T23:21:55.603Z,1557357715.603 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-08T23:21:55.603Z,1557357715.603 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-08T23:21:55.726Z,1557357715.726 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-08T23:21:55.726Z,1557357715.726 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-08T23:21:55.748Z,1557357715.748 [NavChart] Loaded
2019-05-08T23:21:55.748Z,1557357715.748 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-08T23:21:55.753Z,1557357715.753 [UniversalFixResidualReporter] Loaded
2019-05-08T23:21:55.753Z,1557357715.753 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-08T23:21:55.754Z,1557357715.754 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-08T23:21:55.754Z,1557357715.754 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-08T23:21:56.282Z,1557357716.282 [AcousticModem_Benthos_ATM900] Loaded
2019-05-08T23:21:56.282Z,1557357716.282 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-05-08T23:21:56.423Z,1557357716.423 [DataOverHttps] Loaded
2019-05-08T23:21:56.423Z,1557357716.423 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-08T23:21:56.424Z,1557357716.424 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409694E0
2019-05-08T23:21:56.425Z,1557357716.425 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 891
2019-05-08T23:21:56.438Z,1557357716.438 [Depth_Keller] Loaded
2019-05-08T23:21:56.438Z,1557357716.438 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-08T23:21:56.530Z,1557357716.530 [NAL9602] Loaded
2019-05-08T23:21:56.530Z,1557357716.530 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-08T23:21:56.535Z,1557357716.535 [Onboard] Loaded
2019-05-08T23:21:56.536Z,1557357716.536 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-08T23:21:56.539Z,1557357716.539 [Radio_Surface] Loaded
2019-05-08T23:21:56.540Z,1557357716.540 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-08T23:21:56.541Z,1557357716.541 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2019-05-08T23:21:56.541Z,1557357716.541 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 892
2019-05-08T23:21:56.695Z,1557357716.695 [PNI_TCM] Loaded
2019-05-08T23:21:56.695Z,1557357716.695 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-05-08T23:21:57.989Z,1557357717.989 [BPC1] Loaded
2019-05-08T23:21:57.989Z,1557357717.989 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-08T23:21:57.989Z,1557357717.989 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-08T23:21:57.990Z,1557357717.990 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-08T23:21:58.435Z,1557357718.435 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-08T23:21:58.435Z,1557357718.435 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-08T23:21:58.665Z,1557357718.665 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-08T23:21:58.752Z,1557357718.752 [VerticalControl] Loaded
2019-05-08T23:21:58.752Z,1557357718.752 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-08T23:21:58.753Z,1557357718.753 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-08T23:21:58.812Z,1557357718.812 [HorizontalControl] Loaded
2019-05-08T23:21:58.812Z,1557357718.812 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-08T23:21:58.813Z,1557357718.813 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-08T23:21:58.815Z,1557357718.815 [SpeedControl] Loaded
2019-05-08T23:21:58.815Z,1557357718.815 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-08T23:21:58.816Z,1557357718.816 [LoopControl](DEBUG): Construct LoopControl.
2019-05-08T23:21:58.816Z,1557357718.816 [LoopControl] Loaded
2019-05-08T23:21:58.817Z,1557357718.817 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-08T23:21:58.817Z,1557357718.817 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-08T23:21:58.818Z,1557357718.818 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-08T23:21:58.841Z,1557357718.841 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-08T23:21:58.843Z,1557357718.843 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-08T23:21:59.191Z,1557357719.191 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-08T23:21:59.195Z,1557357719.195 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-08T23:21:59.196Z,1557357719.196 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-08T23:21:59.203Z,1557357719.203 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-08T23:21:59.204Z,1557357719.204 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0
2019-05-08T23:21:59.205Z,1557357719.205 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 893
2019-05-08T23:21:59.210Z,1557357719.210 [Supervisor](INFO): Main Thread ID is 802
2019-05-08T23:21:59.210Z,1557357719.210 [Supervisor](DEBUG): Running supervisor.
2019-05-08T23:21:59.211Z,1557357719.211 [CommandLine ThreadHandler](INFO): Handler Thread ID is 894
2019-05-08T23:21:59.213Z,1557357719.213 [controlThread ThreadHandler](INFO): Handler Thread ID is 895
2019-05-08T23:21:59.213Z,1557357719.213 [controlThread](DEBUG): Initializing ControlThread
2019-05-08T23:21:59.215Z,1557357719.215 [SBIT](INFO): Initialize SBIT Component.
2019-05-08T23:21:59.215Z,1557357719.215 [SBIT](IMPORTANT): git: 2019-05-01-6-g08bb213
2019-05-08T23:21:59.216Z,1557357719.216 [SBIT](INFO): git hash: 08bb2137fac00bb96d649b4144ef516361bafd54
2019-05-08T23:21:59.216Z,1557357719.216 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-08T23:21:59.217Z,1557357719.217 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-08T23:21:59.219Z,1557357719.219 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-05-08T23:21:59.219Z,1557357719.219 [IBIT](INFO): Initialize IBIT Component.
2019-05-08T23:21:59.220Z,1557357719.220 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-08T23:21:59.221Z,1557357719.221 [logger ThreadHandler](INFO): Handler Thread ID is 896
2019-05-08T23:21:59.233Z,1557357719.233 [CBIT](DEBUG): Initialized mux pins.
2019-05-08T23:21:59.233Z,1557357719.233 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-08T23:21:59.241Z,1557357719.241 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 897
2019-05-08T23:21:59.242Z,1557357719.242 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:21:59.245Z,1557357719.245 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:21:59.247Z,1557357719.247 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 899
2019-05-08T23:21:59.248Z,1557357719.248 [WetLabsBB2FL](INFO): Powering down
2019-05-08T23:21:59.257Z,1557357719.257 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-08T23:21:59.257Z,1557357719.257 [CBIT](DEBUG): Initializing heartbeat.
2019-05-08T23:21:59.277Z,1557357719.277 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 900
2019-05-08T23:21:59.278Z,1557357719.278 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-08T23:21:59.289Z,1557357719.289 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 901
2019-05-08T23:21:59.305Z,1557357719.305 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 902
2019-05-08T23:21:59.314Z,1557357719.314 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-08T23:21:59.314Z,1557357719.314 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-08T23:21:59.314Z,1557357719.314 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-08T23:21:59.315Z,1557357719.315 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-08T23:21:59.315Z,1557357719.315 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-08T23:21:59.315Z,1557357719.315 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-08T23:21:59.315Z,1557357719.315 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-08T23:21:59.315Z,1557357719.315 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-08T23:21:59.315Z,1557357719.315 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-08T23:21:59.316Z,1557357719.316 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-08T23:21:59.316Z,1557357719.316 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-08T23:21:59.316Z,1557357719.316 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-08T23:21:59.316Z,1557357719.316 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-08T23:21:59.316Z,1557357719.316 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-08T23:21:59.317Z,1557357719.317 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-08T23:21:59.317Z,1557357719.317 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-08T23:21:59.329Z,1557357719.329 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-08T23:21:59.329Z,1557357719.329 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-08T23:21:59.365Z,1557357719.365 [CBIT](DEBUG): Backplane powered.
2019-05-08T23:21:59.369Z,1557357719.369 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-08T23:21:59.370Z,1557357719.370 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-08T23:21:59.370Z,1557357719.370 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-08T23:21:59.370Z,1557357719.370 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-08T23:21:59.371Z,1557357719.371 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-08T23:21:59.371Z,1557357719.371 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-08T23:21:59.373Z,1557357719.373 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-08T23:21:59.373Z,1557357719.373 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-08T23:21:59.390Z,1557357719.390 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-08T23:21:59.392Z,1557357719.392 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-08T23:21:59.393Z,1557357719.393 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-08T23:21:59.393Z,1557357719.393 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-08T23:21:59.394Z,1557357719.394 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-08T23:21:59.434Z,1557357719.434 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-08T23:21:59.472Z,1557357719.472 [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-05-08T23:21:59.472Z,1557357719.472 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-08T23:21:59.551Z,1557357719.551 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-08T23:21:59.552Z,1557357719.552 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-08T23:21:59.570Z,1557357719.570 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-08T23:21:59.605Z,1557357719.605 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-08T23:21:59.607Z,1557357719.607 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-08T23:21:59.626Z,1557357719.626 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-08T23:21:59.658Z,1557357719.658 [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-05-08T23:21:59.663Z,1557357719.663 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-08T23:21:59.682Z,1557357719.682 [ESPComponent](INFO): powering down ESP
2019-05-08T23:21:59.693Z,1557357719.693 [Radio_Surface](INFO): Powering up
2019-05-08T23:21:59.966Z,1557357719.966 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:21:59.966Z,1557357719.966 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:22:00.283Z,1557357720.283 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-08T23:22:00.289Z,1557357720.289 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-08T23:22:00.290Z,1557357720.290 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-08T23:22:00.297Z,1557357720.297 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-08T23:22:00.298Z,1557357720.298 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-08T23:22:00.305Z,1557357720.305 [MassServo](DEBUG): Initializing MassServo.
2019-05-08T23:22:00.306Z,1557357720.306 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-08T23:22:00.313Z,1557357720.313 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-08T23:22:00.314Z,1557357720.314 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-08T23:22:00.321Z,1557357720.321 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-08T23:22:07.224Z,1557357727.224 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:22:07.224Z,1557357727.224 [PNI_TCM] Data Fault, FailCount= 1
2019-05-08T23:22:07.224Z,1557357727.224 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:22:07.297Z,1557357727.297 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:22:07.635Z,1557357727.635 [PNI_TCM](INFO): Powering down
2019-05-08T23:22:09.009Z,1557357729.009 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:22:09.009Z,1557357729.009 [PNI_TCM] No Fault, FailCount= 1
2019-05-08T23:22:09.293Z,1557357729.293 [CBIT](CRITICAL): Environmental Failure. Press:14.747319 PSI. Humidity:38%. Temp:26 C. ABORTING MISSION
2019-05-08T23:22:09.293Z,1557357729.293 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-05-08T23:22:09.694Z,1557357729.694 [CommandLine](FAULT): Scheduling is paused
2019-05-08T23:22:09.696Z,1557357729.696 [CBIT](INFO): Critical error at 20190508T232209
2019-05-08T23:22:10.029Z,1557357730.029 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:22:10.029Z,1557357730.029 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-08T23:22:10.029Z,1557357730.029 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:22:10.102Z,1557357730.102 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:22:10.565Z,1557357730.565 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:22:11.293Z,1557357731.293 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:22:11.293Z,1557357731.293 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-08T23:22:12.293Z,1557357732.293 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:22:12.293Z,1557357732.293 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-08T23:22:12.293Z,1557357732.293 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:22:12.525Z,1557357732.525 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:22:12.697Z,1557357732.697 [CTD_Seabird](INFO): Powering down
2019-05-08T23:22:13.725Z,1557357733.725 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:22:13.725Z,1557357733.725 [CTD_Seabird] No Fault, FailCount= 1
2019-05-08T23:22:14.082Z,1557357734.082 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:22:14.083Z,1557357734.083 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:22:16.516Z,1557357736.516 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:22:16.516Z,1557357736.516 [PNI_TCM] Data Fault, FailCount= 2
2019-05-08T23:22:16.517Z,1557357736.517 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:22:16.557Z,1557357736.557 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:22:16.920Z,1557357736.920 [PNI_TCM](INFO): Powering down
2019-05-08T23:22:18.160Z,1557357738.160 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:22:18.160Z,1557357738.160 [PNI_TCM] No Fault, FailCount= 2
2019-05-08T23:22:20.133Z,1557357740.133 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:22:20.133Z,1557357740.133 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-05-08T23:22:20.134Z,1557357740.134 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:22:20.165Z,1557357740.165 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:22:20.537Z,1557357740.537 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:22:21.408Z,1557357741.408 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:22:21.408Z,1557357741.408 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-05-08T23:22:21.745Z,1557357741.745 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:22:21.745Z,1557357741.745 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-08T23:22:21.745Z,1557357741.745 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:22:21.808Z,1557357741.808 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:22:22.269Z,1557357742.269 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:22:23.017Z,1557357743.017 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:22:23.017Z,1557357743.017 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-08T23:22:23.781Z,1557357743.781 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:22:23.782Z,1557357743.782 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:22:23.862Z,1557357743.862 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-08T23:22:23.866Z,1557357743.866 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-08T23:22:25.820Z,1557357745.820 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:22:25.820Z,1557357745.820 [PNI_TCM] Data Fault, FailCount= 3
2019-05-08T23:22:25.821Z,1557357745.821 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:22:25.958Z,1557357745.958 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:22:26.212Z,1557357746.212 [PNI_TCM](INFO): Powering down
2019-05-08T23:22:26.922Z,1557357746.922 [NAL9602](INFO): Powering up NAL9602
2019-05-08T23:22:27.133Z,1557357747.133 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:22:27.133Z,1557357747.133 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-08T23:22:27.133Z,1557357747.133 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:22:27.479Z,1557357747.479 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:22:27.479Z,1557357747.479 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:22:27.479Z,1557357747.479 [PNI_TCM] No Fault, FailCount= 3
2019-05-08T23:22:27.537Z,1557357747.537 [CTD_Seabird](INFO): Powering down
2019-05-08T23:22:28.737Z,1557357748.737 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:22:28.738Z,1557357748.738 [CTD_Seabird] No Fault, FailCount= 2
2019-05-08T23:22:28.877Z,1557357748.877 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:22:28.885Z,1557357748.885 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:22:33.761Z,1557357753.761 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:22:33.761Z,1557357753.761 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-08T23:22:33.761Z,1557357753.761 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:22:33.854Z,1557357753.854 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:22:33.854Z,1557357753.854 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-08T23:22:34.285Z,1557357754.285 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:22:34.677Z,1557357754.677 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006646
CHAN A1 (24V): -0.130983
CHAN A2 (12V): -0.005163
CHAN A3 (5V): -0.002601
CHAN B0 (3.3V): -0.001095
CHAN B1 (3.15aV): -0.001138
CHAN B2 (3.15bV): -0.001323
CHAN B3 (GND): 0.000303
OPEN: -0.001029
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-08T23:22:34.996Z,1557357754.996 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:22:34.996Z,1557357754.996 [PNI_TCM] Data Fault, FailCount= 4
2019-05-08T23:22:34.996Z,1557357754.996 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:22:35.062Z,1557357755.062 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:22:35.409Z,1557357755.409 [PNI_TCM](INFO): Powering down
2019-05-08T23:22:36.694Z,1557357756.694 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:22:36.694Z,1557357756.694 [PNI_TCM] No Fault, FailCount= 4
2019-05-08T23:22:37.810Z,1557357757.810 [NAL9602](INFO): NAL9602 initialized
2019-05-08T23:22:38.629Z,1557357758.629 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:22:41.929Z,1557357761.929 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:22:41.929Z,1557357761.929 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-08T23:22:41.929Z,1557357761.929 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:22:42.102Z,1557357762.102 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:22:42.102Z,1557357762.102 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-08T23:22:42.333Z,1557357762.333 [CTD_Seabird](INFO): Powering down
2019-05-08T23:22:43.989Z,1557357763.989 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:22:43.990Z,1557357763.990 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-05-08T23:22:43.990Z,1557357763.990 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:22:44.146Z,1557357764.146 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:22:44.393Z,1557357764.393 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:22:44.541Z,1557357764.541 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:22:44.541Z,1557357764.541 [PNI_TCM] Data Fault, FailCount= 5
2019-05-08T23:22:44.541Z,1557357764.541 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:22:44.818Z,1557357764.818 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:22:44.818Z,1557357764.818 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-08T23:22:44.857Z,1557357764.857 [PNI_TCM](INFO): Powering down
2019-05-08T23:22:45.132Z,1557357765.132 [CBIT](INFO): Critical error at 20190508T232244
2019-05-08T23:22:45.318Z,1557357765.318 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:22:45.318Z,1557357765.318 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-05-08T23:22:47.654Z,1557357767.654 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:22:47.654Z,1557357767.654 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:23:07.946Z,1557357787.946 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:23:07.946Z,1557357787.946 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-05-08T23:23:07.946Z,1557357787.946 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:23:08.113Z,1557357788.113 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:23:08.350Z,1557357788.350 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:23:09.454Z,1557357789.454 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:23:09.454Z,1557357789.454 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-05-08T23:23:11.581Z,1557357791.581 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:23:11.582Z,1557357791.582 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:23:17.690Z,1557357797.690 [SBIT](IMPORTANT): SBIT PASSED
2019-05-08T23:23:17.762Z,1557357797.762 [CommandLine](IMPORTANT): got command configSet list
2019-05-08T23:23:17.762Z,1557357797.762 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-08T23:23:17.763Z,1557357797.763 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-08T23:23:17.763Z,1557357797.763 [CommandLine](IMPORTANT): PAR_Licor.adcCal=8.765e-06 microampere_per_count;

2019-05-08T23:23:17.763Z,1557357797.763 [CommandLine](IMPORTANT): PAR_Licor.darkCount=0 count;

2019-05-08T23:23:17.763Z,1557357797.763 [CommandLine](IMPORTANT): PAR_Licor.multiplier=190.3 micromole_per_second_per_square_meter_per_microampere;

2019-05-08T23:23:17.763Z,1557357797.763 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-05-08T23:23:18.097Z,1557357798.097 [MissionManager](IMPORTANT): Started mission Startup
2019-05-08T23:23:18.098Z,1557357798.098 [Startup] Running Loop=1
2019-05-08T23:23:18.098Z,1557357798.098 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-08T23:23:18.098Z,1557357798.098 [Startup:A.GoToSurface] Running Loop=1
2019-05-08T23:23:18.098Z,1557357798.098 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-08T23:23:18.099Z,1557357798.099 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-08T23:23:18.099Z,1557357798.099 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-08T23:23:18.099Z,1557357798.099 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-08T23:23:18.100Z,1557357798.100 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-08T23:23:18.100Z,1557357798.100 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-08T23:23:18.102Z,1557357798.102 [Startup:StartupSatComms] Running Loop=1
2019-05-08T23:23:18.102Z,1557357798.102 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-08T23:23:18.103Z,1557357798.103 [Startup:StartupSatComms:A] Running Loop=1
2019-05-08T23:23:18.490Z,1557357798.490 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-08T23:23:31.782Z,1557357811.782 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:23:31.782Z,1557357811.782 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-05-08T23:23:31.782Z,1557357811.782 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:23:31.888Z,1557357811.888 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:23:32.185Z,1557357812.185 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:23:33.041Z,1557357813.041 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:23:33.041Z,1557357813.041 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-05-08T23:23:35.417Z,1557357815.417 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:23:35.418Z,1557357815.418 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:23:55.617Z,1557357835.617 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:23:55.617Z,1557357835.617 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-05-08T23:23:55.618Z,1557357835.618 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:23:55.695Z,1557357835.695 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:23:56.017Z,1557357836.017 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:23:56.878Z,1557357836.878 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:23:56.878Z,1557357836.878 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-05-08T23:23:59.253Z,1557357839.253 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:23:59.254Z,1557357839.254 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:23:59.691Z,1557357839.691 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-08T23:23:59.692Z,1557357839.692 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-08T23:23:59.692Z,1557357839.692 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-08T23:23:59.702Z,1557357839.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-08T23:24:00.106Z,1557357840.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-08T23:24:00.106Z,1557357840.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-08T23:24:18.269Z,1557357858.269 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-08T23:23:18.1Z
2019-05-08T23:24:18.269Z,1557357858.269 [Startup:StartupSatComms:A] Stopped
2019-05-08T23:24:18.269Z,1557357858.269 [Startup:StartupSatComms:B] Running Loop=1
2019-05-08T23:24:18.678Z,1557357858.678 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-08T23:24:19.453Z,1557357859.453 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:24:19.454Z,1557357859.454 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-05-08T23:24:19.454Z,1557357859.454 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:24:19.491Z,1557357859.491 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:24:19.853Z,1557357859.853 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:24:20.769Z,1557357860.769 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:24:20.769Z,1557357860.769 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-05-08T23:24:23.085Z,1557357863.085 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:24:23.086Z,1557357863.086 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:24:29.971Z,1557357869.971 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005035
2019-05-08T23:24:37.035Z,1557357877.035 [DataOverHttps](INFO): Sending 587 bytes from file Logs/20190508T190948/Courier0067.lzma
2019-05-08T23:24:37.839Z,1557357877.839 [DataOverHttps](INFO): Moved sent file to Logs/20190508T190948/Courier0067.lzma.bak
2019-05-08T23:24:37.839Z,1557357877.839 [DataOverHttps](INFO): SBD MOMSN=10980762
2019-05-08T23:24:43.285Z,1557357883.285 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:24:43.286Z,1557357883.286 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-05-08T23:24:43.286Z,1557357883.286 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:24:43.452Z,1557357883.452 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:24:43.689Z,1557357883.689 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:24:44.558Z,1557357884.558 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:24:44.558Z,1557357884.558 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-05-08T23:24:46.925Z,1557357886.925 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:24:46.926Z,1557357886.926 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:24:51.882Z,1557357891.882 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20190508T230710/Courier0000.lzma
2019-05-08T23:24:52.700Z,1557357892.700 [DataOverHttps](INFO): Moved sent file to Logs/20190508T230710/Courier0000.lzma.bak
2019-05-08T23:24:52.700Z,1557357892.700 [DataOverHttps](INFO): SBD MOMSN=10980770
2019-05-08T23:25:03.978Z,1557357903.978 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:25:03.978Z,1557357903.978 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-08T23:25:06.868Z,1557357906.868 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190508T230843/Courier0000.lzma
2019-05-08T23:25:07.126Z,1557357907.126 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:25:07.126Z,1557357907.126 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-05-08T23:25:07.126Z,1557357907.126 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:25:07.166Z,1557357907.166 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:25:07.167Z,1557357907.167 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:25:07.529Z,1557357907.529 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:25:07.675Z,1557357907.675 [DataOverHttps](INFO): Moved sent file to Logs/20190508T230843/Courier0000.lzma.bak
2019-05-08T23:25:07.675Z,1557357907.675 [DataOverHttps](INFO): SBD MOMSN=10980774
2019-05-08T23:25:12.022Z,1557357912.022 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:25:12.022Z,1557357912.022 [CTD_Seabird] No Fault, FailCount= 3
2019-05-08T23:25:12.353Z,1557357912.353 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:25:12.361Z,1557357912.361 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:25:14.389Z,1557357914.389 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:25:14.390Z,1557357914.390 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-08T23:25:14.390Z,1557357914.390 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:25:14.439Z,1557357914.439 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:25:14.913Z,1557357914.913 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:25:15.643Z,1557357915.643 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:25:15.643Z,1557357915.643 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-08T23:25:18.481Z,1557357918.481 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-08T23:24:18.3Z
2019-05-08T23:25:18.481Z,1557357918.481 [Startup:StartupSatComms:B] Stopped
2019-05-08T23:25:18.481Z,1557357918.481 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-08T23:25:18.481Z,1557357918.481 [Startup:StartupSatComms] Stopped
2019-05-08T23:25:18.482Z,1557357918.482 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-08T23:25:18.482Z,1557357918.482 [Startup](INFO): Completed Startup
2019-05-08T23:25:18.482Z,1557357918.482 [MissionManager](INFO): Startup is completed.
2019-05-08T23:25:18.483Z,1557357918.483 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-08T23:25:18.483Z,1557357918.483 [Startup] Stopped
2019-05-08T23:25:18.483Z,1557357918.483 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-08T23:25:18.483Z,1557357918.483 [Startup:A.GoToSurface] Stopped
2019-05-08T23:25:18.483Z,1557357918.483 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-08T23:25:18.885Z,1557357918.885 [MissionManager](IMPORTANT): Started mission Default
2019-05-08T23:25:18.885Z,1557357918.885 [Default] Running Loop=1
2019-05-08T23:25:18.885Z,1557357918.885 [Default](DEBUG): Aggregate::initialize Default
2019-05-08T23:25:18.885Z,1557357918.885 [Default:B.GoToSurface] Running Loop=1
2019-05-08T23:25:18.885Z,1557357918.885 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-08T23:25:18.886Z,1557357918.886 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-08T23:25:18.886Z,1557357918.886 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-08T23:25:18.886Z,1557357918.886 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-08T23:25:18.887Z,1557357918.887 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-08T23:25:18.887Z,1557357918.887 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-08T23:25:18.887Z,1557357918.887 [Default:A.Wait] Running Loop=1
2019-05-08T23:25:18.887Z,1557357918.887 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-08T23:25:20.911Z,1557357920.911 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20190508T231146/Courier0000.lzma
2019-05-08T23:25:21.706Z,1557357921.706 [DataOverHttps](INFO): Moved sent file to Logs/20190508T231146/Courier0000.lzma.bak
2019-05-08T23:25:21.706Z,1557357921.706 [DataOverHttps](INFO): SBD MOMSN=10980777
2019-05-08T23:25:26.105Z,1557357926.105 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:25:26.105Z,1557357926.105 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-08T23:25:26.105Z,1557357926.105 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:25:26.186Z,1557357926.186 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:25:26.409Z,1557357926.409 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:25:26.409Z,1557357926.409 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-08T23:25:26.409Z,1557357926.409 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:25:26.629Z,1557357926.629 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:25:26.698Z,1557357926.698 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:25:26.813Z,1557357926.813 [CTD_Seabird](INFO): Powering down
2019-05-08T23:25:27.354Z,1557357927.354 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:25:27.354Z,1557357927.354 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-08T23:25:28.176Z,1557357928.176 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:25:28.176Z,1557357928.176 [CTD_Seabird] No Fault, FailCount= 1
2019-05-08T23:25:28.566Z,1557357928.566 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:25:28.568Z,1557357928.568 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:25:32.195Z,1557357932.195 [Default:A.Wait](INFO): Done Waiting.
2019-05-08T23:25:32.195Z,1557357932.195 [Default:A.Wait] Stopped
2019-05-08T23:25:32.195Z,1557357932.195 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-08T23:25:32.610Z,1557357932.610 [Default:CheckIn] Running Loop=1
2019-05-08T23:25:32.610Z,1557357932.610 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-08T23:25:32.610Z,1557357932.610 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-08T23:25:33.021Z,1557357933.021 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-08T23:25:37.821Z,1557357937.821 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:25:37.821Z,1557357937.821 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-08T23:25:37.821Z,1557357937.821 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:25:37.931Z,1557357937.931 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:25:37.932Z,1557357937.932 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-08T23:25:38.345Z,1557357938.345 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:25:41.621Z,1557357941.621 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:25:41.621Z,1557357941.621 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-08T23:25:41.621Z,1557357941.621 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:25:41.914Z,1557357941.914 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:25:42.025Z,1557357942.025 [CTD_Seabird](INFO): Powering down
2019-05-08T23:25:43.148Z,1557357943.148 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:25:43.148Z,1557357943.148 [CTD_Seabird] No Fault, FailCount= 2
2019-05-08T23:25:43.369Z,1557357943.369 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:25:43.371Z,1557357943.371 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:25:56.421Z,1557357956.421 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:25:56.421Z,1557357956.421 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-08T23:25:56.421Z,1557357956.421 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:25:56.468Z,1557357956.468 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:25:56.478Z,1557357956.478 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-08T23:25:56.826Z,1557357956.826 [CTD_Seabird](INFO): Powering down
2019-05-08T23:26:00.512Z,1557357960.512 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-08T23:26:00.512Z,1557357960.512 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-08T23:26:00.512Z,1557357960.512 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-08T23:26:00.538Z,1557357960.538 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-08T23:26:01.066Z,1557357961.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-08T23:26:01.066Z,1557357961.066 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-08T23:27:41.045Z,1557358061.045 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-05-08T23:27:41.873Z,1557358061.873 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:27:44.701Z,1557358064.701 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:27:44.735Z,1557358064.735 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:27:44.735Z,1557358064.735 [PNI_TCM] No Fault, FailCount= 5
2019-05-08T23:27:47.529Z,1557358067.529 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:27:50.357Z,1557358070.357 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:27:52.377Z,1557358072.377 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:27:52.377Z,1557358072.377 [PNI_TCM] Data Fault, FailCount= 1
2019-05-08T23:27:52.377Z,1557358072.377 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:27:52.418Z,1557358072.418 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:27:52.781Z,1557358072.781 [PNI_TCM](INFO): Powering down
2019-05-08T23:27:53.589Z,1557358073.589 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:27:54.021Z,1557358074.021 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:27:54.021Z,1557358074.021 [PNI_TCM] No Fault, FailCount= 1
2019-05-08T23:27:56.413Z,1557358076.413 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:27:59.649Z,1557358079.649 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:01.673Z,1557358081.673 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:28:01.673Z,1557358081.673 [PNI_TCM] Data Fault, FailCount= 2
2019-05-08T23:28:01.673Z,1557358081.673 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:28:01.737Z,1557358081.737 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-08T23:28:01.737Z,1557358081.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-08T23:28:01.737Z,1557358081.737 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-08T23:28:01.748Z,1557358081.748 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-08T23:28:01.748Z,1557358081.748 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:28:02.082Z,1557358082.082 [PNI_TCM](INFO): Powering down
2019-05-08T23:28:02.202Z,1557358082.202 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-08T23:28:02.202Z,1557358082.202 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-08T23:28:02.477Z,1557358082.477 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:03.347Z,1557358083.347 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:28:03.347Z,1557358083.347 [PNI_TCM] No Fault, FailCount= 2
2019-05-08T23:28:05.709Z,1557358085.709 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:08.146Z,1557358088.146 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:28:08.147Z,1557358088.147 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-08T23:28:08.533Z,1557358088.533 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:10.962Z,1557358090.962 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:28:10.962Z,1557358090.962 [PNI_TCM] Data Fault, FailCount= 3
2019-05-08T23:28:10.962Z,1557358090.962 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:28:10.977Z,1557358090.977 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:28:11.372Z,1557358091.372 [PNI_TCM](INFO): Powering down
2019-05-08T23:28:11.769Z,1557358091.769 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:12.611Z,1557358092.611 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:28:12.611Z,1557358092.611 [PNI_TCM] No Fault, FailCount= 3
2019-05-08T23:28:14.597Z,1557358094.597 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:17.425Z,1557358097.425 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:18.613Z,1557358098.613 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:28:18.613Z,1557358098.613 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-08T23:28:18.613Z,1557358098.613 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:28:18.653Z,1557358098.653 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:28:19.137Z,1557358099.137 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:28:19.870Z,1557358099.870 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:28:19.870Z,1557358099.870 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-08T23:28:20.249Z,1557358100.249 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:28:20.249Z,1557358100.249 [PNI_TCM] Data Fault, FailCount= 4
2019-05-08T23:28:20.249Z,1557358100.249 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:28:20.264Z,1557358100.264 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:28:20.669Z,1557358100.669 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:20.683Z,1557358100.683 [PNI_TCM](INFO): Powering down
2019-05-08T23:28:21.884Z,1557358101.884 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:28:21.884Z,1557358101.884 [PNI_TCM] No Fault, FailCount= 4
2019-05-08T23:28:23.485Z,1557358103.485 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:24.086Z,1557358104.086 [CommandLine](IMPORTANT): got command run ./Missions/test.xml
2019-05-08T23:28:24.087Z,1557358104.087 [MissionManager](INFO): Loading Mission: ./Missions/test.xml
2019-05-08T23:28:24.164Z,1557358104.164 [MissionManager](INFO): DefineArg SampleAtDepth.TargetDepth = 5.000000 m
2019-05-08T23:28:24.167Z,1557358104.167 [MissionManager](INFO): DefineArg SampleAtDepth.SettleTime = 10.000000 s
2019-05-08T23:28:24.170Z,1557358104.170 [MissionManager](INFO): DefineArg SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
2019-05-08T23:28:24.173Z,1557358104.173 [MissionManager](INFO): DefineArg SampleAtDepth.RotateOnly = 0.000000 bool
2019-05-08T23:28:24.177Z,1557358104.177 [MissionManager](INFO): DefineArg SampleAtDepth.UseCANONSampler = 0.000000 bool
2019-05-08T23:28:24.180Z,1557358104.180 [MissionManager](INFO): DefineArg SampleAtDepth.UseESP = 1.000000 bool
2019-05-08T23:28:24.183Z,1557358104.183 [MissionManager](INFO): DefineArg SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2019-05-08T23:28:24.187Z,1557358104.187 [MissionManager](INFO): DefineArg SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2019-05-08T23:28:24.191Z,1557358104.191 [MissionManager](INFO): DefineArg SampleAtDepth.BuoyancyNeutral = 134.999995 cc
2019-05-08T23:28:24.194Z,1557358104.194 [SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Construct.
2019-05-08T23:28:24.202Z,1557358104.202 [SampleAtDepth:ApproachDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-08T23:28:24.206Z,1557358104.206 [SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Construct.
2019-05-08T23:28:24.214Z,1557358104.214 [SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Construct Wait.
2019-05-08T23:28:24.223Z,1557358104.223 [SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-08T23:28:24.226Z,1557358104.226 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-05-08T23:28:24.227Z,1557358104.227 [MissionManager](ERROR): Slate does not contain celsius
2019-05-08T23:28:24.251Z,1557358104.251 [SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-08T23:28:24.282Z,1557358104.282 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-05-08T23:28:24.282Z,1557358104.282 [MissionManager](ERROR): Slate does not contain celsius
2019-05-08T23:28:24.357Z,1557358104.357 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Universal="Tethys/Universal"
           xmlns:Control="Tethys/Control"
           xmlns:Guidance="Tethys/Guidance"
           xmlns:Science="Tethys/Science"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                               Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd"
       Id="SampleAtDepth">

    <Description>
        Take a water sample at a specified depth.
    </Description>

    <DefineArg Name="TargetDepth"><Description>
        Depth to sample at. Initialized to 5 m.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        Time to wait after reaching target depth before triggering sample.
        Initialized to 10 minutes.
    </Description><Units:second/><Value>10</Value></DefineArg>

    <DefineArg Name="MaxWaitNotReachingDepth"><Description>
        Maximum wait time the vehicle cannot reach the targeted depth.
        Initialized to 3 hours.
    </Description><Units:hour/><Value>3</Value></DefineArg>

    <DefineArg Name="RotateOnly"><Units:bool/><Value>0</Value></DefineArg>

    <DefineArg Name="UseCANONSampler"><Description>
        Whether to use CANON Sampler to sample.
    </Description><Science:CANONSampler.loadAtStartup/></DefineArg>

    <DefineArg Name="UseESP"><Description>
        Whether to use ESP to sample.
    </Description><Science:ESPComponent.loadAtStartup/></DefineArg>

    <DefineArg Name="CANONSamplerTriggerTimeout"><Description>
        How long to wait for a CANON Sampler sample to start.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="CANONSamplerTimeout"><Description>
        How long to wait for a CANON Sampler sample to complete.
    </Description><Science:CANONSampler.sampleTimeout/></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position during spiral or donut. Defaults to
        buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc
        for active buoyancy
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <When>
        <Called/>
    </When>

    <Aggregate Id="ApproachDepth">
        <Sequence/>

        <Syslog>Moving to <Arg Name="TargetDepth"/><Units:meter/></Syslog>

        <Guidance:Pitch>
            <Sequence/>
            <Timeout Duration="MaxWaitNotReachingDepth">
                <Syslog Severity="Important">Timed out trying to reach the targeted depth. Stopping mission.</Syslog>

                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                </Guidance:Execute>
            </Timeout>
            <Setting><Guidance:Pitch.depth/><Arg Name="TargetDepth"/></Setting>
        </Guidance:Pitch>
    </Aggregate>

    <Aggregate Id="SampleWrapper">
        <Sequence/>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.depth/><Arg Name="TargetDepth"/></Setting>
        </Guidance:Pitch>

        <Syslog Severity="Important">At <Universal:depth/><Units:meter/>, settling for <Arg Name="SettleTime"/><Units:minute/></Syslog>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="SettleTime"/></Setting>
        </Guidance:Wait>

        <Aggregate Id="SampleCANONSampler">
            <Sequence/>

            <Break>
                <Not><Arg Name="UseCANONSampler"/></Not>
            </Break>

            <Assign><Parallel/><Science:CANONSampler.sampleTimeout/><Arg Name="CANONSamplerTimeout"/></Assign>

            <Assign><Parallel/><Science:CANONSampler.rotateOnly/><Arg Name="RotateOnly"/></Assign>

            <ReadDatum Id="TriggerCANONSampler">
                <Timeout Duration="CANONSamplerTriggerTimeout">
                    <Syslog Severity="Important">Timed out triggering CANONSampler. Stopping mission.</Syslog>

                    <Guidance:Execute>
                        <Sequence/>
                        <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                    </Guidance:Execute>
                </Timeout>
                <Science:CANONSampler.sampling/>
            </ReadDatum>

            <Syslog Severity="Important">CANONSampler sampling at <Universal:depth/><Units:meter/> in <Science:CTD_NeilBrown.bin_mean_sea_water_temperature/><Units:celsius/> water with <Science:WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water/><Units:microgram_per_liter/> chlorophyll fluorescence.</Syslog>

            <ReadDatum>
                <Timeout Duration="CANONSamplerTimeout">
                    <Syslog Severity="Important">Timed out sampling with CANONSampler. Stopping mission.</Syslog>

                    <Guidance:Execute>
                        <Sequence/>
                        <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                    </Guidance:Execute>
                </Timeout>
                <Science:CANONSampler.sample_number/>
            </ReadDatum>
        </Aggregate>

        <Aggregate Id="SampleESP">
            <Sequence/>

            <Break>
                <Not><Arg Name="UseESP"/></Not>
            </Break>

            <ReadDatum Id="TriggerESP">
                <Science:ESPComponent.sampling/>
            </ReadDatum>

            <Syslog Severity="Important">ESP sampling at <Universal:depth/><Units:meter/> in <Science:CTD_NeilBrown.bin_mean_sea_water_temperature/><Units:celsius/> water with <Science:WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water/><Units:microgram_per_liter/>chlorophyll fluorescence.</Syslog>

            <ReadDatum Id="WaitForESP">
                <Science:ESPComponent.sample_number/>
            </ReadDatum>
        </Aggregate>

        <Aggregate Id="TestEndFiltering">
            <Parallel/>
            <Timeout Duration="P10M">
                <Syslog Severity="Important">Timed out to test filtering-to-processing transition</Syslog>
                <Assign><Parallel/><Science:ESPComponent.endFiltering/><True/></Assign>
            </Timeout>
        </Aggregate>

    </Aggregate>
</Aggregate>


2019-05-08T23:28:24.359Z,1557358104.359 [CommandLine](IMPORTANT): Running ./Missions/test.xml
2019-05-08T23:28:24.709Z,1557358104.709 [Default] Stopped
2019-05-08T23:28:24.709Z,1557358104.709 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-08T23:28:24.709Z,1557358104.709 [Default:B.GoToSurface] Stopped
2019-05-08T23:28:24.709Z,1557358104.709 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-08T23:28:24.709Z,1557358104.709 [Default:CheckIn] Stopped
2019-05-08T23:28:24.709Z,1557358104.709 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-08T23:28:24.709Z,1557358104.709 [Default:CheckIn:Read_GPS] Stopped
2019-05-08T23:28:24.710Z,1557358104.710 [MissionManager](IMPORTANT): Started mission SampleAtDepth
2019-05-08T23:28:24.710Z,1557358104.710 [SampleAtDepth] Running Loop=1
2019-05-08T23:28:24.710Z,1557358104.710 [SampleAtDepth](DEBUG): Aggregate::initialize SampleAtDepth
2019-05-08T23:28:24.710Z,1557358104.710 [SampleAtDepth:ApproachDepth] Running Loop=1
2019-05-08T23:28:24.710Z,1557358104.710 [SampleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize SampleAtDepth:ApproachDepth
2019-05-08T23:28:24.710Z,1557358104.710 [SampleAtDepth:ApproachDepth:A] Running Loop=1
2019-05-08T23:28:24.711Z,1557358104.711 [SampleAtDepth:ApproachDepth:A](INFO): Moving to 5.000000 m 
2019-05-08T23:28:24.711Z,1557358104.711 [SampleAtDepth:ApproachDepth:A] Stopped
2019-05-08T23:28:24.711Z,1557358104.711 [SampleAtDepth:ApproachDepth:B.Pitch] Running Loop=1
2019-05-08T23:28:24.711Z,1557358104.711 [SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Initialize.
2019-05-08T23:28:26.713Z,1557358106.713 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:26.759Z,1557358106.759 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:28:26.759Z,1557358106.759 [CTD_Seabird] No Fault, FailCount= 3
2019-05-08T23:28:26.854Z,1557358106.854 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:28:26.856Z,1557358106.856 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:28:29.545Z,1557358109.545 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:29.559Z,1557358109.559 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:28:29.559Z,1557358109.559 [PNI_TCM] Data Fault, FailCount= 5
2019-05-08T23:28:29.559Z,1557358109.559 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:28:29.680Z,1557358109.680 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:28:29.680Z,1557358109.680 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-08T23:28:29.972Z,1557358109.972 [PNI_TCM](INFO): Powering down
2019-05-08T23:28:30.174Z,1557358110.174 [CBIT](INFO): Critical error at 20190508T232829
2019-05-08T23:28:30.175Z,1557358110.175 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-08T23:28:30.330Z,1557358110.330 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:28:30.330Z,1557358110.330 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-08T23:28:30.330Z,1557358110.330 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:28:30.414Z,1557358110.414 [MissionManager](INFO): MissionManager is completed.
2019-05-08T23:28:30.414Z,1557358110.414 [MissionManager](INFO): Uninitializing Mission SampleAtDepth
2019-05-08T23:28:30.414Z,1557358110.414 [SampleAtDepth] Stopped
2019-05-08T23:28:30.414Z,1557358110.414 [SampleAtDepth](DEBUG): Aggregate::uninitialize SampleAtDepth
2019-05-08T23:28:30.414Z,1557358110.414 [SampleAtDepth:ApproachDepth] Stopped
2019-05-08T23:28:30.415Z,1557358110.415 [SampleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize SampleAtDepth:ApproachDepth
2019-05-08T23:28:30.415Z,1557358110.415 [SampleAtDepth:ApproachDepth:B.Pitch] Stopped
2019-05-08T23:28:30.502Z,1557358110.502 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:28:30.853Z,1557358110.853 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:28:30.905Z,1557358110.905 [MissionManager](IMPORTANT): Started mission Default
2019-05-08T23:28:30.906Z,1557358110.906 [Default] Running Loop=1
2019-05-08T23:28:30.906Z,1557358110.906 [Default](DEBUG): Aggregate::initialize Default
2019-05-08T23:28:30.906Z,1557358110.906 [Default:B.GoToSurface] Running Loop=1
2019-05-08T23:28:30.906Z,1557358110.906 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-08T23:28:30.906Z,1557358110.906 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-08T23:28:30.906Z,1557358110.906 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-08T23:28:30.907Z,1557358110.907 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-08T23:28:30.907Z,1557358110.907 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-08T23:28:30.907Z,1557358110.907 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-08T23:28:30.908Z,1557358110.908 [Default:A.Wait] Running Loop=1
2019-05-08T23:28:30.908Z,1557358110.908 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-08T23:28:31.578Z,1557358111.578 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:28:31.578Z,1557358111.578 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-08T23:28:32.777Z,1557358112.777 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:35.609Z,1557358115.609 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:38.429Z,1557358118.429 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:39.905Z,1557358119.905 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:28:39.905Z,1557358119.905 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-08T23:28:39.905Z,1557358119.905 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:28:40.074Z,1557358120.074 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:28:40.309Z,1557358120.309 [CTD_Seabird](INFO): Powering down
2019-05-08T23:28:41.275Z,1557358121.275 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:28:41.275Z,1557358121.275 [CTD_Seabird] No Fault, FailCount= 1
2019-05-08T23:28:41.661Z,1557358121.661 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:28:41.662Z,1557358121.662 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:28:41.665Z,1557358121.665 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:41.706Z,1557358121.706 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 5.000000 meter
2019-05-08T23:28:41.708Z,1557358121.708 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2019-05-08T23:28:41.804Z,1557358121.804 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-08T23:28:42.045Z,1557358122.045 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:28:42.045Z,1557358122.045 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-08T23:28:42.045Z,1557358122.045 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:28:42.113Z,1557358122.113 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:28:42.113Z,1557358122.113 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-08T23:28:42.569Z,1557358122.569 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:28:44.118Z,1557358124.118 [Default:A.Wait](INFO): Done Waiting.
2019-05-08T23:28:44.118Z,1557358124.118 [Default:A.Wait] Stopped
2019-05-08T23:28:44.118Z,1557358124.118 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-08T23:28:44.531Z,1557358124.531 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:47.761Z,1557358127.761 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:50.589Z,1557358130.589 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:53.821Z,1557358133.821 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:54.725Z,1557358134.725 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:28:54.725Z,1557358134.725 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-08T23:28:54.725Z,1557358134.725 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:28:55.098Z,1557358135.098 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:28:55.129Z,1557358135.129 [CTD_Seabird](INFO): Powering down
2019-05-08T23:28:56.326Z,1557358136.326 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:28:56.326Z,1557358136.326 [CTD_Seabird] No Fault, FailCount= 2
2019-05-08T23:28:56.473Z,1557358136.473 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:28:56.475Z,1557358136.475 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:28:56.653Z,1557358136.653 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:28:59.477Z,1557358139.477 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:02.709Z,1557358142.709 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:05.531Z,1557358145.531 [CommandLine](IMPORTANT): got command maintain clear
2019-05-08T23:29:05.537Z,1557358145.537 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:05.592Z,1557358145.592 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-08T23:29:05.971Z,1557358145.971 [Default:CheckIn] Running Loop=1
2019-05-08T23:29:05.971Z,1557358145.971 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-08T23:29:05.971Z,1557358145.971 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-08T23:29:08.801Z,1557358148.801 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:09.525Z,1557358149.525 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:29:09.525Z,1557358149.525 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-08T23:29:09.525Z,1557358149.525 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:29:09.729Z,1557358149.729 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:29:09.730Z,1557358149.730 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-08T23:29:09.929Z,1557358149.929 [CTD_Seabird](INFO): Powering down
2019-05-08T23:29:10.329Z,1557358150.329 [CommandLine](IMPORTANT): got command show stack
2019-05-08T23:29:10.330Z,1557358150.330 [CommandLine](IMPORTANT): Behavior Stack:

2019-05-08T23:29:10.330Z,1557358150.330 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-05-08T23:29:10.330Z,1557358150.330 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2019-05-08T23:29:11.629Z,1557358151.629 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:14.861Z,1557358154.861 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:17.701Z,1557358157.701 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:20.533Z,1557358160.533 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:23.765Z,1557358163.765 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:26.585Z,1557358166.585 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:29.817Z,1557358169.817 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:32.653Z,1557358172.653 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:35.885Z,1557358175.885 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:38.713Z,1557358178.713 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:41.541Z,1557358181.541 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:43.561Z,1557358183.561 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:46.789Z,1557358186.789 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:49.621Z,1557358189.621 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:52.853Z,1557358192.853 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:55.677Z,1557358195.677 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:29:58.913Z,1557358198.913 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:01.741Z,1557358201.741 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:02.579Z,1557358202.579 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-08T23:30:02.579Z,1557358202.579 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-05-08T23:30:02.579Z,1557358202.579 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-08T23:30:02.589Z,1557358202.589 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-08T23:30:02.967Z,1557358202.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-08T23:30:02.967Z,1557358202.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-05-08T23:30:04.569Z,1557358204.569 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:07.411Z,1557358207.411 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:30:07.411Z,1557358207.411 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 8
2019-05-08T23:30:07.785Z,1557358207.785 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:30:07.786Z,1557358207.786 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:30:07.821Z,1557358207.821 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:10.629Z,1557358210.629 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:13.861Z,1557358213.861 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:16.689Z,1557358216.689 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:19.921Z,1557358219.921 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:22.749Z,1557358222.749 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:25.989Z,1557358225.989 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:27.981Z,1557358227.981 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:30:27.982Z,1557358227.982 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-05-08T23:30:27.982Z,1557358227.982 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:30:28.050Z,1557358228.050 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:30:28.385Z,1557358228.385 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:30:28.809Z,1557358228.809 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:29.246Z,1557358229.246 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:30:29.246Z,1557358229.246 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-05-08T23:30:31.621Z,1557358231.621 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:30:31.622Z,1557358231.622 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:30:31.657Z,1557358231.657 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:34.869Z,1557358234.869 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:37.697Z,1557358237.697 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:40.929Z,1557358240.929 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:43.757Z,1557358243.757 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:43.799Z,1557358243.799 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-05-08T23:30:43.803Z,1557358243.803 [BPC1](INFO): Received data from all battery sticks.
2019-05-08T23:30:46.989Z,1557358246.989 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:49.809Z,1557358249.809 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:51.821Z,1557358251.821 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:30:51.821Z,1557358251.821 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-05-08T23:30:51.822Z,1557358251.822 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:30:51.856Z,1557358251.856 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:30:52.221Z,1557358252.221 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:30:52.645Z,1557358252.645 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:53.076Z,1557358253.076 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:30:53.076Z,1557358253.076 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-05-08T23:30:55.454Z,1557358255.454 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:30:55.454Z,1557358255.454 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:30:55.877Z,1557358255.877 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:30:58.705Z,1557358258.705 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:01.937Z,1557358261.937 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:04.773Z,1557358264.773 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:08.025Z,1557358268.025 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:10.829Z,1557358270.829 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:12.070Z,1557358272.070 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:31:12.070Z,1557358272.070 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-08T23:31:13.661Z,1557358273.661 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:15.665Z,1557358275.665 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:31:15.665Z,1557358275.665 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-05-08T23:31:15.666Z,1557358275.666 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:31:15.719Z,1557358275.719 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:31:16.065Z,1557358276.065 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:31:16.893Z,1557358276.893 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:16.921Z,1557358276.921 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:31:16.921Z,1557358276.921 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-05-08T23:31:19.301Z,1557358279.301 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:31:19.302Z,1557358279.302 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:31:19.721Z,1557358279.721 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:22.525Z,1557358282.525 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:31:22.525Z,1557358282.525 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-08T23:31:22.525Z,1557358282.525 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:31:22.592Z,1557358282.592 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:31:23.049Z,1557358283.049 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:31:23.073Z,1557358283.073 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:23.788Z,1557358283.788 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:31:23.788Z,1557358283.788 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-08T23:31:25.781Z,1557358285.781 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:29.009Z,1557358289.009 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:31.841Z,1557358291.841 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:34.241Z,1557358294.241 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:31:34.241Z,1557358294.241 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-08T23:31:34.241Z,1557358294.241 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:31:34.286Z,1557358294.286 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:31:34.765Z,1557358294.765 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:31:35.069Z,1557358295.069 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:35.504Z,1557358295.504 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:31:35.505Z,1557358295.505 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-08T23:31:37.901Z,1557358297.901 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:39.494Z,1557358299.494 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:31:39.494Z,1557358299.494 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-05-08T23:31:39.495Z,1557358299.495 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:31:39.525Z,1557358299.525 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:31:39.901Z,1557358299.901 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:31:40.064Z,1557358300.064 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:31:40.064Z,1557358300.064 [CTD_Seabird] No Fault, FailCount= 3
2019-05-08T23:31:40.390Z,1557358300.390 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:31:40.391Z,1557358300.391 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:31:40.729Z,1557358300.729 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:40.756Z,1557358300.756 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:31:40.756Z,1557358300.756 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-05-08T23:31:43.137Z,1557358303.137 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:31:43.138Z,1557358303.138 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:31:43.961Z,1557358303.961 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:45.957Z,1557358305.957 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:31:45.957Z,1557358305.957 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-08T23:31:45.957Z,1557358305.957 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:31:46.026Z,1557358306.026 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:31:46.026Z,1557358306.026 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-08T23:31:46.481Z,1557358306.481 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:31:46.781Z,1557358306.781 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:50.021Z,1557358310.021 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:52.841Z,1557358312.841 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:53.441Z,1557358313.441 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:31:53.441Z,1557358313.441 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-08T23:31:53.441Z,1557358313.441 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:31:53.684Z,1557358313.684 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:31:53.845Z,1557358313.845 [CTD_Seabird](INFO): Powering down
2019-05-08T23:31:54.883Z,1557358314.883 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:31:54.883Z,1557358314.883 [CTD_Seabird] No Fault, FailCount= 1
2019-05-08T23:31:55.189Z,1557358315.189 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:31:55.190Z,1557358315.190 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:31:56.081Z,1557358316.081 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:31:58.908Z,1557358318.908 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:01.737Z,1557358321.737 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:03.337Z,1557358323.337 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:32:03.337Z,1557358323.337 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-05-08T23:32:03.338Z,1557358323.338 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:32:03.367Z,1557358323.367 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:32:03.737Z,1557358323.737 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:32:03.869Z,1557358323.869 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:03.892Z,1557358323.892 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-08T23:32:03.892Z,1557358323.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-05-08T23:32:03.892Z,1557358323.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-08T23:32:03.924Z,1557358323.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-08T23:32:04.185Z,1557358324.185 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-08T23:32:04.185Z,1557358324.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-05-08T23:32:04.592Z,1557358324.592 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:32:04.592Z,1557358324.592 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-05-08T23:32:06.973Z,1557358326.973 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:32:06.974Z,1557358326.974 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:32:07.009Z,1557358327.009 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:08.241Z,1557358328.241 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:32:08.241Z,1557358328.241 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-08T23:32:08.241Z,1557358328.241 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:32:08.621Z,1557358328.621 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:32:08.645Z,1557358328.645 [CTD_Seabird](INFO): Powering down
2019-05-08T23:32:09.817Z,1557358329.817 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:09.857Z,1557358329.857 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:32:09.857Z,1557358329.857 [CTD_Seabird] No Fault, FailCount= 2
2019-05-08T23:32:09.985Z,1557358329.985 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:32:09.987Z,1557358329.987 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:32:13.049Z,1557358333.049 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:15.877Z,1557358335.877 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:19.109Z,1557358339.109 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:21.937Z,1557358341.937 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:23.037Z,1557358343.037 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:32:23.037Z,1557358343.037 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-08T23:32:23.037Z,1557358343.037 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:32:23.174Z,1557358343.174 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:32:23.174Z,1557358343.174 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-08T23:32:23.441Z,1557358343.441 [CTD_Seabird](INFO): Powering down
2019-05-08T23:32:25.169Z,1557358345.169 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:27.169Z,1557358347.169 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:32:27.170Z,1557358347.170 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-05-08T23:32:27.170Z,1557358347.170 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:32:27.263Z,1557358347.263 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:32:27.573Z,1557358347.573 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:32:27.993Z,1557358347.993 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:28.442Z,1557358348.442 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:32:28.442Z,1557358348.442 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-05-08T23:32:30.809Z,1557358350.809 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:32:30.810Z,1557358350.810 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:32:30.845Z,1557358350.845 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:34.057Z,1557358354.057 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:36.885Z,1557358356.885 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:32:40.103Z,1557358360.103 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-05-08T23:32:40.103Z,1557358360.103 [NAL9602] Data Fault, FailCount= 1
2019-05-08T23:32:40.103Z,1557358360.103 [NAL9602](ERROR): Data Fault
2019-05-08T23:32:40.172Z,1557358360.172 [CBIT](ERROR): Data Fault in component: NAL9602
2019-05-08T23:32:40.506Z,1557358360.506 [NAL9602](INFO): Powering down
2019-05-08T23:32:41.335Z,1557358361.335 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-08T23:32:41.335Z,1557358361.335 [NAL9602] No Fault, FailCount= 1
2019-05-08T23:32:41.414Z,1557358361.414 [CommandLine](IMPORTANT): got command configSet PNI_TCM.loadAtStartup 0.000000 bool persist
2019-05-08T23:32:41.415Z,1557358361.415 [CommandLine](IMPORTANT): configSet PNI_TCM.loadAtStartup requires a restart to take effect.
2019-05-08T23:32:51.002Z,1557358371.002 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:32:51.002Z,1557358371.002 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-05-08T23:32:51.002Z,1557358371.002 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:32:51.032Z,1557358371.032 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:32:51.409Z,1557358371.409 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:32:52.244Z,1557358372.244 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:32:52.244Z,1557358372.244 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-05-08T23:32:54.645Z,1557358374.645 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:32:54.646Z,1557358374.646 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:33:09.782Z,1557358389.782 [CommandLine](IMPORTANT): got command configSet CTD_Seabird.loadAtStartup 0.000000 bool persist
2019-05-08T23:33:09.783Z,1557358389.783 [CommandLine](IMPORTANT): configSet CTD_Seabird.loadAtStartup requires a restart to take effect.
2019-05-08T23:33:10.800Z,1557358390.800 [NAL9602](INFO): Powering up NAL9602
2019-05-08T23:33:14.845Z,1557358394.845 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-08T23:33:14.845Z,1557358394.845 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-05-08T23:33:14.846Z,1557358394.846 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-08T23:33:14.875Z,1557358394.875 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:33:14.875Z,1557358394.875 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-08T23:33:15.245Z,1557358395.245 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:33:21.715Z,1557358401.715 [NAL9602](INFO): NAL9602 initialized
2019-05-08T23:33:22.537Z,1557358402.537 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:24.953Z,1557358404.953 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:27.785Z,1557358407.785 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:29.830Z,1557358409.830 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:33:29.831Z,1557358409.831 [PNI_TCM] No Fault, FailCount= 5
2019-05-08T23:33:31.013Z,1557358411.013 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:33.849Z,1557358413.849 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:37.081Z,1557358417.081 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:37.499Z,1557358417.499 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:33:37.500Z,1557358417.500 [PNI_TCM] Data Fault, FailCount= 1
2019-05-08T23:33:37.500Z,1557358417.500 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:33:37.522Z,1557358417.522 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:33:37.902Z,1557358417.902 [PNI_TCM](INFO): Powering down
2019-05-08T23:33:39.116Z,1557358419.116 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:33:39.116Z,1557358419.116 [PNI_TCM] No Fault, FailCount= 1
2019-05-08T23:33:39.909Z,1557358419.909 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:42.737Z,1557358422.737 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:44.757Z,1557358424.757 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:46.777Z,1557358426.777 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:46.791Z,1557358426.791 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:33:46.791Z,1557358426.791 [PNI_TCM] Data Fault, FailCount= 2
2019-05-08T23:33:46.791Z,1557358426.791 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:33:46.806Z,1557358426.806 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:33:47.195Z,1557358427.195 [PNI_TCM](INFO): Powering down
2019-05-08T23:33:48.400Z,1557358428.400 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:33:48.400Z,1557358428.400 [PNI_TCM] No Fault, FailCount= 2
2019-05-08T23:33:50.009Z,1557358430.009 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:52.837Z,1557358432.837 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:56.069Z,1557358436.069 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:33:56.083Z,1557358436.083 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:33:56.083Z,1557358436.083 [PNI_TCM] Data Fault, FailCount= 3
2019-05-08T23:33:56.083Z,1557358436.083 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:33:56.098Z,1557358436.098 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:33:56.486Z,1557358436.486 [PNI_TCM](INFO): Powering down
2019-05-08T23:33:57.722Z,1557358437.722 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:33:57.722Z,1557358437.722 [PNI_TCM] No Fault, FailCount= 3
2019-05-08T23:33:58.893Z,1557358438.893 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:02.129Z,1557358442.129 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:04.949Z,1557358444.949 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:04.967Z,1557358444.967 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-08T23:34:04.967Z,1557358444.967 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-05-08T23:34:04.968Z,1557358444.968 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-08T23:34:04.990Z,1557358444.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-08T23:34:05.375Z,1557358445.375 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:34:05.375Z,1557358445.375 [PNI_TCM] Data Fault, FailCount= 4
2019-05-08T23:34:05.375Z,1557358445.375 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:34:05.431Z,1557358445.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-08T23:34:05.431Z,1557358445.431 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-05-08T23:34:05.431Z,1557358445.431 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:34:05.777Z,1557358445.777 [PNI_TCM](INFO): Powering down
2019-05-08T23:34:06.167Z,1557358446.167 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-08T23:29:05.0Z
2019-05-08T23:34:06.167Z,1557358446.167 [Default:CheckIn:Read_GPS] Stopped
2019-05-08T23:34:06.167Z,1557358446.167 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-08T23:34:06.582Z,1557358446.582 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-08T23:34:07.030Z,1557358447.030 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-08T23:34:07.030Z,1557358447.030 [PNI_TCM] No Fault, FailCount= 4
2019-05-08T23:34:07.781Z,1557358447.781 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:09.813Z,1557358449.813 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:11.821Z,1557358451.821 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:13.236Z,1557358453.236 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190508T231513/Courier0000.lzma
2019-05-08T23:34:14.042Z,1557358454.042 [DataOverHttps](INFO): Moved sent file to Logs/20190508T231513/Courier0000.lzma.bak
2019-05-08T23:34:14.042Z,1557358454.042 [DataOverHttps](INFO): SBD MOMSN=10980872
2019-05-08T23:34:14.658Z,1557358454.658 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-08T23:34:14.658Z,1557358454.658 [PNI_TCM] Data Fault, FailCount= 5
2019-05-08T23:34:14.658Z,1557358454.658 [PNI_TCM](ERROR): Data Fault
2019-05-08T23:34:14.687Z,1557358454.687 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-08T23:34:14.687Z,1557358454.687 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-08T23:34:15.053Z,1557358455.053 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:15.073Z,1557358455.073 [PNI_TCM](INFO): Powering down
2019-05-08T23:34:15.212Z,1557358455.212 [CBIT](INFO): Critical error at 20190508T233414
2019-05-08T23:34:16.286Z,1557358456.286 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:34:16.286Z,1557358456.286 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-08T23:34:17.913Z,1557358457.913 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:21.113Z,1557358461.113 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:23.945Z,1557358463.945 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:26.749Z,1557358466.749 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:34:26.749Z,1557358466.749 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-08T23:34:26.749Z,1557358466.749 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:34:26.806Z,1557358466.806 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:34:27.273Z,1557358467.273 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:34:27.297Z,1557358467.297 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:28.008Z,1557358468.008 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:34:28.008Z,1557358468.008 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-08T23:34:28.273Z,1557358468.273 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20190508T231751/Courier0000.lzma
2019-05-08T23:34:29.078Z,1557358469.078 [DataOverHttps](INFO): Moved sent file to Logs/20190508T231751/Courier0000.lzma.bak
2019-05-08T23:34:29.078Z,1557358469.078 [DataOverHttps](INFO): SBD MOMSN=10980875
2019-05-08T23:34:30.001Z,1557358470.001 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:32.833Z,1557358472.833 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:34.853Z,1557358474.853 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:38.081Z,1557358478.081 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:38.465Z,1557358478.465 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:34:38.465Z,1557358478.465 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-08T23:34:38.465Z,1557358478.465 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:34:38.589Z,1557358478.589 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:34:38.989Z,1557358478.989 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:34:39.714Z,1557358479.714 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:34:39.714Z,1557358479.714 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-08T23:34:40.913Z,1557358480.913 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:42.307Z,1557358482.307 [CommandLine](IMPORTANT): got command configSet AcousticModem_Benthos_ATM900.loadAtStartup 0.000000 bool persist
2019-05-08T23:34:42.307Z,1557358482.307 [CommandLine](IMPORTANT): configSet AcousticModem_Benthos_ATM900.loadAtStartup requires a restart to take effect.
2019-05-08T23:34:44.141Z,1557358484.141 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:46.416Z,1557358486.416 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190508T231751/Courier0004.lzma
2019-05-08T23:34:46.973Z,1557358486.973 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:47.222Z,1557358487.222 [DataOverHttps](INFO): Moved sent file to Logs/20190508T231751/Courier0004.lzma.bak
2019-05-08T23:34:47.222Z,1557358487.222 [DataOverHttps](INFO): SBD MOMSN=10980887
2019-05-08T23:34:50.181Z,1557358490.181 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:34:50.181Z,1557358490.181 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-08T23:34:50.181Z,1557358490.181 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:34:50.197Z,1557358490.197 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:50.230Z,1557358490.230 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:34:50.231Z,1557358490.231 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-08T23:34:50.705Z,1557358490.705 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:34:53.029Z,1557358493.029 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:53.091Z,1557358493.091 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:34:53.091Z,1557358493.091 [CTD_Seabird] No Fault, FailCount= 3
2019-05-08T23:34:53.465Z,1557358493.465 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:34:53.467Z,1557358493.467 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:34:55.861Z,1557358495.861 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:34:57.881Z,1557358497.881 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:01.109Z,1557358501.109 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:03.596Z,1557358503.596 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190508T232147/Courier0000.lzma
2019-05-08T23:35:03.941Z,1557358503.941 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:04.402Z,1557358504.402 [DataOverHttps](INFO): Moved sent file to Logs/20190508T232147/Courier0000.lzma.bak
2019-05-08T23:35:04.402Z,1557358504.402 [DataOverHttps](INFO): SBD MOMSN=10980893
2019-05-08T23:35:06.517Z,1557358506.517 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:35:06.517Z,1557358506.517 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-08T23:35:06.517Z,1557358506.517 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:35:06.803Z,1557358506.803 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:35:06.921Z,1557358506.921 [CTD_Seabird](INFO): Powering down
2019-05-08T23:35:07.169Z,1557358507.169 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:08.021Z,1557358508.021 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:35:08.022Z,1557358508.022 [CTD_Seabird] No Fault, FailCount= 1
2019-05-08T23:35:08.265Z,1557358508.265 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:35:08.273Z,1557358508.273 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:35:10.001Z,1557358510.001 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:13.249Z,1557358513.249 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:16.057Z,1557358516.057 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:18.192Z,1557358518.192 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20190508T232147/Courier0004.lzma
2019-05-08T23:35:18.933Z,1557358518.933 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:19.402Z,1557358519.402 [DataOverHttps](INFO): Moved sent file to Logs/20190508T232147/Courier0004.lzma.bak
2019-05-08T23:35:19.402Z,1557358519.402 [DataOverHttps](INFO): SBD MOMSN=10980898
2019-05-08T23:35:20.905Z,1557358520.905 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:21.317Z,1557358521.317 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:35:21.317Z,1557358521.317 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-08T23:35:21.317Z,1557358521.317 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:35:21.359Z,1557358521.359 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:35:21.722Z,1557358521.722 [CTD_Seabird](INFO): Powering down
2019-05-08T23:35:22.929Z,1557358522.929 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:22.961Z,1557358522.961 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:35:22.962Z,1557358522.962 [CTD_Seabird] No Fault, FailCount= 2
2019-05-08T23:35:23.069Z,1557358523.069 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:35:23.071Z,1557358523.071 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:35:26.157Z,1557358526.157 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:28.989Z,1557358528.989 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:32.217Z,1557358532.217 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:34.626Z,1557358534.626 [DataOverHttps](INFO): Sending 792 bytes from file Logs/20190508T190948/Express0068.lzma
2019-05-08T23:35:35.049Z,1557358535.049 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:35.422Z,1557358535.422 [DataOverHttps](INFO): Moved sent file to Logs/20190508T190948/Express0068.lzma.bak
2019-05-08T23:35:35.423Z,1557358535.423 [DataOverHttps](INFO): SBD MOMSN=10980901
2019-05-08T23:35:36.121Z,1557358536.121 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-08T23:35:36.121Z,1557358536.121 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-08T23:35:36.121Z,1557358536.121 [CTD_Seabird](ERROR): Communications Fault
2019-05-08T23:35:36.306Z,1557358536.306 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-08T23:35:36.306Z,1557358536.306 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-08T23:35:36.525Z,1557358536.525 [CTD_Seabird](INFO): Powering down
2019-05-08T23:35:38.277Z,1557358538.277 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:41.105Z,1557358541.105 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:43.933Z,1557358543.933 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:45.957Z,1557358545.957 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:47.973Z,1557358547.973 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:51.209Z,1557358551.209 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:54.041Z,1557358554.041 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:35:55.226Z,1557358555.226 [DataOverHttps](INFO): Sending 757 bytes from file Logs/20190508T230710/Express0001.lzma
2019-05-08T23:35:56.030Z,1557358556.030 [DataOverHttps](INFO): Moved sent file to Logs/20190508T230710/Express0001.lzma.bak
2019-05-08T23:35:56.030Z,1557358556.030 [DataOverHttps](INFO): SBD MOMSN=10980956
2019-05-08T23:35:57.273Z,1557358557.273 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:00.105Z,1557358560.105 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:03.333Z,1557358563.333 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:06.161Z,1557358566.161 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:06.192Z,1557358566.192 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-08T23:36:06.192Z,1557358566.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-05-08T23:36:06.192Z,1557358566.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-08T23:36:06.211Z,1557358566.211 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-08T23:36:06.613Z,1557358566.613 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-08T23:36:06.613Z,1557358566.613 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-05-08T23:36:08.989Z,1557358568.989 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:11.009Z,1557358571.009 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:14.196Z,1557358574.196 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190508T230843/Express0001.lzma
2019-05-08T23:36:14.241Z,1557358574.241 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:15.002Z,1557358575.002 [DataOverHttps](INFO): Moved sent file to Logs/20190508T230843/Express0001.lzma.bak
2019-05-08T23:36:15.002Z,1557358575.002 [DataOverHttps](INFO): SBD MOMSN=10980979
2019-05-08T23:36:17.069Z,1557358577.069 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:20.301Z,1557358580.301 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:23.125Z,1557358583.125 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:26.365Z,1557358586.365 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:28.546Z,1557358588.546 [DataOverHttps](INFO): Sending 1293 bytes from file Logs/20190508T231146/Express0001.lzma
2019-05-08T23:36:29.185Z,1557358589.185 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:29.350Z,1557358589.350 [DataOverHttps](INFO): Moved sent file to Logs/20190508T231146/Express0001.lzma.bak
2019-05-08T23:36:29.350Z,1557358589.350 [DataOverHttps](INFO): SBD MOMSN=10980981
2019-05-08T23:36:32.017Z,1557358592.017 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:34.037Z,1557358594.037 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:37.269Z,1557358597.269 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:40.101Z,1557358600.101 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:43.333Z,1557358603.333 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:43.368Z,1557358603.368 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190508T231513/Express0001.lzma
2019-05-08T23:36:44.174Z,1557358604.174 [DataOverHttps](INFO): Moved sent file to Logs/20190508T231513/Express0001.lzma.bak
2019-05-08T23:36:44.174Z,1557358604.174 [DataOverHttps](INFO): SBD MOMSN=10981027
2019-05-08T23:36:46.161Z,1557358606.161 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:49.389Z,1557358609.389 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:52.217Z,1557358612.217 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:55.045Z,1557358615.045 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:57.065Z,1557358617.065 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:36:59.519Z,1557358619.519 [DataOverHttps](INFO): Sending 1211 bytes from file Logs/20190508T231751/Express0001.lzma
2019-05-08T23:37:00.297Z,1557358620.297 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:00.322Z,1557358620.322 [DataOverHttps](INFO): Moved sent file to Logs/20190508T231751/Express0001.lzma.bak
2019-05-08T23:37:00.322Z,1557358620.322 [DataOverHttps](INFO): SBD MOMSN=10981029
2019-05-08T23:37:03.125Z,1557358623.125 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:06.357Z,1557358626.357 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:09.184Z,1557358629.184 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:12.417Z,1557358632.417 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:15.249Z,1557358635.249 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:15.380Z,1557358635.380 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190508T231751/Express0005.lzma
2019-05-08T23:37:16.186Z,1557358636.186 [DataOverHttps](INFO): Moved sent file to Logs/20190508T231751/Express0005.lzma.bak
2019-05-08T23:37:16.186Z,1557358636.186 [DataOverHttps](INFO): SBD MOMSN=10981075
2019-05-08T23:37:18.073Z,1557358638.073 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:20.089Z,1557358640.089 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:20.540Z,1557358640.540 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:37:20.540Z,1557358640.540 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-08T23:37:22.113Z,1557358642.113 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:25.345Z,1557358645.345 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:28.173Z,1557358648.173 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:30.981Z,1557358650.981 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:37:30.981Z,1557358650.981 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-08T23:37:30.981Z,1557358650.981 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:37:31.070Z,1557358651.070 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:37:31.505Z,1557358651.505 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:37:31.529Z,1557358651.529 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:32.122Z,1557358652.122 [DataOverHttps](INFO): Sending 1114 bytes from file Logs/20190508T232147/Express0001.lzma
2019-05-08T23:37:32.244Z,1557358652.244 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:37:32.244Z,1557358652.244 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-08T23:37:32.926Z,1557358652.926 [DataOverHttps](INFO): Moved sent file to Logs/20190508T232147/Express0001.lzma.bak
2019-05-08T23:37:32.926Z,1557358652.926 [DataOverHttps](INFO): SBD MOMSN=10981101
2019-05-08T23:37:34.241Z,1557358654.241 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:37.461Z,1557358657.461 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:40.293Z,1557358660.293 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:42.697Z,1557358662.697 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:37:42.697Z,1557358662.697 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-08T23:37:42.697Z,1557358662.697 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:37:42.772Z,1557358662.772 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:37:43.221Z,1557358663.221 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:37:43.240Z,1557358663.240 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:43.957Z,1557358663.957 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-08T23:37:43.957Z,1557358663.957 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-08T23:37:45.141Z,1557358665.141 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:47.161Z,1557358667.161 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:50.094Z,1557358670.094 [DataOverHttps](INFO): Sending 1040 bytes from file Logs/20190508T232147/Express0005.lzma
2019-05-08T23:37:50.389Z,1557358670.389 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:50.898Z,1557358670.898 [DataOverHttps](INFO): Moved sent file to Logs/20190508T232147/Express0005.lzma.bak
2019-05-08T23:37:50.898Z,1557358670.898 [DataOverHttps](INFO): SBD MOMSN=10981138
2019-05-08T23:37:53.221Z,1557358673.221 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:54.061Z,1557358674.061 [Default:CheckIn:Read_Iridium] Stopped
2019-05-08T23:37:54.062Z,1557358674.062 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-08T23:37:54.062Z,1557358674.062 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-08T23:37:54.413Z,1557358674.413 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-08T23:37:54.413Z,1557358674.413 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-08T23:37:54.413Z,1557358674.413 [Aanderaa_O2](ERROR): Communications Fault
2019-05-08T23:37:54.490Z,1557358674.490 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-08T23:37:54.490Z,1557358674.490 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-08T23:37:54.937Z,1557358674.937 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:37:56.453Z,1557358676.453 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:37:59.281Z,1557358679.281 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:38:02.513Z,1557358682.513 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:38:05.341Z,1557358685.341 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:38:06.198Z,1557358686.198 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-08T23:38:06.198Z,1557358686.198 [CTD_Seabird] No Fault, FailCount= 3
2019-05-08T23:38:06.537Z,1557358686.537 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-08T23:38:06.541Z,1557358686.541 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-08T23:38:07.372Z,1557358687.372 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-08T23:38:07.372Z,1557358687.372 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-05-08T23:38:07.372Z,1557358687.372 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-08T23:38:07.403Z,1557358687.403 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-08T23:38:07.793Z,1557358687.793 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-08T23:38:07.793Z,1557358687.793 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-05-08T23:38:08.173Z,1557358688.173 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:38:10.193Z,1557358690.193 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:38:13.207Z,1557358693.207 [CommandLine](IMPORTANT): got command configSet Aanderaa_O2.loadAtStartup 0.000000 bool persist
2019-05-08T23:38:13.208Z,1557358693.208 [CommandLine](IMPORTANT): configSet Aanderaa_O2.loadAtStartup requires a restart to take effect.
2019-05-08T23:38:13.425Z,1557358693.425 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:38:15.058Z,1557358695.058 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-08T23:38:15.058Z,1557358695.058 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 8
2019-05-08T23:38:15.429Z,1557358695.429 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-08T23:38:15.430Z,1557358695.430 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-08T23:38:16.249Z,1557358696.249 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:38:16.514Z,1557358696.514 [CommandLine](IMPORTANT): got command restart application
2019-05-08T23:38:17.521Z,1557358697.521 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-08T23:38:17.521Z,1557358697.521 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:17.677Z,1557358697.677 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-08T23:38:17.677Z,1557358697.677 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:17.678Z,1557358697.678 [CommandLine](INFO): Join timeout helper Thread ID is 1020
2019-05-08T23:38:17.678Z,1557358697.678 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-08T23:38:17.678Z,1557358697.678 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:17.679Z,1557358697.679 [NavChartDb](INFO): Join timeout helper Thread ID is 1021
2019-05-08T23:38:18.017Z,1557358698.017 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-08T23:38:18.018Z,1557358698.018 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:18.021Z,1557358698.021 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-08T23:38:18.021Z,1557358698.021 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:18.021Z,1557358698.021 [Radio_Surface](INFO): Join timeout helper Thread ID is 1022
2019-05-08T23:38:18.421Z,1557358698.421 [Radio_Surface](INFO): Powering down
2019-05-08T23:38:18.422Z,1557358698.422 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-08T23:38:18.422Z,1557358698.422 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:18.425Z,1557358698.425 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2019-05-08T23:38:18.429Z,1557358698.429 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-08T23:38:18.429Z,1557358698.429 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:18.429Z,1557358698.429 [DataOverHttps](INFO): Join timeout helper Thread ID is 1023
2019-05-08T23:38:18.829Z,1557358698.829 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-08T23:38:18.829Z,1557358698.829 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:18.833Z,1557358698.833 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-08T23:38:18.833Z,1557358698.833 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:18.833Z,1557358698.833 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1024
2019-05-08T23:38:19.257Z,1557358699.257 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-08T23:38:19.257Z,1557358699.257 [WetLabsBB2FL](INFO): Powering down
2019-05-08T23:38:19.258Z,1557358699.258 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:19.274Z,1557358699.274 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-08T23:38:19.274Z,1557358699.274 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:19.274Z,1557358699.274 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1025
2019-05-08T23:38:19.481Z,1557358699.481 [NAL9602](DEBUG): Fix Requested
2019-05-08T23:38:19.589Z,1557358699.589 [CTD_Seabird](INFO): Powering down
2019-05-08T23:38:19.717Z,1557358699.717 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-08T23:38:19.717Z,1557358699.717 [CTD_Seabird](INFO): Powering down
2019-05-08T23:38:19.729Z,1557358699.729 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:19.737Z,1557358699.737 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-08T23:38:19.738Z,1557358699.738 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:19.738Z,1557358699.738 [logger](INFO): Join timeout helper Thread ID is 1026
2019-05-08T23:38:19.813Z,1557358699.813 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-08T23:38:19.813Z,1557358699.813 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:19.817Z,1557358699.817 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-08T23:38:19.818Z,1557358699.818 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:19.818Z,1557358699.818 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-08T23:38:19.818Z,1557358699.818 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:19.818Z,1557358699.818 [controlThread](INFO): Join timeout helper Thread ID is 1027
2019-05-08T23:38:19.861Z,1557358699.861 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-08T23:38:19.861Z,1557358699.861 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-08T23:38:19.862Z,1557358699.862 [Aanderaa_O2](INFO): Powering down
2019-05-08T23:38:19.864Z,1557358699.864 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-08T23:38:19.981Z,1557358699.981 [NAL9602](INFO): Powering down
2019-05-08T23:38:19.982Z,1557358699.982 [PNI_TCM](INFO): Powering down
2019-05-08T23:38:19.984Z,1557358699.984 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-08T23:38:19.985Z,1557358699.985 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-08T23:38:19.985Z,1557358699.985 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-08T23:38:19.986Z,1557358699.986 [MissionManager](INFO): Uninitializing Mission Default
2019-05-08T23:38:19.986Z,1557358699.986 [Default] Stopped
2019-05-08T23:38:19.986Z,1557358699.986 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-08T23:38:19.986Z,1557358699.986 [Default:B.GoToSurface] Stopped
2019-05-08T23:38:19.986Z,1557358699.986 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-08T23:38:19.986Z,1557358699.986 [Default:CheckIn] Stopped
2019-05-08T23:38:19.986Z,1557358699.986 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-08T23:38:19.986Z,1557358699.986 [Default:CheckIn:C.Wait] Stopped
2019-05-08T23:38:19.986Z,1557358699.986 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-08T23:38:19.989Z,1557358699.989 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-08T23:38:19.989Z,1557358699.989 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-08T23:38:19.989Z,1557358699.989 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-08T23:38:19.990Z,1557358699.990 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-08T23:38:19.990Z,1557358699.990 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-08T23:38:19.990Z,1557358699.990 [BuoyancyServo](INFO): Powering down
2019-05-08T23:38:20.005Z,1557358700.005 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-08T23:38:20.005Z,1557358700.005 [ElevatorServo](INFO): Powering down
2019-05-08T23:38:20.006Z,1557358700.006 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-08T23:38:20.006Z,1557358700.006 [MassServo](INFO): Powering down
2019-05-08T23:38:20.007Z,1557358700.007 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-08T23:38:20.007Z,1557358700.007 [RudderServo](INFO): Powering down
2019-05-08T23:38:20.008Z,1557358700.008 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-08T23:38:20.008Z,1557358700.008 [ThrusterServo](INFO): Powering down
2019-05-08T23:38:20.009Z,1557358700.009 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-08T23:38:20.009Z,1557358700.009 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-08T23:38:20.009Z,1557358700.009 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-08T23:38:20.009Z,1557358700.009 [CBIT](DEBUG): Powering off loads.
2019-05-08T23:38:20.021Z,1557358700.021 [CBIT](DEBUG): Disabling WDT.
2019-05-08T23:38:20.033Z,1557358700.033 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-08T23:38:20.033Z,1557358700.033 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:20.065Z,1557358700.065 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:20.069Z,1557358700.069 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:20.134Z,1557358700.134 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:20.138Z,1557358700.138 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:20.187Z,1557358700.187 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-08T23:38:20.245Z,1557358700.245 [logger ThreadHandler](INFO): Thread cancelled.