2019-10-09T23:46:15.763Z,1570664775.763 [Supervisor](DEBUG): Initializing supervisor.
2019-10-09T23:46:15.765Z,1570664775.765 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-09T23:46:15.766Z,1570664775.766 [SyncHandler](INFO): Protected caller Thread ID is 4886
2019-10-09T23:46:15.766Z,1570664775.766 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-09T23:46:15.767Z,1570664775.767 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-09T23:46:15.767Z,1570664775.767 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4887
2019-10-09T23:46:15.770Z,1570664775.770 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-09T23:46:15.781Z,1570664775.781 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-09T23:46:15.782Z,1570664775.782 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-09T23:46:15.782Z,1570664775.782 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4888
2019-10-09T23:46:15.783Z,1570664775.783 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-09T23:46:15.784Z,1570664775.784 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-09T23:46:15.784Z,1570664775.784 [logger ThreadHandler](INFO): Protected caller Thread ID is 4889
2019-10-09T23:46:15.786Z,1570664775.786 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-09T23:46:15.787Z,1570664775.787 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-09T23:46:15.788Z,1570664775.788 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-09T23:46:15.981Z,1570664775.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-09T23:46:15.981Z,1570664775.981 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-09T23:46:16.059Z,1570664776.059 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-09T23:46:16.477Z,1570664776.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-09T23:46:16.477Z,1570664776.477 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-09T23:46:16.792Z,1570664776.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-09T23:46:16.793Z,1570664776.793 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-09T23:46:16.885Z,1570664776.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-09T23:46:16.886Z,1570664776.886 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-09T23:46:17.173Z,1570664777.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-09T23:46:17.173Z,1570664777.173 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-09T23:46:17.362Z,1570664777.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-09T23:46:17.362Z,1570664777.362 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-09T23:46:17.799Z,1570664777.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-09T23:46:17.800Z,1570664777.800 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-09T23:46:17.902Z,1570664777.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-09T23:46:17.902Z,1570664777.902 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-09T23:46:17.000Z,1570664778.000 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-09T23:46:18.000Z,1570664778.000 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-09T23:46:18.611Z,1570664778.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-09T23:46:18.612Z,1570664778.612 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-09T23:46:19.563Z,1570664779.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-09T23:46:19.563Z,1570664779.563 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-09T23:46:19.749Z,1570664779.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-09T23:46:19.749Z,1570664779.749 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-09T23:46:19.890Z,1570664779.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-09T23:46:19.891Z,1570664779.891 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-09T23:46:20.105Z,1570664780.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-09T23:46:20.107Z,1570664780.107 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-10-09T23:46:20.108Z,1570664780.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-10-09T23:46:20.196Z,1570664780.196 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-10-09T23:46:20.282Z,1570664780.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-10-09T23:46:20.383Z,1570664780.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-10-09T23:46:20.464Z,1570664780.464 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-10-09T23:46:20.558Z,1570664780.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-10-09T23:46:20.655Z,1570664780.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-10-09T23:46:20.859Z,1570664780.859 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-10-09T23:46:20.934Z,1570664780.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-10-09T23:46:21.096Z,1570664781.096 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-10-09T23:46:21.226Z,1570664781.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-10-09T23:46:21.341Z,1570664781.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-10-09T23:46:21.566Z,1570664781.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-09T23:46:21.568Z,1570664781.568 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-10-09T23:46:21.568Z,1570664781.568 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-09T23:46:21.573Z,1570664781.573 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-09T23:46:21.640Z,1570664781.640 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-09T23:46:21.750Z,1570664781.750 [VerticalControl] Loaded
2019-10-09T23:46:21.751Z,1570664781.751 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-09T23:46:21.751Z,1570664781.751 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-09T23:46:21.819Z,1570664781.819 [HorizontalControl] Loaded
2019-10-09T23:46:21.819Z,1570664781.819 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-09T23:46:21.820Z,1570664781.820 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-09T23:46:21.825Z,1570664781.825 [SpeedControl] Loaded
2019-10-09T23:46:21.825Z,1570664781.825 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-09T23:46:21.826Z,1570664781.826 [LoopControl](DEBUG): Construct LoopControl.
2019-10-09T23:46:21.827Z,1570664781.827 [LoopControl] Loaded
2019-10-09T23:46:21.827Z,1570664781.827 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-09T23:46:21.827Z,1570664781.827 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-09T23:46:21.828Z,1570664781.828 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-09T23:46:21.841Z,1570664781.841 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-09T23:46:21.842Z,1570664781.842 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-09T23:46:21.938Z,1570664781.938 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-09T23:46:21.939Z,1570664781.939 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-09T23:46:22.062Z,1570664782.062 [BuoyancyServo] Loaded
2019-10-09T23:46:22.063Z,1570664782.063 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-09T23:46:22.074Z,1570664782.074 [ElevatorServo] Loaded
2019-10-09T23:46:22.074Z,1570664782.074 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-09T23:46:22.085Z,1570664782.085 [MassServo] Loaded
2019-10-09T23:46:22.085Z,1570664782.085 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-09T23:46:22.096Z,1570664782.096 [RudderServo] Loaded
2019-10-09T23:46:22.096Z,1570664782.096 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-09T23:46:22.107Z,1570664782.107 [ThrusterServo] Loaded
2019-10-09T23:46:22.107Z,1570664782.107 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-09T23:46:22.108Z,1570664782.108 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-09T23:46:22.108Z,1570664782.108 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-09T23:46:22.214Z,1570664782.214 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-09T23:46:22.214Z,1570664782.214 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-09T23:46:22.235Z,1570664782.235 [NavChart] Loaded
2019-10-09T23:46:22.235Z,1570664782.235 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-09T23:46:22.239Z,1570664782.239 [UniversalFixResidualReporter] Loaded
2019-10-09T23:46:22.239Z,1570664782.239 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-09T23:46:22.239Z,1570664782.239 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-09T23:46:22.240Z,1570664782.240 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-09T23:46:22.310Z,1570664782.310 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-09T23:46:22.311Z,1570664782.311 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-09T23:46:22.567Z,1570664782.567 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-09T23:46:22.572Z,1570664782.572 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-09T23:46:22.573Z,1570664782.573 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-09T23:46:22.578Z,1570664782.578 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-09T23:46:22.579Z,1570664782.579 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-09T23:46:22.584Z,1570664782.584 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-09T23:46:22.584Z,1570664782.584 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-09T23:46:22.590Z,1570664782.590 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-09T23:46:22.660Z,1570664782.660 [AHRS_M2] Loaded
2019-10-09T23:46:22.660Z,1570664782.660 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-09T23:46:22.736Z,1570664782.736 [DataOverHttps] Loaded
2019-10-09T23:46:22.736Z,1570664782.736 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-09T23:46:22.738Z,1570664782.738 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0
2019-10-09T23:46:22.738Z,1570664782.738 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4972
2019-10-09T23:46:22.751Z,1570664782.751 [Depth_Keller] Loaded
2019-10-09T23:46:22.751Z,1570664782.751 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-09T23:46:22.756Z,1570664782.756 [DropWeight] Loaded
2019-10-09T23:46:22.756Z,1570664782.756 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-09T23:46:22.852Z,1570664782.852 [NAL9602] Loaded
2019-10-09T23:46:22.852Z,1570664782.852 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-09T23:46:22.867Z,1570664782.867 [Onboard] Loaded
2019-10-09T23:46:22.868Z,1570664782.868 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-09T23:46:22.871Z,1570664782.871 [Radio_Surface] Loaded
2019-10-09T23:46:22.871Z,1570664782.871 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-09T23:46:22.872Z,1570664782.872 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0
2019-10-09T23:46:22.872Z,1570664782.872 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4973
2019-10-09T23:46:25.045Z,1570664785.045 [BPC1] Loaded
2019-10-09T23:46:25.045Z,1570664785.045 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-09T23:46:25.045Z,1570664785.045 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-09T23:46:25.046Z,1570664785.046 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-09T23:46:25.087Z,1570664785.087 [DepthRateCalculator] Loaded
2019-10-09T23:46:25.087Z,1570664785.087 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-09T23:46:25.092Z,1570664785.092 [PitchRateCalculator] Loaded
2019-10-09T23:46:25.093Z,1570664785.093 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-09T23:46:25.104Z,1570664785.104 [SpeedCalculator] Loaded
2019-10-09T23:46:25.104Z,1570664785.104 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-09T23:46:25.126Z,1570664785.126 [TempGradientCalculator] Loaded
2019-10-09T23:46:25.126Z,1570664785.126 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-09T23:46:25.131Z,1570664785.131 [YawRateCalculator] Loaded
2019-10-09T23:46:25.131Z,1570664785.131 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-09T23:46:25.160Z,1570664785.160 [ElevatorOffsetCalculator] Loaded
2019-10-09T23:46:25.160Z,1570664785.160 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-09T23:46:25.161Z,1570664785.161 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-09T23:46:25.161Z,1570664785.161 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-09T23:46:25.343Z,1570664785.343 [Aanderaa_O2] Loaded
2019-10-09T23:46:25.343Z,1570664785.343 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-10-09T23:46:25.352Z,1570664785.352 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-09T23:46:25.357Z,1570664785.357 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-09T23:46:25.358Z,1570664785.358 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-09T23:46:25.363Z,1570664785.363 [CTD_Seabird](INFO): created writer for : depth
2019-10-09T23:46:25.363Z,1570664785.363 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-09T23:46:25.368Z,1570664785.368 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-09T23:46:25.369Z,1570664785.369 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-09T23:46:25.374Z,1570664785.374 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-09T23:46:25.375Z,1570664785.375 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-09T23:46:25.380Z,1570664785.380 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-09T23:46:25.380Z,1570664785.380 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-09T23:46:25.386Z,1570664785.386 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-09T23:46:25.386Z,1570664785.386 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-09T23:46:25.391Z,1570664785.391 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-09T23:46:25.418Z,1570664785.418 [CTD_Seabird] Loaded
2019-10-09T23:46:25.419Z,1570664785.419 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-09T23:46:25.420Z,1570664785.420 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409B04E0
2019-10-09T23:46:25.420Z,1570664785.420 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4974
2019-10-09T23:46:25.434Z,1570664785.434 [PAR_Licor] Loaded
2019-10-09T23:46:25.435Z,1570664785.435 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-09T23:46:25.481Z,1570664785.481 [WetLabsBB2FL] Loaded
2019-10-09T23:46:25.482Z,1570664785.482 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-09T23:46:25.483Z,1570664785.483 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0
2019-10-09T23:46:25.483Z,1570664785.483 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4975
2019-10-09T23:46:25.484Z,1570664785.484 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-09T23:46:25.484Z,1570664785.484 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-09T23:46:25.517Z,1570664785.517 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-09T23:46:25.518Z,1570664785.518 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-09T23:46:25.803Z,1570664785.803 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-09T23:46:25.803Z,1570664785.803 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-09T23:46:25.932Z,1570664785.932 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-09T23:46:25.943Z,1570664785.943 [SBIT] Loaded
2019-10-09T23:46:25.943Z,1570664785.943 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-09T23:46:25.944Z,1570664785.944 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-09T23:46:25.955Z,1570664785.955 [IBIT] Loaded
2019-10-09T23:46:25.956Z,1570664785.956 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-09T23:46:25.959Z,1570664785.959 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-09T23:46:26.096Z,1570664786.096 [CBIT] Loaded
2019-10-09T23:46:26.096Z,1570664786.096 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-09T23:46:26.096Z,1570664786.096 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-09T23:46:26.100Z,1570664786.100 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-09T23:46:26.101Z,1570664786.101 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-09T23:46:26.107Z,1570664786.107 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-09T23:46:26.108Z,1570664786.108 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2019-10-09T23:46:26.109Z,1570664786.109 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4976
2019-10-09T23:46:26.113Z,1570664786.113 [Supervisor](INFO): Main Thread ID is 4885
2019-10-09T23:46:26.113Z,1570664786.113 [Supervisor](DEBUG): Running supervisor.
2019-10-09T23:46:26.114Z,1570664786.114 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4977
2019-10-09T23:46:26.116Z,1570664786.116 [controlThread ThreadHandler](INFO): Handler Thread ID is 4978
2019-10-09T23:46:26.117Z,1570664786.117 [controlThread](DEBUG): Initializing ControlThread
2019-10-09T23:46:26.118Z,1570664786.118 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-09T23:46:26.119Z,1570664786.119 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-09T23:46:26.120Z,1570664786.120 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-09T23:46:26.120Z,1570664786.120 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-09T23:46:26.123Z,1570664786.123 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-09T23:46:26.123Z,1570664786.123 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-09T23:46:26.127Z,1570664786.127 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-09T23:46:26.127Z,1570664786.127 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-09T23:46:26.128Z,1570664786.128 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-09T23:46:26.128Z,1570664786.128 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-09T23:46:26.128Z,1570664786.128 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-09T23:46:26.129Z,1570664786.129 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-09T23:46:26.133Z,1570664786.133 [SBIT](INFO): Initialize SBIT Component.
2019-10-09T23:46:26.134Z,1570664786.134 [SBIT](IMPORTANT): git: 2019-09-10
2019-10-09T23:46:26.134Z,1570664786.134 [SBIT](INFO): git hash: e950883795b57abbf97d89589ea0268241928c93
2019-10-09T23:46:26.134Z,1570664786.134 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-09T23:46:26.135Z,1570664786.135 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-10-09T23:46:26.136Z,1570664786.136 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-10-09T23:46:26.137Z,1570664786.137 [IBIT](INFO): Initialize IBIT Component.
2019-10-09T23:46:26.138Z,1570664786.138 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-09T23:46:26.139Z,1570664786.139 [logger ThreadHandler](INFO): Handler Thread ID is 4979
2019-10-09T23:46:26.149Z,1570664786.149 [CBIT](DEBUG): Initialized mux pins.
2019-10-09T23:46:26.149Z,1570664786.149 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-09T23:46:26.157Z,1570664786.157 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4980
2019-10-09T23:46:26.158Z,1570664786.158 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-09T23:46:26.169Z,1570664786.169 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4981
2019-10-09T23:46:26.173Z,1570664786.173 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-09T23:46:26.173Z,1570664786.173 [CBIT](DEBUG): Initializing heartbeat.
2019-10-09T23:46:26.181Z,1570664786.181 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4982
2019-10-09T23:46:26.182Z,1570664786.182 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-09T23:46:26.185Z,1570664786.185 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-09T23:46:26.187Z,1570664786.187 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4984
2019-10-09T23:46:26.188Z,1570664786.188 [WetLabsBB2FL](INFO): Powering down
2019-10-09T23:46:26.215Z,1570664786.215 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4985
2019-10-09T23:46:26.218Z,1570664786.218 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-09T23:46:26.218Z,1570664786.218 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-09T23:46:26.218Z,1570664786.218 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-09T23:46:26.219Z,1570664786.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-09T23:46:26.219Z,1570664786.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-09T23:46:26.219Z,1570664786.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-09T23:46:26.219Z,1570664786.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-09T23:46:26.219Z,1570664786.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-09T23:46:26.219Z,1570664786.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-09T23:46:26.220Z,1570664786.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-09T23:46:26.220Z,1570664786.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-09T23:46:26.220Z,1570664786.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-09T23:46:26.220Z,1570664786.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-09T23:46:26.220Z,1570664786.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-09T23:46:26.221Z,1570664786.221 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-09T23:46:26.221Z,1570664786.221 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-09T23:46:26.245Z,1570664786.245 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-09T23:46:26.245Z,1570664786.245 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-09T23:46:26.281Z,1570664786.281 [CBIT](DEBUG): Backplane powered.
2019-10-09T23:46:26.282Z,1570664786.282 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-09T23:46:26.292Z,1570664786.292 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-09T23:46:26.302Z,1570664786.302 [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-10-09T23:46:26.303Z,1570664786.303 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-09T23:46:26.383Z,1570664786.383 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-09T23:46:26.384Z,1570664786.384 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-09T23:46:26.385Z,1570664786.385 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-09T23:46:26.396Z,1570664786.396 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-09T23:46:26.423Z,1570664786.423 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-09T23:46:26.428Z,1570664786.428 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-09T23:46:26.447Z,1570664786.447 [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-10-09T23:46:26.452Z,1570664786.452 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,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-10-09T23:46:26.465Z,1570664786.465 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-09T23:46:26.506Z,1570664786.506 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-10-09T23:46:26.569Z,1570664786.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-09T23:46:26.573Z,1570664786.573 [Radio_Surface](INFO): Powering up
2019-10-09T23:46:26.600Z,1570664786.600 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-09T23:46:26.613Z,1570664786.613 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-09T23:46:26.614Z,1570664786.614 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-09T23:46:26.629Z,1570664786.629 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-09T23:46:26.630Z,1570664786.630 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-09T23:46:26.637Z,1570664786.637 [MassServo](DEBUG): Initializing MassServo.
2019-10-09T23:46:26.638Z,1570664786.638 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-09T23:46:26.646Z,1570664786.646 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-09T23:46:26.646Z,1570664786.646 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-09T23:46:26.653Z,1570664786.653 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-09T23:46:26.875Z,1570664786.875 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-10-09T23:46:26.875Z,1570664786.875 [DropWeight] Hardware Fault, FailCount= 1
2019-10-09T23:46:26.875Z,1570664786.875 [DropWeight](ERROR): Hardware Fault
2019-10-09T23:46:26.944Z,1570664786.944 [CommandLine](FAULT): Scheduling is paused
2019-10-09T23:46:26.944Z,1570664786.944 [CBIT](INFO): Critical error at 20191009T234626
2019-10-09T23:46:26.945Z,1570664786.945 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-09T23:46:26.947Z,1570664786.947 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-09T23:46:26.948Z,1570664786.948 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-09T23:46:27.621Z,1570664787.621 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-09T23:46:27.621Z,1570664787.621 [RudderServo](FAULT): Rudder failed to initialize
2019-10-09T23:46:27.621Z,1570664787.621 [RudderServo] Communications Fault, FailCount= 1
2019-10-09T23:46:27.621Z,1570664787.621 [RudderServo](ERROR): Communications Fault
2019-10-09T23:46:27.730Z,1570664787.730 [CBIT](INFO): Critical error at 20191009T234626
2019-10-09T23:46:27.733Z,1570664787.733 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-09T23:46:27.954Z,1570664787.954 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-09T23:46:27.954Z,1570664787.954 [RudderServo](INFO): Powering down
2019-10-09T23:46:28.617Z,1570664788.617 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-09T23:46:28.738Z,1570664788.738 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-09T23:46:28.742Z,1570664788.742 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-09T23:46:28.742Z,1570664788.742 [RudderServo] No Fault, FailCount= 1
2019-10-09T23:46:29.969Z,1570664789.969 [Aanderaa_O2](INFO): Powering down
2019-10-09T23:46:36.346Z,1570664796.346 [CBIT](CRITICAL): Environmental Failure. Press:14.649905 PSI. Humidity:37%. Temp:24 C. ABORTING MISSION
2019-10-09T23:46:36.757Z,1570664796.757 [CBIT](INFO): Critical error at 20191009T234636
2019-10-09T23:46:49.659Z,1570664809.659 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-09T23:46:49.671Z,1570664809.671 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-09T23:46:52.954Z,1570664812.954 [NAL9602](INFO): Powering up NAL9602
2019-10-09T23:47:00.687Z,1570664820.687 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.000821
CHAN A2 (12V): -0.001941
CHAN A3 (5V): -0.001350
CHAN B0 (3.3V): -0.000654
CHAN B1 (3.15aV): -0.000084
CHAN B2 (3.15bV): -0.000148
CHAN B3 (GND): 0.000040
OPEN: -0.000464
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-09T23:47:03.862Z,1570664823.862 [NAL9602](INFO): NAL9602 initialized
2019-10-09T23:47:04.677Z,1570664824.677 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:47:42.941Z,1570664862.941 [SBIT](IMPORTANT): SBIT PASSED
2019-10-09T23:47:43.002Z,1570664863.002 [CommandLine](IMPORTANT): got command configSet list
2019-10-09T23:47:43.002Z,1570664863.002 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-09T23:47:43.003Z,1570664863.003 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere;

2019-10-09T23:47:43.003Z,1570664863.003 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-10-09T23:47:43.003Z,1570664863.003 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-10-09T23:47:43.004Z,1570664863.004 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-10-09T23:47:43.351Z,1570664863.351 [MissionManager](IMPORTANT): Started mission Startup
2019-10-09T23:47:43.351Z,1570664863.351 [Startup] Running Loop=1
2019-10-09T23:47:43.351Z,1570664863.351 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-09T23:47:43.351Z,1570664863.351 [Startup:A.GoToSurface] Running Loop=1
2019-10-09T23:47:43.352Z,1570664863.352 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T23:47:43.352Z,1570664863.352 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T23:47:43.354Z,1570664863.354 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T23:47:43.354Z,1570664863.354 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T23:47:43.354Z,1570664863.354 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T23:47:43.355Z,1570664863.355 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T23:47:43.356Z,1570664863.356 [Startup:StartupSatComms] Running Loop=1
2019-10-09T23:47:43.356Z,1570664863.356 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-09T23:47:43.356Z,1570664863.356 [Startup:StartupSatComms:A] Running Loop=1
2019-10-09T23:47:43.762Z,1570664863.762 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-09T23:48:43.533Z,1570664923.533 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-09T23:47:43.4Z
2019-10-09T23:48:43.534Z,1570664923.534 [Startup:StartupSatComms:A] Stopped
2019-10-09T23:48:43.534Z,1570664923.534 [Startup:StartupSatComms:B] Running Loop=1
2019-10-09T23:48:43.942Z,1570664923.942 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-09T23:48:46.833Z,1570664926.833 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-10-09T23:49:10.171Z,1570664950.171 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005157
2019-10-09T23:49:17.449Z,1570664957.449 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191009T234120/Courier0004.lzma
2019-10-09T23:49:19.456Z,1570664959.456 [DataOverHttps](INFO): Moved sent file to Logs/20191009T234120/Courier0004.lzma.bak
2019-10-09T23:49:19.456Z,1570664959.456 [DataOverHttps](INFO): SBD MOMSN=11868092
2019-10-09T23:49:26.362Z,1570664966.362 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-09T23:49:26.362Z,1570664966.362 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-09T23:49:26.372Z,1570664966.372 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-09T23:49:26.776Z,1570664966.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-09T23:49:26.776Z,1570664966.776 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-09T23:49:36.369Z,1570664976.369 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20191009T234615/Courier0000.lzma
2019-10-09T23:49:38.374Z,1570664978.374 [DataOverHttps](INFO): Moved sent file to Logs/20191009T234615/Courier0000.lzma.bak
2019-10-09T23:49:38.374Z,1570664978.374 [DataOverHttps](INFO): SBD MOMSN=11868097
2019-10-09T23:49:43.746Z,1570664983.746 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-09T23:48:43.5Z
2019-10-09T23:49:43.746Z,1570664983.746 [Startup:StartupSatComms:B] Stopped
2019-10-09T23:49:43.746Z,1570664983.746 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-09T23:49:43.746Z,1570664983.746 [Startup:StartupSatComms] Stopped
2019-10-09T23:49:43.746Z,1570664983.746 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-09T23:49:43.747Z,1570664983.747 [Startup](INFO): Completed Startup
2019-10-09T23:49:43.747Z,1570664983.747 [MissionManager](INFO): Startup is completed.
2019-10-09T23:49:43.747Z,1570664983.747 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-09T23:49:43.747Z,1570664983.747 [Startup] Stopped
2019-10-09T23:49:43.748Z,1570664983.748 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-09T23:49:43.748Z,1570664983.748 [Startup:A.GoToSurface] Stopped
2019-10-09T23:49:43.748Z,1570664983.748 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-09T23:49:44.162Z,1570664984.162 [MissionManager](IMPORTANT): Started mission Default
2019-10-09T23:49:44.162Z,1570664984.162 [Default] Running Loop=1
2019-10-09T23:49:44.162Z,1570664984.162 [Default](DEBUG): Aggregate::initialize Default
2019-10-09T23:49:44.162Z,1570664984.162 [Default:B.GoToSurface] Running Loop=1
2019-10-09T23:49:44.162Z,1570664984.162 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T23:49:44.163Z,1570664984.163 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T23:49:44.163Z,1570664984.163 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T23:49:44.163Z,1570664984.163 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T23:49:44.164Z,1570664984.164 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T23:49:44.164Z,1570664984.164 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T23:49:44.164Z,1570664984.164 [Default:A.Wait] Running Loop=1
2019-10-09T23:49:44.164Z,1570664984.164 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T23:49:55.128Z,1570664995.128 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20191009T234120/Express0005.lzma
2019-10-09T23:49:57.134Z,1570664997.134 [DataOverHttps](INFO): Moved sent file to Logs/20191009T234120/Express0005.lzma.bak
2019-10-09T23:49:57.134Z,1570664997.134 [DataOverHttps](INFO): SBD MOMSN=11868103
2019-10-09T23:49:57.460Z,1570664997.460 [Default:A.Wait](INFO): Done Waiting.
2019-10-09T23:49:57.460Z,1570664997.460 [Default:A.Wait] Stopped
2019-10-09T23:49:57.460Z,1570664997.460 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T23:49:57.871Z,1570664997.871 [Default:CheckIn] Running Loop=1
2019-10-09T23:49:57.872Z,1570664997.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-09T23:49:57.872Z,1570664997.872 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-09T23:49:58.279Z,1570664998.279 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-09T23:52:07.533Z,1570665127.533 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-09T23:52:08.341Z,1570665128.341 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:12.788Z,1570665132.788 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:14.805Z,1570665134.805 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:16.829Z,1570665136.829 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:20.061Z,1570665140.061 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:22.905Z,1570665142.905 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:26.125Z,1570665146.125 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:27.376Z,1570665147.376 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-09T23:52:27.376Z,1570665147.376 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-09T23:52:27.385Z,1570665147.385 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-09T23:52:27.777Z,1570665147.777 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-09T23:52:27.777Z,1570665147.777 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-09T23:52:28.957Z,1570665148.957 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:32.185Z,1570665152.185 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:35.023Z,1570665155.023 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:37.853Z,1570665157.853 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:39.871Z,1570665159.871 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:43.097Z,1570665163.097 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:45.921Z,1570665165.921 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:49.157Z,1570665169.157 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:51.981Z,1570665171.981 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:55.213Z,1570665175.213 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:52:58.045Z,1570665178.045 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:00.872Z,1570665180.872 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:02.893Z,1570665182.893 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:04.909Z,1570665184.909 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:08.141Z,1570665188.141 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:10.976Z,1570665190.976 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:14.205Z,1570665194.205 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:17.037Z,1570665197.037 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:20.269Z,1570665200.269 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:23.089Z,1570665203.089 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:25.921Z,1570665205.921 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:27.937Z,1570665207.937 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:31.173Z,1570665211.173 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:33.997Z,1570665213.997 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:37.233Z,1570665217.233 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:40.061Z,1570665220.061 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:43.301Z,1570665223.301 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:46.117Z,1570665226.117 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:48.945Z,1570665228.945 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:50.965Z,1570665230.965 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:54.201Z,1570665234.201 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:53:55.434Z,1570665235.434 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-10-09T23:53:55.437Z,1570665235.437 [BPC1](INFO): Received data from all battery sticks.
2019-10-09T23:53:57.028Z,1570665237.028 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:00.265Z,1570665240.265 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:03.093Z,1570665243.093 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:06.321Z,1570665246.321 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:09.145Z,1570665249.145 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:11.981Z,1570665251.981 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:14.000Z,1570665254.000 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:16.013Z,1570665256.013 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:19.249Z,1570665259.249 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:22.081Z,1570665262.081 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:25.305Z,1570665265.305 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:28.149Z,1570665268.149 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:31.369Z,1570665271.369 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:34.197Z,1570665274.197 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:37.021Z,1570665277.021 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:39.041Z,1570665279.041 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:41.073Z,1570665281.073 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:44.297Z,1570665284.297 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:47.125Z,1570665287.125 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:50.353Z,1570665290.353 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:53.197Z,1570665293.197 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:56.421Z,1570665296.421 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:54:58.051Z,1570665298.051 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-09T23:49:57.9Z
2019-10-09T23:54:58.051Z,1570665298.051 [Default:CheckIn:Read_GPS] Stopped
2019-10-09T23:54:58.051Z,1570665298.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-09T23:54:58.463Z,1570665298.463 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-09T23:54:58.857Z,1570665298.857 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20191009T234615/Courier0004.lzma
2019-10-09T23:54:59.289Z,1570665299.289 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:00.538Z,1570665300.538 [DataOverHttps](INFO): Moved sent file to Logs/20191009T234615/Courier0004.lzma.bak
2019-10-09T23:55:00.538Z,1570665300.538 [DataOverHttps](INFO): SBD MOMSN=11868111
2019-10-09T23:55:02.113Z,1570665302.113 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:05.341Z,1570665305.341 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:08.169Z,1570665308.169 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:11.411Z,1570665311.411 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:14.233Z,1570665314.233 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:17.110Z,1570665317.110 [DataOverHttps](INFO): Sending 707 bytes from file Logs/20191009T234615/Express0001.lzma
2019-10-09T23:55:17.465Z,1570665317.465 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:19.114Z,1570665319.114 [DataOverHttps](INFO): Moved sent file to Logs/20191009T234615/Express0001.lzma.bak
2019-10-09T23:55:19.115Z,1570665319.115 [DataOverHttps](INFO): SBD MOMSN=11868113
2019-10-09T23:55:20.289Z,1570665320.289 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:23.121Z,1570665323.121 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:26.353Z,1570665326.353 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:28.393Z,1570665328.393 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-10-09T23:55:28.393Z,1570665328.393 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-09T23:55:28.403Z,1570665328.403 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-09T23:55:28.808Z,1570665328.808 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-09T23:55:28.809Z,1570665328.809 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-10-09T23:55:29.177Z,1570665329.177 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:32.409Z,1570665332.409 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:35.253Z,1570665335.253 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:35.800Z,1570665335.800 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20191009T234615/Express0005.lzma
2019-10-09T23:55:37.807Z,1570665337.807 [DataOverHttps](INFO): Moved sent file to Logs/20191009T234615/Express0005.lzma.bak
2019-10-09T23:55:37.807Z,1570665337.807 [DataOverHttps](INFO): SBD MOMSN=11868134
2019-10-09T23:55:38.481Z,1570665338.481 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:39.314Z,1570665339.314 [Default:CheckIn:Read_Iridium] Stopped
2019-10-09T23:55:39.314Z,1570665339.314 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-09T23:55:39.314Z,1570665339.314 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-09T23:55:41.309Z,1570665341.309 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:43.313Z,1570665343.313 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-10-09T23:55:43.313Z,1570665343.313 [AHRS_M2] Data Fault, FailCount= 1
2019-10-09T23:55:43.313Z,1570665343.313 [AHRS_M2](ERROR): Data Fault
2019-10-09T23:55:43.410Z,1570665343.410 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-10-09T23:55:43.704Z,1570665343.704 [AHRS_M2](INFO): Powering down
2019-10-09T23:55:44.129Z,1570665344.129 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:44.567Z,1570665344.567 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-09T23:55:44.567Z,1570665344.567 [AHRS_M2] No Fault, FailCount= 1
2019-10-09T23:55:44.916Z,1570665344.916 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-09T23:55:46.141Z,1570665346.141 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:48.473Z,1570665348.473 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:55:48.498Z,1570665348.498 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:48.877Z,1570665348.877 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:55:49.037Z,1570665349.037 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:55:49.449Z,1570665349.449 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:55:49.841Z,1570665349.841 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:55:50.245Z,1570665350.245 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:55:50.673Z,1570665350.673 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:55:51.077Z,1570665351.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:55:51.417Z,1570665351.417 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:54.241Z,1570665354.241 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:55:57.473Z,1570665357.473 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:00.301Z,1570665360.301 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:01.493Z,1570665361.493 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-10-09T23:56:01.493Z,1570665361.493 [AHRS_M2] Data Fault, FailCount= 2
2019-10-09T23:56:01.493Z,1570665361.493 [AHRS_M2](ERROR): Data Fault
2019-10-09T23:56:01.562Z,1570665361.562 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-10-09T23:56:01.884Z,1570665361.884 [AHRS_M2](INFO): Powering down
2019-10-09T23:56:02.735Z,1570665362.735 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-09T23:56:02.735Z,1570665362.735 [AHRS_M2] No Fault, FailCount= 2
2019-10-09T23:56:03.096Z,1570665363.096 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-09T23:56:03.517Z,1570665363.517 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:06.405Z,1570665366.405 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:06.425Z,1570665366.425 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:06.829Z,1570665366.829 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:07.233Z,1570665367.233 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:07.637Z,1570665367.637 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:08.033Z,1570665368.033 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:08.445Z,1570665368.445 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:08.849Z,1570665368.849 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:09.253Z,1570665369.253 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:09.281Z,1570665369.281 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:09.649Z,1570665369.649 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:10.061Z,1570665370.061 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:10.465Z,1570665370.465 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:10.869Z,1570665370.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:11.501Z,1570665371.501 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:11.905Z,1570665371.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:12.085Z,1570665372.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:12.101Z,1570665372.101 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:12.713Z,1570665372.713 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:13.129Z,1570665373.129 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:13.573Z,1570665373.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:13.997Z,1570665373.997 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:14.413Z,1570665374.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:14.813Z,1570665374.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:15.213Z,1570665375.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:15.229Z,1570665375.229 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:15.621Z,1570665375.621 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:16.025Z,1570665376.025 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:16.025Z,1570665376.025 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-09T23:56:16.025Z,1570665376.025 [AHRS_M2] Communications Fault, FailCount= 3
2019-10-09T23:56:16.025Z,1570665376.025 [AHRS_M2](ERROR): Communications Fault
2019-10-09T23:56:16.104Z,1570665376.104 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-09T23:56:16.110Z,1570665376.110 [AHRS_M2](INFO): Powering down
2019-10-09T23:56:16.952Z,1570665376.952 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-09T23:56:16.952Z,1570665376.952 [AHRS_M2] No Fault, FailCount= 3
2019-10-09T23:56:17.320Z,1570665377.320 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-09T23:56:18.549Z,1570665378.549 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:20.877Z,1570665380.877 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:21.281Z,1570665381.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:21.685Z,1570665381.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:21.705Z,1570665381.705 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:22.097Z,1570665382.097 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:22.501Z,1570665382.501 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:22.929Z,1570665382.929 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:23.329Z,1570665383.329 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:23.721Z,1570665383.721 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:24.125Z,1570665384.125 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:24.545Z,1570665384.545 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:24.561Z,1570665384.561 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:24.957Z,1570665384.957 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:25.365Z,1570665385.365 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:25.761Z,1570665385.761 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:26.173Z,1570665386.173 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:26.577Z,1570665386.577 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:26.985Z,1570665386.985 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:27.385Z,1570665387.385 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:27.401Z,1570665387.401 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:27.789Z,1570665387.789 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:28.193Z,1570665388.193 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:28.605Z,1570665388.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:29.001Z,1570665389.001 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:29.405Z,1570665389.405 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:29.813Z,1570665389.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:30.213Z,1570665390.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:30.213Z,1570665390.213 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-09T23:56:30.213Z,1570665390.213 [AHRS_M2] Communications Fault, FailCount= 4
2019-10-09T23:56:30.213Z,1570665390.213 [AHRS_M2](ERROR): Communications Fault
2019-10-09T23:56:30.233Z,1570665390.233 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:30.290Z,1570665390.290 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-09T23:56:30.295Z,1570665390.295 [AHRS_M2](INFO): Powering down
2019-10-09T23:56:31.148Z,1570665391.148 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-09T23:56:31.148Z,1570665391.148 [AHRS_M2] No Fault, FailCount= 4
2019-10-09T23:56:31.504Z,1570665391.504 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-09T23:56:32.328Z,1570665392.328 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:35.061Z,1570665395.061 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:35.469Z,1570665395.469 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:35.869Z,1570665395.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:35.889Z,1570665395.889 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:36.269Z,1570665396.269 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:36.677Z,1570665396.677 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:37.077Z,1570665397.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:37.481Z,1570665397.481 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:37.885Z,1570665397.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:38.289Z,1570665398.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:38.310Z,1570665398.310 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:38.697Z,1570665398.697 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:39.101Z,1570665399.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:39.517Z,1570665399.517 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:39.905Z,1570665399.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:40.305Z,1570665400.305 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:40.717Z,1570665400.717 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:41.121Z,1570665401.121 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:41.513Z,1570665401.513 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:41.549Z,1570665401.549 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:41.945Z,1570665401.945 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:42.369Z,1570665402.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:42.785Z,1570665402.785 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:43.193Z,1570665403.193 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:43.597Z,1570665403.597 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:44.009Z,1570665404.009 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:44.417Z,1570665404.417 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-09T23:56:44.417Z,1570665404.417 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-09T23:56:44.417Z,1570665404.417 [AHRS_M2] Communications Fault, FailCount= 5
2019-10-09T23:56:44.417Z,1570665404.417 [AHRS_M2](ERROR): Communications Fault
2019-10-09T23:56:44.433Z,1570665404.433 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:44.522Z,1570665404.522 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-09T23:56:44.522Z,1570665404.522 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-10-09T23:56:44.531Z,1570665404.531 [AHRS_M2](INFO): Powering down
2019-10-09T23:56:44.688Z,1570665404.688 [CBIT](INFO): Critical error at 20191009T235644
2019-10-09T23:56:46.557Z,1570665406.557 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:49.389Z,1570665409.389 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:52.617Z,1570665412.617 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:55.449Z,1570665415.449 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:56:58.277Z,1570665418.277 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:57:01.509Z,1570665421.509 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:57:04.337Z,1570665424.337 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:57:04.732Z,1570665424.732 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-09T23:57:04.732Z,1570665424.732 [NAL9602] Data Fault, FailCount= 1
2019-10-09T23:57:04.732Z,1570665424.732 [NAL9602](ERROR): Data Fault
2019-10-09T23:57:04.760Z,1570665424.760 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-09T23:57:05.130Z,1570665425.130 [NAL9602](INFO): Powering down
2019-10-09T23:57:05.968Z,1570665425.968 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-09T23:57:05.968Z,1570665425.968 [NAL9602] No Fault, FailCount= 1
2019-10-09T23:57:35.430Z,1570665455.430 [NAL9602](INFO): Powering up NAL9602
2019-10-09T23:57:46.338Z,1570665466.338 [NAL9602](INFO): NAL9602 initialized
2019-10-09T23:57:49.181Z,1570665469.181 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:57:52.009Z,1570665472.009 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:57:54.837Z,1570665474.837 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:57:58.069Z,1570665478.069 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:00.897Z,1570665480.897 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:01.328Z,1570665481.328 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-09T23:58:01.329Z,1570665481.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-10-09T23:58:01.329Z,1570665481.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-09T23:58:01.339Z,1570665481.339 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-09T23:58:01.729Z,1570665481.729 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-09T23:58:01.729Z,1570665481.729 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-10-09T23:58:04.125Z,1570665484.125 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:06.961Z,1570665486.961 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:10.193Z,1570665490.193 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:13.017Z,1570665493.017 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:15.841Z,1570665495.841 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:17.861Z,1570665497.861 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:19.881Z,1570665499.881 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:23.113Z,1570665503.113 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:25.945Z,1570665505.945 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:29.177Z,1570665509.177 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:32.009Z,1570665512.009 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:35.237Z,1570665515.237 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:38.065Z,1570665518.065 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:40.892Z,1570665520.892 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:42.909Z,1570665522.909 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:46.141Z,1570665526.141 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:48.973Z,1570665528.973 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:52.205Z,1570665532.205 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:55.029Z,1570665535.029 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:58:58.261Z,1570665538.261 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:01.089Z,1570665541.089 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:03.921Z,1570665543.921 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:05.941Z,1570665545.941 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:07.960Z,1570665547.960 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:11.197Z,1570665551.197 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:14.025Z,1570665554.025 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:17.253Z,1570665557.253 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:20.081Z,1570665560.081 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:23.309Z,1570665563.309 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:26.137Z,1570665566.137 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:28.969Z,1570665568.969 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:30.985Z,1570665570.985 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:34.221Z,1570665574.221 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:37.049Z,1570665577.049 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:40.277Z,1570665580.277 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:43.105Z,1570665583.105 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:46.341Z,1570665586.341 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:49.169Z,1570665589.169 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:51.993Z,1570665591.993 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:54.017Z,1570665594.017 [NAL9602](DEBUG): Fix Requested
2019-10-09T23:59:57.245Z,1570665597.245 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:00.073Z,1570665600.073 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:02.513Z,1570665602.513 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-10T00:00:02.513Z,1570665602.513 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-10-10T00:00:02.513Z,1570665602.513 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-10T00:00:02.523Z,1570665602.523 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-10T00:00:02.921Z,1570665602.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-10T00:00:02.922Z,1570665602.922 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-10-10T00:00:03.308Z,1570665603.308 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:06.137Z,1570665606.137 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:09.369Z,1570665609.369 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:12.197Z,1570665612.197 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:15.021Z,1570665615.021 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:17.045Z,1570665617.045 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:19.061Z,1570665619.061 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:22.297Z,1570665622.297 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:25.129Z,1570665625.129 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:28.357Z,1570665628.357 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:31.181Z,1570665631.181 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:34.417Z,1570665634.417 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:37.249Z,1570665637.249 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:40.073Z,1570665640.073 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:40.105Z,1570665640.105 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-10T00:00:40.105Z,1570665640.105 [Default:CheckIn:C.Wait] Stopped
2019-10-10T00:00:40.105Z,1570665640.105 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-10T00:00:40.106Z,1570665640.106 [Default:CheckIn:D] Running Loop=1
2019-10-10T00:00:40.497Z,1570665640.497 [Default:CheckIn:D] Stopped
2019-10-10T00:00:40.497Z,1570665640.497 [Default:CheckIn:E] Running Loop=1
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.938905 min 
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn:E] Stopped
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn] Stopped
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn](INFO): Running loop #2
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn] Running Loop=2
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-10T00:00:40.903Z,1570665640.903 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-10T00:00:42.093Z,1570665642.093 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:45.321Z,1570665645.321 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:48.149Z,1570665648.149 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:51.385Z,1570665651.385 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:54.217Z,1570665654.217 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:00:57.445Z,1570665657.445 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:00.269Z,1570665660.269 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:03.105Z,1570665663.105 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:05.117Z,1570665665.117 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:07.141Z,1570665667.141 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:10.369Z,1570665670.369 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:13.205Z,1570665673.205 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:16.433Z,1570665676.433 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:19.261Z,1570665679.261 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:22.493Z,1570665682.493 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:25.325Z,1570665685.325 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:26.959Z,1570665686.959 [CBIT](INFO): Clearing failed state for component DropWeight
2019-10-10T00:01:26.959Z,1570665686.959 [DropWeight] No Fault, FailCount= 1
2019-10-10T00:01:28.149Z,1570665688.149 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:30.169Z,1570665690.169 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:33.401Z,1570665693.401 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:36.229Z,1570665696.229 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:39.457Z,1570665699.457 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:42.297Z,1570665702.297 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:44.750Z,1570665704.750 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-10T00:01:44.750Z,1570665704.750 [AHRS_M2] No Fault, FailCount= 5
2019-10-10T00:01:45.100Z,1570665705.100 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-10T00:01:45.521Z,1570665705.521 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:48.657Z,1570665708.657 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:48.691Z,1570665708.691 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:49.069Z,1570665709.069 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:49.465Z,1570665709.465 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:49.869Z,1570665709.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:50.281Z,1570665710.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:50.685Z,1570665710.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:51.105Z,1570665711.105 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:51.505Z,1570665711.505 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:51.525Z,1570665711.525 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:51.917Z,1570665711.917 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:52.321Z,1570665712.321 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:52.721Z,1570665712.721 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:53.125Z,1570665713.125 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:53.529Z,1570665713.529 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:53.933Z,1570665713.933 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:54.337Z,1570665714.337 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:54.357Z,1570665714.357 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:54.745Z,1570665714.745 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:55.145Z,1570665715.145 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:55.537Z,1570665715.537 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:55.949Z,1570665715.949 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:56.353Z,1570665716.353 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:56.761Z,1570665716.761 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:57.161Z,1570665717.161 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:57.189Z,1570665717.189 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:01:57.569Z,1570665717.569 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:57.975Z,1570665717.975 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:01:57.975Z,1570665717.975 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-10T00:01:57.975Z,1570665717.975 [AHRS_M2] Communications Fault, FailCount= 1
2019-10-10T00:01:57.975Z,1570665717.975 [AHRS_M2](ERROR): Communications Fault
2019-10-10T00:01:58.051Z,1570665718.051 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-10T00:01:58.064Z,1570665718.064 [AHRS_M2](INFO): Powering down
2019-10-10T00:01:58.902Z,1570665718.902 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-10T00:01:58.902Z,1570665718.902 [AHRS_M2] No Fault, FailCount= 1
2019-10-10T00:01:59.264Z,1570665719.264 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-10T00:01:59.285Z,1570665719.285 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:02:02.821Z,1570665722.821 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:02.842Z,1570665722.842 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:02:03.237Z,1570665723.237 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:03.645Z,1570665723.645 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:03.694Z,1570665723.694 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-10T00:02:03.694Z,1570665723.694 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-10-10T00:02:03.694Z,1570665723.694 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-10T00:02:03.705Z,1570665723.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-10T00:02:04.049Z,1570665724.049 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:04.106Z,1570665724.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-10T00:02:04.106Z,1570665724.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-10-10T00:02:04.437Z,1570665724.437 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:04.837Z,1570665724.837 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:05.241Z,1570665725.241 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:05.261Z,1570665725.261 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:02:05.645Z,1570665725.645 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:06.049Z,1570665726.049 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:06.457Z,1570665726.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:06.873Z,1570665726.873 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:07.265Z,1570665727.265 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:07.669Z,1570665727.669 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:08.081Z,1570665728.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:08.489Z,1570665728.489 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:08.505Z,1570665728.505 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:02:08.901Z,1570665728.901 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:09.317Z,1570665729.317 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:09.729Z,1570665729.729 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:10.122Z,1570665730.122 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:10.529Z,1570665730.529 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:10.941Z,1570665730.941 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:11.349Z,1570665731.349 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:11.369Z,1570665731.369 [NAL9602](DEBUG): Fix Requested
2019-10-10T00:02:11.445Z,1570665731.445 [CommandLine](IMPORTANT): got command quit
2019-10-10T00:02:11.765Z,1570665731.765 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:12.181Z,1570665732.181 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-10T00:02:12.181Z,1570665732.181 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-10T00:02:12.181Z,1570665732.181 [AHRS_M2] Communications Fault, FailCount= 2
2019-10-10T00:02:12.181Z,1570665732.181 [AHRS_M2](ERROR): Communications Fault
2019-10-10T00:02:12.238Z,1570665732.238 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-10T00:02:12.273Z,1570665732.273 [AHRS_M2](INFO): Powering down
2019-10-10T00:02:12.449Z,1570665732.449 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-10T00:02:12.449Z,1570665732.449 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:12.505Z,1570665732.505 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-10T00:02:12.505Z,1570665732.505 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:12.506Z,1570665732.506 [CommandLine](INFO): Join timeout helper Thread ID is 5008
2019-10-10T00:02:12.506Z,1570665732.506 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-10T00:02:12.506Z,1570665732.506 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:12.507Z,1570665732.507 [NavChartDb](INFO): Join timeout helper Thread ID is 5009
2019-10-10T00:02:12.693Z,1570665732.693 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-10T00:02:12.693Z,1570665732.693 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:12.705Z,1570665732.705 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-10T00:02:12.705Z,1570665732.705 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:12.705Z,1570665732.705 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5010
2019-10-10T00:02:12.945Z,1570665732.945 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-10T00:02:12.946Z,1570665732.946 [WetLabsBB2FL](INFO): Powering down
2019-10-10T00:02:12.946Z,1570665732.946 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:12.965Z,1570665732.965 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-10T00:02:12.965Z,1570665732.965 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:12.965Z,1570665732.965 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5011
2019-10-10T00:02:13.112Z,1570665733.112 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-10T00:02:13.112Z,1570665733.112 [AHRS_M2] No Fault, FailCount= 2
2019-10-10T00:02:13.125Z,1570665733.125 [CTD_Seabird](INFO): Powering down
2019-10-10T00:02:13.137Z,1570665733.137 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-10T00:02:13.137Z,1570665733.137 [CTD_Seabird](INFO): Powering down
2019-10-10T00:02:13.149Z,1570665733.149 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.169Z,1570665733.169 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-10T00:02:13.169Z,1570665733.169 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.169Z,1570665733.169 [Radio_Surface](INFO): Join timeout helper Thread ID is 5012
2019-10-10T00:02:13.337Z,1570665733.337 [Radio_Surface](INFO): Powering down
2019-10-10T00:02:13.338Z,1570665733.338 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-10T00:02:13.338Z,1570665733.338 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.358Z,1570665733.358 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-10T00:02:13.358Z,1570665733.358 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.358Z,1570665733.358 [DataOverHttps](INFO): Join timeout helper Thread ID is 5013
2019-10-10T00:02:13.457Z,1570665733.457 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-10T00:02:13.457Z,1570665733.457 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.468Z,1570665733.468 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-10T00:02:13.478Z,1570665733.478 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-10T00:02:13.478Z,1570665733.478 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.478Z,1570665733.478 [logger](INFO): Join timeout helper Thread ID is 5015
2019-10-10T00:02:13.505Z,1570665733.505 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-10T00:02:13.505Z,1570665733.505 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.521Z,1570665733.521 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-10T00:02:13.522Z,1570665733.522 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.522Z,1570665733.522 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-10T00:02:13.522Z,1570665733.522 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:13.522Z,1570665733.522 [controlThread](INFO): Join timeout helper Thread ID is 5016
2019-10-10T00:02:13.869Z,1570665733.869 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-10T00:02:13.869Z,1570665733.869 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-10T00:02:13.870Z,1570665733.870 [AHRS_M2](INFO): Powering down
2019-10-10T00:02:13.941Z,1570665733.941 [NAL9602](INFO): Powering down
2019-10-10T00:02:13.943Z,1570665733.943 [Aanderaa_O2](INFO): Powering down
2019-10-10T00:02:13.944Z,1570665733.944 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-10T00:02:13.945Z,1570665733.945 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-10T00:02:13.946Z,1570665733.946 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-10T00:02:13.946Z,1570665733.946 [MissionManager](INFO): Uninitializing Mission Default
2019-10-10T00:02:13.946Z,1570665733.946 [Default] Stopped
2019-10-10T00:02:13.946Z,1570665733.946 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-10T00:02:13.946Z,1570665733.946 [Default:B.GoToSurface] Stopped
2019-10-10T00:02:13.947Z,1570665733.947 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-10T00:02:13.947Z,1570665733.947 [Default:CheckIn] Stopped
2019-10-10T00:02:13.947Z,1570665733.947 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-10T00:02:13.947Z,1570665733.947 [Default:CheckIn:Read_GPS] Stopped
2019-10-10T00:02:13.949Z,1570665733.949 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-10T00:02:13.949Z,1570665733.949 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-10T00:02:13.950Z,1570665733.950 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-10T00:02:13.950Z,1570665733.950 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-10T00:02:13.950Z,1570665733.950 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-10T00:02:13.950Z,1570665733.950 [BuoyancyServo](INFO): Powering down
2019-10-10T00:02:13.965Z,1570665733.965 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-10T00:02:13.965Z,1570665733.965 [ElevatorServo](INFO): Powering down
2019-10-10T00:02:13.966Z,1570665733.966 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-10T00:02:13.966Z,1570665733.966 [MassServo](INFO): Powering down
2019-10-10T00:02:13.967Z,1570665733.967 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-10T00:02:13.967Z,1570665733.967 [RudderServo](INFO): Powering down
2019-10-10T00:02:13.968Z,1570665733.968 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-10T00:02:13.968Z,1570665733.968 [ThrusterServo](INFO): Powering down
2019-10-10T00:02:13.968Z,1570665733.968 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-10T00:02:13.969Z,1570665733.969 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-10T00:02:13.969Z,1570665733.969 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-10T00:02:13.969Z,1570665733.969 [CBIT](DEBUG): Powering off loads.
2019-10-10T00:02:13.981Z,1570665733.981 [CBIT](DEBUG): Disabling WDT.
2019-10-10T00:02:13.993Z,1570665733.993 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-10T00:02:13.994Z,1570665733.994 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:14.073Z,1570665734.073 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:14.079Z,1570665734.079 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:14.109Z,1570665734.109 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:14.111Z,1570665734.111 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:14.146Z,1570665734.146 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-10T00:02:14.204Z,1570665734.204 [logger ThreadHandler](INFO): Thread cancelled.