2019-10-15T22:16:34.094Z,1571177794.094 [Supervisor](DEBUG): Initializing supervisor.
2019-10-15T22:16:34.097Z,1571177794.097 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-15T22:16:34.098Z,1571177794.098 [SyncHandler](INFO): Protected caller Thread ID is 6475
2019-10-15T22:16:34.098Z,1571177794.098 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-15T22:16:34.099Z,1571177794.099 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-15T22:16:34.099Z,1571177794.099 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6476
2019-10-15T22:16:34.102Z,1571177794.102 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-15T22:16:34.113Z,1571177794.113 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-15T22:16:34.114Z,1571177794.114 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-15T22:16:34.114Z,1571177794.114 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6477
2019-10-15T22:16:34.115Z,1571177794.115 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-15T22:16:34.116Z,1571177794.116 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-15T22:16:34.116Z,1571177794.116 [logger ThreadHandler](INFO): Protected caller Thread ID is 6478
2019-10-15T22:16:34.118Z,1571177794.118 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-15T22:16:34.119Z,1571177794.119 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-15T22:16:34.120Z,1571177794.120 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-15T22:16:34.313Z,1571177794.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-15T22:16:34.314Z,1571177794.314 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-15T22:16:34.391Z,1571177794.391 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-15T22:16:34.815Z,1571177794.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-15T22:16:34.815Z,1571177794.815 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-15T22:16:35.132Z,1571177795.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-15T22:16:35.132Z,1571177795.132 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-15T22:16:35.226Z,1571177795.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-15T22:16:35.227Z,1571177795.227 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-15T22:16:35.515Z,1571177795.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-15T22:16:35.516Z,1571177795.516 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-15T22:16:35.705Z,1571177795.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-15T22:16:35.706Z,1571177795.706 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-15T22:16:36.148Z,1571177796.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-15T22:16:36.149Z,1571177796.149 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-15T22:16:36.251Z,1571177796.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-15T22:16:36.252Z,1571177796.252 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-15T22:16:36.350Z,1571177796.350 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-15T22:16:36.350Z,1571177796.350 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-15T22:16:37.009Z,1571177797.009 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-15T22:16:37.010Z,1571177797.010 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-15T22:16:37.395Z,1571177797.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-15T22:16:37.395Z,1571177797.395 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-15T22:16:37.582Z,1571177797.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-15T22:16:37.583Z,1571177797.583 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-15T22:16:37.723Z,1571177797.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-15T22:16:37.724Z,1571177797.724 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-15T22:16:37.869Z,1571177797.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-15T22:16:37.871Z,1571177797.871 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-10-15T22:16:37.872Z,1571177797.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-10-15T22:16:37.960Z,1571177797.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-10-15T22:16:38.044Z,1571177798.044 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-10-15T22:16:38.145Z,1571177798.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-10-15T22:16:38.227Z,1571177798.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-10-15T22:16:38.319Z,1571177798.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-10-15T22:16:38.417Z,1571177798.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-10-15T22:16:38.621Z,1571177798.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-10-15T22:16:38.695Z,1571177798.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-10-15T22:16:38.969Z,1571177798.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-10-15T22:16:39.308Z,1571177799.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-10-15T22:16:39.602Z,1571177799.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-10-15T22:16:40.049Z,1571177800.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-15T22:16:40.067Z,1571177800.067 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-10-15T22:16:40.067Z,1571177800.067 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-15T22:16:40.068Z,1571177800.068 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-15T22:16:40.139Z,1571177800.139 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-15T22:16:40.251Z,1571177800.251 [VerticalControl] Loaded
2019-10-15T22:16:40.252Z,1571177800.252 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-15T22:16:40.252Z,1571177800.252 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-15T22:16:40.321Z,1571177800.321 [HorizontalControl] Loaded
2019-10-15T22:16:40.321Z,1571177800.321 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-15T22:16:40.322Z,1571177800.322 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-15T22:16:40.328Z,1571177800.328 [SpeedControl] Loaded
2019-10-15T22:16:40.328Z,1571177800.328 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-15T22:16:40.329Z,1571177800.329 [LoopControl](DEBUG): Construct LoopControl.
2019-10-15T22:16:40.329Z,1571177800.329 [LoopControl] Loaded
2019-10-15T22:16:40.330Z,1571177800.330 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-15T22:16:40.330Z,1571177800.330 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-15T22:16:40.331Z,1571177800.331 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-15T22:16:40.344Z,1571177800.344 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-15T22:16:40.344Z,1571177800.344 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-15T22:16:40.447Z,1571177800.447 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-15T22:16:40.447Z,1571177800.447 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-15T22:16:40.551Z,1571177800.551 [BuoyancyServo] Loaded
2019-10-15T22:16:40.551Z,1571177800.551 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-15T22:16:40.562Z,1571177800.562 [ElevatorServo] Loaded
2019-10-15T22:16:40.563Z,1571177800.563 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-15T22:16:40.574Z,1571177800.574 [MassServo] Loaded
2019-10-15T22:16:40.574Z,1571177800.574 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-15T22:16:40.585Z,1571177800.585 [RudderServo] Loaded
2019-10-15T22:16:40.585Z,1571177800.585 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-15T22:16:40.596Z,1571177800.596 [ThrusterServo] Loaded
2019-10-15T22:16:40.597Z,1571177800.597 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-15T22:16:40.597Z,1571177800.597 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-15T22:16:40.598Z,1571177800.598 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-15T22:16:40.706Z,1571177800.706 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-15T22:16:40.707Z,1571177800.707 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-15T22:16:40.728Z,1571177800.728 [NavChart] Loaded
2019-10-15T22:16:40.728Z,1571177800.728 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-15T22:16:40.732Z,1571177800.732 [UniversalFixResidualReporter] Loaded
2019-10-15T22:16:40.732Z,1571177800.732 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-15T22:16:40.732Z,1571177800.732 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-15T22:16:40.733Z,1571177800.733 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-15T22:16:40.805Z,1571177800.805 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-15T22:16:40.806Z,1571177800.806 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-15T22:16:41.072Z,1571177801.072 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-15T22:16:41.077Z,1571177801.077 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-15T22:16:41.078Z,1571177801.078 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-15T22:16:41.084Z,1571177801.084 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-15T22:16:41.084Z,1571177801.084 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-15T22:16:41.089Z,1571177801.089 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-15T22:16:41.090Z,1571177801.090 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-15T22:16:41.095Z,1571177801.095 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-15T22:16:41.167Z,1571177801.167 [AHRS_M2] Loaded
2019-10-15T22:16:41.167Z,1571177801.167 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-15T22:16:41.243Z,1571177801.243 [DataOverHttps] Loaded
2019-10-15T22:16:41.244Z,1571177801.244 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-15T22:16:41.245Z,1571177801.245 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0
2019-10-15T22:16:41.245Z,1571177801.245 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6561
2019-10-15T22:16:41.259Z,1571177801.259 [Depth_Keller] Loaded
2019-10-15T22:16:41.259Z,1571177801.259 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-15T22:16:41.264Z,1571177801.264 [DropWeight] Loaded
2019-10-15T22:16:41.264Z,1571177801.264 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-15T22:16:41.394Z,1571177801.394 [DVL_micro] Loaded
2019-10-15T22:16:41.395Z,1571177801.395 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-10-15T22:16:41.494Z,1571177801.494 [NAL9602] Loaded
2019-10-15T22:16:41.494Z,1571177801.494 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-15T22:16:41.511Z,1571177801.511 [Onboard] Loaded
2019-10-15T22:16:41.511Z,1571177801.511 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-15T22:16:41.514Z,1571177801.514 [Radio_Surface] Loaded
2019-10-15T22:16:41.514Z,1571177801.514 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-15T22:16:41.515Z,1571177801.515 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0
2019-10-15T22:16:41.516Z,1571177801.516 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6562
2019-10-15T22:16:41.645Z,1571177801.645 [DAT] Loaded
2019-10-15T22:16:41.645Z,1571177801.645 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-15T22:16:43.203Z,1571177803.203 [BPC1] Loaded
2019-10-15T22:16:43.204Z,1571177803.204 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-15T22:16:43.204Z,1571177803.204 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-15T22:16:43.204Z,1571177803.204 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-15T22:16:43.246Z,1571177803.246 [DepthRateCalculator] Loaded
2019-10-15T22:16:43.246Z,1571177803.246 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-15T22:16:43.251Z,1571177803.251 [PitchRateCalculator] Loaded
2019-10-15T22:16:43.252Z,1571177803.252 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-15T22:16:43.263Z,1571177803.263 [SpeedCalculator] Loaded
2019-10-15T22:16:43.264Z,1571177803.264 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-15T22:16:43.285Z,1571177803.285 [TempGradientCalculator] Loaded
2019-10-15T22:16:43.285Z,1571177803.285 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-15T22:16:43.291Z,1571177803.291 [YawRateCalculator] Loaded
2019-10-15T22:16:43.291Z,1571177803.291 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-15T22:16:43.320Z,1571177803.320 [ElevatorOffsetCalculator] Loaded
2019-10-15T22:16:43.321Z,1571177803.321 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-15T22:16:43.321Z,1571177803.321 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-15T22:16:43.322Z,1571177803.322 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-15T22:16:43.511Z,1571177803.511 [Aanderaa_O2] Loaded
2019-10-15T22:16:43.512Z,1571177803.512 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-10-15T22:16:43.598Z,1571177803.598 [CTD_NeilBrown] Loaded
2019-10-15T22:16:43.598Z,1571177803.598 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-10-15T22:16:43.599Z,1571177803.599 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409B04E0
2019-10-15T22:16:43.599Z,1571177803.599 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6563
2019-10-15T22:16:43.608Z,1571177803.608 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-15T22:16:43.613Z,1571177803.613 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-15T22:16:43.614Z,1571177803.614 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-15T22:16:43.619Z,1571177803.619 [CTD_Seabird](INFO): created writer for : depth
2019-10-15T22:16:43.619Z,1571177803.619 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-15T22:16:43.624Z,1571177803.624 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-15T22:16:43.625Z,1571177803.625 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-15T22:16:43.630Z,1571177803.630 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-15T22:16:43.631Z,1571177803.631 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-15T22:16:43.636Z,1571177803.636 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-15T22:16:43.637Z,1571177803.637 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-15T22:16:43.642Z,1571177803.642 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-15T22:16:43.642Z,1571177803.642 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-15T22:16:43.647Z,1571177803.647 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-15T22:16:43.675Z,1571177803.675 [CTD_Seabird] Loaded
2019-10-15T22:16:43.676Z,1571177803.676 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-15T22:16:43.677Z,1571177803.677 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0
2019-10-15T22:16:43.677Z,1571177803.677 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6564
2019-10-15T22:16:43.692Z,1571177803.692 [PAR_Licor] Loaded
2019-10-15T22:16:43.692Z,1571177803.692 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-15T22:16:43.741Z,1571177803.741 [WetLabsBB2FL] Loaded
2019-10-15T22:16:43.741Z,1571177803.741 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-15T22:16:43.742Z,1571177803.742 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A104E0
2019-10-15T22:16:43.743Z,1571177803.743 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6565
2019-10-15T22:16:43.744Z,1571177803.744 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-15T22:16:43.744Z,1571177803.744 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-15T22:16:43.777Z,1571177803.777 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-15T22:16:43.777Z,1571177803.777 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-15T22:16:44.222Z,1571177804.222 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-15T22:16:44.223Z,1571177804.223 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-15T22:16:44.381Z,1571177804.381 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-15T22:16:44.392Z,1571177804.392 [SBIT] Loaded
2019-10-15T22:16:44.392Z,1571177804.392 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-15T22:16:44.393Z,1571177804.393 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-15T22:16:44.404Z,1571177804.404 [IBIT] Loaded
2019-10-15T22:16:44.405Z,1571177804.405 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-15T22:16:44.408Z,1571177804.408 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-15T22:16:44.549Z,1571177804.549 [CBIT] Loaded
2019-10-15T22:16:44.549Z,1571177804.549 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-15T22:16:44.549Z,1571177804.549 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-15T22:16:44.553Z,1571177804.553 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-15T22:16:44.554Z,1571177804.554 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-15T22:16:44.560Z,1571177804.560 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-15T22:16:44.562Z,1571177804.562 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0
2019-10-15T22:16:44.562Z,1571177804.562 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6566
2019-10-15T22:16:44.567Z,1571177804.567 [Supervisor](INFO): Main Thread ID is 6474
2019-10-15T22:16:44.567Z,1571177804.567 [Supervisor](DEBUG): Running supervisor.
2019-10-15T22:16:44.567Z,1571177804.567 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6567
2019-10-15T22:16:44.570Z,1571177804.570 [controlThread ThreadHandler](INFO): Handler Thread ID is 6568
2019-10-15T22:16:44.570Z,1571177804.570 [controlThread](DEBUG): Initializing ControlThread
2019-10-15T22:16:44.571Z,1571177804.571 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-15T22:16:44.573Z,1571177804.573 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-15T22:16:44.573Z,1571177804.573 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-15T22:16:44.574Z,1571177804.574 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-15T22:16:44.576Z,1571177804.576 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-15T22:16:44.576Z,1571177804.576 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-15T22:16:44.580Z,1571177804.580 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-15T22:16:44.580Z,1571177804.580 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-15T22:16:44.580Z,1571177804.580 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-15T22:16:44.580Z,1571177804.580 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-15T22:16:44.581Z,1571177804.581 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-15T22:16:44.581Z,1571177804.581 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-15T22:16:44.585Z,1571177804.585 [SBIT](INFO): Initialize SBIT Component.
2019-10-15T22:16:44.586Z,1571177804.586 [SBIT](IMPORTANT): git: 2019-09-10
2019-10-15T22:16:44.586Z,1571177804.586 [SBIT](INFO): git hash: e950883795b57abbf97d89589ea0268241928c93
2019-10-15T22:16:44.586Z,1571177804.586 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-15T22:16:44.587Z,1571177804.587 [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-15T22:16:44.588Z,1571177804.588 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-10-15T22:16:44.589Z,1571177804.589 [IBIT](INFO): Initialize IBIT Component.
2019-10-15T22:16:44.590Z,1571177804.590 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-15T22:16:44.591Z,1571177804.591 [logger ThreadHandler](INFO): Handler Thread ID is 6569
2019-10-15T22:16:44.601Z,1571177804.601 [CBIT](DEBUG): Initialized mux pins.
2019-10-15T22:16:44.601Z,1571177804.601 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-10-15T22:16:44.601Z,1571177804.601 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-15T22:16:44.609Z,1571177804.609 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6570
2019-10-15T22:16:44.610Z,1571177804.610 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-15T22:16:44.621Z,1571177804.621 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6571
2019-10-15T22:16:44.625Z,1571177804.625 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-15T22:16:44.625Z,1571177804.625 [CBIT](DEBUG): Initializing heartbeat.
2019-10-15T22:16:44.633Z,1571177804.633 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6572
2019-10-15T22:16:44.634Z,1571177804.634 [CTD_NeilBrown](INFO): Powering down
2019-10-15T22:16:44.662Z,1571177804.662 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6573
2019-10-15T22:16:44.662Z,1571177804.662 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:16:44.666Z,1571177804.666 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6575
2019-10-15T22:16:44.667Z,1571177804.667 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:16:44.671Z,1571177804.671 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:16:44.697Z,1571177804.697 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-15T22:16:44.697Z,1571177804.697 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-15T22:16:44.701Z,1571177804.701 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6576
2019-10-15T22:16:44.704Z,1571177804.704 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-15T22:16:44.705Z,1571177804.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-15T22:16:44.705Z,1571177804.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-15T22:16:44.705Z,1571177804.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-15T22:16:44.705Z,1571177804.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-15T22:16:44.705Z,1571177804.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-15T22:16:44.706Z,1571177804.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-15T22:16:44.706Z,1571177804.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-15T22:16:44.706Z,1571177804.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-15T22:16:44.706Z,1571177804.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-15T22:16:44.706Z,1571177804.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-15T22:16:44.707Z,1571177804.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-15T22:16:44.707Z,1571177804.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-15T22:16:44.707Z,1571177804.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-15T22:16:44.707Z,1571177804.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-15T22:16:44.707Z,1571177804.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-15T22:16:44.733Z,1571177804.733 [CBIT](DEBUG): Backplane powered.
2019-10-15T22:16:44.734Z,1571177804.734 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-15T22:16:44.743Z,1571177804.743 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:16:44.754Z,1571177804.754 [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-15T22:16:44.755Z,1571177804.755 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-15T22:16:44.818Z,1571177804.818 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-15T22:16:44.819Z,1571177804.819 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-15T22:16:44.821Z,1571177804.821 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:16:44.859Z,1571177804.859 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-15T22:16:44.878Z,1571177804.878 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-15T22:16:44.883Z,1571177804.883 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-15T22:16:44.902Z,1571177804.902 [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-15T22:16:44.907Z,1571177804.907 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,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-15T22:16:44.917Z,1571177804.917 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:16:44.986Z,1571177804.986 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-10-15T22:16:45.009Z,1571177805.009 [DVL_micro](INFO): Initializing
2019-10-15T22:16:45.026Z,1571177805.026 [Radio_Surface](INFO): Powering up
2019-10-15T22:16:45.036Z,1571177805.036 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-15T22:16:45.036Z,1571177805.036 [DAT](INFO): Powering up
2019-10-15T22:16:45.036Z,1571177805.036 [DAT](DEBUG): Initializing DAT.
2019-10-15T22:16:45.069Z,1571177805.069 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-15T22:16:45.199Z,1571177805.199 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-15T22:16:45.227Z,1571177805.227 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:16:45.233Z,1571177805.233 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-15T22:16:45.234Z,1571177805.234 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:16:45.241Z,1571177805.241 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-15T22:16:45.242Z,1571177805.242 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:16:45.249Z,1571177805.249 [MassServo](DEBUG): Initializing MassServo.
2019-10-15T22:16:45.250Z,1571177805.250 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:16:45.257Z,1571177805.257 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:16:45.258Z,1571177805.258 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:16:45.265Z,1571177805.265 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-15T22:16:45.439Z,1571177805.439 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-10-15T22:16:45.439Z,1571177805.439 [DropWeight] Hardware Fault, FailCount= 1
2019-10-15T22:16:45.439Z,1571177805.439 [DropWeight](ERROR): Hardware Fault
2019-10-15T22:16:45.536Z,1571177805.536 [CommandLine](FAULT): Scheduling is paused
2019-10-15T22:16:45.537Z,1571177805.537 [CBIT](INFO): Critical error at 20191015T221645
2019-10-15T22:16:45.537Z,1571177805.537 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-15T22:16:45.540Z,1571177805.540 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-15T22:16:45.540Z,1571177805.540 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-15T22:16:46.093Z,1571177806.093 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-15T22:16:46.093Z,1571177806.093 [RudderServo](FAULT): Rudder failed to initialize
2019-10-15T22:16:46.093Z,1571177806.093 [RudderServo] Communications Fault, FailCount= 1
2019-10-15T22:16:46.093Z,1571177806.093 [RudderServo](ERROR): Communications Fault
2019-10-15T22:16:46.226Z,1571177806.226 [CBIT](INFO): Critical error at 20191015T221645
2019-10-15T22:16:46.229Z,1571177806.229 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-15T22:16:46.402Z,1571177806.402 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-15T22:16:46.402Z,1571177806.402 [RudderServo](INFO): Powering down
2019-10-15T22:16:47.073Z,1571177807.073 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:16:47.194Z,1571177807.194 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:16:47.198Z,1571177807.198 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-15T22:16:47.198Z,1571177807.198 [RudderServo] No Fault, FailCount= 1
2019-10-15T22:16:48.577Z,1571177808.577 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:16:48.981Z,1571177808.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:16:49.657Z,1571177809.657 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:50.289Z,1571177810.289 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:50.841Z,1571177810.841 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:51.533Z,1571177811.533 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:52.121Z,1571177812.121 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:52.605Z,1571177812.605 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:53.081Z,1571177813.081 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:53.545Z,1571177813.545 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:54.009Z,1571177814.009 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:54.457Z,1571177814.457 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:54.905Z,1571177814.905 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:55.049Z,1571177815.049 [CBIT](CRITICAL): Environmental Failure. Press:14.758745 PSI. Humidity:39%. Temp:23 C. ABORTING MISSION
2019-10-15T22:16:55.365Z,1571177815.365 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:55.385Z,1571177815.385 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-15T22:16:55.385Z,1571177815.385 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-10-15T22:16:55.385Z,1571177815.385 [Aanderaa_O2](ERROR): Communications Fault
2019-10-15T22:16:55.495Z,1571177815.495 [CBIT](INFO): Critical error at 20191015T221655
2019-10-15T22:16:55.497Z,1571177815.497 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-15T22:16:55.813Z,1571177815.813 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:55.818Z,1571177815.818 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:16:55.818Z,1571177815.818 [DVL_micro] Communications Fault, FailCount= 1
2019-10-15T22:16:55.818Z,1571177815.818 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:16:55.953Z,1571177815.953 [Aanderaa_O2](INFO): Powering down
2019-10-15T22:16:56.121Z,1571177816.121 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:16:56.449Z,1571177816.449 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:56.525Z,1571177816.525 [DVL_micro](INFO): uninitialize:Powering down
2019-10-15T22:16:57.001Z,1571177817.001 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:57.149Z,1571177817.149 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-15T22:16:57.149Z,1571177817.149 [Aanderaa_O2] No Fault, FailCount= 1
2019-10-15T22:16:57.481Z,1571177817.481 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:57.625Z,1571177817.625 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-15T22:16:57.625Z,1571177817.625 [DVL_micro] No Fault, FailCount= 1
2019-10-15T22:16:57.713Z,1571177817.713 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:16:57.713Z,1571177817.713 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-15T22:16:57.713Z,1571177817.713 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:16:57.953Z,1571177817.953 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:16:57.953Z,1571177817.953 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:16:57.953Z,1571177817.953 [AHRS_M2] Communications Fault, FailCount= 1
2019-10-15T22:16:57.953Z,1571177817.953 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:16:57.964Z,1571177817.964 [DVL_micro](INFO): Initializing
2019-10-15T22:16:58.117Z,1571177818.117 [CTD_Seabird](INFO): Powering down
2019-10-15T22:16:58.137Z,1571177818.137 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:16:58.137Z,1571177818.137 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:16:58.142Z,1571177818.142 [AHRS_M2](INFO): Powering down
2019-10-15T22:16:58.447Z,1571177818.447 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005080
2019-10-15T22:16:58.565Z,1571177818.565 [DAT](INFO): setting local address to 2
2019-10-15T22:16:58.969Z,1571177818.969 [DAT](INFO): set local address to 2
2019-10-15T22:16:59.089Z,1571177819.089 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:16:59.089Z,1571177819.089 [AHRS_M2] No Fault, FailCount= 1
2019-10-15T22:16:59.352Z,1571177819.352 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:16:59.477Z,1571177819.477 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-15T22:16:59.477Z,1571177819.477 [CTD_Seabird] No Fault, FailCount= 1
2019-10-15T22:16:59.513Z,1571177819.513 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:16:59.515Z,1571177819.515 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:17:02.897Z,1571177822.897 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:03.361Z,1571177823.361 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:03.821Z,1571177823.821 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:04.253Z,1571177824.253 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:04.741Z,1571177824.741 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:05.201Z,1571177825.201 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:05.641Z,1571177825.641 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:06.121Z,1571177826.121 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:06.565Z,1571177826.565 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:07.017Z,1571177827.017 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:07.497Z,1571177827.497 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:07.518Z,1571177827.518 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-15T22:17:07.518Z,1571177827.518 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-10-15T22:17:07.518Z,1571177827.518 [Aanderaa_O2](ERROR): Communications Fault
2019-10-15T22:17:07.645Z,1571177827.645 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-15T22:17:07.969Z,1571177827.969 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:08.109Z,1571177828.109 [Aanderaa_O2](INFO): Powering down
2019-10-15T22:17:08.246Z,1571177828.246 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-15T22:17:08.250Z,1571177828.250 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-15T22:17:08.581Z,1571177828.581 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:08.586Z,1571177828.586 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:17:08.586Z,1571177828.586 [DVL_micro] Communications Fault, FailCount= 2
2019-10-15T22:17:08.586Z,1571177828.586 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:17:08.757Z,1571177828.757 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:17:09.089Z,1571177829.089 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:09.165Z,1571177829.165 [DVL_micro](INFO): uninitialize:Powering down
2019-10-15T22:17:09.306Z,1571177829.306 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-10-15T22:17:09.306Z,1571177829.306 [Aanderaa_O2] No Fault, FailCount= 2
2019-10-15T22:17:09.637Z,1571177829.637 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:10.081Z,1571177830.081 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:10.266Z,1571177830.266 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-15T22:17:10.266Z,1571177830.266 [DVL_micro] No Fault, FailCount= 2
2019-10-15T22:17:10.589Z,1571177830.589 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:10.600Z,1571177830.600 [DVL_micro](INFO): Initializing
2019-10-15T22:17:11.469Z,1571177831.469 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:12.041Z,1571177832.041 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:12.047Z,1571177832.047 [NAL9602](INFO): Powering up NAL9602
2019-10-15T22:17:12.565Z,1571177832.565 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:17:12.565Z,1571177832.565 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-15T22:17:12.565Z,1571177832.565 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:17:12.693Z,1571177832.693 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:12.693Z,1571177832.693 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:17:12.693Z,1571177832.693 [AHRS_M2] Communications Fault, FailCount= 2
2019-10-15T22:17:12.693Z,1571177832.693 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:17:12.969Z,1571177832.969 [CTD_Seabird](INFO): Powering down
2019-10-15T22:17:13.045Z,1571177833.045 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:17:13.046Z,1571177833.046 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:17:13.051Z,1571177833.051 [AHRS_M2](INFO): Powering down
2019-10-15T22:17:13.962Z,1571177833.962 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:17:13.962Z,1571177833.962 [AHRS_M2] No Fault, FailCount= 2
2019-10-15T22:17:13.962Z,1571177833.962 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-15T22:17:13.962Z,1571177833.962 [CTD_Seabird] No Fault, FailCount= 2
2019-10-15T22:17:14.260Z,1571177834.260 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:17:14.313Z,1571177834.313 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:17:14.315Z,1571177834.315 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:17:17.817Z,1571177837.817 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:18.381Z,1571177838.381 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:18.921Z,1571177838.921 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:19.333Z,1571177839.333 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:19.857Z,1571177839.857 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:19.878Z,1571177839.878 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-10-15T22:17:19.878Z,1571177839.878 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-10-15T22:17:19.878Z,1571177839.878 [Aanderaa_O2](ERROR): Communications Fault
2019-10-15T22:17:19.978Z,1571177839.978 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-10-15T22:17:19.978Z,1571177839.978 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-10-15T22:17:19.998Z,1571177839.998 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.047784
CHAN A2 (12V): -0.002260
CHAN A3 (5V): -0.001524
CHAN B0 (3.3V): 0.000081
CHAN B1 (3.15aV): 0.000136
CHAN B2 (3.15bV): -0.000110
CHAN B3 (GND): -0.000222
OPEN: -0.000492
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-15T22:17:20.341Z,1571177840.341 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:20.489Z,1571177840.489 [Aanderaa_O2](INFO): Powering down
2019-10-15T22:17:20.873Z,1571177840.873 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:21.321Z,1571177841.321 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:21.753Z,1571177841.753 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:21.767Z,1571177841.767 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:17:21.767Z,1571177841.767 [DVL_micro] Communications Fault, FailCount= 3
2019-10-15T22:17:21.767Z,1571177841.767 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:17:21.854Z,1571177841.854 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:17:22.209Z,1571177842.209 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:22.281Z,1571177842.281 [DVL_micro](INFO): uninitialize:Powering down
2019-10-15T22:17:22.753Z,1571177842.753 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:23.205Z,1571177843.205 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:23.341Z,1571177843.341 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-15T22:17:23.341Z,1571177843.341 [DVL_micro] No Fault, FailCount= 3
2019-10-15T22:17:23.673Z,1571177843.673 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:23.693Z,1571177843.693 [DVL_micro](INFO): Initializing
2019-10-15T22:17:24.681Z,1571177844.681 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:25.173Z,1571177845.173 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:25.641Z,1571177845.641 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:26.153Z,1571177846.153 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:26.629Z,1571177846.629 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:27.109Z,1571177847.109 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:27.109Z,1571177847.109 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:17:27.109Z,1571177847.109 [AHRS_M2] Communications Fault, FailCount= 3
2019-10-15T22:17:27.109Z,1571177847.109 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:17:27.293Z,1571177847.293 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:17:27.298Z,1571177847.298 [AHRS_M2](INFO): Powering down
2019-10-15T22:17:27.373Z,1571177847.373 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:17:27.373Z,1571177847.373 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-15T22:17:27.373Z,1571177847.373 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:17:27.518Z,1571177847.518 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:17:27.518Z,1571177847.518 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-15T22:17:27.777Z,1571177847.777 [CTD_Seabird](INFO): Powering down
2019-10-15T22:17:28.266Z,1571177848.266 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:17:28.266Z,1571177848.266 [AHRS_M2] No Fault, FailCount= 3
2019-10-15T22:17:28.508Z,1571177848.508 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:17:32.065Z,1571177852.065 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:32.493Z,1571177852.493 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:32.925Z,1571177852.925 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:32.930Z,1571177852.930 [NAL9602](ERROR): NAL9602 initialization error.
2019-10-15T22:17:32.930Z,1571177852.930 [NAL9602] Communications Fault, FailCount= 1
2019-10-15T22:17:32.930Z,1571177852.930 [NAL9602](ERROR): Communications Fault
2019-10-15T22:17:33.042Z,1571177853.042 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-10-15T22:17:33.385Z,1571177853.385 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:33.391Z,1571177853.391 [NAL9602](INFO): Powering down
2019-10-15T22:17:33.833Z,1571177853.833 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:34.261Z,1571177854.261 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:34.375Z,1571177854.375 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-15T22:17:34.376Z,1571177854.376 [NAL9602] No Fault, FailCount= 1
2019-10-15T22:17:34.697Z,1571177854.697 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:34.702Z,1571177854.702 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:17:34.702Z,1571177854.702 [DVL_micro] Communications Fault, FailCount= 4
2019-10-15T22:17:34.702Z,1571177854.702 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:17:34.839Z,1571177854.839 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:17:35.161Z,1571177855.161 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:35.237Z,1571177855.237 [DVL_micro](INFO): uninitialize:Powering down
2019-10-15T22:17:35.665Z,1571177855.665 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:36.101Z,1571177856.101 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:36.226Z,1571177856.226 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-15T22:17:36.226Z,1571177856.226 [DVL_micro] No Fault, FailCount= 4
2019-10-15T22:17:36.573Z,1571177856.573 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:36.584Z,1571177856.584 [DVL_micro](INFO): Initializing
2019-10-15T22:17:37.033Z,1571177857.033 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:37.461Z,1571177857.461 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:37.913Z,1571177857.913 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:38.345Z,1571177858.345 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:38.761Z,1571177858.761 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:39.189Z,1571177859.189 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:39.617Z,1571177859.617 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:40.045Z,1571177860.045 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:40.477Z,1571177860.477 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:40.981Z,1571177860.981 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:41.405Z,1571177861.405 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:41.405Z,1571177861.405 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:17:41.405Z,1571177861.405 [AHRS_M2] Communications Fault, FailCount= 4
2019-10-15T22:17:41.405Z,1571177861.405 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:17:41.495Z,1571177861.495 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:17:41.509Z,1571177861.509 [AHRS_M2](INFO): Powering down
2019-10-15T22:17:42.354Z,1571177862.354 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:17:42.354Z,1571177862.354 [AHRS_M2] No Fault, FailCount= 4
2019-10-15T22:17:42.720Z,1571177862.720 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:17:46.277Z,1571177866.277 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:46.685Z,1571177866.685 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:47.085Z,1571177867.085 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:47.094Z,1571177867.094 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:17:47.094Z,1571177867.094 [DVL_micro] Communications Fault, FailCount= 5
2019-10-15T22:17:47.094Z,1571177867.094 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:17:47.148Z,1571177867.148 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:17:47.148Z,1571177867.148 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-15T22:17:47.501Z,1571177867.501 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:47.581Z,1571177867.581 [DVL_micro](INFO): uninitialize:Powering down
2019-10-15T22:17:47.616Z,1571177867.616 [CBIT](INFO): Critical error at 20191015T221747
2019-10-15T22:17:47.977Z,1571177867.977 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:48.373Z,1571177868.373 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:48.797Z,1571177868.797 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:49.221Z,1571177869.221 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:49.633Z,1571177869.633 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:49.813Z,1571177869.813 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:50.445Z,1571177870.445 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:50.945Z,1571177870.945 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:51.789Z,1571177871.789 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:52.253Z,1571177872.253 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:52.765Z,1571177872.765 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:53.317Z,1571177873.317 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:53.985Z,1571177873.985 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:54.421Z,1571177874.421 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:54.837Z,1571177874.837 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:55.253Z,1571177875.253 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:55.669Z,1571177875.669 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-10-15T22:17:55.669Z,1571177875.669 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:17:55.669Z,1571177875.669 [AHRS_M2] Communications Fault, FailCount= 5
2019-10-15T22:17:55.669Z,1571177875.669 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:17:55.774Z,1571177875.774 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:17:55.774Z,1571177875.774 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-10-15T22:17:55.779Z,1571177875.779 [AHRS_M2](INFO): Powering down
2019-10-15T22:17:55.932Z,1571177875.932 [CBIT](INFO): Critical error at 20191015T221755
2019-10-15T22:18:01.868Z,1571177881.868 [SBIT](IMPORTANT): SBIT PASSED
2019-10-15T22:18:01.910Z,1571177881.910 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:18:01.910Z,1571177881.910 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-15T22:18:01.911Z,1571177881.911 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere;

2019-10-15T22:18:01.911Z,1571177881.911 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-10-15T22:18:01.911Z,1571177881.911 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-10-15T22:18:01.911Z,1571177881.911 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-10-15T22:18:02.270Z,1571177882.270 [MissionManager](IMPORTANT): Started mission Startup
2019-10-15T22:18:02.271Z,1571177882.271 [Startup] Running Loop=1
2019-10-15T22:18:02.271Z,1571177882.271 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-15T22:18:02.271Z,1571177882.271 [Startup:A.GoToSurface] Running Loop=1
2019-10-15T22:18:02.271Z,1571177882.271 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-15T22:18:02.272Z,1571177882.272 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-15T22:18:02.272Z,1571177882.272 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-15T22:18:02.272Z,1571177882.272 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-15T22:18:02.273Z,1571177882.273 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-15T22:18:02.273Z,1571177882.273 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-15T22:18:02.275Z,1571177882.275 [Startup:StartupSatComms] Running Loop=1
2019-10-15T22:18:02.275Z,1571177882.275 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-15T22:18:02.275Z,1571177882.275 [Startup:StartupSatComms:A] Running Loop=1
2019-10-15T22:18:02.677Z,1571177882.677 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-15T22:18:03.458Z,1571177883.458 [NAL9602](INFO): Powering up NAL9602
2019-10-15T22:18:12.123Z,1571177892.123 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:18:12.123Z,1571177892.123 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-15T22:18:12.123Z,1571177892.123 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere;

2019-10-15T22:18:12.124Z,1571177892.124 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-10-15T22:18:12.124Z,1571177892.124 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-10-15T22:18:12.124Z,1571177892.124 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-10-15T22:18:14.366Z,1571177894.366 [NAL9602](INFO): NAL9602 initialized
2019-10-15T22:18:15.185Z,1571177895.185 [NAL9602](DEBUG): Fix Requested
2019-10-15T22:18:28.422Z,1571177908.422 [CommandLine](IMPORTANT): got command gfscan
2019-10-15T22:18:28.549Z,1571177908.549 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-15T22:18:39.462Z,1571177919.462 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.012703
CHAN A1 (24V): 0.047390
CHAN A2 (12V): -0.001841
CHAN A3 (5V): -0.001594
CHAN B0 (3.3V): 0.000078
CHAN B1 (3.15aV): -0.000163
CHAN B2 (3.15bV): -0.000039
CHAN B3 (GND): -0.000033
OPEN: -0.000473
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-15T22:18:44.710Z,1571177924.710 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-15T22:18:44.710Z,1571177924.710 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-15T22:18:44.710Z,1571177924.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-15T22:18:44.720Z,1571177924.720 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-15T22:18:45.106Z,1571177925.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-15T22:18:45.106Z,1571177925.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-15T22:18:53.175Z,1571177933.175 [CommandLine](IMPORTANT): got command restart application
2019-10-15T22:18:54.181Z,1571177934.181 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:54.181Z,1571177934.181 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.249Z,1571177934.249 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-15T22:18:54.249Z,1571177934.249 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.250Z,1571177934.250 [CommandLine](INFO): Join timeout helper Thread ID is 6607
2019-10-15T22:18:54.250Z,1571177934.250 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-15T22:18:54.250Z,1571177934.250 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.251Z,1571177934.251 [NavChartDb](INFO): Join timeout helper Thread ID is 6608
2019-10-15T22:18:54.625Z,1571177934.625 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:54.625Z,1571177934.625 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.629Z,1571177934.629 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-15T22:18:54.629Z,1571177934.629 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.629Z,1571177934.629 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6609
2019-10-15T22:18:54.709Z,1571177934.709 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:54.709Z,1571177934.709 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:18:54.710Z,1571177934.710 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.713Z,1571177934.713 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-15T22:18:54.713Z,1571177934.713 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.713Z,1571177934.713 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6610
2019-10-15T22:18:54.873Z,1571177934.873 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:54.873Z,1571177934.873 [CTD_Seabird](INFO): Powering down
2019-10-15T22:18:54.885Z,1571177934.885 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.893Z,1571177934.893 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-10-15T22:18:54.893Z,1571177934.893 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:54.893Z,1571177934.893 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6611
2019-10-15T22:18:55.037Z,1571177935.037 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:55.037Z,1571177935.037 [CTD_NeilBrown](INFO): Powering down
2019-10-15T22:18:55.049Z,1571177935.049 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.058Z,1571177935.058 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-15T22:18:55.058Z,1571177935.058 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.058Z,1571177935.058 [Radio_Surface](INFO): Join timeout helper Thread ID is 6612
2019-10-15T22:18:55.133Z,1571177935.133 [Radio_Surface](INFO): Powering down
2019-10-15T22:18:55.134Z,1571177935.134 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:55.134Z,1571177935.134 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.138Z,1571177935.138 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-15T22:18:55.138Z,1571177935.138 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.138Z,1571177935.138 [DataOverHttps](INFO): Join timeout helper Thread ID is 6613
2019-10-15T22:18:55.433Z,1571177935.433 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:55.437Z,1571177935.437 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.446Z,1571177935.446 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-15T22:18:55.446Z,1571177935.446 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.446Z,1571177935.446 [logger](INFO): Join timeout helper Thread ID is 6614
2019-10-15T22:18:55.481Z,1571177935.481 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:55.481Z,1571177935.481 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.486Z,1571177935.486 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-15T22:18:55.486Z,1571177935.486 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.486Z,1571177935.486 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-15T22:18:55.486Z,1571177935.486 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.486Z,1571177935.486 [controlThread](INFO): Join timeout helper Thread ID is 6615
2019-10-15T22:18:55.565Z,1571177935.565 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:18:55.565Z,1571177935.565 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-15T22:18:55.565Z,1571177935.565 [AHRS_M2](INFO): Powering down
2019-10-15T22:18:55.567Z,1571177935.567 [DVL_micro](INFO): uninitialize:Powering down
2019-10-15T22:18:55.567Z,1571177935.567 [NAL9602](INFO): Powering down
2019-10-15T22:18:55.568Z,1571177935.568 [DAT](INFO): Powering down
2019-10-15T22:18:55.690Z,1571177935.690 [Aanderaa_O2](INFO): Powering down
2019-10-15T22:18:55.691Z,1571177935.691 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-15T22:18:55.692Z,1571177935.692 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-15T22:18:55.693Z,1571177935.693 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-15T22:18:55.693Z,1571177935.693 [MissionManager](INFO): Uninitializing Mission Default
2019-10-15T22:18:55.695Z,1571177935.695 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-15T22:18:55.695Z,1571177935.695 [Startup] Stopped
2019-10-15T22:18:55.696Z,1571177935.696 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-15T22:18:55.696Z,1571177935.696 [Startup:A.GoToSurface] Stopped
2019-10-15T22:18:55.696Z,1571177935.696 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-15T22:18:55.696Z,1571177935.696 [Startup:StartupSatComms] Stopped
2019-10-15T22:18:55.696Z,1571177935.696 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-15T22:18:55.696Z,1571177935.696 [Startup:StartupSatComms:A] Stopped
2019-10-15T22:18:55.697Z,1571177935.697 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-15T22:18:55.698Z,1571177935.698 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-15T22:18:55.698Z,1571177935.698 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-15T22:18:55.698Z,1571177935.698 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-15T22:18:55.698Z,1571177935.698 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-15T22:18:55.698Z,1571177935.698 [BuoyancyServo](INFO): Powering down
2019-10-15T22:18:55.713Z,1571177935.713 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-15T22:18:55.713Z,1571177935.713 [ElevatorServo](INFO): Powering down
2019-10-15T22:18:55.714Z,1571177935.714 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-15T22:18:55.714Z,1571177935.714 [MassServo](INFO): Powering down
2019-10-15T22:18:55.715Z,1571177935.715 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-15T22:18:55.715Z,1571177935.715 [RudderServo](INFO): Powering down
2019-10-15T22:18:55.715Z,1571177935.715 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-15T22:18:55.716Z,1571177935.716 [ThrusterServo](INFO): Powering down
2019-10-15T22:18:55.716Z,1571177935.716 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-15T22:18:55.717Z,1571177935.717 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-15T22:18:55.717Z,1571177935.717 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-15T22:18:55.717Z,1571177935.717 [CBIT](DEBUG): Powering off loads.
2019-10-15T22:18:55.729Z,1571177935.729 [CBIT](DEBUG): Disabling WDT.
2019-10-15T22:18:55.741Z,1571177935.741 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-15T22:18:55.742Z,1571177935.742 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.820Z,1571177935.820 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.829Z,1571177935.829 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.860Z,1571177935.860 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.863Z,1571177935.863 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.867Z,1571177935.867 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.904Z,1571177935.904 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:18:55.963Z,1571177935.963 [logger ThreadHandler](INFO): Thread cancelled.