2019-05-29T10:37:14.324Z,1559126234.324 [Supervisor](DEBUG): Initializing supervisor.
2019-05-29T10:37:14.327Z,1559126234.327 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-29T10:37:14.328Z,1559126234.328 [SyncHandler](INFO): Protected caller Thread ID is 4763
2019-05-29T10:37:14.328Z,1559126234.328 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-29T10:37:14.329Z,1559126234.329 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-29T10:37:14.329Z,1559126234.329 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4764
2019-05-29T10:37:14.332Z,1559126234.332 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-29T10:37:14.354Z,1559126234.354 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-29T10:37:14.355Z,1559126234.355 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-29T10:37:14.355Z,1559126234.355 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4765
2019-05-29T10:37:14.356Z,1559126234.356 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-29T10:37:14.357Z,1559126234.357 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-29T10:37:14.358Z,1559126234.358 [logger ThreadHandler](INFO): Protected caller Thread ID is 4766
2019-05-29T10:37:14.360Z,1559126234.360 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-29T10:37:14.360Z,1559126234.360 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-29T10:37:14.362Z,1559126234.362 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-29T10:37:14.458Z,1559126234.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-29T10:37:14.458Z,1559126234.458 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-29T10:37:15.007Z,1559126235.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-29T10:37:15.008Z,1559126235.008 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-29T10:37:15.108Z,1559126235.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-29T10:37:15.108Z,1559126235.108 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-29T10:37:15.213Z,1559126235.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-29T10:37:15.214Z,1559126235.214 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-29T10:37:15.295Z,1559126235.295 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-29T10:37:15.435Z,1559126235.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-29T10:37:15.435Z,1559126235.435 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-29T10:37:15.733Z,1559126235.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-29T10:37:15.734Z,1559126235.734 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-29T10:37:16.193Z,1559126236.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-29T10:37:16.194Z,1559126236.194 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-29T10:37:16.340Z,1559126236.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-29T10:37:16.341Z,1559126236.341 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-29T10:37:16.575Z,1559126236.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-29T10:37:16.575Z,1559126236.575 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-29T10:37:17.378Z,1559126237.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-29T10:37:17.378Z,1559126237.378 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-29T10:37:17.597Z,1559126237.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-29T10:37:17.598Z,1559126237.598 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-29T10:37:17.800Z,1559126237.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-29T10:37:17.801Z,1559126237.801 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-29T10:37:18.189Z,1559126238.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-29T10:37:18.189Z,1559126238.189 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-29T10:37:18.525Z,1559126238.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-29T10:37:18.527Z,1559126238.527 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-05-29T10:37:18.528Z,1559126238.528 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-05-29T10:37:18.611Z,1559126238.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-05-29T10:37:18.767Z,1559126238.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-05-29T10:37:18.877Z,1559126238.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-05-29T10:37:18.963Z,1559126238.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-05-29T10:37:19.059Z,1559126239.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-05-29T10:37:19.254Z,1559126239.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-05-29T10:37:19.482Z,1559126239.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-29T10:37:19.483Z,1559126239.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-05-29T10:37:19.577Z,1559126239.577 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-05-29T10:37:19.677Z,1559126239.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-05-29T10:37:19.795Z,1559126239.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-05-29T10:37:19.897Z,1559126239.897 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/LOGIN/
2019-05-29T10:37:19.898Z,1559126239.898 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-05-29T10:37:19.899Z,1559126239.899 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-29T10:37:19.967Z,1559126239.967 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-29T10:37:20.080Z,1559126240.080 [VerticalControl] Loaded
2019-05-29T10:37:20.080Z,1559126240.080 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-29T10:37:20.081Z,1559126240.081 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-29T10:37:20.148Z,1559126240.148 [HorizontalControl] Loaded
2019-05-29T10:37:20.148Z,1559126240.148 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-29T10:37:20.149Z,1559126240.149 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-29T10:37:20.155Z,1559126240.155 [SpeedControl] Loaded
2019-05-29T10:37:20.155Z,1559126240.155 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-29T10:37:20.156Z,1559126240.156 [LoopControl](DEBUG): Construct LoopControl.
2019-05-29T10:37:20.156Z,1559126240.156 [LoopControl] Loaded
2019-05-29T10:37:20.157Z,1559126240.157 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-29T10:37:20.157Z,1559126240.157 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-29T10:37:20.158Z,1559126240.158 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-29T10:37:20.198Z,1559126240.198 [DepthRateCalculator] Loaded
2019-05-29T10:37:20.199Z,1559126240.199 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-29T10:37:20.204Z,1559126240.204 [PitchRateCalculator] Loaded
2019-05-29T10:37:20.204Z,1559126240.204 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-29T10:37:20.220Z,1559126240.220 [SpeedCalculator] Loaded
2019-05-29T10:37:20.220Z,1559126240.220 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-29T10:37:20.241Z,1559126240.241 [TempGradientCalculator] Loaded
2019-05-29T10:37:20.241Z,1559126240.241 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-29T10:37:20.246Z,1559126240.246 [YawRateCalculator] Loaded
2019-05-29T10:37:20.246Z,1559126240.246 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-29T10:37:20.275Z,1559126240.275 [ElevatorOffsetCalculator] Loaded
2019-05-29T10:37:20.275Z,1559126240.275 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-29T10:37:20.275Z,1559126240.275 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-29T10:37:20.276Z,1559126240.276 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-29T10:37:20.302Z,1559126240.302 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-29T10:37:20.303Z,1559126240.303 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-29T10:37:20.371Z,1559126240.371 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-29T10:37:20.371Z,1559126240.371 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-29T10:37:20.663Z,1559126240.663 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-29T10:37:20.663Z,1559126240.663 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-29T10:37:20.761Z,1559126240.761 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-29T10:37:20.762Z,1559126240.762 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-29T10:37:21.139Z,1559126241.139 [AHRS_M2] Loaded
2019-05-29T10:37:21.139Z,1559126241.139 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-05-29T10:37:21.153Z,1559126241.153 [Depth_Keller] Loaded
2019-05-29T10:37:21.153Z,1559126241.153 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-29T10:37:21.158Z,1559126241.158 [DropWeight] Loaded
2019-05-29T10:37:21.158Z,1559126241.158 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-05-29T10:37:21.210Z,1559126241.210 [Micromodem] Loaded
2019-05-29T10:37:21.210Z,1559126241.210 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-05-29T10:37:21.307Z,1559126241.307 [NAL9602] Loaded
2019-05-29T10:37:21.307Z,1559126241.307 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-29T10:37:21.323Z,1559126241.323 [Onboard] Loaded
2019-05-29T10:37:21.324Z,1559126241.324 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-29T10:37:21.331Z,1559126241.331 [Radio_Surface] Loaded
2019-05-29T10:37:21.331Z,1559126241.331 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-29T10:37:21.332Z,1559126241.332 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2019-05-29T10:37:21.332Z,1559126241.332 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4845
2019-05-29T10:37:23.254Z,1559126243.254 [BPC1] Loaded
2019-05-29T10:37:23.255Z,1559126243.255 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-29T10:37:23.255Z,1559126243.255 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-29T10:37:23.256Z,1559126243.256 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-29T10:37:23.359Z,1559126243.359 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-29T10:37:23.359Z,1559126243.359 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-29T10:37:23.380Z,1559126243.380 [NavChart] Loaded
2019-05-29T10:37:23.380Z,1559126243.380 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-29T10:37:23.385Z,1559126243.385 [UniversalFixResidualReporter] Loaded
2019-05-29T10:37:23.385Z,1559126243.385 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-29T10:37:23.385Z,1559126243.385 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-29T10:37:23.386Z,1559126243.386 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-29T10:37:23.523Z,1559126243.523 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-29T10:37:23.534Z,1559126243.534 [SBIT] Loaded
2019-05-29T10:37:23.534Z,1559126243.534 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-29T10:37:23.535Z,1559126243.535 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-29T10:37:23.547Z,1559126243.547 [IBIT] Loaded
2019-05-29T10:37:23.547Z,1559126243.547 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-29T10:37:23.550Z,1559126243.550 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-29T10:37:23.687Z,1559126243.687 [CBIT] Loaded
2019-05-29T10:37:23.688Z,1559126243.688 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-29T10:37:23.688Z,1559126243.688 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-29T10:37:23.689Z,1559126243.689 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-29T10:37:23.791Z,1559126243.791 [BuoyancyServo] Loaded
2019-05-29T10:37:23.791Z,1559126243.791 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-29T10:37:23.803Z,1559126243.803 [ElevatorServo] Loaded
2019-05-29T10:37:23.803Z,1559126243.803 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-29T10:37:23.814Z,1559126243.814 [MassServo] Loaded
2019-05-29T10:37:23.814Z,1559126243.814 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-29T10:37:23.825Z,1559126243.825 [RudderServo] Loaded
2019-05-29T10:37:23.826Z,1559126243.826 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-29T10:37:23.836Z,1559126243.836 [ThrusterServo] Loaded
2019-05-29T10:37:23.837Z,1559126243.837 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-29T10:37:23.837Z,1559126243.837 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-29T10:37:23.838Z,1559126243.838 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-29T10:37:23.850Z,1559126243.850 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-29T10:37:23.851Z,1559126243.851 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-29T10:37:24.098Z,1559126244.098 [CTD_NeilBrown] Loaded
2019-05-29T10:37:24.098Z,1559126244.098 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-29T10:37:24.099Z,1559126244.099 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 40A4C4E0
2019-05-29T10:37:24.099Z,1559126244.099 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4846
2019-05-29T10:37:24.109Z,1559126244.109 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-29T10:37:24.160Z,1559126244.160 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-29T10:37:24.166Z,1559126244.166 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-29T10:37:24.171Z,1559126244.171 [CTD_Seabird](INFO): created writer for : depth
2019-05-29T10:37:24.172Z,1559126244.172 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-29T10:37:24.177Z,1559126244.177 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-29T10:37:24.177Z,1559126244.177 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-29T10:37:24.182Z,1559126244.182 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-29T10:37:24.183Z,1559126244.183 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-29T10:37:24.188Z,1559126244.188 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-29T10:37:24.189Z,1559126244.189 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-29T10:37:24.194Z,1559126244.194 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-29T10:37:24.194Z,1559126244.194 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-29T10:37:24.199Z,1559126244.199 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-29T10:37:24.226Z,1559126244.226 [CTD_Seabird] Loaded
2019-05-29T10:37:24.226Z,1559126244.226 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-29T10:37:24.227Z,1559126244.227 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0
2019-05-29T10:37:24.228Z,1559126244.228 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4847
2019-05-29T10:37:24.276Z,1559126244.276 [WetLabsBB2FL] Loaded
2019-05-29T10:37:24.276Z,1559126244.276 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-29T10:37:24.277Z,1559126244.277 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAC4E0
2019-05-29T10:37:24.277Z,1559126244.277 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4848
2019-05-29T10:37:24.278Z,1559126244.278 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-29T10:37:24.282Z,1559126244.282 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-29T10:37:24.283Z,1559126244.283 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-29T10:37:24.290Z,1559126244.290 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-29T10:37:24.291Z,1559126244.291 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADC4E0
2019-05-29T10:37:24.291Z,1559126244.291 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4849
2019-05-29T10:37:24.296Z,1559126244.296 [Supervisor](INFO): Main Thread ID is 4762
2019-05-29T10:37:24.296Z,1559126244.296 [Supervisor](DEBUG): Running supervisor.
2019-05-29T10:37:24.296Z,1559126244.296 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4850
2019-05-29T10:37:24.298Z,1559126244.298 [controlThread ThreadHandler](INFO): Handler Thread ID is 4851
2019-05-29T10:37:24.299Z,1559126244.299 [controlThread](DEBUG): Initializing ControlThread
2019-05-29T10:37:24.299Z,1559126244.299 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-29T10:37:24.301Z,1559126244.301 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-29T10:37:24.302Z,1559126244.302 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-29T10:37:24.302Z,1559126244.302 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-29T10:37:24.303Z,1559126244.303 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-29T10:37:24.303Z,1559126244.303 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-29T10:37:24.303Z,1559126244.303 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-29T10:37:24.304Z,1559126244.304 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-29T10:37:24.304Z,1559126244.304 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-29T10:37:24.304Z,1559126244.304 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-29T10:37:24.312Z,1559126244.312 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-29T10:37:24.312Z,1559126244.312 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-29T10:37:24.312Z,1559126244.312 [SBIT](INFO): Initialize SBIT Component.
2019-05-29T10:37:24.313Z,1559126244.313 [SBIT](IMPORTANT): git: 2019-05-01-19-g6ef4ecc
2019-05-29T10:37:24.313Z,1559126244.313 [SBIT](INFO): git hash: 6ef4ecc926d3cc84e5878e2d73d98fd38ff7dcf7
2019-05-29T10:37:24.313Z,1559126244.313 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-29T10:37:24.313Z,1559126244.313 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-29T10:37:24.315Z,1559126244.315 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-05-29T10:37:24.315Z,1559126244.315 [IBIT](INFO): Initialize IBIT Component.
2019-05-29T10:37:24.316Z,1559126244.316 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-29T10:37:24.317Z,1559126244.317 [logger ThreadHandler](INFO): Handler Thread ID is 4852
2019-05-29T10:37:24.329Z,1559126244.329 [CBIT](DEBUG): Initialized mux pins.
2019-05-29T10:37:24.329Z,1559126244.329 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-05-29T10:37:24.329Z,1559126244.329 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-29T10:37:24.337Z,1559126244.337 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4853
2019-05-29T10:37:24.349Z,1559126244.349 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4854
2019-05-29T10:37:24.353Z,1559126244.353 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-29T10:37:24.353Z,1559126244.353 [CBIT](DEBUG): Initializing heartbeat.
2019-05-29T10:37:24.361Z,1559126244.361 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4855
2019-05-29T10:37:24.362Z,1559126244.362 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-29T10:37:24.365Z,1559126244.365 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-29T10:37:24.366Z,1559126244.366 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4857
2019-05-29T10:37:24.368Z,1559126244.368 [WetLabsBB2FL](INFO): Powering down
2019-05-29T10:37:24.394Z,1559126244.394 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4858
2019-05-29T10:37:24.398Z,1559126244.398 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-29T10:37:24.398Z,1559126244.398 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-29T10:37:24.398Z,1559126244.398 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-29T10:37:24.398Z,1559126244.398 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-29T10:37:24.398Z,1559126244.398 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-29T10:37:24.399Z,1559126244.399 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-29T10:37:24.399Z,1559126244.399 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-29T10:37:24.399Z,1559126244.399 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-29T10:37:24.399Z,1559126244.399 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-29T10:37:24.399Z,1559126244.399 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-29T10:37:24.400Z,1559126244.400 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-29T10:37:24.400Z,1559126244.400 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-29T10:37:24.400Z,1559126244.400 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-29T10:37:24.400Z,1559126244.400 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-29T10:37:24.400Z,1559126244.400 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-29T10:37:24.400Z,1559126244.400 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-29T10:37:24.425Z,1559126244.425 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-29T10:37:24.425Z,1559126244.425 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-29T10:37:24.461Z,1559126244.461 [CBIT](DEBUG): Backplane powered.
2019-05-29T10:37:24.463Z,1559126244.463 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-29T10:37:24.473Z,1559126244.473 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-29T10:37:24.499Z,1559126244.499 [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-29T10:37:24.500Z,1559126244.500 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-29T10:37:24.571Z,1559126244.571 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-29T10:37:24.572Z,1559126244.572 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-29T10:37:24.573Z,1559126244.573 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-29T10:37:24.608Z,1559126244.608 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-29T10:37:24.611Z,1559126244.611 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-29T10:37:24.628Z,1559126244.628 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-29T10:37:24.657Z,1559126244.657 [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-29T10:37:24.662Z,1559126244.662 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,Micromodem,NAL9602,Onboard,BPC1,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-29T10:37:24.667Z,1559126244.667 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-29T10:37:24.741Z,1559126244.741 [Radio_Surface](INFO): Powering up
2019-05-29T10:37:25.154Z,1559126245.154 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-05-29T10:37:25.201Z,1559126245.201 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-29T10:37:25.227Z,1559126245.227 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:25.233Z,1559126245.233 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-29T10:37:25.234Z,1559126245.234 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:25.241Z,1559126245.241 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-29T10:37:25.242Z,1559126245.242 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:25.249Z,1559126245.249 [MassServo](DEBUG): Initializing MassServo.
2019-05-29T10:37:25.250Z,1559126245.250 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:25.257Z,1559126245.257 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-29T10:37:25.258Z,1559126245.258 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:25.265Z,1559126245.265 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-29T10:37:25.324Z,1559126245.324 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-05-29T10:37:25.324Z,1559126245.324 [DropWeight] Hardware Fault, FailCount= 1
2019-05-29T10:37:25.324Z,1559126245.324 [DropWeight](ERROR): Hardware Fault
2019-05-29T10:37:25.325Z,1559126245.325 [Micromodem](INFO): Powering up
2019-05-29T10:37:25.326Z,1559126245.326 [Micromodem](DEBUG): Initializing Micromodem.
2019-05-29T10:37:25.390Z,1559126245.390 [CommandLine](FAULT): Scheduling is paused
2019-05-29T10:37:25.390Z,1559126245.390 [CBIT](INFO): Critical error at 20190529T103725
2019-05-29T10:37:25.390Z,1559126245.390 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-29T10:37:25.393Z,1559126245.393 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-05-29T10:37:25.394Z,1559126245.394 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-05-29T10:37:25.714Z,1559126245.714 [CBIT](INFO): Critical error at 20190529T103725
2019-05-29T10:37:28.833Z,1559126248.833 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:29.173Z,1559126249.173 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2019-05-29T10:37:29.173Z,1559126249.173 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2019-05-29T10:37:29.173Z,1559126249.173 [BuoyancyServo] Communications Fault, FailCount= 1
2019-05-29T10:37:29.173Z,1559126249.173 [BuoyancyServo](ERROR): Communications Fault
2019-05-29T10:37:29.517Z,1559126249.517 [RudderServo](ERROR): getPosition uart error serial timeout
2019-05-29T10:37:29.517Z,1559126249.517 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2019-05-29T10:37:29.517Z,1559126249.517 [RudderServo] Communications Fault, FailCount= 1
2019-05-29T10:37:29.517Z,1559126249.517 [RudderServo](ERROR): Communications Fault
2019-05-29T10:37:29.521Z,1559126249.521 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-05-29T10:37:29.521Z,1559126249.521 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-29T10:37:29.853Z,1559126249.853 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:29.932Z,1559126249.932 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-29T10:37:29.933Z,1559126249.933 [RudderServo](INFO): Powering down
2019-05-29T10:37:30.289Z,1559126250.289 [ThrusterServo](ERROR): Thruster uart error: serial timeout
2019-05-29T10:37:30.289Z,1559126250.289 [ThrusterServo] Communications Fault, FailCount= 1
2019-05-29T10:37:30.289Z,1559126250.289 [ThrusterServo](ERROR): Communications Fault
2019-05-29T10:37:30.293Z,1559126250.293 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2019-05-29T10:37:30.625Z,1559126250.625 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:30.631Z,1559126250.631 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-05-29T10:37:31.045Z,1559126251.045 [MassServo](ERROR): getPosition uart error serial timeout
2019-05-29T10:37:31.045Z,1559126251.045 [MassServo](FAULT): Invalid EZ Servo response:""
2019-05-29T10:37:31.045Z,1559126251.045 [MassServo] Communications Fault, FailCount= 1
2019-05-29T10:37:31.045Z,1559126251.045 [MassServo](ERROR): Communications Fault
2019-05-29T10:37:31.046Z,1559126251.046 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-29T10:37:31.046Z,1559126251.046 [ThrusterServo](INFO): Powering down
2019-05-29T10:37:31.089Z,1559126251.089 [CBIT](ERROR): Communications Fault in component: MassServo
2019-05-29T10:37:31.421Z,1559126251.421 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:31.447Z,1559126251.447 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-29T10:37:31.447Z,1559126251.447 [BuoyancyServo](INFO): Powering down
2019-05-29T10:37:31.622Z,1559126251.622 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-29T10:37:31.622Z,1559126251.622 [MassServo](INFO): Powering down
2019-05-29T10:37:31.656Z,1559126251.656 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:31.774Z,1559126251.774 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-29T10:37:31.778Z,1559126251.778 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-29T10:37:31.778Z,1559126251.778 [RudderServo] No Fault, FailCount= 1
2019-05-29T10:37:32.109Z,1559126252.109 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:32.264Z,1559126252.264 [CBIT](INFO): Clearing failed state for component ThrusterServo
2019-05-29T10:37:32.264Z,1559126252.264 [ThrusterServo] No Fault, FailCount= 1
2019-05-29T10:37:32.597Z,1559126252.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:32.629Z,1559126252.629 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:32.750Z,1559126252.750 [MassServo](DEBUG): Initializing MassServo.
2019-05-29T10:37:33.053Z,1559126253.053 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-29T10:37:33.053Z,1559126253.053 [RudderServo](FAULT): Rudder failed to initialize
2019-05-29T10:37:33.053Z,1559126253.053 [RudderServo] Communications Fault, FailCount= 2
2019-05-29T10:37:33.053Z,1559126253.053 [RudderServo](ERROR): Communications Fault
2019-05-29T10:37:33.054Z,1559126253.054 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:33.061Z,1559126253.061 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-29T10:37:33.065Z,1559126253.065 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-05-29T10:37:33.065Z,1559126253.065 [BuoyancyServo] No Fault, FailCount= 1
2019-05-29T10:37:33.065Z,1559126253.065 [CBIT](INFO): Clearing failed state for component MassServo
2019-05-29T10:37:33.065Z,1559126253.065 [MassServo] No Fault, FailCount= 1
2019-05-29T10:37:33.066Z,1559126253.066 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-29T10:37:33.397Z,1559126253.397 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:33.403Z,1559126253.403 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-05-29T10:37:33.403Z,1559126253.403 [Micromodem](INFO): Nmea out: $CCCFG,SRC,7*37

2019-05-29T10:37:33.435Z,1559126253.435 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:33.441Z,1559126253.441 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-29T10:37:33.443Z,1559126253.443 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-29T10:37:33.443Z,1559126253.443 [RudderServo](INFO): Powering down
2019-05-29T10:37:33.797Z,1559126253.797 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:34.169Z,1559126254.169 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2019-05-29T10:37:34.194Z,1559126254.194 [ThrusterServo](FAULT): Thruster failed to initialize
2019-05-29T10:37:34.194Z,1559126254.194 [ThrusterServo] Communications Fault, FailCount= 2
2019-05-29T10:37:34.194Z,1559126254.194 [ThrusterServo](ERROR): Communications Fault
2019-05-29T10:37:34.207Z,1559126254.207 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2019-05-29T10:37:34.557Z,1559126254.557 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:34.662Z,1559126254.662 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:34.782Z,1559126254.782 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-29T10:37:34.782Z,1559126254.782 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-29T10:37:34.783Z,1559126254.783 [ThrusterServo](INFO): Powering down
2019-05-29T10:37:34.787Z,1559126254.787 [CBIT](CRITICAL): Environmental Failure. Press:14.464742 PSI. Humidity:37%. Temp:26 C. ABORTING MISSION
2019-05-29T10:37:34.788Z,1559126254.788 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-29T10:37:34.788Z,1559126254.788 [RudderServo] No Fault, FailCount= 2
2019-05-29T10:37:35.117Z,1559126255.117 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:35.146Z,1559126255.146 [CBIT](INFO): Critical error at 20190529T103734
2019-05-29T10:37:35.521Z,1559126255.521 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:35.780Z,1559126255.780 [CBIT](INFO): Clearing failed state for component ThrusterServo
2019-05-29T10:37:35.781Z,1559126255.781 [ThrusterServo] No Fault, FailCount= 2
2019-05-29T10:37:36.113Z,1559126256.113 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:36.141Z,1559126256.141 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:37:36.149Z,1559126256.149 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-29T10:37:36.501Z,1559126256.501 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:36.833Z,1559126256.833 [BuoyancyServo](FAULT): Buoyancy initialization uart error:serial timeout
2019-05-29T10:37:36.833Z,1559126256.833 [BuoyancyServo] Communications Fault, FailCount= 2
2019-05-29T10:37:36.833Z,1559126256.833 [BuoyancyServo](ERROR): Communications Fault
2019-05-29T10:37:37.080Z,1559126257.080 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-05-29T10:37:37.413Z,1559126257.413 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:37.417Z,1559126257.417 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-29T10:37:37.417Z,1559126257.417 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-29T10:37:37.417Z,1559126257.417 [CTD_Seabird](ERROR): Communications Fault
2019-05-29T10:37:37.773Z,1559126257.773 [MassServo](ERROR): getPosition uart error serial timeout
2019-05-29T10:37:37.773Z,1559126257.773 [MassServo](FAULT): Invalid EZ Servo response:""
2019-05-29T10:37:37.773Z,1559126257.773 [MassServo] Communications Fault, FailCount= 2
2019-05-29T10:37:37.773Z,1559126257.773 [MassServo](ERROR): Communications Fault
2019-05-29T10:37:37.821Z,1559126257.821 [CTD_Seabird](INFO): Powering down
2019-05-29T10:37:38.085Z,1559126258.085 [RudderServo](FAULT): Rudder initialization uart error:serial timeout
2019-05-29T10:37:38.085Z,1559126258.085 [RudderServo] Communications Fault, FailCount= 3
2019-05-29T10:37:38.085Z,1559126258.085 [RudderServo](ERROR): Communications Fault
2019-05-29T10:37:38.089Z,1559126258.089 [CBIT](ERROR): Communications Fault in component: MassServo
2019-05-29T10:37:38.089Z,1559126258.089 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-29T10:37:38.090Z,1559126258.090 [CBIT](CRITICAL): Communications Fault in component: RudderServo
2019-05-29T10:37:38.090Z,1559126258.090 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-29T10:37:38.421Z,1559126258.421 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:37:38.421Z,1559126258.421 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-29T10:37:38.421Z,1559126258.421 [AHRS_M2] Communications Fault, FailCount= 1
2019-05-29T10:37:38.421Z,1559126258.421 [AHRS_M2](ERROR): Communications Fault
2019-05-29T10:37:38.467Z,1559126258.467 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-29T10:37:38.467Z,1559126258.467 [MassServo](INFO): Powering down
2019-05-29T10:37:38.468Z,1559126258.468 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-29T10:37:38.468Z,1559126258.468 [RudderServo](INFO): Powering down
2019-05-29T10:37:38.785Z,1559126258.785 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout
2019-05-29T10:37:38.785Z,1559126258.785 [ThrusterServo] Communications Fault, FailCount= 3
2019-05-29T10:37:38.785Z,1559126258.785 [ThrusterServo](ERROR): Communications Fault
2019-05-29T10:37:38.786Z,1559126258.786 [CBIT](INFO): Critical error at 20190529T103738
2019-05-29T10:37:38.789Z,1559126258.789 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-29T10:37:38.790Z,1559126258.790 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2019-05-29T10:37:38.790Z,1559126258.790 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-29T10:37:38.790Z,1559126258.790 [CTD_Seabird] No Fault, FailCount= 1
2019-05-29T10:37:38.796Z,1559126258.796 [AHRS_M2](INFO): Powering down
2019-05-29T10:37:39.185Z,1559126259.185 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-29T10:37:39.186Z,1559126259.186 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-29T10:37:52.237Z,1559126272.237 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-29T10:37:52.237Z,1559126272.237 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-29T10:37:52.237Z,1559126272.237 [CTD_Seabird](ERROR): Communications Fault
2019-05-29T10:37:52.641Z,1559126272.641 [CTD_Seabird](INFO): Powering down
2019-05-29T10:38:08.942Z,1559126288.942 [Micromodem](ERROR): Nmea resend: $CCCFG,SRC,7*37

2019-05-29T10:38:08.946Z,1559126288.946 [NAL9602](INFO): Powering up NAL9602
2019-05-29T10:38:08.974Z,1559126288.974 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-29T10:38:08.974Z,1559126288.974 [ThrusterServo](INFO): Powering down
2019-05-29T10:38:08.979Z,1559126288.979 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-29T10:38:08.979Z,1559126288.979 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-29T10:38:08.979Z,1559126288.979 [CTD_Seabird] No Fault, FailCount= 2
2019-05-29T10:38:09.047Z,1559126289.047 [Micromodem](INFO): Nmea in: 

.403

2019-05-29T10:38:09.047Z,1559126289.047 [Micromodem](ERROR): Response from modem failed NMEA checksum: 

.403
 expected 0
2019-05-29T10:38:09.048Z,1559126289.048 [Micromodem](INFO): Nmea out: $CCCFG,SRC,7*37

2019-05-29T10:38:09.088Z,1559126289.088 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:38:09.206Z,1559126289.206 [MassServo](DEBUG): Initializing MassServo.
2019-05-29T10:38:09.207Z,1559126289.207 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:38:09.326Z,1559126289.326 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-29T10:38:09.327Z,1559126289.327 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-29T10:38:09.331Z,1559126289.331 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-05-29T10:38:09.331Z,1559126289.331 [BuoyancyServo] No Fault, FailCount= 2
2019-05-29T10:38:09.331Z,1559126289.331 [CBIT](INFO): Clearing failed state for component MassServo
2019-05-29T10:38:09.332Z,1559126289.332 [MassServo] No Fault, FailCount= 2
2019-05-29T10:38:09.332Z,1559126289.332 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-29T10:38:09.337Z,1559126289.337 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-29T10:38:09.338Z,1559126289.338 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-29T10:38:09.394Z,1559126289.394 [Micromodem](INFO): Nmea in: $CACFG,SRC,7*35

2019-05-29T10:38:09.395Z,1559126289.395 [Micromodem](INFO): Nmea out: $CCCFG,DTO,10*1C

2019-05-29T10:38:09.429Z,1559126289.429 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:38:09.550Z,1559126289.550 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-29T10:38:09.737Z,1559126289.737 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-29T10:38:09.737Z,1559126289.737 [AHRS_M2] No Fault, FailCount= 1
2019-05-29T10:38:09.738Z,1559126289.738 [CBIT](INFO): Clearing failed state for component ThrusterServo
2019-05-29T10:38:09.738Z,1559126289.738 [ThrusterServo] No Fault, FailCount= 3
2019-05-29T10:38:09.789Z,1559126289.789 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-29T10:38:09.799Z,1559126289.799 [Micromodem](INFO): Nmea in: $CACFG,SRC,7*35

2019-05-29T10:38:09.799Z,1559126289.799 [Micromodem](ERROR): CFG response from modem unexpected: $CACFG,SRC,7*35

2019-05-29T10:38:09.854Z,1559126289.854 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:38:09.855Z,1559126289.855 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-29T10:38:13.345Z,1559126293.345 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:13.355Z,1559126293.355 [Micromodem](INFO): Nmea in: $CACFG,DTO,10*1E

2019-05-29T10:38:13.355Z,1559126293.355 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-05-29T10:38:13.941Z,1559126293.941 [MassServo](ERROR): isCommunicating uart error serial timeout
2019-05-29T10:38:13.941Z,1559126293.941 [MassServo](FAULT): Invalid EZ Servo response:""
2019-05-29T10:38:13.941Z,1559126293.941 [MassServo] Communications Fault, FailCount= 3
2019-05-29T10:38:13.941Z,1559126293.941 [MassServo](ERROR): Communications Fault
2019-05-29T10:38:13.941Z,1559126293.941 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout
2019-05-29T10:38:13.941Z,1559126293.941 [MassServo] Hardware Fault, FailCount= 3
2019-05-29T10:38:13.941Z,1559126293.941 [MassServo](ERROR): Hardware Fault
2019-05-29T10:38:14.265Z,1559126294.265 [MassServo](FAULT): Invalid EZ Servo response:"63"
2019-05-29T10:38:14.266Z,1559126294.266 [MassServo] Communications Fault, FailCount= 3
2019-05-29T10:38:14.266Z,1559126294.266 [MassServo](ERROR): Communications Fault
2019-05-29T10:38:14.301Z,1559126294.301 [CBIT](ERROR): Communications Fault in component: MassServo
2019-05-29T10:38:14.717Z,1559126294.717 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:14.742Z,1559126294.742 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-29T10:38:14.742Z,1559126294.742 [MassServo](INFO): Powering down
2019-05-29T10:38:15.185Z,1559126295.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:15.187Z,1559126295.187 [Micromodem](INFO): Nmea in: $CACFG,BND,1*39

2019-05-29T10:38:15.188Z,1559126295.188 [Micromodem](INFO): Nmea out: $CCCLK,2019,9-,5-,9T,0:,8:*27

2019-05-29T10:38:15.609Z,1559126295.609 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:15.641Z,1559126295.641 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:38:15.758Z,1559126295.758 [MassServo](DEBUG): Initializing MassServo.
2019-05-29T10:38:15.763Z,1559126295.763 [CBIT](INFO): Clearing failed state for component MassServo
2019-05-29T10:38:15.763Z,1559126295.763 [MassServo] No Fault, FailCount= 3
2019-05-29T10:38:16.173Z,1559126296.173 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:16.573Z,1559126296.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:16.977Z,1559126296.977 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:16.986Z,1559126296.986 [Micromodem](INFO): Nmea in: .34*03

2019-05-29T10:38:16.987Z,1559126296.987 [Micromodem](ERROR): Response from modem failed NMEA checksum: .34*03
 expected 0
2019-05-29T10:38:16.987Z,1559126296.987 [Micromodem](INFO): Nmea out: $CCCLK,2019,9-,5-,9T,0:,8:*27

2019-05-29T10:38:17.393Z,1559126297.393 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:17.833Z,1559126297.833 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:18.233Z,1559126298.233 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:18.517Z,1559126298.517 [MassServo](FAULT): Mass initialization uart error:serial timeout
2019-05-29T10:38:18.517Z,1559126298.517 [MassServo] Communications Fault, FailCount= 4
2019-05-29T10:38:18.517Z,1559126298.517 [MassServo](ERROR): Communications Fault
2019-05-29T10:38:18.521Z,1559126298.521 [CBIT](ERROR): Communications Fault in component: MassServo
2019-05-29T10:38:18.953Z,1559126298.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:18.962Z,1559126298.962 [Micromodem](INFO): Nmea in: 08*41

2019-05-29T10:38:18.962Z,1559126298.962 [Micromodem](ERROR): Response from modem failed NMEA checksum: 08*41
 expected 0
2019-05-29T10:38:18.963Z,1559126298.963 [Micromodem](INFO): Nmea out: $CCCLK,2019,9-,5-,9T,0:,8:*27

2019-05-29T10:38:18.996Z,1559126298.996 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-29T10:38:18.996Z,1559126298.996 [MassServo](INFO): Powering down
2019-05-29T10:38:19.365Z,1559126299.365 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:19.746Z,1559126299.746 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:19.778Z,1559126299.778 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:38:19.910Z,1559126299.910 [MassServo](DEBUG): Initializing MassServo.
2019-05-29T10:38:19.914Z,1559126299.914 [CBIT](INFO): Clearing failed state for component MassServo
2019-05-29T10:38:19.914Z,1559126299.914 [MassServo] No Fault, FailCount= 4
2019-05-29T10:38:20.329Z,1559126300.329 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:20.338Z,1559126300.338 [Micromodem](INFO): Nmea in: 08*41

2019-05-29T10:38:20.338Z,1559126300.338 [Micromodem](ERROR): Response from modem failed NMEA checksum: 08*41
 expected 0
2019-05-29T10:38:20.339Z,1559126300.339 [Micromodem](INFO): Nmea out: $CCCLK,2019,9-,5-,9T,0:,8:*27

2019-05-29T10:38:20.749Z,1559126300.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:21.157Z,1559126301.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:21.597Z,1559126301.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:21.664Z,1559126301.664 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000932
CHAN A1 (24V): 0.003298
CHAN A2 (12V): -0.006605
CHAN A3 (5V): -0.002539
CHAN B0 (3.3V): 0.000110
CHAN B1 (3.15aV): -0.000593
CHAN B2 (3.15bV): -0.000060
CHAN B3 (GND): 0.001885
OPEN: -0.000595
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-29T10:38:22.005Z,1559126302.005 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:22.341Z,1559126302.341 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-05-29T10:38:22.341Z,1559126302.341 [MassServo] Communications Fault, FailCount= 5
2019-05-29T10:38:22.341Z,1559126302.341 [MassServo](ERROR): Communications Fault
2019-05-29T10:38:22.342Z,1559126302.342 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-05-29T10:38:22.342Z,1559126302.342 [SBIT](ERROR): Could not read massPosReader_.
2019-05-29T10:38:22.346Z,1559126302.346 [CBIT](ERROR): Communications Fault in component: MassServo
2019-05-29T10:38:22.346Z,1559126302.346 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-05-29T10:38:22.389Z,1559126302.389 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-29T10:38:22.389Z,1559126302.389 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-29T10:38:22.389Z,1559126302.389 [CTD_Seabird](ERROR): Communications Fault
2019-05-29T10:38:22.673Z,1559126302.673 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:22.673Z,1559126302.673 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-29T10:38:22.673Z,1559126302.673 [AHRS_M2] Communications Fault, FailCount= 2
2019-05-29T10:38:22.673Z,1559126302.673 [AHRS_M2](ERROR): Communications Fault
2019-05-29T10:38:22.707Z,1559126302.707 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-29T10:38:22.707Z,1559126302.707 [MassServo](INFO): Powering down
2019-05-29T10:38:22.710Z,1559126302.710 [CBIT](INFO): Critical error at 20190529T103822
2019-05-29T10:38:22.712Z,1559126302.712 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-29T10:38:22.713Z,1559126302.713 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-29T10:38:22.714Z,1559126302.714 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-29T10:38:22.781Z,1559126302.781 [AHRS_M2](INFO): Powering down
2019-05-29T10:38:22.793Z,1559126302.793 [CTD_Seabird](INFO): Powering down
2019-05-29T10:38:52.862Z,1559126332.862 [Micromodem](ERROR): Nmea resend: $CCCLK,2019,9-,5-,9T,0:,8:*27

2019-05-29T10:38:52.865Z,1559126332.865 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-29T10:38:52.866Z,1559126332.866 [NAL9602] Communications Fault, FailCount= 1
2019-05-29T10:38:52.866Z,1559126332.866 [NAL9602](ERROR): Communications Fault
2019-05-29T10:38:52.898Z,1559126332.898 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-05-29T10:38:52.898Z,1559126332.898 [SBIT](ERROR): Could not read massPosReader_.
2019-05-29T10:38:52.899Z,1559126332.899 [SBIT](FAULT): Control surface position failure.
2019-05-29T10:38:52.902Z,1559126332.902 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-29T10:38:52.971Z,1559126332.971 [Micromodem](INFO): Nmea in: $CACLK,2019,09,05,09,00,08*41

2019-05-29T10:38:52.972Z,1559126332.972 [NAL9602](INFO): Powering down
2019-05-29T10:38:53.045Z,1559126333.045 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-29T10:38:53.166Z,1559126333.166 [MassServo](DEBUG): Initializing MassServo.
2019-05-29T10:38:53.370Z,1559126333.370 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-29T10:38:53.370Z,1559126333.370 [AHRS_M2] No Fault, FailCount= 2
2019-05-29T10:38:53.713Z,1559126333.713 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-29T10:38:53.845Z,1559126333.845 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-29T10:38:53.845Z,1559126333.845 [NAL9602] No Fault, FailCount= 1
2019-05-29T10:38:56.139Z,1559126336.139 [Micromodem](INFO): Nmea in: *6
$CACLK,2019,09,05,09,00,08*41

2019-05-29T10:38:56.140Z,1559126336.140 [Micromodem](ERROR): Response from modem failed NMEA checksum: *6
$CACLK,2019,09,05,09,00,08*41
 expected 0
2019-05-29T10:38:57.269Z,1559126337.269 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:57.769Z,1559126337.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:58.161Z,1559126338.161 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:58.573Z,1559126338.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:59.001Z,1559126339.001 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:59.397Z,1559126339.397 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:38:59.777Z,1559126339.777 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:00.185Z,1559126340.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:00.593Z,1559126340.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:00.989Z,1559126340.989 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:01.401Z,1559126341.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:01.797Z,1559126341.797 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:02.209Z,1559126342.209 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:02.629Z,1559126342.629 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:03.025Z,1559126343.025 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:03.413Z,1559126343.413 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:03.821Z,1559126343.821 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:04.221Z,1559126344.221 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:04.633Z,1559126344.633 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:05.037Z,1559126345.037 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:05.441Z,1559126345.441 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:05.925Z,1559126345.925 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:06.329Z,1559126346.329 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:06.398Z,1559126346.398 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-05-29T10:39:06.398Z,1559126346.398 [SBIT](ERROR): Could not read massPosReader_.
2019-05-29T10:39:06.398Z,1559126346.398 [SBIT](FAULT): Control surface position failure.
2019-05-29T10:39:06.725Z,1559126346.725 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:06.725Z,1559126346.725 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-29T10:39:06.725Z,1559126346.725 [AHRS_M2] Communications Fault, FailCount= 3
2019-05-29T10:39:06.725Z,1559126346.725 [AHRS_M2](ERROR): Communications Fault
2019-05-29T10:39:06.782Z,1559126346.782 [SBIT](CRITICAL): SBIT FAILED
2019-05-29T10:39:06.801Z,1559126346.801 [CommandLine](IMPORTANT): got command configSet list
2019-05-29T10:39:06.801Z,1559126346.801 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-29T10:39:06.802Z,1559126346.802 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-05-29T10:39:06.802Z,1559126346.802 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-05-29T10:39:06.802Z,1559126346.802 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-05-29T10:39:06.802Z,1559126346.802 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-05-29T10:39:06.802Z,1559126346.802 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-29T10:39:06.802Z,1559126346.802 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-05-29T10:39:06.803Z,1559126346.803 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold=1 meter;

2019-05-29T10:39:06.809Z,1559126346.809 [CBIT](INFO): Critical error at 20190529T103906
2019-05-29T10:39:06.814Z,1559126346.814 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-29T10:39:06.820Z,1559126346.820 [AHRS_M2](INFO): Powering down
2019-05-29T10:39:18.809Z,1559126358.809 [CommandLine](IMPORTANT): got command show stack
2019-05-29T10:39:18.809Z,1559126358.809 [CommandLine](IMPORTANT): Behavior Stack:

2019-05-29T10:39:18.810Z,1559126358.810 [MissionManager](IMPORTANT): Mission loaded, but not running.
2019-05-29T10:39:36.899Z,1559126376.899 [NAL9602](INFO): Powering up NAL9602
2019-05-29T10:39:36.923Z,1559126376.923 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-29T10:39:36.923Z,1559126376.923 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-29T10:39:36.923Z,1559126376.923 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-29T10:39:36.926Z,1559126376.926 [MissionManager](IMPORTANT): Started mission Startup
2019-05-29T10:39:36.926Z,1559126376.926 [Startup] Running Loop=1
2019-05-29T10:39:36.926Z,1559126376.926 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-29T10:39:36.926Z,1559126376.926 [Startup:A.GoToSurface] Running Loop=1
2019-05-29T10:39:36.926Z,1559126376.926 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-29T10:39:36.927Z,1559126376.927 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-29T10:39:36.927Z,1559126376.927 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-29T10:39:36.928Z,1559126376.928 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-29T10:39:36.928Z,1559126376.928 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-29T10:39:36.929Z,1559126376.929 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-29T10:39:36.930Z,1559126376.930 [Startup:StartupSatComms] Running Loop=1
2019-05-29T10:39:36.930Z,1559126376.930 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-29T10:39:36.930Z,1559126376.930 [Startup:StartupSatComms:A] Running Loop=1
2019-05-29T10:39:36.938Z,1559126376.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-29T10:39:37.049Z,1559126377.049 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-29T10:39:37.058Z,1559126377.058 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-29T10:39:37.058Z,1559126377.058 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-29T10:39:37.365Z,1559126377.365 [BPC1](ERROR): BPC1B buffer size (B10 bytes) exceeded the max device response limit.
2019-05-29T10:39:37.365Z,1559126377.365 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-29T10:39:37.385Z,1559126377.385 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-29T10:39:37.385Z,1559126377.385 [AHRS_M2] No Fault, FailCount= 3
2019-05-29T10:39:37.751Z,1559126377.751 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-29T10:39:37.775Z,1559126377.775 [BPC1](ERROR): BPC1A buffer size (B83 bytes) exceeded the max device response limit.
2019-05-29T10:39:37.776Z,1559126377.776 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-29T10:39:41.305Z,1559126381.305 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:41.701Z,1559126381.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:42.113Z,1559126382.113 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:42.517Z,1559126382.517 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:42.921Z,1559126382.921 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:43.325Z,1559126383.325 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:43.729Z,1559126383.729 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:44.133Z,1559126384.133 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:44.549Z,1559126384.549 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:44.961Z,1559126384.961 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:45.337Z,1559126385.337 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:45.741Z,1559126385.741 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:46.153Z,1559126386.153 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:46.549Z,1559126386.549 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:46.961Z,1559126386.961 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:47.365Z,1559126387.365 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:47.769Z,1559126387.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:48.185Z,1559126388.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:48.570Z,1559126388.570 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:48.973Z,1559126388.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:49.377Z,1559126389.377 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:49.789Z,1559126389.789 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:50.193Z,1559126390.193 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:50.625Z,1559126390.625 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:39:50.625Z,1559126390.625 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-29T10:39:50.625Z,1559126390.625 [AHRS_M2] Communications Fault, FailCount= 4
2019-05-29T10:39:50.625Z,1559126390.625 [AHRS_M2](ERROR): Communications Fault
2019-05-29T10:39:50.742Z,1559126390.742 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-29T10:39:50.748Z,1559126390.748 [AHRS_M2](INFO): Powering down
2019-05-29T10:40:09.153Z,1559126409.153 [CommandLine](IMPORTANT): got command show stack
2019-05-29T10:40:09.153Z,1559126409.153 [CommandLine](IMPORTANT): Behavior Stack:

2019-05-29T10:40:09.154Z,1559126409.154 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2019-05-29T10:40:09.154Z,1559126409.154 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A
2019-05-29T10:40:20.827Z,1559126420.827 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-29T10:40:20.827Z,1559126420.827 [NAL9602] Communications Fault, FailCount= 2
2019-05-29T10:40:20.827Z,1559126420.827 [NAL9602](ERROR): Communications Fault
2019-05-29T10:40:20.874Z,1559126420.874 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-29T10:40:20.967Z,1559126420.967 [NAL9602](INFO): Powering down
2019-05-29T10:40:21.344Z,1559126421.344 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-29T10:40:21.344Z,1559126421.344 [AHRS_M2] No Fault, FailCount= 4
2019-05-29T10:40:21.685Z,1559126421.685 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-29T10:40:21.725Z,1559126421.725 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-29T10:40:21.725Z,1559126421.725 [NAL9602] No Fault, FailCount= 2
2019-05-29T10:40:25.241Z,1559126425.241 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:25.645Z,1559126425.645 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:26.049Z,1559126426.049 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:26.465Z,1559126426.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:26.877Z,1559126426.877 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:27.257Z,1559126427.257 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:27.657Z,1559126427.657 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:28.061Z,1559126428.061 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:28.473Z,1559126428.473 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:28.869Z,1559126428.869 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:29.281Z,1559126429.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:29.709Z,1559126429.709 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:30.105Z,1559126430.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:30.505Z,1559126430.505 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:30.893Z,1559126430.893 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:31.297Z,1559126431.297 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:31.701Z,1559126431.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:32.109Z,1559126432.109 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:32.529Z,1559126432.529 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:32.981Z,1559126432.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:33.373Z,1559126433.373 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:33.877Z,1559126433.877 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:34.321Z,1559126434.321 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:34.697Z,1559126434.697 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-29T10:40:34.697Z,1559126434.697 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-29T10:40:34.697Z,1559126434.697 [AHRS_M2] Communications Fault, FailCount= 5
2019-05-29T10:40:34.697Z,1559126434.697 [AHRS_M2](ERROR): Communications Fault
2019-05-29T10:40:34.803Z,1559126434.803 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-29T10:40:34.803Z,1559126434.803 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-05-29T10:40:34.814Z,1559126434.814 [AHRS_M2](INFO): Powering down
2019-05-29T10:41:04.895Z,1559126464.895 [NAL9602](INFO): Powering up NAL9602
2019-05-29T10:41:04.915Z,1559126464.915 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-29T10:39:36.9Z
2019-05-29T10:41:04.916Z,1559126464.916 [Startup:StartupSatComms:A] Stopped
2019-05-29T10:41:04.916Z,1559126464.916 [Startup:StartupSatComms:B] Running Loop=1
2019-05-29T10:41:04.922Z,1559126464.922 [CBIT](INFO): Critical error at 20190529T104034
2019-05-29T10:41:04.945Z,1559126464.945 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-29T10:41:04.945Z,1559126464.945 [CTD_Seabird] No Fault, FailCount= 3
2019-05-29T10:41:05.025Z,1559126465.025 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-29T10:41:05.337Z,1559126465.337 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-29T10:41:05.338Z,1559126465.338 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-29T10:41:05.850Z,1559126465.850 [Micromodem](INFO): Nmea out: $CCCYC,0,7,9,1,0,0*56

2019-05-29T10:41:05.880Z,1559126465.880 [BPC1](ERROR): BPC1A buffer size (B10 bytes) exceeded the max device response limit.
2019-05-29T10:41:05.880Z,1559126465.880 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-05-29T10:41:05.881Z,1559126465.881 [BPC1](ERROR): BPC1B buffer size (B10 bytes) exceeded the max device response limit.
2019-05-29T10:41:05.881Z,1559126465.881 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-05-29T10:41:10.355Z,1559126470.355 [Micromodem](INFO): Nmea in: 0
$CAERR,090220,NMEA,17,Argument value out of range*0F

2019-05-29T10:41:10.355Z,1559126470.355 [Micromodem](ERROR): Response from modem unexpected: 0
$CAERR,090220,NMEA,17,Argument value out of range*0F

2019-05-29T10:41:17.222Z,1559126477.222 [Micromodem](ERROR): Nmea resend: $CCCYC,0,7,9,1,0,0*56

2019-05-29T10:41:18.393Z,1559126478.393 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-29T10:41:18.393Z,1559126478.393 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-29T10:41:18.393Z,1559126478.393 [CTD_Seabird](ERROR): Communications Fault
2019-05-29T10:41:18.540Z,1559126478.540 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-29T10:41:18.797Z,1559126478.797 [CTD_Seabird](INFO): Powering down
2019-05-29T10:41:20.094Z,1559126480.094 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-29T10:41:20.094Z,1559126480.094 [CTD_Seabird] No Fault, FailCount= 1
2019-05-29T10:41:20.137Z,1559126480.137 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-29T10:41:20.138Z,1559126480.138 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-29T10:41:20.455Z,1559126480.455 [Micromodem](INFO): Nmea in: $CAERR,090232,NMEA,17,Argument value out of range*0C

2019-05-29T10:41:20.455Z,1559126480.455 [Micromodem](ERROR): Response from modem unexpected: $CAERR,090232,NMEA,17,Argument value out of range*0C

2019-05-29T10:41:25.711Z,1559126485.711 [NAL9602](ERROR): NAL9602 initialization error.
2019-05-29T10:41:25.711Z,1559126485.711 [NAL9602] Communications Fault, FailCount= 3
2019-05-29T10:41:25.711Z,1559126485.711 [NAL9602](ERROR): Communications Fault
2019-05-29T10:41:25.740Z,1559126485.740 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-05-29T10:41:26.111Z,1559126486.111 [NAL9602](INFO): Powering down
2019-05-29T10:41:26.958Z,1559126486.958 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-29T10:41:26.958Z,1559126486.958 [NAL9602] No Fault, FailCount= 3
2019-05-29T10:41:28.538Z,1559126488.538 [Micromodem](ERROR): Nmea resend: $CCCYC,0,7,9,1,0,0*56

2019-05-29T10:41:30.559Z,1559126490.559 [Micromodem](INFO): Nmea in: $CAERR,090243,NMEA,17,Argument value out of range*0A

2019-05-29T10:41:30.559Z,1559126490.559 [Micromodem](ERROR): Response from modem unexpected: $CAERR,090243,NMEA,17,Argument value out of range*0A

2019-05-29T10:41:33.189Z,1559126493.189 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-29T10:41:33.189Z,1559126493.189 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-29T10:41:33.189Z,1559126493.189 [CTD_Seabird](ERROR): Communications Fault
2019-05-29T10:41:33.426Z,1559126493.426 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-29T10:41:33.593Z,1559126493.593 [CTD_Seabird](INFO): Powering down
2019-05-29T10:41:34.626Z,1559126494.626 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-29T10:41:34.626Z,1559126494.626 [CTD_Seabird] No Fault, FailCount= 2
2019-05-29T10:41:34.937Z,1559126494.937 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-29T10:41:34.938Z,1559126494.938 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-29T10:41:37.437Z,1559126497.437 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-29T10:41:37.437Z,1559126497.437 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-29T10:41:37.437Z,1559126497.437 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-29T10:41:37.447Z,1559126497.447 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-29T10:41:37.861Z,1559126497.861 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-29T10:41:37.861Z,1559126497.861 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-29T10:41:39.850Z,1559126499.850 [Micromodem](ERROR): Nmea resend: $CCCYC,0,7,9,1,0,0*56

2019-05-29T10:41:41.464Z,1559126501.464 [Micromodem](INFO): Nmea in: $CAERR,090254,NMEA,17,Argument value out of range*0C

2019-05-29T10:41:41.464Z,1559126501.464 [Micromodem](ERROR): Response from modem unexpected: $CAERR,090254,NMEA,17,Argument value out of range*0C

2019-05-29T10:41:41.866Z,1559126501.866 [Micromodem](INFO): Nmea in: $CADQF,196,2*5D

2019-05-29T10:41:41.867Z,1559126501.867 [Micromodem](ERROR): Response from modem unexpected: $CADQF,196,2*5D

2019-05-29T10:41:42.271Z,1559126502.271 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,2*23

2019-05-29T10:41:42.271Z,1559126502.271 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,2*23

2019-05-29T10:41:45.104Z,1559126505.104 [Micromodem](INFO): Nmea in: $CACST,1,090255.0000,1,327,-4,0111,0112,250,03,03,05,04,-1,-1,-1,0,2,1,1,-1,-1,-1,-1,00,196,0.0,-1*47

2019-05-29T10:41:45.105Z,1559126505.105 [Micromodem](ERROR): Response from modem unexpected: $CACST,1,090255.0000,1,327,-4,0111,0112,250,03,03,05,04,-1,-1,-1,0,2,1,1,-1,-1,-1,-1,00,196,0.0,-1*47

2019-05-29T10:41:47.989Z,1559126507.989 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-29T10:41:47.989Z,1559126507.989 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-29T10:41:47.989Z,1559126507.989 [CTD_Seabird](ERROR): Communications Fault
2019-05-29T10:41:48.365Z,1559126508.365 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-29T10:41:48.366Z,1559126508.366 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-29T10:41:48.393Z,1559126508.393 [CTD_Seabird](INFO): Powering down
2019-05-29T10:41:48.401Z,1559126508.401 [CommandLine](IMPORTANT): got command quit
2019-05-29T10:41:49.405Z,1559126509.405 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-29T10:41:49.405Z,1559126509.405 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:49.477Z,1559126509.477 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-29T10:41:49.477Z,1559126509.477 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:49.478Z,1559126509.478 [CommandLine](INFO): Join timeout helper Thread ID is 4894
2019-05-29T10:41:49.478Z,1559126509.478 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-29T10:41:49.478Z,1559126509.478 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:49.478Z,1559126509.478 [NavChartDb](INFO): Join timeout helper Thread ID is 4895
2019-05-29T10:41:49.777Z,1559126509.777 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-29T10:41:49.777Z,1559126509.777 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:49.785Z,1559126509.785 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-29T10:41:49.785Z,1559126509.785 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:49.785Z,1559126509.785 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4896
2019-05-29T10:41:50.009Z,1559126510.009 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-29T10:41:50.010Z,1559126510.010 [WetLabsBB2FL](INFO): Powering down
2019-05-29T10:41:50.010Z,1559126510.010 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.029Z,1559126510.029 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-29T10:41:50.029Z,1559126510.029 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.029Z,1559126510.029 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4897
2019-05-29T10:41:50.141Z,1559126510.141 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-29T10:41:50.141Z,1559126510.141 [CTD_Seabird](INFO): Powering down
2019-05-29T10:41:50.153Z,1559126510.153 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.153Z,1559126510.153 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-29T10:41:50.153Z,1559126510.153 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.154Z,1559126510.154 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4898
2019-05-29T10:41:50.261Z,1559126510.261 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-29T10:41:50.261Z,1559126510.261 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.278Z,1559126510.278 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-29T10:41:50.278Z,1559126510.278 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.278Z,1559126510.278 [Radio_Surface](INFO): Join timeout helper Thread ID is 4899
2019-05-29T10:41:50.593Z,1559126510.593 [Radio_Surface](INFO): Powering down
2019-05-29T10:41:50.594Z,1559126510.594 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-29T10:41:50.594Z,1559126510.594 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.598Z,1559126510.598 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-29T10:41:50.598Z,1559126510.598 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.598Z,1559126510.598 [logger](INFO): Join timeout helper Thread ID is 4900
2019-05-29T10:41:50.609Z,1559126510.609 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-29T10:41:50.609Z,1559126510.609 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.617Z,1559126510.617 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-29T10:41:50.618Z,1559126510.618 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.618Z,1559126510.618 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-29T10:41:50.618Z,1559126510.618 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.618Z,1559126510.618 [controlThread](INFO): Join timeout helper Thread ID is 4901
2019-05-29T10:41:50.745Z,1559126510.745 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-29T10:41:50.745Z,1559126510.745 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-29T10:41:50.745Z,1559126510.745 [AHRS_M2](INFO): Powering down
2019-05-29T10:41:50.746Z,1559126510.746 [Micromodem](INFO): Powering down
2019-05-29T10:41:50.841Z,1559126510.841 [NAL9602](INFO): Powering down
2019-05-29T10:41:50.843Z,1559126510.843 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-29T10:41:50.844Z,1559126510.844 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-29T10:41:50.844Z,1559126510.844 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-29T10:41:50.845Z,1559126510.845 [MissionManager](INFO): Uninitializing Mission Default
2019-05-29T10:41:50.847Z,1559126510.847 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-29T10:41:50.847Z,1559126510.847 [Startup] Stopped
2019-05-29T10:41:50.847Z,1559126510.847 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-29T10:41:50.847Z,1559126510.847 [Startup:A.GoToSurface] Stopped
2019-05-29T10:41:50.847Z,1559126510.847 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-29T10:41:50.848Z,1559126510.848 [Startup:StartupSatComms] Stopped
2019-05-29T10:41:50.848Z,1559126510.848 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-29T10:41:50.848Z,1559126510.848 [Startup:StartupSatComms:B] Stopped
2019-05-29T10:41:50.849Z,1559126510.849 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-29T10:41:50.849Z,1559126510.849 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-29T10:41:50.850Z,1559126510.850 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-29T10:41:50.850Z,1559126510.850 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-29T10:41:50.850Z,1559126510.850 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-29T10:41:50.850Z,1559126510.850 [BuoyancyServo](INFO): Powering down
2019-05-29T10:41:50.865Z,1559126510.865 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-29T10:41:50.865Z,1559126510.865 [ElevatorServo](INFO): Powering down
2019-05-29T10:41:50.866Z,1559126510.866 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-29T10:41:50.866Z,1559126510.866 [MassServo](INFO): Powering down
2019-05-29T10:41:50.867Z,1559126510.867 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-29T10:41:50.867Z,1559126510.867 [RudderServo](INFO): Powering down
2019-05-29T10:41:50.867Z,1559126510.867 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-29T10:41:50.868Z,1559126510.868 [ThrusterServo](INFO): Powering down
2019-05-29T10:41:50.868Z,1559126510.868 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-29T10:41:50.869Z,1559126510.869 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-29T10:41:50.869Z,1559126510.869 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-29T10:41:50.869Z,1559126510.869 [CBIT](DEBUG): Powering off loads.
2019-05-29T10:41:50.881Z,1559126510.881 [CBIT](DEBUG): Disabling WDT.
2019-05-29T10:41:50.893Z,1559126510.893 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-29T10:41:50.893Z,1559126510.893 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:50.967Z,1559126510.967 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:51.257Z,1559126511.257 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:51.259Z,1559126511.259 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:51.263Z,1559126511.263 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:51.272Z,1559126511.272 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-29T10:41:51.326Z,1559126511.326 [logger ThreadHandler](INFO): Thread cancelled.